builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0149 starttime: 1463029774.49 results: success (0) buildid: 20160511211308 builduid: 0a152d4a48674049b523688be4de0588 revision: 1b2854d057e76fb0a7adf2796cf8482adf4b7a4f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 22:09:34.493899) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 22:09:34.494370) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 22:09:34.494651) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 22:09:34.545018) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 22:09:34.545292) ========= 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.qPvj4W3Qs1/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.QYX4BDVmNM/Listeners TMPDIR=/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-11 22:09:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 214M=0s 2016-05-11 22:09:34 (214 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.140132 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 22:09:34.702781) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 22:09:34.706135) ========= 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.qPvj4W3Qs1/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.QYX4BDVmNM/Listeners TMPDIR=/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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.028351 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 22:09:34.754425) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-11 22:09:34.754761) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1b2854d057e76fb0a7adf2796cf8482adf4b7a4f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1b2854d057e76fb0a7adf2796cf8482adf4b7a4f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qPvj4W3Qs1/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.QYX4BDVmNM/Listeners TMPDIR=/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-11 22:09:34,837 truncating revision to first 12 chars 2016-05-11 22:09:34,838 Setting DEBUG logging. 2016-05-11 22:09:34,838 attempt 1/10 2016-05-11 22:09:34,838 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/1b2854d057e7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-11 22:09:35,831 unpacking tar archive at: mozilla-aurora-1b2854d057e7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.246592 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-11 22:09:36.022365) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 22:09:36.022694) ========= script_repo_revision: 1b2854d057e76fb0a7adf2796cf8482adf4b7a4f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 22:09:36.023088) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 22:09:36.023349) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 22:09:36.039636) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 1 hrs, 11 mins, 39 secs) (at 2016-05-11 22:09:36.039922) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qPvj4W3Qs1/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.QYX4BDVmNM/Listeners TMPDIR=/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 22:09:36 INFO - MultiFileLogger online at 20160511 22:09:36 in /builds/slave/test 22:09:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 22:09:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:09:36 INFO - {'append_to_log': False, 22:09:36 INFO - 'base_work_dir': '/builds/slave/test', 22:09:36 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 22:09:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:09:36 INFO - 'branch': 'Mozilla-Aurora', 22:09:36 INFO - 'buildbot_json_path': 'buildprops.json', 22:09:36 INFO - 'config_files': ('talos/mac_config.py',), 22:09:36 INFO - 'default_actions': ('clobber', 22:09:36 INFO - 'read-buildbot-config', 22:09:36 INFO - 'download-and-extract', 22:09:36 INFO - 'populate-webroot', 22:09:36 INFO - 'create-virtualenv', 22:09:36 INFO - 'install', 22:09:36 INFO - 'run-tests'), 22:09:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:09:36 INFO - 'download_minidump_stackwalk': True, 22:09:36 INFO - 'download_symbols': 'ondemand', 22:09:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:09:36 INFO - 'tooltool.py': '/tools/tooltool.py', 22:09:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:09:36 INFO - '/tools/misc-python/virtualenv.py')}, 22:09:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:09:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:09:36 INFO - 'installer_path': 'installer.exe', 22:09:36 INFO - 'log_level': 'info', 22:09:36 INFO - 'log_name': 'talos', 22:09:36 INFO - 'log_to_console': True, 22:09:36 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:09:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:09:36 INFO - 'opt_config_files': (), 22:09:36 INFO - 'pip_index': False, 22:09:36 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:09:36 INFO - 'cmd': ('bash', 22:09:36 INFO - '-c', 22:09:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:09:36 INFO - 'enabled': True, 22:09:36 INFO - 'halt_on_failure': False, 22:09:36 INFO - 'name': 'check_screen_resolution'},), 22:09:36 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:09:36 INFO - 'cmd': ('bash', 22:09:36 INFO - '-c', 22:09:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:09:36 INFO - 'enabled': True, 22:09:36 INFO - 'halt_on_failure': False, 22:09:36 INFO - 'name': 'check_screen_resolution'},), 22:09:36 INFO - 'run_cmd_checks_enabled': True, 22:09:36 INFO - 'sps_profile': False, 22:09:36 INFO - 'sps_profile_interval': 0, 22:09:36 INFO - 'suite': 'dromaeojs', 22:09:36 INFO - 'system_bits': '32', 22:09:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:09:36 INFO - 'title': 't-yosemite-r7-0149', 22:09:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:09:36 INFO - 'use_talos_json': True, 22:09:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:09:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:09:36 INFO - 'work_dir': 'build'} 22:09:36 INFO - ##### 22:09:36 INFO - ##### Running clobber step. 22:09:36 INFO - ##### 22:09:36 INFO - Running pre-action listener: _resource_record_pre_action 22:09:36 INFO - Running main action method: clobber 22:09:36 INFO - rmtree: /builds/slave/test/build 22:09:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:09:38 INFO - Running post-action listener: _resource_record_post_action 22:09:38 INFO - ##### 22:09:38 INFO - ##### Running read-buildbot-config step. 22:09:38 INFO - ##### 22:09:38 INFO - Running pre-action listener: _resource_record_pre_action 22:09:38 INFO - Running main action method: read_buildbot_config 22:09:38 INFO - Using buildbot properties: 22:09:38 INFO - { 22:09:38 INFO - "project": "", 22:09:38 INFO - "product": "firefox", 22:09:38 INFO - "script_repo_revision": "production", 22:09:38 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 22:09:38 INFO - "repository": "", 22:09:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 22:09:38 INFO - "buildid": "20160511211308", 22:09:38 INFO - "pgo_build": "False", 22:09:38 INFO - "basedir": "/builds/slave/test", 22:09:38 INFO - "buildnumber": 318, 22:09:38 INFO - "slavename": "t-yosemite-r7-0149", 22:09:38 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 22:09:38 INFO - "platform": "yosemite_r7", 22:09:38 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 22:09:38 INFO - "branch": "mozilla-aurora", 22:09:38 INFO - "repo_path": "releases/mozilla-aurora", 22:09:38 INFO - "revision": "1b2854d057e76fb0a7adf2796cf8482adf4b7a4f", 22:09:38 INFO - "stage_platform": "macosx64", 22:09:38 INFO - "builduid": "0a152d4a48674049b523688be4de0588", 22:09:38 INFO - "slavebuilddir": "test" 22:09:38 INFO - } 22:09:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 22:09:38 INFO - Running post-action listener: _resource_record_post_action 22:09:38 INFO - ##### 22:09:38 INFO - ##### Running download-and-extract step. 22:09:38 INFO - ##### 22:09:38 INFO - Running pre-action listener: _resource_record_pre_action 22:09:38 INFO - Running main action method: download_and_extract 22:09:38 INFO - mkdir: /builds/slave/test/build/tests 22:09:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:38 INFO - https://queue.taskcluster.net/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 22:09:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 22:09:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 22:09:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 22:09:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 22:09:39 INFO - Downloaded 1199 bytes. 22:09:39 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 22:09:39 INFO - Using the following test package requirements: 22:09:39 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 22:09:39 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 22:09:39 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 22:09:39 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 22:09:39 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 22:09:39 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 22:09:39 INFO - u'jsshell-mac.zip'], 22:09:39 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 22:09:39 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 22:09:39 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 22:09:39 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 22:09:39 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 22:09:39 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 22:09:39 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 22:09:39 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 22:09:39 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 22:09:39 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 22:09:39 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 22:09:39 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 22:09:39 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')]} 22:09:39 INFO - https://queue.taskcluster.net/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:09:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 22:09:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 22:09:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 22:09:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 22:09:40 INFO - Downloaded 18089422 bytes. 22:09:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:09:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:09:44 INFO - Return code: 0 22:09:44 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 22:09:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:44 INFO - https://queue.taskcluster.net/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:09:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 22:09:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 22:09:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 22:09:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 22:09:45 INFO - Downloaded 18089422 bytes. 22:09:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:09:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:09:49 INFO - Return code: 0 22:09:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:49 INFO - https://queue.taskcluster.net/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 22:09:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 22:09:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 22:09:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 22:09:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 22:09:52 INFO - Downloaded 10840145 bytes. 22:09:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:09:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 22:09:53 INFO - Return code: 0 22:09: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')]} 22:09:53 INFO - https://queue.taskcluster.net/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 22:09:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 22:09:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 22:09:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 22:09:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 22:09:56 INFO - Downloaded 114083877 bytes. 22:09:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 22:09:56 INFO - mkdir: /builds/slave/test/properties 22:09:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:09:56 INFO - Writing to file /builds/slave/test/properties/build_url 22:09:56 INFO - Contents: 22:09:56 INFO - build_url:https://queue.taskcluster.net/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 22:09:57 INFO - Running post-action listener: _resource_record_post_action 22:09:57 INFO - Running post-action listener: set_extra_try_arguments 22:09:57 INFO - ##### 22:09:57 INFO - ##### Running populate-webroot step. 22:09:57 INFO - ##### 22:09:57 INFO - Running pre-action listener: _resource_record_pre_action 22:09:57 INFO - Running main action method: populate_webroot 22:09:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:09:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:09:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:09:57 INFO - '--tppagecycles', 22:09:57 INFO - '7'], 22:09:57 INFO - 'tests': ['tsvgm']}}, 22:09:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:09:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:09:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:09:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:09:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:09:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:09:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:57 INFO - 'talos_options': ['--disable-e10s'], 22:09:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:09:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:09:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:57 INFO - 'talos_options': ['--disable-e10s'], 22:09:57 INFO - 'tests': ['damp', 'tps']}, 22:09:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:57 INFO - 'tests': ['damp', 'tps']}, 22:09:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:09:57 INFO - 'tests': ['dromaeo_dom']}, 22:09:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:09:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:09:57 INFO - 'tests': ['a11yr', 22:09:57 INFO - 'ts_paint', 22:09:57 INFO - 'tpaint', 22:09:57 INFO - 'sessionrestore', 22:09:57 INFO - 'sessionrestore_no_auto_restore', 22:09:57 INFO - 'tabpaint']}, 22:09:57 INFO - 'other-e10s': {'tests': ['a11yr', 22:09:57 INFO - 'ts_paint', 22:09:57 INFO - 'tpaint', 22:09:57 INFO - 'sessionrestore', 22:09:57 INFO - 'sessionrestore_no_auto_restore', 22:09:57 INFO - 'tabpaint']}, 22:09:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:09:57 INFO - 'ts_paint', 22:09:57 INFO - 'tpaint', 22:09:57 INFO - 'sessionrestore', 22:09:57 INFO - 'sessionrestore_no_auto_restore', 22:09:57 INFO - 'tabpaint']}, 22:09:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:09:57 INFO - 'ts_paint', 22:09:57 INFO - 'tpaint', 22:09:57 INFO - 'sessionrestore', 22:09:57 INFO - 'sessionrestore_no_auto_restore', 22:09:57 INFO - 'tabpaint']}, 22:09:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:09:57 INFO - 'tests': ['a11yr', 22:09:57 INFO - 'ts_paint', 22:09:57 INFO - 'tpaint', 22:09:57 INFO - 'sessionrestore', 22:09:57 INFO - 'sessionrestore_no_auto_restore', 22:09:57 INFO - 'tabpaint']}, 22:09:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:09:57 INFO - 'tests': ['a11yr', 22:09:57 INFO - 'ts_paint', 22:09:57 INFO - 'tpaint', 22:09:57 INFO - 'sessionrestore', 22:09:57 INFO - 'sessionrestore_no_auto_restore', 22:09:57 INFO - 'tabpaint']}, 22:09:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:09:57 INFO - 'tests': ['tsvgx', 22:09:57 INFO - 'tsvgr_opacity', 22:09:57 INFO - 'tart', 22:09:57 INFO - 'tscrollx', 22:09:57 INFO - 'cart']}, 22:09:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:09:57 INFO - 'tsvgr_opacity', 22:09:57 INFO - 'tart', 22:09:57 INFO - 'tscrollx', 22:09:57 INFO - 'cart']}, 22:09:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:57 INFO - 'talos_options': ['--disable-e10s'], 22:09:57 INFO - 'tests': ['tp5o']}, 22:09:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:57 INFO - 'tests': ['tp5o']}, 22:09:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:57 INFO - 'talos_options': ['--disable-e10s', 22:09:57 INFO - '--xperf_path', 22:09:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:09:57 INFO - 'tests': ['tp5n']}, 22:09:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:57 INFO - 'talos_options': ['--xperf_path', 22:09:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:09:57 INFO - 'tests': ['tp5n']}}, 22:09:57 INFO - 'talos.zip': {'path': '', 22:09:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:09:57 INFO - Running post-action listener: _resource_record_post_action 22:09:57 INFO - ##### 22:09:57 INFO - ##### Running create-virtualenv step. 22:09:57 INFO - ##### 22:09:57 INFO - Running pre-action listener: _resource_record_pre_action 22:09:57 INFO - Running main action method: create_virtualenv 22:09:57 INFO - Creating virtualenv /builds/slave/test/build/venv 22:09:57 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 22:09:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:09:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:09:57 INFO - Using real prefix '/tools/python27' 22:09:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:09:58 INFO - Installing distribute.............................................................................................................................................................................................done. 22:10:01 INFO - Installing pip.................done. 22:10:01 INFO - Return code: 0 22:10:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:10:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:01 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 0x1088d4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088e4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf68499910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108773e00>, '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 0x1085dcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10876eb20>, '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.qPvj4W3Qs1/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.QYX4BDVmNM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 22:10:01 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 22:10:01 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 22:10:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qPvj4W3Qs1/Render', 22:10:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:10:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:10:01 INFO - 'HOME': '/Users/cltbld', 22:10:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:10:01 INFO - 'LOGNAME': 'cltbld', 22:10:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:01 INFO - 'MOZ_NO_REMOTE': '1', 22:10:01 INFO - 'NO_EM_RESTART': '1', 22:10:01 INFO - 'PAGER': '/bin/cat', 22:10:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:10:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:01 INFO - 'PWD': '/builds/slave/test', 22:10:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:10:01 INFO - 'SHELL': '/bin/bash', 22:10:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYX4BDVmNM/Listeners', 22:10:01 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 22:10:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:10:01 INFO - 'USER': 'cltbld', 22:10:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:10:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:10:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:01 INFO - 'XPC_FLAGS': '0x0', 22:10:01 INFO - 'XPC_SERVICE_NAME': '0', 22:10:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:10:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:01 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 22:10:01 INFO - Cleaning up... 22:10:01 INFO - Return code: 0 22:10:01 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 22:10:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:01 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 0x1088d4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088e4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf68499910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108773e00>, '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 0x1085dcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10876eb20>, '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.qPvj4W3Qs1/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.QYX4BDVmNM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 22:10:01 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 22:10:01 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 22:10:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qPvj4W3Qs1/Render', 22:10:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:10:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:10:01 INFO - 'HOME': '/Users/cltbld', 22:10:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:10:01 INFO - 'LOGNAME': 'cltbld', 22:10:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:01 INFO - 'MOZ_NO_REMOTE': '1', 22:10:01 INFO - 'NO_EM_RESTART': '1', 22:10:01 INFO - 'PAGER': '/bin/cat', 22:10:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:10:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:01 INFO - 'PWD': '/builds/slave/test', 22:10:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:10:01 INFO - 'SHELL': '/bin/bash', 22:10:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYX4BDVmNM/Listeners', 22:10:01 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 22:10:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:10:01 INFO - 'USER': 'cltbld', 22:10:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:10:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:10:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:01 INFO - 'XPC_FLAGS': '0x0', 22:10:01 INFO - 'XPC_SERVICE_NAME': '0', 22:10:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:10:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:01 INFO - Downloading/unpacking psutil>=0.7.1 22:10:01 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 22:10:01 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 22:10:01 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 22:10:01 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 22:10:01 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 22:10:01 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 22:10:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:10:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:10:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:10:04 INFO - Installing collected packages: psutil 22:10:04 INFO - Running setup.py install for psutil 22:10:05 INFO - building 'psutil._psutil_osx' extension 22:10:05 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 22:10:05 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 22:10:05 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 22:10:05 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 22:10:05 INFO - building 'psutil._psutil_posix' extension 22:10:05 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 22:10:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:10:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:10:05 INFO - ^ 22:10:06 INFO - 1 warning generated. 22:10:06 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 22:10:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:10:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:10:06 INFO - Successfully installed psutil 22:10:06 INFO - Cleaning up... 22:10:06 INFO - Return code: 0 22:10:06 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 22:10:06 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')]} 22:10:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:06 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')]} 22:10:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:06 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 0x1088d4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088e4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf68499910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108773e00>, '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 0x1085dcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10876eb20>, '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.qPvj4W3Qs1/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.QYX4BDVmNM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 22:10:06 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 22:10:06 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 22:10:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qPvj4W3Qs1/Render', 22:10:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:10:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:10:06 INFO - 'HOME': '/Users/cltbld', 22:10:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:10:06 INFO - 'LOGNAME': 'cltbld', 22:10:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:06 INFO - 'MOZ_NO_REMOTE': '1', 22:10:06 INFO - 'NO_EM_RESTART': '1', 22:10:06 INFO - 'PAGER': '/bin/cat', 22:10:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:10:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:06 INFO - 'PWD': '/builds/slave/test', 22:10:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:10:06 INFO - 'SHELL': '/bin/bash', 22:10:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYX4BDVmNM/Listeners', 22:10:06 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 22:10:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:10:06 INFO - 'USER': 'cltbld', 22:10:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:10:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:10:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:06 INFO - 'XPC_FLAGS': '0x0', 22:10:06 INFO - 'XPC_SERVICE_NAME': '0', 22:10:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:10:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:06 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:10:06 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 22:10:06 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 22:10:06 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 22:10:06 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 22:10:06 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 22:10:06 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 22:10:08 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:10:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:10:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:10:09 INFO - Installing collected packages: mozsystemmonitor 22:10:09 INFO - Running setup.py install for mozsystemmonitor 22:10:09 INFO - Successfully installed mozsystemmonitor 22:10:09 INFO - Cleaning up... 22:10:09 INFO - Return code: 0 22:10:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:10:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1088d4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088e4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf68499910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108773e00>, '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 0x1085dcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10876eb20>, '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.qPvj4W3Qs1/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.QYX4BDVmNM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 22:10:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:10:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:10:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qPvj4W3Qs1/Render', 22:10:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:10:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:10:09 INFO - 'HOME': '/Users/cltbld', 22:10:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:10:09 INFO - 'LOGNAME': 'cltbld', 22:10:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:09 INFO - 'MOZ_NO_REMOTE': '1', 22:10:09 INFO - 'NO_EM_RESTART': '1', 22:10:09 INFO - 'PAGER': '/bin/cat', 22:10:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:10:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:09 INFO - 'PWD': '/builds/slave/test', 22:10:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:10:09 INFO - 'SHELL': '/bin/bash', 22:10:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYX4BDVmNM/Listeners', 22:10:09 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 22:10:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:10:09 INFO - 'USER': 'cltbld', 22:10:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:10:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:10:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:09 INFO - 'XPC_FLAGS': '0x0', 22:10:09 INFO - 'XPC_SERVICE_NAME': '0', 22:10:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:10:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:09 INFO - Downloading/unpacking blobuploader==1.2.4 22:10:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:11 INFO - Downloading blobuploader-1.2.4.tar.gz 22:10:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:10:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:10:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:13 INFO - Downloading docopt-0.6.1.tar.gz 22:10:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:10:13 INFO - Installing collected packages: blobuploader, requests, docopt 22:10:13 INFO - Running setup.py install for blobuploader 22:10:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:10:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:10:13 INFO - Running setup.py install for requests 22:10:13 INFO - Running setup.py install for docopt 22:10:13 INFO - Successfully installed blobuploader requests docopt 22:10:13 INFO - Cleaning up... 22:10:14 INFO - Return code: 0 22:10:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:10:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:14 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 0x1088d4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088e4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf68499910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108773e00>, '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 0x1085dcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10876eb20>, '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.qPvj4W3Qs1/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.QYX4BDVmNM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 22:10:14 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 22:10:14 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 22:10:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qPvj4W3Qs1/Render', 22:10:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:10:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:10:14 INFO - 'HOME': '/Users/cltbld', 22:10:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:10:14 INFO - 'LOGNAME': 'cltbld', 22:10:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:14 INFO - 'MOZ_NO_REMOTE': '1', 22:10:14 INFO - 'NO_EM_RESTART': '1', 22:10:14 INFO - 'PAGER': '/bin/cat', 22:10:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:10:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:14 INFO - 'PWD': '/builds/slave/test', 22:10:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:10:14 INFO - 'SHELL': '/bin/bash', 22:10:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYX4BDVmNM/Listeners', 22:10:14 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 22:10:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:10:14 INFO - 'USER': 'cltbld', 22:10:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:10:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:10:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:14 INFO - 'XPC_FLAGS': '0x0', 22:10:14 INFO - 'XPC_SERVICE_NAME': '0', 22:10:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:10:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:10:14 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-59H49V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:10:14 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-UhTe52-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:10:14 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-2Z2cFN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:10:14 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-k9Hw1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:10:14 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-FmFvHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:10:14 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-LqoTs_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:10:14 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-sAtaTd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:10:15 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-aeuVyJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:10:15 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-R2f8iw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:10:15 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-Un41Me-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:10:15 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-6L5m0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:10:15 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-xc_xyD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:10:15 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-4kS3xL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:10:15 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-_1ZBYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:10:15 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-pkHUwf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:10:15 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-I2CEhx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:10:16 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-m09UYC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:10:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:10:16 INFO - Running setup.py install for manifestparser 22:10:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:10:16 INFO - Running setup.py install for mozcrash 22:10:16 INFO - Running setup.py install for mozdebug 22:10:16 INFO - Running setup.py install for mozdevice 22:10:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:10:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:10:16 INFO - Running setup.py install for mozfile 22:10:16 INFO - Running setup.py install for mozhttpd 22:10:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:10:16 INFO - Running setup.py install for mozinfo 22:10:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:10:17 INFO - Running setup.py install for mozInstall 22:10:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:10:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:10:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:10:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:10:17 INFO - Running setup.py install for mozleak 22:10:17 INFO - Running setup.py install for mozlog 22:10:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:10:17 INFO - Running setup.py install for moznetwork 22:10:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:10:17 INFO - Running setup.py install for mozprocess 22:10:17 INFO - Running setup.py install for mozprofile 22:10:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:10:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:10:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:10:18 INFO - Running setup.py install for mozrunner 22:10:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:10:18 INFO - Running setup.py install for mozscreenshot 22:10:18 INFO - Running setup.py install for moztest 22:10:18 INFO - Running setup.py install for mozversion 22:10:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:10:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:10:18 INFO - Cleaning up... 22:10:18 INFO - Return code: 0 22:10:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:10:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:18 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 0x1088d4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088e4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf68499910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108773e00>, '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 0x1085dcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10876eb20>, '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.qPvj4W3Qs1/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.QYX4BDVmNM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 22:10:18 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 22:10:18 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 22:10:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qPvj4W3Qs1/Render', 22:10:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:10:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:10:18 INFO - 'HOME': '/Users/cltbld', 22:10:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:10:18 INFO - 'LOGNAME': 'cltbld', 22:10:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:18 INFO - 'MOZ_NO_REMOTE': '1', 22:10:18 INFO - 'NO_EM_RESTART': '1', 22:10:18 INFO - 'PAGER': '/bin/cat', 22:10:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:10:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:18 INFO - 'PWD': '/builds/slave/test', 22:10:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:10:18 INFO - 'SHELL': '/bin/bash', 22:10:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYX4BDVmNM/Listeners', 22:10:18 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 22:10:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:10:18 INFO - 'USER': 'cltbld', 22:10:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:10:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:10:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:18 INFO - 'XPC_FLAGS': '0x0', 22:10:18 INFO - 'XPC_SERVICE_NAME': '0', 22:10:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:10:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:10:18 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-SXfGZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:10:19 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)) 22:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:10:19 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-Y9fyTy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:10:19 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)) 22:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:10:19 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-DL8ZFS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:10:19 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)) 22:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:10:19 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-JQm3Vv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:10:19 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)) 22:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:10:19 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-G2eP1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:10:19 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)) 22:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:10:19 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-r_u2pc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:10:19 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)) 22:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:10:19 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-kfTiKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:10:19 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)) 22:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:10:19 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-Y0PDHs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:10:19 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)) 22:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:10:19 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-3ARxp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:10:19 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)) 22:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:10:19 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-YTyDcU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:10:20 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)) 22:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:10:20 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-p6Zlxb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:10:20 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)) 22:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:10:20 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-fak0fl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:10:20 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)) 22:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:10:20 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-ZBjTQ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:10:20 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)) 22:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:10:20 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-Ki85KB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:10:20 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)) 22:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:10:20 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-4SfrkU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:10:20 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)) 22:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:10:20 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-cpMrbC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:10:20 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)) 22:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:10:20 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-vM1AL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:10:20 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)) 22:10:20 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)) 22:10:20 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)) 22:10:20 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)) 22:10:20 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)) 22:10:20 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)) 22:10:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:10:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:23 INFO - Downloading blessings-1.6.tar.gz 22:10:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:10:23 INFO - Installing collected packages: blessings 22:10:23 INFO - Running setup.py install for blessings 22:10:23 INFO - Successfully installed blessings 22:10:23 INFO - Cleaning up... 22:10:23 INFO - Return code: 0 22:10:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:10:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:10:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:10:23 INFO - Reading from file tmpfile_stdout 22:10:23 INFO - Current package versions: 22:10:23 INFO - blessings == 1.6 22:10:23 INFO - blobuploader == 1.2.4 22:10:23 INFO - docopt == 0.6.1 22:10:23 INFO - manifestparser == 1.1 22:10:23 INFO - mozInstall == 1.12 22:10:23 INFO - mozcrash == 0.17 22:10:23 INFO - mozdebug == 0.1 22:10:23 INFO - mozdevice == 0.48 22:10:23 INFO - mozfile == 1.2 22:10:23 INFO - mozhttpd == 0.7 22:10:23 INFO - mozinfo == 0.9 22:10:23 INFO - mozleak == 0.1 22:10:23 INFO - mozlog == 3.1 22:10:23 INFO - moznetwork == 0.27 22:10:23 INFO - mozprocess == 0.22 22:10:23 INFO - mozprofile == 0.28 22:10:23 INFO - mozrunner == 6.11 22:10:23 INFO - mozscreenshot == 0.1 22:10:23 INFO - mozsystemmonitor == 0.0 22:10:23 INFO - moztest == 0.7 22:10:23 INFO - mozversion == 1.4 22:10:23 INFO - psutil == 3.1.1 22:10:23 INFO - requests == 1.2.3 22:10:23 INFO - wsgiref == 0.1.2 22:10:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:10: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')]} 22:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10: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')]} 22:10:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:23 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 0x1088d4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088e4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf68499910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108773e00>, '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 0x1085dcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10876eb20>, '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.qPvj4W3Qs1/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.QYX4BDVmNM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 22:10:23 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 22:10:23 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 22:10:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qPvj4W3Qs1/Render', 22:10:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:10:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:10:23 INFO - 'HOME': '/Users/cltbld', 22:10:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:10:23 INFO - 'LOGNAME': 'cltbld', 22:10:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:23 INFO - 'MOZ_NO_REMOTE': '1', 22:10:23 INFO - 'NO_EM_RESTART': '1', 22:10:23 INFO - 'PAGER': '/bin/cat', 22:10:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:10:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:23 INFO - 'PWD': '/builds/slave/test', 22:10:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:10:23 INFO - 'SHELL': '/bin/bash', 22:10:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYX4BDVmNM/Listeners', 22:10:23 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 22:10:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:10:23 INFO - 'USER': 'cltbld', 22:10:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:10:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:10:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:23 INFO - 'XPC_FLAGS': '0x0', 22:10:23 INFO - 'XPC_SERVICE_NAME': '0', 22:10:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:10:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:24 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)) 22:10:24 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)) 22:10:24 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)) 22:10:24 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)) 22:10:24 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)) 22:10:24 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)) 22:10:24 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)) 22:10:24 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)) 22:10:24 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)) 22:10:24 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)) 22:10:24 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)) 22:10:24 INFO - Cleaning up... 22:10:24 INFO - Return code: 0 22:10:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:10:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:24 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 0x1088d4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088e4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf68499910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108773e00>, '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 0x1085dcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10876eb20>, '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.qPvj4W3Qs1/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.QYX4BDVmNM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 22:10:24 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 22:10:24 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 22:10:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qPvj4W3Qs1/Render', 22:10:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:10:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:10:24 INFO - 'HOME': '/Users/cltbld', 22:10:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:10:24 INFO - 'LOGNAME': 'cltbld', 22:10:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:24 INFO - 'MOZ_NO_REMOTE': '1', 22:10:24 INFO - 'NO_EM_RESTART': '1', 22:10:24 INFO - 'PAGER': '/bin/cat', 22:10:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:10:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:24 INFO - 'PWD': '/builds/slave/test', 22:10:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:10:24 INFO - 'SHELL': '/bin/bash', 22:10:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYX4BDVmNM/Listeners', 22:10:24 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 22:10:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:10:24 INFO - 'USER': 'cltbld', 22:10:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:10:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:10:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:24 INFO - 'XPC_FLAGS': '0x0', 22:10:24 INFO - 'XPC_SERVICE_NAME': '0', 22:10:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:10:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:24 INFO - Downloading/unpacking jsonschema 22:10:24 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 22:10:24 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 22:10:24 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 22:10:24 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 22:10:24 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 22:10:24 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 22:10:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:10:26 INFO - Downloading/unpacking functools32 (from jsonschema) 22:10: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 22:10: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 22:10: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 22:10: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 22:10: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 22:10: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 22:10:27 INFO - Downloading functools32-3.2.3-2.tar.gz 22:10:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:10:27 INFO - warning: no files found matching '*.txt' 22:10:27 INFO - no previously-included directories found matching 'build' 22:10:27 INFO - no previously-included directories found matching 'dist' 22:10:27 INFO - no previously-included directories found matching '.git*' 22:10:27 INFO - Installing collected packages: jsonschema, functools32 22:10:27 INFO - Running setup.py install for functools32 22:10:27 INFO - warning: no files found matching '*.txt' 22:10:27 INFO - no previously-included directories found matching 'build' 22:10:27 INFO - no previously-included directories found matching 'dist' 22:10:27 INFO - no previously-included directories found matching '.git*' 22:10:27 INFO - Successfully installed jsonschema functools32 22:10:27 INFO - Cleaning up... 22:10:27 INFO - Return code: 0 22:10:27 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:10:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:27 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 0x1088d4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088e4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf68499910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108773e00>, '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 0x1085dcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10876eb20>, '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.qPvj4W3Qs1/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.QYX4BDVmNM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 22:10:27 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 22:10:27 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 22:10:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qPvj4W3Qs1/Render', 22:10:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:10:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:10:27 INFO - 'HOME': '/Users/cltbld', 22:10:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:10:27 INFO - 'LOGNAME': 'cltbld', 22:10:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:27 INFO - 'MOZ_NO_REMOTE': '1', 22:10:27 INFO - 'NO_EM_RESTART': '1', 22:10:27 INFO - 'PAGER': '/bin/cat', 22:10:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:10:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:27 INFO - 'PWD': '/builds/slave/test', 22:10:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:10:27 INFO - 'SHELL': '/bin/bash', 22:10:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYX4BDVmNM/Listeners', 22:10:27 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 22:10:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:10:27 INFO - 'USER': 'cltbld', 22:10:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:10:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:10:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:27 INFO - 'XPC_FLAGS': '0x0', 22:10:27 INFO - 'XPC_SERVICE_NAME': '0', 22:10:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:10:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:27 INFO - Downloading/unpacking flake8 22:10:27 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 22:10:27 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 22:10:27 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 22:10:27 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 22:10:27 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 22:10:27 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 22:10:30 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:10:30 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:10:30 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 22:10:30 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 22:10:30 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 22:10:30 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 22:10:30 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 22:10:30 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 22:10:30 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:10:30 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:10:30 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 22:10:30 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 22:10:30 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 22:10:30 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 22:10:30 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 22:10:30 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 22:10:30 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:10:30 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 22:10:30 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 22:10:30 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 22:10:30 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 22:10:30 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 22:10:30 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 22:10:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:10:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:10:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:10:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:10:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:10:31 INFO - no previously-included directories found matching 'docs/_build' 22:10:31 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:10:31 INFO - Running setup.py install for pep8 22:10:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:10:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:10:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:10:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:10:31 INFO - no previously-included directories found matching 'docs/_build' 22:10:31 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:10:31 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:10:31 INFO - Cleaning up... 22:10:31 INFO - Return code: 0 22:10:31 INFO - Running post-action listener: _resource_record_post_action 22:10:31 INFO - Running post-action listener: _start_resource_monitoring 22:10:31 INFO - Starting resource monitoring. 22:10:31 INFO - ##### 22:10:31 INFO - ##### Running install step. 22:10:31 INFO - ##### 22:10:31 INFO - Running pre-action listener: _resource_record_pre_action 22:10:31 INFO - Running main action method: install 22:10:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:10:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:10:32 INFO - Reading from file tmpfile_stdout 22:10:32 INFO - Detecting whether we're running mozinstall >=1.0... 22:10:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:10:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:10:32 INFO - Reading from file tmpfile_stdout 22:10:32 INFO - Output received: 22:10:32 INFO - Usage: mozinstall [options] installer 22:10:32 INFO - Options: 22:10:32 INFO - -h, --help show this help message and exit 22:10:32 INFO - -d DEST, --destination=DEST 22:10:32 INFO - Directory to install application into. [default: 22:10:32 INFO - "/builds/slave/test"] 22:10:32 INFO - --app=APP Application being installed. [default: firefox] 22:10:32 INFO - mkdir: /builds/slave/test/build/application 22:10:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:10:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 22:11:03 INFO - Reading from file tmpfile_stdout 22:11:03 INFO - Output received: 22:11:03 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 22:11:03 INFO - Running post-action listener: _resource_record_post_action 22:11:03 INFO - ##### 22:11:03 INFO - ##### Running run-tests step. 22:11:03 INFO - ##### 22:11:03 INFO - Running pre-action listener: _resource_record_pre_action 22:11:03 INFO - Running main action method: run_tests 22:11:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:11:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:11:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:11:03 INFO - 2016-05-11 22:11:03.888 screenresolution[1638:7589] starting screenresolution argv=screenresolution get 22:11:03 INFO - 2016-05-11 22:11:03.891 screenresolution[1638:7589] Display 0: 1600x1200x32@60 22:11:03 INFO - 2016-05-11 22:11:03.897 screenresolution[1639:7592] starting screenresolution argv=screenresolution list 22:11:03 INFO - Available Modes on Display 0 22:11:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:11:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:11:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:11:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:11:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:11:04 INFO - Intel Iris: 22:11:04 INFO - Chipset Model: Intel Iris 22:11:04 INFO - Type: GPU 22:11:04 INFO - Bus: Built-In 22:11:04 INFO - VRAM (Dynamic, Max): 1536 MB 22:11:04 INFO - Vendor: Intel (0x8086) 22:11:04 INFO - Device ID: 0x0a2e 22:11:04 INFO - Revision ID: 0x0009 22:11:04 INFO - Displays: 22:11:04 INFO - Display: 22:11:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:11:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:11:04 INFO - Display Serial Number: 1600x1200 60 22:11:04 INFO - Main Display: Yes 22:11:04 INFO - Mirror: Off 22:11:04 INFO - Online: Yes 22:11:04 INFO - Rotation: Supported 22:11:04 INFO - Return code: 0 22:11:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:11:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:11:04 INFO - Python 2.7.3 22:11:04 INFO - Return code: 0 22:11:04 INFO - grabbing minidump binary from tooltool 22:11:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:11:04 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 0x108773e00>, '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 0x1085dcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10876eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:11:04 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 22:11:04 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 22:11:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:11:04 INFO - Return code: 0 22:11:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:11:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:11:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:11:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:11:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:11:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:11:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:11:05 INFO - Return code: 0 22:11:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0149', '--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 22:11:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0149 --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 22:11:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qPvj4W3Qs1/Render', 22:11:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:11:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:11:05 INFO - 'HOME': '/Users/cltbld', 22:11:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:11:05 INFO - 'LOGNAME': 'cltbld', 22:11:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:11:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:11:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:05 INFO - 'MOZ_NO_REMOTE': '1', 22:11:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:11:05 INFO - 'NO_EM_RESTART': '1', 22:11:05 INFO - 'PAGER': '/bin/cat', 22:11:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:11:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:05 INFO - 'PWD': '/builds/slave/test', 22:11:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:11:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:11:05 INFO - 'SHELL': '/bin/bash', 22:11:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYX4BDVmNM/Listeners', 22:11:05 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 22:11:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:11:05 INFO - 'USER': 'cltbld', 22:11:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:11:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:11:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:05 INFO - 'XPC_FLAGS': '0x0', 22:11:05 INFO - 'XPC_SERVICE_NAME': '0', 22:11:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:11:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0149', '--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 22:11:05 INFO - mozversion application_buildid: 20160511211308 22:11:05 INFO - mozversion application_changeset: 1b2854d057e76fb0a7adf2796cf8482adf4b7a4f 22:11:05 INFO - mozversion application_display_name: FirefoxDeveloperEdition 22:11:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:11:05 INFO - mozversion application_name: Firefox 22:11:05 INFO - mozversion application_remotingname: firefox-dev 22:11:05 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:11:05 INFO - mozversion application_vendor: Mozilla 22:11:05 INFO - mozversion application_version: 48.0a2 22:11:05 INFO - mozversion platform_buildid: 20160511211308 22:11:05 INFO - mozversion platform_changeset: 1b2854d057e76fb0a7adf2796cf8482adf4b7a4f 22:11:05 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:11:05 INFO - mozversion platform_version: 48.0a2 22:11:05 INFO - using testdate: 1463029865 22:11:05 INFO - actual date: 1463029865 22:11:05 INFO - starting webserver on 'localhost:49275' 22:11:05 INFO - SUITE-START | Running 2 tests 22:11:05 INFO - TEST-START | dromaeo_css 22:11:05 INFO - operating with platform_type : mac_ 22:11:05 INFO - Initialising browser for dromaeo_css test... 22:11:05 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/tmpku0wTC/profile http://localhost:49275/getInfo.html) 22:11:07 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 22:11:07 INFO - PROCESS | 1657 | colorDepth:24 22:11:07 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 22:11:07 INFO - PROCESS | 1657 | __metrics 22:11:07 INFO - PROCESS | 1657 | 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" 22:11:07 ERROR - PROCESS | 1657 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:11:08 INFO - TEST-INFO | 1657: exit 0 22:11:08 INFO - Browser initialized. 22:11:08 INFO - Running cycle 1/1 for dromaeo_css test... 22:11:08 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/tmpku0wTC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:11:20 INFO - PROCESS | 1659 | RSS: Main: 211226624 22:11:20 INFO - PROCESS | 1659 | 22:12:11 INFO - PROCESS | 1659 | 1463029931386 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] 22:14:23 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 22:14:24 INFO - PROCESS | 1659 | RSS: Main: 367546368 22:14:24 INFO - PROCESS | 1659 | 22:17:27 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 22:17:28 INFO - PROCESS | 1659 | RSS: Main: 360497152 22:17:28 INFO - PROCESS | 1659 | 22:20:30 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 22:20:30 INFO - PROCESS | 1659 | RSS: Main: 362520576 22:20:30 INFO - PROCESS | 1659 | 22:23:32 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 22:23:33 INFO - PROCESS | 1659 | RSS: Main: 363827200 22:23:33 INFO - PROCESS | 1659 | 22:26:36 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 22:26:37 INFO - PROCESS | 1659 | RSS: Main: 327196672 22:26:37 INFO - PROCESS | 1659 | 22:29:40 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 22:29:40 INFO - PROCESS | 1659 | RSS: Main: 372387840 22:29:40 INFO - PROCESS | 1659 | 22:29:40 INFO - PROCESS | 1659 | __start_tp_report 22:29:40 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 22:29:40 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 22:29:40 INFO - PROCESS | 1659 | |i|pagename|runs| 22:29:40 INFO - PROCESS | 1659 | |0;dojo.html;3322.7705748872913;3424.075499615104;3457.3949558827208;3459.2389674271653;3499;5653.265075540179;5661.830145095649;5689.4879460848515;5702.77188912443;5714.742836572354;5632.493075623193;5692.288463942007;5697.544196464283;5716.685582292983;5727.770889164429;5087.872803179913;5098.515641014107;5120.103981803189;5245.2132180173085;5246.239295302179;5179.171332586787;5180.378354597454;5188.455212202721;5189.858231189132;5195.896082078375;5115.232715092748;5168.48315168482;5175.404828444721;5184.7407629618365;5193.63644544882;5176.275321454999;5209.088409528355;5221.582273418156;5225.320708307933;5282.735863206863;5035.874103147414;5058.291839142523;5085.847424577269;5145.202493613496;5158.53573178412;5512.035393806068;5532.336119665666;5552.639078459928;5558.888822223574;5570.08093664549;5615;6107.4808641265545;6163.291221509539;6176.505879529627;6199.163112979735;5205.895882082337;6098.517589571661;6191.81424557264;6207.099970504297;6264.4362007419095;5809.419058094157;5821.981153298156;5824.446677565625;5843.9707801461145;5849.034909240013;6309.2428908530355;6335.239771227482;6337.841553961096;6342.746290148342;6343.143675603735;5963.940360596339;6052.03167493199;6080.635161890301;6095.1466794647895;6096.817095487142;6162.815115546541;6238.812835614848;6240.5319601030105;6241.438270555672;6261.186045814015;6126.479249263773;6144.969275153596;6184.536159788034;6200.193974783249;6226.034964580497;6034.547408944347;6114.449699527064;6191.040388739752;6200.596961197508;6223.3465486123305;4867.902641947141;4875.171220892456;4889.315495830587;4902.460729319772;4913.11563918491;4424.513303536608;4471.485779135376;4472.239719247736;4473.642108631302;4488.416505854283;5612.691301978431;5621.437856214346;5623.606347555714;5631.63394379364;5686.232358631614;6209.285932117774;6257.78097766576;6266.686665666698;6285.779997700059;6314.747410103544;5642.887142257132;5649.378568357477;5664.178694089334;5697.772089116472;5699.230603868507;5283.9471605284225;5352.437994010651;5371.57027437804;5374.811881584125;5397.487238712316;6282.685865706788;6293.276273228637;6304;6310.242770867525;6317.052442133717;4479.865604031885;6274.058891166362;6280.340564240689;6300.810975670737;6318.589291696025;6198.969005154945;6239;6273.749050037947;6285.088662144055;6328.0824280478155;6159.168512250968;6168.290646575521;6169.722362493789;6176.382361763787;6187.659678717714;6245.3754624539;6265.4361107500545;6266.498680105672;6268.655223962726;6301.747930082929;5832.212651292021;5855.59010869235;5904.173415721716;5911.379305172895;5919.940800591939;5012.924806127984;5638.661680299195;5907.556933230111;5946.316173639913;6005.279366476051;5762.798302059407;5794.536437084938;5807.883842323215;5808.82573522795;5814.3604203538425;5859.589828711949;5923.644581325134;5943.970280148572;5990.910136348046;5993.550483713651;5840.1531777892815;5841.707914604168;5847.415258474119;5849.3858144894175;5859.472647461749 22:29:40 INFO - PROCESS | 1659 | |1;ext.html;510.79823469729456;530.0406264660963;540.1816248383699;545.5008667069629;585.3151812379516;30723.92466263681;31302.68697313026;31357.843210783914;31433.528497072533;38274.234515309676;44315.77842110785;44843;44879.326810097875;44911;44981.77509112458;9609.615615375376;9835.65575204868;9848.556814943344;9849.40903545786;9870.062344077302;9564;9706.708798736014;9712.41725496469;9724.124828765409;9730.951345243264;9668.274879384038;9679.854802177973;9716.805663886718;9725.708228753148;9737.464439455827;8451.915480845175;9237.907620923805;9674.613015479372;9718.75703107428;9726.221902247838;9504.239660827117;9606.375585586915;9650.276229282832;9656.90343096567;9659.32384733069;40292;40664.1867162658;40678.593214067776;40743.98140046493;40834.1833163338;3195.2970346523725;3223.2908760072746;3226.1450715560395;3370.8651653934057;3420.9144771380916;28758.424831503253;28769.136925892257;28803.56794648082;29038.85480572605;29303.2674183145;2746.0114358830806;2783.3320003199287;2784.081253186464;2786.70739572346;2801.131649188738;435528;435619;435620.8218958885;436859.9999999968;438561;63535;63952;64036.67981660062;64095.359046409874;64221.67889160618;66117.33882661111;66577.33422665809;69427.65286173536;70920.64539677268;71096;17216.741748873777;17223.31106755716;17423.738643920096;17439.738403923697;17557.297708091788;13338.93330533341;13421.463141474427;13431.529896453772;13435.12671676338;13480.797788033187;19494.415167544998;19611.60776784485;19659.508512287306;19663.60672786536;19696.803031969783;8200.179982001871;8200.548969806718;8228.958855205685;8232.83534329322;8246.917530824734;915.7527467583715;1015.2334987084704;1067.9733006674896;1074.6722249713755;1075.0539525217757;29759.85120074429;29782.40435191284;30294.394112118076;30367;30417.935372261865;1073.978520429587;1094.8303013032992;1110.7500812317326;1199.0827017331749;1258.6349958511928;15314.391988841235;15614.29735661898;15880.761788573182;16046.438374657064;16362.918185408997;516749;517266;522137;522189;525137;57703;58183;58244.54388640318;58343;58731.82536349334;1360.2790521023874;1398.880895283769;1403.8525954774807;1431.1842249917656;1480.5558332500423;28392.86428542835;28472.572911406758;29060.709392906647;29629.703702962695;29792.275965504312;52066.47933520616;52071.218931716816;52746.736266318425;52765;52776;521221;522607.38696306275;524496;526509;527578;23573.52852942966;26514.337141571614;26987.865060674572;27099.458010840066;27603.171904842886;40774.38838417367;41346.58653413548;42427.363589545566;42729;42942.78528607337;34780.47829282512;34886.476702849985;35324.47013294854;35417.45825417437;35585.28829423449;35934.82032589925;36275.63724362723;36668.81665591655;36708.632913670524;36903;35300.117497062776;35859;35882;35992.4601130978;36439.27121457609;31390.37219255648;31926.042218733473;32060.671809305444;32502;33918.321633566695;19776.208951641773;24331.878340608182;24455.877720611283;24460.388490287885;25420.237392878244 22:29:40 INFO - PROCESS | 1659 | |2;jquery.html;348.7332190873982;362.66815863484914;400.5493819453116;404.28239874223254;410.34345047923347;16188;16549.007059576434;20100.89949550252;21129.837858917774;21793.019314130863;16139.273732682028;20733.792662073374;20828.687569686466;21063.841488718113;21110.472238194052;5797.4202579742005;5930.97034514829;5956.553258505608;5964.254468191476;6009.969950150243;5763.625364351325;5923.407659234085;5941.70291485424;5943.078822782476;5945.672987985659;5181.766820493087;5887.411258874121;5898.056310990242;5898.734556944926;5901.557383196277;5620.409856965021;5871.412858714137;5871.677057761843;5873.647581145145;5890.499307558863;5160.045391602565;5193;5194.584433245332;5195.324607801;5201.1678131498775;25539.361515962064;25902.48195036089;26286;26346;26359.94560217589;2356.6229403295392;2471.765182307678;2717.1440996086367;2744.423671029086;3028.5154375300067;14867;14889.627759306104;15219.239038048165;15450.1502417367;15652.452164174314;1732.8960262384298;1774.5120091974682;1778.457570441015;1808.6111486030436;1865.5429419792101;106252;112510;112949;114301.42849285784;114962.42518787437;9897.059779320953;11074.557017719362;11402.600908968147;11439.684436289934;11615.419229038684;14443.205623690588;14452.566423007116;14458.06022608527;14490.058146220463;14555;8863.980642226097;9088.500132492643;9096.681616143536;9148.085191480799;9174.311926605398;7015.789526314219;8201.630926608317;8603.741887743252;8719.997200321917;9525.952370238105;6195.256569211723;6891.483138764613;6896.827579310558;6897.793066208022;6925.861482770317;1891.3474851176322;1913.2538310059088;1921.9039048047541;1937.205745644279;1957.9118939647751;915.1763412928285;948.9762755931018;1028.7273872423664;1042.207921979286;1042.2495803021811;16967.406140785013;20667.4833129173;21751.34745957592;21861.562768744538;21894.562108758055;1003.7440452684665;1029.8043371759343;1031.4842578710645;1068.871735391758;1091.0071834630448;11452.656420307405;11809.291442513477;11818.822717659243;11855.932966032926;11868.525258989717;609657.9517102386;617683;630316;631265;631407;108669;112513.43743281231;113285;115005.4249728746;116732.41633791947;1056.5140035583563;1056.6777132974516;1073.3291692692067;1073.393532654048;1075.6769173916127;18601.720974185668;18794.872307661582;18976.905115474336;19382.12780424856;19761.505962351057;49632.75183624059;50743.985120296646;51090;51137.97724045573;52992.470075298756;67195;71041.6447917757;71166.64416677883;71194.93207601993;71399;7981;8253.587320634064;8349.499030058216;8435;8494.660213591387;9329.827205093598;10290.639827605997;12700.682482938;12743.426545805276;13107.737845243233;10998.615048473264;11172.217944744116;11208.27146235492;11498.022668073307;11532;10784.352938823695;10796.460176990962;11057.509141197446;11152.49813758399;11317.830232546354;11267.267627604508;11431.085513159094;11433;11530.538778449103;11542.94228528852;7106.715731370894;8242.59875821124;8329.708460203863;8624.698135565224;8714.38999270045;10604.575816967543;10741.624043158452;11026.669199924016;11397.715057123307;11422.94288528552 22:29:40 INFO - PROCESS | 1659 | |3;mootools.html;1412.9222892740909;1585.6907902958922;1602.7115119278528;1839.4849442156192;1948.1622902152078;7810.843783124335;8488.702895398663;8665.220130188274;8695.56522173892;8702.347323950697;8430.95784521078;8640.092790257011;8641.567921603926;8642.52466114365;8657.437266577668;2439.6828412306377;2473.4311108445067;2475.9752402475924;2478.5910324796387;2478.6653801736716;2402.60357041088;2482.131254061082;2482.590372588521;2483.1805504183576;2485.7389974052735;2180.3785921012513;2420.765409641078;2432.683751112353;2461.0770960889668;2477.764612361823;2420.3465064432503;2449.8407603505802;2464.7165575958634;2464.8890799914047;2466.3957330454064;2424.7211570669424;2456.4472993576483;2457.348802567321;2461.237016524883;2462.249014050714;8443.83112337756;8639.52482613456;8700.129987001312;8711.259542938858;8723.956380218058;5341.4658534146665;5437.075697131497;5443.319585051869;5454.61817672763;5474;3428.674275943781;3446.9655303446893;3526.717862571014;3548.8935331940083;3573.5711714594154;916.8160755702761;1044.6030508406832;1044.9895501044969;1072.3030030480172;1086.3101930274333;11566.768664626661;11620.883791162149;11622.767544649145;11628.76742465146;11630.302181869114;4209.831606735757;4567.817287308537;4943.72809495474;4961.900761984813;4968.27959945813;9046.140616641409;9052.366334356664;9133.995260521337;9136.908630913606;9136.908630913738;5298.814541491107;5312.442193569696;5338.46615338463;5343.251944727741;5345.33183352081;3711.925761484755;4443.755593442392;4566.543345665407;4640.86077417678;4992.600591952635;3585.229175727231;3872.8063596820048;3889.105505733697;3891.338472459632;3924.9607503925167;1214.6760031565636;1240.9317487538272;1244.7386048929645;1248.151257145132;1261.1550261324935;913.7852604637905;921.2491819167357;924.56356623056;928.5218112671979;960.4141473701037;5609.915851262234;5738.512226460798;5833.766649333979;5841.707914604252;5945.732442040118;891.2157301574573;902.7066203483894;939.248601119102;977.3354119198871;981.9312648114707;3526.806025668613;3746.6815320698047;3791.3933770596573;3906.8632597858937;3908.8241029153755;10299.279050466395;10324.48377581133;10568.365898046142;10663.946680266548;10810.135189184846;10517.211209159344;10575.312604680672;10591.57633694659;10629.309094908805;10752.677419677422;8122.959385203154;8260.463069900557;8309.961254843145;8339.583020848933;8448.83102337962;5462.20797984298;5511.283533140666;5565.693886836101;5571.9603287616155;5655.971720141373;9448.858267126136;9519.857202142111;9634.855477167988;9711.5144242789;9713.611455541699;9059.36584439083;9382.202512786216;9509.191718703987;9828.459434731158;9851.950740246253;6649.102371179829;6846.246912839484;7295.598742069024;7306.890396644161;7388.963055184905;7834.960825195838;8030.478018928751;8033.317168040782;8043.316318113026;8058.717944871902;7055.823604409931;7205.135383753774;7205.747798827016;7301.123865136217;7376.7786966391095;7094.503384763017;7118.822029449305;7184.568925864465;7237;7256.45576581769;6695.900743822649;6873.072135261676;6908.171019477695;6909.032735416945;6940.02839602446;4192.4130621712375;4744.762761861962;4762.857114286577;4869.293952376956;4894.412670479565;4822.903541929212;4841.031793641216;4882.902341953212;4902.656814022984;5059.443461219336 22:29:40 INFO - PROCESS | 1659 | |4;prototype.html;292.56700083875876;321.09451347200894;323.04217993648825;329.8087109476503;330.7304546794364;5839.386864379243;6127.417895299951;6489.415952564274;6598.340165983405;6611.107500487433;6460.095586617877;6473.773417930373;6507.583518652709;6517.859374609448;6524.445422139124;4581.060882519084;4623.722576645395;4701.764911754399;4740.6918550294295;4755.3104799804005;4130.215259100776;4569.360289559464;4660.86017419478;4718.339432479455;4747.691400059002;4695.624350051988;4704.764761761915;4708.434987801452;4711.151992641323;4742.122707299143;4009.799510024516;4504.414426124599;4583.12920545097;4651.558017014702;4709.529047095263;4049.574794646549;4066.674666026711;4077.7145599808027;4088.693347998912;4097.364908439196;6143.232095988002;6220.720067596923;6231.6572588507615;6231.844203894893;6239.095331176999;5576.107822748381;5595.160725891149;5597.104463285896;5597.132444471114;5611.410801865827;6886.24251332353;6897.9655101724675;6901.930980690179;6904.275051119661;6915.170179578483;4284.957150428487;4439.644828413751;4485.977570112162;4498.212812757754;4510.954890451087;6477.3846484584365;7262;7265.89101163483;7277;7305.634718264066;4534.818607255739;4552.11233809404;4561.885952851205;4570.565796249351;4576.359309696645;4598.172328980749;4601.539846015438;4604.631629469634;4611.308303754397;4615.330777037311;5451.209574611659;5454.972725136349;5458.754356053987;5460.645058071292;5469;6622.106015687917;6905.930940690529;6922.965385173042;6924.23833378328;6950.513464057569;1573.6301969037268;1586.8333824948445;1597.8561929426176;1603.2464741571443;1607.6784643071433;943.7923656795494;957.5690939077303;993.3940296419179;999.9650012249537;1018.5976539266948;5753.280839895013;6296.898042842484;6388.488920886318;6524.706388212542;6537.117489139;7294.9270507295305;7296.854062918712;7297.124345078624;7301.744438944717;7304.379127774092;5912.556558258148;6051.183090282843;6501.2198536175965;6536.673166341759;6543.967280163569;3800.2779471900803;3827.6937844972335;3829.942550861739;3833.4824798651825;3844.615538446189;7076.575405475688;7492.138404083491;7497.325240728361;7508.2867127622785;7525.247475252431;7368.521046131984;7418.295261950106;7426.517276377018;7451.44114191427;7482.139553951257;7442.441816863865;7452.180260171268;7466.141393739681;7486.4759466836795;7491.8501629967095;6123.601965872204;6363.840903977438;6855.897161542481;7173.767477188484;7207.099112610924;7086.539374940612;7101.644917753988;7108.253633368422;7139.071920650283;7139.500234983501;6131.325554188947;7441.181470038399;7467.925320746723;7475.215102414386;7499.737509187153;4549.27211646135;4554.476235232991;4554.726716397026;4560.361549383023;4560.703554268962;7157.57054576727;7168.677409516688;7168.784936451915;7171.354578087997;7190.532615379983;6439.195100612424;6804;6811.352921472452;6849.452043836382;6861.828454288683;6706.5640733352175;6756.358145976124;6770.01834733951;6788.796336109925;6801.557898736764;5479.506844384025;6599.868002640016;6600.80197594073;6610.07458955737;6625.569337993208;6636.045727699926;6801.081853949653;6817.522773405814;6831.9658401707675;6832.624205668736;6433.453156481564;6573.539852210491;6625.503087268377;6634.369734875179;6649.667516624053 22:29:40 INFO - PROCESS | 1659 | |5;yui.html;701.5124488480506;750.2534977697192;757.8105473631592;759.510115975196;778.8403377307652;14742.631434214136;14947.551573452787;15113.924430377847;15136.243187840606;15190.240487975612;13799;14015.229162396065;14018.859811401908;14040.64898377541;14045.78931316031;723.5984028864004;737.9926200738005;901.3465237702678;924.8936372317203;926.4533924984258;714.8641758065977;727.8325985023448;736.9189389167187;901.1664210605164;915.3180880244195;950.7908260182749;955.8375076237056;956.9808154315632;957.8994205608416;970.776721354089;946.564580293066;946.8627049077879;947.9573419196153;949.544218774985;965.0831709875611;559.3399788249864;712.975045873392;714.4855703893188;717.5622870049266;720.103471178381;14630.707385852224;14640.194789286585;14648.853511464855;14673.70652586953;14693.338799754038;12498.562550310786;12597.433115509824;12694.174878632859;12712.237265763992;13162.473501060042;9746.415215087118;9861.260405469618;9862.161716254128;9985.800283994353;9999.750006249902;3434.4676575130643;3463.1861512544533;3582.6417358264225;3625.818709064536;3628.9455658165148;62984;79939.20060799434;81794;81984.59007704983;82049.58975205146;9202.40184388026;9337.01961294068;9425.575849086808;9533.475658838692;9580.37727547721;21919.890400547894;22019.559608807733;22073.558528829653;22096.33710988673;22106.005229764698;7431.2197219292275;7432.739854105081;7470.103587569526;7470.4770666052855;7482.513636613603;4006.719529632898;4063.2076745034433;4073.837046518165;4189.4344263524645;4258.1483703258855;3675.1363429594026;3741.551013878352;3743.0455233915177;3754.4368344748505;3757.304898593769;630.9936900630935;651.9869602607921;657.4970147836908;661.0447902780533;681.2200030964617;541.1693051166441;543.4321134414546;569.2883895131087;605.1950905295946;617.2870334763355;7665.61671916402;8046.557439340892;8062.7581172564915;8119.228673276029;8502.14978502145;516.2978349444752;532.4010488200774;592.9623159470926;613.1599708399522;620.798240571816;3619.5475565554307;3768.2275133597545;3783.9621603783607;3824.4454554090057;3851.2297540491454;89740;90783;90820.54589727009;90927.09072909318;94231.0576894236;77752;78297;79007.60496197482;79180;80326.59836700778;67501.98747018799;67595.66202168958;68340.6582967092;68458;69076;7400.740974065882;7556.697732090654;7582.582957937367;7632.427567932538;7723.575203363869;48288.758556206994;49725.75137124291;50361.244581330575;50444.49555504398;51052.48947510626;65040.34959650343;67934;68129.65935170293;68370;69859;9768.023197680175;10012.04885535873;10184.643537476153;10192.28653994243;10377.636782712569;9714.271429642946;10545.420001899969;10558.788824223318;10990.065844403316;11049.171312151782;10117.645882394081;10138.391696498235;10225.822312058779;10334.689959301233;10397.688069357931;9636.084571965652;9727;9752.512374381395;10095.697129086138;10275.383476991405;9499.335046546952;9556.713298601053;9689.757756056155;9795.461249631053;9951.402915825074;8820.294376449998;8830.696708213496;8942.418742781698;8983.101689830964;9003.774905627411;8297.128801475757;8729.65081396737;8795.736127916172;8864.42381245217;8979.191872731486 22:29:40 INFO - PROCESS | 1659 | __end_tp_report 22:29:40 INFO - PROCESS | 1659 | __start_cc_report 22:29:40 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1494 22:29:40 INFO - PROCESS | 1659 | __end_cc_report 22:29:40 INFO - PROCESS | 1659 | __startTimestamp1463030980260__endTimestamp 22:29:40 INFO - PROCESS | 1659 | 22:29:40 INFO - PROCESS | 1659 | ------- Summary: start ------- 22:29:40 INFO - PROCESS | 1659 | Number of tests: 6 22:29:40 INFO - PROCESS | 1659 | 22:29:40 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5679.15 Median:5824.45 stddev:629.17 (10.8%) stddev-sans-first:603.34 22:29:40 INFO - PROCESS | 1659 | Values: 3322.8 3424.1 3457.4 3459.2 3499.0 5653.3 5661.8 5689.5 5702.8 5714.7 5632.5 5692.3 5697.5 5716.7 5727.8 5087.9 5098.5 5120.1 5245.2 5246.2 5179.2 5180.4 5188.5 5189.9 5195.9 5115.2 5168.5 5175.4 5184.7 5193.6 5176.3 5209.1 5221.6 5225.3 5282.7 5035.9 5058.3 5085.8 5145.2 5158.5 5512.0 5532.3 5552.6 5558.9 5570.1 5615.0 6107.5 6163.3 6176.5 6199.2 5205.9 6098.5 6191.8 6207.1 6264.4 5809.4 5822.0 5824.4 5844.0 5849.0 6309.2 6335.2 6337.8 6342.7 6343.1 5963.9 6052.0 6080.6 6095.1 6096.8 6162.8 6238.8 6240.5 6241.4 6261.2 6126.5 6145.0 6184.5 6200.2 6226.0 6034.5 6114.4 6191.0 6200.6 6223.3 4867.9 4875.2 4889.3 4902.5 4913.1 4424.5 4471.5 4472.2 4473.6 4488.4 5612.7 5621.4 5623.6 5631.6 5686.2 6209.3 6257.8 6266.7 6285.8 6314.7 5642.9 5649.4 5664.2 5697.8 5699.2 5283.9 5352.4 5371.6 5374.8 5397.5 6282.7 6293.3 6304.0 6310.2 6317.1 4479.9 6274.1 6280.3 6300.8 6318.6 6199.0 6239.0 6273.7 6285.1 6328.1 6159.2 6168.3 6169.7 6176.4 6187.7 6245.4 6265.4 6266.5 6268.7 6301.7 5832.2 5855.6 5904.2 5911.4 5919.9 5012.9 5638.7 5907.6 5946.3 6005.3 5762.8 5794.5 5807.9 5808.8 5814.4 5859.6 5923.6 5944.0 5990.9 5993.6 5840.2 5841.7 5847.4 5849.4 5859.5 22:29:40 INFO - PROCESS | 1659 | 22:29:40 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:63783.70 Median:36688.72 stddev:131827.70 (359.3%) stddev-sans-first:132111.91 22:29:40 INFO - PROCESS | 1659 | Values: 510.8 530.0 540.2 545.5 585.3 30723.9 31302.7 31357.8 31433.5 38274.2 44315.8 44843.0 44879.3 44911.0 44981.8 9609.6 9835.7 9848.6 9849.4 9870.1 9564.0 9706.7 9712.4 9724.1 9731.0 9668.3 9679.9 9716.8 9725.7 9737.5 8451.9 9237.9 9674.6 9718.8 9726.2 9504.2 9606.4 9650.3 9656.9 9659.3 40292.0 40664.2 40678.6 40744.0 40834.2 3195.3 3223.3 3226.1 3370.9 3420.9 28758.4 28769.1 28803.6 29038.9 29303.3 2746.0 2783.3 2784.1 2786.7 2801.1 435528.0 435619.0 435620.8 436860.0 438561.0 63535.0 63952.0 64036.7 64095.4 64221.7 66117.3 66577.3 69427.7 70920.6 71096.0 17216.7 17223.3 17423.7 17439.7 17557.3 13338.9 13421.5 13431.5 13435.1 13480.8 19494.4 19611.6 19659.5 19663.6 19696.8 8200.2 8200.5 8229.0 8232.8 8246.9 915.8 1015.2 1068.0 1074.7 1075.1 29759.9 29782.4 30294.4 30367.0 30417.9 1074.0 1094.8 1110.8 1199.1 1258.6 15314.4 15614.3 15880.8 16046.4 16362.9 516749.0 517266.0 522137.0 522189.0 525137.0 57703.0 58183.0 58244.5 58343.0 58731.8 1360.3 1398.9 1403.9 1431.2 1480.6 28392.9 28472.6 29060.7 29629.7 29792.3 52066.5 52071.2 52746.7 52765.0 52776.0 521221.0 522607.4 524496.0 526509.0 527578.0 23573.5 26514.3 26987.9 27099.5 27603.2 40774.4 41346.6 42427.4 42729.0 42942.8 34780.5 34886.5 35324.5 35417.5 35585.3 35934.8 36275.6 36668.8 36708.6 36903.0 35300.1 35859.0 35882.0 35992.5 36439.3 31390.4 31926.0 32060.7 32502.0 33918.3 19776.2 24331.9 24455.9 24460.4 25420.2 22:29:40 INFO - PROCESS | 1659 | 22:29:40 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:35115.51 Median:21120.16 stddev:103332.23 (489.3%) stddev-sans-first:103589.13 22:29:40 INFO - PROCESS | 1659 | Values: 348.7 362.7 400.5 404.3 410.3 16188.0 16549.0 20100.9 21129.8 21793.0 16139.3 20733.8 20828.7 21063.8 21110.5 5797.4 5931.0 5956.6 5964.3 6010.0 5763.6 5923.4 5941.7 5943.1 5945.7 5181.8 5887.4 5898.1 5898.7 5901.6 5620.4 5871.4 5871.7 5873.6 5890.5 5160.0 5193.0 5194.6 5195.3 5201.2 25539.4 25902.5 26286.0 26346.0 26359.9 2356.6 2471.8 2717.1 2744.4 3028.5 14867.0 14889.6 15219.2 15450.2 15652.5 1732.9 1774.5 1778.5 1808.6 1865.5 106252.0 112510.0 112949.0 114301.4 114962.4 9897.1 11074.6 11402.6 11439.7 11615.4 14443.2 14452.6 14458.1 14490.1 14555.0 8864.0 9088.5 9096.7 9148.1 9174.3 7015.8 8201.6 8603.7 8720.0 9526.0 6195.3 6891.5 6896.8 6897.8 6925.9 1891.3 1913.3 1921.9 1937.2 1957.9 915.2 949.0 1028.7 1042.2 1042.2 16967.4 20667.5 21751.3 21861.6 21894.6 1003.7 1029.8 1031.5 1068.9 1091.0 11452.7 11809.3 11818.8 11855.9 11868.5 609658.0 617683.0 630316.0 631265.0 631407.0 108669.0 112513.4 113285.0 115005.4 116732.4 1056.5 1056.7 1073.3 1073.4 1075.7 18601.7 18794.9 18976.9 19382.1 19761.5 49632.8 50744.0 51090.0 51138.0 52992.5 67195.0 71041.6 71166.6 71194.9 71399.0 7981.0 8253.6 8349.5 8435.0 8494.7 9329.8 10290.6 12700.7 12743.4 13107.7 10998.6 11172.2 11208.3 11498.0 11532.0 10784.4 10796.5 11057.5 11152.5 11317.8 11267.3 11431.1 11433.0 11530.5 11542.9 7106.7 8242.6 8329.7 8624.7 8714.4 10604.6 10741.6 11026.7 11397.7 11422.9 22:29:40 INFO - PROCESS | 1659 | 22:29:40 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5595.87 Median:5325.45 stddev:3088.84 (58.0%) stddev-sans-first:3081.51 22:29:40 INFO - PROCESS | 1659 | Values: 1412.9 1585.7 1602.7 1839.5 1948.2 7810.8 8488.7 8665.2 8695.6 8702.3 8431.0 8640.1 8641.6 8642.5 8657.4 2439.7 2473.4 2476.0 2478.6 2478.7 2402.6 2482.1 2482.6 2483.2 2485.7 2180.4 2420.8 2432.7 2461.1 2477.8 2420.3 2449.8 2464.7 2464.9 2466.4 2424.7 2456.4 2457.3 2461.2 2462.2 8443.8 8639.5 8700.1 8711.3 8724.0 5341.5 5437.1 5443.3 5454.6 5474.0 3428.7 3447.0 3526.7 3548.9 3573.6 916.8 1044.6 1045.0 1072.3 1086.3 11566.8 11620.9 11622.8 11628.8 11630.3 4209.8 4567.8 4943.7 4961.9 4968.3 9046.1 9052.4 9134.0 9136.9 9136.9 5298.8 5312.4 5338.5 5343.3 5345.3 3711.9 4443.8 4566.5 4640.9 4992.6 3585.2 3872.8 3889.1 3891.3 3925.0 1214.7 1240.9 1244.7 1248.2 1261.2 913.8 921.2 924.6 928.5 960.4 5609.9 5738.5 5833.8 5841.7 5945.7 891.2 902.7 939.2 977.3 981.9 3526.8 3746.7 3791.4 3906.9 3908.8 10299.3 10324.5 10568.4 10663.9 10810.1 10517.2 10575.3 10591.6 10629.3 10752.7 8123.0 8260.5 8310.0 8339.6 8448.8 5462.2 5511.3 5565.7 5572.0 5656.0 9448.9 9519.9 9634.9 9711.5 9713.6 9059.4 9382.2 9509.2 9828.5 9852.0 6649.1 6846.2 7295.6 7306.9 7389.0 7835.0 8030.5 8033.3 8043.3 8058.7 7055.8 7205.1 7205.7 7301.1 7376.8 7094.5 7118.8 7184.6 7237.0 7256.5 6695.9 6873.1 6908.2 6909.0 6940.0 4192.4 4744.8 4762.9 4869.3 4894.4 4822.9 4841.0 4882.9 4902.7 5059.4 22:29:40 INFO - PROCESS | 1659 | 22:29:40 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:5614.54 Median:6475.58 stddev:1800.53 (27.8%) stddev-sans-first:1760.71 22:29:40 INFO - PROCESS | 1659 | Values: 292.6 321.1 323.0 329.8 330.7 5839.4 6127.4 6489.4 6598.3 6611.1 6460.1 6473.8 6507.6 6517.9 6524.4 4581.1 4623.7 4701.8 4740.7 4755.3 4130.2 4569.4 4660.9 4718.3 4747.7 4695.6 4704.8 4708.4 4711.2 4742.1 4009.8 4504.4 4583.1 4651.6 4709.5 4049.6 4066.7 4077.7 4088.7 4097.4 6143.2 6220.7 6231.7 6231.8 6239.1 5576.1 5595.2 5597.1 5597.1 5611.4 6886.2 6898.0 6901.9 6904.3 6915.2 4285.0 4439.6 4486.0 4498.2 4511.0 6477.4 7262.0 7265.9 7277.0 7305.6 4534.8 4552.1 4561.9 4570.6 4576.4 4598.2 4601.5 4604.6 4611.3 4615.3 5451.2 5455.0 5458.8 5460.6 5469.0 6622.1 6905.9 6923.0 6924.2 6950.5 1573.6 1586.8 1597.9 1603.2 1607.7 943.8 957.6 993.4 1000.0 1018.6 5753.3 6296.9 6388.5 6524.7 6537.1 7294.9 7296.9 7297.1 7301.7 7304.4 5912.6 6051.2 6501.2 6536.7 6544.0 3800.3 3827.7 3829.9 3833.5 3844.6 7076.6 7492.1 7497.3 7508.3 7525.2 7368.5 7418.3 7426.5 7451.4 7482.1 7442.4 7452.2 7466.1 7486.5 7491.9 6123.6 6363.8 6855.9 7173.8 7207.1 7086.5 7101.6 7108.3 7139.1 7139.5 6131.3 7441.2 7467.9 7475.2 7499.7 4549.3 4554.5 4554.7 4560.4 4560.7 7157.6 7168.7 7168.8 7171.4 7190.5 6439.2 6804.0 6811.4 6849.5 6861.8 6706.6 6756.4 6770.0 6788.8 6801.6 5479.5 6599.9 6600.8 6610.1 6625.6 6636.0 6801.1 6817.5 6832.0 6832.6 6433.5 6573.5 6625.5 6634.4 6649.7 22:29:40 INFO - PROCESS | 1659 | 22:29:40 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:17916.49 Median:69467.50 stddev:25482.52 (36.7%) stddev-sans-first:25521.22 22:29:40 INFO - PROCESS | 1659 | Values: 701.5 750.3 757.8 759.5 778.8 14742.6 14947.6 15113.9 15136.2 15190.2 13799.0 14015.2 14018.9 14040.6 14045.8 723.6 738.0 901.3 924.9 926.5 714.9 727.8 736.9 901.2 915.3 950.8 955.8 957.0 957.9 970.8 946.6 946.9 948.0 949.5 965.1 559.3 713.0 714.5 717.6 720.1 14630.7 14640.2 14648.9 14673.7 14693.3 12498.6 12597.4 12694.2 12712.2 13162.5 9746.4 9861.3 9862.2 9985.8 9999.8 3434.5 3463.2 3582.6 3625.8 3628.9 62984.0 79939.2 81794.0 81984.6 82049.6 9202.4 9337.0 9425.6 9533.5 9580.4 21919.9 22019.6 22073.6 22096.3 22106.0 7431.2 7432.7 7470.1 7470.5 7482.5 4006.7 4063.2 4073.8 4189.4 4258.1 3675.1 3741.6 3743.0 3754.4 3757.3 631.0 652.0 657.5 661.0 681.2 541.2 543.4 569.3 605.2 617.3 7665.6 8046.6 8062.8 8119.2 8502.1 516.3 532.4 593.0 613.2 620.8 3619.5 3768.2 3784.0 3824.4 3851.2 89740.0 90783.0 90820.5 90927.1 94231.1 77752.0 78297.0 79007.6 79180.0 80326.6 67502.0 67595.7 68340.7 68458.0 69076.0 7400.7 7556.7 7582.6 7632.4 7723.6 48288.8 49725.8 50361.2 50444.5 51052.5 65040.3 67934.0 68129.7 68370.0 69859.0 9768.0 10012.0 10184.6 10192.3 10377.6 9714.3 10545.4 10558.8 10990.1 11049.2 10117.6 10138.4 10225.8 10334.7 10397.7 9636.1 9727.0 9752.5 10095.7 10275.4 9499.3 9556.7 9689.8 9795.5 9951.4 8820.3 8830.7 8942.4 8983.1 9003.8 8297.1 8729.7 8795.7 8864.4 8979.2 22:29:40 INFO - PROCESS | 1659 | -------- Summary: end -------- 22:29:40 INFO - PROCESS | 1659 | 22:29:40 INFO - TEST-INFO | 1659: exit 0 22:29:40 INFO - TEST-OK | dromaeo_css | took 1115258ms 22:29:40 INFO - TEST-START | kraken 22:29:40 INFO - operating with platform_type : mac_ 22:29:40 INFO - Initialising browser for kraken test... 22:29:40 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/tmpIcyDzB/profile http://localhost:49275/getInfo.html) 22:29:42 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 22:29:42 INFO - PROCESS | 1668 | colorDepth:24 22:29:42 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 22:29:42 INFO - PROCESS | 1668 | __metrics 22:29:43 INFO - PROCESS | 1668 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:29:43 ERROR - PROCESS | 1668 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:29:43 INFO - TEST-INFO | 1668: exit 0 22:29:43 INFO - Browser initialized. 22:29:43 INFO - Running cycle 1/1 for kraken test... 22:29:43 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/tmpIcyDzB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:29:55 INFO - PROCESS | 1670 | RSS: Main: 211083264 22:29:55 INFO - PROCESS | 1670 | 22:29:58 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 22:29:59 INFO - PROCESS | 1670 | RSS: Main: 271626240 22:29:59 INFO - PROCESS | 1670 | 22:30:01 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 22:30:02 INFO - PROCESS | 1670 | RSS: Main: 285839360 22:30:02 INFO - PROCESS | 1670 | 22:30:05 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 22:30:06 INFO - PROCESS | 1670 | RSS: Main: 372695040 22:30:06 INFO - PROCESS | 1670 | 22:30:08 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 22:30:08 INFO - PROCESS | 1670 | RSS: Main: 279842816 22:30:08 INFO - PROCESS | 1670 | 22:30:11 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:30:11 INFO - PROCESS | 1670 | RSS: Main: 280551424 22:30:11 INFO - PROCESS | 1670 | 22:30:14 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 22:30:15 INFO - PROCESS | 1670 | RSS: Main: 319340544 22:30:15 INFO - PROCESS | 1670 | 22:30:18 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 22:30:18 INFO - PROCESS | 1670 | RSS: Main: 319086592 22:30:18 INFO - PROCESS | 1670 | 22:30:21 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 22:30:22 INFO - PROCESS | 1670 | RSS: Main: 318013440 22:30:22 INFO - PROCESS | 1670 | 22:30:23 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:30:24 INFO - PROCESS | 1670 | RSS: Main: 309334016 22:30:24 INFO - PROCESS | 1670 | 22:30:26 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:30:26 INFO - PROCESS | 1670 | RSS: Main: 316653568 22:30:26 INFO - PROCESS | 1670 | 22:30:28 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:30:29 INFO - PROCESS | 1670 | RSS: Main: 318623744 22:30:29 INFO - PROCESS | 1670 | 22:30:32 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:30:33 INFO - PROCESS | 1670 | RSS: Main: 324055040 22:30:33 INFO - PROCESS | 1670 | 22:30:35 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:30:36 INFO - PROCESS | 1670 | RSS: Main: 322228224 22:30:36 INFO - PROCESS | 1670 | 22:30:38 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 22:30:38 INFO - PROCESS | 1670 | RSS: Main: 386433024 22:30:38 INFO - PROCESS | 1670 | 22:30:38 INFO - PROCESS | 1670 | __start_tp_report 22:30:38 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 22:30:38 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 22:30:38 INFO - PROCESS | 1670 | |i|pagename|runs| 22:30:38 INFO - PROCESS | 1670 | |0;ai-astar;114;116;120;114;113;113;115;116;114;132 22:30:38 INFO - PROCESS | 1670 | |1;audio-beat-detection;158;122;156;159;117;147;166;142;161;143 22:30:38 INFO - PROCESS | 1670 | |2;audio-dft;186;189;187;184;187;187;184;188;187;186 22:30:38 INFO - PROCESS | 1670 | |3;audio-fft;70;79;73;152;56;56;57;63;134;55 22:30:38 INFO - PROCESS | 1670 | |4;audio-oscillator;91;89;146;78;82;79;121;77;103;159 22:30:38 INFO - PROCESS | 1670 | |5;imaging-gaussian-blur;89;86;90;87;89;89;135;87;89;86 22:30:38 INFO - PROCESS | 1670 | |6;imaging-darkroom;110;116;95;108;149;104;95;94;94;94 22:30:38 INFO - PROCESS | 1670 | |7;imaging-desaturate;84;86;86;84;83;85;86;85;84;85 22:30:38 INFO - PROCESS | 1670 | |8;json-parse-financial;60;63;63;62;62;66;64;82;59;58 22:30:38 INFO - PROCESS | 1670 | |9;json-stringify-tinderbox;64;64;65;76;78;75;71;67;69;67 22:30:38 INFO - PROCESS | 1670 | |10;stanford-crypto-aes;82;78;81;81;81;81;82;81;83;83 22:30:38 INFO - PROCESS | 1670 | |11;stanford-crypto-ccm;129;130;127;128;126;127;129;136;146;159 22:30:38 INFO - PROCESS | 1670 | |12;stanford-crypto-pbkdf2;180;167;165;170;168;166;164;168;165;162 22:30:38 INFO - PROCESS | 1670 | |13;stanford-crypto-sha256-iterative;66;68;67;68;69;68;67;69;67;69 22:30:38 INFO - PROCESS | 1670 | __end_tp_report 22:30:38 INFO - PROCESS | 1670 | __start_cc_report 22:30:38 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,2061 22:30:38 INFO - PROCESS | 1670 | __end_cc_report 22:30:38 INFO - PROCESS | 1670 | __startTimestamp1463031038407__endTimestamp 22:30:38 INFO - PROCESS | 1670 | 22:30:38 INFO - PROCESS | 1670 | ------- Summary: start ------- 22:30:38 INFO - PROCESS | 1670 | Number of tests: 14 22:30:38 INFO - PROCESS | 1670 | 22:30:38 INFO - PROCESS | 1670 | [#0] ai-astar Cycles:10 Average:116.70 Median:115.50 stddev:5.76 (5.0%) stddev-sans-first:6.02 22:30:38 INFO - PROCESS | 1670 | Values: 114.0 116.0 120.0 114.0 113.0 113.0 115.0 116.0 114.0 132.0 22:30:38 INFO - PROCESS | 1670 | 22:30:38 INFO - PROCESS | 1670 | [#1] audio-beat-detection Cycles:10 Average:147.10 Median:157.00 stddev:16.56 (10.5%) stddev-sans-first:17.09 22:30:38 INFO - PROCESS | 1670 | Values: 158.0 122.0 156.0 159.0 117.0 147.0 166.0 142.0 161.0 143.0 22:30:38 INFO - PROCESS | 1670 | 22:30:38 INFO - PROCESS | 1670 | [#2] audio-dft Cycles:10 Average:186.50 Median:187.00 stddev:1.58 (0.8%) stddev-sans-first:1.67 22:30:38 INFO - PROCESS | 1670 | Values: 186.0 189.0 187.0 184.0 187.0 187.0 184.0 188.0 187.0 186.0 22:30:38 INFO - PROCESS | 1670 | 22:30:38 INFO - PROCESS | 1670 | [#3] audio-fft Cycles:10 Average:79.50 Median:60.00 stddev:34.71 (57.8%) stddev-sans-first:36.64 22:30:38 INFO - PROCESS | 1670 | Values: 70.0 79.0 73.0 152.0 56.0 56.0 57.0 63.0 134.0 55.0 22:30:38 INFO - PROCESS | 1670 | 22:30:38 INFO - PROCESS | 1670 | [#4] audio-oscillator Cycles:10 Average:102.50 Median:78.50 stddev:29.75 (37.9%) stddev-sans-first:31.26 22:30:38 INFO - PROCESS | 1670 | Values: 91.0 89.0 146.0 78.0 82.0 79.0 121.0 77.0 103.0 159.0 22:30:38 INFO - PROCESS | 1670 | 22:30:38 INFO - PROCESS | 1670 | [#5] imaging-gaussian-blur Cycles:10 Average:92.70 Median:89.00 stddev:14.93 (16.8%) stddev-sans-first:15.78 22:30:38 INFO - PROCESS | 1670 | Values: 89.0 86.0 90.0 87.0 89.0 89.0 135.0 87.0 89.0 86.0 22:30:38 INFO - PROCESS | 1670 | 22:30:38 INFO - PROCESS | 1670 | [#6] imaging-darkroom Cycles:10 Average:105.90 Median:94.00 stddev:17.15 (18.2%) stddev-sans-first:18.13 22:30:38 INFO - PROCESS | 1670 | Values: 110.0 116.0 95.0 108.0 149.0 104.0 95.0 94.0 94.0 94.0 22:30:38 INFO - PROCESS | 1670 | 22:30:38 INFO - PROCESS | 1670 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.00 stddev:1.03 (1.2%) stddev-sans-first:1.05 22:30:38 INFO - PROCESS | 1670 | Values: 84.0 86.0 86.0 84.0 83.0 85.0 86.0 85.0 84.0 85.0 22:30:38 INFO - PROCESS | 1670 | 22:30:38 INFO - PROCESS | 1670 | [#8] json-parse-financial Cycles:10 Average:63.90 Median:63.00 stddev:6.79 (10.8%) stddev-sans-first:7.05 22:30:38 INFO - PROCESS | 1670 | Values: 60.0 63.0 63.0 62.0 62.0 66.0 64.0 82.0 59.0 58.0 22:30:38 INFO - PROCESS | 1670 | 22:30:38 INFO - PROCESS | 1670 | [#9] json-stringify-tinderbox Cycles:10 Average:69.60 Median:70.00 stddev:5.17 (7.4%) stddev-sans-first:5.07 22:30:38 INFO - PROCESS | 1670 | Values: 64.0 64.0 65.0 76.0 78.0 75.0 71.0 67.0 69.0 67.0 22:30:38 INFO - PROCESS | 1670 | 22:30:38 INFO - PROCESS | 1670 | [#10] stanford-crypto-aes Cycles:10 Average:81.30 Median:81.50 stddev:1.42 (1.7%) stddev-sans-first:1.48 22:30:38 INFO - PROCESS | 1670 | Values: 82.0 78.0 81.0 81.0 81.0 81.0 82.0 81.0 83.0 83.0 22:30:38 INFO - PROCESS | 1670 | 22:30:38 INFO - PROCESS | 1670 | [#11] stanford-crypto-ccm Cycles:10 Average:133.70 Median:129.50 stddev:10.73 (8.3%) stddev-sans-first:11.24 22:30:38 INFO - PROCESS | 1670 | Values: 129.0 130.0 127.0 128.0 126.0 127.0 129.0 136.0 146.0 159.0 22:30:38 INFO - PROCESS | 1670 | 22:30:38 INFO - PROCESS | 1670 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.50 Median:167.50 stddev:4.95 (3.0%) stddev-sans-first:2.42 22:30:38 INFO - PROCESS | 1670 | Values: 180.0 167.0 165.0 170.0 168.0 166.0 164.0 168.0 165.0 162.0 22:30:38 INFO - PROCESS | 1670 | 22:30:38 INFO - PROCESS | 1670 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:68.00 stddev:1.03 (1.5%) stddev-sans-first:0.87 22:30:38 INFO - PROCESS | 1670 | Values: 66.0 68.0 67.0 68.0 69.0 68.0 67.0 69.0 67.0 69.0 22:30:38 INFO - PROCESS | 1670 | -------- Summary: end -------- 22:30:38 INFO - PROCESS | 1670 | 22:30:39 INFO - TEST-INFO | 1670: exit 0 22:30:39 INFO - TEST-OK | kraken | took 58374ms 22:30:39 INFO - SUITE-END | took 1173s 22:30:39 INFO - Completed test suite (00:19:34) 22:30:39 INFO - javascript benchmark 22:30:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3322.7705748872913, 3424.075499615104, 3457.3949558827208, 3459.2389674271653, 3499.0, 5653.265075540179, 5661.830145095649, 5689.4879460848515, 5702.77188912443, 5714.742836572354, 5632.493075623193, 5692.288463942007, 5697.544196464283, 5716.685582292983, 5727.770889164429, 5087.872803179913, 5098.515641014107, 5120.103981803189, 5245.2132180173085, 5246.239295302179, 5179.171332586787, 5180.378354597454, 5188.455212202721, 5189.858231189132, 5195.896082078375, 5115.232715092748, 5168.48315168482, 5175.404828444721, 5184.7407629618365, 5193.63644544882, 5176.275321454999, 5209.088409528355, 5221.582273418156, 5225.320708307933, 5282.735863206863, 5035.874103147414, 5058.291839142523, 5085.847424577269, 5145.202493613496, 5158.53573178412, 5512.035393806068, 5532.336119665666, 5552.639078459928, 5558.888822223574, 5570.08093664549, 5615.0, 6107.4808641265545, 6163.291221509539, 6176.505879529627, 6199.163112979735, 5205.895882082337, 6098.517589571661, 6191.81424557264, 6207.099970504297, 6264.4362007419095, 5809.419058094157, 5821.981153298156, 5824.446677565625, 5843.9707801461145, 5849.034909240013, 6309.2428908530355, 6335.239771227482, 6337.841553961096, 6342.746290148342, 6343.143675603735, 5963.940360596339, 6052.03167493199, 6080.635161890301, 6095.1466794647895, 6096.817095487142, 6162.815115546541, 6238.812835614848, 6240.5319601030105, 6241.438270555672, 6261.186045814015, 6126.479249263773, 6144.969275153596, 6184.536159788034, 6200.193974783249, 6226.034964580497, 6034.547408944347, 6114.449699527064, 6191.040388739752, 6200.596961197508, 6223.3465486123305, 4867.902641947141, 4875.171220892456, 4889.315495830587, 4902.460729319772, 4913.11563918491, 4424.513303536608, 4471.485779135376, 4472.239719247736, 4473.642108631302, 4488.416505854283, 5612.691301978431, 5621.437856214346, 5623.606347555714, 5631.63394379364, 5686.232358631614, 6209.285932117774, 6257.78097766576, 6266.686665666698, 6285.779997700059, 6314.747410103544, 5642.887142257132, 5649.378568357477, 5664.178694089334, 5697.772089116472, 5699.230603868507, 5283.9471605284225, 5352.437994010651, 5371.57027437804, 5374.811881584125, 5397.487238712316, 6282.685865706788, 6293.276273228637, 6304.0, 6310.242770867525, 6317.052442133717, 4479.865604031885, 6274.058891166362, 6280.340564240689, 6300.810975670737, 6318.589291696025, 6198.969005154945, 6239.0, 6273.749050037947, 6285.088662144055, 6328.0824280478155, 6159.168512250968, 6168.290646575521, 6169.722362493789, 6176.382361763787, 6187.659678717714, 6245.3754624539, 6265.4361107500545, 6266.498680105672, 6268.655223962726, 6301.747930082929, 5832.212651292021, 5855.59010869235, 5904.173415721716, 5911.379305172895, 5919.940800591939, 5012.924806127984, 5638.661680299195, 5907.556933230111, 5946.316173639913, 6005.279366476051, 5762.798302059407, 5794.536437084938, 5807.883842323215, 5808.82573522795, 5814.3604203538425, 5859.589828711949, 5923.644581325134, 5943.970280148572, 5990.910136348046, 5993.550483713651, 5840.1531777892815, 5841.707914604168, 5847.415258474119, 5849.3858144894175, 5859.472647461749], "value": 5641.404638056639, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [510.79823469729456, 530.0406264660963, 540.1816248383699, 545.5008667069629, 585.3151812379516, 30723.92466263681, 31302.68697313026, 31357.843210783914, 31433.528497072533, 38274.234515309676, 44315.77842110785, 44843.0, 44879.326810097875, 44911.0, 44981.77509112458, 9609.615615375376, 9835.65575204868, 9848.556814943344, 9849.40903545786, 9870.062344077302, 9564.0, 9706.708798736014, 9712.41725496469, 9724.124828765409, 9730.951345243264, 9668.274879384038, 9679.854802177973, 9716.805663886718, 9725.708228753148, 9737.464439455827, 8451.915480845175, 9237.907620923805, 9674.613015479372, 9718.75703107428, 9726.221902247838, 9504.239660827117, 9606.375585586915, 9650.276229282832, 9656.90343096567, 9659.32384733069, 40292.0, 40664.1867162658, 40678.593214067776, 40743.98140046493, 40834.1833163338, 3195.2970346523725, 3223.2908760072746, 3226.1450715560395, 3370.8651653934057, 3420.9144771380916, 28758.424831503253, 28769.136925892257, 28803.56794648082, 29038.85480572605, 29303.2674183145, 2746.0114358830806, 2783.3320003199287, 2784.081253186464, 2786.70739572346, 2801.131649188738, 435528.0, 435619.0, 435620.8218958885, 436859.9999999968, 438561.0, 63535.0, 63952.0, 64036.67981660062, 64095.359046409874, 64221.67889160618, 66117.33882661111, 66577.33422665809, 69427.65286173536, 70920.64539677268, 71096.0, 17216.741748873777, 17223.31106755716, 17423.738643920096, 17439.738403923697, 17557.297708091788, 13338.93330533341, 13421.463141474427, 13431.529896453772, 13435.12671676338, 13480.797788033187, 19494.415167544998, 19611.60776784485, 19659.508512287306, 19663.60672786536, 19696.803031969783, 8200.179982001871, 8200.548969806718, 8228.958855205685, 8232.83534329322, 8246.917530824734, 915.7527467583715, 1015.2334987084704, 1067.9733006674896, 1074.6722249713755, 1075.0539525217757, 29759.85120074429, 29782.40435191284, 30294.394112118076, 30367.0, 30417.935372261865, 1073.978520429587, 1094.8303013032992, 1110.7500812317326, 1199.0827017331749, 1258.6349958511928, 15314.391988841235, 15614.29735661898, 15880.761788573182, 16046.438374657064, 16362.918185408997, 516749.0, 517266.0, 522137.0, 522189.0, 525137.0, 57703.0, 58183.0, 58244.54388640318, 58343.0, 58731.82536349334, 1360.2790521023874, 1398.880895283769, 1403.8525954774807, 1431.1842249917656, 1480.5558332500423, 28392.86428542835, 28472.572911406758, 29060.709392906647, 29629.703702962695, 29792.275965504312, 52066.47933520616, 52071.218931716816, 52746.736266318425, 52765.0, 52776.0, 521221.0, 522607.38696306275, 524496.0, 526509.0, 527578.0, 23573.52852942966, 26514.337141571614, 26987.865060674572, 27099.458010840066, 27603.171904842886, 40774.38838417367, 41346.58653413548, 42427.363589545566, 42729.0, 42942.78528607337, 34780.47829282512, 34886.476702849985, 35324.47013294854, 35417.45825417437, 35585.28829423449, 35934.82032589925, 36275.63724362723, 36668.81665591655, 36708.632913670524, 36903.0, 35300.117497062776, 35859.0, 35882.0, 35992.4601130978, 36439.27121457609, 31390.37219255648, 31926.042218733473, 32060.671809305444, 32502.0, 33918.321633566695, 19776.208951641773, 24331.878340608182, 24455.877720611283, 24460.388490287885, 25420.237392878244], "value": 19686.6380159381, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.7332190873982, 362.66815863484914, 400.5493819453116, 404.28239874223254, 410.34345047923347, 16188.0, 16549.007059576434, 20100.89949550252, 21129.837858917774, 21793.019314130863, 16139.273732682028, 20733.792662073374, 20828.687569686466, 21063.841488718113, 21110.472238194052, 5797.4202579742005, 5930.97034514829, 5956.553258505608, 5964.254468191476, 6009.969950150243, 5763.625364351325, 5923.407659234085, 5941.70291485424, 5943.078822782476, 5945.672987985659, 5181.766820493087, 5887.411258874121, 5898.056310990242, 5898.734556944926, 5901.557383196277, 5620.409856965021, 5871.412858714137, 5871.677057761843, 5873.647581145145, 5890.499307558863, 5160.045391602565, 5193.0, 5194.584433245332, 5195.324607801, 5201.1678131498775, 25539.361515962064, 25902.48195036089, 26286.0, 26346.0, 26359.94560217589, 2356.6229403295392, 2471.765182307678, 2717.1440996086367, 2744.423671029086, 3028.5154375300067, 14867.0, 14889.627759306104, 15219.239038048165, 15450.1502417367, 15652.452164174314, 1732.8960262384298, 1774.5120091974682, 1778.457570441015, 1808.6111486030436, 1865.5429419792101, 106252.0, 112510.0, 112949.0, 114301.42849285784, 114962.42518787437, 9897.059779320953, 11074.557017719362, 11402.600908968147, 11439.684436289934, 11615.419229038684, 14443.205623690588, 14452.566423007116, 14458.06022608527, 14490.058146220463, 14555.0, 8863.980642226097, 9088.500132492643, 9096.681616143536, 9148.085191480799, 9174.311926605398, 7015.789526314219, 8201.630926608317, 8603.741887743252, 8719.997200321917, 9525.952370238105, 6195.256569211723, 6891.483138764613, 6896.827579310558, 6897.793066208022, 6925.861482770317, 1891.3474851176322, 1913.2538310059088, 1921.9039048047541, 1937.205745644279, 1957.9118939647751, 915.1763412928285, 948.9762755931018, 1028.7273872423664, 1042.207921979286, 1042.2495803021811, 16967.406140785013, 20667.4833129173, 21751.34745957592, 21861.562768744538, 21894.562108758055, 1003.7440452684665, 1029.8043371759343, 1031.4842578710645, 1068.871735391758, 1091.0071834630448, 11452.656420307405, 11809.291442513477, 11818.822717659243, 11855.932966032926, 11868.525258989717, 609657.9517102386, 617683.0, 630316.0, 631265.0, 631407.0, 108669.0, 112513.43743281231, 113285.0, 115005.4249728746, 116732.41633791947, 1056.5140035583563, 1056.6777132974516, 1073.3291692692067, 1073.393532654048, 1075.6769173916127, 18601.720974185668, 18794.872307661582, 18976.905115474336, 19382.12780424856, 19761.505962351057, 49632.75183624059, 50743.985120296646, 51090.0, 51137.97724045573, 52992.470075298756, 67195.0, 71041.6447917757, 71166.64416677883, 71194.93207601993, 71399.0, 7981.0, 8253.587320634064, 8349.499030058216, 8435.0, 8494.660213591387, 9329.827205093598, 10290.639827605997, 12700.682482938, 12743.426545805276, 13107.737845243233, 10998.615048473264, 11172.217944744116, 11208.27146235492, 11498.022668073307, 11532.0, 10784.352938823695, 10796.460176990962, 11057.509141197446, 11152.49813758399, 11317.830232546354, 11267.267627604508, 11431.085513159094, 11433.0, 11530.538778449103, 11542.94228528852, 7106.715731370894, 8242.59875821124, 8329.708460203863, 8624.698135565224, 8714.38999270045, 10604.575816967543, 10741.624043158452, 11026.669199924016, 11397.715057123307, 11422.94288528552], "value": 9808.426652952321, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1412.9222892740909, 1585.6907902958922, 1602.7115119278528, 1839.4849442156192, 1948.1622902152078, 7810.843783124335, 8488.702895398663, 8665.220130188274, 8695.56522173892, 8702.347323950697, 8430.95784521078, 8640.092790257011, 8641.567921603926, 8642.52466114365, 8657.437266577668, 2439.6828412306377, 2473.4311108445067, 2475.9752402475924, 2478.5910324796387, 2478.6653801736716, 2402.60357041088, 2482.131254061082, 2482.590372588521, 2483.1805504183576, 2485.7389974052735, 2180.3785921012513, 2420.765409641078, 2432.683751112353, 2461.0770960889668, 2477.764612361823, 2420.3465064432503, 2449.8407603505802, 2464.7165575958634, 2464.8890799914047, 2466.3957330454064, 2424.7211570669424, 2456.4472993576483, 2457.348802567321, 2461.237016524883, 2462.249014050714, 8443.83112337756, 8639.52482613456, 8700.129987001312, 8711.259542938858, 8723.956380218058, 5341.4658534146665, 5437.075697131497, 5443.319585051869, 5454.61817672763, 5474.0, 3428.674275943781, 3446.9655303446893, 3526.717862571014, 3548.8935331940083, 3573.5711714594154, 916.8160755702761, 1044.6030508406832, 1044.9895501044969, 1072.3030030480172, 1086.3101930274333, 11566.768664626661, 11620.883791162149, 11622.767544649145, 11628.76742465146, 11630.302181869114, 4209.831606735757, 4567.817287308537, 4943.72809495474, 4961.900761984813, 4968.27959945813, 9046.140616641409, 9052.366334356664, 9133.995260521337, 9136.908630913606, 9136.908630913738, 5298.814541491107, 5312.442193569696, 5338.46615338463, 5343.251944727741, 5345.33183352081, 3711.925761484755, 4443.755593442392, 4566.543345665407, 4640.86077417678, 4992.600591952635, 3585.229175727231, 3872.8063596820048, 3889.105505733697, 3891.338472459632, 3924.9607503925167, 1214.6760031565636, 1240.9317487538272, 1244.7386048929645, 1248.151257145132, 1261.1550261324935, 913.7852604637905, 921.2491819167357, 924.56356623056, 928.5218112671979, 960.4141473701037, 5609.915851262234, 5738.512226460798, 5833.766649333979, 5841.707914604252, 5945.732442040118, 891.2157301574573, 902.7066203483894, 939.248601119102, 977.3354119198871, 981.9312648114707, 3526.806025668613, 3746.6815320698047, 3791.3933770596573, 3906.8632597858937, 3908.8241029153755, 10299.279050466395, 10324.48377581133, 10568.365898046142, 10663.946680266548, 10810.135189184846, 10517.211209159344, 10575.312604680672, 10591.57633694659, 10629.309094908805, 10752.677419677422, 8122.959385203154, 8260.463069900557, 8309.961254843145, 8339.583020848933, 8448.83102337962, 5462.20797984298, 5511.283533140666, 5565.693886836101, 5571.9603287616155, 5655.971720141373, 9448.858267126136, 9519.857202142111, 9634.855477167988, 9711.5144242789, 9713.611455541699, 9059.36584439083, 9382.202512786216, 9509.191718703987, 9828.459434731158, 9851.950740246253, 6649.102371179829, 6846.246912839484, 7295.598742069024, 7306.890396644161, 7388.963055184905, 7834.960825195838, 8030.478018928751, 8033.317168040782, 8043.316318113026, 8058.717944871902, 7055.823604409931, 7205.135383753774, 7205.747798827016, 7301.123865136217, 7376.7786966391095, 7094.503384763017, 7118.822029449305, 7184.568925864465, 7237.0, 7256.45576581769, 6695.900743822649, 6873.072135261676, 6908.171019477695, 6909.032735416945, 6940.02839602446, 4192.4130621712375, 4744.762761861962, 4762.857114286577, 4869.293952376956, 4894.412670479565, 4822.903541929212, 4841.031793641216, 4882.902341953212, 4902.656814022984, 5059.443461219336], "value": 4535.224357577655, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.56700083875876, 321.09451347200894, 323.04217993648825, 329.8087109476503, 330.7304546794364, 5839.386864379243, 6127.417895299951, 6489.415952564274, 6598.340165983405, 6611.107500487433, 6460.095586617877, 6473.773417930373, 6507.583518652709, 6517.859374609448, 6524.445422139124, 4581.060882519084, 4623.722576645395, 4701.764911754399, 4740.6918550294295, 4755.3104799804005, 4130.215259100776, 4569.360289559464, 4660.86017419478, 4718.339432479455, 4747.691400059002, 4695.624350051988, 4704.764761761915, 4708.434987801452, 4711.151992641323, 4742.122707299143, 4009.799510024516, 4504.414426124599, 4583.12920545097, 4651.558017014702, 4709.529047095263, 4049.574794646549, 4066.674666026711, 4077.7145599808027, 4088.693347998912, 4097.364908439196, 6143.232095988002, 6220.720067596923, 6231.6572588507615, 6231.844203894893, 6239.095331176999, 5576.107822748381, 5595.160725891149, 5597.104463285896, 5597.132444471114, 5611.410801865827, 6886.24251332353, 6897.9655101724675, 6901.930980690179, 6904.275051119661, 6915.170179578483, 4284.957150428487, 4439.644828413751, 4485.977570112162, 4498.212812757754, 4510.954890451087, 6477.3846484584365, 7262.0, 7265.89101163483, 7277.0, 7305.634718264066, 4534.818607255739, 4552.11233809404, 4561.885952851205, 4570.565796249351, 4576.359309696645, 4598.172328980749, 4601.539846015438, 4604.631629469634, 4611.308303754397, 4615.330777037311, 5451.209574611659, 5454.972725136349, 5458.754356053987, 5460.645058071292, 5469.0, 6622.106015687917, 6905.930940690529, 6922.965385173042, 6924.23833378328, 6950.513464057569, 1573.6301969037268, 1586.8333824948445, 1597.8561929426176, 1603.2464741571443, 1607.6784643071433, 943.7923656795494, 957.5690939077303, 993.3940296419179, 999.9650012249537, 1018.5976539266948, 5753.280839895013, 6296.898042842484, 6388.488920886318, 6524.706388212542, 6537.117489139, 7294.9270507295305, 7296.854062918712, 7297.124345078624, 7301.744438944717, 7304.379127774092, 5912.556558258148, 6051.183090282843, 6501.2198536175965, 6536.673166341759, 6543.967280163569, 3800.2779471900803, 3827.6937844972335, 3829.942550861739, 3833.4824798651825, 3844.615538446189, 7076.575405475688, 7492.138404083491, 7497.325240728361, 7508.2867127622785, 7525.247475252431, 7368.521046131984, 7418.295261950106, 7426.517276377018, 7451.44114191427, 7482.139553951257, 7442.441816863865, 7452.180260171268, 7466.141393739681, 7486.4759466836795, 7491.8501629967095, 6123.601965872204, 6363.840903977438, 6855.897161542481, 7173.767477188484, 7207.099112610924, 7086.539374940612, 7101.644917753988, 7108.253633368422, 7139.071920650283, 7139.500234983501, 6131.325554188947, 7441.181470038399, 7467.925320746723, 7475.215102414386, 7499.737509187153, 4549.27211646135, 4554.476235232991, 4554.726716397026, 4560.361549383023, 4560.703554268962, 7157.57054576727, 7168.677409516688, 7168.784936451915, 7171.354578087997, 7190.532615379983, 6439.195100612424, 6804.0, 6811.352921472452, 6849.452043836382, 6861.828454288683, 6706.5640733352175, 6756.358145976124, 6770.01834733951, 6788.796336109925, 6801.557898736764, 5479.506844384025, 6599.868002640016, 6600.80197594073, 6610.07458955737, 6625.569337993208, 6636.045727699926, 6801.081853949653, 6817.522773405814, 6831.9658401707675, 6832.624205668736, 6433.453156481564, 6573.539852210491, 6625.503087268377, 6634.369734875179, 6649.667516624053], "value": 5006.86012573685, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [701.5124488480506, 750.2534977697192, 757.8105473631592, 759.510115975196, 778.8403377307652, 14742.631434214136, 14947.551573452787, 15113.924430377847, 15136.243187840606, 15190.240487975612, 13799.0, 14015.229162396065, 14018.859811401908, 14040.64898377541, 14045.78931316031, 723.5984028864004, 737.9926200738005, 901.3465237702678, 924.8936372317203, 926.4533924984258, 714.8641758065977, 727.8325985023448, 736.9189389167187, 901.1664210605164, 915.3180880244195, 950.7908260182749, 955.8375076237056, 956.9808154315632, 957.8994205608416, 970.776721354089, 946.564580293066, 946.8627049077879, 947.9573419196153, 949.544218774985, 965.0831709875611, 559.3399788249864, 712.975045873392, 714.4855703893188, 717.5622870049266, 720.103471178381, 14630.707385852224, 14640.194789286585, 14648.853511464855, 14673.70652586953, 14693.338799754038, 12498.562550310786, 12597.433115509824, 12694.174878632859, 12712.237265763992, 13162.473501060042, 9746.415215087118, 9861.260405469618, 9862.161716254128, 9985.800283994353, 9999.750006249902, 3434.4676575130643, 3463.1861512544533, 3582.6417358264225, 3625.818709064536, 3628.9455658165148, 62984.0, 79939.20060799434, 81794.0, 81984.59007704983, 82049.58975205146, 9202.40184388026, 9337.01961294068, 9425.575849086808, 9533.475658838692, 9580.37727547721, 21919.890400547894, 22019.559608807733, 22073.558528829653, 22096.33710988673, 22106.005229764698, 7431.2197219292275, 7432.739854105081, 7470.103587569526, 7470.4770666052855, 7482.513636613603, 4006.719529632898, 4063.2076745034433, 4073.837046518165, 4189.4344263524645, 4258.1483703258855, 3675.1363429594026, 3741.551013878352, 3743.0455233915177, 3754.4368344748505, 3757.304898593769, 630.9936900630935, 651.9869602607921, 657.4970147836908, 661.0447902780533, 681.2200030964617, 541.1693051166441, 543.4321134414546, 569.2883895131087, 605.1950905295946, 617.2870334763355, 7665.61671916402, 8046.557439340892, 8062.7581172564915, 8119.228673276029, 8502.14978502145, 516.2978349444752, 532.4010488200774, 592.9623159470926, 613.1599708399522, 620.798240571816, 3619.5475565554307, 3768.2275133597545, 3783.9621603783607, 3824.4454554090057, 3851.2297540491454, 89740.0, 90783.0, 90820.54589727009, 90927.09072909318, 94231.0576894236, 77752.0, 78297.0, 79007.60496197482, 79180.0, 80326.59836700778, 67501.98747018799, 67595.66202168958, 68340.6582967092, 68458.0, 69076.0, 7400.740974065882, 7556.697732090654, 7582.582957937367, 7632.427567932538, 7723.575203363869, 48288.758556206994, 49725.75137124291, 50361.244581330575, 50444.49555504398, 51052.48947510626, 65040.34959650343, 67934.0, 68129.65935170293, 68370.0, 69859.0, 9768.023197680175, 10012.04885535873, 10184.643537476153, 10192.28653994243, 10377.636782712569, 9714.271429642946, 10545.420001899969, 10558.788824223318, 10990.065844403316, 11049.171312151782, 10117.645882394081, 10138.391696498235, 10225.822312058779, 10334.689959301233, 10397.688069357931, 9636.084571965652, 9727.0, 9752.512374381395, 10095.697129086138, 10275.383476991405, 9499.335046546952, 9556.713298601053, 9689.757756056155, 9795.461249631053, 9951.402915825074, 8820.294376449998, 8830.696708213496, 8942.418742781698, 8983.101689830964, 9003.774905627411, 8297.128801475757, 8729.65081396737, 8795.736127916172, 8864.42381245217, 8979.191872731486], "value": 6702.082552961218, "unit": "score"}], "name": "dromaeo_css", "value": 7411.882655092624, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 116.0, 120.0, 114.0, 113.0, 113.0, 115.0, 116.0, 114.0, 132.0], "value": 116.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 122.0, 156.0, 159.0, 117.0, 147.0, 166.0, 142.0, 161.0, 143.0], "value": 147.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 189.0, 187.0, 184.0, 187.0, 187.0, 184.0, 188.0, 187.0, 186.0], "value": 186.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 79.0, 73.0, 152.0, 56.0, 56.0, 57.0, 63.0, 134.0, 55.0], "value": 79.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 146.0, 78.0, 82.0, 79.0, 121.0, 77.0, 103.0, 159.0], "value": 102.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 90.0, 87.0, 89.0, 89.0, 135.0, 87.0, 89.0, 86.0], "value": 92.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 116.0, 95.0, 108.0, 149.0, 104.0, 95.0, 94.0, 94.0, 94.0], "value": 105.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 86.0, 84.0, 83.0, 85.0, 86.0, 85.0, 84.0, 85.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 63.0, 63.0, 62.0, 62.0, 66.0, 64.0, 82.0, 59.0, 58.0], "value": 63.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 65.0, 76.0, 78.0, 75.0, 71.0, 67.0, 69.0, 67.0], "value": 69.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 78.0, 81.0, 81.0, 81.0, 81.0, 82.0, 81.0, 83.0, 83.0], "value": 81.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 130.0, 127.0, 128.0, 126.0, 127.0, 129.0, 136.0, 146.0, 159.0], "value": 133.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 167.0, 165.0, 170.0, 168.0, 166.0, 164.0, 168.0, 165.0, 162.0], "value": 167.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 67.0, 68.0, 69.0, 68.0, 67.0, 69.0, 67.0, 69.0], "value": 67.8, "unit": "score"}], "name": "kraken", "value": 1499.4999999999998, "alertThreshold": 2.0}]} 22:30:39 INFO - Return code: 0 22:30:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:30:39 ERROR - # TBPL SUCCESS # 22:30:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:30:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:30:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:30:39 INFO - 2016-05-11 22:30:39.728 screenresolution[1675:10052] starting screenresolution argv=screenresolution get 22:30:39 INFO - 2016-05-11 22:30:39.730 screenresolution[1675:10052] Display 0: 1600x1200x32@60 22:30:39 INFO - 2016-05-11 22:30:39.736 screenresolution[1676:10055] starting screenresolution argv=screenresolution list 22:30:39 INFO - Available Modes on Display 0 22:30:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:30:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:30:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:30:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:30:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:30:39 INFO - Intel Iris: 22:30:39 INFO - Chipset Model: Intel Iris 22:30:39 INFO - Type: GPU 22:30:39 INFO - Bus: Built-In 22:30:39 INFO - VRAM (Dynamic, Max): 1536 MB 22:30:39 INFO - Vendor: Intel (0x8086) 22:30:39 INFO - Device ID: 0x0a2e 22:30:39 INFO - Revision ID: 0x0009 22:30:39 INFO - Displays: 22:30:39 INFO - Display: 22:30:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:30:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:30:39 INFO - Display Serial Number: 1600x1200 60 22:30:39 INFO - Main Display: Yes 22:30:39 INFO - Mirror: Off 22:30:39 INFO - Online: Yes 22:30:39 INFO - Rotation: Supported 22:30:39 INFO - Return code: 0 22:30:39 INFO - Running post-action listener: _resource_record_post_action 22:30:39 INFO - Running post-run listener: _resource_record_post_run 22:30:41 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 37750272; Write bytes: 657275904; Read time: 394; Write time: 1875 22:30:41 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 270336; Write bytes: 355756032; Read time: 6; Write time: 697 22:30:41 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 35935744; Write bytes: 296463872; Read time: 375; Write time: 1102 22:30:41 INFO - Running post-run listener: _upload_blobber_files 22:30:41 INFO - Blob upload gear active. 22:30:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:30:41 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 22:30:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:30:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:30:41 INFO - (blobuploader) - INFO - Open directory for files ... 22:30:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:30:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:30:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:30:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:30:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:30:42 INFO - (blobuploader) - INFO - Done attempting. 22:30:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:30:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:30:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:30:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:30:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:30:43 INFO - (blobuploader) - INFO - Done attempting. 22:30:43 INFO - (blobuploader) - INFO - Iteration through files over. 22:30:43 INFO - Return code: 0 22:30:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:30:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:30:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9e157d2167a41b3a3f15f5fe902b5a6ff60186a72eb9feb7a4c6bf3e48d8e95fc23283b9b0bf79ea39ec94e29588dc97c0f7c4416d93b66ddf78b66271df8e22", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3cc572d683e03c52ec3ccaeba47a6d16a99823649530d1112c4e26f24d3670adeda38ea6543bfdefbd8a9db414e91f28be5c0188300d8383617aa037c6aadfe4"} 22:30:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:30:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:30:43 INFO - Contents: 22:30:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9e157d2167a41b3a3f15f5fe902b5a6ff60186a72eb9feb7a4c6bf3e48d8e95fc23283b9b0bf79ea39ec94e29588dc97c0f7c4416d93b66ddf78b66271df8e22", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3cc572d683e03c52ec3ccaeba47a6d16a99823649530d1112c4e26f24d3670adeda38ea6543bfdefbd8a9db414e91f28be5c0188300d8383617aa037c6aadfe4"} 22:30:43 INFO - Running post-run listener: copy_logs_to_upload_dir 22:30:43 INFO - Copying logs to upload dir... 22:30:43 INFO - mkdir: /builds/slave/test/build/upload/logs 22:30:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.465475 ========= master_lag: 3031.76 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 1 hrs, 11 mins, 39 secs) (at 2016-05-11 23:21:15.269574) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 23:21:15.274439) ========= 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.qPvj4W3Qs1/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.QYX4BDVmNM/Listeners TMPDIR=/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9e157d2167a41b3a3f15f5fe902b5a6ff60186a72eb9feb7a4c6bf3e48d8e95fc23283b9b0bf79ea39ec94e29588dc97c0f7c4416d93b66ddf78b66271df8e22", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3cc572d683e03c52ec3ccaeba47a6d16a99823649530d1112c4e26f24d3670adeda38ea6543bfdefbd8a9db414e91f28be5c0188300d8383617aa037c6aadfe4"} build_url:https://queue.taskcluster.net/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011070 build_url: 'https://queue.taskcluster.net/v1/task/Za43laKxTBmQNedcRvSEhg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9e157d2167a41b3a3f15f5fe902b5a6ff60186a72eb9feb7a4c6bf3e48d8e95fc23283b9b0bf79ea39ec94e29588dc97c0f7c4416d93b66ddf78b66271df8e22", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3cc572d683e03c52ec3ccaeba47a6d16a99823649530d1112c4e26f24d3670adeda38ea6543bfdefbd8a9db414e91f28be5c0188300d8383617aa037c6aadfe4"}' ========= master_lag: 0.45 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 23:21:15.738339) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 8 secs) (at 2016-05-11 23:21:15.738672) ========= 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.qPvj4W3Qs1/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.QYX4BDVmNM/Listeners TMPDIR=/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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.005381 ========= master_lag: 8.98 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 8 secs) (at 2016-05-11 23:21:24.728881) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 23:21:24.729173) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 23:21:25.050861) ========= ========= Total master_lag: 3041.26 =========