builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0292 starttime: 1468023398.26 results: success (0) buildid: 20160708151127 builduid: f79d4f8ccb4d45c0863bd48756e9225d revision: cbacbb7c2c3523d036f737c9dc6c4406d14ade2b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-08 17:16:38.256772) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-08 17:16:38.257166) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-08 17:16:38.257444) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-08 17:16:38.281712) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 17:16:38.282041) ========= 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.9UFj6P8omw/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.GWg1M9aklk/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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-07-08 17:16:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.0M=0.001s 2016-07-08 17:16:38 (19.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.132459 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 17:16:38.436841) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 17:16:38.437157) ========= 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.9UFj6P8omw/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.GWg1M9aklk/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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.024857 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 17:16:38.485693) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 17:16:38.486089) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev cbacbb7c2c3523d036f737c9dc6c4406d14ade2b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev cbacbb7c2c3523d036f737c9dc6c4406d14ade2b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9UFj6P8omw/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.GWg1M9aklk/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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-07-08 17:16:38,567 truncating revision to first 12 chars 2016-07-08 17:16:38,567 Setting DEBUG logging. 2016-07-08 17:16:38,567 attempt 1/10 2016-07-08 17:16:38,567 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/cbacbb7c2c35?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-08 17:16:39,278 unpacking tar archive at: mozilla-beta-cbacbb7c2c35/testing/mozharness/ program finished with exit code 0 elapsedTime=0.972610 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 17:16:39.481033) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-08 17:16:39.481353) ========= script_repo_revision: cbacbb7c2c3523d036f737c9dc6c4406d14ade2b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-08 17:16:39.481743) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-08 17:16:39.482008) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-08 17:16:39.499911) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-07-08 17:16:39.500208) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9UFj6P8omw/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.GWg1M9aklk/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 17:16:39 INFO - MultiFileLogger online at 20160708 17:16:39 in /builds/slave/test 17:16:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 17:16:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:16:39 INFO - {'append_to_log': False, 17:16:39 INFO - 'base_work_dir': '/builds/slave/test', 17:16:39 INFO - 'blob_upload_branch': 'Mozilla-Beta', 17:16:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:16:39 INFO - 'branch': 'Mozilla-Beta', 17:16:39 INFO - 'buildbot_json_path': 'buildprops.json', 17:16:39 INFO - 'config_files': ('talos/mac_config.py',), 17:16:39 INFO - 'default_actions': ('clobber', 17:16:39 INFO - 'read-buildbot-config', 17:16:39 INFO - 'download-and-extract', 17:16:39 INFO - 'populate-webroot', 17:16:39 INFO - 'create-virtualenv', 17:16:39 INFO - 'install', 17:16:39 INFO - 'run-tests'), 17:16:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:16:39 INFO - 'download_minidump_stackwalk': True, 17:16:39 INFO - 'download_symbols': 'ondemand', 17:16:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:16:39 INFO - 'tooltool.py': '/tools/tooltool.py', 17:16:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:16:39 INFO - '/tools/misc-python/virtualenv.py')}, 17:16:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:16:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:16:39 INFO - 'installer_path': 'installer.exe', 17:16:39 INFO - 'log_level': 'info', 17:16:39 INFO - 'log_name': 'talos', 17:16:39 INFO - 'log_to_console': True, 17:16:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:16:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:16:39 INFO - 'opt_config_files': (), 17:16:39 INFO - 'pip_index': False, 17:16:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:16:39 INFO - 'cmd': ('bash', 17:16:39 INFO - '-c', 17:16:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:16:39 INFO - 'enabled': True, 17:16:39 INFO - 'halt_on_failure': False, 17:16:39 INFO - 'name': 'check_screen_resolution'},), 17:16:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:16:39 INFO - 'cmd': ('bash', 17:16:39 INFO - '-c', 17:16:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:16:39 INFO - 'enabled': True, 17:16:39 INFO - 'halt_on_failure': False, 17:16:39 INFO - 'name': 'check_screen_resolution'},), 17:16:39 INFO - 'run_cmd_checks_enabled': True, 17:16:39 INFO - 'sps_profile': False, 17:16:39 INFO - 'sps_profile_interval': 0, 17:16:39 INFO - 'suite': 'dromaeojs', 17:16:39 INFO - 'system_bits': '32', 17:16:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:16:39 INFO - 'title': 't-yosemite-r7-0292', 17:16:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:16:39 INFO - 'use_talos_json': True, 17:16:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:16:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:16:39 INFO - 'work_dir': 'build'} 17:16:39 INFO - ##### 17:16:39 INFO - ##### Running clobber step. 17:16:39 INFO - ##### 17:16:39 INFO - Running pre-action listener: _resource_record_pre_action 17:16:39 INFO - Running main action method: clobber 17:16:39 INFO - rmtree: /builds/slave/test/build 17:16:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:16:41 INFO - Running post-action listener: _resource_record_post_action 17:16:41 INFO - ##### 17:16:41 INFO - ##### Running read-buildbot-config step. 17:16:41 INFO - ##### 17:16:41 INFO - Running pre-action listener: _resource_record_pre_action 17:16:41 INFO - Running main action method: read_buildbot_config 17:16:41 INFO - Using buildbot properties: 17:16:41 INFO - { 17:16:41 INFO - "project": "", 17:16:41 INFO - "product": "firefox", 17:16:41 INFO - "script_repo_revision": "production", 17:16:41 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 17:16:41 INFO - "repository": "", 17:16:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 17:16:41 INFO - "buildid": "20160708151127", 17:16:41 INFO - "pgo_build": "False", 17:16:41 INFO - "basedir": "/builds/slave/test", 17:16:41 INFO - "buildnumber": 31, 17:16:41 INFO - "slavename": "t-yosemite-r7-0292", 17:16:41 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 17:16:41 INFO - "platform": "yosemite_r7", 17:16:41 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 17:16:41 INFO - "branch": "mozilla-beta", 17:16:41 INFO - "repo_path": "releases/mozilla-beta", 17:16:41 INFO - "revision": "cbacbb7c2c3523d036f737c9dc6c4406d14ade2b", 17:16:41 INFO - "stage_platform": "macosx64", 17:16:41 INFO - "builduid": "f79d4f8ccb4d45c0863bd48756e9225d", 17:16:41 INFO - "slavebuilddir": "test" 17:16:41 INFO - } 17:16:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 17:16:41 INFO - Running post-action listener: _resource_record_post_action 17:16:41 INFO - ##### 17:16:41 INFO - ##### Running download-and-extract step. 17:16:41 INFO - ##### 17:16:41 INFO - Running pre-action listener: _resource_record_pre_action 17:16:41 INFO - Running main action method: download_and_extract 17:16:41 INFO - mkdir: /builds/slave/test/build/tests 17:16:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:16:41 INFO - https://queue.taskcluster.net/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 17:16:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 17:16:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 17:16:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 17:16:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 17:16:42 INFO - Downloaded 1165 bytes. 17:16:42 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 17:16:42 INFO - Using the following test package requirements: 17:16:42 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 17:16:42 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 17:16:42 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 17:16:42 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 17:16:42 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 17:16:42 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 17:16:42 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 17:16:42 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 17:16:42 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 17:16:42 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 17:16:42 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 17:16:42 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 17:16:42 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 17:16:42 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 17:16:42 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 17:16:42 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 17:16:42 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 17:16:42 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 17:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:16:42 INFO - https://queue.taskcluster.net/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:16:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 17:16:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 17:16:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 17:16:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 17:16:43 INFO - Downloaded 18351540 bytes. 17:16:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:16:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 17:16:46 INFO - Return code: 0 17:16:46 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 17:16:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:16:46 INFO - https://queue.taskcluster.net/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:16:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 17:16:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 17:16:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 17:16:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 17:16:48 INFO - Downloaded 18351540 bytes. 17:16:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:16:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 17:16:51 INFO - Return code: 0 17:16: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')]} 17:16:51 INFO - https://queue.taskcluster.net/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 17:16:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 17:16:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 17:16:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 17:16:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 17:16:52 INFO - Downloaded 10840145 bytes. 17:16:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:16:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 17:16:53 INFO - Return code: 0 17:16:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:16:53 INFO - https://queue.taskcluster.net/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 17:16:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.dmg 17:16:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.dmg 17:16:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 17:16:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 17:16:55 INFO - Downloaded 86191171 bytes. 17:16:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.dmg 17:16:55 INFO - mkdir: /builds/slave/test/properties 17:16:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:16:55 INFO - Writing to file /builds/slave/test/properties/build_url 17:16:55 INFO - Contents: 17:16:55 INFO - build_url:https://queue.taskcluster.net/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.dmg 17:16:56 INFO - Running post-action listener: _resource_record_post_action 17:16:56 INFO - Running post-action listener: set_extra_try_arguments 17:16:56 INFO - ##### 17:16:56 INFO - ##### Running populate-webroot step. 17:16:56 INFO - ##### 17:16:56 INFO - Running pre-action listener: _resource_record_pre_action 17:16:56 INFO - Running main action method: populate_webroot 17:16:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:16:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:16:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:16:56 INFO - '--tppagecycles', 17:16:56 INFO - '7'], 17:16:56 INFO - 'tests': ['tsvgm']}}, 17:16:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:16:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:16:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:16:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:16:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:16:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:16:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:16:56 INFO - 'talos_options': ['--disable-e10s'], 17:16:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:16:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:16:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:16:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:16:56 INFO - 'talos_options': ['--disable-e10s'], 17:16:56 INFO - 'tests': ['damp', 'tps']}, 17:16:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:16:56 INFO - 'tests': ['damp', 'tps']}, 17:16:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:16:56 INFO - 'tests': ['dromaeo_dom']}, 17:16:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:16:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:16:56 INFO - 'tests': ['a11yr', 17:16:56 INFO - 'ts_paint', 17:16:56 INFO - 'tpaint', 17:16:56 INFO - 'sessionrestore', 17:16:56 INFO - 'sessionrestore_no_auto_restore', 17:16:56 INFO - 'tabpaint']}, 17:16:56 INFO - 'other-e10s': {'tests': ['a11yr', 17:16:56 INFO - 'ts_paint', 17:16:56 INFO - 'tpaint', 17:16:56 INFO - 'sessionrestore', 17:16:56 INFO - 'sessionrestore_no_auto_restore', 17:16:56 INFO - 'tabpaint']}, 17:16:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:16:56 INFO - 'ts_paint', 17:16:56 INFO - 'tpaint', 17:16:56 INFO - 'sessionrestore', 17:16:56 INFO - 'sessionrestore_no_auto_restore', 17:16:56 INFO - 'tabpaint']}, 17:16:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:16:56 INFO - 'ts_paint', 17:16:56 INFO - 'tpaint', 17:16:56 INFO - 'sessionrestore', 17:16:56 INFO - 'sessionrestore_no_auto_restore', 17:16:56 INFO - 'tabpaint']}, 17:16:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:16:56 INFO - 'tests': ['a11yr', 17:16:56 INFO - 'ts_paint', 17:16:56 INFO - 'tpaint', 17:16:56 INFO - 'sessionrestore', 17:16:56 INFO - 'sessionrestore_no_auto_restore', 17:16:56 INFO - 'tabpaint']}, 17:16:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:16:56 INFO - 'tests': ['a11yr', 17:16:56 INFO - 'ts_paint', 17:16:56 INFO - 'tpaint', 17:16:56 INFO - 'sessionrestore', 17:16:56 INFO - 'sessionrestore_no_auto_restore', 17:16:56 INFO - 'tabpaint']}, 17:16:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:16:56 INFO - 'tests': ['tsvgx', 17:16:56 INFO - 'tsvgr_opacity', 17:16:56 INFO - 'tart', 17:16:56 INFO - 'tscrollx', 17:16:56 INFO - 'cart']}, 17:16:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:16:56 INFO - 'tsvgr_opacity', 17:16:56 INFO - 'tart', 17:16:56 INFO - 'tscrollx', 17:16:56 INFO - 'cart']}, 17:16:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:16:56 INFO - 'talos_options': ['--disable-e10s'], 17:16:56 INFO - 'tests': ['tp5o']}, 17:16:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:16:56 INFO - 'tests': ['tp5o']}, 17:16:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:16:56 INFO - 'talos_options': ['--disable-e10s', 17:16:56 INFO - '--xperf_path', 17:16:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:16:56 INFO - 'tests': ['tp5n']}, 17:16:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:16:56 INFO - 'talos_options': ['--xperf_path', 17:16:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:16:56 INFO - 'tests': ['tp5n']}}, 17:16:56 INFO - 'talos.zip': {'path': '', 17:16:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:16:56 INFO - Running post-action listener: _resource_record_post_action 17:16:56 INFO - ##### 17:16:56 INFO - ##### Running create-virtualenv step. 17:16:56 INFO - ##### 17:16:56 INFO - Running pre-action listener: _resource_record_pre_action 17:16:56 INFO - Running main action method: create_virtualenv 17:16:56 INFO - Creating virtualenv /builds/slave/test/build/venv 17:16:56 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:16:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:16:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:16:56 INFO - Using real prefix '/tools/python27' 17:16:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:16:57 INFO - Installing distribute.............................................................................................................................................................................................done. 17:17:00 INFO - Installing pip.................done. 17:17:00 INFO - Return code: 0 17:17:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:17:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:17:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:17:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:00 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 0x10a2a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe73473fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a148e00>, '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 0x109fb1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a143b20>, '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.9UFj6P8omw/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.GWg1M9aklk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:17:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:17:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:17:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9UFj6P8omw/Render', 17:17:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:00 INFO - 'HOME': '/Users/cltbld', 17:17:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:00 INFO - 'LOGNAME': 'cltbld', 17:17:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:00 INFO - 'MOZ_NO_REMOTE': '1', 17:17:00 INFO - 'NO_EM_RESTART': '1', 17:17:00 INFO - 'PAGER': '/bin/cat', 17:17:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:00 INFO - 'PWD': '/builds/slave/test', 17:17:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:00 INFO - 'SHELL': '/bin/bash', 17:17:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GWg1M9aklk/Listeners', 17:17:00 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 17:17:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:00 INFO - 'USER': 'cltbld', 17:17:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:00 INFO - 'XPC_FLAGS': '0x0', 17:17:00 INFO - 'XPC_SERVICE_NAME': '0', 17:17:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:00 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 17:17:00 INFO - Cleaning up... 17:17:00 INFO - Return code: 0 17:17:00 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 17:17:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:17:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:17:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:00 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 0x10a2a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe73473fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a148e00>, '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 0x109fb1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a143b20>, '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.9UFj6P8omw/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.GWg1M9aklk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:17:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 17:17:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 17:17:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9UFj6P8omw/Render', 17:17:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:00 INFO - 'HOME': '/Users/cltbld', 17:17:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:00 INFO - 'LOGNAME': 'cltbld', 17:17:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:00 INFO - 'MOZ_NO_REMOTE': '1', 17:17:00 INFO - 'NO_EM_RESTART': '1', 17:17:00 INFO - 'PAGER': '/bin/cat', 17:17:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:00 INFO - 'PWD': '/builds/slave/test', 17:17:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:00 INFO - 'SHELL': '/bin/bash', 17:17:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GWg1M9aklk/Listeners', 17:17:00 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 17:17:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:00 INFO - 'USER': 'cltbld', 17:17:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:00 INFO - 'XPC_FLAGS': '0x0', 17:17:00 INFO - 'XPC_SERVICE_NAME': '0', 17:17:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:00 INFO - Downloading/unpacking psutil>=0.7.1 17:17:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:17:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:17:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:17:03 INFO - Installing collected packages: psutil 17:17:03 INFO - Running setup.py install for psutil 17:17:03 INFO - building 'psutil._psutil_osx' extension 17:17:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 17:17:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 17:17:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 17:17:04 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 17:17:04 INFO - building 'psutil._psutil_posix' extension 17:17:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 17:17:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:17:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:17:04 INFO - ^ 17:17:04 INFO - 1 warning generated. 17:17:04 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 17:17:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:17:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:17:04 INFO - Successfully installed psutil 17:17:04 INFO - Cleaning up... 17:17:05 INFO - Return code: 0 17:17:05 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 17:17:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:17:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:17:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:05 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 0x10a2a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe73473fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a148e00>, '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 0x109fb1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a143b20>, '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.9UFj6P8omw/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.GWg1M9aklk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:17:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 17:17:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 17:17:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9UFj6P8omw/Render', 17:17:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:05 INFO - 'HOME': '/Users/cltbld', 17:17:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:05 INFO - 'LOGNAME': 'cltbld', 17:17:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:05 INFO - 'MOZ_NO_REMOTE': '1', 17:17:05 INFO - 'NO_EM_RESTART': '1', 17:17:05 INFO - 'PAGER': '/bin/cat', 17:17:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:05 INFO - 'PWD': '/builds/slave/test', 17:17:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:05 INFO - 'SHELL': '/bin/bash', 17:17:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GWg1M9aklk/Listeners', 17:17:05 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 17:17:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:05 INFO - 'USER': 'cltbld', 17:17:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:05 INFO - 'XPC_FLAGS': '0x0', 17:17:05 INFO - 'XPC_SERVICE_NAME': '0', 17:17:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:05 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 17:17:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:07 INFO - Downloading mozsystemmonitor-0.0.tar.gz 17:17:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:17:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 17:17:07 INFO - Installing collected packages: mozsystemmonitor 17:17:07 INFO - Running setup.py install for mozsystemmonitor 17:17:07 INFO - Successfully installed mozsystemmonitor 17:17:07 INFO - Cleaning up... 17:17:08 INFO - Return code: 0 17:17:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:17: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')]} 17:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17: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')]} 17:17:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:08 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 0x10a2a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe73473fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a148e00>, '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 0x109fb1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a143b20>, '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.9UFj6P8omw/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.GWg1M9aklk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:17:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:17:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:17:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9UFj6P8omw/Render', 17:17:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:08 INFO - 'HOME': '/Users/cltbld', 17:17:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:08 INFO - 'LOGNAME': 'cltbld', 17:17:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:08 INFO - 'MOZ_NO_REMOTE': '1', 17:17:08 INFO - 'NO_EM_RESTART': '1', 17:17:08 INFO - 'PAGER': '/bin/cat', 17:17:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:08 INFO - 'PWD': '/builds/slave/test', 17:17:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:08 INFO - 'SHELL': '/bin/bash', 17:17:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GWg1M9aklk/Listeners', 17:17:08 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 17:17:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:08 INFO - 'USER': 'cltbld', 17:17:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:08 INFO - 'XPC_FLAGS': '0x0', 17:17:08 INFO - 'XPC_SERVICE_NAME': '0', 17:17:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:08 INFO - Downloading/unpacking blobuploader==1.2.4 17:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:10 INFO - Downloading blobuploader-1.2.4.tar.gz 17:17:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:17:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:17:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:17:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:12 INFO - Downloading docopt-0.6.1.tar.gz 17:17:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:17:12 INFO - Installing collected packages: blobuploader, requests, docopt 17:17:12 INFO - Running setup.py install for blobuploader 17:17:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:17:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:17:12 INFO - Running setup.py install for requests 17:17:12 INFO - Running setup.py install for docopt 17:17:12 INFO - Successfully installed blobuploader requests docopt 17:17:12 INFO - Cleaning up... 17:17:13 INFO - Return code: 0 17:17:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:17:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:17:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:17:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:13 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 0x10a2a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe73473fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a148e00>, '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 0x109fb1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a143b20>, '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.9UFj6P8omw/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.GWg1M9aklk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:17:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:17:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:17:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9UFj6P8omw/Render', 17:17:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:13 INFO - 'HOME': '/Users/cltbld', 17:17:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:13 INFO - 'LOGNAME': 'cltbld', 17:17:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:13 INFO - 'MOZ_NO_REMOTE': '1', 17:17:13 INFO - 'NO_EM_RESTART': '1', 17:17:13 INFO - 'PAGER': '/bin/cat', 17:17:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:13 INFO - 'PWD': '/builds/slave/test', 17:17:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:13 INFO - 'SHELL': '/bin/bash', 17:17:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GWg1M9aklk/Listeners', 17:17:13 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 17:17:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:13 INFO - 'USER': 'cltbld', 17:17:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:13 INFO - 'XPC_FLAGS': '0x0', 17:17:13 INFO - 'XPC_SERVICE_NAME': '0', 17:17:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:17:13 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-SUuyRm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:17:13 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-xhdVSG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:17:13 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-nQlJEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:17:13 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-tgoJyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:17:13 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-P_QAmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:17:13 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-RbRiyB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:17:13 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-FkmV60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:17:14 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-GuKPhu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:17:14 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-QnNMi0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:17:14 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-5OIaae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:17:14 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-xbhEID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:17:14 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-DMnaBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:17:14 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-dyCCCa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:17:14 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-z4C2YU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:17:14 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-QPFRKP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:17:14 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-ZpDi0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:17:14 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-2VSP_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:17:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:17:15 INFO - Running setup.py install for manifestparser 17:17:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:17:15 INFO - Running setup.py install for mozcrash 17:17:15 INFO - Running setup.py install for mozdebug 17:17:15 INFO - Running setup.py install for mozdevice 17:17:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:17:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:17:15 INFO - Running setup.py install for mozfile 17:17:15 INFO - Running setup.py install for mozhttpd 17:17:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:17:15 INFO - Running setup.py install for mozinfo 17:17:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:17:16 INFO - Running setup.py install for mozInstall 17:17:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:17:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:17:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:17:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:17:16 INFO - Running setup.py install for mozleak 17:17:16 INFO - Running setup.py install for mozlog 17:17:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:17:16 INFO - Running setup.py install for moznetwork 17:17:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:17:16 INFO - Running setup.py install for mozprocess 17:17:16 INFO - Running setup.py install for mozprofile 17:17:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:17:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:17:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:17:17 INFO - Running setup.py install for mozrunner 17:17:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:17:17 INFO - Running setup.py install for mozscreenshot 17:17:17 INFO - Running setup.py install for moztest 17:17:17 INFO - Running setup.py install for mozversion 17:17:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:17:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:17:17 INFO - Cleaning up... 17:17:17 INFO - Return code: 0 17:17:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:17:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:17:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:17 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 0x10a2a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe73473fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a148e00>, '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 0x109fb1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a143b20>, '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.9UFj6P8omw/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.GWg1M9aklk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:17:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:17:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:17:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9UFj6P8omw/Render', 17:17:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:17 INFO - 'HOME': '/Users/cltbld', 17:17:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:17 INFO - 'LOGNAME': 'cltbld', 17:17:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:17 INFO - 'MOZ_NO_REMOTE': '1', 17:17:17 INFO - 'NO_EM_RESTART': '1', 17:17:17 INFO - 'PAGER': '/bin/cat', 17:17:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:17 INFO - 'PWD': '/builds/slave/test', 17:17:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:17 INFO - 'SHELL': '/bin/bash', 17:17:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GWg1M9aklk/Listeners', 17:17:17 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 17:17:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:17 INFO - 'USER': 'cltbld', 17:17:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:17 INFO - 'XPC_FLAGS': '0x0', 17:17:17 INFO - 'XPC_SERVICE_NAME': '0', 17:17:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:17:17 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-7j7A0w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:17:18 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 17:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:17:18 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-orFklV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:17:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:17:18 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-juiho_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:17:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:17:18 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-w5Wm6p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:17:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:17:18 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-CKLpQL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:17:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 17:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:17:18 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-OAQNXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:17:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 17:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:17:18 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-vPrBzf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:17:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 17:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:17:18 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-lTBD8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:17:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:17:18 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-cN1kxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:17:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 17:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:17:18 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-n9ZEN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:17:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:17:19 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-dgKsmz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:17:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 17:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:17:19 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-YG2hg4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:17:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 17:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:17:19 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-ejnNlj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:17:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 17:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:17:19 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-AsOVFN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:17:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 17:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:17:19 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-wUs2XM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:17:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 17:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:17:19 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-qVy9ul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:17:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 17:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:17:19 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-J3ReAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:17:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 17:17:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:17:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:17:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:17:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:17:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:17:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:17:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:22 INFO - Downloading blessings-1.6.tar.gz 17:17:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:17:22 INFO - Installing collected packages: blessings 17:17:22 INFO - Running setup.py install for blessings 17:17:22 INFO - Successfully installed blessings 17:17:22 INFO - Cleaning up... 17:17:22 INFO - Return code: 0 17:17:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:17:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:17:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:17:22 INFO - Reading from file tmpfile_stdout 17:17:22 INFO - Current package versions: 17:17:22 INFO - blessings == 1.6 17:17:22 INFO - blobuploader == 1.2.4 17:17:22 INFO - docopt == 0.6.1 17:17:22 INFO - manifestparser == 1.1 17:17:22 INFO - mozInstall == 1.12 17:17:22 INFO - mozcrash == 0.17 17:17:22 INFO - mozdebug == 0.1 17:17:22 INFO - mozdevice == 0.48 17:17:22 INFO - mozfile == 1.2 17:17:22 INFO - mozhttpd == 0.7 17:17:22 INFO - mozinfo == 0.9 17:17:22 INFO - mozleak == 0.1 17:17:22 INFO - mozlog == 3.1 17:17:22 INFO - moznetwork == 0.27 17:17:22 INFO - mozprocess == 0.22 17:17:22 INFO - mozprofile == 0.28 17:17:22 INFO - mozrunner == 6.11 17:17:22 INFO - mozscreenshot == 0.1 17:17:22 INFO - mozsystemmonitor == 0.0 17:17:22 INFO - moztest == 0.7 17:17:22 INFO - mozversion == 1.4 17:17:22 INFO - psutil == 3.1.1 17:17:22 INFO - requests == 1.2.3 17:17:22 INFO - wsgiref == 0.1.2 17:17:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:17:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:17:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:17:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:22 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 0x10a2a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe73473fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a148e00>, '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 0x109fb1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a143b20>, '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.9UFj6P8omw/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.GWg1M9aklk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:17:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 17:17:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:17:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9UFj6P8omw/Render', 17:17:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:22 INFO - 'HOME': '/Users/cltbld', 17:17:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:22 INFO - 'LOGNAME': 'cltbld', 17:17:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:22 INFO - 'MOZ_NO_REMOTE': '1', 17:17:22 INFO - 'NO_EM_RESTART': '1', 17:17:22 INFO - 'PAGER': '/bin/cat', 17:17:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:22 INFO - 'PWD': '/builds/slave/test', 17:17:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:22 INFO - 'SHELL': '/bin/bash', 17:17:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GWg1M9aklk/Listeners', 17:17:22 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 17:17:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:22 INFO - 'USER': 'cltbld', 17:17:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:22 INFO - 'XPC_FLAGS': '0x0', 17:17:22 INFO - 'XPC_SERVICE_NAME': '0', 17:17:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:17:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 17:17:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 17:17:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:17:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 17:17:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 17:17:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 17:17:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 17:17:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 17:17:23 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:17:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:17:23 INFO - Cleaning up... 17:17:23 INFO - Return code: 0 17:17:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:17:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:17:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:23 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 0x10a2a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe73473fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a148e00>, '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 0x109fb1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a143b20>, '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.9UFj6P8omw/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.GWg1M9aklk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:17:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:17:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:17:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9UFj6P8omw/Render', 17:17:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:23 INFO - 'HOME': '/Users/cltbld', 17:17:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:23 INFO - 'LOGNAME': 'cltbld', 17:17:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:23 INFO - 'MOZ_NO_REMOTE': '1', 17:17:23 INFO - 'NO_EM_RESTART': '1', 17:17:23 INFO - 'PAGER': '/bin/cat', 17:17:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:23 INFO - 'PWD': '/builds/slave/test', 17:17:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:23 INFO - 'SHELL': '/bin/bash', 17:17:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GWg1M9aklk/Listeners', 17:17:23 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 17:17:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:23 INFO - 'USER': 'cltbld', 17:17:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:23 INFO - 'XPC_FLAGS': '0x0', 17:17:23 INFO - 'XPC_SERVICE_NAME': '0', 17:17:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:23 INFO - Downloading/unpacking jsonschema 17:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:17:25 INFO - Downloading/unpacking functools32 (from jsonschema) 17:17:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:26 INFO - Downloading functools32-3.2.3-2.tar.gz 17:17:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:17:26 INFO - warning: no files found matching '*.txt' 17:17:26 INFO - no previously-included directories found matching 'build' 17:17:26 INFO - no previously-included directories found matching 'dist' 17:17:26 INFO - no previously-included directories found matching '.git*' 17:17:26 INFO - Installing collected packages: jsonschema, functools32 17:17:26 INFO - Running setup.py install for functools32 17:17:26 INFO - warning: no files found matching '*.txt' 17:17:26 INFO - no previously-included directories found matching 'build' 17:17:26 INFO - no previously-included directories found matching 'dist' 17:17:26 INFO - no previously-included directories found matching '.git*' 17:17:26 INFO - Successfully installed jsonschema functools32 17:17:26 INFO - Cleaning up... 17:17:26 INFO - Return code: 0 17:17:26 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:17: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')]} 17:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17: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')]} 17:17:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:26 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 0x10a2a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe73473fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a148e00>, '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 0x109fb1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a143b20>, '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.9UFj6P8omw/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.GWg1M9aklk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:17:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 17:17:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 17:17:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9UFj6P8omw/Render', 17:17:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:26 INFO - 'HOME': '/Users/cltbld', 17:17:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:26 INFO - 'LOGNAME': 'cltbld', 17:17:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:26 INFO - 'MOZ_NO_REMOTE': '1', 17:17:26 INFO - 'NO_EM_RESTART': '1', 17:17:26 INFO - 'PAGER': '/bin/cat', 17:17:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:26 INFO - 'PWD': '/builds/slave/test', 17:17:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:26 INFO - 'SHELL': '/bin/bash', 17:17:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GWg1M9aklk/Listeners', 17:17:26 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 17:17:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:26 INFO - 'USER': 'cltbld', 17:17:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:26 INFO - 'XPC_FLAGS': '0x0', 17:17:26 INFO - 'XPC_SERVICE_NAME': '0', 17:17:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:26 INFO - Downloading/unpacking flake8 17:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:29 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:17:29 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:29 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:17:29 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:29 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:17:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:17:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:17:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:17:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:17:30 INFO - no previously-included directories found matching 'docs/_build' 17:17:30 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:17:30 INFO - Running setup.py install for pep8 17:17:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:17:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:17:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:17:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:17:30 INFO - no previously-included directories found matching 'docs/_build' 17:17:30 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:17:30 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:17:30 INFO - Cleaning up... 17:17:30 INFO - Return code: 0 17:17:30 INFO - Running post-action listener: _resource_record_post_action 17:17:30 INFO - Running post-action listener: _start_resource_monitoring 17:17:30 INFO - Starting resource monitoring. 17:17:30 INFO - ##### 17:17:30 INFO - ##### Running install step. 17:17:30 INFO - ##### 17:17:30 INFO - Running pre-action listener: _resource_record_pre_action 17:17:30 INFO - Running main action method: install 17:17:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:17:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:17:31 INFO - Reading from file tmpfile_stdout 17:17:31 INFO - Detecting whether we're running mozinstall >=1.0... 17:17:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:17:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:17:31 INFO - Reading from file tmpfile_stdout 17:17:31 INFO - Output received: 17:17:31 INFO - Usage: mozinstall [options] installer 17:17:31 INFO - Options: 17:17:31 INFO - -h, --help show this help message and exit 17:17:31 INFO - -d DEST, --destination=DEST 17:17:31 INFO - Directory to install application into. [default: 17:17:31 INFO - "/builds/slave/test"] 17:17:31 INFO - --app=APP Application being installed. [default: firefox] 17:17:31 INFO - mkdir: /builds/slave/test/build/application 17:17:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:17:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 17:17:52 INFO - Reading from file tmpfile_stdout 17:17:52 INFO - Output received: 17:17:52 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 17:17:52 INFO - Running post-action listener: _resource_record_post_action 17:17:52 INFO - ##### 17:17:52 INFO - ##### Running run-tests step. 17:17:52 INFO - ##### 17:17:52 INFO - Running pre-action listener: _resource_record_pre_action 17:17:52 INFO - Running main action method: run_tests 17:17:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:17:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:17:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:17:52 INFO - 2016-07-08 17:17:52.808 screenresolution[1645:7483] starting screenresolution argv=screenresolution get 17:17:52 INFO - 2016-07-08 17:17:52.811 screenresolution[1645:7483] Display 0: 1600x1200x32@60 17:17:52 INFO - 2016-07-08 17:17:52.817 screenresolution[1646:7485] starting screenresolution argv=screenresolution list 17:17:52 INFO - Available Modes on Display 0 17:17:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:17:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:17:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:17:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:17:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:17:53 INFO - Intel Iris: 17:17:53 INFO - Chipset Model: Intel Iris 17:17:53 INFO - Type: GPU 17:17:53 INFO - Bus: Built-In 17:17:53 INFO - VRAM (Dynamic, Max): 1536 MB 17:17:53 INFO - Vendor: Intel (0x8086) 17:17:53 INFO - Device ID: 0x0a2e 17:17:53 INFO - Revision ID: 0x0009 17:17:53 INFO - Displays: 17:17:53 INFO - Display: 17:17:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:17:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:17:53 INFO - Display Serial Number: 1600x1200 60 17:17:53 INFO - Main Display: Yes 17:17:53 INFO - Mirror: Off 17:17:53 INFO - Online: Yes 17:17:53 INFO - Rotation: Supported 17:17:53 INFO - Return code: 0 17:17:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:17:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:17:53 INFO - Python 2.7.3 17:17:53 INFO - Return code: 0 17:17:53 INFO - grabbing minidump binary from tooltool 17:17:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:17:53 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 0x10a148e00>, '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 0x109fb1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a143b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:17:53 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 17:17:53 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 17:17:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:17:53 INFO - Return code: 0 17:17:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:17:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:17:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:17:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:17:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:17:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:17:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:17:54 INFO - Return code: 0 17:17:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0292', '--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 17:17:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0292 --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 17:17:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9UFj6P8omw/Render', 17:17:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:54 INFO - 'HOME': '/Users/cltbld', 17:17:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:54 INFO - 'LOGNAME': 'cltbld', 17:17:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:17:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:17:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:54 INFO - 'MOZ_NO_REMOTE': '1', 17:17:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:17:54 INFO - 'NO_EM_RESTART': '1', 17:17:54 INFO - 'PAGER': '/bin/cat', 17:17:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:54 INFO - 'PWD': '/builds/slave/test', 17:17:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:17:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:54 INFO - 'SHELL': '/bin/bash', 17:17:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GWg1M9aklk/Listeners', 17:17:54 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 17:17:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:54 INFO - 'USER': 'cltbld', 17:17:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:54 INFO - 'XPC_FLAGS': '0x0', 17:17:54 INFO - 'XPC_SERVICE_NAME': '0', 17:17:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0292', '--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 17:17:54 INFO - mozversion application_buildid: 20160708151127 17:17:54 INFO - mozversion application_changeset: cbacbb7c2c3523d036f737c9dc6c4406d14ade2b 17:17:54 INFO - mozversion application_display_name: Firefox 17:17:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:17:54 INFO - mozversion application_name: Firefox 17:17:54 INFO - mozversion application_remotingname: firefox 17:17:54 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 17:17:54 INFO - mozversion application_vendor: Mozilla 17:17:54 INFO - mozversion application_version: 48.0 17:17:54 INFO - mozversion platform_buildid: 20160708151127 17:17:54 INFO - mozversion platform_changeset: cbacbb7c2c3523d036f737c9dc6c4406d14ade2b 17:17:54 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 17:17:54 INFO - mozversion platform_version: 48.0 17:17:54 INFO - using testdate: 1468023474 17:17:54 INFO - actual date: 1468023474 17:17:54 INFO - starting webserver on 'localhost:49278' 17:17:54 INFO - SUITE-START | Running 2 tests 17:17:54 INFO - TEST-START | dromaeo_css 17:17:54 INFO - operating with platform_type : mac_ 17:17:54 INFO - Initialising browser for dromaeo_css test... 17:17:54 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmpnWQ8B4/profile http://localhost:49278/getInfo.html) 17:17:56 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 17:17:56 INFO - PROCESS | 1664 | colorDepth:24 17:17:56 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 17:17:56 INFO - PROCESS | 1664 | __metrics 17:17:56 INFO - PROCESS | 1664 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:17:56 ERROR - PROCESS | 1664 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:17:57 INFO - TEST-INFO | 1664: exit 0 17:17:57 INFO - Browser initialized. 17:17:57 INFO - Running cycle 1/1 for dromaeo_css test... 17:17:57 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmpnWQ8B4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:18:09 INFO - PROCESS | 1667 | RSS: Main: 161435648 17:18:09 INFO - PROCESS | 1667 | 17:19:00 INFO - PROCESS | 1667 | 1468023540745 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 17:21:10 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 17:21:11 INFO - PROCESS | 1667 | RSS: Main: 253194240 17:21:11 INFO - PROCESS | 1667 | 17:24:14 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 17:24:15 INFO - PROCESS | 1667 | RSS: Main: 304201728 17:24:15 INFO - PROCESS | 1667 | 17:27:16 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 17:27:17 INFO - PROCESS | 1667 | RSS: Main: 306405376 17:27:17 INFO - PROCESS | 1667 | 17:30:19 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 17:30:20 INFO - PROCESS | 1667 | RSS: Main: 302272512 17:30:20 INFO - PROCESS | 1667 | 17:33:23 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 17:33:23 INFO - PROCESS | 1667 | RSS: Main: 273489920 17:33:23 INFO - PROCESS | 1667 | 17:36:26 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 17:36:26 INFO - PROCESS | 1667 | RSS: Main: 312889344 17:36:26 INFO - PROCESS | 1667 | 17:36:26 INFO - PROCESS | 1667 | __start_tp_report 17:36:26 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 17:36:26 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 17:36:26 INFO - PROCESS | 1667 | |i|pagename|runs| 17:36:26 INFO - PROCESS | 1667 | |0;dojo.html;3557.430811070229;3639.3813051781194;3644.580873199582;3654.104744337638;3720.2373513429748;5874.089516124996;5919.230500035;5955.731992060357;5963.224780778498;5965.821025369235;5925.4370834770625;5951.553633477485;5982.162497250389;5988.221531200948;5997.220361353029;5279.815206467774;5292.206169074631;5369.328833895763;5396.676199428027;5415.864603384907;5372.382176049765;5409.783608655649;5414.593905457087;5416.322959630046;5417.7291135443265;5316.574674026068;5378.408375078739;5398.217258497515;5400.83797486076;5424.159255315412;5417.214503896952;5425.186222066722;5432.402435732106;5447.400785913546;5504.972475137599;5164.8967020659375;5318.468153184651;5328.8401347959625;5341.252224688546;5353.277307563468;5705.315362156526;5745;5772.884542309131;5777.88444231113;5894.28254593044;5921.141434491975;6263.1544741459775;6319.273283572402;6341.682915854189;6352.205974253218;6253.1558239637525;6346.048092786073;6365.522585806083;6378.553501254868;6390.7763228286785;6053.152558641794;6125.540584456184;6133.82435864808;6143.539234557426;6152.107944348089;5542.251796007568;6481;6508.284088750233;6527.510436717265;6527.934720652733;6205.689715514206;6257.2491301044165;6370.713317900706;6387.872242555216;6387.936120638827;6329.461995730322;6346.27017892939;6374.298827129012;6375.458086062644;6376.33048529907;6260.092286618415;6317.178766760292;6334.556581039283;6337.873242535124;6351.555391122577;6272.2159730033745;6330.335314791881;6334.461570766444;6366.872662546723;6379.106925030499;5065.11360511909;5085.771140298695;5111.46329635383;5121.334226550524;5146.742662866916;4525.0497395546645;4572.245579479415;4575.3823233863;4579.061292435043;4595.379623750818;5988;6094.421030002142;6144.907826382608;6152.753889844356;6167.6916154193;5842.620229685057;5984.042553191469;6347.365263473708;6363.236411630542;6380.840478988062;6097.5731698780655;6118.785842495491;6120.693965301717;6122.234720659918;6141.692915354214;5515.531179849758;5612.635178713371;5615.950178184933;5623.943760562423;5626.746796394172;6486.481081513468;6605.306442823435;6631.336866313427;6637.966810166112;6638.7676431325635;6351.618902865843;6371.6814159290925;6380.96809515968;6389.424951754364;6397.520185986163;6434.903476447951;6513.967430162819;6583.572067815577;6586.176727909012;6622.10601568782;6313.084602732486;6330.208723909512;6338.524610654127;6341;6347.5239357047485;6273.937260627518;6287.685615719287;6335.841603959753;6336.651484168231;6367.649779262185;6190.102435146969;6268.27914789805;6307.211598550181;6358.809235722936;6361.332060133619;6327.96836015817;6354.301026886947;6356.364363563606;6360.809175724736;6369.426751592379;6216.658083805434;6223.284322302812;6238.906416403667;6259.998400255937;6260.499160067275;5124.897502049864;6188.504919606331;6331.366863313632;6337.397947195009;6341.524385671185;6241.906371404523;6267.592606480564;6298.212723409574;6298.307186209422;6303 17:36:26 INFO - PROCESS | 1667 | |1;ext.html;531.8138651471985;561.3880869851861;574.3643297373403;581.2879222951882;609.5062998970834;31252.53121203181;32495.837520812423;32594.67405325946;32607.1848203795;32664.183395415126;36586.719464818736;37094;41295;42660.3600945986;42673.35989960153;10016.649417270397;10183.694489165337;10187.083162515371;10188.949055254714;10217.795644087113;10035.347702399356;10197.898021019806;10198.592056317775;10212.642557510451;10212.897871021307;9297.860532092023;9505.287103467268;9510.28672849539;12229;12459.065570082235;12008.09939254555;12103.576374826927;12111.091668124925;12112.939435302855;12123.030157587373;11943.163978521507;12003.159778815487;12021.57924472648;12030.037596992308;12040.81938770919;43065.784671076755;43758.781206093765;43874.78062609698;43889;44018.779906100266;3271.5583396241677;3283.885064022748;3441.105312618737;3516.208853008078;3628.673419392241;24387.024519019218;28977.710222897713;30476.84761576178;30930.845345773352;31531;2826.6749323827817;2832.3910359272854;2845.231787417386;2862.427514497109;2874.841883696396;504345;506765.4661726705;506892;507132;507289;37867.86396408112;38627.80686096552;38700;54497;66785.66607166933;37645.81177094097;37745.43381849275;37930;37969.24061518754;38015.23969520594;13677.452901883813;16311.021338719715;16328.673426531403;16328.877245957949;16331.26509307084;13822.654433639038;13891.305434728425;13892.888568914463;13912.930435347758;13924.30378481072;8217;8687.522186279815;8710.303175745925;8756.649734010696;8803.559822008874;3370.1574606348413;3428.451447768345;3513.191965847869;3520.489529018278;3540.097275194809;1025.3232866308201;1025.5795123999274;1049.7480604654982;1155.630198336541;1225.9877401226051;23598.174063908024;28868.42263154725;30141.547876781864;30150.38195416564;30684.69315306863;1093.8577984862077;1132.116948779953;1151.7120719820045;1225.66906935127;1251.9812202816966;14243.202153378403;15227;15360.61598460025;15410.768838467431;15626.68746625061;644808.7759561172;649217;652907;653081;656281;56837.715811420676;66139;66789.33210667929;67041.3295867035;70653.64673176705;1569.4350033987755;1571.2615070916652;1578.7710781936555;1589.32453707174;1591.2282542966652;30307.848460757556;31493.055208343787;31740;31932.00229317761;32076.19809504781;57472;58299;58347;58460.70769646124;58558;541584;782125;898846;922247;922860;24142.758572414754;28918.277043074093;28966.13101606955;29393.55909661314;29416.852915735286;48453;48653;49308.50691493039;49637.00725985532;50855.74572127116;38846.240291687114;39557.40663889986;41113.79443102885;41406.585934140276;41832.79083604563;38717.419238712006;39325.41011884881;39921.60078399179;39994.800025999684;40837.97905052397;35985.640143599274;39545.80227098846;40247.19505609813;40746.1850762989;40897.591024090565;34138.82930585331;35069.47395789014;35219.47170792389;35412;35569.93290201298;23332.65001024952;25703.228903132935;25791.226263212135;25873.741262587886;26345.868270658524 17:36:26 INFO - PROCESS | 1667 | |2;jquery.html;418.44555963348563;423.99576004239947;424.7578880038378;429.3881219262552;433.4127257565998;20320.679155854858;21074;21434;21729.78270217297;21910.904454777276;15767.526974190763;17444.38944636938;21107.15571377143;21227.363179104614;21387.893060534716;5701.258836351279;6042.486388656947;6053.061775424816;6053.273607167146;6068.211132552762;5942.227510423639;5951.49412299955;6022.096685497167;6033.668148251866;6056.364081771417;5768.278965129359;5913.379095195007;5930.140129681194;5964.2842858857;5968.403159684019;5718.456746609066;5861.941380586203;5907.113932910098;5919.8456301021315;5921.200637913869;5212.139996900507;5251.86870328241;5256.316678831766;5259.789608415659;5260.289860868772;25894.35264118412;26157;26786.73213267862;27391.315217119532;27438.725612744016;2033.8983050847546;2113.4505028692497;2240.630296001158;2327.1273272522803;2367.7513861044513;10112.848307275397;10233.334833235816;10255.743606409826;11534.019608333301;13901.721965560631;1683.0070258547394;1753.2285794250488;1769.3276554909053;1816.3370369815054;1831.4597193827854;97814;102009.48995254976;103432;104113;104663.4766826161;11642.708932276591;11852.11109166816;11857.940710296565;11859.58491452795;11991.880081199077;15372.692546149014;15390.153541555099;15427.151506667242;15446.691066178613;15448.768268475982;9052.683156089506;9144.679936202201;9149.908500915039;9212.355135140477;9231.76920576991;8914;8924.68763593271;8986.68546600866;9142.634294628273;9206.539673016323;3779.7732136071922;4715.363425937455;4724.976375118103;4764.190087685102;4782.0914026334885;1847.8152184781684;2017.70743242232;2025.3721346382667;2047.0481226229874;2085.1555120176354;884.8584226523855;918.5636822509255;1008.2035192198082;1022.4632068164274;1048.3290693955873;17425.651486970444;20316;20403.285884993955;20471.795282047286;20543.178272868918;973.4694591447678;1024.4519182237468;1039.740064983754;1045.1690905729963;1060.4750648429076;10726.946365268279;11046.281991670516;11074.889251107386;11079.612213572647;11084.445777711082;612608;614405;614512;628536;630382;119346;124889;129987;130877;131292.3435382836;895.3464763065034;1031.7884833609091;1050;1050.4537640426936;1050.8791488978713;17839;17899.552511187325;17914.5521361967;18235.635287294444;18468.907655461637;51828;51934.961300773015;52789.472105279994;53061;54274;73610;74847;76571.61714191393;76656;76999.61500192463;8085.312748416451;8326.375521835766;8413.747587572137;8560.400771945904;8603.225709686158;9489.240860730986;12046.457909394274;12662.113652044269;12718.872811272138;12750.744985100431;10575;11305.43472826339;11610.361430121424;11702.531898723955;11741.94322510978;11235;11257.549698012315;11327.830082548933;11518;11573.247738896946;6150.446459818638;9592.376495527767;11468.713282168013;11793.587224447447;12010.519579216734;8276.220612568326;8380.16198380157;8536.615852286537;8567.614457349559;8777.956110219407;10619.15046796239;11539.942300288445;11797.941010294893;11845.348505832262;11882.881171188177 17:36:26 INFO - PROCESS | 1667 | |3;mootools.html;1489.3223583269614;1602.4391462987953;1625.4067265448107;1904.2573396375417;2017.4048655646582;8254.463459875104;8992.41549299296;9129.543522823866;9137.725868223964;9148.405353652024;8815.911840881588;9104.954475227614;9107.544622768868;9112.088791120885;9115.68095116671;2049.7950204979456;2547.0703193334484;2562.295368773593;2574.7296533863955;2574.961375579368;2409.3976505873534;2584.3539115221197;2590.2099859542927;2590.313566904772;2603.635491031257;2462.679851619287;2490.402303447178;2492.2149522900227;2503.7746602805746;2509.14689005738;2548.6686730724978;2577.1237779155085;2587.1721049264243;2595.429005618761;2599.636050952868;2543.122622695167;2577.420080481895;2587.068655284096;2590.896364145432;2591.0931174089105;8958.328125390557;9189.67836125739;9237.815243695088;9265.768355791091;9302.976672566012;5572.219889215513;5730;5739.598228124033;5790.218320526717;5799.246098007232;3714.4985426967282;3783.886483405502;3838.059675379522;3839.36650452675;3850.0182453474185;1066.72798436399;1075.93544387337;1078.282941843673;1099.230538622959;1113.6993011886746;12517.749645007048;12594.74810503794;12596.496140154475;12626.936865315707;12652.746945061139;4768.880777980578;4815.903681926412;4845.806167753321;4891.217405215149;4896.755162241873;9700.175485083846;9710.320277580502;9715.659951901649;9716.902830971741;9729.075737804897;5574.080276754371;5576.721163941867;5629.4652008059165;5638.154276858504;5646.265985421951;4648.279516674921;4749.3588365570895;4801.639877009238;4820.758962051884;5247.344081989751;3419.401604719214;3470.427379482357;3594.5866225383893;4066.552679205285;4135.9172816544105;1113.905318047975;1280.1934781087855;1285.6528737241078;1288.278564004142;1298.214580178997;865.7099871542978;938.671464987249;958.5925981457992;984.625842179967;991.4546999150439;6056.848578785565;6094.299155597075;6141.907871381933;6146.569740118149;6199.659018754011;1001.1240164855751;1023.8464230365356;1024;1034.6792494326783;1069.1233188785122;4055.1889622075705;4093.6520395766693;4104.692148088905;4157.605027522381;4162.354835000579;11136.665900023012;11650.067994560415;11652.76694466106;11663.89193026661;11664.766704665859;11370.09039276856;11370.147238956946;11385.259958102697;11399.886001139883;11478.02436792882;8271.793205169799;8521.147885211552;8590.441621294596;8615.052344242129;8718.694845680371;5940.108983652487;5988.790392336247;6066.757329706938;6073.817785466443;6077.756889724362;9594.42433453995;9702.70891873245;9775.413475191512;9788.070133337322;9987.400755954666;9848.359856609584;10151.340162889388;10189.388636681822;10378.117859981987;10392.844107338547;7126.572405655677;7317.853642927005;7697.114831794415;7957.084935232522;8119.147489513516;7345;8240.629171687408;8700.434471759316;8759.905011873516;8765.91234087651;7626.504277221963;7656.885146722692;7757.844843103219;7861.1745766693675;7863.724769633035;7477.289657482531;7513.323800857949;7514.436417268836;7616.162222155448;7644.923550764421;7181.892271616034;7307.634618269171;7323.743668971773;7334.119905611361;7349;4943.703377797344;4957.00859828043;5002.874928126826;5100.234964755168;5156.819511317086;5062.240663900445;5088.109580823415;5133.614978876524;5172.586193104617;5324.9733751331 17:36:26 INFO - PROCESS | 1667 | |4;prototype.html;335.6073394128869;344.37324070192244;346.9791812491251;355.5520044743622;356.718192627824;5381.4349493303225;6706.0611514387965;6707.061011458393;6723.226828914676;6778;6739.595624262548;6751.662416879149;6764.222114456839;6767.458603311736;6773.695183716733;4718.221493453576;4818.229083346665;4842.515748425165;4883.731394773304;4925.285833554132;4814.301926220696;4916.704997700132;4917.21324588066;4917.606591472691;4935.185694360444;4875.70745755254;4878.317035615017;4880.7071575705395;4889.3399391082285;4906.828261010865;4680.859574212779;4803.927941080887;4805.0389922015565;4814.253790662453;4820.132376172288;4126.607972242632;4181.330987042059;4184.326269492228;4215.873523794291;4228.112096459717;5199.428062913076;6460.773872914426;6481.384268494533;6514.283428822873;6529.444997175198;5933.376995415462;5940.881182376372;5948.434898684662;5950.196723442323;5951.58338916276;6831.931680683179;7189.424846012306;7204.747833825843;7219.711211551532;7228.421726261887;3877.670398016172;4619.099275641252;4638.5593368629925;4653.302004699322;4657.720536767771;7508.962455187689;7614.010178676792;7630.542167469914;7639.350655194316;7646.4265180111715;4742.312364707098;4743.620510359163;4749.95250047502;4755.5719985201495;4756.7859446325;4809.2064809306075;4813.109574728686;4813.374261346073;4813.831515896927;4824.565789079;5532.86167845799;5720.342160651495;5737.196792449061;5740.942590574125;5763.366029736726;7198.172210195895;7220.566765994057;7228.277172282729;7232.891506627405;7250.7462238822445;1621.3514594162193;1665.5003498950266;1671.1226606031687;1671.3147609480054;1676.8490835824834;873.9868901966476;1035.7203555040096;1064.505005172583;1067.6796960911695;1067.9519421626046;5604.243427137284;6626.271110177877;6791.811432999304;6804.21751498584;6877.828054298682;7310.561366318038;7445.664945077485;7465.626718664045;7495.66269517873;7503.324700776956;5671.943280567224;6866.450683945272;6887;6922.134733158356;6974.511784175161;3716.680578394685;3955.6044395560216;4025.8590949316635;4028;4030.1939612077704;7441.665125069385;7555;7866.410019248579;7921.801954951173;7947.960260198662;7753.883691744629;7794.610269486503;7795.532268063935;7803.336716379058;7841.098273698483;7498.850022999619;7838.882416763753;7907.604619768988;7919.762407127911;7930.9603451982375;7536.321731044232;7547.0943486781935;7557.017587713562;7558.735444259424;7577.393808495417;7166;7471.439642026761;7475.588842613708;7481.70073197066;7485.288897554724;7892.329152022143;7958.681652733826;7980.603394406072;7992.880106798519;7999.4400391972;4755.310479980452;4831.033793241436;4832.565069143794;4837.032593481247;4841.104395686669;6886.69009894566;7535.623218838927;7577.280158384945;7579.54522728616;7582.393408527194;6388.9041664376;7317.634118293958;7348;7354.448416368687;7358.337749602561;7239.239879812544;7312.5246858954;7329.706811727471;7336.596487193256;7353.8161545961775;7104.928950710427;7129.108861392326;7135.678894449866;7139.464540159405;7147.714091436493;5986.485676015996;6963.6866341013765;7159.64201789898;7266.1280646322775;7321.048263725682;5486.341639003185;5740.425957404226;6988.37104660583;7060.71757129709;7178.24628414009 17:36:26 INFO - PROCESS | 1667 | |5;yui.html;714.9811518586014;715.6779449247838;761.889526018727;783.2872086401376;826.6899912532798;12723.36383180842;14470;15742;15767.290471928762;15814.762778558317;16547.42084027059;16642.33430662772;16653.83346166538;16658.83341166588;16686.249118789652;802.5826570183501;979.8873129590118;998.1316254858248;1004.5128112865254;1009.9444530550817;803.1245941923303;984.1732944326764;1001.644416232238;1004.5128112865254;1008.8991100889889;1043.0560342889696;1045.325764881651;1045.5242864496636;1049.5959055763549;1053.6628278950739;830.4726498673325;1026.856240126383;1038.397729316998;1038.8389799581078;1044.5821671331453;784.2510402565593;787.189195129018;788.8619491588948;789.2147313423123;790.8457850719112;15578.610534736723;15604.531864044096;15650.843491565052;15686.372545098182;15692.843071569252;12543.937280313632;12631;12654.557090501788;12676.68308292291;12817.743645127046;10194.643187488477;10279.229057820694;10671.893281067167;10806.135509159249;10861.891381086167;3542.982285088584;3568.1614820517157;3694.4458331250257;3697.630236976308;3747.8671481290394;86887;87742;88079.55960220222;88322.99999999936;88608.11391886063;9438.90561094394;9528.571214295373;9675.225981921429;9730.172935300438;9957;22920.885395572914;22961.770382296298;22980.770192298198;22991.310260692208;22999.96500157497;7361.227071157451;7582.658780354897;7767.572783496849;7792.376609871197;7960.880586791202;4161.812718427678;4231.640310573635;4237.745735255833;4307.203167414038;4416.116776644684;3780.111673756665;3854.6530812226474;3856.1709232515145;3858.3826587745825;3859.363205071188;675.6587923098804;689.2315068698365;689.9068625735562;698.0367093411061;720.1430297945428;586.3901542395861;608.9939100608938;619.3125630550086;627.1690010735794;630.5901164243279;7971.362291016821;8389.706360277361;8431.831363372698;8487.490750554864;8770.12298770118;584.4593750780512;599.1551911804335;618.5917294585552;620.981370558884;624.5940138909745;3870.651641352292;3894.6494815466745;3913.8043097844993;3979.621935916083;4004.3392840181627;94423.0557694428;95588.04411955792;95796.0420395801;96040.59898502454;96188.51905740426;70094;71902.28097718956;79617.60191199007;79653;79953;66449.66775166093;70441;71428;71466.28533714596;71562.64218678977;7810.140884502814;7883.724069657764;7894.36845052383;7961.243681850215;8277.130901255281;47915.80210494626;48760.51239487559;48805.26792098051;49012.52962411133;49126;51801;62148.68925655343;64383.35616643961;65879.34120658733;66302.66848665726;10491.108255798343;10643.095336896451;10709.678709638723;10726.946365268124;10830.458477076272;9700.708978730649;10775.353478791298;10959.835602466128;10992.890071099506;11368.317900925971;8626.482411055356;10310.845337320095;10715.089217416295;10745.409002504624;10985.61550345734;10444.321119127233;11395.772084558428;11476.942615286871;11817.586384476503;11818.46816893259;10922.126229901747;11255.887441125484;11533.135014873751;11580.42097895085;11617.419129043345;9738.415695058318;9776.462294573867;10065.949670251895;10132.189424845847;10252.794944100928;9523.619055237712;10058.34620749649;10083.546240419346;10095.798084038424;10136.645217417356 17:36:26 INFO - PROCESS | 1667 | __end_tp_report 17:36:26 INFO - PROCESS | 1667 | __start_cc_report 17:36:26 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,1205 17:36:26 INFO - PROCESS | 1667 | __end_cc_report 17:36:26 INFO - PROCESS | 1667 | __startTimestamp1468024586692__endTimestamp 17:36:26 INFO - PROCESS | 1667 | 17:36:26 INFO - PROCESS | 1667 | ------- Summary: start ------- 17:36:26 INFO - PROCESS | 1667 | Number of tests: 6 17:36:26 INFO - PROCESS | 1667 | 17:36:26 INFO - PROCESS | 1667 | [#0] dojo.html Cycles:165 Average:5929.83 Median:6205.69 stddev:631.55 (10.2%) stddev-sans-first:605.44 17:36:26 INFO - PROCESS | 1667 | Values: 3557.4 3639.4 3644.6 3654.1 3720.2 5874.1 5919.2 5955.7 5963.2 5965.8 5925.4 5951.6 5982.2 5988.2 5997.2 5279.8 5292.2 5369.3 5396.7 5415.9 5372.4 5409.8 5414.6 5416.3 5417.7 5316.6 5378.4 5398.2 5400.8 5424.2 5417.2 5425.2 5432.4 5447.4 5505.0 5164.9 5318.5 5328.8 5341.3 5353.3 5705.3 5745.0 5772.9 5777.9 5894.3 5921.1 6263.2 6319.3 6341.7 6352.2 6253.2 6346.0 6365.5 6378.6 6390.8 6053.2 6125.5 6133.8 6143.5 6152.1 5542.3 6481.0 6508.3 6527.5 6527.9 6205.7 6257.2 6370.7 6387.9 6387.9 6329.5 6346.3 6374.3 6375.5 6376.3 6260.1 6317.2 6334.6 6337.9 6351.6 6272.2 6330.3 6334.5 6366.9 6379.1 5065.1 5085.8 5111.5 5121.3 5146.7 4525.0 4572.2 4575.4 4579.1 4595.4 5988.0 6094.4 6144.9 6152.8 6167.7 5842.6 5984.0 6347.4 6363.2 6380.8 6097.6 6118.8 6120.7 6122.2 6141.7 5515.5 5612.6 5616.0 5623.9 5626.7 6486.5 6605.3 6631.3 6638.0 6638.8 6351.6 6371.7 6381.0 6389.4 6397.5 6434.9 6514.0 6583.6 6586.2 6622.1 6313.1 6330.2 6338.5 6341.0 6347.5 6273.9 6287.7 6335.8 6336.7 6367.6 6190.1 6268.3 6307.2 6358.8 6361.3 6328.0 6354.3 6356.4 6360.8 6369.4 6216.7 6223.3 6238.9 6260.0 6260.5 5124.9 6188.5 6331.4 6337.4 6341.5 6241.9 6267.6 6298.2 6298.3 6303.0 17:36:26 INFO - PROCESS | 1667 | 17:36:26 INFO - PROCESS | 1667 | [#1] ext.html Cycles:180 Average:76840.18 Median:18783.64 stddev:181644.99 (967.0%) stddev-sans-first:182064.20 17:36:26 INFO - PROCESS | 1667 | Values: 531.8 561.4 574.4 581.3 609.5 31252.5 32495.8 32594.7 32607.2 32664.2 36586.7 37094.0 41295.0 42660.4 42673.4 10016.6 10183.7 10187.1 10188.9 10217.8 10035.3 10197.9 10198.6 10212.6 10212.9 9297.9 9505.3 9510.3 12229.0 12459.1 12008.1 12103.6 12111.1 12112.9 12123.0 11943.2 12003.2 12021.6 12030.0 12040.8 43065.8 43758.8 43874.8 43889.0 44018.8 3271.6 3283.9 3441.1 3516.2 3628.7 24387.0 28977.7 30476.8 30930.8 31531.0 2826.7 2832.4 2845.2 2862.4 2874.8 504345.0 506765.5 506892.0 507132.0 507289.0 37867.9 38627.8 38700.0 54497.0 66785.7 37645.8 37745.4 37930.0 37969.2 38015.2 13677.5 16311.0 16328.7 16328.9 16331.3 13822.7 13891.3 13892.9 13912.9 13924.3 8217.0 8687.5 8710.3 8756.6 8803.6 3370.2 3428.5 3513.2 3520.5 3540.1 1025.3 1025.6 1049.7 1155.6 1226.0 23598.2 28868.4 30141.5 30150.4 30684.7 1093.9 1132.1 1151.7 1225.7 1252.0 14243.2 15227.0 15360.6 15410.8 15626.7 644808.8 649217.0 652907.0 653081.0 656281.0 56837.7 66139.0 66789.3 67041.3 70653.6 1569.4 1571.3 1578.8 1589.3 1591.2 30307.8 31493.1 31740.0 31932.0 32076.2 57472.0 58299.0 58347.0 58460.7 58558.0 541584.0 782125.0 898846.0 922247.0 922860.0 24142.8 28918.3 28966.1 29393.6 29416.9 48453.0 48653.0 49308.5 49637.0 50855.7 38846.2 39557.4 41113.8 41406.6 41832.8 38717.4 39325.4 39921.6 39994.8 40838.0 35985.6 39545.8 40247.2 40746.2 40897.6 34138.8 35069.5 35219.5 35412.0 35569.9 23332.7 25703.2 25791.2 25873.7 26345.9 17:36:26 INFO - PROCESS | 1667 | 17:36:26 INFO - PROCESS | 1667 | [#2] jquery.html Cycles:180 Average:35211.52 Median:21410.95 stddev:102922.35 (480.7%) stddev-sans-first:103177.92 17:36:26 INFO - PROCESS | 1667 | Values: 418.4 424.0 424.8 429.4 433.4 20320.7 21074.0 21434.0 21729.8 21910.9 15767.5 17444.4 21107.2 21227.4 21387.9 5701.3 6042.5 6053.1 6053.3 6068.2 5942.2 5951.5 6022.1 6033.7 6056.4 5768.3 5913.4 5930.1 5964.3 5968.4 5718.5 5861.9 5907.1 5919.8 5921.2 5212.1 5251.9 5256.3 5259.8 5260.3 25894.4 26157.0 26786.7 27391.3 27438.7 2033.9 2113.5 2240.6 2327.1 2367.8 10112.8 10233.3 10255.7 11534.0 13901.7 1683.0 1753.2 1769.3 1816.3 1831.5 97814.0 102009.5 103432.0 104113.0 104663.5 11642.7 11852.1 11857.9 11859.6 11991.9 15372.7 15390.2 15427.2 15446.7 15448.8 9052.7 9144.7 9149.9 9212.4 9231.8 8914.0 8924.7 8986.7 9142.6 9206.5 3779.8 4715.4 4725.0 4764.2 4782.1 1847.8 2017.7 2025.4 2047.0 2085.2 884.9 918.6 1008.2 1022.5 1048.3 17425.7 20316.0 20403.3 20471.8 20543.2 973.5 1024.5 1039.7 1045.2 1060.5 10726.9 11046.3 11074.9 11079.6 11084.4 612608.0 614405.0 614512.0 628536.0 630382.0 119346.0 124889.0 129987.0 130877.0 131292.3 895.3 1031.8 1050.0 1050.5 1050.9 17839.0 17899.6 17914.6 18235.6 18468.9 51828.0 51935.0 52789.5 53061.0 54274.0 73610.0 74847.0 76571.6 76656.0 76999.6 8085.3 8326.4 8413.7 8560.4 8603.2 9489.2 12046.5 12662.1 12718.9 12750.7 10575.0 11305.4 11610.4 11702.5 11741.9 11235.0 11257.5 11327.8 11518.0 11573.2 6150.4 9592.4 11468.7 11793.6 12010.5 8276.2 8380.2 8536.6 8567.6 8778.0 10619.2 11539.9 11797.9 11845.3 11882.9 17:36:26 INFO - PROCESS | 1667 | 17:36:26 INFO - PROCESS | 1667 | [#3] mootools.html Cycles:180 Average:5925.33 Median:5032.56 stddev:3314.07 (65.9%) stddev-sans-first:3306.60 17:36:26 INFO - PROCESS | 1667 | Values: 1489.3 1602.4 1625.4 1904.3 2017.4 8254.5 8992.4 9129.5 9137.7 9148.4 8815.9 9105.0 9107.5 9112.1 9115.7 2049.8 2547.1 2562.3 2574.7 2575.0 2409.4 2584.4 2590.2 2590.3 2603.6 2462.7 2490.4 2492.2 2503.8 2509.1 2548.7 2577.1 2587.2 2595.4 2599.6 2543.1 2577.4 2587.1 2590.9 2591.1 8958.3 9189.7 9237.8 9265.8 9303.0 5572.2 5730.0 5739.6 5790.2 5799.2 3714.5 3783.9 3838.1 3839.4 3850.0 1066.7 1075.9 1078.3 1099.2 1113.7 12517.7 12594.7 12596.5 12626.9 12652.7 4768.9 4815.9 4845.8 4891.2 4896.8 9700.2 9710.3 9715.7 9716.9 9729.1 5574.1 5576.7 5629.5 5638.2 5646.3 4648.3 4749.4 4801.6 4820.8 5247.3 3419.4 3470.4 3594.6 4066.6 4135.9 1113.9 1280.2 1285.7 1288.3 1298.2 865.7 938.7 958.6 984.6 991.5 6056.8 6094.3 6141.9 6146.6 6199.7 1001.1 1023.8 1024.0 1034.7 1069.1 4055.2 4093.7 4104.7 4157.6 4162.4 11136.7 11650.1 11652.8 11663.9 11664.8 11370.1 11370.1 11385.3 11399.9 11478.0 8271.8 8521.1 8590.4 8615.1 8718.7 5940.1 5988.8 6066.8 6073.8 6077.8 9594.4 9702.7 9775.4 9788.1 9987.4 9848.4 10151.3 10189.4 10378.1 10392.8 7126.6 7317.9 7697.1 7957.1 8119.1 7345.0 8240.6 8700.4 8759.9 8765.9 7626.5 7656.9 7757.8 7861.2 7863.7 7477.3 7513.3 7514.4 7616.2 7644.9 7181.9 7307.6 7323.7 7334.1 7349.0 4943.7 4957.0 5002.9 5100.2 5156.8 5062.2 5088.1 5133.6 5172.6 5325.0 17:36:26 INFO - PROCESS | 1667 | 17:36:26 INFO - PROCESS | 1667 | [#4] prototype.html Cycles:180 Average:5886.96 Median:6745.63 stddev:1915.06 (28.4%) stddev-sans-first:1874.56 17:36:26 INFO - PROCESS | 1667 | Values: 335.6 344.4 347.0 355.6 356.7 5381.4 6706.1 6707.1 6723.2 6778.0 6739.6 6751.7 6764.2 6767.5 6773.7 4718.2 4818.2 4842.5 4883.7 4925.3 4814.3 4916.7 4917.2 4917.6 4935.2 4875.7 4878.3 4880.7 4889.3 4906.8 4680.9 4803.9 4805.0 4814.3 4820.1 4126.6 4181.3 4184.3 4215.9 4228.1 5199.4 6460.8 6481.4 6514.3 6529.4 5933.4 5940.9 5948.4 5950.2 5951.6 6831.9 7189.4 7204.7 7219.7 7228.4 3877.7 4619.1 4638.6 4653.3 4657.7 7509.0 7614.0 7630.5 7639.4 7646.4 4742.3 4743.6 4750.0 4755.6 4756.8 4809.2 4813.1 4813.4 4813.8 4824.6 5532.9 5720.3 5737.2 5740.9 5763.4 7198.2 7220.6 7228.3 7232.9 7250.7 1621.4 1665.5 1671.1 1671.3 1676.8 874.0 1035.7 1064.5 1067.7 1068.0 5604.2 6626.3 6791.8 6804.2 6877.8 7310.6 7445.7 7465.6 7495.7 7503.3 5671.9 6866.5 6887.0 6922.1 6974.5 3716.7 3955.6 4025.9 4028.0 4030.2 7441.7 7555.0 7866.4 7921.8 7948.0 7753.9 7794.6 7795.5 7803.3 7841.1 7498.9 7838.9 7907.6 7919.8 7931.0 7536.3 7547.1 7557.0 7558.7 7577.4 7166.0 7471.4 7475.6 7481.7 7485.3 7892.3 7958.7 7980.6 7992.9 7999.4 4755.3 4831.0 4832.6 4837.0 4841.1 6886.7 7535.6 7577.3 7579.5 7582.4 6388.9 7317.6 7348.0 7354.4 7358.3 7239.2 7312.5 7329.7 7336.6 7353.8 7104.9 7129.1 7135.7 7139.5 7147.7 5986.5 6963.7 7159.6 7266.1 7321.0 5486.3 5740.4 6988.4 7060.7 7178.2 17:36:26 INFO - PROCESS | 1667 | 17:36:26 INFO - PROCESS | 1667 | [#5] yui.html Cycles:180 Average:18488.32 Median:4234.69 stddev:26051.12 (615.2%) stddev-sans-first:26090.02 17:36:26 INFO - PROCESS | 1667 | Values: 715.0 715.7 761.9 783.3 826.7 12723.4 14470.0 15742.0 15767.3 15814.8 16547.4 16642.3 16653.8 16658.8 16686.2 802.6 979.9 998.1 1004.5 1009.9 803.1 984.2 1001.6 1004.5 1008.9 1043.1 1045.3 1045.5 1049.6 1053.7 830.5 1026.9 1038.4 1038.8 1044.6 784.3 787.2 788.9 789.2 790.8 15578.6 15604.5 15650.8 15686.4 15692.8 12543.9 12631.0 12654.6 12676.7 12817.7 10194.6 10279.2 10671.9 10806.1 10861.9 3543.0 3568.2 3694.4 3697.6 3747.9 86887.0 87742.0 88079.6 88323.0 88608.1 9438.9 9528.6 9675.2 9730.2 9957.0 22920.9 22961.8 22980.8 22991.3 23000.0 7361.2 7582.7 7767.6 7792.4 7960.9 4161.8 4231.6 4237.7 4307.2 4416.1 3780.1 3854.7 3856.2 3858.4 3859.4 675.7 689.2 689.9 698.0 720.1 586.4 609.0 619.3 627.2 630.6 7971.4 8389.7 8431.8 8487.5 8770.1 584.5 599.2 618.6 621.0 624.6 3870.7 3894.6 3913.8 3979.6 4004.3 94423.1 95588.0 95796.0 96040.6 96188.5 70094.0 71902.3 79617.6 79653.0 79953.0 66449.7 70441.0 71428.0 71466.3 71562.6 7810.1 7883.7 7894.4 7961.2 8277.1 47915.8 48760.5 48805.3 49012.5 49126.0 51801.0 62148.7 64383.4 65879.3 66302.7 10491.1 10643.1 10709.7 10726.9 10830.5 9700.7 10775.4 10959.8 10992.9 11368.3 8626.5 10310.8 10715.1 10745.4 10985.6 10444.3 11395.8 11476.9 11817.6 11818.5 10922.1 11255.9 11533.1 11580.4 11617.4 9738.4 9776.5 10065.9 10132.2 10252.8 9523.6 10058.3 10083.5 10095.8 10136.6 17:36:26 INFO - PROCESS | 1667 | -------- Summary: end -------- 17:36:26 INFO - PROCESS | 1667 | 17:36:27 INFO - TEST-INFO | 1667: exit 0 17:36:27 INFO - TEST-OK | dromaeo_css | took 1112754ms 17:36:27 INFO - TEST-START | kraken 17:36:27 INFO - operating with platform_type : mac_ 17:36:27 INFO - Initialising browser for kraken test... 17:36:27 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmpqI0ZUR/profile http://localhost:49278/getInfo.html) 17:36:29 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 17:36:29 INFO - PROCESS | 1675 | colorDepth:24 17:36:29 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 17:36:29 INFO - PROCESS | 1675 | __metrics 17:36:29 INFO - PROCESS | 1675 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:36:29 ERROR - PROCESS | 1675 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:36:30 INFO - TEST-INFO | 1675: exit 0 17:36:30 INFO - Browser initialized. 17:36:30 INFO - Running cycle 1/1 for kraken test... 17:36:30 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmpqI0ZUR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:36:41 INFO - PROCESS | 1678 | RSS: Main: 161415168 17:36:41 INFO - PROCESS | 1678 | 17:36:44 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 17:36:45 INFO - PROCESS | 1678 | RSS: Main: 224243712 17:36:45 INFO - PROCESS | 1678 | 17:36:48 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 17:36:48 INFO - PROCESS | 1678 | RSS: Main: 239869952 17:36:48 INFO - PROCESS | 1678 | 17:36:51 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 17:36:52 INFO - PROCESS | 1678 | RSS: Main: 325160960 17:36:52 INFO - PROCESS | 1678 | 17:36:54 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 17:36:55 INFO - PROCESS | 1678 | RSS: Main: 231845888 17:36:55 INFO - PROCESS | 1678 | 17:36:57 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:36:57 INFO - PROCESS | 1678 | RSS: Main: 232521728 17:36:57 INFO - PROCESS | 1678 | 17:37:00 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 17:37:01 INFO - PROCESS | 1678 | RSS: Main: 270761984 17:37:01 INFO - PROCESS | 1678 | 17:37:04 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 17:37:04 INFO - PROCESS | 1678 | RSS: Main: 271187968 17:37:04 INFO - PROCESS | 1678 | 17:37:07 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 17:37:07 INFO - PROCESS | 1678 | RSS: Main: 269365248 17:37:07 INFO - PROCESS | 1678 | 17:37:09 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:37:10 INFO - PROCESS | 1678 | RSS: Main: 261013504 17:37:10 INFO - PROCESS | 1678 | 17:37:11 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:37:12 INFO - PROCESS | 1678 | RSS: Main: 268251136 17:37:12 INFO - PROCESS | 1678 | 17:37:14 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:37:15 INFO - PROCESS | 1678 | RSS: Main: 271511552 17:37:15 INFO - PROCESS | 1678 | 17:37:18 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:37:18 INFO - PROCESS | 1678 | RSS: Main: 278659072 17:37:18 INFO - PROCESS | 1678 | 17:37:21 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:37:22 INFO - PROCESS | 1678 | RSS: Main: 275533824 17:37:22 INFO - PROCESS | 1678 | 17:37:23 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 17:37:24 INFO - PROCESS | 1678 | RSS: Main: 336277504 17:37:24 INFO - PROCESS | 1678 | 17:37:24 INFO - PROCESS | 1678 | __start_tp_report 17:37:24 INFO - PROCESS | 1678 | _x_x_mozilla_page_load 17:37:24 INFO - PROCESS | 1678 | _x_x_mozilla_page_load_details 17:37:24 INFO - PROCESS | 1678 | |i|pagename|runs| 17:37:24 INFO - PROCESS | 1678 | |0;ai-astar;114;113;121;113;112;113;113;114;116;188 17:37:24 INFO - PROCESS | 1678 | |1;audio-beat-detection;156;120;139;164;121;141;136;120;197;146 17:37:24 INFO - PROCESS | 1678 | |2;audio-dft;182;184;183;184;184;184;181;183;183;183 17:37:24 INFO - PROCESS | 1678 | |3;audio-fft;69;80;74;151;56;54;56;61;131;53 17:37:24 INFO - PROCESS | 1678 | |4;audio-oscillator;90;91;134;78;79;111;109;80;112;166 17:37:24 INFO - PROCESS | 1678 | |5;imaging-gaussian-blur;87;83;86;83;86;83;90;130;130;88 17:37:24 INFO - PROCESS | 1678 | |6;imaging-darkroom;92;91;92;92;91;92;92;93;98;92 17:37:24 INFO - PROCESS | 1678 | |7;imaging-desaturate;86;86;84;86;86;83;83;90;85;84 17:37:24 INFO - PROCESS | 1678 | |8;json-parse-financial;59;62;61;61;60;62;62;80;58;55 17:37:24 INFO - PROCESS | 1678 | |9;json-stringify-tinderbox;57;60;62;73;69;73;70;66;66;66 17:37:24 INFO - PROCESS | 1678 | |10;stanford-crypto-aes;80;80;79;80;80;93;104;79;80;83 17:37:24 INFO - PROCESS | 1678 | |11;stanford-crypto-ccm;125;127;125;126;126;124;124;127;144;149 17:37:24 INFO - PROCESS | 1678 | |12;stanford-crypto-pbkdf2;162;160;214;157;191;213;171;163;161;163 17:37:24 INFO - PROCESS | 1678 | |13;stanford-crypto-sha256-iterative;65;65;66;63;66;67;69;67;66;64 17:37:24 INFO - PROCESS | 1678 | __end_tp_report 17:37:24 INFO - PROCESS | 1678 | __start_cc_report 17:37:24 INFO - PROCESS | 1678 | _x_x_mozilla_cycle_collect,1935 17:37:24 INFO - PROCESS | 1678 | __end_cc_report 17:37:24 INFO - PROCESS | 1678 | __startTimestamp1468024644192__endTimestamp 17:37:24 INFO - PROCESS | 1678 | 17:37:24 INFO - PROCESS | 1678 | ------- Summary: start ------- 17:37:24 INFO - PROCESS | 1678 | Number of tests: 14 17:37:24 INFO - PROCESS | 1678 | 17:37:24 INFO - PROCESS | 1678 | [#0] ai-astar Cycles:10 Average:121.70 Median:114.00 stddev:23.44 (20.6%) stddev-sans-first:24.69 17:37:24 INFO - PROCESS | 1678 | Values: 114.0 113.0 121.0 113.0 112.0 113.0 113.0 114.0 116.0 188.0 17:37:24 INFO - PROCESS | 1678 | 17:37:24 INFO - PROCESS | 1678 | [#1] audio-beat-detection Cycles:10 Average:144.00 Median:143.50 stddev:23.89 (16.6%) stddev-sans-first:24.94 17:37:24 INFO - PROCESS | 1678 | Values: 156.0 120.0 139.0 164.0 121.0 141.0 136.0 120.0 197.0 146.0 17:37:24 INFO - PROCESS | 1678 | 17:37:24 INFO - PROCESS | 1678 | [#2] audio-dft Cycles:10 Average:183.10 Median:183.50 stddev:0.99 (0.5%) stddev-sans-first:0.97 17:37:24 INFO - PROCESS | 1678 | Values: 182.0 184.0 183.0 184.0 184.0 184.0 181.0 183.0 183.0 183.0 17:37:24 INFO - PROCESS | 1678 | 17:37:24 INFO - PROCESS | 1678 | [#3] audio-fft Cycles:10 Average:78.50 Median:58.50 stddev:34.47 (58.9%) stddev-sans-first:36.39 17:37:24 INFO - PROCESS | 1678 | Values: 69.0 80.0 74.0 151.0 56.0 54.0 56.0 61.0 131.0 53.0 17:37:24 INFO - PROCESS | 1678 | 17:37:24 INFO - PROCESS | 1678 | [#4] audio-oscillator Cycles:10 Average:105.00 Median:78.50 stddev:28.11 (35.8%) stddev-sans-first:29.29 17:37:24 INFO - PROCESS | 1678 | Values: 90.0 91.0 134.0 78.0 79.0 111.0 109.0 80.0 112.0 166.0 17:37:24 INFO - PROCESS | 1678 | 17:37:24 INFO - PROCESS | 1678 | [#5] imaging-gaussian-blur Cycles:10 Average:94.60 Median:86.00 stddev:18.80 (21.9%) stddev-sans-first:19.74 17:37:24 INFO - PROCESS | 1678 | Values: 87.0 83.0 86.0 83.0 86.0 83.0 90.0 130.0 130.0 88.0 17:37:24 INFO - PROCESS | 1678 | 17:37:24 INFO - PROCESS | 1678 | [#6] imaging-darkroom Cycles:10 Average:92.50 Median:92.00 stddev:2.01 (2.2%) stddev-sans-first:2.13 17:37:24 INFO - PROCESS | 1678 | Values: 92.0 91.0 92.0 92.0 91.0 92.0 92.0 93.0 98.0 92.0 17:37:24 INFO - PROCESS | 1678 | 17:37:24 INFO - PROCESS | 1678 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:86.00 stddev:2.06 (2.4%) stddev-sans-first:2.17 17:37:24 INFO - PROCESS | 1678 | Values: 86.0 86.0 84.0 86.0 86.0 83.0 83.0 90.0 85.0 84.0 17:37:24 INFO - PROCESS | 1678 | 17:37:24 INFO - PROCESS | 1678 | [#8] json-parse-financial Cycles:10 Average:62.00 Median:61.50 stddev:6.70 (10.9%) stddev-sans-first:7.02 17:37:24 INFO - PROCESS | 1678 | Values: 59.0 62.0 61.0 61.0 60.0 62.0 62.0 80.0 58.0 55.0 17:37:24 INFO - PROCESS | 1678 | 17:37:24 INFO - PROCESS | 1678 | [#9] json-stringify-tinderbox Cycles:10 Average:66.20 Median:67.50 stddev:5.33 (7.9%) stddev-sans-first:4.49 17:37:24 INFO - PROCESS | 1678 | Values: 57.0 60.0 62.0 73.0 69.0 73.0 70.0 66.0 66.0 66.0 17:37:24 INFO - PROCESS | 1678 | 17:37:24 INFO - PROCESS | 1678 | [#10] stanford-crypto-aes Cycles:10 Average:83.80 Median:80.00 stddev:8.24 (10.3%) stddev-sans-first:8.63 17:37:24 INFO - PROCESS | 1678 | Values: 80.0 80.0 79.0 80.0 80.0 93.0 104.0 79.0 80.0 83.0 17:37:24 INFO - PROCESS | 1678 | 17:37:24 INFO - PROCESS | 1678 | [#11] stanford-crypto-ccm Cycles:10 Average:129.70 Median:126.50 stddev:8.99 (7.1%) stddev-sans-first:9.38 17:37:24 INFO - PROCESS | 1678 | Values: 125.0 127.0 125.0 126.0 126.0 124.0 124.0 127.0 144.0 149.0 17:37:24 INFO - PROCESS | 1678 | 17:37:24 INFO - PROCESS | 1678 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.50 Median:167.00 stddev:22.20 (13.3%) stddev-sans-first:23.01 17:37:24 INFO - PROCESS | 1678 | Values: 162.0 160.0 214.0 157.0 191.0 213.0 171.0 163.0 161.0 163.0 17:37:24 INFO - PROCESS | 1678 | 17:37:24 INFO - PROCESS | 1678 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.00 stddev:1.69 (2.6%) stddev-sans-first:1.76 17:37:24 INFO - PROCESS | 1678 | Values: 65.0 65.0 66.0 63.0 66.0 67.0 69.0 67.0 66.0 64.0 17:37:24 INFO - PROCESS | 1678 | -------- Summary: end -------- 17:37:24 INFO - PROCESS | 1678 | 17:37:24 INFO - TEST-INFO | 1678: exit 0 17:37:24 INFO - TEST-OK | kraken | took 57678ms 17:37:24 INFO - SUITE-END | took 1170s 17:37:25 INFO - Completed test suite (00:19:30) 17:37:25 INFO - javascript benchmark 17:37:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3557.430811070229, 3639.3813051781194, 3644.580873199582, 3654.104744337638, 3720.2373513429748, 5874.089516124996, 5919.230500035, 5955.731992060357, 5963.224780778498, 5965.821025369235, 5925.4370834770625, 5951.553633477485, 5982.162497250389, 5988.221531200948, 5997.220361353029, 5279.815206467774, 5292.206169074631, 5369.328833895763, 5396.676199428027, 5415.864603384907, 5372.382176049765, 5409.783608655649, 5414.593905457087, 5416.322959630046, 5417.7291135443265, 5316.574674026068, 5378.408375078739, 5398.217258497515, 5400.83797486076, 5424.159255315412, 5417.214503896952, 5425.186222066722, 5432.402435732106, 5447.400785913546, 5504.972475137599, 5164.8967020659375, 5318.468153184651, 5328.8401347959625, 5341.252224688546, 5353.277307563468, 5705.315362156526, 5745.0, 5772.884542309131, 5777.88444231113, 5894.28254593044, 5921.141434491975, 6263.1544741459775, 6319.273283572402, 6341.682915854189, 6352.205974253218, 6253.1558239637525, 6346.048092786073, 6365.522585806083, 6378.553501254868, 6390.7763228286785, 6053.152558641794, 6125.540584456184, 6133.82435864808, 6143.539234557426, 6152.107944348089, 5542.251796007568, 6481.0, 6508.284088750233, 6527.510436717265, 6527.934720652733, 6205.689715514206, 6257.2491301044165, 6370.713317900706, 6387.872242555216, 6387.936120638827, 6329.461995730322, 6346.27017892939, 6374.298827129012, 6375.458086062644, 6376.33048529907, 6260.092286618415, 6317.178766760292, 6334.556581039283, 6337.873242535124, 6351.555391122577, 6272.2159730033745, 6330.335314791881, 6334.461570766444, 6366.872662546723, 6379.106925030499, 5065.11360511909, 5085.771140298695, 5111.46329635383, 5121.334226550524, 5146.742662866916, 4525.0497395546645, 4572.245579479415, 4575.3823233863, 4579.061292435043, 4595.379623750818, 5988.0, 6094.421030002142, 6144.907826382608, 6152.753889844356, 6167.6916154193, 5842.620229685057, 5984.042553191469, 6347.365263473708, 6363.236411630542, 6380.840478988062, 6097.5731698780655, 6118.785842495491, 6120.693965301717, 6122.234720659918, 6141.692915354214, 5515.531179849758, 5612.635178713371, 5615.950178184933, 5623.943760562423, 5626.746796394172, 6486.481081513468, 6605.306442823435, 6631.336866313427, 6637.966810166112, 6638.7676431325635, 6351.618902865843, 6371.6814159290925, 6380.96809515968, 6389.424951754364, 6397.520185986163, 6434.903476447951, 6513.967430162819, 6583.572067815577, 6586.176727909012, 6622.10601568782, 6313.084602732486, 6330.208723909512, 6338.524610654127, 6341.0, 6347.5239357047485, 6273.937260627518, 6287.685615719287, 6335.841603959753, 6336.651484168231, 6367.649779262185, 6190.102435146969, 6268.27914789805, 6307.211598550181, 6358.809235722936, 6361.332060133619, 6327.96836015817, 6354.301026886947, 6356.364363563606, 6360.809175724736, 6369.426751592379, 6216.658083805434, 6223.284322302812, 6238.906416403667, 6259.998400255937, 6260.499160067275, 5124.897502049864, 6188.504919606331, 6331.366863313632, 6337.397947195009, 6341.524385671185, 6241.906371404523, 6267.592606480564, 6298.212723409574, 6298.307186209422, 6303.0], "value": 5891.993171154896, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [531.8138651471985, 561.3880869851861, 574.3643297373403, 581.2879222951882, 609.5062998970834, 31252.53121203181, 32495.837520812423, 32594.67405325946, 32607.1848203795, 32664.183395415126, 36586.719464818736, 37094.0, 41295.0, 42660.3600945986, 42673.35989960153, 10016.649417270397, 10183.694489165337, 10187.083162515371, 10188.949055254714, 10217.795644087113, 10035.347702399356, 10197.898021019806, 10198.592056317775, 10212.642557510451, 10212.897871021307, 9297.860532092023, 9505.287103467268, 9510.28672849539, 12229.0, 12459.065570082235, 12008.09939254555, 12103.576374826927, 12111.091668124925, 12112.939435302855, 12123.030157587373, 11943.163978521507, 12003.159778815487, 12021.57924472648, 12030.037596992308, 12040.81938770919, 43065.784671076755, 43758.781206093765, 43874.78062609698, 43889.0, 44018.779906100266, 3271.5583396241677, 3283.885064022748, 3441.105312618737, 3516.208853008078, 3628.673419392241, 24387.024519019218, 28977.710222897713, 30476.84761576178, 30930.845345773352, 31531.0, 2826.6749323827817, 2832.3910359272854, 2845.231787417386, 2862.427514497109, 2874.841883696396, 504345.0, 506765.4661726705, 506892.0, 507132.0, 507289.0, 37867.86396408112, 38627.80686096552, 38700.0, 54497.0, 66785.66607166933, 37645.81177094097, 37745.43381849275, 37930.0, 37969.24061518754, 38015.23969520594, 13677.452901883813, 16311.021338719715, 16328.673426531403, 16328.877245957949, 16331.26509307084, 13822.654433639038, 13891.305434728425, 13892.888568914463, 13912.930435347758, 13924.30378481072, 8217.0, 8687.522186279815, 8710.303175745925, 8756.649734010696, 8803.559822008874, 3370.1574606348413, 3428.451447768345, 3513.191965847869, 3520.489529018278, 3540.097275194809, 1025.3232866308201, 1025.5795123999274, 1049.7480604654982, 1155.630198336541, 1225.9877401226051, 23598.174063908024, 28868.42263154725, 30141.547876781864, 30150.38195416564, 30684.69315306863, 1093.8577984862077, 1132.116948779953, 1151.7120719820045, 1225.66906935127, 1251.9812202816966, 14243.202153378403, 15227.0, 15360.61598460025, 15410.768838467431, 15626.68746625061, 644808.7759561172, 649217.0, 652907.0, 653081.0, 656281.0, 56837.715811420676, 66139.0, 66789.33210667929, 67041.3295867035, 70653.64673176705, 1569.4350033987755, 1571.2615070916652, 1578.7710781936555, 1589.32453707174, 1591.2282542966652, 30307.848460757556, 31493.055208343787, 31740.0, 31932.00229317761, 32076.19809504781, 57472.0, 58299.0, 58347.0, 58460.70769646124, 58558.0, 541584.0, 782125.0, 898846.0, 922247.0, 922860.0, 24142.758572414754, 28918.277043074093, 28966.13101606955, 29393.55909661314, 29416.852915735286, 48453.0, 48653.0, 49308.50691493039, 49637.00725985532, 50855.74572127116, 38846.240291687114, 39557.40663889986, 41113.79443102885, 41406.585934140276, 41832.79083604563, 38717.419238712006, 39325.41011884881, 39921.60078399179, 39994.800025999684, 40837.97905052397, 35985.640143599274, 39545.80227098846, 40247.19505609813, 40746.1850762989, 40897.591024090565, 34138.82930585331, 35069.47395789014, 35219.47170792389, 35412.0, 35569.93290201298, 23332.65001024952, 25703.228903132935, 25791.226263212135, 25873.741262587886, 26345.868270658524], "value": 19689.67927408308, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [418.44555963348563, 423.99576004239947, 424.7578880038378, 429.3881219262552, 433.4127257565998, 20320.679155854858, 21074.0, 21434.0, 21729.78270217297, 21910.904454777276, 15767.526974190763, 17444.38944636938, 21107.15571377143, 21227.363179104614, 21387.893060534716, 5701.258836351279, 6042.486388656947, 6053.061775424816, 6053.273607167146, 6068.211132552762, 5942.227510423639, 5951.49412299955, 6022.096685497167, 6033.668148251866, 6056.364081771417, 5768.278965129359, 5913.379095195007, 5930.140129681194, 5964.2842858857, 5968.403159684019, 5718.456746609066, 5861.941380586203, 5907.113932910098, 5919.8456301021315, 5921.200637913869, 5212.139996900507, 5251.86870328241, 5256.316678831766, 5259.789608415659, 5260.289860868772, 25894.35264118412, 26157.0, 26786.73213267862, 27391.315217119532, 27438.725612744016, 2033.8983050847546, 2113.4505028692497, 2240.630296001158, 2327.1273272522803, 2367.7513861044513, 10112.848307275397, 10233.334833235816, 10255.743606409826, 11534.019608333301, 13901.721965560631, 1683.0070258547394, 1753.2285794250488, 1769.3276554909053, 1816.3370369815054, 1831.4597193827854, 97814.0, 102009.48995254976, 103432.0, 104113.0, 104663.4766826161, 11642.708932276591, 11852.11109166816, 11857.940710296565, 11859.58491452795, 11991.880081199077, 15372.692546149014, 15390.153541555099, 15427.151506667242, 15446.691066178613, 15448.768268475982, 9052.683156089506, 9144.679936202201, 9149.908500915039, 9212.355135140477, 9231.76920576991, 8914.0, 8924.68763593271, 8986.68546600866, 9142.634294628273, 9206.539673016323, 3779.7732136071922, 4715.363425937455, 4724.976375118103, 4764.190087685102, 4782.0914026334885, 1847.8152184781684, 2017.70743242232, 2025.3721346382667, 2047.0481226229874, 2085.1555120176354, 884.8584226523855, 918.5636822509255, 1008.2035192198082, 1022.4632068164274, 1048.3290693955873, 17425.651486970444, 20316.0, 20403.285884993955, 20471.795282047286, 20543.178272868918, 973.4694591447678, 1024.4519182237468, 1039.740064983754, 1045.1690905729963, 1060.4750648429076, 10726.946365268279, 11046.281991670516, 11074.889251107386, 11079.612213572647, 11084.445777711082, 612608.0, 614405.0, 614512.0, 628536.0, 630382.0, 119346.0, 124889.0, 129987.0, 130877.0, 131292.3435382836, 895.3464763065034, 1031.7884833609091, 1050.0, 1050.4537640426936, 1050.8791488978713, 17839.0, 17899.552511187325, 17914.5521361967, 18235.635287294444, 18468.907655461637, 51828.0, 51934.961300773015, 52789.472105279994, 53061.0, 54274.0, 73610.0, 74847.0, 76571.61714191393, 76656.0, 76999.61500192463, 8085.312748416451, 8326.375521835766, 8413.747587572137, 8560.400771945904, 8603.225709686158, 9489.240860730986, 12046.457909394274, 12662.113652044269, 12718.872811272138, 12750.744985100431, 10575.0, 11305.43472826339, 11610.361430121424, 11702.531898723955, 11741.94322510978, 11235.0, 11257.549698012315, 11327.830082548933, 11518.0, 11573.247738896946, 6150.446459818638, 9592.376495527767, 11468.713282168013, 11793.587224447447, 12010.519579216734, 8276.220612568326, 8380.16198380157, 8536.615852286537, 8567.614457349559, 8777.956110219407, 10619.15046796239, 11539.942300288445, 11797.941010294893, 11845.348505832262, 11882.881171188177], "value": 9685.636968322513, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1489.3223583269614, 1602.4391462987953, 1625.4067265448107, 1904.2573396375417, 2017.4048655646582, 8254.463459875104, 8992.41549299296, 9129.543522823866, 9137.725868223964, 9148.405353652024, 8815.911840881588, 9104.954475227614, 9107.544622768868, 9112.088791120885, 9115.68095116671, 2049.7950204979456, 2547.0703193334484, 2562.295368773593, 2574.7296533863955, 2574.961375579368, 2409.3976505873534, 2584.3539115221197, 2590.2099859542927, 2590.313566904772, 2603.635491031257, 2462.679851619287, 2490.402303447178, 2492.2149522900227, 2503.7746602805746, 2509.14689005738, 2548.6686730724978, 2577.1237779155085, 2587.1721049264243, 2595.429005618761, 2599.636050952868, 2543.122622695167, 2577.420080481895, 2587.068655284096, 2590.896364145432, 2591.0931174089105, 8958.328125390557, 9189.67836125739, 9237.815243695088, 9265.768355791091, 9302.976672566012, 5572.219889215513, 5730.0, 5739.598228124033, 5790.218320526717, 5799.246098007232, 3714.4985426967282, 3783.886483405502, 3838.059675379522, 3839.36650452675, 3850.0182453474185, 1066.72798436399, 1075.93544387337, 1078.282941843673, 1099.230538622959, 1113.6993011886746, 12517.749645007048, 12594.74810503794, 12596.496140154475, 12626.936865315707, 12652.746945061139, 4768.880777980578, 4815.903681926412, 4845.806167753321, 4891.217405215149, 4896.755162241873, 9700.175485083846, 9710.320277580502, 9715.659951901649, 9716.902830971741, 9729.075737804897, 5574.080276754371, 5576.721163941867, 5629.4652008059165, 5638.154276858504, 5646.265985421951, 4648.279516674921, 4749.3588365570895, 4801.639877009238, 4820.758962051884, 5247.344081989751, 3419.401604719214, 3470.427379482357, 3594.5866225383893, 4066.552679205285, 4135.9172816544105, 1113.905318047975, 1280.1934781087855, 1285.6528737241078, 1288.278564004142, 1298.214580178997, 865.7099871542978, 938.671464987249, 958.5925981457992, 984.625842179967, 991.4546999150439, 6056.848578785565, 6094.299155597075, 6141.907871381933, 6146.569740118149, 6199.659018754011, 1001.1240164855751, 1023.8464230365356, 1024.0, 1034.6792494326783, 1069.1233188785122, 4055.1889622075705, 4093.6520395766693, 4104.692148088905, 4157.605027522381, 4162.354835000579, 11136.665900023012, 11650.067994560415, 11652.76694466106, 11663.89193026661, 11664.766704665859, 11370.09039276856, 11370.147238956946, 11385.259958102697, 11399.886001139883, 11478.02436792882, 8271.793205169799, 8521.147885211552, 8590.441621294596, 8615.052344242129, 8718.694845680371, 5940.108983652487, 5988.790392336247, 6066.757329706938, 6073.817785466443, 6077.756889724362, 9594.42433453995, 9702.70891873245, 9775.413475191512, 9788.070133337322, 9987.400755954666, 9848.359856609584, 10151.340162889388, 10189.388636681822, 10378.117859981987, 10392.844107338547, 7126.572405655677, 7317.853642927005, 7697.114831794415, 7957.084935232522, 8119.147489513516, 7345.0, 8240.629171687408, 8700.434471759316, 8759.905011873516, 8765.91234087651, 7626.504277221963, 7656.885146722692, 7757.844843103219, 7861.1745766693675, 7863.724769633035, 7477.289657482531, 7513.323800857949, 7514.436417268836, 7616.162222155448, 7644.923550764421, 7181.892271616034, 7307.634618269171, 7323.743668971773, 7334.119905611361, 7349.0, 4943.703377797344, 4957.00859828043, 5002.874928126826, 5100.234964755168, 5156.819511317086, 5062.240663900445, 5088.109580823415, 5133.614978876524, 5172.586193104617, 5324.9733751331], "value": 4778.022290733065, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.6073394128869, 344.37324070192244, 346.9791812491251, 355.5520044743622, 356.718192627824, 5381.4349493303225, 6706.0611514387965, 6707.061011458393, 6723.226828914676, 6778.0, 6739.595624262548, 6751.662416879149, 6764.222114456839, 6767.458603311736, 6773.695183716733, 4718.221493453576, 4818.229083346665, 4842.515748425165, 4883.731394773304, 4925.285833554132, 4814.301926220696, 4916.704997700132, 4917.21324588066, 4917.606591472691, 4935.185694360444, 4875.70745755254, 4878.317035615017, 4880.7071575705395, 4889.3399391082285, 4906.828261010865, 4680.859574212779, 4803.927941080887, 4805.0389922015565, 4814.253790662453, 4820.132376172288, 4126.607972242632, 4181.330987042059, 4184.326269492228, 4215.873523794291, 4228.112096459717, 5199.428062913076, 6460.773872914426, 6481.384268494533, 6514.283428822873, 6529.444997175198, 5933.376995415462, 5940.881182376372, 5948.434898684662, 5950.196723442323, 5951.58338916276, 6831.931680683179, 7189.424846012306, 7204.747833825843, 7219.711211551532, 7228.421726261887, 3877.670398016172, 4619.099275641252, 4638.5593368629925, 4653.302004699322, 4657.720536767771, 7508.962455187689, 7614.010178676792, 7630.542167469914, 7639.350655194316, 7646.4265180111715, 4742.312364707098, 4743.620510359163, 4749.95250047502, 4755.5719985201495, 4756.7859446325, 4809.2064809306075, 4813.109574728686, 4813.374261346073, 4813.831515896927, 4824.565789079, 5532.86167845799, 5720.342160651495, 5737.196792449061, 5740.942590574125, 5763.366029736726, 7198.172210195895, 7220.566765994057, 7228.277172282729, 7232.891506627405, 7250.7462238822445, 1621.3514594162193, 1665.5003498950266, 1671.1226606031687, 1671.3147609480054, 1676.8490835824834, 873.9868901966476, 1035.7203555040096, 1064.505005172583, 1067.6796960911695, 1067.9519421626046, 5604.243427137284, 6626.271110177877, 6791.811432999304, 6804.21751498584, 6877.828054298682, 7310.561366318038, 7445.664945077485, 7465.626718664045, 7495.66269517873, 7503.324700776956, 5671.943280567224, 6866.450683945272, 6887.0, 6922.134733158356, 6974.511784175161, 3716.680578394685, 3955.6044395560216, 4025.8590949316635, 4028.0, 4030.1939612077704, 7441.665125069385, 7555.0, 7866.410019248579, 7921.801954951173, 7947.960260198662, 7753.883691744629, 7794.610269486503, 7795.532268063935, 7803.336716379058, 7841.098273698483, 7498.850022999619, 7838.882416763753, 7907.604619768988, 7919.762407127911, 7930.9603451982375, 7536.321731044232, 7547.0943486781935, 7557.017587713562, 7558.735444259424, 7577.393808495417, 7166.0, 7471.439642026761, 7475.588842613708, 7481.70073197066, 7485.288897554724, 7892.329152022143, 7958.681652733826, 7980.603394406072, 7992.880106798519, 7999.4400391972, 4755.310479980452, 4831.033793241436, 4832.565069143794, 4837.032593481247, 4841.104395686669, 6886.69009894566, 7535.623218838927, 7577.280158384945, 7579.54522728616, 7582.393408527194, 6388.9041664376, 7317.634118293958, 7348.0, 7354.448416368687, 7358.337749602561, 7239.239879812544, 7312.5246858954, 7329.706811727471, 7336.596487193256, 7353.8161545961775, 7104.928950710427, 7129.108861392326, 7135.678894449866, 7139.464540159405, 7147.714091436493, 5986.485676015996, 6963.6866341013765, 7159.64201789898, 7266.1280646322775, 7321.048263725682, 5486.341639003185, 5740.425957404226, 6988.37104660583, 7060.71757129709, 7178.24628414009], "value": 5247.920640175063, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [714.9811518586014, 715.6779449247838, 761.889526018727, 783.2872086401376, 826.6899912532798, 12723.36383180842, 14470.0, 15742.0, 15767.290471928762, 15814.762778558317, 16547.42084027059, 16642.33430662772, 16653.83346166538, 16658.83341166588, 16686.249118789652, 802.5826570183501, 979.8873129590118, 998.1316254858248, 1004.5128112865254, 1009.9444530550817, 803.1245941923303, 984.1732944326764, 1001.644416232238, 1004.5128112865254, 1008.8991100889889, 1043.0560342889696, 1045.325764881651, 1045.5242864496636, 1049.5959055763549, 1053.6628278950739, 830.4726498673325, 1026.856240126383, 1038.397729316998, 1038.8389799581078, 1044.5821671331453, 784.2510402565593, 787.189195129018, 788.8619491588948, 789.2147313423123, 790.8457850719112, 15578.610534736723, 15604.531864044096, 15650.843491565052, 15686.372545098182, 15692.843071569252, 12543.937280313632, 12631.0, 12654.557090501788, 12676.68308292291, 12817.743645127046, 10194.643187488477, 10279.229057820694, 10671.893281067167, 10806.135509159249, 10861.891381086167, 3542.982285088584, 3568.1614820517157, 3694.4458331250257, 3697.630236976308, 3747.8671481290394, 86887.0, 87742.0, 88079.55960220222, 88322.99999999936, 88608.11391886063, 9438.90561094394, 9528.571214295373, 9675.225981921429, 9730.172935300438, 9957.0, 22920.885395572914, 22961.770382296298, 22980.770192298198, 22991.310260692208, 22999.96500157497, 7361.227071157451, 7582.658780354897, 7767.572783496849, 7792.376609871197, 7960.880586791202, 4161.812718427678, 4231.640310573635, 4237.745735255833, 4307.203167414038, 4416.116776644684, 3780.111673756665, 3854.6530812226474, 3856.1709232515145, 3858.3826587745825, 3859.363205071188, 675.6587923098804, 689.2315068698365, 689.9068625735562, 698.0367093411061, 720.1430297945428, 586.3901542395861, 608.9939100608938, 619.3125630550086, 627.1690010735794, 630.5901164243279, 7971.362291016821, 8389.706360277361, 8431.831363372698, 8487.490750554864, 8770.12298770118, 584.4593750780512, 599.1551911804335, 618.5917294585552, 620.981370558884, 624.5940138909745, 3870.651641352292, 3894.6494815466745, 3913.8043097844993, 3979.621935916083, 4004.3392840181627, 94423.0557694428, 95588.04411955792, 95796.0420395801, 96040.59898502454, 96188.51905740426, 70094.0, 71902.28097718956, 79617.60191199007, 79653.0, 79953.0, 66449.66775166093, 70441.0, 71428.0, 71466.28533714596, 71562.64218678977, 7810.140884502814, 7883.724069657764, 7894.36845052383, 7961.243681850215, 8277.130901255281, 47915.80210494626, 48760.51239487559, 48805.26792098051, 49012.52962411133, 49126.0, 51801.0, 62148.68925655343, 64383.35616643961, 65879.34120658733, 66302.66848665726, 10491.108255798343, 10643.095336896451, 10709.678709638723, 10726.946365268124, 10830.458477076272, 9700.708978730649, 10775.353478791298, 10959.835602466128, 10992.890071099506, 11368.317900925971, 8626.482411055356, 10310.845337320095, 10715.089217416295, 10745.409002504624, 10985.61550345734, 10444.321119127233, 11395.772084558428, 11476.942615286871, 11817.586384476503, 11818.46816893259, 10922.126229901747, 11255.887441125484, 11533.135014873751, 11580.42097895085, 11617.419129043345, 9738.415695058318, 9776.462294573867, 10065.949670251895, 10132.189424845847, 10252.794944100928, 9523.619055237712, 10058.34620749649, 10083.546240419346, 10095.798084038424, 10136.645217417356], "value": 7099.090919997604, "unit": "score"}], "name": "dromaeo_css", "value": 7647.4559914601, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 121.0, 113.0, 112.0, 113.0, 113.0, 114.0, 116.0, 188.0], "value": 121.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 120.0, 139.0, 164.0, 121.0, 141.0, 136.0, 120.0, 197.0, 146.0], "value": 144.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 183.0, 184.0, 184.0, 184.0, 181.0, 183.0, 183.0, 183.0], "value": 183.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 80.0, 74.0, 151.0, 56.0, 54.0, 56.0, 61.0, 131.0, 53.0], "value": 78.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 134.0, 78.0, 79.0, 111.0, 109.0, 80.0, 112.0, 166.0], "value": 105.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 86.0, 83.0, 86.0, 83.0, 90.0, 130.0, 130.0, 88.0], "value": 94.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 92.0, 92.0, 91.0, 92.0, 92.0, 93.0, 98.0, 92.0], "value": 92.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 84.0, 86.0, 86.0, 83.0, 83.0, 90.0, 85.0, 84.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 62.0, 61.0, 61.0, 60.0, 62.0, 62.0, 80.0, 58.0, 55.0], "value": 62.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 60.0, 62.0, 73.0, 69.0, 73.0, 70.0, 66.0, 66.0, 66.0], "value": 66.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 79.0, 80.0, 80.0, 93.0, 104.0, 79.0, 80.0, 83.0], "value": 83.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 127.0, 125.0, 126.0, 126.0, 124.0, 124.0, 127.0, 144.0, 149.0], "value": 129.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 214.0, 157.0, 191.0, 213.0, 171.0, 163.0, 161.0, 163.0], "value": 175.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 66.0, 63.0, 66.0, 67.0, 69.0, 67.0, 66.0, 64.0], "value": 65.8, "unit": "score"}], "name": "kraken", "value": 1487.6999999999998, "alertThreshold": 2.0}]} 17:37:25 INFO - Return code: 0 17:37:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 17:37:25 ERROR - # TBPL SUCCESS # 17:37:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:37:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:37:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:37:25 INFO - 2016-07-08 17:37:25.503 screenresolution[1682:9984] starting screenresolution argv=screenresolution get 17:37:25 INFO - 2016-07-08 17:37:25.506 screenresolution[1682:9984] Display 0: 1600x1200x32@60 17:37:25 INFO - 2016-07-08 17:37:25.512 screenresolution[1683:9987] starting screenresolution argv=screenresolution list 17:37:25 INFO - Available Modes on Display 0 17:37:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:37:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:37:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:37:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:37:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:37:25 INFO - Intel Iris: 17:37:25 INFO - Chipset Model: Intel Iris 17:37:25 INFO - Type: GPU 17:37:25 INFO - Bus: Built-In 17:37:25 INFO - VRAM (Dynamic, Max): 1536 MB 17:37:25 INFO - Vendor: Intel (0x8086) 17:37:25 INFO - Device ID: 0x0a2e 17:37:25 INFO - Revision ID: 0x0009 17:37:25 INFO - Displays: 17:37:25 INFO - Display: 17:37:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:37:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:37:25 INFO - Display Serial Number: 1600x1200 60 17:37:25 INFO - Main Display: Yes 17:37:25 INFO - Mirror: Off 17:37:25 INFO - Online: Yes 17:37:25 INFO - Rotation: Supported 17:37:25 INFO - Return code: 0 17:37:25 INFO - Running post-action listener: _resource_record_post_action 17:37:25 INFO - Running post-run listener: _resource_record_post_run 17:37:26 INFO - Total resource usage - Wall time: 1194s; CPU: 52.0%; Read bytes: 37062144; Write bytes: 493210624; Read time: 242; Write time: 1533 17:37:26 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 188324352; Write bytes: 175394816; Read time: 16584; Write time: 383 17:37:26 INFO - run-tests - Wall time: 1173s; CPU: 52.0%; Read bytes: 30817792; Write bytes: 298478592; Read time: 188; Write time: 1093 17:37:26 INFO - Running post-run listener: _upload_blobber_files 17:37:26 INFO - Blob upload gear active. 17:37:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:37:26 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:37:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:37:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:37:27 INFO - (blobuploader) - INFO - Open directory for files ... 17:37:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:37:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:37:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:37:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:37:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:37:28 INFO - (blobuploader) - INFO - Done attempting. 17:37:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:37:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:37:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:37:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:37:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:37:29 INFO - (blobuploader) - INFO - Done attempting. 17:37:29 INFO - (blobuploader) - INFO - Iteration through files over. 17:37:29 INFO - Return code: 0 17:37:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:37:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:37:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/19a122d3e0228e9747cb980eeb0cd39ea6a7a33c7cd056463c09634856e814c7d388b8a4ff694f09789904fca6700287847648102e597facb726ea2264616b8e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ef41a899c8fecc3073fcff4871f53ee3c25a200ce2fe195643a613be340cf725403a2e4a54b9879f7213fb3592e2e6b79304efd72428a283cc0f96f38aeaaf02"} 17:37:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:37:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:37:29 INFO - Contents: 17:37:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/19a122d3e0228e9747cb980eeb0cd39ea6a7a33c7cd056463c09634856e814c7d388b8a4ff694f09789904fca6700287847648102e597facb726ea2264616b8e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ef41a899c8fecc3073fcff4871f53ee3c25a200ce2fe195643a613be340cf725403a2e4a54b9879f7213fb3592e2e6b79304efd72428a283cc0f96f38aeaaf02"} 17:37:29 INFO - Running post-run listener: copy_logs_to_upload_dir 17:37:29 INFO - Copying logs to upload dir... 17:37:29 INFO - mkdir: /builds/slave/test/build/upload/logs 17:37:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.309767 ========= master_lag: 0.51 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-07-08 17:37:30.315568) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-08 17:37:30.320123) ========= 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.9UFj6P8omw/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.GWg1M9aklk/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/19a122d3e0228e9747cb980eeb0cd39ea6a7a33c7cd056463c09634856e814c7d388b8a4ff694f09789904fca6700287847648102e597facb726ea2264616b8e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ef41a899c8fecc3073fcff4871f53ee3c25a200ce2fe195643a613be340cf725403a2e4a54b9879f7213fb3592e2e6b79304efd72428a283cc0f96f38aeaaf02"} build_url:https://queue.taskcluster.net/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011040 build_url: 'https://queue.taskcluster.net/v1/task/CTeUfv0WTjK9VCdmkVQLpg/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/19a122d3e0228e9747cb980eeb0cd39ea6a7a33c7cd056463c09634856e814c7d388b8a4ff694f09789904fca6700287847648102e597facb726ea2264616b8e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ef41a899c8fecc3073fcff4871f53ee3c25a200ce2fe195643a613be340cf725403a2e4a54b9879f7213fb3592e2e6b79304efd72428a283cc0f96f38aeaaf02"}' ========= master_lag: 0.78 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-08 17:37:31.110817) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 17:37:31.111146) ========= 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.9UFj6P8omw/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.GWg1M9aklk/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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.005650 ========= master_lag: 0.62 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 17:37:31.740591) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-08 17:37:31.741027) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-08 17:37:31.741509) ========= ========= Total master_lag: 1.98 =========