builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0308 starttime: 1465220870.91 results: success (0) buildid: 20160606010529 builduid: 78750d9664274d05bee96204469a3a74 revision: d8adb38dd576398dacbfb96d2c247f8e0158bdf1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:50.911678) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:50.912119) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:50.912402) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:50.933788) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:50.934130) ========= 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.djG6xTcUqP/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.dnDYTOcxny/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-06-06 06:47:50-- 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% 7.74M=0.001s 2016-06-06 06:47:50 (7.74 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.091131 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:51.045541) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:51.045853) ========= 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.djG6xTcUqP/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.dnDYTOcxny/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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.021038 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:51.094676) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:51.095034) ========= 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.djG6xTcUqP/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.dnDYTOcxny/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-06-06 06:47:51,122 truncating revision to first 12 chars 2016-06-06 06:47:51,123 Setting DEBUG logging. 2016-06-06 06:47:51,123 attempt 1/10 2016-06-06 06:47:51,123 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d8adb38dd576?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-06 06:47:51,821 unpacking tar archive at: mozilla-aurora-d8adb38dd576/testing/mozharness/ program finished with exit code 0 elapsedTime=0.968786 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:52.085128) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:52.085492) ========= script_repo_revision: d8adb38dd576398dacbfb96d2c247f8e0158bdf1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:52.085926) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:52.086192) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 06:47:52.103319) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-06-06 06:47:52.103638) ========= /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.djG6xTcUqP/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.dnDYTOcxny/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 06:47:52 INFO - MultiFileLogger online at 20160606 06:47:52 in /builds/slave/test 06:47:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 06:47:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:47:52 INFO - {'append_to_log': False, 06:47:52 INFO - 'base_work_dir': '/builds/slave/test', 06:47:52 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 06:47:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:47:52 INFO - 'branch': 'Mozilla-Aurora', 06:47:52 INFO - 'buildbot_json_path': 'buildprops.json', 06:47:52 INFO - 'config_files': ('talos/mac_config.py',), 06:47:52 INFO - 'default_actions': ('clobber', 06:47:52 INFO - 'read-buildbot-config', 06:47:52 INFO - 'download-and-extract', 06:47:52 INFO - 'populate-webroot', 06:47:52 INFO - 'create-virtualenv', 06:47:52 INFO - 'install', 06:47:52 INFO - 'run-tests'), 06:47:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:47:52 INFO - 'download_minidump_stackwalk': True, 06:47:52 INFO - 'download_symbols': 'ondemand', 06:47:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:47:52 INFO - 'tooltool.py': '/tools/tooltool.py', 06:47:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:47:52 INFO - '/tools/misc-python/virtualenv.py')}, 06:47:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:47:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:47:52 INFO - 'installer_path': 'installer.exe', 06:47:52 INFO - 'log_level': 'info', 06:47:52 INFO - 'log_name': 'talos', 06:47:52 INFO - 'log_to_console': True, 06:47:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:47:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:47:52 INFO - 'opt_config_files': (), 06:47:52 INFO - 'pip_index': False, 06:47:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:47:52 INFO - 'cmd': ('bash', 06:47:52 INFO - '-c', 06:47:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:47:52 INFO - 'enabled': True, 06:47:52 INFO - 'halt_on_failure': False, 06:47:52 INFO - 'name': 'check_screen_resolution'},), 06:47:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:47:52 INFO - 'cmd': ('bash', 06:47:52 INFO - '-c', 06:47:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:47:52 INFO - 'enabled': True, 06:47:52 INFO - 'halt_on_failure': False, 06:47:52 INFO - 'name': 'check_screen_resolution'},), 06:47:52 INFO - 'run_cmd_checks_enabled': True, 06:47:52 INFO - 'sps_profile': False, 06:47:52 INFO - 'sps_profile_interval': 0, 06:47:52 INFO - 'suite': 'dromaeojs', 06:47:52 INFO - 'system_bits': '32', 06:47:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:47:52 INFO - 'title': 't-yosemite-r7-0308', 06:47:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:47:52 INFO - 'use_talos_json': True, 06:47:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:47:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:47:52 INFO - 'work_dir': 'build'} 06:47:52 INFO - ##### 06:47:52 INFO - ##### Running clobber step. 06:47:52 INFO - ##### 06:47:52 INFO - Running pre-action listener: _resource_record_pre_action 06:47:52 INFO - Running main action method: clobber 06:47:52 INFO - rmtree: /builds/slave/test/build 06:47:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:47:53 INFO - Running post-action listener: _resource_record_post_action 06:47:53 INFO - ##### 06:47:53 INFO - ##### Running read-buildbot-config step. 06:47:53 INFO - ##### 06:47:53 INFO - Running pre-action listener: _resource_record_pre_action 06:47:53 INFO - Running main action method: read_buildbot_config 06:47:53 INFO - Using buildbot properties: 06:47:53 INFO - { 06:47:53 INFO - "project": "", 06:47:53 INFO - "product": "firefox", 06:47:53 INFO - "script_repo_revision": "production", 06:47:53 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 06:47:53 INFO - "repository": "", 06:47:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 06:47:53 INFO - "buildid": "20160606010529", 06:47:53 INFO - "pgo_build": "False", 06:47:53 INFO - "basedir": "/builds/slave/test", 06:47:53 INFO - "buildnumber": 16, 06:47:53 INFO - "slavename": "t-yosemite-r7-0308", 06:47:53 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 06:47:53 INFO - "platform": "yosemite_r7", 06:47:53 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 06:47:53 INFO - "branch": "mozilla-aurora", 06:47:53 INFO - "repo_path": "releases/mozilla-aurora", 06:47:53 INFO - "revision": "d8adb38dd576398dacbfb96d2c247f8e0158bdf1", 06:47:53 INFO - "stage_platform": "macosx64", 06:47:53 INFO - "builduid": "78750d9664274d05bee96204469a3a74", 06:47:53 INFO - "slavebuilddir": "test" 06:47:53 INFO - } 06:47:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 06:47:53 INFO - Running post-action listener: _resource_record_post_action 06:47:53 INFO - ##### 06:47:53 INFO - ##### Running download-and-extract step. 06:47:53 INFO - ##### 06:47:53 INFO - Running pre-action listener: _resource_record_pre_action 06:47:53 INFO - Running main action method: download_and_extract 06:47:53 INFO - mkdir: /builds/slave/test/build/tests 06:47:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:47:53 INFO - https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 06:47:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 06:47:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 06:47:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 06:47:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 06:47:55 INFO - Downloaded 1199 bytes. 06:47:55 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 06:47:55 INFO - Using the following test package requirements: 06:47:55 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 06:47:55 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:47:55 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 06:47:55 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:47:55 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 06:47:55 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:47:55 INFO - u'jsshell-mac.zip'], 06:47:55 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:47:55 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 06:47:55 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 06:47:55 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:47:55 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 06:47:55 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:47:55 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 06:47:55 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:47:55 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 06:47:55 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:47:55 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 06:47:55 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 06:47:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:47:55 INFO - https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:47:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 06:47:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 06:47:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 06:47:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 06:47:56 INFO - Downloaded 18466859 bytes. 06:47:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:47:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 06:47:59 INFO - Return code: 0 06:47:59 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 06:47:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:47:59 INFO - https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:47:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 06:47:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 06:47:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 06:47:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 06:48:01 INFO - Downloaded 18466859 bytes. 06:48:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:48:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 06:48:04 INFO - Return code: 0 06:48:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:04 INFO - https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 06:48:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 06:48:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 06:48:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 06:48:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 06:48:05 INFO - Downloaded 10840145 bytes. 06:48:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:48:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 06:48:05 INFO - Return code: 0 06:48:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:05 INFO - https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 06:48:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 06:48:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 06:48:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 06:48:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 06:48:08 INFO - Downloaded 114179823 bytes. 06:48:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 06:48:08 INFO - mkdir: /builds/slave/test/properties 06:48:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:48:08 INFO - Writing to file /builds/slave/test/properties/build_url 06:48:08 INFO - Contents: 06:48:08 INFO - build_url:https://queue.taskcluster.net/v1/task/CuA52A_4SlSbZdtsy9sFpA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 06:48:09 INFO - Running post-action listener: _resource_record_post_action 06:48:09 INFO - Running post-action listener: set_extra_try_arguments 06:48:09 INFO - ##### 06:48:09 INFO - ##### Running populate-webroot step. 06:48:09 INFO - ##### 06:48:09 INFO - Running pre-action listener: _resource_record_pre_action 06:48:09 INFO - Running main action method: populate_webroot 06:48:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:48:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:48:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:48:09 INFO - '--tppagecycles', 06:48:09 INFO - '7'], 06:48:09 INFO - 'tests': ['tsvgm']}}, 06:48:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:48:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:48:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:48:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:48:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:48:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:48:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:48:09 INFO - 'talos_options': ['--disable-e10s'], 06:48:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:48:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:48:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:48:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:48:09 INFO - 'talos_options': ['--disable-e10s'], 06:48:09 INFO - 'tests': ['damp', 'tps']}, 06:48:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:48:09 INFO - 'tests': ['damp', 'tps']}, 06:48:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:48:09 INFO - 'tests': ['dromaeo_dom']}, 06:48:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:48:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:48:09 INFO - 'tests': ['a11yr', 06:48:09 INFO - 'ts_paint', 06:48:09 INFO - 'tpaint', 06:48:09 INFO - 'sessionrestore', 06:48:09 INFO - 'sessionrestore_no_auto_restore', 06:48:09 INFO - 'tabpaint']}, 06:48:09 INFO - 'other-e10s': {'tests': ['a11yr', 06:48:09 INFO - 'ts_paint', 06:48:09 INFO - 'tpaint', 06:48:09 INFO - 'sessionrestore', 06:48:09 INFO - 'sessionrestore_no_auto_restore', 06:48:09 INFO - 'tabpaint']}, 06:48:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:48:09 INFO - 'ts_paint', 06:48:09 INFO - 'tpaint', 06:48:09 INFO - 'sessionrestore', 06:48:09 INFO - 'sessionrestore_no_auto_restore', 06:48:09 INFO - 'tabpaint']}, 06:48:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:48:09 INFO - 'ts_paint', 06:48:09 INFO - 'tpaint', 06:48:09 INFO - 'sessionrestore', 06:48:09 INFO - 'sessionrestore_no_auto_restore', 06:48:09 INFO - 'tabpaint']}, 06:48:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:48:09 INFO - 'tests': ['a11yr', 06:48:09 INFO - 'ts_paint', 06:48:09 INFO - 'tpaint', 06:48:09 INFO - 'sessionrestore', 06:48:09 INFO - 'sessionrestore_no_auto_restore', 06:48:09 INFO - 'tabpaint']}, 06:48:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:48:09 INFO - 'tests': ['a11yr', 06:48:09 INFO - 'ts_paint', 06:48:09 INFO - 'tpaint', 06:48:09 INFO - 'sessionrestore', 06:48:09 INFO - 'sessionrestore_no_auto_restore', 06:48:09 INFO - 'tabpaint']}, 06:48:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:48:09 INFO - 'tests': ['tsvgx', 06:48:09 INFO - 'tsvgr_opacity', 06:48:09 INFO - 'tart', 06:48:09 INFO - 'tscrollx', 06:48:09 INFO - 'cart']}, 06:48:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:48:09 INFO - 'tsvgr_opacity', 06:48:09 INFO - 'tart', 06:48:09 INFO - 'tscrollx', 06:48:09 INFO - 'cart']}, 06:48:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:48:09 INFO - 'talos_options': ['--disable-e10s'], 06:48:09 INFO - 'tests': ['tp5o']}, 06:48:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:48:09 INFO - 'tests': ['tp5o']}, 06:48:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:48:09 INFO - 'talos_options': ['--disable-e10s', 06:48:09 INFO - '--xperf_path', 06:48:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:48:09 INFO - 'tests': ['tp5n']}, 06:48:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:48:09 INFO - 'talos_options': ['--xperf_path', 06:48:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:48:09 INFO - 'tests': ['tp5n']}}, 06:48:09 INFO - 'talos.zip': {'path': '', 06:48:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:48:09 INFO - Running post-action listener: _resource_record_post_action 06:48:09 INFO - ##### 06:48:09 INFO - ##### Running create-virtualenv step. 06:48:09 INFO - ##### 06:48:09 INFO - Running pre-action listener: _resource_record_pre_action 06:48:09 INFO - Running main action method: create_virtualenv 06:48:09 INFO - Creating virtualenv /builds/slave/test/build/venv 06:48:09 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 06:48:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:48:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:48:09 INFO - Using real prefix '/tools/python27' 06:48:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:48:10 INFO - Installing distribute.............................................................................................................................................................................................done. 06:48:13 INFO - Installing pip.................done. 06:48:13 INFO - Return code: 0 06:48:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:48:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:13 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 0x1014d9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014e9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff72a48d300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101378e00>, '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 0x1011e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101373b20>, '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.djG6xTcUqP/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.dnDYTOcxny/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:48:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 06:48:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:48:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.djG6xTcUqP/Render', 06:48:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:13 INFO - 'HOME': '/Users/cltbld', 06:48:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:13 INFO - 'LOGNAME': 'cltbld', 06:48:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:13 INFO - 'MOZ_NO_REMOTE': '1', 06:48:13 INFO - 'NO_EM_RESTART': '1', 06:48:13 INFO - 'PAGER': '/bin/cat', 06:48:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:13 INFO - 'PWD': '/builds/slave/test', 06:48:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:13 INFO - 'SHELL': '/bin/bash', 06:48:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dnDYTOcxny/Listeners', 06:48:13 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 06:48:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:13 INFO - 'USER': 'cltbld', 06:48:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:13 INFO - 'XPC_FLAGS': '0x0', 06:48:13 INFO - 'XPC_SERVICE_NAME': '0', 06:48:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:13 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 06:48:13 INFO - Cleaning up... 06:48:13 INFO - Return code: 0 06:48:13 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 06:48:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:13 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 0x1014d9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014e9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff72a48d300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101378e00>, '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 0x1011e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101373b20>, '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.djG6xTcUqP/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.dnDYTOcxny/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:48:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 06:48:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 06:48:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.djG6xTcUqP/Render', 06:48:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:13 INFO - 'HOME': '/Users/cltbld', 06:48:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:13 INFO - 'LOGNAME': 'cltbld', 06:48:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:13 INFO - 'MOZ_NO_REMOTE': '1', 06:48:13 INFO - 'NO_EM_RESTART': '1', 06:48:13 INFO - 'PAGER': '/bin/cat', 06:48:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:13 INFO - 'PWD': '/builds/slave/test', 06:48:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:13 INFO - 'SHELL': '/bin/bash', 06:48:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dnDYTOcxny/Listeners', 06:48:13 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 06:48:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:13 INFO - 'USER': 'cltbld', 06:48:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:13 INFO - 'XPC_FLAGS': '0x0', 06:48:13 INFO - 'XPC_SERVICE_NAME': '0', 06:48:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:13 INFO - Downloading/unpacking psutil>=0.7.1 06:48: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 06:48: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 06:48: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 06:48: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 06:48: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 06:48: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 06:48:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:48:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:48:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:48:16 INFO - Installing collected packages: psutil 06:48:16 INFO - Running setup.py install for psutil 06:48:16 INFO - building 'psutil._psutil_osx' extension 06:48:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 06:48:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 06:48:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 06:48:17 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 06:48:17 INFO - building 'psutil._psutil_posix' extension 06:48:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 06:48:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:48:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:48:17 INFO - ^ 06:48:17 INFO - 1 warning generated. 06:48:17 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 06:48:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:48:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:48:17 INFO - Successfully installed psutil 06:48:17 INFO - Cleaning up... 06:48:17 INFO - Return code: 0 06:48:17 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 06:48:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:17 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 0x1014d9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014e9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff72a48d300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101378e00>, '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 0x1011e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101373b20>, '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.djG6xTcUqP/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.dnDYTOcxny/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:48:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 06:48:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 06:48:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.djG6xTcUqP/Render', 06:48:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:17 INFO - 'HOME': '/Users/cltbld', 06:48:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:17 INFO - 'LOGNAME': 'cltbld', 06:48:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:17 INFO - 'MOZ_NO_REMOTE': '1', 06:48:17 INFO - 'NO_EM_RESTART': '1', 06:48:17 INFO - 'PAGER': '/bin/cat', 06:48:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:17 INFO - 'PWD': '/builds/slave/test', 06:48:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:17 INFO - 'SHELL': '/bin/bash', 06:48:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dnDYTOcxny/Listeners', 06:48:17 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 06:48:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:17 INFO - 'USER': 'cltbld', 06:48:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:17 INFO - 'XPC_FLAGS': '0x0', 06:48:17 INFO - 'XPC_SERVICE_NAME': '0', 06:48:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:17 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 06:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:20 INFO - Downloading mozsystemmonitor-0.0.tar.gz 06:48:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:48:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 06:48:20 INFO - Installing collected packages: mozsystemmonitor 06:48:20 INFO - Running setup.py install for mozsystemmonitor 06:48:20 INFO - Successfully installed mozsystemmonitor 06:48:20 INFO - Cleaning up... 06:48:20 INFO - Return code: 0 06:48:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:48:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:20 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 0x1014d9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014e9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff72a48d300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101378e00>, '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 0x1011e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101373b20>, '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.djG6xTcUqP/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.dnDYTOcxny/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:48:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:48:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:48:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.djG6xTcUqP/Render', 06:48:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:20 INFO - 'HOME': '/Users/cltbld', 06:48:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:20 INFO - 'LOGNAME': 'cltbld', 06:48:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:20 INFO - 'MOZ_NO_REMOTE': '1', 06:48:20 INFO - 'NO_EM_RESTART': '1', 06:48:20 INFO - 'PAGER': '/bin/cat', 06:48:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:20 INFO - 'PWD': '/builds/slave/test', 06:48:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:20 INFO - 'SHELL': '/bin/bash', 06:48:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dnDYTOcxny/Listeners', 06:48:20 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 06:48:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:20 INFO - 'USER': 'cltbld', 06:48:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:20 INFO - 'XPC_FLAGS': '0x0', 06:48:20 INFO - 'XPC_SERVICE_NAME': '0', 06:48:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:20 INFO - Downloading/unpacking blobuploader==1.2.4 06:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:23 INFO - Downloading blobuploader-1.2.4.tar.gz 06:48:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:48:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:48:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:24 INFO - Downloading docopt-0.6.1.tar.gz 06:48:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:48:24 INFO - Installing collected packages: blobuploader, requests, docopt 06:48:24 INFO - Running setup.py install for blobuploader 06:48:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:48:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:48:24 INFO - Running setup.py install for requests 06:48:25 INFO - Running setup.py install for docopt 06:48:25 INFO - Successfully installed blobuploader requests docopt 06:48:25 INFO - Cleaning up... 06:48:25 INFO - Return code: 0 06:48:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:48:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:25 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 0x1014d9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014e9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff72a48d300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101378e00>, '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 0x1011e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101373b20>, '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.djG6xTcUqP/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.dnDYTOcxny/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:48:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:48:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:48:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.djG6xTcUqP/Render', 06:48:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:25 INFO - 'HOME': '/Users/cltbld', 06:48:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:25 INFO - 'LOGNAME': 'cltbld', 06:48:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:25 INFO - 'MOZ_NO_REMOTE': '1', 06:48:25 INFO - 'NO_EM_RESTART': '1', 06:48:25 INFO - 'PAGER': '/bin/cat', 06:48:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:25 INFO - 'PWD': '/builds/slave/test', 06:48:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:25 INFO - 'SHELL': '/bin/bash', 06:48:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dnDYTOcxny/Listeners', 06:48:25 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 06:48:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:25 INFO - 'USER': 'cltbld', 06:48:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:25 INFO - 'XPC_FLAGS': '0x0', 06:48:25 INFO - 'XPC_SERVICE_NAME': '0', 06:48:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:48:25 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-EoO934-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:48:25 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-gsp08y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:48:25 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-zcfaZg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:48:25 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-aGYpFd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:48:26 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-7eiR8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:48:26 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-vyXKmv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:48:26 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-XW0Z1E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:48:26 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-yqxO4r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:48:26 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-ccdZs9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:48:26 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-KmDQdb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:48:26 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-CKYtrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:48:26 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-6v6BeR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:48:26 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-6rPbCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:48:26 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-gyW4tW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:48:27 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-CFivVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:48:27 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-eSsg4e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:48:27 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-stDivF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:48:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:48:27 INFO - Running setup.py install for manifestparser 06:48:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:48:27 INFO - Running setup.py install for mozcrash 06:48:27 INFO - Running setup.py install for mozdebug 06:48:27 INFO - Running setup.py install for mozdevice 06:48:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:48:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:48:27 INFO - Running setup.py install for mozfile 06:48:28 INFO - Running setup.py install for mozhttpd 06:48:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:48:28 INFO - Running setup.py install for mozinfo 06:48:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:48:28 INFO - Running setup.py install for mozInstall 06:48:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:48:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:48:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:48:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:48:28 INFO - Running setup.py install for mozleak 06:48:28 INFO - Running setup.py install for mozlog 06:48:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:48:28 INFO - Running setup.py install for moznetwork 06:48:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:48:28 INFO - Running setup.py install for mozprocess 06:48:29 INFO - Running setup.py install for mozprofile 06:48:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:48:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:48:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:48:29 INFO - Running setup.py install for mozrunner 06:48:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:48:29 INFO - Running setup.py install for mozscreenshot 06:48:29 INFO - Running setup.py install for moztest 06:48:29 INFO - Running setup.py install for mozversion 06:48:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:48:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:48:29 INFO - Cleaning up... 06:48:29 INFO - Return code: 0 06:48:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:48:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-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 0x1014d9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014e9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff72a48d300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101378e00>, '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 0x1011e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101373b20>, '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.djG6xTcUqP/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.dnDYTOcxny/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:48:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:48:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:48:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.djG6xTcUqP/Render', 06:48:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:29 INFO - 'HOME': '/Users/cltbld', 06:48:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:29 INFO - 'LOGNAME': 'cltbld', 06:48:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:29 INFO - 'MOZ_NO_REMOTE': '1', 06:48:29 INFO - 'NO_EM_RESTART': '1', 06:48:29 INFO - 'PAGER': '/bin/cat', 06:48:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:29 INFO - 'PWD': '/builds/slave/test', 06:48:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:29 INFO - 'SHELL': '/bin/bash', 06:48:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dnDYTOcxny/Listeners', 06:48:29 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 06:48:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:29 INFO - 'USER': 'cltbld', 06:48:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:29 INFO - 'XPC_FLAGS': '0x0', 06:48:29 INFO - 'XPC_SERVICE_NAME': '0', 06:48:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:48:30 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-HYeC2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 06:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:48:30 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-MQq6VM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:48:30 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-tZ7wGs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:48:30 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-cRlaFW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:48:30 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-9uAwl8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 06:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:48:30 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-pNpqIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 06:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:48:30 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-nWVNyr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 06:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:48:30 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-V29MVe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 06:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:48:31 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-lQmT7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 06:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:48:31 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-1bMFxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:48:31 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-urbFk5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 06:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:48:31 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-rWZH_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 06:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:48:31 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-2FCFKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 06:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:48:31 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-NHYGyH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 06:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:48:31 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-nWCr77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 06:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:48:31 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-LAPDSH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 06:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:48:31 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-GAiu1H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:48:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 06:48:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:48:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:48:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:48:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:48:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:48:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:48:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:34 INFO - Downloading blessings-1.6.tar.gz 06:48:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:48:34 INFO - Installing collected packages: blessings 06:48:34 INFO - Running setup.py install for blessings 06:48:34 INFO - Successfully installed blessings 06:48:34 INFO - Cleaning up... 06:48:34 INFO - Return code: 0 06:48:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:48:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:48:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:48:35 INFO - Reading from file tmpfile_stdout 06:48:35 INFO - Current package versions: 06:48:35 INFO - blessings == 1.6 06:48:35 INFO - blobuploader == 1.2.4 06:48:35 INFO - docopt == 0.6.1 06:48:35 INFO - manifestparser == 1.1 06:48:35 INFO - mozInstall == 1.12 06:48:35 INFO - mozcrash == 0.17 06:48:35 INFO - mozdebug == 0.1 06:48:35 INFO - mozdevice == 0.48 06:48:35 INFO - mozfile == 1.2 06:48:35 INFO - mozhttpd == 0.7 06:48:35 INFO - mozinfo == 0.9 06:48:35 INFO - mozleak == 0.1 06:48:35 INFO - mozlog == 3.1 06:48:35 INFO - moznetwork == 0.27 06:48:35 INFO - mozprocess == 0.22 06:48:35 INFO - mozprofile == 0.28 06:48:35 INFO - mozrunner == 6.11 06:48:35 INFO - mozscreenshot == 0.1 06:48:35 INFO - mozsystemmonitor == 0.0 06:48:35 INFO - moztest == 0.7 06:48:35 INFO - mozversion == 1.4 06:48:35 INFO - psutil == 3.1.1 06:48:35 INFO - requests == 1.2.3 06:48:35 INFO - wsgiref == 0.1.2 06:48:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:48: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')]} 06:48:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48: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')]} 06:48:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:35 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 0x1014d9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014e9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff72a48d300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101378e00>, '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 0x1011e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101373b20>, '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.djG6xTcUqP/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.dnDYTOcxny/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:48:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 06:48:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:48:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.djG6xTcUqP/Render', 06:48:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:35 INFO - 'HOME': '/Users/cltbld', 06:48:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:35 INFO - 'LOGNAME': 'cltbld', 06:48:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:35 INFO - 'MOZ_NO_REMOTE': '1', 06:48:35 INFO - 'NO_EM_RESTART': '1', 06:48:35 INFO - 'PAGER': '/bin/cat', 06:48:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:35 INFO - 'PWD': '/builds/slave/test', 06:48:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:35 INFO - 'SHELL': '/bin/bash', 06:48:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dnDYTOcxny/Listeners', 06:48:35 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 06:48:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:35 INFO - 'USER': 'cltbld', 06:48:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:35 INFO - 'XPC_FLAGS': '0x0', 06:48:35 INFO - 'XPC_SERVICE_NAME': '0', 06:48:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 06:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 06:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 06:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 06:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 06:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 06:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 06:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:48:35 INFO - Cleaning up... 06:48:35 INFO - Return code: 0 06:48:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:48: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')]} 06:48:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48: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')]} 06:48:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:35 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 0x1014d9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014e9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff72a48d300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101378e00>, '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 0x1011e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101373b20>, '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.djG6xTcUqP/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.dnDYTOcxny/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:48:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:48:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:48:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.djG6xTcUqP/Render', 06:48:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:35 INFO - 'HOME': '/Users/cltbld', 06:48:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:35 INFO - 'LOGNAME': 'cltbld', 06:48:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:35 INFO - 'MOZ_NO_REMOTE': '1', 06:48:35 INFO - 'NO_EM_RESTART': '1', 06:48:35 INFO - 'PAGER': '/bin/cat', 06:48:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:35 INFO - 'PWD': '/builds/slave/test', 06:48:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:35 INFO - 'SHELL': '/bin/bash', 06:48:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dnDYTOcxny/Listeners', 06:48:35 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 06:48:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:35 INFO - 'USER': 'cltbld', 06:48:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:35 INFO - 'XPC_FLAGS': '0x0', 06:48:35 INFO - 'XPC_SERVICE_NAME': '0', 06:48:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:35 INFO - Downloading/unpacking jsonschema 06:48:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:48:38 INFO - Downloading/unpacking functools32 (from jsonschema) 06:48:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:38 INFO - Downloading functools32-3.2.3-2.tar.gz 06:48:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:48:38 INFO - warning: no files found matching '*.txt' 06:48:38 INFO - no previously-included directories found matching 'build' 06:48:38 INFO - no previously-included directories found matching 'dist' 06:48:38 INFO - no previously-included directories found matching '.git*' 06:48:38 INFO - Installing collected packages: jsonschema, functools32 06:48:38 INFO - Running setup.py install for functools32 06:48:38 INFO - warning: no files found matching '*.txt' 06:48:38 INFO - no previously-included directories found matching 'build' 06:48:38 INFO - no previously-included directories found matching 'dist' 06:48:38 INFO - no previously-included directories found matching '.git*' 06:48:38 INFO - Successfully installed jsonschema functools32 06:48:38 INFO - Cleaning up... 06:48:38 INFO - Return code: 0 06:48:38 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 06:48: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')]} 06:48:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48: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')]} 06:48:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:38 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 0x1014d9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014e9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff72a48d300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101378e00>, '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 0x1011e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101373b20>, '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.djG6xTcUqP/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.dnDYTOcxny/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:48:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 06:48:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 06:48:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.djG6xTcUqP/Render', 06:48:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:38 INFO - 'HOME': '/Users/cltbld', 06:48:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:38 INFO - 'LOGNAME': 'cltbld', 06:48:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:38 INFO - 'MOZ_NO_REMOTE': '1', 06:48:38 INFO - 'NO_EM_RESTART': '1', 06:48:38 INFO - 'PAGER': '/bin/cat', 06:48:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:38 INFO - 'PWD': '/builds/slave/test', 06:48:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:38 INFO - 'SHELL': '/bin/bash', 06:48:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dnDYTOcxny/Listeners', 06:48:38 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 06:48:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:38 INFO - 'USER': 'cltbld', 06:48:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:38 INFO - 'XPC_FLAGS': '0x0', 06:48:38 INFO - 'XPC_SERVICE_NAME': '0', 06:48:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:39 INFO - Downloading/unpacking flake8 06:48:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:41 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 06:48:41 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 06:48:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:41 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 06:48:41 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 06:48:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:42 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 06:48:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 06:48:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:48:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:48:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:48:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:48:42 INFO - no previously-included directories found matching 'docs/_build' 06:48:42 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 06:48:42 INFO - Running setup.py install for pep8 06:48:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:48:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:48:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:48:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:48:43 INFO - no previously-included directories found matching 'docs/_build' 06:48:43 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 06:48:43 INFO - Successfully installed flake8 mccabe pyflakes pep8 06:48:43 INFO - Cleaning up... 06:48:43 INFO - Return code: 0 06:48:43 INFO - Running post-action listener: _resource_record_post_action 06:48:43 INFO - Running post-action listener: _start_resource_monitoring 06:48:43 INFO - Starting resource monitoring. 06:48:43 INFO - ##### 06:48:43 INFO - ##### Running install step. 06:48:43 INFO - ##### 06:48:43 INFO - Running pre-action listener: _resource_record_pre_action 06:48:43 INFO - Running main action method: install 06:48:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:48:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:48:43 INFO - Reading from file tmpfile_stdout 06:48:43 INFO - Detecting whether we're running mozinstall >=1.0... 06:48:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:48:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:48:43 INFO - Reading from file tmpfile_stdout 06:48:43 INFO - Output received: 06:48:43 INFO - Usage: mozinstall [options] installer 06:48:43 INFO - Options: 06:48:43 INFO - -h, --help show this help message and exit 06:48:43 INFO - -d DEST, --destination=DEST 06:48:43 INFO - Directory to install application into. [default: 06:48:43 INFO - "/builds/slave/test"] 06:48:43 INFO - --app=APP Application being installed. [default: firefox] 06:48:43 INFO - mkdir: /builds/slave/test/build/application 06:48:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:48:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 06:49:15 INFO - Reading from file tmpfile_stdout 06:49:15 INFO - Output received: 06:49:15 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 06:49:15 INFO - Running post-action listener: _resource_record_post_action 06:49:15 INFO - ##### 06:49:15 INFO - ##### Running run-tests step. 06:49:15 INFO - ##### 06:49:15 INFO - Running pre-action listener: _resource_record_pre_action 06:49:15 INFO - Running main action method: run_tests 06:49:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:49:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:49:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:49:15 INFO - 2016-06-06 06:49:15.055 screenresolution[3002:38524] starting screenresolution argv=screenresolution get 06:49:15 INFO - 2016-06-06 06:49:15.058 screenresolution[3002:38524] Display 0: 1600x1200x32@60 06:49:15 INFO - 2016-06-06 06:49:15.064 screenresolution[3003:38527] starting screenresolution argv=screenresolution list 06:49:15 INFO - Available Modes on Display 0 06:49:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:49:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:49:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:49:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:49:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:49:15 INFO - Intel Iris: 06:49:15 INFO - Chipset Model: Intel Iris 06:49:15 INFO - Type: GPU 06:49:15 INFO - Bus: Built-In 06:49:15 INFO - VRAM (Dynamic, Max): 1536 MB 06:49:15 INFO - Vendor: Intel (0x8086) 06:49:15 INFO - Device ID: 0x0a2e 06:49:15 INFO - Revision ID: 0x0009 06:49:15 INFO - Displays: 06:49:15 INFO - Display: 06:49:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:49:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:49:15 INFO - Display Serial Number: 1600x1200 60 06:49:15 INFO - Main Display: Yes 06:49:15 INFO - Mirror: Off 06:49:15 INFO - Online: Yes 06:49:15 INFO - Rotation: Supported 06:49:15 INFO - Return code: 0 06:49:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:49:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:49:15 INFO - Python 2.7.3 06:49:15 INFO - Return code: 0 06:49:15 INFO - grabbing minidump binary from tooltool 06:49:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:49:15 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 0x101378e00>, '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 0x1011e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101373b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:49:15 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 06:49:15 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 06:49:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:49:15 INFO - Return code: 0 06:49:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:49:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:49:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:49:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:49:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:49:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 06:49:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 06:49:16 INFO - Return code: 0 06:49:16 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-0308', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 06:49:16 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-0308 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 06:49:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.djG6xTcUqP/Render', 06:49:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:49:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:49:16 INFO - 'HOME': '/Users/cltbld', 06:49:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:49:16 INFO - 'LOGNAME': 'cltbld', 06:49:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:49:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:49:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:49:16 INFO - 'MOZ_NO_REMOTE': '1', 06:49:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:49:16 INFO - 'NO_EM_RESTART': '1', 06:49:16 INFO - 'PAGER': '/bin/cat', 06:49:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:49:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:49:16 INFO - 'PWD': '/builds/slave/test', 06:49:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:49:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:49:16 INFO - 'SHELL': '/bin/bash', 06:49:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dnDYTOcxny/Listeners', 06:49:16 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 06:49:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:49:16 INFO - 'USER': 'cltbld', 06:49:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:49:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:49:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:49:16 INFO - 'XPC_FLAGS': '0x0', 06:49:16 INFO - 'XPC_SERVICE_NAME': '0', 06:49:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:49:16 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-0308', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 06:49:16 INFO - mozversion application_buildid: 20160606010529 06:49:16 INFO - mozversion application_changeset: d8adb38dd576398dacbfb96d2c247f8e0158bdf1 06:49:16 INFO - mozversion application_display_name: FirefoxDeveloperEdition 06:49:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:49:16 INFO - mozversion application_name: Firefox 06:49:16 INFO - mozversion application_remotingname: firefox-dev 06:49:16 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:49:16 INFO - mozversion application_vendor: Mozilla 06:49:16 INFO - mozversion application_version: 48.0a2 06:49:16 INFO - mozversion platform_buildid: 20160606010529 06:49:16 INFO - mozversion platform_changeset: d8adb38dd576398dacbfb96d2c247f8e0158bdf1 06:49:16 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:49:16 INFO - mozversion platform_version: 48.0a2 06:49:16 INFO - using testdate: 1465220956 06:49:16 INFO - actual date: 1465220956 06:49:16 INFO - starting webserver on 'localhost:57267' 06:49:16 INFO - SUITE-START | Running 2 tests 06:49:16 INFO - TEST-START | dromaeo_css 06:49:16 INFO - operating with platform_type : mac_ 06:49:16 INFO - Initialising browser for dromaeo_css test... 06:49:16 INFO - TEST-INFO | started process 3021 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/tmp2Xp4Ub/profile http://localhost:57267/getInfo.html) 06:49:18 INFO - PROCESS | 3021 | __metrics Screen width/height:1600/1200 06:49:18 INFO - PROCESS | 3021 | colorDepth:24 06:49:18 INFO - PROCESS | 3021 | Browser inner width/height: 1024/689 06:49:18 INFO - PROCESS | 3021 | __metrics 06:49:18 INFO - PROCESS | 3021 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:49:18 ERROR - PROCESS | 3021 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:49:19 INFO - TEST-INFO | 3021: exit 0 06:49:19 INFO - Browser initialized. 06:49:19 INFO - Running cycle 1/1 for dromaeo_css test... 06:49:19 INFO - TEST-INFO | started process 3022 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/tmp2Xp4Ub/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:49:31 INFO - PROCESS | 3022 | RSS: Main: 211472384 06:49:31 INFO - PROCESS | 3022 | 06:50:23 INFO - PROCESS | 3022 | 1465221023003 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 06:52:33 INFO - PROCESS | 3022 | Cycle 1(1): loaded http://localhost:57267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57267/tests/dromaeo/cssquery-ext.html) 06:52:34 INFO - PROCESS | 3022 | RSS: Main: 329355264 06:52:34 INFO - PROCESS | 3022 | 06:55:37 INFO - PROCESS | 3022 | Cycle 1(1): loaded http://localhost:57267/tests/dromaeo/cssquery-ext.html (next: http://localhost:57267/tests/dromaeo/cssquery-jquery.html) 06:55:38 INFO - PROCESS | 3022 | RSS: Main: 354451456 06:55:38 INFO - PROCESS | 3022 | 06:58:40 INFO - PROCESS | 3022 | Cycle 1(1): loaded http://localhost:57267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57267/tests/dromaeo/cssquery-mootools.html) 06:58:41 INFO - PROCESS | 3022 | RSS: Main: 357093376 06:58:41 INFO - PROCESS | 3022 | 07:01:43 INFO - PROCESS | 3022 | Cycle 1(1): loaded http://localhost:57267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57267/tests/dromaeo/cssquery-prototype.html) 07:01:43 INFO - PROCESS | 3022 | RSS: Main: 352202752 07:01:43 INFO - PROCESS | 3022 | 07:04:47 INFO - PROCESS | 3022 | Cycle 1(1): loaded http://localhost:57267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57267/tests/dromaeo/cssquery-yui.html) 07:04:47 INFO - PROCESS | 3022 | RSS: Main: 326004736 07:04:47 INFO - PROCESS | 3022 | 07:07:50 INFO - PROCESS | 3022 | Cycle 1(1): loaded http://localhost:57267/tests/dromaeo/cssquery-yui.html (next: http://localhost:57267/tests/dromaeo/cssquery-dojo.html) 07:07:50 INFO - PROCESS | 3022 | RSS: Main: 367824896 07:07:50 INFO - PROCESS | 3022 | 07:07:50 INFO - PROCESS | 3022 | __start_tp_report 07:07:50 INFO - PROCESS | 3022 | _x_x_mozilla_page_load 07:07:50 INFO - PROCESS | 3022 | _x_x_mozilla_page_load_details 07:07:50 INFO - PROCESS | 3022 | |i|pagename|runs| 07:07:50 INFO - PROCESS | 3022 | |0;dojo.html;3120.6411262704787;3407.0630576591434;3421.5038819371175;3431.5538979932603;3482.6865582097607;5692.316921969365;5704.229928959589;5716.714164291784;5721.885562288751;5736.455036771506;5686.431356864311;5730.885382292352;5741.540676745861;5760.107183386572;5766.855828604287;5114.923276150842;5165.845024649267;5178.430372659004;5306.655067420625;5319.308489896308;5236.947630523703;5254.7635356409055;5262.60530460217;5268.420473747885;5269.209618557228;5144.09978253806;5206.088934436478;5207.297014902996;5231.450697676746;5245.711485868275;4575.519570445122;5195.870103247411;5236.6857988520815;5259.158534634478;5303.496167864047;5054.671446356012;5090.10923088458;5156.845294641167;5177.171652535576;5189.117849965515;5478.45215478449;5507.118860982224;5515.4760297772045;5525.889482210333;5552.11166213408;6201.379862013808;6224;6231;6241.126242326078;6245.906311405332;6049.304330002018;6140.903050468145;6220.409061139185;6222.346653601352;6247.312795592481;5767.971160144172;5796.797112101056;5799.507041901444;5799.8550036249435;5802.825915222508;6254.718537665816;6269.49844012487;6272.18461599989;6281.905771413432;6285.811425657238;5035.219540971156;5936.287645482484;5950.107483877453;6035.547333949883;6066.998945174085;6181.598196117238;6190.659513726789;6197.566170368031;6198.504119670415;6225.968870155621;6086.391360863967;6139.662318572521;6145.877082458326;6166.784162554289;6169.259688837368;5985.34161242263;6100.969495152496;6103.1455596216565;6103.328633850273;6170.136180934673;4876.951230487721;4899.608031357483;4907.7300748458465;4911.238757992517;4932.90134197314;4358.5205627380965;4432.445944256968;4436.933445998313;4461.665375096881;4488.663350248745;5563.221149039137;5591.664500129924;5597.832065038056;5618.4662457066515;5631.71841407928;6213.068039793977;6224.221972253468;6234.812955611339;6238.1890354254565;6249.437550620466;5516.779328826802;5570.248016517799;5592.2730045094695;5603.551715862721;5658.34928983164;5371.033214021436;5391.433899440581;5396.082665946798;5402.972985135049;5423.755930983115;6297.748090076438;6334.493240540745;6341.048842673451;6343.492520598341;6345.746170153142;6065.696715164136;6116.33577150815;6145.016797312409;6165.445109940127;6223.657698826608;6336.746530138743;6363.61818290904;6381.936180638134;6383.329750376143;6387;6152.753889844356;6157.907631385443;6170.598911070766;6171.043488259327;6188.350223226496;6092.390760923872;6121.148138557471;6205.782797602063;6222.533310001678;6238.4073513018;4728.31439441286;5986.653003074273;5988.221531200916;6025.246844144482;6056.485198757979;5967.433093856077;6030.065339872327;6048.60684055535;6050.606710563799;6076.756929722762;4684.8360307389075;5901.7639294427745;5969.462748352669;6005.819825405245;6019.0031804902155;6025.102107742252;6034.849128771816;6039.305479869696;6050.1227322037175;6110.114033465212;5761.308642962871;5881.235439392852;5909.970450147722;5913.083472061838;5925.762969481345 07:07:50 INFO - PROCESS | 3022 | |1;ext.html;520.7370278009605;532.5766016017267;541.3233458177278;591.6213623281101;618.3013195089549;30942.91699790505;31450.842745786267;38327.23345533091;38656.84029479113;38772;42070.94822629436;42664.72005839822;42677.7866110669;42731.572684273146;42772.57227427725;9261.768455788591;9474.147326740593;9485.288603354742;9485.95257023714;9487.28845336599;8453.95773021137;8693.304535637133;11355.943220283887;11574.710632234219;11601.419929003558;11286.153538484605;11340.60307889224;11368.97679208871;11376.089912806956;11381.260218085798;7930;11326.60356887509;11385.658430247106;11389.658310250665;11434.028107610862;11301.26541774782;11411.94294028533;11437.313761174357;11445.141614378956;11460.484278207417;38770;39076.60923390787;39115.804420977714;39156.21687566233;39172.60827391747;3193.936121277585;3225.709686128236;3497.965020349815;3671.284099600579;3851.7111216658864;28326.875484338758;28533;28614.998475053482;29262.853685731647;29294.121176364744;2730.7269273072734;2738.397552538458;2745.7117002714826;2784.986075069632;2788.093869492423;499702;501163;501861.99999999633;501887;502233;63054.68472657699;63488.36511634824;63496.68251658712;63571.04643430352;63714;61099.69450152721;71989;73095.63452182812;73116.63441682865;73141;18013.7297940531;18044.548886277684;18205.453836384666;18236.36172733948;18268.451946441626;12590.48914130292;12765.872341276654;12779.744405111845;13808;13912.791308130387;17391.304347826197;17640.20619072145;19947.80313181185;20601.793982059986;20602.27892023772;8099.83800324002;8224.465409748347;8252.381071419668;8270.007599088149;8277.917220827836;975.9853602195973;984.4733067808688;1050.6217761605815;1089.1178145702006;1133.56924368741;25311.746882531308;30064;30332.54501182484;30383.69616303853;30481.542776858365;1188.1326631559011;1209.4315671634317;1239.114033466076;1240.2558464920976;1260.1305099481328;15359.308831102628;15441.459548915735;15523.767143492856;15594.766078508832;15617.3753049879;634827;641996;643030;643777;644550;59955.70022149861;61602;61837.69081154565;61879.38120618826;61991;1327.9800802987963;1386.3899884050986;1521.6195951012246;1533.9233038348261;1572.6697393547483;28489;28760.712392875805;29219.707802921697;29921.850390747906;30027;47533;47571;47752.7612361936;47791.56625301247;47902.28146577874;347909;352777;353141;353291.46708532586;610801;23437.531249374577;45628.08743825038;45765.77117114393;45803.54196458126;46491.3026304599;48126.518734812205;49876.50123498718;50045.999080018926;50066.249006265665;51632;38248;38926.2214755709;39292.80353598214;39475.60524394834;40733.79633101816;38944.2211155781;38948.02629934161;39327.016824579616;39951;40277.79861100676;39513.2097358057;39666.206675866895;39850.40224396694;40437.79781101076;40768.79615601903;28635.570466442605;33815.35703615158;34623.82688086543;35494.46758298679;35739.64260357363;22241.44396390103;25373.619395709447;25850.35374115662;26172.8691356542;26491.867540662173 07:07:50 INFO - PROCESS | 3022 | |2;jquery.html;395.60044355201245;397.703710735502;400.42139108987527;403.2378804060327;412.4123124547519;14854.554363369116;19016.714749278774;20120;20788.168473261052;20834.791652083473;16602.418915337967;16715.749263761052;18632.44102676922;20648.587028259462;20906.790932090673;4894.898647804252;5760.56795740319;5962.04607262838;5972.761089556434;5977.342492325841;4157.97921010396;5809.099589563603;5909.940900590982;5928.703564821763;5945.643261404308;4285.250081235787;4629.837955671552;6493.675316234193;6507.05647681086;6526.445252153549;6388.744450221986;6473.093766872642;6474.773382931575;6480.935190648104;6501.804945851632;5534.169874518814;5666.886662266773;5701;5701.486866182022;5709.65742055478;25296.747032529624;25320.860561274785;25657.973681052736;26567;26649.8007589657;2162.0703097667997;2164.837637177218;2207.6136676081624;2318.304508647399;2802.1733588591414;14055.64860878476;14528.273586320642;14756.188409637465;14860.182689952047;15011.099334039993;1689.1487408708165;1765.5144835170302;1784.2595322940963;1794.5244510204807;1827.5339788354017;108297.91702083035;109628;114390;115470;117197;10831.620893268699;11157.051650609626;11275.605353812578;11383.373914434786;11552;14248.430062797372;14335.641608959859;14354.066985645899;14376.928115359357;14389.352479138464;6719.932800672029;8072.556009419538;8179.345652347798;8196.54918979462;8829.602667879832;8127.512349259064;8156.306713929383;8174.182581741778;8951.32865035125;8998.775030624287;6802.183737951477;6846.246912839583;6847.897281540681;6892.483063770137;7262.346388825031;1627.983720162807;1848.196034724899;1863.7018077107598;1919.2171351540323;1919.8176173263519;913.5569248914271;979.9412035277907;1027.106417416852;1066.648006157966;1106.4025426269884;16001.59996000086;20195.495112622008;21325.46686332823;21714.674279885498;21938.341849744535;891.5364010714392;972.9124378805941;1009.8687170667766;1030.1038096855634;1083.2397354299264;9422.387544809566;10724.410157441416;11459.713507162222;11484.483198256099;11518.308901465938;603304;614923;634354;636627;647171;137539.6246037547;138009.30995344959;141332;143995;144933;1009.5002973528151;1042.3433237060756;1053.5891002509027;1057.2123767793043;1057.7514284143067;14926.402943882558;17431.128443577516;18189.363372281907;18372.35696750607;18651.813481865007;54340.45659543512;56401.71799140978;56475.15287270777;56684.29947101593;56714;79768.60115699384;81963.18036819555;82901.58549207215;83396;85536;5966.820995370146;6715.7985260442265;6795.762148324785;8325.916740832514;8325.95837020811;11745.941270293593;12337.753244934871;12579.119461637598;12678.429470674026;12856.485740570273;10999.890001099886;11024.44877756125;11233.775324493301;11258;11457.369844658624;5652;7244.877044058179;10594.046535811505;10903.618373356894;11133.944330278297;10336.534855931652;11048.558057677603;11564.768704625692;11609.3614851184;11634.70913227176;8130.471519351223;8159;8333.916660833556;8350.331973442231;8545.615447304763;9025.638974440948;10308.32995855267;11004.174686898354;11319.264247824194;11394.658160255205 07:07:50 INFO - PROCESS | 3022 | |3;mootools.html;1424.914505129693;1573.150498730685;1595.4017243533672;1842.9815701842986;1975.8122978317056;7968.721094761684;8565;8779.429337093085;8801.823963520726;8842.469451832887;8645.092265312145;8758.386912916098;8769.561521923893;8769.60536775845;9089.727308180749;2112.7359080114984;2458.6803715516958;2481.937951551208;2483.3046746910804;2483.6026235802274;2417.02110645189;2452.1049816816912;2456.815738819587;2468.1361523466735;2476.702795664523;2447.902083916641;2468.666729991455;2480.2063339731294;2484.6521486991837;2498.263012411334;2453.1533096721405;2466.654668346433;2468.2965354873863;2480.9255722328357;2484.1181380609896;2034.491377155711;2426.478307163968;2454.337328921199;2457.6313552967017;2459.2622213335926;8514.48913065218;8814.647414103492;8873.068327825615;8878.978917424514;8880.200781929592;5347.919781203285;5494.09347457669;5507.449255074501;5553.305836770403;5559.138333558305;2517.0241360531895;3433.9828300858335;3479.356319080978;3494.475828625701;3513.578370595519;1053.773438710681;1062.2988827373974;1071.9792942389781;1079.5519859258397;1085.8262677971486;12064.577739779066;12143.757124857453;12153.210041347284;12173.026157907347;12175.878241217652;4550.590446859798;4707.670463067552;4726.740029298353;4726.976365118152;4727.834525791581;9400.670976515927;9474.621015159455;9509.809803804024;9513.571889265;9525.095115963972;4482.529334419904;5178.404483484373;5353.839384818461;5368.1679339702405;5403.297571315782;4347.239233134189;4457.643388528974;4710.8351207707565;4890.266460030953;4943.332650092263;3811.0472381904524;3835.4438606401914;3882.514685664292;3916.5104361954754;3917;1195.9701007474882;1196.0551164579883;1199.1126566340845;1208.4259976511087;1212.1636071110904;715.5492040014757;755.7921571567863;865.5542395666237;873.6811063961608;945.7871978804714;5627.099664053813;5747.224124743107;5873.471387575053;5947.702614869239;6033.84695118307;485.4732615112572;504.924510792018;508.8219123306813;509.16242780625663;663.6316844151543;3652.2878038782183;3791.1469919267943;3845.942310865339;3905.5703872573995;3906.0430194602504;10474.842877356845;10876.129909607213;11011.944940275247;11024.228304018801;11032.779344413068;10716.732081697864;10729.678109656723;10861.837072443761;10863.130949524018;10867.456627168767;8411.411201215857;8468;8470.407071504935;8486.32109431244;8505.957470212734;4207.430079050906;5796.797112101056;5809.680467574324;5844.649321040752;5909.025010873158;9191.816163676554;9361.485118318558;9461.290403219924;9470.052994700474;9548.904510954802;9603.37578057424;9903.158231550398;9973.451460169761;10126.645567405105;10151.54318055704;7606.087269527471;7643.617819109134;7738.845223095619;7747.1090824555895;7772.455928085206;8331.875021874797;8334.91646086005;8339.624716887876;8410.663573457237;8417.62120704582;6667.866642667216;7174.1032370953635;7622.69509219625;7747.2640099190485;7755.72854950074;7411.036565246483;7483.738069167553;7593.468457208163;7626.504277221963;7646.770596882103;7043.823904402226;7068.3285087916565;7094.326039026284;7141.214466408587;7161.069061022034;4800.543948324904;4935.654504184673;4941.258811178352;5011.273365362075;5034.773435195351;4869.829555965524;4992.925106123338;5025.422076461254;5099.949000509948;5267.815626453055 07:07:50 INFO - PROCESS | 3022 | |4;prototype.html;323.1613961769209;327.3779818345144;330.10212222754114;334.725525069443;339.99320013599714;6578.572392794471;6613.86772264555;6701.82718024345;6713.429358504533;6717.395434410902;6599.8020059398295;6632.1709786276715;6634.767783127591;6637.871561834488;6668.166479190101;4652.32541281514;4788.592969597588;4815.181419158752;4859.368282123319;4870.512948705136;4806.327114204014;4869.805207791702;4876.561109500144;4884.023195360925;4902.607791376699;4773.474917759044;4831.975840120795;4833.540813622701;4845.152098382787;4865.732384718839;3548.7870727756417;3811.485449464328;4843.878903027417;4854.17479028566;4875.951240487603;3365.848536815849;3997.160596274786;4026.0136266614572;4149.771762553059;4171.124063946575;6291.528135389818;6326.841828954267;6338.936610633881;6341.968290158566;6384.3296453872135;5712.685802280873;5740.138979153118;5740.712964351766;5748.42515748426;5769.221155144044;7038.824029399306;7043.92956070443;7044.929550704479;7049.013138160662;7050.6122163281;4684.648651351163;4686.273627587695;4688.17956857552;4689.085628302482;4692.178868697998;7121.53710008853;7442.441816863703;7491.363234125106;7498.47510674253;7516.285952834472;4183.247015537232;4231.730480855731;4657.138429390574;4661.9976705008585;4673.579377856009;4725.834595789131;4726.905461890743;4730.219513780256;4730.763461826895;4733.597644200213;5612.943870561242;5632.155176723524;5632.408597097327;5633.324001119892;5634.915476267859;5995.070764031582;7126.429885609139;7128.6435678215885;7128.750493732695;7141.857162856818;1490.9329080191417;1635.4194261037399;1646.0617448054734;1647.3822316631263;1649.7442896351085;1027.1166796554958;1033.7570670892485;1047.5704960965968;1048.260976011925;1050.5115121468555;6491.7078731457195;6657.500687448461;6686.09737685416;6698.162729658793;6700.463962882957;7243.058402407653;7464.328210461084;7468.813279667943;7498.625068746541;7507.962460187773;6768.661566921733;6770.492213084039;6771.593704377753;6776.119104516381;6780.423663988616;3908.726389152732;3950.762954222698;3954;3959.6436320731273;3962.5641179470235;7509.098908131059;7765.883511747328;7796.766097016985;7861.371090312761;7861.449698521049;7683.462157649023;7721.112072111667;7722.382209423233;7737.613119344122;7741.7677469676;7870.645820938072;7872.094709108525;7873.763787086396;7880.448368614257;7896.7236146734585;7450.36671882896;7450.8882366763455;7451;7456.7390141344795;7477.401807855467;7054.188768291507;7313.414926805949;7314.08573928259;7336.229695882069;7345.3021962913435;7740.806479838049;7801.102873169658;7814.257645523666;7825.921740782519;7826.178251283761;4628.514006029453;4677;4678.50875658051;4682.508336624742;4688.179568575554;7381.44639152055;7387.335139837441;7387.704491820267;7404.777856664308;7414.184439711736;6864.519483636098;7164.820879478055;7178.461615378972;7180.3537681608905;7182.856342873218;7080.822979425556;7161.4270858330165;7174.461915356264;7183.20984691673;7198.316159964795;6990.860182796214;6991.126109236346;6996.125484314461;6997.300269972962;6999;6891.241963384124;7123.038389817516;7140.7857764267155;7142.714291428285;7149.714011439484;6681.76613818514;6910.654467276516;7005.124359455068;7010.439164866696;7015.649217539205 07:07:50 INFO - PROCESS | 3022 | |5;yui.html;699.8530308635186;731.73657483306;738.9445791565633;756.6897571995482;757.4849102610223;14898.627534311634;14985.250737463111;15269.69460610787;15300.54098377049;15306.923465382672;14062.718745625081;14141.929290353533;14146.221957792317;14152.716945661106;14158.433662653508;733.0067758187658;752.7440670172141;899.5457294066504;920.2362039507193;932.4638332958542;932.7668082979255;935.588341129904;939.4316438554679;939.9295052871028;940.4404379394249;981.4601968917103;983.1692220074026;987.9506024698736;989.1542730964993;990.2523594686002;644.185105841109;864.654138344664;959.5729900194416;980.6518685866486;983.9065288797552;730.0800990751638;730.5069078372078;731.3235257386949;731.4294850016942;731.4843035659848;14900.403983840633;14964.775528366974;14993.775093373608;15000.949933504658;15024.399024039025;12562.996934273804;12581.874181258254;12595.803398677202;12809.61571152867;12948.093633445662;10077.949610251902;10126.392416455037;10136;10243.692689219335;10344.793104137874;3436.1409647588102;3505.544279243682;3529.5588051493564;3600.08197909534;3609.855605775766;87614.12385876187;87709;90507;90655;91314.54342728244;9470.95264523673;9503.441226237588;9604.327697061139;9748.463834489172;9814.165795907285;21006.894965525382;22870.542589148125;22872.7712722874;22898.8855055727;22951.08195672188;7688.961555192188;7698.6305464363995;7717.961410192913;7723.227677232344;8016.759497215093;4170.020045289354;4173.937390939138;4188.162367526446;4237.766922819213;4459.331100334911;3628.8911332660064;3668.523091998023;3685.686716629063;3686.2443199144113;3686.612905644922;553.3995614758032;611.2084850119106;640.5708175522411;649.4771708774482;657.6777379084215;460.5544558394867;498.78053656391074;560.249265983584;612.1399433795524;617.1452538234578;7974;8083.636236369378;8129.512229266263;8208.220219079178;8266.214709602578;600.2317034196236;601.3145014683265;601.9909701354483;611.1627070912789;615.6798464798369;3669.229461813049;3783.50814394127;3864.555576108727;3986.0443526046656;4026.8187931543152;97578.512107439;98278;100066.49966750265;100187;100288.99711002942;81260.78108828372;81984;83124;83212;84406;72830;75181;75422.6696927593;75428.24571754322;75812;7491.662875170521;7640.808979775327;7740.458167928191;7834.0599128105;7835.333996610352;47058.29412558883;47818.04363912772;48074.759626201645;49062.75468622634;51532.711682208246;64522;68733;69354.61290774257;69419.30580694128;70296;9863.112319891245;10033.827479852984;10049.246306527186;10162.18702503783;10167.644132455329;10622.150227981898;10755.569777209137;10800.891991080303;10905.945470272914;10976.560937562728;10044.849327260243;10155.695329140137;10285.919978402459;10928.071113955084;10947.56209751601;10101;10105.040021197974;10215.02957219063;10371;11059.447027648424;9933.3543319684;10107.039831216023;10289.74275643115;10304.072633463024;10974.914766194397;8759.956200218958;8792.252658523841;8846.46921184731;9045.095490450902;9047.909520904706;8794.164554367326;8926.68756593516;8938.597763100546;9008.684696035869;9112.453252804853 07:07:50 INFO - PROCESS | 3022 | __end_tp_report 07:07:50 INFO - PROCESS | 3022 | __start_cc_report 07:07:50 INFO - PROCESS | 3022 | _x_x_mozilla_cycle_collect,1115 07:07:50 INFO - PROCESS | 3022 | __end_cc_report 07:07:50 INFO - PROCESS | 3022 | __startTimestamp1465222070843__endTimestamp 07:07:50 INFO - PROCESS | 3022 | 07:07:50 INFO - PROCESS | 3022 | ------- Summary: start ------- 07:07:50 INFO - PROCESS | 3022 | Number of tests: 6 07:07:50 INFO - PROCESS | 3022 | 07:07:50 INFO - PROCESS | 3022 | [#0] dojo.html Cycles:165 Average:5689.89 Median:5913.08 stddev:640.13 (10.8%) stddev-sans-first:609.53 07:07:50 INFO - PROCESS | 3022 | Values: 3120.6 3407.1 3421.5 3431.6 3482.7 5692.3 5704.2 5716.7 5721.9 5736.5 5686.4 5730.9 5741.5 5760.1 5766.9 5114.9 5165.8 5178.4 5306.7 5319.3 5236.9 5254.8 5262.6 5268.4 5269.2 5144.1 5206.1 5207.3 5231.5 5245.7 4575.5 5195.9 5236.7 5259.2 5303.5 5054.7 5090.1 5156.8 5177.2 5189.1 5478.5 5507.1 5515.5 5525.9 5552.1 6201.4 6224.0 6231.0 6241.1 6245.9 6049.3 6140.9 6220.4 6222.3 6247.3 5768.0 5796.8 5799.5 5799.9 5802.8 6254.7 6269.5 6272.2 6281.9 6285.8 5035.2 5936.3 5950.1 6035.5 6067.0 6181.6 6190.7 6197.6 6198.5 6226.0 6086.4 6139.7 6145.9 6166.8 6169.3 5985.3 6101.0 6103.1 6103.3 6170.1 4877.0 4899.6 4907.7 4911.2 4932.9 4358.5 4432.4 4436.9 4461.7 4488.7 5563.2 5591.7 5597.8 5618.5 5631.7 6213.1 6224.2 6234.8 6238.2 6249.4 5516.8 5570.2 5592.3 5603.6 5658.3 5371.0 5391.4 5396.1 5403.0 5423.8 6297.7 6334.5 6341.0 6343.5 6345.7 6065.7 6116.3 6145.0 6165.4 6223.7 6336.7 6363.6 6381.9 6383.3 6387.0 6152.8 6157.9 6170.6 6171.0 6188.4 6092.4 6121.1 6205.8 6222.5 6238.4 4728.3 5986.7 5988.2 6025.2 6056.5 5967.4 6030.1 6048.6 6050.6 6076.8 4684.8 5901.8 5969.5 6005.8 6019.0 6025.1 6034.8 6039.3 6050.1 6110.1 5761.3 5881.2 5910.0 5913.1 5925.8 07:07:50 INFO - PROCESS | 3022 | 07:07:50 INFO - PROCESS | 3022 | [#1] ext.html Cycles:180 Average:66710.84 Median:20959.64 stddev:140956.23 (672.5%) stddev-sans-first:141264.05 07:07:50 INFO - PROCESS | 3022 | Values: 520.7 532.6 541.3 591.6 618.3 30942.9 31450.8 38327.2 38656.8 38772.0 42070.9 42664.7 42677.8 42731.6 42772.6 9261.8 9474.1 9485.3 9486.0 9487.3 8454.0 8693.3 11355.9 11574.7 11601.4 11286.2 11340.6 11369.0 11376.1 11381.3 7930.0 11326.6 11385.7 11389.7 11434.0 11301.3 11411.9 11437.3 11445.1 11460.5 38770.0 39076.6 39115.8 39156.2 39172.6 3193.9 3225.7 3498.0 3671.3 3851.7 28326.9 28533.0 28615.0 29262.9 29294.1 2730.7 2738.4 2745.7 2785.0 2788.1 499702.0 501163.0 501862.0 501887.0 502233.0 63054.7 63488.4 63496.7 63571.0 63714.0 61099.7 71989.0 73095.6 73116.6 73141.0 18013.7 18044.5 18205.5 18236.4 18268.5 12590.5 12765.9 12779.7 13808.0 13912.8 17391.3 17640.2 19947.8 20601.8 20602.3 8099.8 8224.5 8252.4 8270.0 8277.9 976.0 984.5 1050.6 1089.1 1133.6 25311.7 30064.0 30332.5 30383.7 30481.5 1188.1 1209.4 1239.1 1240.3 1260.1 15359.3 15441.5 15523.8 15594.8 15617.4 634827.0 641996.0 643030.0 643777.0 644550.0 59955.7 61602.0 61837.7 61879.4 61991.0 1328.0 1386.4 1521.6 1533.9 1572.7 28489.0 28760.7 29219.7 29921.9 30027.0 47533.0 47571.0 47752.8 47791.6 47902.3 347909.0 352777.0 353141.0 353291.5 610801.0 23437.5 45628.1 45765.8 45803.5 46491.3 48126.5 49876.5 50046.0 50066.2 51632.0 38248.0 38926.2 39292.8 39475.6 40733.8 38944.2 38948.0 39327.0 39951.0 40277.8 39513.2 39666.2 39850.4 40437.8 40768.8 28635.6 33815.4 34623.8 35494.5 35739.6 22241.4 25373.6 25850.4 26172.9 26491.9 07:07:50 INFO - PROCESS | 3022 | 07:07:50 INFO - PROCESS | 3022 | [#2] jquery.html Cycles:180 Average:36247.16 Median:2163.45 stddev:104777.67 (4843.1%) stddev-sans-first:105037.02 07:07:50 INFO - PROCESS | 3022 | Values: 395.6 397.7 400.4 403.2 412.4 14854.6 19016.7 20120.0 20788.2 20834.8 16602.4 16715.7 18632.4 20648.6 20906.8 4894.9 5760.6 5962.0 5972.8 5977.3 4158.0 5809.1 5909.9 5928.7 5945.6 4285.3 4629.8 6493.7 6507.1 6526.4 6388.7 6473.1 6474.8 6480.9 6501.8 5534.2 5666.9 5701.0 5701.5 5709.7 25296.7 25320.9 25658.0 26567.0 26649.8 2162.1 2164.8 2207.6 2318.3 2802.2 14055.6 14528.3 14756.2 14860.2 15011.1 1689.1 1765.5 1784.3 1794.5 1827.5 108297.9 109628.0 114390.0 115470.0 117197.0 10831.6 11157.1 11275.6 11383.4 11552.0 14248.4 14335.6 14354.1 14376.9 14389.4 6719.9 8072.6 8179.3 8196.5 8829.6 8127.5 8156.3 8174.2 8951.3 8998.8 6802.2 6846.2 6847.9 6892.5 7262.3 1628.0 1848.2 1863.7 1919.2 1919.8 913.6 979.9 1027.1 1066.6 1106.4 16001.6 20195.5 21325.5 21714.7 21938.3 891.5 972.9 1009.9 1030.1 1083.2 9422.4 10724.4 11459.7 11484.5 11518.3 603304.0 614923.0 634354.0 636627.0 647171.0 137539.6 138009.3 141332.0 143995.0 144933.0 1009.5 1042.3 1053.6 1057.2 1057.8 14926.4 17431.1 18189.4 18372.4 18651.8 54340.5 56401.7 56475.2 56684.3 56714.0 79768.6 81963.2 82901.6 83396.0 85536.0 5966.8 6715.8 6795.8 8325.9 8326.0 11745.9 12337.8 12579.1 12678.4 12856.5 10999.9 11024.4 11233.8 11258.0 11457.4 5652.0 7244.9 10594.0 10903.6 11133.9 10336.5 11048.6 11564.8 11609.4 11634.7 8130.5 8159.0 8333.9 8350.3 8545.6 9025.6 10308.3 11004.2 11319.3 11394.7 07:07:50 INFO - PROCESS | 3022 | 07:07:50 INFO - PROCESS | 3022 | [#3] mootools.html Cycles:180 Average:5702.42 Median:5018.35 stddev:3233.37 (64.4%) stddev-sans-first:3226.46 07:07:50 INFO - PROCESS | 3022 | Values: 1424.9 1573.2 1595.4 1843.0 1975.8 7968.7 8565.0 8779.4 8801.8 8842.5 8645.1 8758.4 8769.6 8769.6 9089.7 2112.7 2458.7 2481.9 2483.3 2483.6 2417.0 2452.1 2456.8 2468.1 2476.7 2447.9 2468.7 2480.2 2484.7 2498.3 2453.2 2466.7 2468.3 2480.9 2484.1 2034.5 2426.5 2454.3 2457.6 2459.3 8514.5 8814.6 8873.1 8879.0 8880.2 5347.9 5494.1 5507.4 5553.3 5559.1 2517.0 3434.0 3479.4 3494.5 3513.6 1053.8 1062.3 1072.0 1079.6 1085.8 12064.6 12143.8 12153.2 12173.0 12175.9 4550.6 4707.7 4726.7 4727.0 4727.8 9400.7 9474.6 9509.8 9513.6 9525.1 4482.5 5178.4 5353.8 5368.2 5403.3 4347.2 4457.6 4710.8 4890.3 4943.3 3811.0 3835.4 3882.5 3916.5 3917.0 1196.0 1196.1 1199.1 1208.4 1212.2 715.5 755.8 865.6 873.7 945.8 5627.1 5747.2 5873.5 5947.7 6033.8 485.5 504.9 508.8 509.2 663.6 3652.3 3791.1 3845.9 3905.6 3906.0 10474.8 10876.1 11011.9 11024.2 11032.8 10716.7 10729.7 10861.8 10863.1 10867.5 8411.4 8468.0 8470.4 8486.3 8506.0 4207.4 5796.8 5809.7 5844.6 5909.0 9191.8 9361.5 9461.3 9470.1 9548.9 9603.4 9903.2 9973.5 10126.6 10151.5 7606.1 7643.6 7738.8 7747.1 7772.5 8331.9 8334.9 8339.6 8410.7 8417.6 6667.9 7174.1 7622.7 7747.3 7755.7 7411.0 7483.7 7593.5 7626.5 7646.8 7043.8 7068.3 7094.3 7141.2 7161.1 4800.5 4935.7 4941.3 5011.3 5034.8 4869.8 4992.9 5025.4 5099.9 5267.8 07:07:50 INFO - PROCESS | 3022 | 07:07:50 INFO - PROCESS | 3022 | [#4] prototype.html Cycles:180 Average:5836.78 Median:6683.93 stddev:1910.47 (28.6%) stddev-sans-first:1870.47 07:07:50 INFO - PROCESS | 3022 | Values: 323.2 327.4 330.1 334.7 340.0 6578.6 6613.9 6701.8 6713.4 6717.4 6599.8 6632.2 6634.8 6637.9 6668.2 4652.3 4788.6 4815.2 4859.4 4870.5 4806.3 4869.8 4876.6 4884.0 4902.6 4773.5 4832.0 4833.5 4845.2 4865.7 3548.8 3811.5 4843.9 4854.2 4876.0 3365.8 3997.2 4026.0 4149.8 4171.1 6291.5 6326.8 6338.9 6342.0 6384.3 5712.7 5740.1 5740.7 5748.4 5769.2 7038.8 7043.9 7044.9 7049.0 7050.6 4684.6 4686.3 4688.2 4689.1 4692.2 7121.5 7442.4 7491.4 7498.5 7516.3 4183.2 4231.7 4657.1 4662.0 4673.6 4725.8 4726.9 4730.2 4730.8 4733.6 5612.9 5632.2 5632.4 5633.3 5634.9 5995.1 7126.4 7128.6 7128.8 7141.9 1490.9 1635.4 1646.1 1647.4 1649.7 1027.1 1033.8 1047.6 1048.3 1050.5 6491.7 6657.5 6686.1 6698.2 6700.5 7243.1 7464.3 7468.8 7498.6 7508.0 6768.7 6770.5 6771.6 6776.1 6780.4 3908.7 3950.8 3954.0 3959.6 3962.6 7509.1 7765.9 7796.8 7861.4 7861.4 7683.5 7721.1 7722.4 7737.6 7741.8 7870.6 7872.1 7873.8 7880.4 7896.7 7450.4 7450.9 7451.0 7456.7 7477.4 7054.2 7313.4 7314.1 7336.2 7345.3 7740.8 7801.1 7814.3 7825.9 7826.2 4628.5 4677.0 4678.5 4682.5 4688.2 7381.4 7387.3 7387.7 7404.8 7414.2 6864.5 7164.8 7178.5 7180.4 7182.9 7080.8 7161.4 7174.5 7183.2 7198.3 6990.9 6991.1 6996.1 6997.3 6999.0 6891.2 7123.0 7140.8 7142.7 7149.7 6681.8 6910.7 7005.1 7010.4 7015.6 07:07:50 INFO - PROCESS | 3022 | 07:07:50 INFO - PROCESS | 3022 | [#5] yui.html Cycles:180 Average:18854.48 Median:613.91 stddev:27510.43 (4481.2%) stddev-sans-first:27553.84 07:07:50 INFO - PROCESS | 3022 | Values: 699.9 731.7 738.9 756.7 757.5 14898.6 14985.3 15269.7 15300.5 15306.9 14062.7 14141.9 14146.2 14152.7 14158.4 733.0 752.7 899.5 920.2 932.5 932.8 935.6 939.4 939.9 940.4 981.5 983.2 988.0 989.2 990.3 644.2 864.7 959.6 980.7 983.9 730.1 730.5 731.3 731.4 731.5 14900.4 14964.8 14993.8 15000.9 15024.4 12563.0 12581.9 12595.8 12809.6 12948.1 10077.9 10126.4 10136.0 10243.7 10344.8 3436.1 3505.5 3529.6 3600.1 3609.9 87614.1 87709.0 90507.0 90655.0 91314.5 9471.0 9503.4 9604.3 9748.5 9814.2 21006.9 22870.5 22872.8 22898.9 22951.1 7689.0 7698.6 7718.0 7723.2 8016.8 4170.0 4173.9 4188.2 4237.8 4459.3 3628.9 3668.5 3685.7 3686.2 3686.6 553.4 611.2 640.6 649.5 657.7 460.6 498.8 560.2 612.1 617.1 7974.0 8083.6 8129.5 8208.2 8266.2 600.2 601.3 602.0 611.2 615.7 3669.2 3783.5 3864.6 3986.0 4026.8 97578.5 98278.0 100066.5 100187.0 100289.0 81260.8 81984.0 83124.0 83212.0 84406.0 72830.0 75181.0 75422.7 75428.2 75812.0 7491.7 7640.8 7740.5 7834.1 7835.3 47058.3 47818.0 48074.8 49062.8 51532.7 64522.0 68733.0 69354.6 69419.3 70296.0 9863.1 10033.8 10049.2 10162.2 10167.6 10622.2 10755.6 10800.9 10905.9 10976.6 10044.8 10155.7 10285.9 10928.1 10947.6 10101.0 10105.0 10215.0 10371.0 11059.4 9933.4 10107.0 10289.7 10304.1 10974.9 8760.0 8792.3 8846.5 9045.1 9047.9 8794.2 8926.7 8938.6 9008.7 9112.5 07:07:50 INFO - PROCESS | 3022 | -------- Summary: end -------- 07:07:50 INFO - PROCESS | 3022 | 07:07:51 INFO - TEST-INFO | 3022: exit 0 07:07:51 INFO - TEST-OK | dromaeo_css | took 1114731ms 07:07:51 INFO - TEST-START | kraken 07:07:51 INFO - operating with platform_type : mac_ 07:07:51 INFO - Initialising browser for kraken test... 07:07:51 INFO - TEST-INFO | started process 3024 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/tmp0k2zTK/profile http://localhost:57267/getInfo.html) 07:07:53 INFO - PROCESS | 3024 | __metrics Screen width/height:1600/1200 07:07:53 INFO - PROCESS | 3024 | colorDepth:24 07:07:53 INFO - PROCESS | 3024 | Browser inner width/height: 1024/689 07:07:53 INFO - PROCESS | 3024 | __metrics 07:07:53 INFO - PROCESS | 3024 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:07:53 ERROR - PROCESS | 3024 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:07:54 INFO - TEST-INFO | 3024: exit 0 07:07:54 INFO - Browser initialized. 07:07:54 INFO - Running cycle 1/1 for kraken test... 07:07:54 INFO - TEST-INFO | started process 3026 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/tmp0k2zTK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:08:06 INFO - PROCESS | 3026 | RSS: Main: 210800640 07:08:06 INFO - PROCESS | 3026 | 07:08:09 INFO - PROCESS | 3026 | Cycle 1(1): loaded http://localhost:57267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57267/tests/kraken/driver.html?testName=audio-beat-detection) 07:08:10 INFO - PROCESS | 3026 | RSS: Main: 271966208 07:08:10 INFO - PROCESS | 3026 | 07:08:13 INFO - PROCESS | 3026 | Cycle 1(1): loaded http://localhost:57267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57267/tests/kraken/driver.html?testName=audio-dft) 07:08:13 INFO - PROCESS | 3026 | RSS: Main: 286310400 07:08:13 INFO - PROCESS | 3026 | 07:08:16 INFO - PROCESS | 3026 | Cycle 1(1): loaded http://localhost:57267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57267/tests/kraken/driver.html?testName=audio-fft) 07:08:17 INFO - PROCESS | 3026 | RSS: Main: 374677504 07:08:17 INFO - PROCESS | 3026 | 07:08:19 INFO - PROCESS | 3026 | Cycle 1(1): loaded http://localhost:57267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57267/tests/kraken/driver.html?testName=audio-oscillator) 07:08:20 INFO - PROCESS | 3026 | RSS: Main: 279822336 07:08:20 INFO - PROCESS | 3026 | 07:08:22 INFO - PROCESS | 3026 | Cycle 1(1): loaded http://localhost:57267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:08:23 INFO - PROCESS | 3026 | RSS: Main: 281128960 07:08:23 INFO - PROCESS | 3026 | 07:08:26 INFO - PROCESS | 3026 | Cycle 1(1): loaded http://localhost:57267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57267/tests/kraken/driver.html?testName=imaging-darkroom) 07:08:26 INFO - PROCESS | 3026 | RSS: Main: 318758912 07:08:26 INFO - PROCESS | 3026 | 07:08:29 INFO - PROCESS | 3026 | Cycle 1(1): loaded http://localhost:57267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57267/tests/kraken/driver.html?testName=imaging-desaturate) 07:08:30 INFO - PROCESS | 3026 | RSS: Main: 316792832 07:08:30 INFO - PROCESS | 3026 | 07:08:33 INFO - PROCESS | 3026 | Cycle 1(1): loaded http://localhost:57267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57267/tests/kraken/driver.html?testName=json-parse-financial) 07:08:33 INFO - PROCESS | 3026 | RSS: Main: 318668800 07:08:33 INFO - PROCESS | 3026 | 07:08:35 INFO - PROCESS | 3026 | Cycle 1(1): loaded http://localhost:57267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:08:36 INFO - PROCESS | 3026 | RSS: Main: 309415936 07:08:36 INFO - PROCESS | 3026 | 07:08:37 INFO - PROCESS | 3026 | Cycle 1(1): loaded http://localhost:57267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57267/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:08:38 INFO - PROCESS | 3026 | RSS: Main: 315863040 07:08:38 INFO - PROCESS | 3026 | 07:08:40 INFO - PROCESS | 3026 | Cycle 1(1): loaded http://localhost:57267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:08:41 INFO - PROCESS | 3026 | RSS: Main: 319393792 07:08:41 INFO - PROCESS | 3026 | 07:08:44 INFO - PROCESS | 3026 | Cycle 1(1): loaded http://localhost:57267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:08:45 INFO - PROCESS | 3026 | RSS: Main: 320921600 07:08:45 INFO - PROCESS | 3026 | 07:08:47 INFO - PROCESS | 3026 | Cycle 1(1): loaded http://localhost:57267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:08:48 INFO - PROCESS | 3026 | RSS: Main: 317792256 07:08:48 INFO - PROCESS | 3026 | 07:08:50 INFO - PROCESS | 3026 | Cycle 1(1): loaded http://localhost:57267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57267/tests/kraken/driver.html?testName=ai-astar) 07:08:50 INFO - PROCESS | 3026 | RSS: Main: 382009344 07:08:50 INFO - PROCESS | 3026 | 07:08:50 INFO - PROCESS | 3026 | __start_tp_report 07:08:50 INFO - PROCESS | 3026 | _x_x_mozilla_page_load 07:08:50 INFO - PROCESS | 3026 | _x_x_mozilla_page_load_details 07:08:50 INFO - PROCESS | 3026 | |i|pagename|runs| 07:08:50 INFO - PROCESS | 3026 | |0;ai-astar;117;163;96;157;167;167;113;113;112;136 07:08:50 INFO - PROCESS | 3026 | |1;audio-beat-detection;159;122;150;198;136;142;119;156;158;118 07:08:50 INFO - PROCESS | 3026 | |2;audio-dft;183;185;184;184;184;185;189;185;183;183 07:08:50 INFO - PROCESS | 3026 | |3;audio-fft;71;78;75;154;57;59;77;91;144;56 07:08:50 INFO - PROCESS | 3026 | |4;audio-oscillator;93;93;140;78;113;71;142;111;116;171 07:08:50 INFO - PROCESS | 3026 | |5;imaging-gaussian-blur;88;85;88;87;135;124;90;85;88;84 07:08:50 INFO - PROCESS | 3026 | |6;imaging-darkroom;92;120;151;94;92;94;97;93;93;93 07:08:50 INFO - PROCESS | 3026 | |7;imaging-desaturate;84;86;85;84;98;140;141;85;85;86 07:08:50 INFO - PROCESS | 3026 | |8;json-parse-financial;61;66;65;65;64;64;65;81;60;57 07:08:50 INFO - PROCESS | 3026 | |9;json-stringify-tinderbox;64;64;73;69;80;78;72;70;71;70 07:08:50 INFO - PROCESS | 3026 | |10;stanford-crypto-aes;80;82;94;104;107;81;79;79;81;85 07:08:50 INFO - PROCESS | 3026 | |11;stanford-crypto-ccm;137;139;153;150;141;129;139;148;187;175 07:08:50 INFO - PROCESS | 3026 | |12;stanford-crypto-pbkdf2;208;199;166;165;165;168;168;165;175;164 07:08:50 INFO - PROCESS | 3026 | |13;stanford-crypto-sha256-iterative;68;68;69;68;67;66;67;68;68;65 07:08:50 INFO - PROCESS | 3026 | __end_tp_report 07:08:50 INFO - PROCESS | 3026 | __start_cc_report 07:08:50 INFO - PROCESS | 3026 | _x_x_mozilla_cycle_collect,1971 07:08:50 INFO - PROCESS | 3026 | __end_cc_report 07:08:50 INFO - PROCESS | 3026 | __startTimestamp1465222130452__endTimestamp 07:08:50 INFO - PROCESS | 3026 | 07:08:50 INFO - PROCESS | 3026 | ------- Summary: start ------- 07:08:50 INFO - PROCESS | 3026 | Number of tests: 14 07:08:50 INFO - PROCESS | 3026 | 07:08:50 INFO - PROCESS | 3026 | [#0] ai-astar Cycles:10 Average:134.10 Median:160.00 stddev:27.18 (17.0%) stddev-sans-first:28.12 07:08:50 INFO - PROCESS | 3026 | Values: 117.0 163.0 96.0 157.0 167.0 167.0 113.0 113.0 112.0 136.0 07:08:50 INFO - PROCESS | 3026 | 07:08:50 INFO - PROCESS | 3026 | [#1] audio-beat-detection Cycles:10 Average:145.80 Median:153.00 stddev:24.35 (15.9%) stddev-sans-first:25.36 07:08:50 INFO - PROCESS | 3026 | Values: 159.0 122.0 150.0 198.0 136.0 142.0 119.0 156.0 158.0 118.0 07:08:50 INFO - PROCESS | 3026 | 07:08:50 INFO - PROCESS | 3026 | [#2] audio-dft Cycles:10 Average:184.50 Median:184.50 stddev:1.78 (1.0%) stddev-sans-first:1.80 07:08:50 INFO - PROCESS | 3026 | Values: 183.0 185.0 184.0 184.0 184.0 185.0 189.0 185.0 183.0 183.0 07:08:50 INFO - PROCESS | 3026 | 07:08:50 INFO - PROCESS | 3026 | [#3] audio-fft Cycles:10 Average:86.20 Median:73.00 stddev:34.92 (47.8%) stddev-sans-first:36.60 07:08:50 INFO - PROCESS | 3026 | Values: 71.0 78.0 75.0 154.0 57.0 59.0 77.0 91.0 144.0 56.0 07:08:50 INFO - PROCESS | 3026 | 07:08:50 INFO - PROCESS | 3026 | [#4] audio-oscillator Cycles:10 Average:112.80 Median:121.00 stddev:31.15 (25.7%) stddev-sans-first:32.21 07:08:50 INFO - PROCESS | 3026 | Values: 93.0 93.0 140.0 78.0 113.0 71.0 142.0 111.0 116.0 171.0 07:08:50 INFO - PROCESS | 3026 | 07:08:50 INFO - PROCESS | 3026 | [#5] imaging-gaussian-blur Cycles:10 Average:95.40 Median:87.50 stddev:18.25 (20.9%) stddev-sans-first:19.16 07:08:50 INFO - PROCESS | 3026 | Values: 88.0 85.0 88.0 87.0 135.0 124.0 90.0 85.0 88.0 84.0 07:08:50 INFO - PROCESS | 3026 | 07:08:50 INFO - PROCESS | 3026 | [#6] imaging-darkroom Cycles:10 Average:101.90 Median:93.00 stddev:19.21 (20.7%) stddev-sans-first:20.04 07:08:50 INFO - PROCESS | 3026 | Values: 92.0 120.0 151.0 94.0 92.0 94.0 97.0 93.0 93.0 93.0 07:08:50 INFO - PROCESS | 3026 | 07:08:50 INFO - PROCESS | 3026 | [#7] imaging-desaturate Cycles:10 Average:97.40 Median:85.00 stddev:23.09 (27.2%) stddev-sans-first:23.97 07:08:50 INFO - PROCESS | 3026 | Values: 84.0 86.0 85.0 84.0 98.0 140.0 141.0 85.0 85.0 86.0 07:08:50 INFO - PROCESS | 3026 | 07:08:50 INFO - PROCESS | 3026 | [#8] json-parse-financial Cycles:10 Average:64.80 Median:65.00 stddev:6.36 (9.8%) stddev-sans-first:6.59 07:08:50 INFO - PROCESS | 3026 | Values: 61.0 66.0 65.0 65.0 64.0 64.0 65.0 81.0 60.0 57.0 07:08:50 INFO - PROCESS | 3026 | 07:08:50 INFO - PROCESS | 3026 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:71.50 stddev:5.15 (7.2%) stddev-sans-first:4.78 07:08:50 INFO - PROCESS | 3026 | Values: 64.0 64.0 73.0 69.0 80.0 78.0 72.0 70.0 71.0 70.0 07:08:50 INFO - PROCESS | 3026 | 07:08:50 INFO - PROCESS | 3026 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:81.00 stddev:10.62 (13.1%) stddev-sans-first:10.94 07:08:50 INFO - PROCESS | 3026 | Values: 80.0 82.0 94.0 104.0 107.0 81.0 79.0 79.0 81.0 85.0 07:08:50 INFO - PROCESS | 3026 | 07:08:50 INFO - PROCESS | 3026 | [#11] stanford-crypto-ccm Cycles:10 Average:149.80 Median:149.00 stddev:18.07 (12.1%) stddev-sans-first:18.57 07:08:50 INFO - PROCESS | 3026 | Values: 137.0 139.0 153.0 150.0 141.0 129.0 139.0 148.0 187.0 175.0 07:08:50 INFO - PROCESS | 3026 | 07:08:50 INFO - PROCESS | 3026 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.30 Median:168.00 stddev:15.85 (9.4%) stddev-sans-first:11.17 07:08:50 INFO - PROCESS | 3026 | Values: 208.0 199.0 166.0 165.0 165.0 168.0 168.0 165.0 175.0 164.0 07:08:50 INFO - PROCESS | 3026 | 07:08:50 INFO - PROCESS | 3026 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.00 stddev:1.17 (1.7%) stddev-sans-first:1.22 07:08:50 INFO - PROCESS | 3026 | Values: 68.0 68.0 69.0 68.0 67.0 66.0 67.0 68.0 68.0 65.0 07:08:50 INFO - PROCESS | 3026 | -------- Summary: end -------- 07:08:50 INFO - PROCESS | 3026 | 07:08:51 INFO - TEST-INFO | 3026: exit 0 07:08:51 INFO - TEST-OK | kraken | took 59838ms 07:08:51 INFO - SUITE-END | took 1174s 07:08:51 INFO - Completed test suite (00:19:34) 07:08:51 INFO - javascript benchmark 07:08:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3120.6411262704787, 3407.0630576591434, 3421.5038819371175, 3431.5538979932603, 3482.6865582097607, 5692.316921969365, 5704.229928959589, 5716.714164291784, 5721.885562288751, 5736.455036771506, 5686.431356864311, 5730.885382292352, 5741.540676745861, 5760.107183386572, 5766.855828604287, 5114.923276150842, 5165.845024649267, 5178.430372659004, 5306.655067420625, 5319.308489896308, 5236.947630523703, 5254.7635356409055, 5262.60530460217, 5268.420473747885, 5269.209618557228, 5144.09978253806, 5206.088934436478, 5207.297014902996, 5231.450697676746, 5245.711485868275, 4575.519570445122, 5195.870103247411, 5236.6857988520815, 5259.158534634478, 5303.496167864047, 5054.671446356012, 5090.10923088458, 5156.845294641167, 5177.171652535576, 5189.117849965515, 5478.45215478449, 5507.118860982224, 5515.4760297772045, 5525.889482210333, 5552.11166213408, 6201.379862013808, 6224.0, 6231.0, 6241.126242326078, 6245.906311405332, 6049.304330002018, 6140.903050468145, 6220.409061139185, 6222.346653601352, 6247.312795592481, 5767.971160144172, 5796.797112101056, 5799.507041901444, 5799.8550036249435, 5802.825915222508, 6254.718537665816, 6269.49844012487, 6272.18461599989, 6281.905771413432, 6285.811425657238, 5035.219540971156, 5936.287645482484, 5950.107483877453, 6035.547333949883, 6066.998945174085, 6181.598196117238, 6190.659513726789, 6197.566170368031, 6198.504119670415, 6225.968870155621, 6086.391360863967, 6139.662318572521, 6145.877082458326, 6166.784162554289, 6169.259688837368, 5985.34161242263, 6100.969495152496, 6103.1455596216565, 6103.328633850273, 6170.136180934673, 4876.951230487721, 4899.608031357483, 4907.7300748458465, 4911.238757992517, 4932.90134197314, 4358.5205627380965, 4432.445944256968, 4436.933445998313, 4461.665375096881, 4488.663350248745, 5563.221149039137, 5591.664500129924, 5597.832065038056, 5618.4662457066515, 5631.71841407928, 6213.068039793977, 6224.221972253468, 6234.812955611339, 6238.1890354254565, 6249.437550620466, 5516.779328826802, 5570.248016517799, 5592.2730045094695, 5603.551715862721, 5658.34928983164, 5371.033214021436, 5391.433899440581, 5396.082665946798, 5402.972985135049, 5423.755930983115, 6297.748090076438, 6334.493240540745, 6341.048842673451, 6343.492520598341, 6345.746170153142, 6065.696715164136, 6116.33577150815, 6145.016797312409, 6165.445109940127, 6223.657698826608, 6336.746530138743, 6363.61818290904, 6381.936180638134, 6383.329750376143, 6387.0, 6152.753889844356, 6157.907631385443, 6170.598911070766, 6171.043488259327, 6188.350223226496, 6092.390760923872, 6121.148138557471, 6205.782797602063, 6222.533310001678, 6238.4073513018, 4728.31439441286, 5986.653003074273, 5988.221531200916, 6025.246844144482, 6056.485198757979, 5967.433093856077, 6030.065339872327, 6048.60684055535, 6050.606710563799, 6076.756929722762, 4684.8360307389075, 5901.7639294427745, 5969.462748352669, 6005.819825405245, 6019.0031804902155, 6025.102107742252, 6034.849128771816, 6039.305479869696, 6050.1227322037175, 6110.114033465212, 5761.308642962871, 5881.235439392852, 5909.970450147722, 5913.083472061838, 5925.762969481345], "value": 5650.183067869178, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [520.7370278009605, 532.5766016017267, 541.3233458177278, 591.6213623281101, 618.3013195089549, 30942.91699790505, 31450.842745786267, 38327.23345533091, 38656.84029479113, 38772.0, 42070.94822629436, 42664.72005839822, 42677.7866110669, 42731.572684273146, 42772.57227427725, 9261.768455788591, 9474.147326740593, 9485.288603354742, 9485.95257023714, 9487.28845336599, 8453.95773021137, 8693.304535637133, 11355.943220283887, 11574.710632234219, 11601.419929003558, 11286.153538484605, 11340.60307889224, 11368.97679208871, 11376.089912806956, 11381.260218085798, 7930.0, 11326.60356887509, 11385.658430247106, 11389.658310250665, 11434.028107610862, 11301.26541774782, 11411.94294028533, 11437.313761174357, 11445.141614378956, 11460.484278207417, 38770.0, 39076.60923390787, 39115.804420977714, 39156.21687566233, 39172.60827391747, 3193.936121277585, 3225.709686128236, 3497.965020349815, 3671.284099600579, 3851.7111216658864, 28326.875484338758, 28533.0, 28614.998475053482, 29262.853685731647, 29294.121176364744, 2730.7269273072734, 2738.397552538458, 2745.7117002714826, 2784.986075069632, 2788.093869492423, 499702.0, 501163.0, 501861.99999999633, 501887.0, 502233.0, 63054.68472657699, 63488.36511634824, 63496.68251658712, 63571.04643430352, 63714.0, 61099.69450152721, 71989.0, 73095.63452182812, 73116.63441682865, 73141.0, 18013.7297940531, 18044.548886277684, 18205.453836384666, 18236.36172733948, 18268.451946441626, 12590.48914130292, 12765.872341276654, 12779.744405111845, 13808.0, 13912.791308130387, 17391.304347826197, 17640.20619072145, 19947.80313181185, 20601.793982059986, 20602.27892023772, 8099.83800324002, 8224.465409748347, 8252.381071419668, 8270.007599088149, 8277.917220827836, 975.9853602195973, 984.4733067808688, 1050.6217761605815, 1089.1178145702006, 1133.56924368741, 25311.746882531308, 30064.0, 30332.54501182484, 30383.69616303853, 30481.542776858365, 1188.1326631559011, 1209.4315671634317, 1239.114033466076, 1240.2558464920976, 1260.1305099481328, 15359.308831102628, 15441.459548915735, 15523.767143492856, 15594.766078508832, 15617.3753049879, 634827.0, 641996.0, 643030.0, 643777.0, 644550.0, 59955.70022149861, 61602.0, 61837.69081154565, 61879.38120618826, 61991.0, 1327.9800802987963, 1386.3899884050986, 1521.6195951012246, 1533.9233038348261, 1572.6697393547483, 28489.0, 28760.712392875805, 29219.707802921697, 29921.850390747906, 30027.0, 47533.0, 47571.0, 47752.7612361936, 47791.56625301247, 47902.28146577874, 347909.0, 352777.0, 353141.0, 353291.46708532586, 610801.0, 23437.531249374577, 45628.08743825038, 45765.77117114393, 45803.54196458126, 46491.3026304599, 48126.518734812205, 49876.50123498718, 50045.999080018926, 50066.249006265665, 51632.0, 38248.0, 38926.2214755709, 39292.80353598214, 39475.60524394834, 40733.79633101816, 38944.2211155781, 38948.02629934161, 39327.016824579616, 39951.0, 40277.79861100676, 39513.2097358057, 39666.206675866895, 39850.40224396694, 40437.79781101076, 40768.79615601903, 28635.570466442605, 33815.35703615158, 34623.82688086543, 35494.46758298679, 35739.64260357363, 22241.44396390103, 25373.619395709447, 25850.35374115662, 26172.8691356542, 26491.867540662173], "value": 20659.80971424974, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.60044355201245, 397.703710735502, 400.42139108987527, 403.2378804060327, 412.4123124547519, 14854.554363369116, 19016.714749278774, 20120.0, 20788.168473261052, 20834.791652083473, 16602.418915337967, 16715.749263761052, 18632.44102676922, 20648.587028259462, 20906.790932090673, 4894.898647804252, 5760.56795740319, 5962.04607262838, 5972.761089556434, 5977.342492325841, 4157.97921010396, 5809.099589563603, 5909.940900590982, 5928.703564821763, 5945.643261404308, 4285.250081235787, 4629.837955671552, 6493.675316234193, 6507.05647681086, 6526.445252153549, 6388.744450221986, 6473.093766872642, 6474.773382931575, 6480.935190648104, 6501.804945851632, 5534.169874518814, 5666.886662266773, 5701.0, 5701.486866182022, 5709.65742055478, 25296.747032529624, 25320.860561274785, 25657.973681052736, 26567.0, 26649.8007589657, 2162.0703097667997, 2164.837637177218, 2207.6136676081624, 2318.304508647399, 2802.1733588591414, 14055.64860878476, 14528.273586320642, 14756.188409637465, 14860.182689952047, 15011.099334039993, 1689.1487408708165, 1765.5144835170302, 1784.2595322940963, 1794.5244510204807, 1827.5339788354017, 108297.91702083035, 109628.0, 114390.0, 115470.0, 117197.0, 10831.620893268699, 11157.051650609626, 11275.605353812578, 11383.373914434786, 11552.0, 14248.430062797372, 14335.641608959859, 14354.066985645899, 14376.928115359357, 14389.352479138464, 6719.932800672029, 8072.556009419538, 8179.345652347798, 8196.54918979462, 8829.602667879832, 8127.512349259064, 8156.306713929383, 8174.182581741778, 8951.32865035125, 8998.775030624287, 6802.183737951477, 6846.246912839583, 6847.897281540681, 6892.483063770137, 7262.346388825031, 1627.983720162807, 1848.196034724899, 1863.7018077107598, 1919.2171351540323, 1919.8176173263519, 913.5569248914271, 979.9412035277907, 1027.106417416852, 1066.648006157966, 1106.4025426269884, 16001.59996000086, 20195.495112622008, 21325.46686332823, 21714.674279885498, 21938.341849744535, 891.5364010714392, 972.9124378805941, 1009.8687170667766, 1030.1038096855634, 1083.2397354299264, 9422.387544809566, 10724.410157441416, 11459.713507162222, 11484.483198256099, 11518.308901465938, 603304.0, 614923.0, 634354.0, 636627.0, 647171.0, 137539.6246037547, 138009.30995344959, 141332.0, 143995.0, 144933.0, 1009.5002973528151, 1042.3433237060756, 1053.5891002509027, 1057.2123767793043, 1057.7514284143067, 14926.402943882558, 17431.128443577516, 18189.363372281907, 18372.35696750607, 18651.813481865007, 54340.45659543512, 56401.71799140978, 56475.15287270777, 56684.29947101593, 56714.0, 79768.60115699384, 81963.18036819555, 82901.58549207215, 83396.0, 85536.0, 5966.820995370146, 6715.7985260442265, 6795.762148324785, 8325.916740832514, 8325.95837020811, 11745.941270293593, 12337.753244934871, 12579.119461637598, 12678.429470674026, 12856.485740570273, 10999.890001099886, 11024.44877756125, 11233.775324493301, 11258.0, 11457.369844658624, 5652.0, 7244.877044058179, 10594.046535811505, 10903.618373356894, 11133.944330278297, 10336.534855931652, 11048.558057677603, 11564.768704625692, 11609.3614851184, 11634.70913227176, 8130.471519351223, 8159.0, 8333.916660833556, 8350.331973442231, 8545.615447304763, 9025.638974440948, 10308.32995855267, 11004.174686898354, 11319.264247824194, 11394.658160255205], "value": 9720.2592736507, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1424.914505129693, 1573.150498730685, 1595.4017243533672, 1842.9815701842986, 1975.8122978317056, 7968.721094761684, 8565.0, 8779.429337093085, 8801.823963520726, 8842.469451832887, 8645.092265312145, 8758.386912916098, 8769.561521923893, 8769.60536775845, 9089.727308180749, 2112.7359080114984, 2458.6803715516958, 2481.937951551208, 2483.3046746910804, 2483.6026235802274, 2417.02110645189, 2452.1049816816912, 2456.815738819587, 2468.1361523466735, 2476.702795664523, 2447.902083916641, 2468.666729991455, 2480.2063339731294, 2484.6521486991837, 2498.263012411334, 2453.1533096721405, 2466.654668346433, 2468.2965354873863, 2480.9255722328357, 2484.1181380609896, 2034.491377155711, 2426.478307163968, 2454.337328921199, 2457.6313552967017, 2459.2622213335926, 8514.48913065218, 8814.647414103492, 8873.068327825615, 8878.978917424514, 8880.200781929592, 5347.919781203285, 5494.09347457669, 5507.449255074501, 5553.305836770403, 5559.138333558305, 2517.0241360531895, 3433.9828300858335, 3479.356319080978, 3494.475828625701, 3513.578370595519, 1053.773438710681, 1062.2988827373974, 1071.9792942389781, 1079.5519859258397, 1085.8262677971486, 12064.577739779066, 12143.757124857453, 12153.210041347284, 12173.026157907347, 12175.878241217652, 4550.590446859798, 4707.670463067552, 4726.740029298353, 4726.976365118152, 4727.834525791581, 9400.670976515927, 9474.621015159455, 9509.809803804024, 9513.571889265, 9525.095115963972, 4482.529334419904, 5178.404483484373, 5353.839384818461, 5368.1679339702405, 5403.297571315782, 4347.239233134189, 4457.643388528974, 4710.8351207707565, 4890.266460030953, 4943.332650092263, 3811.0472381904524, 3835.4438606401914, 3882.514685664292, 3916.5104361954754, 3917.0, 1195.9701007474882, 1196.0551164579883, 1199.1126566340845, 1208.4259976511087, 1212.1636071110904, 715.5492040014757, 755.7921571567863, 865.5542395666237, 873.6811063961608, 945.7871978804714, 5627.099664053813, 5747.224124743107, 5873.471387575053, 5947.702614869239, 6033.84695118307, 485.4732615112572, 504.924510792018, 508.8219123306813, 509.16242780625663, 663.6316844151543, 3652.2878038782183, 3791.1469919267943, 3845.942310865339, 3905.5703872573995, 3906.0430194602504, 10474.842877356845, 10876.129909607213, 11011.944940275247, 11024.228304018801, 11032.779344413068, 10716.732081697864, 10729.678109656723, 10861.837072443761, 10863.130949524018, 10867.456627168767, 8411.411201215857, 8468.0, 8470.407071504935, 8486.32109431244, 8505.957470212734, 4207.430079050906, 5796.797112101056, 5809.680467574324, 5844.649321040752, 5909.025010873158, 9191.816163676554, 9361.485118318558, 9461.290403219924, 9470.052994700474, 9548.904510954802, 9603.37578057424, 9903.158231550398, 9973.451460169761, 10126.645567405105, 10151.54318055704, 7606.087269527471, 7643.617819109134, 7738.845223095619, 7747.1090824555895, 7772.455928085206, 8331.875021874797, 8334.91646086005, 8339.624716887876, 8410.663573457237, 8417.62120704582, 6667.866642667216, 7174.1032370953635, 7622.69509219625, 7747.2640099190485, 7755.72854950074, 7411.036565246483, 7483.738069167553, 7593.468457208163, 7626.504277221963, 7646.770596882103, 7043.823904402226, 7068.3285087916565, 7094.326039026284, 7141.214466408587, 7161.069061022034, 4800.543948324904, 4935.654504184673, 4941.258811178352, 5011.273365362075, 5034.773435195351, 4869.829555965524, 4992.925106123338, 5025.422076461254, 5099.949000509948, 5267.815626453055], "value": 4513.630324864525, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.1613961769209, 327.3779818345144, 330.10212222754114, 334.725525069443, 339.99320013599714, 6578.572392794471, 6613.86772264555, 6701.82718024345, 6713.429358504533, 6717.395434410902, 6599.8020059398295, 6632.1709786276715, 6634.767783127591, 6637.871561834488, 6668.166479190101, 4652.32541281514, 4788.592969597588, 4815.181419158752, 4859.368282123319, 4870.512948705136, 4806.327114204014, 4869.805207791702, 4876.561109500144, 4884.023195360925, 4902.607791376699, 4773.474917759044, 4831.975840120795, 4833.540813622701, 4845.152098382787, 4865.732384718839, 3548.7870727756417, 3811.485449464328, 4843.878903027417, 4854.17479028566, 4875.951240487603, 3365.848536815849, 3997.160596274786, 4026.0136266614572, 4149.771762553059, 4171.124063946575, 6291.528135389818, 6326.841828954267, 6338.936610633881, 6341.968290158566, 6384.3296453872135, 5712.685802280873, 5740.138979153118, 5740.712964351766, 5748.42515748426, 5769.221155144044, 7038.824029399306, 7043.92956070443, 7044.929550704479, 7049.013138160662, 7050.6122163281, 4684.648651351163, 4686.273627587695, 4688.17956857552, 4689.085628302482, 4692.178868697998, 7121.53710008853, 7442.441816863703, 7491.363234125106, 7498.47510674253, 7516.285952834472, 4183.247015537232, 4231.730480855731, 4657.138429390574, 4661.9976705008585, 4673.579377856009, 4725.834595789131, 4726.905461890743, 4730.219513780256, 4730.763461826895, 4733.597644200213, 5612.943870561242, 5632.155176723524, 5632.408597097327, 5633.324001119892, 5634.915476267859, 5995.070764031582, 7126.429885609139, 7128.6435678215885, 7128.750493732695, 7141.857162856818, 1490.9329080191417, 1635.4194261037399, 1646.0617448054734, 1647.3822316631263, 1649.7442896351085, 1027.1166796554958, 1033.7570670892485, 1047.5704960965968, 1048.260976011925, 1050.5115121468555, 6491.7078731457195, 6657.500687448461, 6686.09737685416, 6698.162729658793, 6700.463962882957, 7243.058402407653, 7464.328210461084, 7468.813279667943, 7498.625068746541, 7507.962460187773, 6768.661566921733, 6770.492213084039, 6771.593704377753, 6776.119104516381, 6780.423663988616, 3908.726389152732, 3950.762954222698, 3954.0, 3959.6436320731273, 3962.5641179470235, 7509.098908131059, 7765.883511747328, 7796.766097016985, 7861.371090312761, 7861.449698521049, 7683.462157649023, 7721.112072111667, 7722.382209423233, 7737.613119344122, 7741.7677469676, 7870.645820938072, 7872.094709108525, 7873.763787086396, 7880.448368614257, 7896.7236146734585, 7450.36671882896, 7450.8882366763455, 7451.0, 7456.7390141344795, 7477.401807855467, 7054.188768291507, 7313.414926805949, 7314.08573928259, 7336.229695882069, 7345.3021962913435, 7740.806479838049, 7801.102873169658, 7814.257645523666, 7825.921740782519, 7826.178251283761, 4628.514006029453, 4677.0, 4678.50875658051, 4682.508336624742, 4688.179568575554, 7381.44639152055, 7387.335139837441, 7387.704491820267, 7404.777856664308, 7414.184439711736, 6864.519483636098, 7164.820879478055, 7178.461615378972, 7180.3537681608905, 7182.856342873218, 7080.822979425556, 7161.4270858330165, 7174.461915356264, 7183.20984691673, 7198.316159964795, 6990.860182796214, 6991.126109236346, 6996.125484314461, 6997.300269972962, 6999.0, 6891.241963384124, 7123.038389817516, 7140.7857764267155, 7142.714291428285, 7149.714011439484, 6681.76613818514, 6910.654467276516, 7005.124359455068, 7010.439164866696, 7015.649217539205], "value": 5191.621509131956, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [699.8530308635186, 731.73657483306, 738.9445791565633, 756.6897571995482, 757.4849102610223, 14898.627534311634, 14985.250737463111, 15269.69460610787, 15300.54098377049, 15306.923465382672, 14062.718745625081, 14141.929290353533, 14146.221957792317, 14152.716945661106, 14158.433662653508, 733.0067758187658, 752.7440670172141, 899.5457294066504, 920.2362039507193, 932.4638332958542, 932.7668082979255, 935.588341129904, 939.4316438554679, 939.9295052871028, 940.4404379394249, 981.4601968917103, 983.1692220074026, 987.9506024698736, 989.1542730964993, 990.2523594686002, 644.185105841109, 864.654138344664, 959.5729900194416, 980.6518685866486, 983.9065288797552, 730.0800990751638, 730.5069078372078, 731.3235257386949, 731.4294850016942, 731.4843035659848, 14900.403983840633, 14964.775528366974, 14993.775093373608, 15000.949933504658, 15024.399024039025, 12562.996934273804, 12581.874181258254, 12595.803398677202, 12809.61571152867, 12948.093633445662, 10077.949610251902, 10126.392416455037, 10136.0, 10243.692689219335, 10344.793104137874, 3436.1409647588102, 3505.544279243682, 3529.5588051493564, 3600.08197909534, 3609.855605775766, 87614.12385876187, 87709.0, 90507.0, 90655.0, 91314.54342728244, 9470.95264523673, 9503.441226237588, 9604.327697061139, 9748.463834489172, 9814.165795907285, 21006.894965525382, 22870.542589148125, 22872.7712722874, 22898.8855055727, 22951.08195672188, 7688.961555192188, 7698.6305464363995, 7717.961410192913, 7723.227677232344, 8016.759497215093, 4170.020045289354, 4173.937390939138, 4188.162367526446, 4237.766922819213, 4459.331100334911, 3628.8911332660064, 3668.523091998023, 3685.686716629063, 3686.2443199144113, 3686.612905644922, 553.3995614758032, 611.2084850119106, 640.5708175522411, 649.4771708774482, 657.6777379084215, 460.5544558394867, 498.78053656391074, 560.249265983584, 612.1399433795524, 617.1452538234578, 7974.0, 8083.636236369378, 8129.512229266263, 8208.220219079178, 8266.214709602578, 600.2317034196236, 601.3145014683265, 601.9909701354483, 611.1627070912789, 615.6798464798369, 3669.229461813049, 3783.50814394127, 3864.555576108727, 3986.0443526046656, 4026.8187931543152, 97578.512107439, 98278.0, 100066.49966750265, 100187.0, 100288.99711002942, 81260.78108828372, 81984.0, 83124.0, 83212.0, 84406.0, 72830.0, 75181.0, 75422.6696927593, 75428.24571754322, 75812.0, 7491.662875170521, 7640.808979775327, 7740.458167928191, 7834.0599128105, 7835.333996610352, 47058.29412558883, 47818.04363912772, 48074.759626201645, 49062.75468622634, 51532.711682208246, 64522.0, 68733.0, 69354.61290774257, 69419.30580694128, 70296.0, 9863.112319891245, 10033.827479852984, 10049.246306527186, 10162.18702503783, 10167.644132455329, 10622.150227981898, 10755.569777209137, 10800.891991080303, 10905.945470272914, 10976.560937562728, 10044.849327260243, 10155.695329140137, 10285.919978402459, 10928.071113955084, 10947.56209751601, 10101.0, 10105.040021197974, 10215.02957219063, 10371.0, 11059.447027648424, 9933.3543319684, 10107.039831216023, 10289.74275643115, 10304.072633463024, 10974.914766194397, 8759.956200218958, 8792.252658523841, 8846.46921184731, 9045.095490450902, 9047.909520904706, 8794.164554367326, 8926.68756593516, 8938.597763100546, 9008.684696035869, 9112.453252804853], "value": 6863.9170744754265, "unit": "score"}], "name": "dromaeo_css", "value": 7531.553640879058, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 163.0, 96.0, 157.0, 167.0, 167.0, 113.0, 113.0, 112.0, 136.0], "value": 134.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 122.0, 150.0, 198.0, 136.0, 142.0, 119.0, 156.0, 158.0, 118.0], "value": 145.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 184.0, 184.0, 184.0, 185.0, 189.0, 185.0, 183.0, 183.0], "value": 184.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 78.0, 75.0, 154.0, 57.0, 59.0, 77.0, 91.0, 144.0, 56.0], "value": 86.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 140.0, 78.0, 113.0, 71.0, 142.0, 111.0, 116.0, 171.0], "value": 112.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 87.0, 135.0, 124.0, 90.0, 85.0, 88.0, 84.0], "value": 95.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 120.0, 151.0, 94.0, 92.0, 94.0, 97.0, 93.0, 93.0, 93.0], "value": 101.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 85.0, 84.0, 98.0, 140.0, 141.0, 85.0, 85.0, 86.0], "value": 97.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 66.0, 65.0, 65.0, 64.0, 64.0, 65.0, 81.0, 60.0, 57.0], "value": 64.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 73.0, 69.0, 80.0, 78.0, 72.0, 70.0, 71.0, 70.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 82.0, 94.0, 104.0, 107.0, 81.0, 79.0, 79.0, 81.0, 85.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 139.0, 153.0, 150.0, 141.0, 129.0, 139.0, 148.0, 187.0, 175.0], "value": 149.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [208.0, 199.0, 166.0, 165.0, 165.0, 168.0, 168.0, 165.0, 175.0, 164.0], "value": 174.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 68.0, 67.0, 66.0, 67.0, 68.0, 68.0, 65.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1572.6999999999998, "alertThreshold": 2.0}]} 07:08:51 INFO - Return code: 0 07:08:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 07:08:51 ERROR - # TBPL SUCCESS # 07:08:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:08:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:08:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:08:51 INFO - 2016-06-06 07:08:51.773 screenresolution[3030:40460] starting screenresolution argv=screenresolution get 07:08:51 INFO - 2016-06-06 07:08:51.776 screenresolution[3030:40460] Display 0: 1600x1200x32@60 07:08:51 INFO - 2016-06-06 07:08:51.782 screenresolution[3031:40463] starting screenresolution argv=screenresolution list 07:08:51 INFO - Available Modes on Display 0 07:08:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:08:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:08:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:08:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:08:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:08:52 INFO - Intel Iris: 07:08:52 INFO - Chipset Model: Intel Iris 07:08:52 INFO - Type: GPU 07:08:52 INFO - Bus: Built-In 07:08:52 INFO - VRAM (Dynamic, Max): 1536 MB 07:08:52 INFO - Vendor: Intel (0x8086) 07:08:52 INFO - Device ID: 0x0a2e 07:08:52 INFO - Revision ID: 0x0009 07:08:52 INFO - Displays: 07:08:52 INFO - Display: 07:08:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:08:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:08:52 INFO - Display Serial Number: 1600x1200 60 07:08:52 INFO - Main Display: Yes 07:08:52 INFO - Mirror: Off 07:08:52 INFO - Online: Yes 07:08:52 INFO - Rotation: Supported 07:08:52 INFO - Return code: 0 07:08:52 INFO - Running post-action listener: _resource_record_post_action 07:08:52 INFO - Running post-run listener: _resource_record_post_run 07:08:53 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 602112; Write bytes: 648964608; Read time: 3; Write time: 1838 07:08:53 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 345784832; Write bytes: 345982464; Read time: 26607; Write time: 754 07:08:53 INFO - run-tests - Wall time: 1177s; CPU: 52.0%; Read bytes: 602112; Write bytes: 293020672; Read time: 3; Write time: 1047 07:08:53 INFO - Running post-run listener: _upload_blobber_files 07:08:53 INFO - Blob upload gear active. 07:08:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:08:53 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:08:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:08:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:08:53 INFO - (blobuploader) - INFO - Open directory for files ... 07:08:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:08:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:08:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:08:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:08:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:08:54 INFO - (blobuploader) - INFO - Done attempting. 07:08:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:08:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:08:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:08:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:08:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:08:55 INFO - (blobuploader) - INFO - Done attempting. 07:08:55 INFO - (blobuploader) - INFO - Iteration through files over. 07:08:55 INFO - Return code: 0 07:08:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:08:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:08:55 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6028c1912f15049834dacc3974bd623191ca9a3921a7d97062d754f9c7a17d9e64787c90b4c1482220247732c323069f2c684c3f96bb6b9032c77138b6170f86", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e7800010c9c3f5f851e95933d959b6bac2cd700e025d6efd34f800d3d7f7c359140d067d076d1941490b69d85ace1dc7fb76381746d00d9df5e8534fe3699847"} 07:08:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:08:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:08:55 INFO - Contents: 07:08:55 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6028c1912f15049834dacc3974bd623191ca9a3921a7d97062d754f9c7a17d9e64787c90b4c1482220247732c323069f2c684c3f96bb6b9032c77138b6170f86", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e7800010c9c3f5f851e95933d959b6bac2cd700e025d6efd34f800d3d7f7c359140d067d076d1941490b69d85ace1dc7fb76381746d00d9df5e8534fe3699847"} 07:08:55 INFO - Running post-run listener: copy_logs_to_upload_dir 07:08:55 INFO - Copying logs to upload dir... 07:08:55 INFO - mkdir: /builds/slave/test/build/upload/logs 07:08:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.818619 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-06-06 07:08:55.949786) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 07:08:55.953380) ========= 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.djG6xTcUqP/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.dnDYTOcxny/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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/6028c1912f15049834dacc3974bd623191ca9a3921a7d97062d754f9c7a17d9e64787c90b4c1482220247732c323069f2c684c3f96bb6b9032c77138b6170f86", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e7800010c9c3f5f851e95933d959b6bac2cd700e025d6efd34f800d3d7f7c359140d067d076d1941490b69d85ace1dc7fb76381746d00d9df5e8534fe3699847"} 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.011250 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/6028c1912f15049834dacc3974bd623191ca9a3921a7d97062d754f9c7a17d9e64787c90b4c1482220247732c323069f2c684c3f96bb6b9032c77138b6170f86", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e7800010c9c3f5f851e95933d959b6bac2cd700e025d6efd34f800d3d7f7c359140d067d076d1941490b69d85ace1dc7fb76381746d00d9df5e8534fe3699847"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 07:08:55.985591) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 07:08:55.985963) ========= 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.djG6xTcUqP/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.dnDYTOcxny/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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.005281 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 07:08:56.038049) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 07:08:56.038429) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 07:08:56.038800) ========= ========= Total master_lag: 0.16 =========