builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0181 starttime: 1465206992.58 results: success (0) buildid: 20160606010529 builduid: 78750d9664274d05bee96204469a3a74 revision: d8adb38dd576398dacbfb96d2c247f8e0158bdf1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 02:56:32.577554) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 02:56:32.577957) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 02:56:32.578242) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 02:56:32.601703) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 02:56:32.602018) ========= 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.XDghxrnrIf/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.H1SApKv8nz/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 02:56:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 16.1M=0.001s 2016-06-06 02:56:32 (16.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.134756 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 02:56:32.757937) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 02:56:32.758259) ========= 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.XDghxrnrIf/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.H1SApKv8nz/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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.027313 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 02:56:32.812371) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 02:56:32.812754) ========= 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.XDghxrnrIf/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.H1SApKv8nz/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 02:56:32,885 truncating revision to first 12 chars 2016-06-06 02:56:32,885 Setting DEBUG logging. 2016-06-06 02:56:32,886 attempt 1/10 2016-06-06 02:56:32,886 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 02:56:33,516 unpacking tar archive at: mozilla-aurora-d8adb38dd576/testing/mozharness/ program finished with exit code 0 elapsedTime=0.888845 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 02:56:33.722059) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 02:56:33.722469) ========= script_repo_revision: d8adb38dd576398dacbfb96d2c247f8e0158bdf1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 02:56:33.722953) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 02:56:33.723277) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 02:56:33.740148) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-06-06 02:56:33.740440) ========= /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.XDghxrnrIf/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.H1SApKv8nz/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 02:56:33 INFO - MultiFileLogger online at 20160606 02:56:33 in /builds/slave/test 02:56:33 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 02:56:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:56:33 INFO - {'append_to_log': False, 02:56:33 INFO - 'base_work_dir': '/builds/slave/test', 02:56:33 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:56:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:56:33 INFO - 'branch': 'Mozilla-Aurora', 02:56:33 INFO - 'buildbot_json_path': 'buildprops.json', 02:56:33 INFO - 'config_files': ('talos/mac_config.py',), 02:56:33 INFO - 'default_actions': ('clobber', 02:56:33 INFO - 'read-buildbot-config', 02:56:33 INFO - 'download-and-extract', 02:56:33 INFO - 'populate-webroot', 02:56:33 INFO - 'create-virtualenv', 02:56:33 INFO - 'install', 02:56:33 INFO - 'run-tests'), 02:56:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:56:33 INFO - 'download_minidump_stackwalk': True, 02:56:33 INFO - 'download_symbols': 'ondemand', 02:56:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:56:33 INFO - 'tooltool.py': '/tools/tooltool.py', 02:56:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:56:33 INFO - '/tools/misc-python/virtualenv.py')}, 02:56:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:56:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:56:33 INFO - 'installer_path': 'installer.exe', 02:56:33 INFO - 'log_level': 'info', 02:56:33 INFO - 'log_name': 'talos', 02:56:33 INFO - 'log_to_console': True, 02:56:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:56:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:56:33 INFO - 'opt_config_files': (), 02:56:33 INFO - 'pip_index': False, 02:56:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:56:33 INFO - 'cmd': ('bash', 02:56:33 INFO - '-c', 02:56:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:56:33 INFO - 'enabled': True, 02:56:33 INFO - 'halt_on_failure': False, 02:56:33 INFO - 'name': 'check_screen_resolution'},), 02:56:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:56:33 INFO - 'cmd': ('bash', 02:56:33 INFO - '-c', 02:56:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:56:33 INFO - 'enabled': True, 02:56:33 INFO - 'halt_on_failure': False, 02:56:33 INFO - 'name': 'check_screen_resolution'},), 02:56:33 INFO - 'run_cmd_checks_enabled': True, 02:56:33 INFO - 'sps_profile': False, 02:56:33 INFO - 'sps_profile_interval': 0, 02:56:33 INFO - 'suite': 'dromaeojs', 02:56:33 INFO - 'system_bits': '32', 02:56:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:56:33 INFO - 'title': 't-yosemite-r7-0181', 02:56:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:56:33 INFO - 'use_talos_json': True, 02:56:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:56:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:56:33 INFO - 'work_dir': 'build'} 02:56:33 INFO - ##### 02:56:33 INFO - ##### Running clobber step. 02:56:33 INFO - ##### 02:56:33 INFO - Running pre-action listener: _resource_record_pre_action 02:56:33 INFO - Running main action method: clobber 02:56:33 INFO - rmtree: /builds/slave/test/build 02:56:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:56:35 INFO - Running post-action listener: _resource_record_post_action 02:56:35 INFO - ##### 02:56:35 INFO - ##### Running read-buildbot-config step. 02:56:35 INFO - ##### 02:56:35 INFO - Running pre-action listener: _resource_record_pre_action 02:56:35 INFO - Running main action method: read_buildbot_config 02:56:35 INFO - Using buildbot properties: 02:56:35 INFO - { 02:56:35 INFO - "project": "", 02:56:35 INFO - "product": "firefox", 02:56:35 INFO - "script_repo_revision": "production", 02:56:35 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:56:35 INFO - "repository": "", 02:56:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:56:35 INFO - "buildid": "20160606010529", 02:56:35 INFO - "pgo_build": "False", 02:56:35 INFO - "basedir": "/builds/slave/test", 02:56:35 INFO - "buildnumber": 16, 02:56:35 INFO - "slavename": "t-yosemite-r7-0181", 02:56:35 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 02:56:35 INFO - "platform": "yosemite_r7", 02:56:35 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:56:35 INFO - "branch": "mozilla-aurora", 02:56:35 INFO - "repo_path": "releases/mozilla-aurora", 02:56:35 INFO - "revision": "d8adb38dd576398dacbfb96d2c247f8e0158bdf1", 02:56:35 INFO - "stage_platform": "macosx64", 02:56:35 INFO - "builduid": "78750d9664274d05bee96204469a3a74", 02:56:35 INFO - "slavebuilddir": "test" 02:56:35 INFO - } 02:56:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 02:56:35 INFO - Running post-action listener: _resource_record_post_action 02:56:35 INFO - ##### 02:56:35 INFO - ##### Running download-and-extract step. 02:56:35 INFO - ##### 02:56:35 INFO - Running pre-action listener: _resource_record_pre_action 02:56:35 INFO - Running main action method: download_and_extract 02:56:35 INFO - mkdir: /builds/slave/test/build/tests 02:56:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:56:35 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 02:56:35 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 02:56:35 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 02:56:35 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 02:56:35 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 02:56:38 INFO - Downloaded 1199 bytes. 02:56:38 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:56:38 INFO - Using the following test package requirements: 02:56:38 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:56:38 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:56:38 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 02:56:38 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:56:38 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 02:56:38 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:56:38 INFO - u'jsshell-mac.zip'], 02:56:38 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:56:38 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 02:56:38 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:56:38 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:56:38 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 02:56:38 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:56:38 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 02:56:38 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:56:38 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 02:56:38 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:56:38 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 02:56:38 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:56:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:56:38 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 02:56:38 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 02:56:38 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 02:56:38 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 02:56:38 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 02:56:40 INFO - Downloaded 18466859 bytes. 02:56:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:56:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:56:43 INFO - Return code: 0 02:56:43 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 02:56:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:56:43 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 02:56:43 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 02:56:43 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 02:56:43 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 02:56:43 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 02:56:45 INFO - Downloaded 18466859 bytes. 02:56:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:56:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:56:49 INFO - Return code: 0 02:56:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:56:49 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 02:56:49 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 02:56:49 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 02:56:49 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 02:56:49 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 02:56:50 INFO - Downloaded 10840145 bytes. 02:56:50 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'] 02:56:50 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 02:56:50 INFO - Return code: 0 02:56:50 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')]} 02:56:50 INFO - https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 02:56:50 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 02:56:50 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 02:56:50 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 02:56:50 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 02:56:57 INFO - Downloaded 114179823 bytes. 02:56:57 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 02:56:57 INFO - mkdir: /builds/slave/test/properties 02:56:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:56:57 INFO - Writing to file /builds/slave/test/properties/build_url 02:56:57 INFO - Contents: 02:56:57 INFO - build_url:https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:56:58 INFO - Running post-action listener: _resource_record_post_action 02:56:58 INFO - Running post-action listener: set_extra_try_arguments 02:56:58 INFO - ##### 02:56:58 INFO - ##### Running populate-webroot step. 02:56:58 INFO - ##### 02:56:58 INFO - Running pre-action listener: _resource_record_pre_action 02:56:58 INFO - Running main action method: populate_webroot 02:56:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:56:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:56:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:56:58 INFO - '--tppagecycles', 02:56:58 INFO - '7'], 02:56:58 INFO - 'tests': ['tsvgm']}}, 02:56:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:56:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:56:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:56:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:56:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:56:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:56:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:56:58 INFO - 'talos_options': ['--disable-e10s'], 02:56:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:56:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:56:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:56:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:56:58 INFO - 'talos_options': ['--disable-e10s'], 02:56:58 INFO - 'tests': ['damp', 'tps']}, 02:56:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:56:58 INFO - 'tests': ['damp', 'tps']}, 02:56:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:56:58 INFO - 'tests': ['dromaeo_dom']}, 02:56:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:56:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:56:58 INFO - 'tests': ['a11yr', 02:56:58 INFO - 'ts_paint', 02:56:58 INFO - 'tpaint', 02:56:58 INFO - 'sessionrestore', 02:56:58 INFO - 'sessionrestore_no_auto_restore', 02:56:58 INFO - 'tabpaint']}, 02:56:58 INFO - 'other-e10s': {'tests': ['a11yr', 02:56:58 INFO - 'ts_paint', 02:56:58 INFO - 'tpaint', 02:56:58 INFO - 'sessionrestore', 02:56:58 INFO - 'sessionrestore_no_auto_restore', 02:56:58 INFO - 'tabpaint']}, 02:56:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:56:58 INFO - 'ts_paint', 02:56:58 INFO - 'tpaint', 02:56:58 INFO - 'sessionrestore', 02:56:58 INFO - 'sessionrestore_no_auto_restore', 02:56:58 INFO - 'tabpaint']}, 02:56:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:56:58 INFO - 'ts_paint', 02:56:58 INFO - 'tpaint', 02:56:58 INFO - 'sessionrestore', 02:56:58 INFO - 'sessionrestore_no_auto_restore', 02:56:58 INFO - 'tabpaint']}, 02:56:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:56:58 INFO - 'tests': ['a11yr', 02:56:58 INFO - 'ts_paint', 02:56:58 INFO - 'tpaint', 02:56:58 INFO - 'sessionrestore', 02:56:58 INFO - 'sessionrestore_no_auto_restore', 02:56:58 INFO - 'tabpaint']}, 02:56:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:56:58 INFO - 'tests': ['a11yr', 02:56:58 INFO - 'ts_paint', 02:56:58 INFO - 'tpaint', 02:56:58 INFO - 'sessionrestore', 02:56:58 INFO - 'sessionrestore_no_auto_restore', 02:56:58 INFO - 'tabpaint']}, 02:56:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:56:58 INFO - 'tests': ['tsvgx', 02:56:58 INFO - 'tsvgr_opacity', 02:56:58 INFO - 'tart', 02:56:58 INFO - 'tscrollx', 02:56:58 INFO - 'cart']}, 02:56:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:56:58 INFO - 'tsvgr_opacity', 02:56:58 INFO - 'tart', 02:56:58 INFO - 'tscrollx', 02:56:58 INFO - 'cart']}, 02:56:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:56:58 INFO - 'talos_options': ['--disable-e10s'], 02:56:58 INFO - 'tests': ['tp5o']}, 02:56:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:56:58 INFO - 'tests': ['tp5o']}, 02:56:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:56:58 INFO - 'talos_options': ['--disable-e10s', 02:56:58 INFO - '--xperf_path', 02:56:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:56:58 INFO - 'tests': ['tp5n']}, 02:56:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:56:58 INFO - 'talos_options': ['--xperf_path', 02:56:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:56:58 INFO - 'tests': ['tp5n']}}, 02:56:58 INFO - 'talos.zip': {'path': '', 02:56:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:56:58 INFO - Running post-action listener: _resource_record_post_action 02:56:58 INFO - ##### 02:56:58 INFO - ##### Running create-virtualenv step. 02:56:58 INFO - ##### 02:56:58 INFO - Running pre-action listener: _resource_record_pre_action 02:56:58 INFO - Running main action method: create_virtualenv 02:56:58 INFO - Creating virtualenv /builds/slave/test/build/venv 02:56:58 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 02:56:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:56:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:56:58 INFO - Using real prefix '/tools/python27' 02:56:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:56:59 INFO - Installing distribute.............................................................................................................................................................................................done. 02:57:02 INFO - Installing pip.................done. 02:57:02 INFO - Return code: 0 02:57:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:57:02 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')]} 02:57:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:02 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')]} 02:57:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:02 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 0x105eb7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ec7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92424bb240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d56e00>, '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 0x105bbfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d51b20>, '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.XDghxrnrIf/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.H1SApKv8nz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 02:57:02 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 02:57:02 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 02:57:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XDghxrnrIf/Render', 02:57:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:02 INFO - 'HOME': '/Users/cltbld', 02:57:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:02 INFO - 'LOGNAME': 'cltbld', 02:57:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:02 INFO - 'MOZ_NO_REMOTE': '1', 02:57:02 INFO - 'NO_EM_RESTART': '1', 02:57:02 INFO - 'PAGER': '/bin/cat', 02:57:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:02 INFO - 'PWD': '/builds/slave/test', 02:57:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:02 INFO - 'SHELL': '/bin/bash', 02:57:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H1SApKv8nz/Listeners', 02:57:02 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 02:57:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:02 INFO - 'USER': 'cltbld', 02:57:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:02 INFO - 'XPC_FLAGS': '0x0', 02:57:02 INFO - 'XPC_SERVICE_NAME': '0', 02:57:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:02 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 02:57:02 INFO - Cleaning up... 02:57:02 INFO - Return code: 0 02:57:02 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:57:02 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')]} 02:57:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:02 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')]} 02:57:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:02 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 0x105eb7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ec7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92424bb240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d56e00>, '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 0x105bbfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d51b20>, '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.XDghxrnrIf/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.H1SApKv8nz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 02:57:02 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 02:57:02 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 02:57:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XDghxrnrIf/Render', 02:57:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:02 INFO - 'HOME': '/Users/cltbld', 02:57:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:02 INFO - 'LOGNAME': 'cltbld', 02:57:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:02 INFO - 'MOZ_NO_REMOTE': '1', 02:57:02 INFO - 'NO_EM_RESTART': '1', 02:57:02 INFO - 'PAGER': '/bin/cat', 02:57:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:02 INFO - 'PWD': '/builds/slave/test', 02:57:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:02 INFO - 'SHELL': '/bin/bash', 02:57:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H1SApKv8nz/Listeners', 02:57:02 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 02:57:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:02 INFO - 'USER': 'cltbld', 02:57:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:02 INFO - 'XPC_FLAGS': '0x0', 02:57:02 INFO - 'XPC_SERVICE_NAME': '0', 02:57:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:02 INFO - Downloading/unpacking psutil>=0.7.1 02:57:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:57:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:57:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:57:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:57:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:57:05 INFO - Installing collected packages: psutil 02:57:05 INFO - Running setup.py install for psutil 02:57:05 INFO - building 'psutil._psutil_osx' extension 02:57:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:57:06 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 02:57:06 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 02:57:06 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 02:57:06 INFO - building 'psutil._psutil_posix' extension 02:57:06 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 02:57:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:57:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:57:06 INFO - ^ 02:57:06 INFO - 1 warning generated. 02:57:06 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:57:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:57:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:57:07 INFO - Successfully installed psutil 02:57:07 INFO - Cleaning up... 02:57:07 INFO - Return code: 0 02:57:07 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:57:07 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')]} 02:57:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:07 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')]} 02:57:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:07 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 0x105eb7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ec7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92424bb240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d56e00>, '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 0x105bbfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d51b20>, '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.XDghxrnrIf/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.H1SApKv8nz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 02:57:07 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 02:57:07 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 02:57:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XDghxrnrIf/Render', 02:57:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:07 INFO - 'HOME': '/Users/cltbld', 02:57:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:07 INFO - 'LOGNAME': 'cltbld', 02:57:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:07 INFO - 'MOZ_NO_REMOTE': '1', 02:57:07 INFO - 'NO_EM_RESTART': '1', 02:57:07 INFO - 'PAGER': '/bin/cat', 02:57:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:07 INFO - 'PWD': '/builds/slave/test', 02:57:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:07 INFO - 'SHELL': '/bin/bash', 02:57:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H1SApKv8nz/Listeners', 02:57:07 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 02:57:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:07 INFO - 'USER': 'cltbld', 02:57:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:07 INFO - 'XPC_FLAGS': '0x0', 02:57:07 INFO - 'XPC_SERVICE_NAME': '0', 02:57:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:07 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:57:07 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 02:57:07 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 02:57:07 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 02:57:07 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 02:57:07 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 02:57:07 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 02:57:09 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:57:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:57:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:57:09 INFO - Installing collected packages: mozsystemmonitor 02:57:09 INFO - Running setup.py install for mozsystemmonitor 02:57:10 INFO - Successfully installed mozsystemmonitor 02:57:10 INFO - Cleaning up... 02:57:10 INFO - Return code: 0 02:57:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:57: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')]} 02:57:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57: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')]} 02:57:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:10 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 0x105eb7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ec7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92424bb240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d56e00>, '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 0x105bbfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d51b20>, '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.XDghxrnrIf/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.H1SApKv8nz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 02:57:10 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 02:57:10 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 02:57:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XDghxrnrIf/Render', 02:57:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:10 INFO - 'HOME': '/Users/cltbld', 02:57:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:10 INFO - 'LOGNAME': 'cltbld', 02:57:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:10 INFO - 'MOZ_NO_REMOTE': '1', 02:57:10 INFO - 'NO_EM_RESTART': '1', 02:57:10 INFO - 'PAGER': '/bin/cat', 02:57:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:10 INFO - 'PWD': '/builds/slave/test', 02:57:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:10 INFO - 'SHELL': '/bin/bash', 02:57:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H1SApKv8nz/Listeners', 02:57:10 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 02:57:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:10 INFO - 'USER': 'cltbld', 02:57:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:10 INFO - 'XPC_FLAGS': '0x0', 02:57:10 INFO - 'XPC_SERVICE_NAME': '0', 02:57:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:10 INFO - Downloading/unpacking blobuploader==1.2.4 02:57:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:57:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:57:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:12 INFO - Downloading blobuploader-1.2.4.tar.gz 02:57:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:57:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:57:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:57:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:57:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:57:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:57:13 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 02:57:13 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 02:57:13 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 02:57:13 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 02:57:13 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 02:57:13 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 02:57:13 INFO - Downloading docopt-0.6.1.tar.gz 02:57:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:57:14 INFO - Installing collected packages: blobuploader, requests, docopt 02:57:14 INFO - Running setup.py install for blobuploader 02:57:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:57:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:57:14 INFO - Running setup.py install for requests 02:57:14 INFO - Running setup.py install for docopt 02:57:14 INFO - Successfully installed blobuploader requests docopt 02:57:14 INFO - Cleaning up... 02:57:14 INFO - Return code: 0 02:57:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:57:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:57:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:57:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105eb7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ec7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92424bb240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d56e00>, '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 0x105bbfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d51b20>, '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.XDghxrnrIf/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.H1SApKv8nz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 02:57:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:57:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:57:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XDghxrnrIf/Render', 02:57:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:14 INFO - 'HOME': '/Users/cltbld', 02:57:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:14 INFO - 'LOGNAME': 'cltbld', 02:57:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:14 INFO - 'MOZ_NO_REMOTE': '1', 02:57:14 INFO - 'NO_EM_RESTART': '1', 02:57:14 INFO - 'PAGER': '/bin/cat', 02:57:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:14 INFO - 'PWD': '/builds/slave/test', 02:57:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:14 INFO - 'SHELL': '/bin/bash', 02:57:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H1SApKv8nz/Listeners', 02:57:14 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 02:57:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:14 INFO - 'USER': 'cltbld', 02:57:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:14 INFO - 'XPC_FLAGS': '0x0', 02:57:14 INFO - 'XPC_SERVICE_NAME': '0', 02:57:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:57:15 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-r8GsTT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:57:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:57:15 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-eYFW0z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:57:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:57:15 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-TJE7Iq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:57:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:57:15 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-IkOzro-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:57:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:57:15 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-0U3TjH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:57:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:57:15 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-4F12C_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:57:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:57:15 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-l5LNOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:57:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:57:15 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-opFZl0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:57:16 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-XwI8oR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:57:16 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-R4Jtn0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:57:16 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-56ipqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:57:16 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-DavF5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:57:16 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-iPswFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:57:16 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-qW95S3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:57:16 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-ciE6UA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:57:16 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-ONPvdP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:57:16 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-jffe6A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:57:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:57:16 INFO - Running setup.py install for manifestparser 02:57:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:57:17 INFO - Running setup.py install for mozcrash 02:57:17 INFO - Running setup.py install for mozdebug 02:57:17 INFO - Running setup.py install for mozdevice 02:57:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:57:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:57:17 INFO - Running setup.py install for mozfile 02:57:17 INFO - Running setup.py install for mozhttpd 02:57:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:57:17 INFO - Running setup.py install for mozinfo 02:57:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:57:17 INFO - Running setup.py install for mozInstall 02:57:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:57:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:57:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:57:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:57:18 INFO - Running setup.py install for mozleak 02:57:18 INFO - Running setup.py install for mozlog 02:57:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:57:18 INFO - Running setup.py install for moznetwork 02:57:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:57:18 INFO - Running setup.py install for mozprocess 02:57:18 INFO - Running setup.py install for mozprofile 02:57:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:57:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:57:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:57:18 INFO - Running setup.py install for mozrunner 02:57:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:57:19 INFO - Running setup.py install for mozscreenshot 02:57:19 INFO - Running setup.py install for moztest 02:57:19 INFO - Running setup.py install for mozversion 02:57:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:57:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:57:19 INFO - Cleaning up... 02:57:19 INFO - Return code: 0 02:57:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:57:19 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')]} 02:57:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:19 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')]} 02:57:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:19 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 0x105eb7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ec7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92424bb240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d56e00>, '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 0x105bbfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d51b20>, '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.XDghxrnrIf/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.H1SApKv8nz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 02:57:19 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 02:57:19 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 02:57:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XDghxrnrIf/Render', 02:57:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:19 INFO - 'HOME': '/Users/cltbld', 02:57:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:19 INFO - 'LOGNAME': 'cltbld', 02:57:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:19 INFO - 'MOZ_NO_REMOTE': '1', 02:57:19 INFO - 'NO_EM_RESTART': '1', 02:57:19 INFO - 'PAGER': '/bin/cat', 02:57:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:19 INFO - 'PWD': '/builds/slave/test', 02:57:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:19 INFO - 'SHELL': '/bin/bash', 02:57:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H1SApKv8nz/Listeners', 02:57:19 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 02:57:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:19 INFO - 'USER': 'cltbld', 02:57:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:19 INFO - 'XPC_FLAGS': '0x0', 02:57:19 INFO - 'XPC_SERVICE_NAME': '0', 02:57:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:57:19 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-gTRoo1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:57:19 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:57:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:57:19 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-L4KWam-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:57:20 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)) 02:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:57:20 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-ABsecH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:57:20 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)) 02:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:57:20 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-t6sLg6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:57:20 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)) 02:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:57:20 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-tlTWYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:57:20 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)) 02:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:57:20 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-4e4Lj_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:57:20 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)) 02:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:57:20 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-yafhC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:57:20 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)) 02:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:57:20 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-CGOQR1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:57:20 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)) 02:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:57:20 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-_u02AP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:57:20 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)) 02:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:57:20 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-0k7IQu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:57:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:57:20 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-HxN_Lx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:57:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:57:20 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-Ia1qGk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:57:21 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)) 02:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:57:21 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-mr7lXx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:57:21 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)) 02:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:57:21 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-7dQycf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:57:21 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)) 02:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:57:21 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-mN4133-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:57:21 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)) 02:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:57:21 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-vY6IhU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:57:21 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)) 02:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:57:21 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-SnFrQd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:57:21 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)) 02:57:21 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)) 02:57:21 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)) 02:57:21 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)) 02:57:21 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)) 02:57:21 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)) 02:57:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:57:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:57:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:57:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:24 INFO - Downloading blessings-1.6.tar.gz 02:57:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:57:24 INFO - Installing collected packages: blessings 02:57:24 INFO - Running setup.py install for blessings 02:57:24 INFO - Successfully installed blessings 02:57:24 INFO - Cleaning up... 02:57:24 INFO - Return code: 0 02:57:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:57:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:57:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:57:24 INFO - Reading from file tmpfile_stdout 02:57:24 INFO - Current package versions: 02:57:24 INFO - blessings == 1.6 02:57:24 INFO - blobuploader == 1.2.4 02:57:24 INFO - docopt == 0.6.1 02:57:24 INFO - manifestparser == 1.1 02:57:24 INFO - mozInstall == 1.12 02:57:24 INFO - mozcrash == 0.17 02:57:24 INFO - mozdebug == 0.1 02:57:24 INFO - mozdevice == 0.48 02:57:24 INFO - mozfile == 1.2 02:57:24 INFO - mozhttpd == 0.7 02:57:24 INFO - mozinfo == 0.9 02:57:24 INFO - mozleak == 0.1 02:57:24 INFO - mozlog == 3.1 02:57:24 INFO - moznetwork == 0.27 02:57:24 INFO - mozprocess == 0.22 02:57:24 INFO - mozprofile == 0.28 02:57:24 INFO - mozrunner == 6.11 02:57:24 INFO - mozscreenshot == 0.1 02:57:24 INFO - mozsystemmonitor == 0.0 02:57:24 INFO - moztest == 0.7 02:57:24 INFO - mozversion == 1.4 02:57:24 INFO - psutil == 3.1.1 02:57:24 INFO - requests == 1.2.3 02:57:24 INFO - wsgiref == 0.1.2 02:57:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:57: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')]} 02:57:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57: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')]} 02:57:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:24 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 0x105eb7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ec7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92424bb240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d56e00>, '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 0x105bbfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d51b20>, '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.XDghxrnrIf/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.H1SApKv8nz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 02:57:24 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 02:57:24 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 02:57:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XDghxrnrIf/Render', 02:57:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:24 INFO - 'HOME': '/Users/cltbld', 02:57:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:24 INFO - 'LOGNAME': 'cltbld', 02:57:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:24 INFO - 'MOZ_NO_REMOTE': '1', 02:57:24 INFO - 'NO_EM_RESTART': '1', 02:57:24 INFO - 'PAGER': '/bin/cat', 02:57:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:24 INFO - 'PWD': '/builds/slave/test', 02:57:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:24 INFO - 'SHELL': '/bin/bash', 02:57:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H1SApKv8nz/Listeners', 02:57:24 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 02:57:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:24 INFO - 'USER': 'cltbld', 02:57:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:24 INFO - 'XPC_FLAGS': '0x0', 02:57:24 INFO - 'XPC_SERVICE_NAME': '0', 02:57:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:57:24 INFO - Cleaning up... 02:57:24 INFO - Return code: 0 02:57:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:57: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')]} 02:57:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57: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')]} 02:57:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105eb7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ec7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92424bb240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d56e00>, '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 0x105bbfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d51b20>, '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.XDghxrnrIf/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.H1SApKv8nz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 02:57:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:57:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:57:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XDghxrnrIf/Render', 02:57:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:24 INFO - 'HOME': '/Users/cltbld', 02:57:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:24 INFO - 'LOGNAME': 'cltbld', 02:57:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:24 INFO - 'MOZ_NO_REMOTE': '1', 02:57:24 INFO - 'NO_EM_RESTART': '1', 02:57:24 INFO - 'PAGER': '/bin/cat', 02:57:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:24 INFO - 'PWD': '/builds/slave/test', 02:57:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:24 INFO - 'SHELL': '/bin/bash', 02:57:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H1SApKv8nz/Listeners', 02:57:24 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 02:57:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:24 INFO - 'USER': 'cltbld', 02:57:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:24 INFO - 'XPC_FLAGS': '0x0', 02:57:24 INFO - 'XPC_SERVICE_NAME': '0', 02:57:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:25 INFO - Downloading/unpacking jsonschema 02:57:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:57:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:57:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:57:27 INFO - Downloading/unpacking functools32 (from jsonschema) 02:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:57:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:28 INFO - Downloading functools32-3.2.3-2.tar.gz 02:57:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:57:28 INFO - warning: no files found matching '*.txt' 02:57:28 INFO - no previously-included directories found matching 'build' 02:57:28 INFO - no previously-included directories found matching 'dist' 02:57:28 INFO - no previously-included directories found matching '.git*' 02:57:28 INFO - Installing collected packages: jsonschema, functools32 02:57:28 INFO - Running setup.py install for functools32 02:57:28 INFO - warning: no files found matching '*.txt' 02:57:28 INFO - no previously-included directories found matching 'build' 02:57:28 INFO - no previously-included directories found matching 'dist' 02:57:28 INFO - no previously-included directories found matching '.git*' 02:57:28 INFO - Successfully installed jsonschema functools32 02:57:28 INFO - Cleaning up... 02:57:28 INFO - Return code: 0 02:57:28 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:57:28 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')]} 02:57:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:28 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')]} 02:57:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:28 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 0x105eb7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ec7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92424bb240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d56e00>, '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 0x105bbfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d51b20>, '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.XDghxrnrIf/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.H1SApKv8nz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 02:57:28 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 02:57:28 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 02:57:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XDghxrnrIf/Render', 02:57:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:28 INFO - 'HOME': '/Users/cltbld', 02:57:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:28 INFO - 'LOGNAME': 'cltbld', 02:57:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:28 INFO - 'MOZ_NO_REMOTE': '1', 02:57:28 INFO - 'NO_EM_RESTART': '1', 02:57:28 INFO - 'PAGER': '/bin/cat', 02:57:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:28 INFO - 'PWD': '/builds/slave/test', 02:57:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:28 INFO - 'SHELL': '/bin/bash', 02:57:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H1SApKv8nz/Listeners', 02:57:28 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 02:57:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:28 INFO - 'USER': 'cltbld', 02:57:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:28 INFO - 'XPC_FLAGS': '0x0', 02:57:28 INFO - 'XPC_SERVICE_NAME': '0', 02:57:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:29 INFO - Downloading/unpacking flake8 02:57: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 02:57: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 02:57: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 02:57: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 02:57: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 02:57: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 02:57:31 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:57:31 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:57:31 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 02:57:31 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 02:57:31 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 02:57:31 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 02:57:31 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 02:57:31 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 02:57:32 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:57:32 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:57: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 02:57: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 02:57: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 02:57: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 02:57: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 02:57: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 02:57:32 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:57: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 02:57: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 02:57: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 02:57: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 02:57: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 02:57: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 02:57:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:57:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:57:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:57:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:57:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:57:33 INFO - no previously-included directories found matching 'docs/_build' 02:57:33 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:57:33 INFO - Running setup.py install for pep8 02:57:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:57:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:57:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:57:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:57:33 INFO - no previously-included directories found matching 'docs/_build' 02:57:33 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:57:33 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:57:33 INFO - Cleaning up... 02:57:33 INFO - Return code: 0 02:57:33 INFO - Running post-action listener: _resource_record_post_action 02:57:33 INFO - Running post-action listener: _start_resource_monitoring 02:57:33 INFO - Starting resource monitoring. 02:57:33 INFO - ##### 02:57:33 INFO - ##### Running install step. 02:57:33 INFO - ##### 02:57:33 INFO - Running pre-action listener: _resource_record_pre_action 02:57:33 INFO - Running main action method: install 02:57:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:57:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:57:33 INFO - Reading from file tmpfile_stdout 02:57:33 INFO - Detecting whether we're running mozinstall >=1.0... 02:57:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:57:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:57:33 INFO - Reading from file tmpfile_stdout 02:57:33 INFO - Output received: 02:57:33 INFO - Usage: mozinstall [options] installer 02:57:33 INFO - Options: 02:57:33 INFO - -h, --help show this help message and exit 02:57:33 INFO - -d DEST, --destination=DEST 02:57:33 INFO - Directory to install application into. [default: 02:57:33 INFO - "/builds/slave/test"] 02:57:33 INFO - --app=APP Application being installed. [default: firefox] 02:57:33 INFO - mkdir: /builds/slave/test/build/application 02:57:33 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'] 02:57:33 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 02:58:05 INFO - Reading from file tmpfile_stdout 02:58:05 INFO - Output received: 02:58:05 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:58:05 INFO - Running post-action listener: _resource_record_post_action 02:58:05 INFO - ##### 02:58:05 INFO - ##### Running run-tests step. 02:58:05 INFO - ##### 02:58:05 INFO - Running pre-action listener: _resource_record_pre_action 02:58:05 INFO - Running main action method: run_tests 02:58:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:58:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:58:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:58:06 INFO - 2016-06-06 02:58:06.012 screenresolution[1654:7644] starting screenresolution argv=screenresolution get 02:58:06 INFO - 2016-06-06 02:58:06.015 screenresolution[1654:7644] Display 0: 1600x1200x32@60 02:58:06 INFO - 2016-06-06 02:58:06.021 screenresolution[1655:7646] starting screenresolution argv=screenresolution list 02:58:06 INFO - Available Modes on Display 0 02:58:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:58:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:58:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:58:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:58:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:58:06 INFO - Intel Iris: 02:58:06 INFO - Chipset Model: Intel Iris 02:58:06 INFO - Type: GPU 02:58:06 INFO - Bus: Built-In 02:58:06 INFO - VRAM (Dynamic, Max): 1536 MB 02:58:06 INFO - Vendor: Intel (0x8086) 02:58:06 INFO - Device ID: 0x0a2e 02:58:06 INFO - Revision ID: 0x0009 02:58:06 INFO - Displays: 02:58:06 INFO - Display: 02:58:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:58:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:58:06 INFO - Display Serial Number: 1600x1200 60 02:58:06 INFO - Main Display: Yes 02:58:06 INFO - Mirror: Off 02:58:06 INFO - Online: Yes 02:58:06 INFO - Rotation: Supported 02:58:06 INFO - Return code: 0 02:58:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:58:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:58:06 INFO - Python 2.7.3 02:58:06 INFO - Return code: 0 02:58:06 INFO - grabbing minidump binary from tooltool 02:58:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:58:06 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 0x105d56e00>, '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 0x105bbfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d51b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:58:06 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 02:58:06 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 02:58:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:58:06 INFO - Return code: 0 02:58:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:58:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:58:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:58:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:58:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:58:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:58:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:58:07 INFO - Return code: 0 02:58:07 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-0181', '--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 02:58:07 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-0181 --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 02:58:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XDghxrnrIf/Render', 02:58:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:58:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:58:07 INFO - 'HOME': '/Users/cltbld', 02:58:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:58:07 INFO - 'LOGNAME': 'cltbld', 02:58:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:58:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:58:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:07 INFO - 'MOZ_NO_REMOTE': '1', 02:58:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:58:07 INFO - 'NO_EM_RESTART': '1', 02:58:07 INFO - 'PAGER': '/bin/cat', 02:58:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:58:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:07 INFO - 'PWD': '/builds/slave/test', 02:58:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:58:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:58:07 INFO - 'SHELL': '/bin/bash', 02:58:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H1SApKv8nz/Listeners', 02:58:07 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 02:58:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:58:07 INFO - 'USER': 'cltbld', 02:58:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:58:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:58:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:07 INFO - 'XPC_FLAGS': '0x0', 02:58:07 INFO - 'XPC_SERVICE_NAME': '0', 02:58:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:58:07 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-0181', '--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 02:58:07 INFO - mozversion application_buildid: 20160606010529 02:58:07 INFO - mozversion application_changeset: d8adb38dd576398dacbfb96d2c247f8e0158bdf1 02:58:07 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:58:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:58:07 INFO - mozversion application_name: Firefox 02:58:07 INFO - mozversion application_remotingname: firefox-dev 02:58:07 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:58:07 INFO - mozversion application_vendor: Mozilla 02:58:07 INFO - mozversion application_version: 48.0a2 02:58:07 INFO - mozversion platform_buildid: 20160606010529 02:58:07 INFO - mozversion platform_changeset: d8adb38dd576398dacbfb96d2c247f8e0158bdf1 02:58:07 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:58:07 INFO - mozversion platform_version: 48.0a2 02:58:07 INFO - using testdate: 1465207087 02:58:07 INFO - actual date: 1465207087 02:58:07 INFO - starting webserver on 'localhost:49271' 02:58:07 INFO - SUITE-START | Running 2 tests 02:58:07 INFO - TEST-START | dromaeo_css 02:58:07 INFO - operating with platform_type : mac_ 02:58:07 INFO - Initialising browser for dromaeo_css test... 02:58:07 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmp2wHzTj/profile http://localhost:49271/getInfo.html) 02:58:10 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 02:58:10 INFO - PROCESS | 1673 | colorDepth:24 02:58:10 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 02:58:10 INFO - PROCESS | 1673 | __metrics 02:58:10 INFO - PROCESS | 1673 | 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" 02:58:10 ERROR - PROCESS | 1673 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:58:11 INFO - TEST-INFO | 1673: exit 0 02:58:11 INFO - Browser initialized. 02:58:11 INFO - Running cycle 1/1 for dromaeo_css test... 02:58:11 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmp2wHzTj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:58:22 INFO - PROCESS | 1676 | RSS: Main: 211345408 02:58:22 INFO - PROCESS | 1676 | 02:59:14 INFO - PROCESS | 1676 | 1465207154289 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] 03:01:24 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 03:01:26 INFO - PROCESS | 1676 | RSS: Main: 375377920 03:01:26 INFO - PROCESS | 1676 | 03:04:29 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 03:04:30 INFO - PROCESS | 1676 | RSS: Main: 353026048 03:04:30 INFO - PROCESS | 1676 | 03:07:32 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 03:07:32 INFO - PROCESS | 1676 | RSS: Main: 363773952 03:07:32 INFO - PROCESS | 1676 | 03:10:35 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 03:10:35 INFO - PROCESS | 1676 | RSS: Main: 358989824 03:10:35 INFO - PROCESS | 1676 | 03:13:38 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 03:13:39 INFO - PROCESS | 1676 | RSS: Main: 323432448 03:13:39 INFO - PROCESS | 1676 | 03:16:42 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 03:16:42 INFO - PROCESS | 1676 | RSS: Main: 376135680 03:16:42 INFO - PROCESS | 1676 | 03:16:42 INFO - PROCESS | 1676 | __start_tp_report 03:16:42 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 03:16:42 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 03:16:42 INFO - PROCESS | 1676 | |i|pagename|runs| 03:16:42 INFO - PROCESS | 1676 | |0;dojo.html;3376.814275214863;3444.4488881778907;3455.498952651865;3455.6717111874395;3514.139035936196;5655.23654306669;5656.858578535528;5659.943400565999;5664.801731939383;5686.772529098841;4731.810727570893;5676.574256930737;5694.430556944303;5706.629069110505;5722.685252311121;5051.393832740058;5175.327207463025;5187.144121220003;5261.316028916235;5294.1529355303155;5272;5275.973620131894;5288.048151332759;5290.497402746752;5291.126964050927;5170.1986192140275;5206.45332240115;5222.556082732972;5237.47625237477;5238.423773384925;5271.841844744645;5293.444188360244;5295.655782374172;5302.681839089667;5353.464653534655;5098.209777484496;5123.7438128093445;5194.27280180775;5217.947820521784;5242.973785131088;5463.153211252262;5495.6153069284765;5524.530414914737;5557.9717752215965;5627;5119.3088932994315;6154.96922515389;6181.381861813783;6182.1345011698395;6200.813975580695;5803.65178089316;6109.5723299369065;6238.469730072904;6242.87514249717;6247.187865577492;5725.570582206351;5771.480566748971;5805.303363596396;5880.617759845596;5883.735231914574;6288;6318.684065796691;6324.272708638474;6339.746410143634;6341.048842673636;5916.083007133901;6122.938770612237;6159.4456498915315;6161.4454699076405;6165.414285642857;6168.0439531872635;6172.938270617326;6270.122182894398;6270.749170033238;6280.497560195174;6096.969515152396;6157.507399408037;6162.969185154046;6174.536909731788;6181.629102253879;6069.271687397452;6201.875962480815;6223.96888015557;6232.781852635136;6239.344868788802;4878.0975519529;4886.828960986349;4898.681585696918;4900.656954013185;4907.87730306738;4364.214441400578;4391.187630288407;4423.668224883082;4500.954990450054;4513.661475389359;5692.8861422771315;5693;5702.429757024264;5712.771489140471;5722;6240.7503699852405;6270.216222972129;6285.962816135378;6298.716557754912;6298.842528936813;5583.357913839879;5668.8866222675315;5669.31968163823;5673.063944449203;5754.510866576305;5371.301730774974;5383.246345511631;5386.461353864661;5387;5393.6763794172475;6348.619082855043;6356.682165891687;6361.968190159019;6362.491000719931;6371.33101024395;6068.332483426908;6130.673316708229;6139.093519790236;6216.974199257072;6279.183706118175;6380.170577824853;6383.265924418565;6414.583052101599;6421.197350331208;6424.325445828121;6127.509799216141;6199.504039676905;6216.347283535164;6226.750929962751;6236.282827474898;4692.343071969995;6019;6154.907676384947;6277;6278.780242691484;6117.401149625572;6124.83277758918;6124.969375153096;6128.8774224516155;6156.538259630456;6070.544709146743;6093.116498107838;6122.081687746873;6124.356942521149;6142.04798256271;5786.94213057864;5800.854978625399;5893.116032594973;5909.320428150646;5912.320083190316;5737.139429085503;5799.797007104731;6055.969720151371;6124.755009799558;6135.600157732601;5936.881262374642;5944.078667806497;5948.791792287249;5957.851053723692;6000.039993601003 03:16:42 INFO - PROCESS | 1676 | |1;ext.html;509.69418348990604;512.1063743767127;528.8466344760018;569.8888716700244;575.804226562969;22035.889820550896;38052.61947380525;39014.609853901486;39064.41403378944;39176.80411597945;42173.367399489034;42563.36154957678;42614.57385426145;42637.78681106598;42700.78649606755;8432.704855330065;9383.81232375352;9606.855897161548;9619.855702164474;9620.230381569492;8544.572771361438;8683.088275731052;8693.217610415062;8716.128387161265;11492.310461372344;11492.655220343404;11536.365499897502;11549.595764148256;11558.595449159282;11606.12954028447;11483.770324593503;11556.422178891105;11557.19099663024;11559.653210403701;11576.53693852245;8513.489190648581;8768.07935166811;8768.561571921378;11387.202895797298;11425.657230283105;38966;39526.802365988275;39530.0117497062;39589.80205098985;39616.60383396187;3167.33485967947;3252.414565378231;3265.1673823175174;3270.934581308384;3539.2390636013124;27539.86230068857;27737.445251094865;27784.861075694695;28171.29571760702;28348;2671.7862570994275;2713.1996061162004;2757.8483183424905;2760;2779.5274803283287;369326;370397;370629.1468542667;371006;372335;64485.67757161278;64712.67643661846;64826;64866.999999999054;65006;68307.65846170737;68699;68858.3114168862;68972.96540551796;68978.31021689819;16790.83209167917;17255.050972196397;17287.481375558753;17296.308147674205;17307.39424120175;12283.93858030722;13265.734685306239;13327;13327;13400.262985535694;18035.008074556026;18186.18162182705;18231.544211394557;18246.63506729858;18485.907570462063;7049.400800931979;7129.465290103263;7166.355028047396;7174.174969878531;7229.2047874733735;991.861139440479;1008.5511947183433;1011.4841430870309;1111.938099115343;1126.4480404601686;29960.400791984473;30248;30291.709721007555;30310.84844575807;30324.130246378605;1034.6637342863448;1065.414022287754;1116.6929094499078;1187.4597058338436;1218.2994778002508;13712.65718357049;14983.250837458301;15047.924760376129;15404.922975385276;15405.768913466307;590984;912813;932737;933176;934037;58552.7072364644;58859;59293.40706592879;59413.108803367984;59476.70261648664;1488.642725745835;1490.9701805964037;1491.2171110167249;1502.0687173952;1506.7965824613536;24150;29199;29762.851185743933;30456;30499.390012199186;51927.22109168441;52262.21606675827;52341.7382913083;52342.47657523376;52446;469791;472185;472369;472610;473084;24352.391190219678;24800.751992480567;26440.73559264383;27100.593491098043;27410.177694669193;34821.651783481844;42894;43040.784796077074;44147.77926110349;44697.32954005628;34865;36105.91682249537;36143.45784813283;36258.4561231587;36938.8153059233;36286.27427451489;36583.08542286464;36758.63241367659;37239.88280351594;37392.25215495621;36169.45745813868;37037.07407314839;37271;37670.8116459416;37784.62215377811;32239.838800805846;34085.65914340825;34213;34843;35658.46512302266;19032.42902712921;24890.626640600043;25071.247862564152;25251.74748252494;25336.11323603665 03:16:42 INFO - PROCESS | 1676 | |2;jquery.html;398.36659711059417;401.19760479041923;403.4009495898592;403.5843081625926;415.79833780616394;14944.551663450115;15707.685846283068;16194.271257793398;20281.59436811263;20285.594288114247;14091;14776.556703298891;15613.765793513077;16231.513054608351;19264.903675481604;3869.9613003869954;3918.789674772184;4014.8996275093064;5940.88118237635;5943.167956486095;5697.715114244271;5838.649681019131;5846.88306233875;5878.441548052928;5897.705114744267;5796.710164491779;5819.592628516006;5838.1534677471955;5842.24050873388;5843.123531470293;4051.1695102504063;4259.5740425957465;5686.772529098831;6391.520635952322;6401.711922963478;5572.136318870582;5575.916361254584;5586.217929489874;5591.580631452657;5597.132444471114;22858.42853928665;23661.29016129519;23926.52146957051;23981.880090599436;24977.500449990897;2206.9227577034885;2297.414159389362;2576.0854896511655;2738.904438224706;2871.7415432611165;9654.75863103421;9990.800183996353;10098.394096354243;13326.067175297732;13764.724705505834;1628.7719719239317;1669.4407373529762;1734.6443978984278;1737.0446254560068;1737.5134962210602;101217.49391252997;121374.78625213723;121516;123731;128830;11391.259568128045;11542.94228528852;11604.6518604442;11610.477528511068;11747.412629368497;14734.336954837057;14896;14937.551873443814;14949.551513454613;14963.925180374246;7745.651445684957;8775.034746177915;8779;8860.512671803113;8889.555522223864;7026.1217347831525;7394.704211831574;7459.664315105833;7849.626315394783;7958.363330933512;4559.54404559548;7486.7005319786595;9146.085391460801;9283.767905802273;9452.810943781085;3265.3959017581824;3667.8532858685353;3755.1738617504107;3759.9132481472498;3869.123475114567;920.7513971227731;981.622075500923;1013.4679293371039;1015.304516406264;1036.9481525923673;19690.507737306394;20487.590248194952;20544.794552054587;20676.793232067488;20929;968.7551496327186;984.8378912882724;992.4739887859446;1008.2186305613209;1032.7418145463635;10659.200559958033;10703.882526634985;10728.946355268174;10737.409442480739;10834.566617335218;622332;624181;626140;629435;643481;127276.36361818132;131873;133098;133324;133403;1013.8225810483283;1014.2089170447063;1025.220832167558;1029.3978022856563;1031.7884833609091;15532.456364027204;16420.425285114965;17437.47687569375;17514.211860466054;18103.275868965095;39740;54553.726321473536;54650.7267463676;55964.16053759115;56618.716906415204;78911.81632275635;79592.60203698944;79906;80175.59912200402;80527;8082.959585202037;8108.83782324362;8173.509589424653;8379.329653627574;8408.033076196316;9120.224780893699;11778.411079446165;12270.877291226974;12719.618411447671;12815.359232038547;10675.306105103144;10863.837042444527;10865.239433239598;10925.617603383844;10928.836067459153;10455.268131230741;10646.89353106459;10724.356538607708;10766.73083172927;10823.242373034127;8450.492970421794;10736.677899663022;10757.677269681923;11280.830787538029;11381.43092845338;8185.263326300662;8195.221453961864;8307.584620769057;8328.125546817739;8463.153684631488;7828.72599459039;9301.953490232505;10308.020738029874;10376.533056012346;10572.947135264274 03:16:42 INFO - PROCESS | 1676 | |3;mootools.html;1342.523404191512;1447.992760036201;1605.3097168217669;1783.8216178382163;1944.7246721336035;7991.600419979007;8650.653973841047;8824.558772061388;8835.160659737314;8857.202851743341;8612.22489975902;8849.964508163126;8859.557022148883;8863.246624036965;8907.643694252238;2459.3482727077385;2494.550980823451;2498.525280196766;2498.9750102498924;2500.2124330835727;2213.878236696981;2418.141559746291;2483.8261321707396;2488.4401009772837;2488.539620170265;2323.082382458928;2447.889844956981;2486.0304481252324;2488.7635674610883;2490.7882829959476;2046.6623007203868;2447.657327974085;2477.1330034487964;2484.4037431016427;2488.216211893248;2436.1838784007377;2460.90156393744;2467.728549859514;2471.1598056660823;2474.851508909453;8571.185737354941;8879;8937.553122343857;8944.10558944107;8958.507282099481;5421.349438067457;5584.972075139639;5643.520300774439;5647.830565083054;5648.887022259573;3619.1133172372674;3657.7256705747177;3716.349638813197;3765.246950609889;3781.470594116826;1052.3738375666467;1064.4039337970762;1064.8668916385452;1074.382230217628;1104.3981030338484;10513.264071514997;11863.406829658568;12091.153619246656;12175.208611440317;12206.816897746452;4582.587567118909;4667.206574882211;4673.929891051637;4743.288506724019;4747.477777444478;9455.90544094564;9485.952570237105;9486.762830929281;9493.193078588398;9493.335466517277;5385.865353366197;5405.324334458192;5440.129579267298;5444.673319600836;5450.645708028966;4451.310046942729;4569.794359253841;4838.443578988461;5002.699838009731;5077.339945807022;3838.961610383916;3867.806609669505;3934.567197608261;3952.822123004472;3953.584873588232;1193.9462724177433;1196.2583198417037;1200.7358381156132;1203.7351782607811;1204.8795120488057;675.3280485916545;833.9791505212297;921.5484412637893;953.9379940303996;957.7988622389237;5751.683657398799;5806.564507661942;5955.851103722442;6013.1581578579035;6043.54673399488;504.5963229416452;640.810960766575;1003.5298288008103;1062.3625824505389;1102.2780079048237;3838.846446142179;3942.191850670663;3943.8028098594955;4036.3340048891605;4065.0650350418978;10652.09457196147;10800.081993030522;11075.781664016951;11086.279391839505;11116.777664446667;10805.189610779224;10918.235723499438;10923;10923.071538919123;10932.398718070583;7867;8242.010958684996;8326.000879894451;8329.833403331899;8384.874226886479;5474.972625136849;5887.882242355044;5904.793332233351;6021.939780602138;6029.517638588814;9308.674196403093;9420.95289523548;9595.760105997128;9737.756556086155;9771;9833.016698330395;9859.802803943738;9874.358166719141;9927.950360248442;10080.798384032425;7480.551166930001;7562.2437756223935;7631.503952243086;7649.61751912391;7651;8294.419390642597;8320.16798320163;8338.499690018618;8372.162783721822;8445;7511.06111736033;7535.8492830141995;7543.1325397579985;7670;7698.615069246627;7272.963635182002;7321.560706357635;7401.666924988496;7407.666655000646;7419.369353605004;6974.4769142315545;6974.546654467444;6992.230854606091;7024.121984751906;7027.789166325018;4804.375431193923;4811.08589368019;4935.309056732132;4990.750462476789;5019.272205530251;4969.776360063734;5048.469910659328;5070.974645126751;5073.213651883964;5186.325777648882 03:16:42 INFO - PROCESS | 1676 | |4;prototype.html;314.6695969232304;321.30437602590393;330.2322101114909;330.4778450048923;330.83788943417727;6494.318096599854;6565.080888675589;6571.901421478682;6573.046908198315;6581.078648989145;6269.592476489025;6411.775587854426;6450.9354906451035;6462.579932304397;6526.608403495794;4893.070316639836;4909.926351104737;4933.802647894098;4955.950440495603;4966.106100901836;4869.634777391692;4921.876953076166;4924.433690125627;4941.308216849644;4962.330085438473;3858.498395208619;4599.908001839978;4684.156851766681;4878.975605121969;4894.657373983822;4797.184478638623;4823.155947709154;4848.975755121237;4860.3195552622665;4876.463589005207;4151.6886233532305;4152.979235103805;4157.896052598679;4165.812538435777;4173.770442625654;5100.311457953166;6307;6317.336679648617;6325.87348253037;6340.904886426707;5680.687562184078;5691.6585004899425;5692.459216374438;5700.173474846166;5703.486686198221;6795.422389096932;6974.895376569356;6989.685464154125;6994.370506654374;7027.859442811166;4658.767061646938;4694.413198350207;4706.7175969441605;4714;4718.528147185289;7436.330730234251;7449.180590135081;7449.925500744977;7466.253374662545;7469.5144815587355;3919.784411857318;4748.620110391161;4751.50109238525;4755.643326750507;4775.73733444657;4831.299461578052;4832.9275060874115;4842.225243960949;4846.055019271279;4867.756612169377;5322.840314790563;5521.5306698931045;5707.086866101404;5714.200011998323;5714.200011998323;6976.407005404598;6978.092847929736;7026.824329391806;7066.893996590055;7077.610731409822;1606.3654856331684;1628.9266982985794;1631.7633943078188;1633.5589390864639;1634.8474325600423;970.7233438470003;983.5524836199512;1020.8723909511311;1040.6877936618985;1043.860086822027;6437.195350581177;6451.225852897683;6510.837229069217;6522.641254731036;6548.705308261045;7220.711171553184;7227.602481863548;7229.2409297024105;7247.2390399007345;7256.129264488295;6487.286398496161;6494.1232933553365;6501.0898474213645;6503.772367967098;6524.217093948757;3273.4762438009802;3711.9628803712158;3759.793211373346;3787.2614840106467;3818.236352729465;7676.234466072835;7681.423893208032;7728.304452599293;7732.7680169595005;7737.92262077372;7549.9245007549225;7565.508241964365;7580.128285247267;7592.696292148363;7596.886046709304;6225.3774622537385;7684.6926122954455;7697.961510192413;7702.460827742117;7705.42209334291;7025.192102908231;7321.487495875451;7326.780196594111;7327.670254838438;7374.631268436664;7230.168530619047;7254.129504459499;7271.636418179176;7275.163356214103;7294.817629559304;7715.884261735966;7720.922790772245;7732.1494635588915;7733.458657893893;7746.457747957815;4595.126925884071;4634.2585186370015;4638.768061596974;4639.884002899955;4644.396228490275;6971.407430368476;7263.128424588872;7297;7302.817429564303;7311.634418279171;6997.650117494003;7051.894221586577;7060.258672839484;7063.223045464892;7067.6112813795735;7036.577805331696;7037.190723066708;7041.788746337618;7046.964765176346;7047.647617619201;6863;6869.587824730333;6887;6887.139107611549;6890.931090689029;6766.18805743314;6995.860082798214;7014.789556313319;7014.894776578253;7015.508914375945;6643.40209381158;6773.559718618274;6798.456123510206;6882.311768823078;6890.724371025303 03:16:42 INFO - PROCESS | 1676 | |5;yui.html;660.2440205964172;671.4628297362112;682.5290549520831;724.9601271930048;727.131078361358;13345.394029955185;14942.402303907831;14976;15150.848491515082;15168.696626067485;12264.141510094298;12521.248725076503;13713.177209367444;13867.791983120236;13906.582802515915;895.7133717210495;919;919.2048877720757;919.5586118663045;922.1746536849515;898.9820203595924;908.3278374003223;912.0651332384326;914.7621618379236;918.3892711346946;749.2732049911577;943.5612440215299;961.1877963121186;963.0995019656608;963.5423173992333;950.7147855643314;951.923846092311;952.1145334838598;953.8998405167426;954.6467806911453;709.4749885085051;709.8473828126926;709.8757717399486;710.8827043537862;711.345562082885;14544.2000689962;14615;14617.122972621677;14620.97653164279;14941.551753447415;12602;12671.429785659668;12919.22484650924;12944.417501212469;13056.412461439162;9983.550740216708;10036.29745917786;10082.395056296573;10085.798284034352;10401.84397234042;3482.791032538056;3483.2336885885065;3546.1666508370513;3582.6059133495555;3617.7286703497343;81852.59073704653;81893;82509.76235356474;83448.58275708643;83886.74169887457;9172.770680732901;9325.626974921062;9432.309193365341;9566.426014439156;9683.225341972626;20326.186952522028;20384.082716277804;20625.071871765806;20699.689504657443;20746.58506829855;7505.362044226302;7538.924610753932;7559.546427214274;7572.4699271050495;7644.273793989562;4026.154507553388;4100;4109.712320137562;4158.7296825706235;4248.171606536695;3335.849886755102;3445.9483107753404;3533.876314328986;3590.1383667919513;3597.6762091411897;668.3483603486561;672.2907332763883;689.9931000690029;690.2372877969872;692.3353580562716;538.249142446293;567.545963229423;581.6045089339206;583.1718959078034;598.9940100598939;7256.82907904416;7416.888746668804;8132.75601731949;8189;8223.54770487629;586.5161241975387;589.4547543522225;589.6255877517807;605.8061420345447;607.5261296188979;3833.961660383416;3903.9219215616095;3930.4300876373336;3950.841966321315;3965.6629186519467;90619;95397.52301238588;95519;95801.5209923946;96015.71913544822;77582;77637;79330.20669793343;80047.59976200081;81073;67010.32474188185;69418.3058169422;69815;69926.70659640657;70473.94289085668;7620.657070431955;7875;8122.431429799857;8253.628586713492;8360.163983601591;42610.14779704327;43285;48088;48704.2694359592;48778.512214877395;59572.70213648904;67502.98745518723;68783.65608171927;68817.96773048508;69987.6500617514;10889.401082940514;10963.890361096286;11112.444377781241;11246.606368777053;11290.43547822622;11701.2979221247;11794;12001.39993000364;12031.15781895259;12437.689057773629;11229.831552526555;11304.660860174208;11435.542578297107;11731.941340293244;12449.502019919102;11019.889801102207;11152.944235279096;11283.05504910756;11618.5352585899;11811.763764724485;11170.050545703707;11195;11201.551937922392;11480.712982175512;11795.528178873092;9218.262538996998;9649;9726.416415015121;9748.85376719364;9782.951085244527;9419.717408477756;9571.856422153813;9625;9739.707808765748;9802.460864652512 03:16:42 INFO - PROCESS | 1676 | __end_tp_report 03:16:42 INFO - PROCESS | 1676 | __start_cc_report 03:16:42 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,1609 03:16:42 INFO - PROCESS | 1676 | __end_cc_report 03:16:42 INFO - PROCESS | 1676 | __startTimestamp1465208202404__endTimestamp 03:16:42 INFO - PROCESS | 1676 | 03:16:42 INFO - PROCESS | 1676 | ------- Summary: start ------- 03:16:42 INFO - PROCESS | 1676 | Number of tests: 6 03:16:42 INFO - PROCESS | 1676 | 03:16:42 INFO - PROCESS | 1676 | [#0] dojo.html Cycles:165 Average:5716.99 Median:5893.12 stddev:634.67 (10.8%) stddev-sans-first:609.48 03:16:42 INFO - PROCESS | 1676 | Values: 3376.8 3444.4 3455.5 3455.7 3514.1 5655.2 5656.9 5659.9 5664.8 5686.8 4731.8 5676.6 5694.4 5706.6 5722.7 5051.4 5175.3 5187.1 5261.3 5294.2 5272.0 5276.0 5288.0 5290.5 5291.1 5170.2 5206.5 5222.6 5237.5 5238.4 5271.8 5293.4 5295.7 5302.7 5353.5 5098.2 5123.7 5194.3 5217.9 5243.0 5463.2 5495.6 5524.5 5558.0 5627.0 5119.3 6155.0 6181.4 6182.1 6200.8 5803.7 6109.6 6238.5 6242.9 6247.2 5725.6 5771.5 5805.3 5880.6 5883.7 6288.0 6318.7 6324.3 6339.7 6341.0 5916.1 6122.9 6159.4 6161.4 6165.4 6168.0 6172.9 6270.1 6270.7 6280.5 6097.0 6157.5 6163.0 6174.5 6181.6 6069.3 6201.9 6224.0 6232.8 6239.3 4878.1 4886.8 4898.7 4900.7 4907.9 4364.2 4391.2 4423.7 4501.0 4513.7 5692.9 5693.0 5702.4 5712.8 5722.0 6240.8 6270.2 6286.0 6298.7 6298.8 5583.4 5668.9 5669.3 5673.1 5754.5 5371.3 5383.2 5386.5 5387.0 5393.7 6348.6 6356.7 6362.0 6362.5 6371.3 6068.3 6130.7 6139.1 6217.0 6279.2 6380.2 6383.3 6414.6 6421.2 6424.3 6127.5 6199.5 6216.3 6226.8 6236.3 4692.3 6019.0 6154.9 6277.0 6278.8 6117.4 6124.8 6125.0 6128.9 6156.5 6070.5 6093.1 6122.1 6124.4 6142.0 5786.9 5800.9 5893.1 5909.3 5912.3 5737.1 5799.8 6056.0 6124.8 6135.6 5936.9 5944.1 5948.8 5957.9 6000.0 03:16:42 INFO - PROCESS | 1676 | 03:16:42 INFO - PROCESS | 1676 | [#1] ext.html Cycles:180 Average:70035.42 Median:36124.69 stddev:165032.05 (456.8%) stddev-sans-first:165412.45 03:16:42 INFO - PROCESS | 1676 | Values: 509.7 512.1 528.8 569.9 575.8 22035.9 38052.6 39014.6 39064.4 39176.8 42173.4 42563.4 42614.6 42637.8 42700.8 8432.7 9383.8 9606.9 9619.9 9620.2 8544.6 8683.1 8693.2 8716.1 11492.3 11492.7 11536.4 11549.6 11558.6 11606.1 11483.8 11556.4 11557.2 11559.7 11576.5 8513.5 8768.1 8768.6 11387.2 11425.7 38966.0 39526.8 39530.0 39589.8 39616.6 3167.3 3252.4 3265.2 3270.9 3539.2 27539.9 27737.4 27784.9 28171.3 28348.0 2671.8 2713.2 2757.8 2760.0 2779.5 369326.0 370397.0 370629.1 371006.0 372335.0 64485.7 64712.7 64826.0 64867.0 65006.0 68307.7 68699.0 68858.3 68973.0 68978.3 16790.8 17255.1 17287.5 17296.3 17307.4 12283.9 13265.7 13327.0 13327.0 13400.3 18035.0 18186.2 18231.5 18246.6 18485.9 7049.4 7129.5 7166.4 7174.2 7229.2 991.9 1008.6 1011.5 1111.9 1126.4 29960.4 30248.0 30291.7 30310.8 30324.1 1034.7 1065.4 1116.7 1187.5 1218.3 13712.7 14983.3 15047.9 15404.9 15405.8 590984.0 912813.0 932737.0 933176.0 934037.0 58552.7 58859.0 59293.4 59413.1 59476.7 1488.6 1491.0 1491.2 1502.1 1506.8 24150.0 29199.0 29762.9 30456.0 30499.4 51927.2 52262.2 52341.7 52342.5 52446.0 469791.0 472185.0 472369.0 472610.0 473084.0 24352.4 24800.8 26440.7 27100.6 27410.2 34821.7 42894.0 43040.8 44147.8 44697.3 34865.0 36105.9 36143.5 36258.5 36938.8 36286.3 36583.1 36758.6 37239.9 37392.3 36169.5 37037.1 37271.0 37670.8 37784.6 32239.8 34085.7 34213.0 34843.0 35658.5 19032.4 24890.6 25071.2 25251.7 25336.1 03:16:42 INFO - PROCESS | 1676 | 03:16:42 INFO - PROCESS | 1676 | [#2] jquery.html Cycles:180 Average:35793.59 Median:13776.79 stddev:104923.07 (761.6%) stddev-sans-first:105183.75 03:16:42 INFO - PROCESS | 1676 | Values: 398.4 401.2 403.4 403.6 415.8 14944.6 15707.7 16194.3 20281.6 20285.6 14091.0 14776.6 15613.8 16231.5 19264.9 3870.0 3918.8 4014.9 5940.9 5943.2 5697.7 5838.6 5846.9 5878.4 5897.7 5796.7 5819.6 5838.2 5842.2 5843.1 4051.2 4259.6 5686.8 6391.5 6401.7 5572.1 5575.9 5586.2 5591.6 5597.1 22858.4 23661.3 23926.5 23981.9 24977.5 2206.9 2297.4 2576.1 2738.9 2871.7 9654.8 9990.8 10098.4 13326.1 13764.7 1628.8 1669.4 1734.6 1737.0 1737.5 101217.5 121374.8 121516.0 123731.0 128830.0 11391.3 11542.9 11604.7 11610.5 11747.4 14734.3 14896.0 14937.6 14949.6 14963.9 7745.7 8775.0 8779.0 8860.5 8889.6 7026.1 7394.7 7459.7 7849.6 7958.4 4559.5 7486.7 9146.1 9283.8 9452.8 3265.4 3667.9 3755.2 3759.9 3869.1 920.8 981.6 1013.5 1015.3 1036.9 19690.5 20487.6 20544.8 20676.8 20929.0 968.8 984.8 992.5 1008.2 1032.7 10659.2 10703.9 10728.9 10737.4 10834.6 622332.0 624181.0 626140.0 629435.0 643481.0 127276.4 131873.0 133098.0 133324.0 133403.0 1013.8 1014.2 1025.2 1029.4 1031.8 15532.5 16420.4 17437.5 17514.2 18103.3 39740.0 54553.7 54650.7 55964.2 56618.7 78911.8 79592.6 79906.0 80175.6 80527.0 8083.0 8108.8 8173.5 8379.3 8408.0 9120.2 11778.4 12270.9 12719.6 12815.4 10675.3 10863.8 10865.2 10925.6 10928.8 10455.3 10646.9 10724.4 10766.7 10823.2 8450.5 10736.7 10757.7 11280.8 11381.4 8185.3 8195.2 8307.6 8328.1 8463.2 7828.7 9302.0 10308.0 10376.5 10572.9 03:16:42 INFO - PROCESS | 1676 | 03:16:42 INFO - PROCESS | 1676 | [#3] mootools.html Cycles:180 Average:5741.32 Median:5059.72 stddev:3189.03 (63.0%) stddev-sans-first:3180.83 03:16:42 INFO - PROCESS | 1676 | Values: 1342.5 1448.0 1605.3 1783.8 1944.7 7991.6 8650.7 8824.6 8835.2 8857.2 8612.2 8850.0 8859.6 8863.2 8907.6 2459.3 2494.6 2498.5 2499.0 2500.2 2213.9 2418.1 2483.8 2488.4 2488.5 2323.1 2447.9 2486.0 2488.8 2490.8 2046.7 2447.7 2477.1 2484.4 2488.2 2436.2 2460.9 2467.7 2471.2 2474.9 8571.2 8879.0 8937.6 8944.1 8958.5 5421.3 5585.0 5643.5 5647.8 5648.9 3619.1 3657.7 3716.3 3765.2 3781.5 1052.4 1064.4 1064.9 1074.4 1104.4 10513.3 11863.4 12091.2 12175.2 12206.8 4582.6 4667.2 4673.9 4743.3 4747.5 9455.9 9486.0 9486.8 9493.2 9493.3 5385.9 5405.3 5440.1 5444.7 5450.6 4451.3 4569.8 4838.4 5002.7 5077.3 3839.0 3867.8 3934.6 3952.8 3953.6 1193.9 1196.3 1200.7 1203.7 1204.9 675.3 834.0 921.5 953.9 957.8 5751.7 5806.6 5955.9 6013.2 6043.5 504.6 640.8 1003.5 1062.4 1102.3 3838.8 3942.2 3943.8 4036.3 4065.1 10652.1 10800.1 11075.8 11086.3 11116.8 10805.2 10918.2 10923.0 10923.1 10932.4 7867.0 8242.0 8326.0 8329.8 8384.9 5475.0 5887.9 5904.8 6021.9 6029.5 9308.7 9421.0 9595.8 9737.8 9771.0 9833.0 9859.8 9874.4 9928.0 10080.8 7480.6 7562.2 7631.5 7649.6 7651.0 8294.4 8320.2 8338.5 8372.2 8445.0 7511.1 7535.8 7543.1 7670.0 7698.6 7273.0 7321.6 7401.7 7407.7 7419.4 6974.5 6974.5 6992.2 7024.1 7027.8 4804.4 4811.1 4935.3 4990.8 5019.3 4969.8 5048.5 5071.0 5073.2 5186.3 03:16:42 INFO - PROCESS | 1676 | 03:16:42 INFO - PROCESS | 1676 | [#4] prototype.html Cycles:180 Average:5771.55 Median:6516.74 stddev:1854.74 (28.5%) stddev-sans-first:1814.16 03:16:42 INFO - PROCESS | 1676 | Values: 314.7 321.3 330.2 330.5 330.8 6494.3 6565.1 6571.9 6573.0 6581.1 6269.6 6411.8 6450.9 6462.6 6526.6 4893.1 4909.9 4933.8 4956.0 4966.1 4869.6 4921.9 4924.4 4941.3 4962.3 3858.5 4599.9 4684.2 4879.0 4894.7 4797.2 4823.2 4849.0 4860.3 4876.5 4151.7 4153.0 4157.9 4165.8 4173.8 5100.3 6307.0 6317.3 6325.9 6340.9 5680.7 5691.7 5692.5 5700.2 5703.5 6795.4 6974.9 6989.7 6994.4 7027.9 4658.8 4694.4 4706.7 4714.0 4718.5 7436.3 7449.2 7449.9 7466.3 7469.5 3919.8 4748.6 4751.5 4755.6 4775.7 4831.3 4832.9 4842.2 4846.1 4867.8 5322.8 5521.5 5707.1 5714.2 5714.2 6976.4 6978.1 7026.8 7066.9 7077.6 1606.4 1628.9 1631.8 1633.6 1634.8 970.7 983.6 1020.9 1040.7 1043.9 6437.2 6451.2 6510.8 6522.6 6548.7 7220.7 7227.6 7229.2 7247.2 7256.1 6487.3 6494.1 6501.1 6503.8 6524.2 3273.5 3712.0 3759.8 3787.3 3818.2 7676.2 7681.4 7728.3 7732.8 7737.9 7549.9 7565.5 7580.1 7592.7 7596.9 6225.4 7684.7 7698.0 7702.5 7705.4 7025.2 7321.5 7326.8 7327.7 7374.6 7230.2 7254.1 7271.6 7275.2 7294.8 7715.9 7720.9 7732.1 7733.5 7746.5 4595.1 4634.3 4638.8 4639.9 4644.4 6971.4 7263.1 7297.0 7302.8 7311.6 6997.7 7051.9 7060.3 7063.2 7067.6 7036.6 7037.2 7041.8 7047.0 7047.6 6863.0 6869.6 6887.0 6887.1 6890.9 6766.2 6995.9 7014.8 7014.9 7015.5 6643.4 6773.6 6798.5 6882.3 6890.7 03:16:42 INFO - PROCESS | 1676 | 03:16:42 INFO - PROCESS | 1676 | [#5] yui.html Cycles:180 Average:18246.83 Median:664.30 stddev:25903.29 (3899.4%) stddev-sans-first:25942.29 03:16:42 INFO - PROCESS | 1676 | Values: 660.2 671.5 682.5 725.0 727.1 13345.4 14942.4 14976.0 15150.8 15168.7 12264.1 12521.2 13713.2 13867.8 13906.6 895.7 919.0 919.2 919.6 922.2 899.0 908.3 912.1 914.8 918.4 749.3 943.6 961.2 963.1 963.5 950.7 951.9 952.1 953.9 954.6 709.5 709.8 709.9 710.9 711.3 14544.2 14615.0 14617.1 14621.0 14941.6 12602.0 12671.4 12919.2 12944.4 13056.4 9983.6 10036.3 10082.4 10085.8 10401.8 3482.8 3483.2 3546.2 3582.6 3617.7 81852.6 81893.0 82509.8 83448.6 83886.7 9172.8 9325.6 9432.3 9566.4 9683.2 20326.2 20384.1 20625.1 20699.7 20746.6 7505.4 7538.9 7559.5 7572.5 7644.3 4026.2 4100.0 4109.7 4158.7 4248.2 3335.8 3445.9 3533.9 3590.1 3597.7 668.3 672.3 690.0 690.2 692.3 538.2 567.5 581.6 583.2 599.0 7256.8 7416.9 8132.8 8189.0 8223.5 586.5 589.5 589.6 605.8 607.5 3834.0 3903.9 3930.4 3950.8 3965.7 90619.0 95397.5 95519.0 95801.5 96015.7 77582.0 77637.0 79330.2 80047.6 81073.0 67010.3 69418.3 69815.0 69926.7 70473.9 7620.7 7875.0 8122.4 8253.6 8360.2 42610.1 43285.0 48088.0 48704.3 48778.5 59572.7 67503.0 68783.7 68818.0 69987.7 10889.4 10963.9 11112.4 11246.6 11290.4 11701.3 11794.0 12001.4 12031.2 12437.7 11229.8 11304.7 11435.5 11731.9 12449.5 11019.9 11152.9 11283.1 11618.5 11811.8 11170.1 11195.0 11201.6 11480.7 11795.5 9218.3 9649.0 9726.4 9748.9 9783.0 9419.7 9571.9 9625.0 9739.7 9802.5 03:16:42 INFO - PROCESS | 1676 | -------- Summary: end -------- 03:16:42 INFO - PROCESS | 1676 | 03:16:42 INFO - TEST-INFO | 1676: exit 0 03:16:42 INFO - TEST-OK | dromaeo_css | took 1115323ms 03:16:42 INFO - TEST-START | kraken 03:16:42 INFO - operating with platform_type : mac_ 03:16:42 INFO - Initialising browser for kraken test... 03:16:42 INFO - TEST-INFO | started process 1683 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmp7zcfbg/profile http://localhost:49271/getInfo.html) 03:16:45 INFO - PROCESS | 1683 | __metrics Screen width/height:1600/1200 03:16:45 INFO - PROCESS | 1683 | colorDepth:24 03:16:45 INFO - PROCESS | 1683 | Browser inner width/height: 1024/689 03:16:45 INFO - PROCESS | 1683 | __metrics 03:16:45 INFO - PROCESS | 1683 | 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" 03:16:45 ERROR - PROCESS | 1683 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:16:45 INFO - TEST-INFO | 1683: exit 0 03:16:45 INFO - Browser initialized. 03:16:45 INFO - Running cycle 1/1 for kraken test... 03:16:45 INFO - TEST-INFO | started process 1685 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmp7zcfbg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:16:57 INFO - PROCESS | 1685 | RSS: Main: 211099648 03:16:57 INFO - PROCESS | 1685 | 03:17:00 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 03:17:01 INFO - PROCESS | 1685 | RSS: Main: 271204352 03:17:01 INFO - PROCESS | 1685 | 03:17:04 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 03:17:04 INFO - PROCESS | 1685 | RSS: Main: 285265920 03:17:04 INFO - PROCESS | 1685 | 03:17:07 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 03:17:08 INFO - PROCESS | 1685 | RSS: Main: 373174272 03:17:08 INFO - PROCESS | 1685 | 03:17:10 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 03:17:11 INFO - PROCESS | 1685 | RSS: Main: 279113728 03:17:11 INFO - PROCESS | 1685 | 03:17:13 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:17:13 INFO - PROCESS | 1685 | RSS: Main: 279891968 03:17:13 INFO - PROCESS | 1685 | 03:17:16 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 03:17:17 INFO - PROCESS | 1685 | RSS: Main: 318726144 03:17:17 INFO - PROCESS | 1685 | 03:17:20 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 03:17:21 INFO - PROCESS | 1685 | RSS: Main: 318492672 03:17:21 INFO - PROCESS | 1685 | 03:17:24 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 03:17:24 INFO - PROCESS | 1685 | RSS: Main: 317317120 03:17:24 INFO - PROCESS | 1685 | 03:17:26 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:17:26 INFO - PROCESS | 1685 | RSS: Main: 308760576 03:17:26 INFO - PROCESS | 1685 | 03:17:28 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:17:29 INFO - PROCESS | 1685 | RSS: Main: 316022784 03:17:29 INFO - PROCESS | 1685 | 03:17:31 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:17:32 INFO - PROCESS | 1685 | RSS: Main: 318009344 03:17:32 INFO - PROCESS | 1685 | 03:17:35 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:17:35 INFO - PROCESS | 1685 | RSS: Main: 323428352 03:17:35 INFO - PROCESS | 1685 | 03:17:38 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:17:39 INFO - PROCESS | 1685 | RSS: Main: 321380352 03:17:39 INFO - PROCESS | 1685 | 03:17:41 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 03:17:41 INFO - PROCESS | 1685 | RSS: Main: 385490944 03:17:41 INFO - PROCESS | 1685 | 03:17:41 INFO - PROCESS | 1685 | __start_tp_report 03:17:41 INFO - PROCESS | 1685 | _x_x_mozilla_page_load 03:17:41 INFO - PROCESS | 1685 | _x_x_mozilla_page_load_details 03:17:41 INFO - PROCESS | 1685 | |i|pagename|runs| 03:17:41 INFO - PROCESS | 1685 | |0;ai-astar;113;114;118;112;111;111;112;112;112;135 03:17:41 INFO - PROCESS | 1685 | |1;audio-beat-detection;158;161;189;155;141;173;138;118;170;119 03:17:41 INFO - PROCESS | 1685 | |2;audio-dft;183;187;187;186;184;182;185;186;184;181 03:17:41 INFO - PROCESS | 1685 | |3;audio-fft;73;75;74;151;55;58;58;66;132;56 03:17:41 INFO - PROCESS | 1685 | |4;audio-oscillator;92;91;145;77;78;84;127;79;76;124 03:17:41 INFO - PROCESS | 1685 | |5;imaging-gaussian-blur;87;84;87;83;86;83;87;90;134;131 03:17:41 INFO - PROCESS | 1685 | |6;imaging-darkroom;95;132;150;126;93;92;93;113;151;101 03:17:41 INFO - PROCESS | 1685 | |7;imaging-desaturate;119;133;136;84;83;84;86;84;86;86 03:17:41 INFO - PROCESS | 1685 | |8;json-parse-financial;59;64;63;63;64;65;64;83;61;60 03:17:41 INFO - PROCESS | 1685 | |9;json-stringify-tinderbox;64;68;71;75;75;69;68;68;68;74 03:17:41 INFO - PROCESS | 1685 | |10;stanford-crypto-aes;81;82;81;82;81;79;81;93;105;107 03:17:41 INFO - PROCESS | 1685 | |11;stanford-crypto-ccm;140;145;129;126;127;114;127;124;169;175 03:17:41 INFO - PROCESS | 1685 | |12;stanford-crypto-pbkdf2;167;171;169;167;164;164;165;170;165;164 03:17:41 INFO - PROCESS | 1685 | |13;stanford-crypto-sha256-iterative;67;67;68;67;69;73;93;101;96;70 03:17:41 INFO - PROCESS | 1685 | __end_tp_report 03:17:41 INFO - PROCESS | 1685 | __start_cc_report 03:17:41 INFO - PROCESS | 1685 | _x_x_mozilla_cycle_collect,2012 03:17:41 INFO - PROCESS | 1685 | __end_cc_report 03:17:41 INFO - PROCESS | 1685 | __startTimestamp1465208261320__endTimestamp 03:17:41 INFO - PROCESS | 1685 | 03:17:41 INFO - PROCESS | 1685 | ------- Summary: start ------- 03:17:41 INFO - PROCESS | 1685 | Number of tests: 14 03:17:41 INFO - PROCESS | 1685 | 03:17:41 INFO - PROCESS | 1685 | [#0] ai-astar Cycles:10 Average:115.00 Median:112.50 stddev:7.32 (6.5%) stddev-sans-first:7.73 03:17:41 INFO - PROCESS | 1685 | Values: 113.0 114.0 118.0 112.0 111.0 111.0 112.0 112.0 112.0 135.0 03:17:41 INFO - PROCESS | 1685 | 03:17:41 INFO - PROCESS | 1685 | [#1] audio-beat-detection Cycles:10 Average:152.20 Median:159.50 stddev:23.15 (14.5%) stddev-sans-first:24.45 03:17:41 INFO - PROCESS | 1685 | Values: 158.0 161.0 189.0 155.0 141.0 173.0 138.0 118.0 170.0 119.0 03:17:41 INFO - PROCESS | 1685 | 03:17:41 INFO - PROCESS | 1685 | [#2] audio-dft Cycles:10 Average:184.50 Median:185.50 stddev:2.07 (1.1%) stddev-sans-first:2.12 03:17:41 INFO - PROCESS | 1685 | Values: 183.0 187.0 187.0 186.0 184.0 182.0 185.0 186.0 184.0 181.0 03:17:41 INFO - PROCESS | 1685 | 03:17:41 INFO - PROCESS | 1685 | [#3] audio-fft Cycles:10 Average:79.80 Median:62.00 stddev:33.70 (54.4%) stddev-sans-first:35.65 03:17:41 INFO - PROCESS | 1685 | Values: 73.0 75.0 74.0 151.0 55.0 58.0 58.0 66.0 132.0 56.0 03:17:41 INFO - PROCESS | 1685 | 03:17:41 INFO - PROCESS | 1685 | [#4] audio-oscillator Cycles:10 Average:97.30 Median:78.50 stddev:25.14 (32.0%) stddev-sans-first:26.59 03:17:41 INFO - PROCESS | 1685 | Values: 92.0 91.0 145.0 77.0 78.0 84.0 127.0 79.0 76.0 124.0 03:17:41 INFO - PROCESS | 1685 | 03:17:41 INFO - PROCESS | 1685 | [#5] imaging-gaussian-blur Cycles:10 Average:95.20 Median:86.50 stddev:19.79 (22.9%) stddev-sans-first:20.76 03:17:41 INFO - PROCESS | 1685 | Values: 87.0 84.0 87.0 83.0 86.0 83.0 87.0 90.0 134.0 131.0 03:17:41 INFO - PROCESS | 1685 | 03:17:41 INFO - PROCESS | 1685 | [#6] imaging-darkroom Cycles:10 Average:114.60 Median:121.50 stddev:23.63 (19.5%) stddev-sans-first:23.98 03:17:41 INFO - PROCESS | 1685 | Values: 95.0 132.0 150.0 126.0 93.0 92.0 93.0 113.0 151.0 101.0 03:17:41 INFO - PROCESS | 1685 | 03:17:41 INFO - PROCESS | 1685 | [#7] imaging-desaturate Cycles:10 Average:98.10 Median:84.00 stddev:22.00 (26.2%) stddev-sans-first:21.99 03:17:41 INFO - PROCESS | 1685 | Values: 119.0 133.0 136.0 84.0 83.0 84.0 86.0 84.0 86.0 86.0 03:17:41 INFO - PROCESS | 1685 | 03:17:41 INFO - PROCESS | 1685 | [#8] json-parse-financial Cycles:10 Average:64.60 Median:64.00 stddev:6.75 (10.6%) stddev-sans-first:6.85 03:17:41 INFO - PROCESS | 1685 | Values: 59.0 64.0 63.0 63.0 64.0 65.0 64.0 83.0 61.0 60.0 03:17:41 INFO - PROCESS | 1685 | 03:17:41 INFO - PROCESS | 1685 | [#9] json-stringify-tinderbox Cycles:10 Average:70.00 Median:70.00 stddev:3.65 (5.2%) stddev-sans-first:3.16 03:17:41 INFO - PROCESS | 1685 | Values: 64.0 68.0 71.0 75.0 75.0 69.0 68.0 68.0 68.0 74.0 03:17:41 INFO - PROCESS | 1685 | 03:17:41 INFO - PROCESS | 1685 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:81.00 stddev:10.63 (13.1%) stddev-sans-first:11.04 03:17:41 INFO - PROCESS | 1685 | Values: 81.0 82.0 81.0 82.0 81.0 79.0 81.0 93.0 105.0 107.0 03:17:41 INFO - PROCESS | 1685 | 03:17:41 INFO - PROCESS | 1685 | [#11] stanford-crypto-ccm Cycles:10 Average:137.60 Median:134.50 stddev:20.06 (14.9%) stddev-sans-first:21.25 03:17:41 INFO - PROCESS | 1685 | Values: 140.0 145.0 129.0 126.0 127.0 114.0 127.0 124.0 169.0 175.0 03:17:41 INFO - PROCESS | 1685 | 03:17:41 INFO - PROCESS | 1685 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.60 Median:167.00 stddev:2.63 (1.6%) stddev-sans-first:2.79 03:17:41 INFO - PROCESS | 1685 | Values: 167.0 171.0 169.0 167.0 164.0 164.0 165.0 170.0 165.0 164.0 03:17:41 INFO - PROCESS | 1685 | 03:17:41 INFO - PROCESS | 1685 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.10 Median:69.50 stddev:13.76 (19.8%) stddev-sans-first:14.10 03:17:41 INFO - PROCESS | 1685 | Values: 67.0 67.0 68.0 67.0 69.0 73.0 93.0 101.0 96.0 70.0 03:17:41 INFO - PROCESS | 1685 | -------- Summary: end -------- 03:17:41 INFO - PROCESS | 1685 | 03:17:42 INFO - TEST-INFO | 1685: exit 0 03:17:42 INFO - TEST-OK | kraken | took 59124ms 03:17:42 INFO - SUITE-END | took 1174s 03:17:42 INFO - Completed test suite (00:19:34) 03:17:42 INFO - javascript benchmark 03:17:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3376.814275214863, 3444.4488881778907, 3455.498952651865, 3455.6717111874395, 3514.139035936196, 5655.23654306669, 5656.858578535528, 5659.943400565999, 5664.801731939383, 5686.772529098841, 4731.810727570893, 5676.574256930737, 5694.430556944303, 5706.629069110505, 5722.685252311121, 5051.393832740058, 5175.327207463025, 5187.144121220003, 5261.316028916235, 5294.1529355303155, 5272.0, 5275.973620131894, 5288.048151332759, 5290.497402746752, 5291.126964050927, 5170.1986192140275, 5206.45332240115, 5222.556082732972, 5237.47625237477, 5238.423773384925, 5271.841844744645, 5293.444188360244, 5295.655782374172, 5302.681839089667, 5353.464653534655, 5098.209777484496, 5123.7438128093445, 5194.27280180775, 5217.947820521784, 5242.973785131088, 5463.153211252262, 5495.6153069284765, 5524.530414914737, 5557.9717752215965, 5627.0, 5119.3088932994315, 6154.96922515389, 6181.381861813783, 6182.1345011698395, 6200.813975580695, 5803.65178089316, 6109.5723299369065, 6238.469730072904, 6242.87514249717, 6247.187865577492, 5725.570582206351, 5771.480566748971, 5805.303363596396, 5880.617759845596, 5883.735231914574, 6288.0, 6318.684065796691, 6324.272708638474, 6339.746410143634, 6341.048842673636, 5916.083007133901, 6122.938770612237, 6159.4456498915315, 6161.4454699076405, 6165.414285642857, 6168.0439531872635, 6172.938270617326, 6270.122182894398, 6270.749170033238, 6280.497560195174, 6096.969515152396, 6157.507399408037, 6162.969185154046, 6174.536909731788, 6181.629102253879, 6069.271687397452, 6201.875962480815, 6223.96888015557, 6232.781852635136, 6239.344868788802, 4878.0975519529, 4886.828960986349, 4898.681585696918, 4900.656954013185, 4907.87730306738, 4364.214441400578, 4391.187630288407, 4423.668224883082, 4500.954990450054, 4513.661475389359, 5692.8861422771315, 5693.0, 5702.429757024264, 5712.771489140471, 5722.0, 6240.7503699852405, 6270.216222972129, 6285.962816135378, 6298.716557754912, 6298.842528936813, 5583.357913839879, 5668.8866222675315, 5669.31968163823, 5673.063944449203, 5754.510866576305, 5371.301730774974, 5383.246345511631, 5386.461353864661, 5387.0, 5393.6763794172475, 6348.619082855043, 6356.682165891687, 6361.968190159019, 6362.491000719931, 6371.33101024395, 6068.332483426908, 6130.673316708229, 6139.093519790236, 6216.974199257072, 6279.183706118175, 6380.170577824853, 6383.265924418565, 6414.583052101599, 6421.197350331208, 6424.325445828121, 6127.509799216141, 6199.504039676905, 6216.347283535164, 6226.750929962751, 6236.282827474898, 4692.343071969995, 6019.0, 6154.907676384947, 6277.0, 6278.780242691484, 6117.401149625572, 6124.83277758918, 6124.969375153096, 6128.8774224516155, 6156.538259630456, 6070.544709146743, 6093.116498107838, 6122.081687746873, 6124.356942521149, 6142.04798256271, 5786.94213057864, 5800.854978625399, 5893.116032594973, 5909.320428150646, 5912.320083190316, 5737.139429085503, 5799.797007104731, 6055.969720151371, 6124.755009799558, 6135.600157732601, 5936.881262374642, 5944.078667806497, 5948.791792287249, 5957.851053723692, 6000.039993601003], "value": 5678.4249128626225, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [509.69418348990604, 512.1063743767127, 528.8466344760018, 569.8888716700244, 575.804226562969, 22035.889820550896, 38052.61947380525, 39014.609853901486, 39064.41403378944, 39176.80411597945, 42173.367399489034, 42563.36154957678, 42614.57385426145, 42637.78681106598, 42700.78649606755, 8432.704855330065, 9383.81232375352, 9606.855897161548, 9619.855702164474, 9620.230381569492, 8544.572771361438, 8683.088275731052, 8693.217610415062, 8716.128387161265, 11492.310461372344, 11492.655220343404, 11536.365499897502, 11549.595764148256, 11558.595449159282, 11606.12954028447, 11483.770324593503, 11556.422178891105, 11557.19099663024, 11559.653210403701, 11576.53693852245, 8513.489190648581, 8768.07935166811, 8768.561571921378, 11387.202895797298, 11425.657230283105, 38966.0, 39526.802365988275, 39530.0117497062, 39589.80205098985, 39616.60383396187, 3167.33485967947, 3252.414565378231, 3265.1673823175174, 3270.934581308384, 3539.2390636013124, 27539.86230068857, 27737.445251094865, 27784.861075694695, 28171.29571760702, 28348.0, 2671.7862570994275, 2713.1996061162004, 2757.8483183424905, 2760.0, 2779.5274803283287, 369326.0, 370397.0, 370629.1468542667, 371006.0, 372335.0, 64485.67757161278, 64712.67643661846, 64826.0, 64866.999999999054, 65006.0, 68307.65846170737, 68699.0, 68858.3114168862, 68972.96540551796, 68978.31021689819, 16790.83209167917, 17255.050972196397, 17287.481375558753, 17296.308147674205, 17307.39424120175, 12283.93858030722, 13265.734685306239, 13327.0, 13327.0, 13400.262985535694, 18035.008074556026, 18186.18162182705, 18231.544211394557, 18246.63506729858, 18485.907570462063, 7049.400800931979, 7129.465290103263, 7166.355028047396, 7174.174969878531, 7229.2047874733735, 991.861139440479, 1008.5511947183433, 1011.4841430870309, 1111.938099115343, 1126.4480404601686, 29960.400791984473, 30248.0, 30291.709721007555, 30310.84844575807, 30324.130246378605, 1034.6637342863448, 1065.414022287754, 1116.6929094499078, 1187.4597058338436, 1218.2994778002508, 13712.65718357049, 14983.250837458301, 15047.924760376129, 15404.922975385276, 15405.768913466307, 590984.0, 912813.0, 932737.0, 933176.0, 934037.0, 58552.7072364644, 58859.0, 59293.40706592879, 59413.108803367984, 59476.70261648664, 1488.642725745835, 1490.9701805964037, 1491.2171110167249, 1502.0687173952, 1506.7965824613536, 24150.0, 29199.0, 29762.851185743933, 30456.0, 30499.390012199186, 51927.22109168441, 52262.21606675827, 52341.7382913083, 52342.47657523376, 52446.0, 469791.0, 472185.0, 472369.0, 472610.0, 473084.0, 24352.391190219678, 24800.751992480567, 26440.73559264383, 27100.593491098043, 27410.177694669193, 34821.651783481844, 42894.0, 43040.784796077074, 44147.77926110349, 44697.32954005628, 34865.0, 36105.91682249537, 36143.45784813283, 36258.4561231587, 36938.8153059233, 36286.27427451489, 36583.08542286464, 36758.63241367659, 37239.88280351594, 37392.25215495621, 36169.45745813868, 37037.07407314839, 37271.0, 37670.8116459416, 37784.62215377811, 32239.838800805846, 34085.65914340825, 34213.0, 34843.0, 35658.46512302266, 19032.42902712921, 24890.626640600043, 25071.247862564152, 25251.74748252494, 25336.11323603665], "value": 19888.853893715674, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.36659711059417, 401.19760479041923, 403.4009495898592, 403.5843081625926, 415.79833780616394, 14944.551663450115, 15707.685846283068, 16194.271257793398, 20281.59436811263, 20285.594288114247, 14091.0, 14776.556703298891, 15613.765793513077, 16231.513054608351, 19264.903675481604, 3869.9613003869954, 3918.789674772184, 4014.8996275093064, 5940.88118237635, 5943.167956486095, 5697.715114244271, 5838.649681019131, 5846.88306233875, 5878.441548052928, 5897.705114744267, 5796.710164491779, 5819.592628516006, 5838.1534677471955, 5842.24050873388, 5843.123531470293, 4051.1695102504063, 4259.5740425957465, 5686.772529098831, 6391.520635952322, 6401.711922963478, 5572.136318870582, 5575.916361254584, 5586.217929489874, 5591.580631452657, 5597.132444471114, 22858.42853928665, 23661.29016129519, 23926.52146957051, 23981.880090599436, 24977.500449990897, 2206.9227577034885, 2297.414159389362, 2576.0854896511655, 2738.904438224706, 2871.7415432611165, 9654.75863103421, 9990.800183996353, 10098.394096354243, 13326.067175297732, 13764.724705505834, 1628.7719719239317, 1669.4407373529762, 1734.6443978984278, 1737.0446254560068, 1737.5134962210602, 101217.49391252997, 121374.78625213723, 121516.0, 123731.0, 128830.0, 11391.259568128045, 11542.94228528852, 11604.6518604442, 11610.477528511068, 11747.412629368497, 14734.336954837057, 14896.0, 14937.551873443814, 14949.551513454613, 14963.925180374246, 7745.651445684957, 8775.034746177915, 8779.0, 8860.512671803113, 8889.555522223864, 7026.1217347831525, 7394.704211831574, 7459.664315105833, 7849.626315394783, 7958.363330933512, 4559.54404559548, 7486.7005319786595, 9146.085391460801, 9283.767905802273, 9452.810943781085, 3265.3959017581824, 3667.8532858685353, 3755.1738617504107, 3759.9132481472498, 3869.123475114567, 920.7513971227731, 981.622075500923, 1013.4679293371039, 1015.304516406264, 1036.9481525923673, 19690.507737306394, 20487.590248194952, 20544.794552054587, 20676.793232067488, 20929.0, 968.7551496327186, 984.8378912882724, 992.4739887859446, 1008.2186305613209, 1032.7418145463635, 10659.200559958033, 10703.882526634985, 10728.946355268174, 10737.409442480739, 10834.566617335218, 622332.0, 624181.0, 626140.0, 629435.0, 643481.0, 127276.36361818132, 131873.0, 133098.0, 133324.0, 133403.0, 1013.8225810483283, 1014.2089170447063, 1025.220832167558, 1029.3978022856563, 1031.7884833609091, 15532.456364027204, 16420.425285114965, 17437.47687569375, 17514.211860466054, 18103.275868965095, 39740.0, 54553.726321473536, 54650.7267463676, 55964.16053759115, 56618.716906415204, 78911.81632275635, 79592.60203698944, 79906.0, 80175.59912200402, 80527.0, 8082.959585202037, 8108.83782324362, 8173.509589424653, 8379.329653627574, 8408.033076196316, 9120.224780893699, 11778.411079446165, 12270.877291226974, 12719.618411447671, 12815.359232038547, 10675.306105103144, 10863.837042444527, 10865.239433239598, 10925.617603383844, 10928.836067459153, 10455.268131230741, 10646.89353106459, 10724.356538607708, 10766.73083172927, 10823.242373034127, 8450.492970421794, 10736.677899663022, 10757.677269681923, 11280.830787538029, 11381.43092845338, 8185.263326300662, 8195.221453961864, 8307.584620769057, 8328.125546817739, 8463.153684631488, 7828.72599459039, 9301.953490232505, 10308.020738029874, 10376.533056012346, 10572.947135264274], "value": 9681.140561825894, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1342.523404191512, 1447.992760036201, 1605.3097168217669, 1783.8216178382163, 1944.7246721336035, 7991.600419979007, 8650.653973841047, 8824.558772061388, 8835.160659737314, 8857.202851743341, 8612.22489975902, 8849.964508163126, 8859.557022148883, 8863.246624036965, 8907.643694252238, 2459.3482727077385, 2494.550980823451, 2498.525280196766, 2498.9750102498924, 2500.2124330835727, 2213.878236696981, 2418.141559746291, 2483.8261321707396, 2488.4401009772837, 2488.539620170265, 2323.082382458928, 2447.889844956981, 2486.0304481252324, 2488.7635674610883, 2490.7882829959476, 2046.6623007203868, 2447.657327974085, 2477.1330034487964, 2484.4037431016427, 2488.216211893248, 2436.1838784007377, 2460.90156393744, 2467.728549859514, 2471.1598056660823, 2474.851508909453, 8571.185737354941, 8879.0, 8937.553122343857, 8944.10558944107, 8958.507282099481, 5421.349438067457, 5584.972075139639, 5643.520300774439, 5647.830565083054, 5648.887022259573, 3619.1133172372674, 3657.7256705747177, 3716.349638813197, 3765.246950609889, 3781.470594116826, 1052.3738375666467, 1064.4039337970762, 1064.8668916385452, 1074.382230217628, 1104.3981030338484, 10513.264071514997, 11863.406829658568, 12091.153619246656, 12175.208611440317, 12206.816897746452, 4582.587567118909, 4667.206574882211, 4673.929891051637, 4743.288506724019, 4747.477777444478, 9455.90544094564, 9485.952570237105, 9486.762830929281, 9493.193078588398, 9493.335466517277, 5385.865353366197, 5405.324334458192, 5440.129579267298, 5444.673319600836, 5450.645708028966, 4451.310046942729, 4569.794359253841, 4838.443578988461, 5002.699838009731, 5077.339945807022, 3838.961610383916, 3867.806609669505, 3934.567197608261, 3952.822123004472, 3953.584873588232, 1193.9462724177433, 1196.2583198417037, 1200.7358381156132, 1203.7351782607811, 1204.8795120488057, 675.3280485916545, 833.9791505212297, 921.5484412637893, 953.9379940303996, 957.7988622389237, 5751.683657398799, 5806.564507661942, 5955.851103722442, 6013.1581578579035, 6043.54673399488, 504.5963229416452, 640.810960766575, 1003.5298288008103, 1062.3625824505389, 1102.2780079048237, 3838.846446142179, 3942.191850670663, 3943.8028098594955, 4036.3340048891605, 4065.0650350418978, 10652.09457196147, 10800.081993030522, 11075.781664016951, 11086.279391839505, 11116.777664446667, 10805.189610779224, 10918.235723499438, 10923.0, 10923.071538919123, 10932.398718070583, 7867.0, 8242.010958684996, 8326.000879894451, 8329.833403331899, 8384.874226886479, 5474.972625136849, 5887.882242355044, 5904.793332233351, 6021.939780602138, 6029.517638588814, 9308.674196403093, 9420.95289523548, 9595.760105997128, 9737.756556086155, 9771.0, 9833.016698330395, 9859.802803943738, 9874.358166719141, 9927.950360248442, 10080.798384032425, 7480.551166930001, 7562.2437756223935, 7631.503952243086, 7649.61751912391, 7651.0, 8294.419390642597, 8320.16798320163, 8338.499690018618, 8372.162783721822, 8445.0, 7511.06111736033, 7535.8492830141995, 7543.1325397579985, 7670.0, 7698.615069246627, 7272.963635182002, 7321.560706357635, 7401.666924988496, 7407.666655000646, 7419.369353605004, 6974.4769142315545, 6974.546654467444, 6992.230854606091, 7024.121984751906, 7027.789166325018, 4804.375431193923, 4811.08589368019, 4935.309056732132, 4990.750462476789, 5019.272205530251, 4969.776360063734, 5048.469910659328, 5070.974645126751, 5073.213651883964, 5186.325777648882], "value": 4612.909953031386, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.6695969232304, 321.30437602590393, 330.2322101114909, 330.4778450048923, 330.83788943417727, 6494.318096599854, 6565.080888675589, 6571.901421478682, 6573.046908198315, 6581.078648989145, 6269.592476489025, 6411.775587854426, 6450.9354906451035, 6462.579932304397, 6526.608403495794, 4893.070316639836, 4909.926351104737, 4933.802647894098, 4955.950440495603, 4966.106100901836, 4869.634777391692, 4921.876953076166, 4924.433690125627, 4941.308216849644, 4962.330085438473, 3858.498395208619, 4599.908001839978, 4684.156851766681, 4878.975605121969, 4894.657373983822, 4797.184478638623, 4823.155947709154, 4848.975755121237, 4860.3195552622665, 4876.463589005207, 4151.6886233532305, 4152.979235103805, 4157.896052598679, 4165.812538435777, 4173.770442625654, 5100.311457953166, 6307.0, 6317.336679648617, 6325.87348253037, 6340.904886426707, 5680.687562184078, 5691.6585004899425, 5692.459216374438, 5700.173474846166, 5703.486686198221, 6795.422389096932, 6974.895376569356, 6989.685464154125, 6994.370506654374, 7027.859442811166, 4658.767061646938, 4694.413198350207, 4706.7175969441605, 4714.0, 4718.528147185289, 7436.330730234251, 7449.180590135081, 7449.925500744977, 7466.253374662545, 7469.5144815587355, 3919.784411857318, 4748.620110391161, 4751.50109238525, 4755.643326750507, 4775.73733444657, 4831.299461578052, 4832.9275060874115, 4842.225243960949, 4846.055019271279, 4867.756612169377, 5322.840314790563, 5521.5306698931045, 5707.086866101404, 5714.200011998323, 5714.200011998323, 6976.407005404598, 6978.092847929736, 7026.824329391806, 7066.893996590055, 7077.610731409822, 1606.3654856331684, 1628.9266982985794, 1631.7633943078188, 1633.5589390864639, 1634.8474325600423, 970.7233438470003, 983.5524836199512, 1020.8723909511311, 1040.6877936618985, 1043.860086822027, 6437.195350581177, 6451.225852897683, 6510.837229069217, 6522.641254731036, 6548.705308261045, 7220.711171553184, 7227.602481863548, 7229.2409297024105, 7247.2390399007345, 7256.129264488295, 6487.286398496161, 6494.1232933553365, 6501.0898474213645, 6503.772367967098, 6524.217093948757, 3273.4762438009802, 3711.9628803712158, 3759.793211373346, 3787.2614840106467, 3818.236352729465, 7676.234466072835, 7681.423893208032, 7728.304452599293, 7732.7680169595005, 7737.92262077372, 7549.9245007549225, 7565.508241964365, 7580.128285247267, 7592.696292148363, 7596.886046709304, 6225.3774622537385, 7684.6926122954455, 7697.961510192413, 7702.460827742117, 7705.42209334291, 7025.192102908231, 7321.487495875451, 7326.780196594111, 7327.670254838438, 7374.631268436664, 7230.168530619047, 7254.129504459499, 7271.636418179176, 7275.163356214103, 7294.817629559304, 7715.884261735966, 7720.922790772245, 7732.1494635588915, 7733.458657893893, 7746.457747957815, 4595.126925884071, 4634.2585186370015, 4638.768061596974, 4639.884002899955, 4644.396228490275, 6971.407430368476, 7263.128424588872, 7297.0, 7302.817429564303, 7311.634418279171, 6997.650117494003, 7051.894221586577, 7060.258672839484, 7063.223045464892, 7067.6112813795735, 7036.577805331696, 7037.190723066708, 7041.788746337618, 7046.964765176346, 7047.647617619201, 6863.0, 6869.587824730333, 6887.0, 6887.139107611549, 6890.931090689029, 6766.18805743314, 6995.860082798214, 7014.789556313319, 7014.894776578253, 7015.508914375945, 6643.40209381158, 6773.559718618274, 6798.456123510206, 6882.311768823078, 6890.724371025303], "value": 5142.842650149799, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [660.2440205964172, 671.4628297362112, 682.5290549520831, 724.9601271930048, 727.131078361358, 13345.394029955185, 14942.402303907831, 14976.0, 15150.848491515082, 15168.696626067485, 12264.141510094298, 12521.248725076503, 13713.177209367444, 13867.791983120236, 13906.582802515915, 895.7133717210495, 919.0, 919.2048877720757, 919.5586118663045, 922.1746536849515, 898.9820203595924, 908.3278374003223, 912.0651332384326, 914.7621618379236, 918.3892711346946, 749.2732049911577, 943.5612440215299, 961.1877963121186, 963.0995019656608, 963.5423173992333, 950.7147855643314, 951.923846092311, 952.1145334838598, 953.8998405167426, 954.6467806911453, 709.4749885085051, 709.8473828126926, 709.8757717399486, 710.8827043537862, 711.345562082885, 14544.2000689962, 14615.0, 14617.122972621677, 14620.97653164279, 14941.551753447415, 12602.0, 12671.429785659668, 12919.22484650924, 12944.417501212469, 13056.412461439162, 9983.550740216708, 10036.29745917786, 10082.395056296573, 10085.798284034352, 10401.84397234042, 3482.791032538056, 3483.2336885885065, 3546.1666508370513, 3582.6059133495555, 3617.7286703497343, 81852.59073704653, 81893.0, 82509.76235356474, 83448.58275708643, 83886.74169887457, 9172.770680732901, 9325.626974921062, 9432.309193365341, 9566.426014439156, 9683.225341972626, 20326.186952522028, 20384.082716277804, 20625.071871765806, 20699.689504657443, 20746.58506829855, 7505.362044226302, 7538.924610753932, 7559.546427214274, 7572.4699271050495, 7644.273793989562, 4026.154507553388, 4100.0, 4109.712320137562, 4158.7296825706235, 4248.171606536695, 3335.849886755102, 3445.9483107753404, 3533.876314328986, 3590.1383667919513, 3597.6762091411897, 668.3483603486561, 672.2907332763883, 689.9931000690029, 690.2372877969872, 692.3353580562716, 538.249142446293, 567.545963229423, 581.6045089339206, 583.1718959078034, 598.9940100598939, 7256.82907904416, 7416.888746668804, 8132.75601731949, 8189.0, 8223.54770487629, 586.5161241975387, 589.4547543522225, 589.6255877517807, 605.8061420345447, 607.5261296188979, 3833.961660383416, 3903.9219215616095, 3930.4300876373336, 3950.841966321315, 3965.6629186519467, 90619.0, 95397.52301238588, 95519.0, 95801.5209923946, 96015.71913544822, 77582.0, 77637.0, 79330.20669793343, 80047.59976200081, 81073.0, 67010.32474188185, 69418.3058169422, 69815.0, 69926.70659640657, 70473.94289085668, 7620.657070431955, 7875.0, 8122.431429799857, 8253.628586713492, 8360.163983601591, 42610.14779704327, 43285.0, 48088.0, 48704.2694359592, 48778.512214877395, 59572.70213648904, 67502.98745518723, 68783.65608171927, 68817.96773048508, 69987.6500617514, 10889.401082940514, 10963.890361096286, 11112.444377781241, 11246.606368777053, 11290.43547822622, 11701.2979221247, 11794.0, 12001.39993000364, 12031.15781895259, 12437.689057773629, 11229.831552526555, 11304.660860174208, 11435.542578297107, 11731.941340293244, 12449.502019919102, 11019.889801102207, 11152.944235279096, 11283.05504910756, 11618.5352585899, 11811.763764724485, 11170.050545703707, 11195.0, 11201.551937922392, 11480.712982175512, 11795.528178873092, 9218.262538996998, 9649.0, 9726.416415015121, 9748.85376719364, 9782.951085244527, 9419.717408477756, 9571.856422153813, 9625.0, 9739.707808765748, 9802.460864652512], "value": 6882.220778749334, "unit": "score"}], "name": "dromaeo_css", "value": 7503.86074049466, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 118.0, 112.0, 111.0, 111.0, 112.0, 112.0, 112.0, 135.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 161.0, 189.0, 155.0, 141.0, 173.0, 138.0, 118.0, 170.0, 119.0], "value": 152.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 187.0, 187.0, 186.0, 184.0, 182.0, 185.0, 186.0, 184.0, 181.0], "value": 184.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 74.0, 151.0, 55.0, 58.0, 58.0, 66.0, 132.0, 56.0], "value": 79.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 145.0, 77.0, 78.0, 84.0, 127.0, 79.0, 76.0, 124.0], "value": 97.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 83.0, 86.0, 83.0, 87.0, 90.0, 134.0, 131.0], "value": 95.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 132.0, 150.0, 126.0, 93.0, 92.0, 93.0, 113.0, 151.0, 101.0], "value": 114.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 133.0, 136.0, 84.0, 83.0, 84.0, 86.0, 84.0, 86.0, 86.0], "value": 98.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 64.0, 63.0, 63.0, 64.0, 65.0, 64.0, 83.0, 61.0, 60.0], "value": 64.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 68.0, 71.0, 75.0, 75.0, 69.0, 68.0, 68.0, 68.0, 74.0], "value": 70.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 81.0, 82.0, 81.0, 79.0, 81.0, 93.0, 105.0, 107.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 145.0, 129.0, 126.0, 127.0, 114.0, 127.0, 124.0, 169.0, 175.0], "value": 137.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 171.0, 169.0, 167.0, 164.0, 164.0, 165.0, 170.0, 165.0, 164.0], "value": 166.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 67.0, 69.0, 73.0, 93.0, 101.0, 96.0, 70.0], "value": 77.1, "unit": "score"}], "name": "kraken", "value": 1539.8, "alertThreshold": 2.0}]} 03:17:42 INFO - Return code: 0 03:17:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:17:42 ERROR - # TBPL SUCCESS # 03:17:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:17:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:17:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:17:42 INFO - 2016-06-06 03:17:42.618 screenresolution[1689:9950] starting screenresolution argv=screenresolution get 03:17:42 INFO - 2016-06-06 03:17:42.620 screenresolution[1689:9950] Display 0: 1600x1200x32@60 03:17:42 INFO - 2016-06-06 03:17:42.626 screenresolution[1690:9953] starting screenresolution argv=screenresolution list 03:17:42 INFO - Available Modes on Display 0 03:17:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:17:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:17:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:17:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:17:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:17:42 INFO - Intel Iris: 03:17:42 INFO - Chipset Model: Intel Iris 03:17:42 INFO - Type: GPU 03:17:42 INFO - Bus: Built-In 03:17:42 INFO - VRAM (Dynamic, Max): 1536 MB 03:17:42 INFO - Vendor: Intel (0x8086) 03:17:42 INFO - Device ID: 0x0a2e 03:17:42 INFO - Revision ID: 0x0009 03:17:42 INFO - Displays: 03:17:42 INFO - Display: 03:17:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:17:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:17:42 INFO - Display Serial Number: 1600x1200 60 03:17:42 INFO - Main Display: Yes 03:17:42 INFO - Mirror: Off 03:17:42 INFO - Online: Yes 03:17:42 INFO - Rotation: Supported 03:17:42 INFO - Return code: 0 03:17:42 INFO - Running post-action listener: _resource_record_post_action 03:17:42 INFO - Running post-run listener: _resource_record_post_run 03:17:43 INFO - Total resource usage - Wall time: 1208s; CPU: 52.0%; Read bytes: 37676544; Write bytes: 654575104; Read time: 393; Write time: 2131 03:17:43 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 354417152; Write bytes: 346633728; Read time: 27725; Write time: 660 03:17:43 INFO - run-tests - Wall time: 1177s; CPU: 52.0%; Read bytes: 30625280; Write bytes: 297144320; Read time: 304; Write time: 1400 03:17:43 INFO - Running post-run listener: _upload_blobber_files 03:17:43 INFO - Blob upload gear active. 03:17:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:17:43 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 03:17:43 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'] 03:17:43 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 03:17:44 INFO - (blobuploader) - INFO - Open directory for files ... 03:17:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:17:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:17:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:17:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:17:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:17:45 INFO - (blobuploader) - INFO - Done attempting. 03:17:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:17:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:17:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:17:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:17:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:17:46 INFO - (blobuploader) - INFO - Done attempting. 03:17:46 INFO - (blobuploader) - INFO - Iteration through files over. 03:17:46 INFO - Return code: 0 03:17:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:17:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:17:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee21659c3aebbf6b004572b1203195957592a54f0a7736b12c4bf4015f50724e9b6ecb6790c78a5fd830d2cb35e8153cf9b956be7c91eb6dc804c8cbd129ab57", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c416ea241dfd7266fbd32542ad72e5da259f4b26bc719b4a499efda437abed6826fe722725ef9ce2ae6c56d4c7edcf18c0cc604c2ea301c37c5ac87f66157f21"} 03:17:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:17:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:17:46 INFO - Contents: 03:17:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee21659c3aebbf6b004572b1203195957592a54f0a7736b12c4bf4015f50724e9b6ecb6790c78a5fd830d2cb35e8153cf9b956be7c91eb6dc804c8cbd129ab57", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c416ea241dfd7266fbd32542ad72e5da259f4b26bc719b4a499efda437abed6826fe722725ef9ce2ae6c56d4c7edcf18c0cc604c2ea301c37c5ac87f66157f21"} 03:17:46 INFO - Running post-run listener: copy_logs_to_upload_dir 03:17:46 INFO - Copying logs to upload dir... 03:17:46 INFO - mkdir: /builds/slave/test/build/upload/logs 03:17:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.605533 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-06-06 03:17:46.401232) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 03:17:46.407759) ========= 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.XDghxrnrIf/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.H1SApKv8nz/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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/ee21659c3aebbf6b004572b1203195957592a54f0a7736b12c4bf4015f50724e9b6ecb6790c78a5fd830d2cb35e8153cf9b956be7c91eb6dc804c8cbd129ab57", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c416ea241dfd7266fbd32542ad72e5da259f4b26bc719b4a499efda437abed6826fe722725ef9ce2ae6c56d4c7edcf18c0cc604c2ea301c37c5ac87f66157f21"} 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.010941 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/ee21659c3aebbf6b004572b1203195957592a54f0a7736b12c4bf4015f50724e9b6ecb6790c78a5fd830d2cb35e8153cf9b956be7c91eb6dc804c8cbd129ab57", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c416ea241dfd7266fbd32542ad72e5da259f4b26bc719b4a499efda437abed6826fe722725ef9ce2ae6c56d4c7edcf18c0cc604c2ea301c37c5ac87f66157f21"}' ========= master_lag: 0.82 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 03:17:47.238783) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 03:17:47.239118) ========= 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.XDghxrnrIf/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.H1SApKv8nz/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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.005070 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 03:17:47.267458) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 03:17:47.267765) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 03:17:47.268095) ========= ========= Total master_lag: 0.97 =========