builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0247 starttime: 1465220874.55 results: success (0) buildid: 20160606010529 builduid: 78750d9664274d05bee96204469a3a74 revision: d8adb38dd576398dacbfb96d2c247f8e0158bdf1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:54.554056) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:54.554527) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:54.554823) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:54.579251) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:54.579649) ========= 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.PrkwqTAZDH/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.N6DCQcjahu/Listeners TMPDIR=/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-06-06 06:47:54-- 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% 16.9M=0.001s 2016-06-06 06:47:54 (16.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.403470 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:55.004207) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:55.004520) ========= 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.PrkwqTAZDH/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.N6DCQcjahu/Listeners TMPDIR=/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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.025717 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:55.052666) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:55.053055) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d8adb38dd576398dacbfb96d2c247f8e0158bdf1 --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 d8adb38dd576398dacbfb96d2c247f8e0158bdf1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PrkwqTAZDH/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.N6DCQcjahu/Listeners TMPDIR=/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-06-06 06:47:55,137 truncating revision to first 12 chars 2016-06-06 06:47:55,137 Setting DEBUG logging. 2016-06-06 06:47:55,137 attempt 1/10 2016-06-06 06:47:55,137 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d8adb38dd576?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-06 06:47:55,830 unpacking tar archive at: mozilla-aurora-d8adb38dd576/testing/mozharness/ program finished with exit code 0 elapsedTime=0.963089 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:56.037588) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:56.037941) ========= script_repo_revision: d8adb38dd576398dacbfb96d2c247f8e0158bdf1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:56.038336) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:56.038626) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:56.056195) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-06-06 06:47:56.056622) ========= /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.PrkwqTAZDH/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.N6DCQcjahu/Listeners TMPDIR=/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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 06:47:56 INFO - MultiFileLogger online at 20160606 06:47:56 in /builds/slave/test 06:47:56 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 06:47:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:47:56 INFO - {'append_to_log': False, 06:47:56 INFO - 'base_work_dir': '/builds/slave/test', 06:47:56 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 06:47:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:47:56 INFO - 'branch': 'Mozilla-Aurora', 06:47:56 INFO - 'buildbot_json_path': 'buildprops.json', 06:47:56 INFO - 'config_files': ('talos/mac_config.py',), 06:47:56 INFO - 'default_actions': ('clobber', 06:47:56 INFO - 'read-buildbot-config', 06:47:56 INFO - 'download-and-extract', 06:47:56 INFO - 'populate-webroot', 06:47:56 INFO - 'create-virtualenv', 06:47:56 INFO - 'install', 06:47:56 INFO - 'run-tests'), 06:47:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:47:56 INFO - 'download_minidump_stackwalk': True, 06:47:56 INFO - 'download_symbols': 'ondemand', 06:47:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:47:56 INFO - 'tooltool.py': '/tools/tooltool.py', 06:47:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:47:56 INFO - '/tools/misc-python/virtualenv.py')}, 06:47:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:47:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:47:56 INFO - 'installer_path': 'installer.exe', 06:47:56 INFO - 'log_level': 'info', 06:47:56 INFO - 'log_name': 'talos', 06:47:56 INFO - 'log_to_console': True, 06:47:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:47:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:47:56 INFO - 'opt_config_files': (), 06:47:56 INFO - 'pip_index': False, 06:47:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:47:56 INFO - 'cmd': ('bash', 06:47:56 INFO - '-c', 06:47:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:47:56 INFO - 'enabled': True, 06:47:56 INFO - 'halt_on_failure': False, 06:47:56 INFO - 'name': 'check_screen_resolution'},), 06:47:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:47:56 INFO - 'cmd': ('bash', 06:47:56 INFO - '-c', 06:47:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:47:56 INFO - 'enabled': True, 06:47:56 INFO - 'halt_on_failure': False, 06:47:56 INFO - 'name': 'check_screen_resolution'},), 06:47:56 INFO - 'run_cmd_checks_enabled': True, 06:47:56 INFO - 'sps_profile': False, 06:47:56 INFO - 'sps_profile_interval': 0, 06:47:56 INFO - 'suite': 'dromaeojs', 06:47:56 INFO - 'system_bits': '32', 06:47:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:47:56 INFO - 'title': 't-yosemite-r7-0247', 06:47:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:47:56 INFO - 'use_talos_json': True, 06:47:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:47:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:47:56 INFO - 'work_dir': 'build'} 06:47:56 INFO - ##### 06:47:56 INFO - ##### Running clobber step. 06:47:56 INFO - ##### 06:47:56 INFO - Running pre-action listener: _resource_record_pre_action 06:47:56 INFO - Running main action method: clobber 06:47:56 INFO - rmtree: /builds/slave/test/build 06:47:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:47:58 INFO - Running post-action listener: _resource_record_post_action 06:47:58 INFO - ##### 06:47:58 INFO - ##### Running read-buildbot-config step. 06:47:58 INFO - ##### 06:47:58 INFO - Running pre-action listener: _resource_record_pre_action 06:47:58 INFO - Running main action method: read_buildbot_config 06:47:58 INFO - Using buildbot properties: 06:47:58 INFO - { 06:47:58 INFO - "project": "", 06:47:58 INFO - "product": "firefox", 06:47:58 INFO - "script_repo_revision": "production", 06:47:58 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 06:47:58 INFO - "repository": "", 06:47:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 06:47:58 INFO - "buildid": "20160606010529", 06:47:58 INFO - "pgo_build": "False", 06:47:58 INFO - "basedir": "/builds/slave/test", 06:47:58 INFO - "buildnumber": 372, 06:47:58 INFO - "slavename": "t-yosemite-r7-0247", 06:47:58 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 06:47:58 INFO - "platform": "yosemite_r7", 06:47:58 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 06:47:58 INFO - "branch": "mozilla-aurora", 06:47:58 INFO - "repo_path": "releases/mozilla-aurora", 06:47:58 INFO - "revision": "d8adb38dd576398dacbfb96d2c247f8e0158bdf1", 06:47:58 INFO - "stage_platform": "macosx64", 06:47:58 INFO - "builduid": "78750d9664274d05bee96204469a3a74", 06:47:58 INFO - "slavebuilddir": "test" 06:47:58 INFO - } 06:47:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 06:47:58 INFO - Running post-action listener: _resource_record_post_action 06:47:58 INFO - ##### 06:47:58 INFO - ##### Running download-and-extract step. 06:47:58 INFO - ##### 06:47:58 INFO - Running pre-action listener: _resource_record_pre_action 06:47:58 INFO - Running main action method: download_and_extract 06:47:58 INFO - mkdir: /builds/slave/test/build/tests 06:47:58 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')]} 06:47:58 INFO - https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 06:47:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 06:47:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 06:47:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/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 06:47:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/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 06:47:59 INFO - Downloaded 1199 bytes. 06:47:59 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 06:47:59 INFO - Using the following test package requirements: 06:47:59 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 06:47:59 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:47:59 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 06:47:59 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:47:59 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 06:47:59 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:47:59 INFO - u'jsshell-mac.zip'], 06:47:59 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:47:59 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 06:47:59 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 06:47:59 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:47:59 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 06:47:59 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:47:59 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 06:47:59 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:47:59 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 06:47:59 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:47:59 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 06:47:59 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 06:47:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:47:59 INFO - https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:47:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 06:47:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 06:47:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/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 06:47:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/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 06:48:01 INFO - Downloaded 18466859 bytes. 06:48:01 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'] 06:48:01 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 06:48:04 INFO - Return code: 0 06:48:04 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 06:48: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')]} 06:48:04 INFO - https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:48:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 06:48:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 06:48:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/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 06:48:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/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 06:48:05 INFO - Downloaded 18466859 bytes. 06:48:05 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'] 06:48:05 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 06:48:09 INFO - Return code: 0 06:48: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')]} 06:48:09 INFO - https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 06:48:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 06:48:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 06:48:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/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 06:48:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/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 06:48:10 INFO - Downloaded 10840145 bytes. 06:48:10 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'] 06:48:10 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 06:48:10 INFO - Return code: 0 06:48:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:10 INFO - https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 06:48:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 06:48:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 06:48:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 06:48:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/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 06:48:16 INFO - Downloaded 114179823 bytes. 06:48:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 06:48:16 INFO - mkdir: /builds/slave/test/properties 06:48:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:48:16 INFO - Writing to file /builds/slave/test/properties/build_url 06:48:16 INFO - Contents: 06:48:16 INFO - build_url:https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 06:48:18 INFO - Running post-action listener: _resource_record_post_action 06:48:18 INFO - Running post-action listener: set_extra_try_arguments 06:48:18 INFO - ##### 06:48:18 INFO - ##### Running populate-webroot step. 06:48:18 INFO - ##### 06:48:18 INFO - Running pre-action listener: _resource_record_pre_action 06:48:18 INFO - Running main action method: populate_webroot 06:48:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:48:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:48:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:48:18 INFO - '--tppagecycles', 06:48:18 INFO - '7'], 06:48:18 INFO - 'tests': ['tsvgm']}}, 06:48:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:48:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:48:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:48:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:48:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:48:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:48:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:48:18 INFO - 'talos_options': ['--disable-e10s'], 06:48:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:48:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:48:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:48:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:48:18 INFO - 'talos_options': ['--disable-e10s'], 06:48:18 INFO - 'tests': ['damp', 'tps']}, 06:48:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:48:18 INFO - 'tests': ['damp', 'tps']}, 06:48:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:48:18 INFO - 'tests': ['dromaeo_dom']}, 06:48:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:48:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:48:18 INFO - 'tests': ['a11yr', 06:48:18 INFO - 'ts_paint', 06:48:18 INFO - 'tpaint', 06:48:18 INFO - 'sessionrestore', 06:48:18 INFO - 'sessionrestore_no_auto_restore', 06:48:18 INFO - 'tabpaint']}, 06:48:18 INFO - 'other-e10s': {'tests': ['a11yr', 06:48:18 INFO - 'ts_paint', 06:48:18 INFO - 'tpaint', 06:48:18 INFO - 'sessionrestore', 06:48:18 INFO - 'sessionrestore_no_auto_restore', 06:48:18 INFO - 'tabpaint']}, 06:48:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:48:18 INFO - 'ts_paint', 06:48:18 INFO - 'tpaint', 06:48:18 INFO - 'sessionrestore', 06:48:18 INFO - 'sessionrestore_no_auto_restore', 06:48:18 INFO - 'tabpaint']}, 06:48:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:48:18 INFO - 'ts_paint', 06:48:18 INFO - 'tpaint', 06:48:18 INFO - 'sessionrestore', 06:48:18 INFO - 'sessionrestore_no_auto_restore', 06:48:18 INFO - 'tabpaint']}, 06:48:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:48:18 INFO - 'tests': ['a11yr', 06:48:18 INFO - 'ts_paint', 06:48:18 INFO - 'tpaint', 06:48:18 INFO - 'sessionrestore', 06:48:18 INFO - 'sessionrestore_no_auto_restore', 06:48:18 INFO - 'tabpaint']}, 06:48:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:48:18 INFO - 'tests': ['a11yr', 06:48:18 INFO - 'ts_paint', 06:48:18 INFO - 'tpaint', 06:48:18 INFO - 'sessionrestore', 06:48:18 INFO - 'sessionrestore_no_auto_restore', 06:48:18 INFO - 'tabpaint']}, 06:48:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:48:18 INFO - 'tests': ['tsvgx', 06:48:18 INFO - 'tsvgr_opacity', 06:48:18 INFO - 'tart', 06:48:18 INFO - 'tscrollx', 06:48:18 INFO - 'cart']}, 06:48:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:48:18 INFO - 'tsvgr_opacity', 06:48:18 INFO - 'tart', 06:48:18 INFO - 'tscrollx', 06:48:18 INFO - 'cart']}, 06:48:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:48:18 INFO - 'talos_options': ['--disable-e10s'], 06:48:18 INFO - 'tests': ['tp5o']}, 06:48:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:48:18 INFO - 'tests': ['tp5o']}, 06:48:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:48:18 INFO - 'talos_options': ['--disable-e10s', 06:48:18 INFO - '--xperf_path', 06:48:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:48:18 INFO - 'tests': ['tp5n']}, 06:48:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:48:18 INFO - 'talos_options': ['--xperf_path', 06:48:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:48:18 INFO - 'tests': ['tp5n']}}, 06:48:18 INFO - 'talos.zip': {'path': '', 06:48:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:48:18 INFO - Running post-action listener: _resource_record_post_action 06:48:18 INFO - ##### 06:48:18 INFO - ##### Running create-virtualenv step. 06:48:18 INFO - ##### 06:48:18 INFO - Running pre-action listener: _resource_record_pre_action 06:48:18 INFO - Running main action method: create_virtualenv 06:48:18 INFO - Creating virtualenv /builds/slave/test/build/venv 06:48:18 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 06:48:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:48:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:48:18 INFO - Using real prefix '/tools/python27' 06:48:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:48:18 INFO - Installing distribute.............................................................................................................................................................................................done. 06:48:21 INFO - Installing pip.................done. 06:48:21 INFO - Return code: 0 06:48:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:48:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:21 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 0x10420ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10421e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdf3d740c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040ade00>, '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 0x103f16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040a8b20>, '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.PrkwqTAZDH/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.N6DCQcjahu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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 06:48:21 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 06:48:21 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 06:48:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PrkwqTAZDH/Render', 06:48:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:21 INFO - 'HOME': '/Users/cltbld', 06:48:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:21 INFO - 'LOGNAME': 'cltbld', 06:48:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:21 INFO - 'MOZ_NO_REMOTE': '1', 06:48:21 INFO - 'NO_EM_RESTART': '1', 06:48:21 INFO - 'PAGER': '/bin/cat', 06:48:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:21 INFO - 'PWD': '/builds/slave/test', 06:48:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:21 INFO - 'SHELL': '/bin/bash', 06:48:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N6DCQcjahu/Listeners', 06:48:21 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 06:48:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:21 INFO - 'USER': 'cltbld', 06:48:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:21 INFO - 'XPC_FLAGS': '0x0', 06:48:21 INFO - 'XPC_SERVICE_NAME': '0', 06:48:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:22 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 06:48:22 INFO - Cleaning up... 06:48:22 INFO - Return code: 0 06:48:22 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 06:48:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:22 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 0x10420ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10421e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdf3d740c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040ade00>, '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 0x103f16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040a8b20>, '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.PrkwqTAZDH/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.N6DCQcjahu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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 06:48:22 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 06:48:22 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 06:48:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PrkwqTAZDH/Render', 06:48:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:22 INFO - 'HOME': '/Users/cltbld', 06:48:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:22 INFO - 'LOGNAME': 'cltbld', 06:48:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:22 INFO - 'MOZ_NO_REMOTE': '1', 06:48:22 INFO - 'NO_EM_RESTART': '1', 06:48:22 INFO - 'PAGER': '/bin/cat', 06:48:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:22 INFO - 'PWD': '/builds/slave/test', 06:48:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:22 INFO - 'SHELL': '/bin/bash', 06:48:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N6DCQcjahu/Listeners', 06:48:22 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 06:48:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:22 INFO - 'USER': 'cltbld', 06:48:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:22 INFO - 'XPC_FLAGS': '0x0', 06:48:22 INFO - 'XPC_SERVICE_NAME': '0', 06:48:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:22 INFO - Downloading/unpacking psutil>=0.7.1 06:48:22 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 06:48:22 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 06:48:22 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 06:48:22 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 06:48:22 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 06:48:22 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 06:48:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:48:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:48:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:48:25 INFO - Installing collected packages: psutil 06:48:25 INFO - Running setup.py install for psutil 06:48:25 INFO - building 'psutil._psutil_osx' extension 06:48:25 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 06:48:26 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 06:48:26 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 06:48:26 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 06:48:26 INFO - building 'psutil._psutil_posix' extension 06:48:26 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 06:48:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:48:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:48:26 INFO - ^ 06:48:26 INFO - 1 warning generated. 06:48:26 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 06:48:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:48:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:48:26 INFO - Successfully installed psutil 06:48:26 INFO - Cleaning up... 06:48:26 INFO - Return code: 0 06:48:26 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 06:48:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10420ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10421e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdf3d740c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040ade00>, '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 0x103f16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040a8b20>, '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.PrkwqTAZDH/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.N6DCQcjahu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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 06:48:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 06:48:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 06:48:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PrkwqTAZDH/Render', 06:48:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:26 INFO - 'HOME': '/Users/cltbld', 06:48:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:26 INFO - 'LOGNAME': 'cltbld', 06:48:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:26 INFO - 'MOZ_NO_REMOTE': '1', 06:48:26 INFO - 'NO_EM_RESTART': '1', 06:48:26 INFO - 'PAGER': '/bin/cat', 06:48:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:26 INFO - 'PWD': '/builds/slave/test', 06:48:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:26 INFO - 'SHELL': '/bin/bash', 06:48:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N6DCQcjahu/Listeners', 06:48:26 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 06:48:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:26 INFO - 'USER': 'cltbld', 06:48:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:26 INFO - 'XPC_FLAGS': '0x0', 06:48:26 INFO - 'XPC_SERVICE_NAME': '0', 06:48:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:26 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 06:48: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 06:48: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 06:48: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 06:48: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 06:48: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 06:48: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 06:48:29 INFO - Downloading mozsystemmonitor-0.0.tar.gz 06:48:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:48:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 06:48:29 INFO - Installing collected packages: mozsystemmonitor 06:48:29 INFO - Running setup.py install for mozsystemmonitor 06:48:29 INFO - Successfully installed mozsystemmonitor 06:48:29 INFO - Cleaning up... 06:48:29 INFO - Return code: 0 06:48:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:48:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:29 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 0x10420ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10421e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdf3d740c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040ade00>, '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 0x103f16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040a8b20>, '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.PrkwqTAZDH/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.N6DCQcjahu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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 06:48:29 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 06:48:29 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 06:48:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PrkwqTAZDH/Render', 06:48:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:29 INFO - 'HOME': '/Users/cltbld', 06:48:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:29 INFO - 'LOGNAME': 'cltbld', 06:48:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:29 INFO - 'MOZ_NO_REMOTE': '1', 06:48:29 INFO - 'NO_EM_RESTART': '1', 06:48:29 INFO - 'PAGER': '/bin/cat', 06:48:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:29 INFO - 'PWD': '/builds/slave/test', 06:48:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:29 INFO - 'SHELL': '/bin/bash', 06:48:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N6DCQcjahu/Listeners', 06:48:29 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 06:48:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:29 INFO - 'USER': 'cltbld', 06:48:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:29 INFO - 'XPC_FLAGS': '0x0', 06:48:29 INFO - 'XPC_SERVICE_NAME': '0', 06:48:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:29 INFO - Downloading/unpacking blobuploader==1.2.4 06:48:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:32 INFO - Downloading blobuploader-1.2.4.tar.gz 06:48:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:48:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:48:32 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 06:48:32 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 06:48:32 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 06:48:32 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 06:48:32 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 06:48:32 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 06:48:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:48:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:48:33 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 06:48:33 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 06:48:33 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 06:48:33 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 06:48:33 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 06:48:33 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 06:48:33 INFO - Downloading docopt-0.6.1.tar.gz 06:48:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:48:33 INFO - Installing collected packages: blobuploader, requests, docopt 06:48:33 INFO - Running setup.py install for blobuploader 06:48:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:48:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:48:33 INFO - Running setup.py install for requests 06:48:34 INFO - Running setup.py install for docopt 06:48:34 INFO - Successfully installed blobuploader requests docopt 06:48:34 INFO - Cleaning up... 06:48:34 INFO - Return code: 0 06:48:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:48:34 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')]} 06:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:34 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')]} 06:48:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:34 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 0x10420ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10421e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdf3d740c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040ade00>, '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 0x103f16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040a8b20>, '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.PrkwqTAZDH/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.N6DCQcjahu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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 06:48:34 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 06:48:34 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 06:48:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PrkwqTAZDH/Render', 06:48:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:34 INFO - 'HOME': '/Users/cltbld', 06:48:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:34 INFO - 'LOGNAME': 'cltbld', 06:48:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:34 INFO - 'MOZ_NO_REMOTE': '1', 06:48:34 INFO - 'NO_EM_RESTART': '1', 06:48:34 INFO - 'PAGER': '/bin/cat', 06:48:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:34 INFO - 'PWD': '/builds/slave/test', 06:48:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:34 INFO - 'SHELL': '/bin/bash', 06:48:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N6DCQcjahu/Listeners', 06:48:34 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 06:48:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:34 INFO - 'USER': 'cltbld', 06:48:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:34 INFO - 'XPC_FLAGS': '0x0', 06:48:34 INFO - 'XPC_SERVICE_NAME': '0', 06:48:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:48:34 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-kqGiVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:48:34 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-TyUS50-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:48:34 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-VZZ3oF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:48:35 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-rfgBiN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:48:35 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-U6Ol_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:48:35 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-wEq8Xg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:48:35 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-lJE5cQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:48:35 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-_xxHKe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:48:35 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-X57MH2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:48:35 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-qR5ChS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:48:35 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-5bjYUG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:48:35 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-dItAXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:48:35 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-nYuCAh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:48:36 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-gR4twn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:48:36 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-kK_3za-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:48:36 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-vOF18U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:48:36 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-aTj7Mn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:48:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:48:36 INFO - Running setup.py install for manifestparser 06:48:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:48:36 INFO - Running setup.py install for mozcrash 06:48:36 INFO - Running setup.py install for mozdebug 06:48:36 INFO - Running setup.py install for mozdevice 06:48:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:48:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:48:37 INFO - Running setup.py install for mozfile 06:48:37 INFO - Running setup.py install for mozhttpd 06:48:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:48:37 INFO - Running setup.py install for mozinfo 06:48:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:48:37 INFO - Running setup.py install for mozInstall 06:48:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:48:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:48:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:48:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:48:37 INFO - Running setup.py install for mozleak 06:48:37 INFO - Running setup.py install for mozlog 06:48:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:48:37 INFO - Running setup.py install for moznetwork 06:48:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:48:38 INFO - Running setup.py install for mozprocess 06:48:38 INFO - Running setup.py install for mozprofile 06:48:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:48:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:48:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:48:38 INFO - Running setup.py install for mozrunner 06:48:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:48:38 INFO - Running setup.py install for mozscreenshot 06:48:38 INFO - Running setup.py install for moztest 06:48:38 INFO - Running setup.py install for mozversion 06:48:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:48:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:48:39 INFO - Cleaning up... 06:48:39 INFO - Return code: 0 06:48:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:48: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')]} 06:48:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48: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')]} 06:48:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:39 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 0x10420ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10421e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdf3d740c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040ade00>, '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 0x103f16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040a8b20>, '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.PrkwqTAZDH/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.N6DCQcjahu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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 06:48:39 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 06:48:39 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 06:48:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PrkwqTAZDH/Render', 06:48:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:39 INFO - 'HOME': '/Users/cltbld', 06:48:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:39 INFO - 'LOGNAME': 'cltbld', 06:48:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:39 INFO - 'MOZ_NO_REMOTE': '1', 06:48:39 INFO - 'NO_EM_RESTART': '1', 06:48:39 INFO - 'PAGER': '/bin/cat', 06:48:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:39 INFO - 'PWD': '/builds/slave/test', 06:48:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:39 INFO - 'SHELL': '/bin/bash', 06:48:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N6DCQcjahu/Listeners', 06:48:39 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 06:48:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:39 INFO - 'USER': 'cltbld', 06:48:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:39 INFO - 'XPC_FLAGS': '0x0', 06:48:39 INFO - 'XPC_SERVICE_NAME': '0', 06:48:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:48:39 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-867diP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:48:39 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)) 06:48:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:48:39 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-CLlwm2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:48:39 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)) 06:48:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:48:39 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-Zl5VhZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:48:39 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)) 06:48:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:48:39 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-8SlNYj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:48:39 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)) 06:48:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:48:39 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-8HtM5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:48:39 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)) 06:48:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:48:39 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-Us0qeg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:48:39 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)) 06:48:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:48:39 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-jFO8Fl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:48:40 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)) 06:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:48:40 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-yUCuj1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:48:40 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)) 06:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:48:40 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-Z0A4dh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:48:40 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)) 06:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:48:40 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-TbDOH2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:48:40 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)) 06:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:48:40 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-NMAG7G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:48:40 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)) 06:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:48:40 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-u0eWXt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:48:40 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)) 06:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:48:40 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-gy5rbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:48:40 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)) 06:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:48:40 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-fBrGwk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:48:40 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)) 06:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:48:40 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-jBMOSy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:48:40 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)) 06:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:48:40 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-_GJN5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:48:40 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)) 06:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:48:40 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-ar2SKF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:48:41 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)) 06:48:41 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)) 06:48:41 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)) 06:48:41 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)) 06:48:41 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)) 06:48:41 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)) 06:48:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:48:41 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 06:48:41 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 06:48:41 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 06:48:41 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 06:48:41 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 06:48:41 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 06:48:43 INFO - Downloading blessings-1.6.tar.gz 06:48:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:48:43 INFO - Installing collected packages: blessings 06:48:43 INFO - Running setup.py install for blessings 06:48:43 INFO - Successfully installed blessings 06:48:43 INFO - Cleaning up... 06:48:43 INFO - Return code: 0 06:48:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:48:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:48:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:48:44 INFO - Reading from file tmpfile_stdout 06:48:44 INFO - Current package versions: 06:48:44 INFO - blessings == 1.6 06:48:44 INFO - blobuploader == 1.2.4 06:48:44 INFO - docopt == 0.6.1 06:48:44 INFO - manifestparser == 1.1 06:48:44 INFO - mozInstall == 1.12 06:48:44 INFO - mozcrash == 0.17 06:48:44 INFO - mozdebug == 0.1 06:48:44 INFO - mozdevice == 0.48 06:48:44 INFO - mozfile == 1.2 06:48:44 INFO - mozhttpd == 0.7 06:48:44 INFO - mozinfo == 0.9 06:48:44 INFO - mozleak == 0.1 06:48:44 INFO - mozlog == 3.1 06:48:44 INFO - moznetwork == 0.27 06:48:44 INFO - mozprocess == 0.22 06:48:44 INFO - mozprofile == 0.28 06:48:44 INFO - mozrunner == 6.11 06:48:44 INFO - mozscreenshot == 0.1 06:48:44 INFO - mozsystemmonitor == 0.0 06:48:44 INFO - moztest == 0.7 06:48:44 INFO - mozversion == 1.4 06:48:44 INFO - psutil == 3.1.1 06:48:44 INFO - requests == 1.2.3 06:48:44 INFO - wsgiref == 0.1.2 06:48:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:48: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')]} 06:48:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48: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')]} 06:48:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:44 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 0x10420ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10421e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdf3d740c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040ade00>, '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 0x103f16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040a8b20>, '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.PrkwqTAZDH/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.N6DCQcjahu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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 06:48:44 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 06:48:44 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 06:48:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PrkwqTAZDH/Render', 06:48:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:44 INFO - 'HOME': '/Users/cltbld', 06:48:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:44 INFO - 'LOGNAME': 'cltbld', 06:48:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:44 INFO - 'MOZ_NO_REMOTE': '1', 06:48:44 INFO - 'NO_EM_RESTART': '1', 06:48:44 INFO - 'PAGER': '/bin/cat', 06:48:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:44 INFO - 'PWD': '/builds/slave/test', 06:48:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:44 INFO - 'SHELL': '/bin/bash', 06:48:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N6DCQcjahu/Listeners', 06:48:44 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 06:48:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:44 INFO - 'USER': 'cltbld', 06:48:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:44 INFO - 'XPC_FLAGS': '0x0', 06:48:44 INFO - 'XPC_SERVICE_NAME': '0', 06:48:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:44 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)) 06:48:44 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)) 06:48:44 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)) 06:48:44 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)) 06:48:44 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)) 06:48:44 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)) 06:48:44 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)) 06:48:44 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)) 06:48:44 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)) 06:48:44 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)) 06:48:44 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)) 06:48:44 INFO - Cleaning up... 06:48:44 INFO - Return code: 0 06:48:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:48: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')]} 06:48:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48: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')]} 06:48:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10420ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10421e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdf3d740c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040ade00>, '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 0x103f16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040a8b20>, '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.PrkwqTAZDH/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.N6DCQcjahu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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 06:48:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:48:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:48:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PrkwqTAZDH/Render', 06:48:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:44 INFO - 'HOME': '/Users/cltbld', 06:48:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:44 INFO - 'LOGNAME': 'cltbld', 06:48:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:44 INFO - 'MOZ_NO_REMOTE': '1', 06:48:44 INFO - 'NO_EM_RESTART': '1', 06:48:44 INFO - 'PAGER': '/bin/cat', 06:48:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:44 INFO - 'PWD': '/builds/slave/test', 06:48:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:44 INFO - 'SHELL': '/bin/bash', 06:48:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N6DCQcjahu/Listeners', 06:48:44 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 06:48:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:44 INFO - 'USER': 'cltbld', 06:48:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:44 INFO - 'XPC_FLAGS': '0x0', 06:48:44 INFO - 'XPC_SERVICE_NAME': '0', 06:48:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:44 INFO - Downloading/unpacking jsonschema 06:48:44 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 06:48:44 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 06:48:44 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 06:48:44 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 06:48:44 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 06:48:44 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 06:48:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:48:47 INFO - Downloading/unpacking functools32 (from jsonschema) 06:48:47 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 06:48:47 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 06:48:47 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 06:48:47 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 06:48:47 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 06:48:47 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 06:48:47 INFO - Downloading functools32-3.2.3-2.tar.gz 06:48:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:48:47 INFO - warning: no files found matching '*.txt' 06:48:47 INFO - no previously-included directories found matching 'build' 06:48:47 INFO - no previously-included directories found matching 'dist' 06:48:47 INFO - no previously-included directories found matching '.git*' 06:48:47 INFO - Installing collected packages: jsonschema, functools32 06:48:47 INFO - Running setup.py install for functools32 06:48:47 INFO - warning: no files found matching '*.txt' 06:48:47 INFO - no previously-included directories found matching 'build' 06:48:47 INFO - no previously-included directories found matching 'dist' 06:48:47 INFO - no previously-included directories found matching '.git*' 06:48:47 INFO - Successfully installed jsonschema functools32 06:48:47 INFO - Cleaning up... 06:48:47 INFO - Return code: 0 06:48:47 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 06:48:47 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')]} 06:48:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:47 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')]} 06:48:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:47 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 0x10420ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10421e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdf3d740c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040ade00>, '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 0x103f16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040a8b20>, '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.PrkwqTAZDH/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.N6DCQcjahu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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 06:48:47 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 06:48:47 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 06:48:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PrkwqTAZDH/Render', 06:48:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:47 INFO - 'HOME': '/Users/cltbld', 06:48:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:47 INFO - 'LOGNAME': 'cltbld', 06:48:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:47 INFO - 'MOZ_NO_REMOTE': '1', 06:48:47 INFO - 'NO_EM_RESTART': '1', 06:48:47 INFO - 'PAGER': '/bin/cat', 06:48:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:47 INFO - 'PWD': '/builds/slave/test', 06:48:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:47 INFO - 'SHELL': '/bin/bash', 06:48:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N6DCQcjahu/Listeners', 06:48:47 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 06:48:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:47 INFO - 'USER': 'cltbld', 06:48:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:47 INFO - 'XPC_FLAGS': '0x0', 06:48:47 INFO - 'XPC_SERVICE_NAME': '0', 06:48:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:48 INFO - Downloading/unpacking flake8 06:48:48 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 06:48:48 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 06:48:48 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 06:48:48 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 06:48:48 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 06:48:48 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 06:48:50 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 06:48:50 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 06:48:50 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 06:48:50 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 06:48:50 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 06:48:50 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 06:48:50 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 06:48:50 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 06:48:50 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 06:48:50 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 06:48:50 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 06:48:50 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 06:48:50 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 06:48:50 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 06:48:50 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 06:48:50 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 06:48:51 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 06:48:51 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 06:48:51 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 06:48:51 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 06:48:51 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 06:48:51 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 06:48:51 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 06:48:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 06:48:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:48:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:48:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:48:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:48:51 INFO - no previously-included directories found matching 'docs/_build' 06:48:51 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 06:48:51 INFO - Running setup.py install for pep8 06:48:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:48:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:48:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:48:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:48:51 INFO - no previously-included directories found matching 'docs/_build' 06:48:52 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 06:48:52 INFO - Successfully installed flake8 mccabe pyflakes pep8 06:48:52 INFO - Cleaning up... 06:48:52 INFO - Return code: 0 06:48:52 INFO - Running post-action listener: _resource_record_post_action 06:48:52 INFO - Running post-action listener: _start_resource_monitoring 06:48:52 INFO - Starting resource monitoring. 06:48:52 INFO - ##### 06:48:52 INFO - ##### Running install step. 06:48:52 INFO - ##### 06:48:52 INFO - Running pre-action listener: _resource_record_pre_action 06:48:52 INFO - Running main action method: install 06:48:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:48:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:48:52 INFO - Reading from file tmpfile_stdout 06:48:52 INFO - Detecting whether we're running mozinstall >=1.0... 06:48:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:48:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:48:52 INFO - Reading from file tmpfile_stdout 06:48:52 INFO - Output received: 06:48:52 INFO - Usage: mozinstall [options] installer 06:48:52 INFO - Options: 06:48:52 INFO - -h, --help show this help message and exit 06:48:52 INFO - -d DEST, --destination=DEST 06:48:52 INFO - Directory to install application into. [default: 06:48:52 INFO - "/builds/slave/test"] 06:48:52 INFO - --app=APP Application being installed. [default: firefox] 06:48:52 INFO - mkdir: /builds/slave/test/build/application 06:48:52 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'] 06:48:52 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 06:49:24 INFO - Reading from file tmpfile_stdout 06:49:24 INFO - Output received: 06:49:24 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 06:49:24 INFO - Running post-action listener: _resource_record_post_action 06:49:24 INFO - ##### 06:49:24 INFO - ##### Running run-tests step. 06:49:24 INFO - ##### 06:49:24 INFO - Running pre-action listener: _resource_record_pre_action 06:49:24 INFO - Running main action method: run_tests 06:49:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:49:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:49:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:49:24 INFO - 2016-06-06 06:49:24.250 screenresolution[1639:7532] starting screenresolution argv=screenresolution get 06:49:24 INFO - 2016-06-06 06:49:24.253 screenresolution[1639:7532] Display 0: 1600x1200x32@60 06:49:24 INFO - 2016-06-06 06:49:24.259 screenresolution[1640:7534] starting screenresolution argv=screenresolution list 06:49:24 INFO - Available Modes on Display 0 06:49:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:49:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:49:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:49:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:49:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:49:24 INFO - Intel Iris: 06:49:24 INFO - Chipset Model: Intel Iris 06:49:24 INFO - Type: GPU 06:49:24 INFO - Bus: Built-In 06:49:24 INFO - VRAM (Dynamic, Max): 1536 MB 06:49:24 INFO - Vendor: Intel (0x8086) 06:49:24 INFO - Device ID: 0x0a2e 06:49:24 INFO - Revision ID: 0x0009 06:49:24 INFO - Displays: 06:49:24 INFO - Display: 06:49:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:49:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:49:24 INFO - Display Serial Number: 1600x1200 60 06:49:24 INFO - Main Display: Yes 06:49:24 INFO - Mirror: Off 06:49:24 INFO - Online: Yes 06:49:24 INFO - Rotation: Supported 06:49:24 INFO - Return code: 0 06:49:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:49:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:49:24 INFO - Python 2.7.3 06:49:24 INFO - Return code: 0 06:49:24 INFO - grabbing minidump binary from tooltool 06:49: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')]} 06:49:24 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 0x1040ade00>, '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 0x103f16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040a8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:49:24 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 06:49:24 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 06:49:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:49:24 INFO - Return code: 0 06:49:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:49:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:49:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:49:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:49:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:49:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 06:49:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 06:49:25 INFO - Return code: 0 06:49:25 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/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0247', '--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 06:49:25 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/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0247 --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 06:49:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PrkwqTAZDH/Render', 06:49:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:49:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:49:25 INFO - 'HOME': '/Users/cltbld', 06:49:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:49:25 INFO - 'LOGNAME': 'cltbld', 06:49:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:49:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:49:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:49:25 INFO - 'MOZ_NO_REMOTE': '1', 06:49:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:49:25 INFO - 'NO_EM_RESTART': '1', 06:49:25 INFO - 'PAGER': '/bin/cat', 06:49:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:49:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:49:25 INFO - 'PWD': '/builds/slave/test', 06:49:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:49:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:49:25 INFO - 'SHELL': '/bin/bash', 06:49:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N6DCQcjahu/Listeners', 06:49:25 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 06:49:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:49:25 INFO - 'USER': 'cltbld', 06:49:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:49:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:49:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:49:25 INFO - 'XPC_FLAGS': '0x0', 06:49:25 INFO - 'XPC_SERVICE_NAME': '0', 06:49:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:49:25 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/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0247', '--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 06:49:25 INFO - mozversion application_buildid: 20160606010529 06:49:25 INFO - mozversion application_changeset: d8adb38dd576398dacbfb96d2c247f8e0158bdf1 06:49:25 INFO - mozversion application_display_name: FirefoxDeveloperEdition 06:49:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:49:25 INFO - mozversion application_name: Firefox 06:49:25 INFO - mozversion application_remotingname: firefox-dev 06:49:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:49:25 INFO - mozversion application_vendor: Mozilla 06:49:25 INFO - mozversion application_version: 48.0a2 06:49:25 INFO - mozversion platform_buildid: 20160606010529 06:49:25 INFO - mozversion platform_changeset: d8adb38dd576398dacbfb96d2c247f8e0158bdf1 06:49:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:49:25 INFO - mozversion platform_version: 48.0a2 06:49:25 INFO - using testdate: 1465220965 06:49:25 INFO - actual date: 1465220965 06:49:25 INFO - starting webserver on 'localhost:49270' 06:49:25 INFO - SUITE-START | Running 2 tests 06:49:25 INFO - TEST-START | dromaeo_css 06:49:25 INFO - operating with platform_type : mac_ 06:49:25 INFO - Initialising browser for dromaeo_css test... 06:49:25 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/tmpj56D4A/profile http://localhost:49270/getInfo.html) 06:49:28 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 06:49:28 INFO - PROCESS | 1658 | colorDepth:24 06:49:28 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 06:49:28 INFO - PROCESS | 1658 | __metrics 06:49:28 INFO - PROCESS | 1658 | 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" 06:49:28 ERROR - PROCESS | 1658 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:49:29 INFO - TEST-INFO | 1658: exit 0 06:49:29 INFO - Browser initialized. 06:49:29 INFO - Running cycle 1/1 for dromaeo_css test... 06:49:29 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/tmpj56D4A/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:49:41 INFO - PROCESS | 1660 | RSS: Main: 211402752 06:49:41 INFO - PROCESS | 1660 | 06:50:32 INFO - PROCESS | 1660 | 1465221032291 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] 06:52:42 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 06:52:44 INFO - PROCESS | 1660 | RSS: Main: 319352832 06:52:44 INFO - PROCESS | 1660 | 06:55:46 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 06:55:47 INFO - PROCESS | 1660 | RSS: Main: 359444480 06:55:47 INFO - PROCESS | 1660 | 06:58:49 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 06:58:50 INFO - PROCESS | 1660 | RSS: Main: 360632320 06:58:50 INFO - PROCESS | 1660 | 07:01:52 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 07:01:52 INFO - PROCESS | 1660 | RSS: Main: 361525248 07:01:52 INFO - PROCESS | 1660 | 07:04:56 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 07:04:56 INFO - PROCESS | 1660 | RSS: Main: 324505600 07:04:56 INFO - PROCESS | 1660 | 07:07:59 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 07:07:59 INFO - PROCESS | 1660 | RSS: Main: 369475584 07:07:59 INFO - PROCESS | 1660 | 07:07:59 INFO - PROCESS | 1660 | __start_tp_report 07:07:59 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 07:07:59 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 07:07:59 INFO - PROCESS | 1660 | |i|pagename|runs| 07:07:59 INFO - PROCESS | 1660 | |0;dojo.html;3294.505824126381;3370.7471939604534;3371.1066567359653;3397;3449.793012419255;5504.146857237125;5524.861878453041;5545.750441230144;5555.972220138894;5570.1923221132965;5527.55779537637;5570.721463926808;5573.470520300565;5595.972020139904;5637.3235211774645;4601.769911504428;4889.217725163974;5049.520295571933;5132.101882170633;5137.845864624067;5103.311053007834;5192.169252919533;5202.661826981253;5211;5213.191955246943;4306.870793876189;5080.568151707091;5127.564157046656;5172.094883395391;5175.197844334115;5180.041692286939;5203.557697595709;5213.739313034371;5214.113600687892;5225.242339860718;5029.522195391469;5052.69683818972;5125.461826508237;5145.691258524501;5154.896902061938;5345.946540534623;5380.112281473552;5395.78416863325;5419.024575576394;5457.263269458612;6057.394260573908;6067.484263837579;6076.848078798022;6088.6042407243385;6109.847253818646;5984.102384642295;6097.725602347905;6102.816915492543;6205.6897155142515;6206.875862482771;5665.263515742927;5669.31968163823;5678.9432105678825;5707.001274776981;5735.4551317624755;6258;6287.9056814147825;6307.95918673423;6308.653024083627;6323.5573509854785;5867.882642347215;5969.910451343233;5975.25309336333;6024.4879185268865;6026.819195424145;6070.7875224366935;6074.331823499411;6104;6185.381461853869;6202.81391558254;6030.788922387783;6032.969835150884;6039.939600604026;6050.48570871472;6064.787732429344;6000.279966404059;6004.369541198199;6012.669303188367;6079.969600151971;6086;4738.810447582127;4796.448408433005;4812.205986012339;4813.181759100961;4820.68665536739;4351.978240108842;4379.824807007684;4413.801378937956;4420.491643461043;4422.690411671152;5459.918101228484;5519.724013799294;5543.362513311021;5548.611597188239;5587.245721827582;6005.939940600625;6103.481204097701;6114.327423983358;6119.877602448015;6141.355157708376;5406.02691515531;5474.343078830566;5483.150111732688;5490.890182196334;5499.532539734168;5195.480451954774;5245.921311180336;5277.076511610453;5277.9472205278225;5292.338457692788;6247.718852651642;6256.999999999909;6263.404976527223;6266.968665156645;6281;6086.786962456293;6118.908216376669;6127.14220009208;6145.446909778141;6149.754009839735;6157.384261573807;6221.96889015552;6309.463695585927;6310.368963103653;6315.147455093504;5059.215821547666;5997.940020599738;6030.065339872327;6033.577649564489;6052.122442245938;5996.1005849122985;6097.878042439215;6109.2974307955155;6133.417325354084;6142.692865356804;4941.975290123526;5846.5615078870105;5871.412858714094;5919.733611987384;5970.880582388415;4997.225430058346;5184.600157957255;5806.709664516842;5832.62087964281;5896.086106653475;5600.747966341443;5652.519535839499;5716.02827519331;5742;5752.712364381848;5936.614120082181;5960.046392577166;5960.791372301948;5976.491998180029;5990.071538911476;5834.737436815439;5838.795642152504;5839.153322768259;5861.501772349398;5877.764889404376 07:07:59 INFO - PROCESS | 1660 | |1;ext.html;526.5997841640352;535.4351159526698;543.9646422982507;547.5099785691806;593.9346671866097;30115.64479598418;37399.81300093499;38368.61631383689;38439.23121537568;38515.807420962854;40340.99147521314;40595.79702101493;40607;40627;40630.984225394306;8276.213759692819;8397.6221070052;9207.999999999984;9429.858552121723;9446.527673616327;11228.270162439456;11323.490442930088;11346.886531134707;11350.20548561601;11360.772784544304;9696.515174241295;11149.442527873574;11214.215004949658;11234.887651123507;11238.269512481704;8410.074891761931;11167.609133680322;11385.430728463543;11537.423128843524;11540.942295288554;11085.556577736881;11198.272112312756;11206.887931120666;11210.775784484345;11212.831807522894;37300.44049339235;37711.24577508462;37764.81117594422;37802.62197378046;37918.67284645052;2661.308059904419;3089.258577941278;3221;3254.5280934264397;3343.699067083974;25870.741292587023;26219.344516387053;26277.686115694138;26530.867345663148;27327.726722732717;2725.6184134221226;2749.8350098994124;2758.4896794093156;2788.7490125888567;2841.0340484235458;330265;330497;330831;332686.336568318;391357.04321478494;59708.70145649244;60096.098558521655;60259.397406025375;60373.69813150906;60469.69765151146;63102.053469198;63262;63274;63391.68304158542;63595.68202158959;16404.179791010403;16926;16990.830091698925;17026.23381947815;17065.317387304618;11520.88479115198;11699.2980421175;11706.531738730355;11708.414579271002;11708.765824683458;14506.782398264035;16544.586385340222;16579;16684.416045438353;16700.997940123387;6119.143319935212;6272.278687950944;6982.092327997328;7134.464915131386;7239.384652304613;940.9529523523796;949.9050094990583;1064.3347907557777;1108.174410064491;1146.5070019891527;29808.850955745515;30870.38259234803;31908.680913191034;32411.51382729261;32575.51136732951;1112.5994641928899;1119.2556949628406;1171.46698252295;1172.7185475485992;1192.3025030357337;14379.137251764927;14645.560633181021;14824.777628335583;14893.404263829325;14962.925185374004;608301;739887;741655;744643;1015750;58534;58673.13268673098;59596.40403595995;59598.40401596015;59671;1435.6913263648157;1453.876420504269;1580.6680597074512;1583.1846599001524;1603.2384617306918;24250.64316056043;30578.932673899028;30684;30721.385572288877;31200.531992019685;52463.73768131135;53183.73408132935;53192.46807531875;53269.46730532645;53444.73277633587;476760;493516;494180;494292;494505.10989780724;35849.92450226497;42579;42917;43504.564954350055;44099.77950110357;43793.34309985289;44260;44441.777791110835;45445.09109817851;45525.634230973126;35381.82309088438;35763.64236357603;36957.63042369649;37581.4362784553;37626;35624.287514250085;35933.281334372645;36788.63211367852;37136.44295335518;37923.81038094792;35871.64128358683;36094.63905360913;36151.91544253677;37750.811245943594;38292.234155317296;25110.62334064954;30815.075547733602;31184.908528201406;31254.90607828715;31269.687303127586;23878.664109779813;24030.51938961246;24648.753512465362;24796.909054884232;24913.377165571004 07:07:59 INFO - PROCESS | 1660 | |2;jquery.html;398.1598826476136;399.9100202454448;401.57433120891875;407.5069166308766;415.1240881739529;14676;14959.401623935057;19972.90013549932;20635.587288254224;20674.89662551689;11844.881551184508;12260.81608775869;19485.707714384294;20166.19335226589;20238.494037649067;5377.139657654766;5520.144377621455;5573.972130139344;5580.274564306635;5582.441755824426;5455.990641731292;5468.56251499881;5963.8210853674245;6095.238095238095;6195.5663103582765;6122.326544080148;6126.26484821822;6158.538109641772;6159.35326790685;6166.260048794151;5905.173275741399;6053.152558641794;6124.724387402578;6135.049067394539;6138.69306534676;5407.161889907032;5423.5932305076885;5425.240466334677;5426;5431.8098866539485;13201.933990330082;13805.723885522233;13817.861821381859;13818.170909745448;16364.772642051894;2168.186929901287;2441;2534.6958364996135;2581.690197176351;2830.6461692288462;9983.351082179635;10271.537780799881;10885.564577416893;11366.772664546663;15298.694026119416;1706.9573260668458;1708.700977328975;1724.8102708702033;1770.8937463752216;1822.2528763207022;84786;94407;102966;105836.88326233432;113106.43446782796;9588;10302.742431439274;11296.830547541793;11619.07047436203;11728.589499367652;14940.850591494163;15005.099694018394;15011.024283421542;15017.924910375377;15034.09795412257;7322.816929576803;8585.527795971282;8706.564671766386;8706.651733930572;8842.955785221162;8080.474769139987;8681.956590217009;8698.869516957124;8704.477731336014;8740.300775937909;4673.95326046742;4700.388949436619;4700.858974230779;4714.504976977437;4734.431868175772;1943.679292916653;1980.6236815005102;1996.830269427086;2023.2817349840595;2033.5119571303076;799.9560024198609;944.447498213539;945.9432434053979;976.4190306767462;1026.071405378134;19711.704324435144;20201.59596808085;21186.258480953093;22232.555348892933;22318.442038948833;980.8773903262093;1009.6062535611118;1015.664830605913;1037.2894567221476;1070.946452677363;10791;11036.454896314523;11038.724031899106;11116.943890330405;11117.555297787998;634716;644240;651155;657874;660962;87266;120507;125151;125655;128236;1070.2401295080424;1070.7376692710336;1077.2513103393067;1102.2559772153763;1109.2955972957068;16017.839821601634;17113.572160696083;17463.47609571715;17967.371142009968;17978.640427191644;52153.217701735266;52321;53517.197242040624;53764;54642.72678636581;72103;74999;76434.23565764271;77551.61224193843;79454.6027269883;7733;7996.200379961957;8119;8219.465734727224;8243.887075245006;12200.084993625336;12725.300108494122;12838.229706217657;13080.542181023618;13119.344032798514;9516.429014259167;9714.931357550806;11015.339079655247;11247.156463265124;11258.21192516516;10989.120870330195;11087.611933582286;11150.940660637227;11303.078172730371;11427.971482566276;11443.9128282813;11652.53389864396;11668.591599293984;11829.935305822517;11912.583059592873;8205.79485512843;8366.079731229682;8419.410641255054;8470.237678608955;8682.43564168327;10252.692419227435;11365.31808091517;11598.304101753922;11649.59226427071;11726 07:07:59 INFO - PROCESS | 1660 | |3;mootools.html;1450.535828534868;1569.7802307676923;1598.336690273536;1852.5276054606074;1977.5847072114855;7742.80642983925;8424.578771061446;8653.95673021634;8663.39356245063;8666.826663466727;8355.91644083559;8623.180797824196;8626.439281446701;8630.65477380904;8637.438566493189;2164.9783502165014;2354.6232602783557;2414.4567472318763;2452.190777043576;2478.6901637295337;2463.8398504097268;2475.5667758142254;2476.418041760185;2477.938051548708;2481.2680259323542;1951.9804801952012;2394.6886904702365;2449.289705985262;2449.730529641738;2453.1414005098163;2156.7196264485674;2383.7258715247794;2434.2088821133116;2441.3164313991933;2444.6088625819784;2413.963790543143;2440.0727723465147;2446.804255659543;2450.497647982177;2454.6686197363306;8556.957215213946;8619.827603447897;8622.611982460805;8625.051244363114;8633.13668633138;5130.743462826881;5364.678119312854;5366.517013468807;5370.275012873251;5376.946230537723;3543.9114022149392;3565.6612621801146;3567.6967457766123;3588.5334906461994;3627.4921510988484;1066.1204506282347;1075.1560025380118;1080.983785243222;1081.4808891732014;1081.6592773276393;11660.825087623693;11699.947004769612;11703.53185872564;11704.297742135499;11714.23857449263;4701.835435759732;4711.411073615798;4741.431028276629;4783.234682450791;4827.734474603931;9899.059589339002;9906.05892440217;9906.851397229048;9917.801643967081;9925.950370248103;5353;5380.408155102936;5386;5391.838244852661;5397.973010135002;4071.6539094176733;4325.329573916048;4444.911101777946;4869.977304766039;4875.902481950341;3941.546722126992;3946.427767973628;3995.081131339808;4046.5346485154582;4051.210014047291;1329.6808765896114;1337.752515784583;1338.136901698399;1350.9797353039712;1351.7634413977514;928.8095940332215;935.9766005849908;942.8491441369348;955.8948515663271;979.1823827104305;5858.736356863951;5977.820665380045;6002.879942401128;6008.459238668541;6144.569880108439;460.59927862759616;466.23770135828164;472.89596288816404;474.05662731164733;485.1509857748939;3887.3391523441082;3890.4747859039235;3961.6830653547645;4018.618231268025;4095.7542547447247;10024.448655324028;10616.150707943347;10623.36259824413;10711.517981690688;10722.303050301705;10121.797564048826;10403.94798026005;10445.373277603216;10485.737856553647;10553.736156596147;8066.596670166468;8193.959030204811;8264.586770661443;8273.710420135267;8325.875111873205;5515.58633102527;5660.094384898396;5674.092145256739;5799.188113664175;5811.157382179645;9166.724998250063;9212.677556285498;9437.764055898657;9464.858027129461;9516.571754271214;9562.521873906417;9782.168515676247;9845.261605379483;9918.801623967625;9944.35361701487;5579.972100139474;6230.501559875109;6780.559263647847;7459.104907411145;7510.774676759706;7056.259092795182;8110.229528194812;8115.594220288844;8116.391270654606;8334.749957501284;6995.160580730345;7150.606716630636;7152.034475345762;7220.675069621985;7290.635468226674;7041.929580704333;7068.222495525591;7071.858562828817;7169.677364518505;7201.207867134715;6613.272540020497;6955.026296318419;6995.160580730141;7019.333163349474;7039.612821294878;4895.828645997373;4950.801967921243;4951.5048495150195;5052.292679025013;5077;4953;4957.603391728725;4975.626827987843;5016.54851063406;5206.297149884868 07:07:59 INFO - PROCESS | 1660 | |4;prototype.html;288.44762280233357;324.40471734367435;328.85201659253335;334.52664479761154;338.53789577227093;6647.966760166199;6656.467482601386;6755.459563234942;6757.155355580552;6764.1882974043065;5692.658440493573;6638.369354911276;6639.402453779159;6673.833154171136;6685.398314151726;4195.916081678364;4526.97736511317;4779.498152693969;4833.347498087748;4925.630577706668;4863.781129849148;4905.656604037718;4907.337509436234;4915.508449155073;4927.211646136619;4771.141194584973;4909.484504127069;4916.287138364935;4917.21324588066;4920.876978075541;4649;4714.316424118484;4849.927251091236;4914;4914.5085491450745;3460.8269586520573;4172.415861779354;4175.081482073969;4204.558521355275;4210.51579068408;6201.286852012031;6259.6870156492;6289.937100629027;6297.96851015742;6309.684515774239;5826.766929322822;5827.912581311284;5837.124431335291;5837.620554663933;5841.620294680833;7064.576125432439;7083.539569927938;7085.787426377217;7096.822579435504;7107.253738357502;4556.316552517116;4700.271457924027;4725.716457012591;4732.503087175865;4734.786934587952;7351.044364232669;7418;7471.2155223701275;7477.21489243627;7477.364424023965;4826.758662066883;4830.082284365959;4834.129856625841;4835.854924352275;4844.539768721966;4240.809163587646;4808.230683090689;4884.682495637772;4899.1426500362295;4900.411950565884;5356.955393698191;5617.185508101303;5857.384974577693;5863.709983803642;5866.624017273011;5581.553475721932;7144.785656430316;7151.356377926011;7153.535020223669;7185.6766445510075;1696.720041193214;1712.803027651836;1721.8106008339073;1725.8015328237163;1727.75811386406;1041.7656027393775;1094.9871368983659;1101.3612104979093;1125.431657013203;1130.8190689489643;5198;6470.805875823638;6570.638614876228;6592.077109204715;6625.138731964814;6354.586951848115;7096.609686467294;7424.071991001125;7430;7437.51656142349;6633;6634.800955971328;6648.335166483313;6650.634215118215;6651.401373876374;3888.4556162137324;3934.9803250984132;3941;3950.367941129463;3964.960350396517;7541.132769731441;7808.375329973588;7816.765497035098;7829.530228186213;7832.490888092256;7643.5796031218815;7664.233576642403;7682.423818213544;7688.423368247292;7707.807304817425;7812.828075788563;7813.453058285979;7826.921730782619;7829.64766585505;7871.881921771178;7116.679749411393;7374.483786134919;7389.815254618463;7397.482176247611;7420.109586849612;7256.637168141678;7286.7085316586745;7294.197638259893;7315.231900650355;7320.304571065741;7788.88316675239;7810.179931107152;7821.178776228414;7827.412944029106;7845.607719613882;4839.491853355347;4852.781624826821;4853.39332583427;4856.757162141808;4858.05267972742;7017.789466316019;7320.487565870338;7331.816704582215;7338.522996005243;7339.9266007341375;7034.718611255697;7116.146062472537;7144.571325720473;7145.1425828900865;7158.176809666955;5113.693178409308;7070.7525236616475;7085.256048114874;7090.680919358744;7129.215786263403;6958.512904096665;6959.826004349932;6963;6965.233824279224;6971.442284617117;6837.521373503807;7119.964400177966;7125.714971401086;7166.677499512639;7196.676149573177;6662.633555154319;6742.460603151834;6881.690323935535;7038.683259253244;7053.400460960671 07:07:59 INFO - PROCESS | 1660 | |5;yui.html;681.4820736240457;694.4790907911673;720.8646381948429;751.4476859508264;765.5445010218921;14906.403743850247;15314.310856011478;15335.539933802003;15340.693186136285;15349.769753453707;15180.468683596077;15627.921860390683;15630.983986040928;15643;15722.764158537631;759.2104211619909;766.1074847802331;917.9173874351324;930.4277869110482;942.688912658821;935.1957316707626;953.980920381592;954.2080073538946;954.4607296877277;954.8138113067954;981.7103954333488;997.3616885193481;1000.6597756762699;1003.5634498992926;1004.7036124343336;787.3779714025941;974.181687382595;982.0425085541611;993.6124911284569;994.8507723841409;733.6331834082958;740.0157790139102;740.6629983357559;741.4290995933102;742.6695120671303;14939;15023.849761502355;15035.323410446446;15044.473443429537;15052.774208386883;12206.267623942593;12316.014718822562;12343.567975120917;12434;12592.622221333375;8722.781930451725;9996.250281228937;10106.989301069907;10205.489725513695;10289.022542858416;3433.484977253432;3445.896623101311;3488.1977145256733;3559.911002224965;3566.32239874423;75360;75442;75559.62220188863;76189;76398.99999999945;9352.251819854395;9374.62501499946;9402.294827887936;9497.810043799223;10072;20817.68773468399;20823.479413014797;20828.895855520626;20865.58268834615;20917.686234706493;7232.312930271616;7259.382952449101;7340.779776606711;7341.265873412616;7573.242675732493;4080.510338759368;4147.108371700038;4228.746275223496;4259.574042595716;4416.712913660602;3591.2099338145567;3659.0852286928266;3662.212624285791;3682.576503702055;3683.839590528975;621.9315875253718;643.5141468191486;646.5861848417017;647.7279542592123;649.7530938243438;538.8006437618028;575.928772483181;607.1530215349562;613.7176898626682;629.8362425769242;7967.8406431871035;8205.917940820635;8256.545889975989;8264;8313.45962512447;454.2799662534853;574.7528562717988;595.4640823259101;608.9421504957111;613.5183880653755;3747.643973822537;3775.8867233983024;3799.3920972644246;3831.8658846940225;3872.9031774205305;82711.5864420674;86214.56892715496;86717.5664121688;86801;86831.56584217038;70090.64954675194;72117.63941180261;72262.63868680623;73098;73228.26771732321;64811.67594161999;64851.67574162099;65098.67450662716;65361;66577.33422665905;7614.5811980339395;7651.768672070642;7718.807029824299;7757.612119394121;7770.339521140766;47620.28569571528;47845.521544784104;48161;48272.758636208;48725.78185545248;64601.70796584136;65742.34257657362;65862.34137658562;66280;67077.32922670711;9677.661281855102;9685.41887486753;9869.407835529892;10027.448490333101;10682;8750.69372571957;10620.946895265473;10811.405372704263;11018.008379245906;11860.94069529647;10302.845457318295;10388.42863642507;10437.58249670035;10461.68614941553;10613.991670791262;10296.897031029894;10392.064714175458;10392.58429662805;10531.210159237935;10574.471276436301;10115.696529104138;10154.39073655583;10219.795604088025;10232.795344093225;10375.792484150426;7604.201558836243;8791.384603077724;8823.69117080899;8937.687180948635;9142.3600347975;8708.390412671051;8871.245944094566;8916.910830891868;8966.955165224132;8973.750830116975 07:07:59 INFO - PROCESS | 1660 | __end_tp_report 07:07:59 INFO - PROCESS | 1660 | __start_cc_report 07:07:59 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,1023 07:07:59 INFO - PROCESS | 1660 | __end_cc_report 07:07:59 INFO - PROCESS | 1660 | __startTimestamp1465222079598__endTimestamp 07:07:59 INFO - PROCESS | 1660 | 07:07:59 INFO - PROCESS | 1660 | ------- Summary: start ------- 07:07:59 INFO - PROCESS | 1660 | Number of tests: 6 07:07:59 INFO - PROCESS | 1660 | 07:07:59 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5593.61 Median:5742.00 stddev:628.44 (10.9%) stddev-sans-first:603.93 07:07:59 INFO - PROCESS | 1660 | Values: 3294.5 3370.7 3371.1 3397.0 3449.8 5504.1 5524.9 5545.8 5556.0 5570.2 5527.6 5570.7 5573.5 5596.0 5637.3 4601.8 4889.2 5049.5 5132.1 5137.8 5103.3 5192.2 5202.7 5211.0 5213.2 4306.9 5080.6 5127.6 5172.1 5175.2 5180.0 5203.6 5213.7 5214.1 5225.2 5029.5 5052.7 5125.5 5145.7 5154.9 5345.9 5380.1 5395.8 5419.0 5457.3 6057.4 6067.5 6076.8 6088.6 6109.8 5984.1 6097.7 6102.8 6205.7 6206.9 5665.3 5669.3 5678.9 5707.0 5735.5 6258.0 6287.9 6308.0 6308.7 6323.6 5867.9 5969.9 5975.3 6024.5 6026.8 6070.8 6074.3 6104.0 6185.4 6202.8 6030.8 6033.0 6039.9 6050.5 6064.8 6000.3 6004.4 6012.7 6080.0 6086.0 4738.8 4796.4 4812.2 4813.2 4820.7 4352.0 4379.8 4413.8 4420.5 4422.7 5459.9 5519.7 5543.4 5548.6 5587.2 6005.9 6103.5 6114.3 6119.9 6141.4 5406.0 5474.3 5483.2 5490.9 5499.5 5195.5 5245.9 5277.1 5277.9 5292.3 6247.7 6257.0 6263.4 6267.0 6281.0 6086.8 6118.9 6127.1 6145.4 6149.8 6157.4 6222.0 6309.5 6310.4 6315.1 5059.2 5997.9 6030.1 6033.6 6052.1 5996.1 6097.9 6109.3 6133.4 6142.7 4942.0 5846.6 5871.4 5919.7 5970.9 4997.2 5184.6 5806.7 5832.6 5896.1 5600.7 5652.5 5716.0 5742.0 5752.7 5936.6 5960.0 5960.8 5976.5 5990.1 5834.7 5838.8 5839.2 5861.5 5877.8 07:07:59 INFO - PROCESS | 1660 | 07:07:59 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:67293.09 Median:168015.02 stddev:153052.95 (91.1%) stddev-sans-first:153400.21 07:07:59 INFO - PROCESS | 1660 | Values: 526.6 535.4 544.0 547.5 593.9 30115.6 37399.8 38368.6 38439.2 38515.8 40341.0 40595.8 40607.0 40627.0 40631.0 8276.2 8397.6 9208.0 9429.9 9446.5 11228.3 11323.5 11346.9 11350.2 11360.8 9696.5 11149.4 11214.2 11234.9 11238.3 8410.1 11167.6 11385.4 11537.4 11540.9 11085.6 11198.3 11206.9 11210.8 11212.8 37300.4 37711.2 37764.8 37802.6 37918.7 2661.3 3089.3 3221.0 3254.5 3343.7 25870.7 26219.3 26277.7 26530.9 27327.7 2725.6 2749.8 2758.5 2788.7 2841.0 330265.0 330497.0 330831.0 332686.3 391357.0 59708.7 60096.1 60259.4 60373.7 60469.7 63102.1 63262.0 63274.0 63391.7 63595.7 16404.2 16926.0 16990.8 17026.2 17065.3 11520.9 11699.3 11706.5 11708.4 11708.8 14506.8 16544.6 16579.0 16684.4 16701.0 6119.1 6272.3 6982.1 7134.5 7239.4 941.0 949.9 1064.3 1108.2 1146.5 29808.9 30870.4 31908.7 32411.5 32575.5 1112.6 1119.3 1171.5 1172.7 1192.3 14379.1 14645.6 14824.8 14893.4 14962.9 608301.0 739887.0 741655.0 744643.0 1015750.0 58534.0 58673.1 59596.4 59598.4 59671.0 1435.7 1453.9 1580.7 1583.2 1603.2 24250.6 30578.9 30684.0 30721.4 31200.5 52463.7 53183.7 53192.5 53269.5 53444.7 476760.0 493516.0 494180.0 494292.0 494505.1 35849.9 42579.0 42917.0 43504.6 44099.8 43793.3 44260.0 44441.8 45445.1 45525.6 35381.8 35763.6 36957.6 37581.4 37626.0 35624.3 35933.3 36788.6 37136.4 37923.8 35871.6 36094.6 36151.9 37750.8 38292.2 25110.6 30815.1 31184.9 31254.9 31269.7 23878.7 24030.5 24648.8 24796.9 24913.4 07:07:59 INFO - PROCESS | 1660 | 07:07:59 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:35204.10 Median:11677.22 stddev:107515.08 (920.7%) stddev-sans-first:107784.92 07:07:59 INFO - PROCESS | 1660 | Values: 398.2 399.9 401.6 407.5 415.1 14676.0 14959.4 19972.9 20635.6 20674.9 11844.9 12260.8 19485.7 20166.2 20238.5 5377.1 5520.1 5574.0 5580.3 5582.4 5456.0 5468.6 5963.8 6095.2 6195.6 6122.3 6126.3 6158.5 6159.4 6166.3 5905.2 6053.2 6124.7 6135.0 6138.7 5407.2 5423.6 5425.2 5426.0 5431.8 13201.9 13805.7 13817.9 13818.2 16364.8 2168.2 2441.0 2534.7 2581.7 2830.6 9983.4 10271.5 10885.6 11366.8 15298.7 1707.0 1708.7 1724.8 1770.9 1822.3 84786.0 94407.0 102966.0 105836.9 113106.4 9588.0 10302.7 11296.8 11619.1 11728.6 14940.9 15005.1 15011.0 15017.9 15034.1 7322.8 8585.5 8706.6 8706.7 8843.0 8080.5 8682.0 8698.9 8704.5 8740.3 4674.0 4700.4 4700.9 4714.5 4734.4 1943.7 1980.6 1996.8 2023.3 2033.5 800.0 944.4 945.9 976.4 1026.1 19711.7 20201.6 21186.3 22232.6 22318.4 980.9 1009.6 1015.7 1037.3 1070.9 10791.0 11036.5 11038.7 11116.9 11117.6 634716.0 644240.0 651155.0 657874.0 660962.0 87266.0 120507.0 125151.0 125655.0 128236.0 1070.2 1070.7 1077.3 1102.3 1109.3 16017.8 17113.6 17463.5 17967.4 17978.6 52153.2 52321.0 53517.2 53764.0 54642.7 72103.0 74999.0 76434.2 77551.6 79454.6 7733.0 7996.2 8119.0 8219.5 8243.9 12200.1 12725.3 12838.2 13080.5 13119.3 9516.4 9714.9 11015.3 11247.2 11258.2 10989.1 11087.6 11150.9 11303.1 11428.0 11443.9 11652.5 11668.6 11829.9 11912.6 8205.8 8366.1 8419.4 8470.2 8682.4 10252.7 11365.3 11598.3 11649.6 11726.0 07:07:59 INFO - PROCESS | 1660 | 07:07:59 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:5615.68 Median:4996.09 stddev:3116.24 (62.4%) stddev-sans-first:3109.26 07:07:59 INFO - PROCESS | 1660 | Values: 1450.5 1569.8 1598.3 1852.5 1977.6 7742.8 8424.6 8654.0 8663.4 8666.8 8355.9 8623.2 8626.4 8630.7 8637.4 2165.0 2354.6 2414.5 2452.2 2478.7 2463.8 2475.6 2476.4 2477.9 2481.3 1952.0 2394.7 2449.3 2449.7 2453.1 2156.7 2383.7 2434.2 2441.3 2444.6 2414.0 2440.1 2446.8 2450.5 2454.7 8557.0 8619.8 8622.6 8625.1 8633.1 5130.7 5364.7 5366.5 5370.3 5376.9 3543.9 3565.7 3567.7 3588.5 3627.5 1066.1 1075.2 1081.0 1081.5 1081.7 11660.8 11699.9 11703.5 11704.3 11714.2 4701.8 4711.4 4741.4 4783.2 4827.7 9899.1 9906.1 9906.9 9917.8 9926.0 5353.0 5380.4 5386.0 5391.8 5398.0 4071.7 4325.3 4444.9 4870.0 4875.9 3941.5 3946.4 3995.1 4046.5 4051.2 1329.7 1337.8 1338.1 1351.0 1351.8 928.8 936.0 942.8 955.9 979.2 5858.7 5977.8 6002.9 6008.5 6144.6 460.6 466.2 472.9 474.1 485.2 3887.3 3890.5 3961.7 4018.6 4095.8 10024.4 10616.2 10623.4 10711.5 10722.3 10121.8 10403.9 10445.4 10485.7 10553.7 8066.6 8194.0 8264.6 8273.7 8325.9 5515.6 5660.1 5674.1 5799.2 5811.2 9166.7 9212.7 9437.8 9464.9 9516.6 9562.5 9782.2 9845.3 9918.8 9944.4 5580.0 6230.5 6780.6 7459.1 7510.8 7056.3 8110.2 8115.6 8116.4 8334.7 6995.2 7150.6 7152.0 7220.7 7290.6 7041.9 7068.2 7071.9 7169.7 7201.2 6613.3 6955.0 6995.2 7019.3 7039.6 4895.8 4950.8 4951.5 5052.3 5077.0 4953.0 4957.6 4975.6 5016.5 5206.3 07:07:59 INFO - PROCESS | 1660 | 07:07:59 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:5824.74 Median:6636.59 stddev:1862.86 (28.1%) stddev-sans-first:1821.14 07:07:59 INFO - PROCESS | 1660 | Values: 288.4 324.4 328.9 334.5 338.5 6648.0 6656.5 6755.5 6757.2 6764.2 5692.7 6638.4 6639.4 6673.8 6685.4 4195.9 4527.0 4779.5 4833.3 4925.6 4863.8 4905.7 4907.3 4915.5 4927.2 4771.1 4909.5 4916.3 4917.2 4920.9 4649.0 4714.3 4849.9 4914.0 4914.5 3460.8 4172.4 4175.1 4204.6 4210.5 6201.3 6259.7 6289.9 6298.0 6309.7 5826.8 5827.9 5837.1 5837.6 5841.6 7064.6 7083.5 7085.8 7096.8 7107.3 4556.3 4700.3 4725.7 4732.5 4734.8 7351.0 7418.0 7471.2 7477.2 7477.4 4826.8 4830.1 4834.1 4835.9 4844.5 4240.8 4808.2 4884.7 4899.1 4900.4 5357.0 5617.2 5857.4 5863.7 5866.6 5581.6 7144.8 7151.4 7153.5 7185.7 1696.7 1712.8 1721.8 1725.8 1727.8 1041.8 1095.0 1101.4 1125.4 1130.8 5198.0 6470.8 6570.6 6592.1 6625.1 6354.6 7096.6 7424.1 7430.0 7437.5 6633.0 6634.8 6648.3 6650.6 6651.4 3888.5 3935.0 3941.0 3950.4 3965.0 7541.1 7808.4 7816.8 7829.5 7832.5 7643.6 7664.2 7682.4 7688.4 7707.8 7812.8 7813.5 7826.9 7829.6 7871.9 7116.7 7374.5 7389.8 7397.5 7420.1 7256.6 7286.7 7294.2 7315.2 7320.3 7788.9 7810.2 7821.2 7827.4 7845.6 4839.5 4852.8 4853.4 4856.8 4858.1 7017.8 7320.5 7331.8 7338.5 7339.9 7034.7 7116.1 7144.6 7145.1 7158.2 5113.7 7070.8 7085.3 7090.7 7129.2 6958.5 6959.8 6963.0 6965.2 6971.4 6837.5 7120.0 7125.7 7166.7 7196.7 6662.6 6742.5 6881.7 7038.7 7053.4 07:07:59 INFO - PROCESS | 1660 | 07:07:59 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:17398.78 Median:32624.15 stddev:24087.28 (73.8%) stddev-sans-first:24122.14 07:07:59 INFO - PROCESS | 1660 | Values: 681.5 694.5 720.9 751.4 765.5 14906.4 15314.3 15335.5 15340.7 15349.8 15180.5 15627.9 15631.0 15643.0 15722.8 759.2 766.1 917.9 930.4 942.7 935.2 954.0 954.2 954.5 954.8 981.7 997.4 1000.7 1003.6 1004.7 787.4 974.2 982.0 993.6 994.9 733.6 740.0 740.7 741.4 742.7 14939.0 15023.8 15035.3 15044.5 15052.8 12206.3 12316.0 12343.6 12434.0 12592.6 8722.8 9996.3 10107.0 10205.5 10289.0 3433.5 3445.9 3488.2 3559.9 3566.3 75360.0 75442.0 75559.6 76189.0 76399.0 9352.3 9374.6 9402.3 9497.8 10072.0 20817.7 20823.5 20828.9 20865.6 20917.7 7232.3 7259.4 7340.8 7341.3 7573.2 4080.5 4147.1 4228.7 4259.6 4416.7 3591.2 3659.1 3662.2 3682.6 3683.8 621.9 643.5 646.6 647.7 649.8 538.8 575.9 607.2 613.7 629.8 7967.8 8205.9 8256.5 8264.0 8313.5 454.3 574.8 595.5 608.9 613.5 3747.6 3775.9 3799.4 3831.9 3872.9 82711.6 86214.6 86717.6 86801.0 86831.6 70090.6 72117.6 72262.6 73098.0 73228.3 64811.7 64851.7 65098.7 65361.0 66577.3 7614.6 7651.8 7718.8 7757.6 7770.3 47620.3 47845.5 48161.0 48272.8 48725.8 64601.7 65742.3 65862.3 66280.0 67077.3 9677.7 9685.4 9869.4 10027.4 10682.0 8750.7 10620.9 10811.4 11018.0 11860.9 10302.8 10388.4 10437.6 10461.7 10614.0 10296.9 10392.1 10392.6 10531.2 10574.5 10115.7 10154.4 10219.8 10232.8 10375.8 7604.2 8791.4 8823.7 8937.7 9142.4 8708.4 8871.2 8916.9 8967.0 8973.8 07:07:59 INFO - PROCESS | 1660 | -------- Summary: end -------- 07:07:59 INFO - PROCESS | 1660 | 07:08:00 INFO - TEST-INFO | 1660: exit 0 07:08:00 INFO - TEST-OK | dromaeo_css | took 1114260ms 07:08:00 INFO - TEST-START | kraken 07:08:00 INFO - operating with platform_type : mac_ 07:08:00 INFO - Initialising browser for kraken test... 07:08:00 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/tmp1SlMky/profile http://localhost:49270/getInfo.html) 07:08:02 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 07:08:02 INFO - PROCESS | 1669 | colorDepth:24 07:08:02 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 07:08:02 INFO - PROCESS | 1669 | __metrics 07:08:02 INFO - PROCESS | 1669 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:08:02 ERROR - PROCESS | 1669 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:08:03 INFO - TEST-INFO | 1669: exit 0 07:08:03 INFO - Browser initialized. 07:08:03 INFO - Running cycle 1/1 for kraken test... 07:08:03 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/tmp1SlMky/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:08:15 INFO - PROCESS | 1671 | RSS: Main: 210857984 07:08:15 INFO - PROCESS | 1671 | 07:08:17 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 07:08:18 INFO - PROCESS | 1671 | RSS: Main: 271867904 07:08:18 INFO - PROCESS | 1671 | 07:08:21 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 07:08:22 INFO - PROCESS | 1671 | RSS: Main: 285683712 07:08:22 INFO - PROCESS | 1671 | 07:08:25 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 07:08:25 INFO - PROCESS | 1671 | RSS: Main: 375582720 07:08:25 INFO - PROCESS | 1671 | 07:08:27 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 07:08:28 INFO - PROCESS | 1671 | RSS: Main: 278573056 07:08:28 INFO - PROCESS | 1671 | 07:08:30 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:08:31 INFO - PROCESS | 1671 | RSS: Main: 279044096 07:08:31 INFO - PROCESS | 1671 | 07:08:33 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 07:08:34 INFO - PROCESS | 1671 | RSS: Main: 317427712 07:08:34 INFO - PROCESS | 1671 | 07:08:37 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 07:08:37 INFO - PROCESS | 1671 | RSS: Main: 318894080 07:08:37 INFO - PROCESS | 1671 | 07:08:40 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 07:08:41 INFO - PROCESS | 1671 | RSS: Main: 317857792 07:08:41 INFO - PROCESS | 1671 | 07:08:43 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:08:43 INFO - PROCESS | 1671 | RSS: Main: 309248000 07:08:43 INFO - PROCESS | 1671 | 07:08:45 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:08:46 INFO - PROCESS | 1671 | RSS: Main: 315518976 07:08:46 INFO - PROCESS | 1671 | 07:08:48 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:08:49 INFO - PROCESS | 1671 | RSS: Main: 319111168 07:08:49 INFO - PROCESS | 1671 | 07:08:51 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:08:52 INFO - PROCESS | 1671 | RSS: Main: 324268032 07:08:52 INFO - PROCESS | 1671 | 07:08:55 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:08:55 INFO - PROCESS | 1671 | RSS: Main: 322134016 07:08:55 INFO - PROCESS | 1671 | 07:08:57 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 07:08:57 INFO - PROCESS | 1671 | RSS: Main: 386260992 07:08:57 INFO - PROCESS | 1671 | 07:08:57 INFO - PROCESS | 1671 | __start_tp_report 07:08:57 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 07:08:57 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 07:08:57 INFO - PROCESS | 1671 | |i|pagename|runs| 07:08:57 INFO - PROCESS | 1671 | |0;ai-astar;114;114;123;114;111;113;114;114;116;134 07:08:57 INFO - PROCESS | 1671 | |1;audio-beat-detection;159;146;161;165;140;174;126;155;158;119 07:08:57 INFO - PROCESS | 1671 | |2;audio-dft;187;184;184;185;180;184;185;185;186;186 07:08:57 INFO - PROCESS | 1671 | |3;audio-fft;69;79;75;150;56;57;57;65;136;55 07:08:57 INFO - PROCESS | 1671 | |4;audio-oscillator;92;93;143;77;79;84;128;79;77;125 07:08:57 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;88;85;87;85;89;85;88;85;85;85 07:08:57 INFO - PROCESS | 1671 | |6;imaging-darkroom;92;94;93;93;92;93;93;93;93;92 07:08:57 INFO - PROCESS | 1671 | |7;imaging-desaturate;125;142;87;86;85;87;87;86;85;84 07:08:57 INFO - PROCESS | 1671 | |8;json-parse-financial;61;65;63;63;65;66;65;84;59;58 07:08:57 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;65;65;62;82;75;70;69;68;69;69 07:08:57 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;80;79;80;81;80;80;80;79;80;85 07:08:57 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;130;128;133;128;126;127;124;127;150;158 07:08:57 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;166;162;162;160;164;167;164;174;175;162 07:08:57 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;67;67;67;67;67;68;67;68;66;66 07:08:57 INFO - PROCESS | 1671 | __end_tp_report 07:08:57 INFO - PROCESS | 1671 | __start_cc_report 07:08:57 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1989 07:08:57 INFO - PROCESS | 1671 | __end_cc_report 07:08:57 INFO - PROCESS | 1671 | __startTimestamp1465222137707__endTimestamp 07:08:57 INFO - PROCESS | 1671 | 07:08:57 INFO - PROCESS | 1671 | ------- Summary: start ------- 07:08:57 INFO - PROCESS | 1671 | Number of tests: 14 07:08:57 INFO - PROCESS | 1671 | 07:08:57 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:116.70 Median:114.00 stddev:6.85 (6.0%) stddev-sans-first:7.19 07:08:57 INFO - PROCESS | 1671 | Values: 114.0 114.0 123.0 114.0 111.0 113.0 114.0 114.0 116.0 134.0 07:08:57 INFO - PROCESS | 1671 | 07:08:57 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:150.30 Median:158.50 stddev:17.46 (11.0%) stddev-sans-first:18.23 07:08:57 INFO - PROCESS | 1671 | Values: 159.0 146.0 161.0 165.0 140.0 174.0 126.0 155.0 158.0 119.0 07:08:57 INFO - PROCESS | 1671 | 07:08:57 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:184.60 Median:185.00 stddev:1.90 (1.0%) stddev-sans-first:1.80 07:08:57 INFO - PROCESS | 1671 | Values: 187.0 184.0 184.0 185.0 180.0 184.0 185.0 185.0 186.0 186.0 07:08:57 INFO - PROCESS | 1671 | 07:08:57 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:79.90 Median:61.00 stddev:34.43 (56.4%) stddev-sans-first:36.29 07:08:57 INFO - PROCESS | 1671 | Values: 69.0 79.0 75.0 150.0 56.0 57.0 57.0 65.0 136.0 55.0 07:08:57 INFO - PROCESS | 1671 | 07:08:57 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:97.70 Median:79.00 stddev:24.75 (31.3%) stddev-sans-first:26.17 07:08:57 INFO - PROCESS | 1671 | Values: 92.0 93.0 143.0 77.0 79.0 84.0 128.0 79.0 77.0 125.0 07:08:57 INFO - PROCESS | 1671 | 07:08:57 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:86.20 Median:86.00 stddev:1.62 (1.9%) stddev-sans-first:1.58 07:08:57 INFO - PROCESS | 1671 | Values: 88.0 85.0 87.0 85.0 89.0 85.0 88.0 85.0 85.0 85.0 07:08:57 INFO - PROCESS | 1671 | 07:08:57 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:92.80 Median:93.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 07:08:57 INFO - PROCESS | 1671 | Values: 92.0 94.0 93.0 93.0 92.0 93.0 93.0 93.0 93.0 92.0 07:08:57 INFO - PROCESS | 1671 | 07:08:57 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:86.00 stddev:20.50 (23.8%) stddev-sans-first:18.74 07:08:57 INFO - PROCESS | 1671 | Values: 125.0 142.0 87.0 86.0 85.0 87.0 87.0 86.0 85.0 84.0 07:08:57 INFO - PROCESS | 1671 | 07:08:57 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:64.90 Median:65.00 stddev:7.23 (11.1%) stddev-sans-first:7.53 07:08:57 INFO - PROCESS | 1671 | Values: 61.0 65.0 63.0 63.0 65.0 66.0 65.0 84.0 59.0 58.0 07:08:57 INFO - PROCESS | 1671 | 07:08:57 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:69.40 Median:69.00 stddev:5.64 (8.2%) stddev-sans-first:5.75 07:08:57 INFO - PROCESS | 1671 | Values: 65.0 65.0 62.0 82.0 75.0 70.0 69.0 68.0 69.0 69.0 07:08:57 INFO - PROCESS | 1671 | 07:08:57 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:80.40 Median:80.00 stddev:1.71 (2.1%) stddev-sans-first:1.81 07:08:57 INFO - PROCESS | 1671 | Values: 80.0 79.0 80.0 81.0 80.0 80.0 80.0 79.0 80.0 85.0 07:08:57 INFO - PROCESS | 1671 | 07:08:57 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:133.10 Median:129.00 stddev:11.43 (8.9%) stddev-sans-first:12.06 07:08:57 INFO - PROCESS | 1671 | Values: 130.0 128.0 133.0 128.0 126.0 127.0 124.0 127.0 150.0 158.0 07:08:57 INFO - PROCESS | 1671 | 07:08:57 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.60 Median:165.00 stddev:5.13 (3.1%) stddev-sans-first:5.43 07:08:57 INFO - PROCESS | 1671 | Values: 166.0 162.0 162.0 160.0 164.0 167.0 164.0 174.0 175.0 162.0 07:08:57 INFO - PROCESS | 1671 | 07:08:57 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 07:08:57 INFO - PROCESS | 1671 | Values: 67.0 67.0 67.0 67.0 67.0 68.0 67.0 68.0 66.0 66.0 07:08:57 INFO - PROCESS | 1671 | -------- Summary: end -------- 07:08:57 INFO - PROCESS | 1671 | 07:08:58 INFO - TEST-INFO | 1671: exit 0 07:08:58 INFO - TEST-OK | kraken | took 58291ms 07:08:58 INFO - SUITE-END | took 1172s 07:08:58 INFO - Completed test suite (00:19:32) 07:08:58 INFO - javascript benchmark 07:08:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3294.505824126381, 3370.7471939604534, 3371.1066567359653, 3397.0, 3449.793012419255, 5504.146857237125, 5524.861878453041, 5545.750441230144, 5555.972220138894, 5570.1923221132965, 5527.55779537637, 5570.721463926808, 5573.470520300565, 5595.972020139904, 5637.3235211774645, 4601.769911504428, 4889.217725163974, 5049.520295571933, 5132.101882170633, 5137.845864624067, 5103.311053007834, 5192.169252919533, 5202.661826981253, 5211.0, 5213.191955246943, 4306.870793876189, 5080.568151707091, 5127.564157046656, 5172.094883395391, 5175.197844334115, 5180.041692286939, 5203.557697595709, 5213.739313034371, 5214.113600687892, 5225.242339860718, 5029.522195391469, 5052.69683818972, 5125.461826508237, 5145.691258524501, 5154.896902061938, 5345.946540534623, 5380.112281473552, 5395.78416863325, 5419.024575576394, 5457.263269458612, 6057.394260573908, 6067.484263837579, 6076.848078798022, 6088.6042407243385, 6109.847253818646, 5984.102384642295, 6097.725602347905, 6102.816915492543, 6205.6897155142515, 6206.875862482771, 5665.263515742927, 5669.31968163823, 5678.9432105678825, 5707.001274776981, 5735.4551317624755, 6258.0, 6287.9056814147825, 6307.95918673423, 6308.653024083627, 6323.5573509854785, 5867.882642347215, 5969.910451343233, 5975.25309336333, 6024.4879185268865, 6026.819195424145, 6070.7875224366935, 6074.331823499411, 6104.0, 6185.381461853869, 6202.81391558254, 6030.788922387783, 6032.969835150884, 6039.939600604026, 6050.48570871472, 6064.787732429344, 6000.279966404059, 6004.369541198199, 6012.669303188367, 6079.969600151971, 6086.0, 4738.810447582127, 4796.448408433005, 4812.205986012339, 4813.181759100961, 4820.68665536739, 4351.978240108842, 4379.824807007684, 4413.801378937956, 4420.491643461043, 4422.690411671152, 5459.918101228484, 5519.724013799294, 5543.362513311021, 5548.611597188239, 5587.245721827582, 6005.939940600625, 6103.481204097701, 6114.327423983358, 6119.877602448015, 6141.355157708376, 5406.02691515531, 5474.343078830566, 5483.150111732688, 5490.890182196334, 5499.532539734168, 5195.480451954774, 5245.921311180336, 5277.076511610453, 5277.9472205278225, 5292.338457692788, 6247.718852651642, 6256.999999999909, 6263.404976527223, 6266.968665156645, 6281.0, 6086.786962456293, 6118.908216376669, 6127.14220009208, 6145.446909778141, 6149.754009839735, 6157.384261573807, 6221.96889015552, 6309.463695585927, 6310.368963103653, 6315.147455093504, 5059.215821547666, 5997.940020599738, 6030.065339872327, 6033.577649564489, 6052.122442245938, 5996.1005849122985, 6097.878042439215, 6109.2974307955155, 6133.417325354084, 6142.692865356804, 4941.975290123526, 5846.5615078870105, 5871.412858714094, 5919.733611987384, 5970.880582388415, 4997.225430058346, 5184.600157957255, 5806.709664516842, 5832.62087964281, 5896.086106653475, 5600.747966341443, 5652.519535839499, 5716.02827519331, 5742.0, 5752.712364381848, 5936.614120082181, 5960.046392577166, 5960.791372301948, 5976.491998180029, 5990.071538911476, 5834.737436815439, 5838.795642152504, 5839.153322768259, 5861.501772349398, 5877.764889404376], "value": 5554.845434571857, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [526.5997841640352, 535.4351159526698, 543.9646422982507, 547.5099785691806, 593.9346671866097, 30115.64479598418, 37399.81300093499, 38368.61631383689, 38439.23121537568, 38515.807420962854, 40340.99147521314, 40595.79702101493, 40607.0, 40627.0, 40630.984225394306, 8276.213759692819, 8397.6221070052, 9207.999999999984, 9429.858552121723, 9446.527673616327, 11228.270162439456, 11323.490442930088, 11346.886531134707, 11350.20548561601, 11360.772784544304, 9696.515174241295, 11149.442527873574, 11214.215004949658, 11234.887651123507, 11238.269512481704, 8410.074891761931, 11167.609133680322, 11385.430728463543, 11537.423128843524, 11540.942295288554, 11085.556577736881, 11198.272112312756, 11206.887931120666, 11210.775784484345, 11212.831807522894, 37300.44049339235, 37711.24577508462, 37764.81117594422, 37802.62197378046, 37918.67284645052, 2661.308059904419, 3089.258577941278, 3221.0, 3254.5280934264397, 3343.699067083974, 25870.741292587023, 26219.344516387053, 26277.686115694138, 26530.867345663148, 27327.726722732717, 2725.6184134221226, 2749.8350098994124, 2758.4896794093156, 2788.7490125888567, 2841.0340484235458, 330265.0, 330497.0, 330831.0, 332686.336568318, 391357.04321478494, 59708.70145649244, 60096.098558521655, 60259.397406025375, 60373.69813150906, 60469.69765151146, 63102.053469198, 63262.0, 63274.0, 63391.68304158542, 63595.68202158959, 16404.179791010403, 16926.0, 16990.830091698925, 17026.23381947815, 17065.317387304618, 11520.88479115198, 11699.2980421175, 11706.531738730355, 11708.414579271002, 11708.765824683458, 14506.782398264035, 16544.586385340222, 16579.0, 16684.416045438353, 16700.997940123387, 6119.143319935212, 6272.278687950944, 6982.092327997328, 7134.464915131386, 7239.384652304613, 940.9529523523796, 949.9050094990583, 1064.3347907557777, 1108.174410064491, 1146.5070019891527, 29808.850955745515, 30870.38259234803, 31908.680913191034, 32411.51382729261, 32575.51136732951, 1112.5994641928899, 1119.2556949628406, 1171.46698252295, 1172.7185475485992, 1192.3025030357337, 14379.137251764927, 14645.560633181021, 14824.777628335583, 14893.404263829325, 14962.925185374004, 608301.0, 739887.0, 741655.0, 744643.0, 1015750.0, 58534.0, 58673.13268673098, 59596.40403595995, 59598.40401596015, 59671.0, 1435.6913263648157, 1453.876420504269, 1580.6680597074512, 1583.1846599001524, 1603.2384617306918, 24250.64316056043, 30578.932673899028, 30684.0, 30721.385572288877, 31200.531992019685, 52463.73768131135, 53183.73408132935, 53192.46807531875, 53269.46730532645, 53444.73277633587, 476760.0, 493516.0, 494180.0, 494292.0, 494505.10989780724, 35849.92450226497, 42579.0, 42917.0, 43504.564954350055, 44099.77950110357, 43793.34309985289, 44260.0, 44441.777791110835, 45445.09109817851, 45525.634230973126, 35381.82309088438, 35763.64236357603, 36957.63042369649, 37581.4362784553, 37626.0, 35624.287514250085, 35933.281334372645, 36788.63211367852, 37136.44295335518, 37923.81038094792, 35871.64128358683, 36094.63905360913, 36151.91544253677, 37750.811245943594, 38292.234155317296, 25110.62334064954, 30815.075547733602, 31184.908528201406, 31254.90607828715, 31269.687303127586, 23878.664109779813, 24030.51938961246, 24648.753512465362, 24796.909054884232, 24913.377165571004], "value": 19864.612559389716, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.1598826476136, 399.9100202454448, 401.57433120891875, 407.5069166308766, 415.1240881739529, 14676.0, 14959.401623935057, 19972.90013549932, 20635.587288254224, 20674.89662551689, 11844.881551184508, 12260.81608775869, 19485.707714384294, 20166.19335226589, 20238.494037649067, 5377.139657654766, 5520.144377621455, 5573.972130139344, 5580.274564306635, 5582.441755824426, 5455.990641731292, 5468.56251499881, 5963.8210853674245, 6095.238095238095, 6195.5663103582765, 6122.326544080148, 6126.26484821822, 6158.538109641772, 6159.35326790685, 6166.260048794151, 5905.173275741399, 6053.152558641794, 6124.724387402578, 6135.049067394539, 6138.69306534676, 5407.161889907032, 5423.5932305076885, 5425.240466334677, 5426.0, 5431.8098866539485, 13201.933990330082, 13805.723885522233, 13817.861821381859, 13818.170909745448, 16364.772642051894, 2168.186929901287, 2441.0, 2534.6958364996135, 2581.690197176351, 2830.6461692288462, 9983.351082179635, 10271.537780799881, 10885.564577416893, 11366.772664546663, 15298.694026119416, 1706.9573260668458, 1708.700977328975, 1724.8102708702033, 1770.8937463752216, 1822.2528763207022, 84786.0, 94407.0, 102966.0, 105836.88326233432, 113106.43446782796, 9588.0, 10302.742431439274, 11296.830547541793, 11619.07047436203, 11728.589499367652, 14940.850591494163, 15005.099694018394, 15011.024283421542, 15017.924910375377, 15034.09795412257, 7322.816929576803, 8585.527795971282, 8706.564671766386, 8706.651733930572, 8842.955785221162, 8080.474769139987, 8681.956590217009, 8698.869516957124, 8704.477731336014, 8740.300775937909, 4673.95326046742, 4700.388949436619, 4700.858974230779, 4714.504976977437, 4734.431868175772, 1943.679292916653, 1980.6236815005102, 1996.830269427086, 2023.2817349840595, 2033.5119571303076, 799.9560024198609, 944.447498213539, 945.9432434053979, 976.4190306767462, 1026.071405378134, 19711.704324435144, 20201.59596808085, 21186.258480953093, 22232.555348892933, 22318.442038948833, 980.8773903262093, 1009.6062535611118, 1015.664830605913, 1037.2894567221476, 1070.946452677363, 10791.0, 11036.454896314523, 11038.724031899106, 11116.943890330405, 11117.555297787998, 634716.0, 644240.0, 651155.0, 657874.0, 660962.0, 87266.0, 120507.0, 125151.0, 125655.0, 128236.0, 1070.2401295080424, 1070.7376692710336, 1077.2513103393067, 1102.2559772153763, 1109.2955972957068, 16017.839821601634, 17113.572160696083, 17463.47609571715, 17967.371142009968, 17978.640427191644, 52153.217701735266, 52321.0, 53517.197242040624, 53764.0, 54642.72678636581, 72103.0, 74999.0, 76434.23565764271, 77551.61224193843, 79454.6027269883, 7733.0, 7996.200379961957, 8119.0, 8219.465734727224, 8243.887075245006, 12200.084993625336, 12725.300108494122, 12838.229706217657, 13080.542181023618, 13119.344032798514, 9516.429014259167, 9714.931357550806, 11015.339079655247, 11247.156463265124, 11258.21192516516, 10989.120870330195, 11087.611933582286, 11150.940660637227, 11303.078172730371, 11427.971482566276, 11443.9128282813, 11652.53389864396, 11668.591599293984, 11829.935305822517, 11912.583059592873, 8205.79485512843, 8366.079731229682, 8419.410641255054, 8470.237678608955, 8682.43564168327, 10252.692419227435, 11365.31808091517, 11598.304101753922, 11649.59226427071, 11726.0], "value": 9416.833334422043, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1450.535828534868, 1569.7802307676923, 1598.336690273536, 1852.5276054606074, 1977.5847072114855, 7742.80642983925, 8424.578771061446, 8653.95673021634, 8663.39356245063, 8666.826663466727, 8355.91644083559, 8623.180797824196, 8626.439281446701, 8630.65477380904, 8637.438566493189, 2164.9783502165014, 2354.6232602783557, 2414.4567472318763, 2452.190777043576, 2478.6901637295337, 2463.8398504097268, 2475.5667758142254, 2476.418041760185, 2477.938051548708, 2481.2680259323542, 1951.9804801952012, 2394.6886904702365, 2449.289705985262, 2449.730529641738, 2453.1414005098163, 2156.7196264485674, 2383.7258715247794, 2434.2088821133116, 2441.3164313991933, 2444.6088625819784, 2413.963790543143, 2440.0727723465147, 2446.804255659543, 2450.497647982177, 2454.6686197363306, 8556.957215213946, 8619.827603447897, 8622.611982460805, 8625.051244363114, 8633.13668633138, 5130.743462826881, 5364.678119312854, 5366.517013468807, 5370.275012873251, 5376.946230537723, 3543.9114022149392, 3565.6612621801146, 3567.6967457766123, 3588.5334906461994, 3627.4921510988484, 1066.1204506282347, 1075.1560025380118, 1080.983785243222, 1081.4808891732014, 1081.6592773276393, 11660.825087623693, 11699.947004769612, 11703.53185872564, 11704.297742135499, 11714.23857449263, 4701.835435759732, 4711.411073615798, 4741.431028276629, 4783.234682450791, 4827.734474603931, 9899.059589339002, 9906.05892440217, 9906.851397229048, 9917.801643967081, 9925.950370248103, 5353.0, 5380.408155102936, 5386.0, 5391.838244852661, 5397.973010135002, 4071.6539094176733, 4325.329573916048, 4444.911101777946, 4869.977304766039, 4875.902481950341, 3941.546722126992, 3946.427767973628, 3995.081131339808, 4046.5346485154582, 4051.210014047291, 1329.6808765896114, 1337.752515784583, 1338.136901698399, 1350.9797353039712, 1351.7634413977514, 928.8095940332215, 935.9766005849908, 942.8491441369348, 955.8948515663271, 979.1823827104305, 5858.736356863951, 5977.820665380045, 6002.879942401128, 6008.459238668541, 6144.569880108439, 460.59927862759616, 466.23770135828164, 472.89596288816404, 474.05662731164733, 485.1509857748939, 3887.3391523441082, 3890.4747859039235, 3961.6830653547645, 4018.618231268025, 4095.7542547447247, 10024.448655324028, 10616.150707943347, 10623.36259824413, 10711.517981690688, 10722.303050301705, 10121.797564048826, 10403.94798026005, 10445.373277603216, 10485.737856553647, 10553.736156596147, 8066.596670166468, 8193.959030204811, 8264.586770661443, 8273.710420135267, 8325.875111873205, 5515.58633102527, 5660.094384898396, 5674.092145256739, 5799.188113664175, 5811.157382179645, 9166.724998250063, 9212.677556285498, 9437.764055898657, 9464.858027129461, 9516.571754271214, 9562.521873906417, 9782.168515676247, 9845.261605379483, 9918.801623967625, 9944.35361701487, 5579.972100139474, 6230.501559875109, 6780.559263647847, 7459.104907411145, 7510.774676759706, 7056.259092795182, 8110.229528194812, 8115.594220288844, 8116.391270654606, 8334.749957501284, 6995.160580730345, 7150.606716630636, 7152.034475345762, 7220.675069621985, 7290.635468226674, 7041.929580704333, 7068.222495525591, 7071.858562828817, 7169.677364518505, 7201.207867134715, 6613.272540020497, 6955.026296318419, 6995.160580730141, 7019.333163349474, 7039.612821294878, 4895.828645997373, 4950.801967921243, 4951.5048495150195, 5052.292679025013, 5077.0, 4953.0, 4957.603391728725, 4975.626827987843, 5016.54851063406, 5206.297149884868], "value": 4485.896905492476, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [288.44762280233357, 324.40471734367435, 328.85201659253335, 334.52664479761154, 338.53789577227093, 6647.966760166199, 6656.467482601386, 6755.459563234942, 6757.155355580552, 6764.1882974043065, 5692.658440493573, 6638.369354911276, 6639.402453779159, 6673.833154171136, 6685.398314151726, 4195.916081678364, 4526.97736511317, 4779.498152693969, 4833.347498087748, 4925.630577706668, 4863.781129849148, 4905.656604037718, 4907.337509436234, 4915.508449155073, 4927.211646136619, 4771.141194584973, 4909.484504127069, 4916.287138364935, 4917.21324588066, 4920.876978075541, 4649.0, 4714.316424118484, 4849.927251091236, 4914.0, 4914.5085491450745, 3460.8269586520573, 4172.415861779354, 4175.081482073969, 4204.558521355275, 4210.51579068408, 6201.286852012031, 6259.6870156492, 6289.937100629027, 6297.96851015742, 6309.684515774239, 5826.766929322822, 5827.912581311284, 5837.124431335291, 5837.620554663933, 5841.620294680833, 7064.576125432439, 7083.539569927938, 7085.787426377217, 7096.822579435504, 7107.253738357502, 4556.316552517116, 4700.271457924027, 4725.716457012591, 4732.503087175865, 4734.786934587952, 7351.044364232669, 7418.0, 7471.2155223701275, 7477.21489243627, 7477.364424023965, 4826.758662066883, 4830.082284365959, 4834.129856625841, 4835.854924352275, 4844.539768721966, 4240.809163587646, 4808.230683090689, 4884.682495637772, 4899.1426500362295, 4900.411950565884, 5356.955393698191, 5617.185508101303, 5857.384974577693, 5863.709983803642, 5866.624017273011, 5581.553475721932, 7144.785656430316, 7151.356377926011, 7153.535020223669, 7185.6766445510075, 1696.720041193214, 1712.803027651836, 1721.8106008339073, 1725.8015328237163, 1727.75811386406, 1041.7656027393775, 1094.9871368983659, 1101.3612104979093, 1125.431657013203, 1130.8190689489643, 5198.0, 6470.805875823638, 6570.638614876228, 6592.077109204715, 6625.138731964814, 6354.586951848115, 7096.609686467294, 7424.071991001125, 7430.0, 7437.51656142349, 6633.0, 6634.800955971328, 6648.335166483313, 6650.634215118215, 6651.401373876374, 3888.4556162137324, 3934.9803250984132, 3941.0, 3950.367941129463, 3964.960350396517, 7541.132769731441, 7808.375329973588, 7816.765497035098, 7829.530228186213, 7832.490888092256, 7643.5796031218815, 7664.233576642403, 7682.423818213544, 7688.423368247292, 7707.807304817425, 7812.828075788563, 7813.453058285979, 7826.921730782619, 7829.64766585505, 7871.881921771178, 7116.679749411393, 7374.483786134919, 7389.815254618463, 7397.482176247611, 7420.109586849612, 7256.637168141678, 7286.7085316586745, 7294.197638259893, 7315.231900650355, 7320.304571065741, 7788.88316675239, 7810.179931107152, 7821.178776228414, 7827.412944029106, 7845.607719613882, 4839.491853355347, 4852.781624826821, 4853.39332583427, 4856.757162141808, 4858.05267972742, 7017.789466316019, 7320.487565870338, 7331.816704582215, 7338.522996005243, 7339.9266007341375, 7034.718611255697, 7116.146062472537, 7144.571325720473, 7145.1425828900865, 7158.176809666955, 5113.693178409308, 7070.7525236616475, 7085.256048114874, 7090.680919358744, 7129.215786263403, 6958.512904096665, 6959.826004349932, 6963.0, 6965.233824279224, 6971.442284617117, 6837.521373503807, 7119.964400177966, 7125.714971401086, 7166.677499512639, 7196.676149573177, 6662.633555154319, 6742.460603151834, 6881.690323935535, 7038.683259253244, 7053.400460960671], "value": 5203.788049415289, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [681.4820736240457, 694.4790907911673, 720.8646381948429, 751.4476859508264, 765.5445010218921, 14906.403743850247, 15314.310856011478, 15335.539933802003, 15340.693186136285, 15349.769753453707, 15180.468683596077, 15627.921860390683, 15630.983986040928, 15643.0, 15722.764158537631, 759.2104211619909, 766.1074847802331, 917.9173874351324, 930.4277869110482, 942.688912658821, 935.1957316707626, 953.980920381592, 954.2080073538946, 954.4607296877277, 954.8138113067954, 981.7103954333488, 997.3616885193481, 1000.6597756762699, 1003.5634498992926, 1004.7036124343336, 787.3779714025941, 974.181687382595, 982.0425085541611, 993.6124911284569, 994.8507723841409, 733.6331834082958, 740.0157790139102, 740.6629983357559, 741.4290995933102, 742.6695120671303, 14939.0, 15023.849761502355, 15035.323410446446, 15044.473443429537, 15052.774208386883, 12206.267623942593, 12316.014718822562, 12343.567975120917, 12434.0, 12592.622221333375, 8722.781930451725, 9996.250281228937, 10106.989301069907, 10205.489725513695, 10289.022542858416, 3433.484977253432, 3445.896623101311, 3488.1977145256733, 3559.911002224965, 3566.32239874423, 75360.0, 75442.0, 75559.62220188863, 76189.0, 76398.99999999945, 9352.251819854395, 9374.62501499946, 9402.294827887936, 9497.810043799223, 10072.0, 20817.68773468399, 20823.479413014797, 20828.895855520626, 20865.58268834615, 20917.686234706493, 7232.312930271616, 7259.382952449101, 7340.779776606711, 7341.265873412616, 7573.242675732493, 4080.510338759368, 4147.108371700038, 4228.746275223496, 4259.574042595716, 4416.712913660602, 3591.2099338145567, 3659.0852286928266, 3662.212624285791, 3682.576503702055, 3683.839590528975, 621.9315875253718, 643.5141468191486, 646.5861848417017, 647.7279542592123, 649.7530938243438, 538.8006437618028, 575.928772483181, 607.1530215349562, 613.7176898626682, 629.8362425769242, 7967.8406431871035, 8205.917940820635, 8256.545889975989, 8264.0, 8313.45962512447, 454.2799662534853, 574.7528562717988, 595.4640823259101, 608.9421504957111, 613.5183880653755, 3747.643973822537, 3775.8867233983024, 3799.3920972644246, 3831.8658846940225, 3872.9031774205305, 82711.5864420674, 86214.56892715496, 86717.5664121688, 86801.0, 86831.56584217038, 70090.64954675194, 72117.63941180261, 72262.63868680623, 73098.0, 73228.26771732321, 64811.67594161999, 64851.67574162099, 65098.67450662716, 65361.0, 66577.33422665905, 7614.5811980339395, 7651.768672070642, 7718.807029824299, 7757.612119394121, 7770.339521140766, 47620.28569571528, 47845.521544784104, 48161.0, 48272.758636208, 48725.78185545248, 64601.70796584136, 65742.34257657362, 65862.34137658562, 66280.0, 67077.32922670711, 9677.661281855102, 9685.41887486753, 9869.407835529892, 10027.448490333101, 10682.0, 8750.69372571957, 10620.946895265473, 10811.405372704263, 11018.008379245906, 11860.94069529647, 10302.845457318295, 10388.42863642507, 10437.58249670035, 10461.68614941553, 10613.991670791262, 10296.897031029894, 10392.064714175458, 10392.58429662805, 10531.210159237935, 10574.471276436301, 10115.696529104138, 10154.39073655583, 10219.795604088025, 10232.795344093225, 10375.792484150426, 7604.201558836243, 8791.384603077724, 8823.69117080899, 8937.687180948635, 9142.3600347975, 8708.390412671051, 8871.245944094566, 8916.910830891868, 8966.955165224132, 8973.750830116975], "value": 6735.470355066712, "unit": "score"}], "name": "dromaeo_css", "value": 7393.873966537489, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 123.0, 114.0, 111.0, 113.0, 114.0, 114.0, 116.0, 134.0], "value": 116.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 146.0, 161.0, 165.0, 140.0, 174.0, 126.0, 155.0, 158.0, 119.0], "value": 150.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 184.0, 184.0, 185.0, 180.0, 184.0, 185.0, 185.0, 186.0, 186.0], "value": 184.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 79.0, 75.0, 150.0, 56.0, 57.0, 57.0, 65.0, 136.0, 55.0], "value": 79.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 143.0, 77.0, 79.0, 84.0, 128.0, 79.0, 77.0, 125.0], "value": 97.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 87.0, 85.0, 89.0, 85.0, 88.0, 85.0, 85.0, 85.0], "value": 86.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 93.0, 93.0, 92.0, 93.0, 93.0, 93.0, 93.0, 92.0], "value": 92.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 142.0, 87.0, 86.0, 85.0, 87.0, 87.0, 86.0, 85.0, 84.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 65.0, 63.0, 63.0, 65.0, 66.0, 65.0, 84.0, 59.0, 58.0], "value": 64.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 62.0, 82.0, 75.0, 70.0, 69.0, 68.0, 69.0, 69.0], "value": 69.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 80.0, 81.0, 80.0, 80.0, 80.0, 79.0, 80.0, 85.0], "value": 80.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 128.0, 133.0, 128.0, 126.0, 127.0, 124.0, 127.0, 150.0, 158.0], "value": 133.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 162.0, 162.0, 160.0, 164.0, 167.0, 164.0, 174.0, 175.0, 162.0], "value": 165.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 67.0, 67.0, 68.0, 67.0, 68.0, 66.0, 66.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1484.0, "alertThreshold": 2.0}]} 07:08:58 INFO - Return code: 0 07:08:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 07:08:59 ERROR - # TBPL SUCCESS # 07:08:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:08:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:08:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:08:59 INFO - 2016-06-06 07:08:59.045 screenresolution[1675:9989] starting screenresolution argv=screenresolution get 07:08:59 INFO - 2016-06-06 07:08:59.047 screenresolution[1675:9989] Display 0: 1600x1200x32@60 07:08:59 INFO - 2016-06-06 07:08:59.053 screenresolution[1676:9992] starting screenresolution argv=screenresolution list 07:08:59 INFO - Available Modes on Display 0 07:08:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:08:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:08:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:08:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:08:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:08:59 INFO - Intel Iris: 07:08:59 INFO - Chipset Model: Intel Iris 07:08:59 INFO - Type: GPU 07:08:59 INFO - Bus: Built-In 07:08:59 INFO - VRAM (Dynamic, Max): 1536 MB 07:08:59 INFO - Vendor: Intel (0x8086) 07:08:59 INFO - Device ID: 0x0a2e 07:08:59 INFO - Revision ID: 0x0009 07:08:59 INFO - Displays: 07:08:59 INFO - Display: 07:08:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:08:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:08:59 INFO - Display Serial Number: 1600x1200 60 07:08:59 INFO - Main Display: Yes 07:08:59 INFO - Mirror: Off 07:08:59 INFO - Online: Yes 07:08:59 INFO - Rotation: Supported 07:08:59 INFO - Return code: 0 07:08:59 INFO - Running post-action listener: _resource_record_post_action 07:08:59 INFO - Running post-run listener: _resource_record_post_run 07:09:00 INFO - Total resource usage - Wall time: 1206s; CPU: 53.0%; Read bytes: 36894208; Write bytes: 655234560; Read time: 246; Write time: 1875 07:09:00 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 354851328; Write bytes: 356173824; Read time: 27459; Write time: 762 07:09:00 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 35079680; Write bytes: 298212864; Read time: 230; Write time: 1110 07:09:00 INFO - Running post-run listener: _upload_blobber_files 07:09:00 INFO - Blob upload gear active. 07:09:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:09:00 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 07:09:00 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'] 07:09:00 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 07:09:00 INFO - (blobuploader) - INFO - Open directory for files ... 07:09:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:09:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:09:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:09:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:09:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:09:01 INFO - (blobuploader) - INFO - Done attempting. 07:09:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:09:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:09:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:09:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:09:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:09:02 INFO - (blobuploader) - INFO - Done attempting. 07:09:02 INFO - (blobuploader) - INFO - Iteration through files over. 07:09:02 INFO - Return code: 0 07:09:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:09:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:09:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7ddc09def6f7f6dc3d45ef731e4fc80f6db82a3d0eb4874d2254ba3b7279d1314912a5840e437db0ef1b7aa0c529aaa66dc8956e98037927da76fc09593bbcaf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c42d199714472bc6757b4283ff078abd5a45bd0f913b45d2ca28ea93c4b794172fd403ca4af908dff60236a64078117f3b02ebc03b399a9acb97cc080f782da"} 07:09:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:09:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:09:02 INFO - Contents: 07:09:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7ddc09def6f7f6dc3d45ef731e4fc80f6db82a3d0eb4874d2254ba3b7279d1314912a5840e437db0ef1b7aa0c529aaa66dc8956e98037927da76fc09593bbcaf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c42d199714472bc6757b4283ff078abd5a45bd0f913b45d2ca28ea93c4b794172fd403ca4af908dff60236a64078117f3b02ebc03b399a9acb97cc080f782da"} 07:09:02 INFO - Running post-run listener: copy_logs_to_upload_dir 07:09:02 INFO - Copying logs to upload dir... 07:09:02 INFO - mkdir: /builds/slave/test/build/upload/logs 07:09:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.428131 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-06-06 07:09:02.543946) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 07:09:02.545114) ========= 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.PrkwqTAZDH/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.N6DCQcjahu/Listeners TMPDIR=/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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/7ddc09def6f7f6dc3d45ef731e4fc80f6db82a3d0eb4874d2254ba3b7279d1314912a5840e437db0ef1b7aa0c529aaa66dc8956e98037927da76fc09593bbcaf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c42d199714472bc6757b4283ff078abd5a45bd0f913b45d2ca28ea93c4b794172fd403ca4af908dff60236a64078117f3b02ebc03b399a9acb97cc080f782da"} build_url:https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011130 build_url: 'https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/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/7ddc09def6f7f6dc3d45ef731e4fc80f6db82a3d0eb4874d2254ba3b7279d1314912a5840e437db0ef1b7aa0c529aaa66dc8956e98037927da76fc09593bbcaf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c42d199714472bc6757b4283ff078abd5a45bd0f913b45d2ca28ea93c4b794172fd403ca4af908dff60236a64078117f3b02ebc03b399a9acb97cc080f782da"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 07:09:02.585048) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-06-06 07:09:02.585426) ========= 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.PrkwqTAZDH/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.N6DCQcjahu/Listeners TMPDIR=/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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.004997 ========= master_lag: 1.98 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-06-06 07:09:04.573468) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 07:09:04.573846) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 07:09:04.579507) ========= ========= Total master_lag: 2.14 =========