builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0210 starttime: 1466699790.83 results: success (0) buildid: 20160623073824 builduid: d39eea867a1c44bca4b8b9a99f11a4a0 revision: 77a913855173eb131614d3ff2325efdece6582a4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-23 09:36:30.829733) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-23 09:36:30.830153) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-23 09:36:30.830444) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-23 09:36:30.857917) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 09:36:30.858212) ========= 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.MgcVFumUzs/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.7aNTjd7kwc/Listeners TMPDIR=/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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-23 09:36:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 26.2M=0s 2016-06-23 09:36:30 (26.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.136996 ========= master_lag: 0.75 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 09:36:31.744907) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 09:36:31.745235) ========= 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.MgcVFumUzs/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.7aNTjd7kwc/Listeners TMPDIR=/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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.005113 ========= master_lag: 0.21 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 09:36:31.960372) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-23 09:36:31.960820) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 77a913855173eb131614d3ff2325efdece6582a4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 77a913855173eb131614d3ff2325efdece6582a4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MgcVFumUzs/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.7aNTjd7kwc/Listeners TMPDIR=/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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-23 09:36:32,041 truncating revision to first 12 chars 2016-06-23 09:36:32,042 Setting DEBUG logging. 2016-06-23 09:36:32,042 attempt 1/10 2016-06-23 09:36:32,042 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/77a913855173?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-23 09:36:33,089 unpacking tar archive at: mozilla-beta-77a913855173/testing/mozharness/ program finished with exit code 0 elapsedTime=1.311089 ========= master_lag: 0.21 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-23 09:36:33.480842) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-23 09:36:33.481377) ========= script_repo_revision: 77a913855173eb131614d3ff2325efdece6582a4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-23 09:36:33.481864) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-23 09:36:33.482190) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-23 09:36:33.501316) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-06-23 09:36:33.501669) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MgcVFumUzs/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.7aNTjd7kwc/Listeners TMPDIR=/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 09:36:33 INFO - MultiFileLogger online at 20160623 09:36:33 in /builds/slave/test 09:36:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:36:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:36:33 INFO - {'append_to_log': False, 09:36:33 INFO - 'base_work_dir': '/builds/slave/test', 09:36:33 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:36:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:36:33 INFO - 'branch': 'Mozilla-Beta', 09:36:33 INFO - 'buildbot_json_path': 'buildprops.json', 09:36:33 INFO - 'config_files': ('talos/mac_config.py',), 09:36:33 INFO - 'default_actions': ('clobber', 09:36:33 INFO - 'read-buildbot-config', 09:36:33 INFO - 'download-and-extract', 09:36:33 INFO - 'populate-webroot', 09:36:33 INFO - 'create-virtualenv', 09:36:33 INFO - 'install', 09:36:33 INFO - 'run-tests'), 09:36:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:36:33 INFO - 'download_minidump_stackwalk': True, 09:36:33 INFO - 'download_symbols': 'ondemand', 09:36:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:36:33 INFO - 'tooltool.py': '/tools/tooltool.py', 09:36:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:36:33 INFO - '/tools/misc-python/virtualenv.py')}, 09:36:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:36:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:36:33 INFO - 'installer_path': 'installer.exe', 09:36:33 INFO - 'log_level': 'info', 09:36:33 INFO - 'log_name': 'talos', 09:36:33 INFO - 'log_to_console': True, 09:36:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:36:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:36:33 INFO - 'opt_config_files': (), 09:36:33 INFO - 'pip_index': False, 09:36:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:36:33 INFO - 'cmd': ('bash', 09:36:33 INFO - '-c', 09:36:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:36:33 INFO - 'enabled': True, 09:36:33 INFO - 'halt_on_failure': False, 09:36:33 INFO - 'name': 'check_screen_resolution'},), 09:36:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:36:33 INFO - 'cmd': ('bash', 09:36:33 INFO - '-c', 09:36:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:36:33 INFO - 'enabled': True, 09:36:33 INFO - 'halt_on_failure': False, 09:36:33 INFO - 'name': 'check_screen_resolution'},), 09:36:33 INFO - 'run_cmd_checks_enabled': True, 09:36:33 INFO - 'sps_profile': False, 09:36:33 INFO - 'sps_profile_interval': 0, 09:36:33 INFO - 'suite': 'dromaeojs', 09:36:33 INFO - 'system_bits': '32', 09:36:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:36:33 INFO - 'title': 't-yosemite-r7-0210', 09:36:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:36:33 INFO - 'use_talos_json': True, 09:36:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:36:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:36:33 INFO - 'work_dir': 'build'} 09:36:33 INFO - ##### 09:36:33 INFO - ##### Running clobber step. 09:36:33 INFO - ##### 09:36:33 INFO - Running pre-action listener: _resource_record_pre_action 09:36:33 INFO - Running main action method: clobber 09:36:33 INFO - rmtree: /builds/slave/test/build 09:36:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:36:35 INFO - Running post-action listener: _resource_record_post_action 09:36:35 INFO - ##### 09:36:35 INFO - ##### Running read-buildbot-config step. 09:36:35 INFO - ##### 09:36:35 INFO - Running pre-action listener: _resource_record_pre_action 09:36:35 INFO - Running main action method: read_buildbot_config 09:36:35 INFO - Using buildbot properties: 09:36:35 INFO - { 09:36:35 INFO - "project": "", 09:36:35 INFO - "product": "firefox", 09:36:35 INFO - "script_repo_revision": "production", 09:36:35 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 09:36:35 INFO - "repository": "", 09:36:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 09:36:35 INFO - "buildid": "20160623073824", 09:36:35 INFO - "pgo_build": "False", 09:36:35 INFO - "basedir": "/builds/slave/test", 09:36:35 INFO - "buildnumber": 13, 09:36:35 INFO - "slavename": "t-yosemite-r7-0210", 09:36:35 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 09:36:35 INFO - "platform": "yosemite_r7", 09:36:35 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 09:36:35 INFO - "branch": "mozilla-beta", 09:36:35 INFO - "repo_path": "releases/mozilla-beta", 09:36:35 INFO - "revision": "77a913855173eb131614d3ff2325efdece6582a4", 09:36:35 INFO - "stage_platform": "macosx64", 09:36:35 INFO - "builduid": "d39eea867a1c44bca4b8b9a99f11a4a0", 09:36:35 INFO - "slavebuilddir": "test" 09:36:35 INFO - } 09:36:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 09:36:35 INFO - Running post-action listener: _resource_record_post_action 09:36:35 INFO - ##### 09:36:35 INFO - ##### Running download-and-extract step. 09:36:35 INFO - ##### 09:36:35 INFO - Running pre-action listener: _resource_record_pre_action 09:36:35 INFO - Running main action method: download_and_extract 09:36:35 INFO - mkdir: /builds/slave/test/build/tests 09:36: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')]} 09:36:35 INFO - https://queue.taskcluster.net/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:36:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 09:36:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 09:36:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 09:36:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 09:36:36 INFO - Downloaded 1165 bytes. 09:36:36 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 09:36:36 INFO - Using the following test package requirements: 09:36:36 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 09:36:36 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:36:36 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 09:36:36 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:36:36 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 09:36:36 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:36:36 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:36:36 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 09:36:36 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 09:36:36 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:36:36 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 09:36:36 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:36:36 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 09:36:36 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:36:36 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 09:36:36 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:36:36 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 09:36:36 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 09:36:36 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')]} 09:36:36 INFO - https://queue.taskcluster.net/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:36:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 09:36:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 09:36:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 09:36:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 09:36:38 INFO - Downloaded 18301196 bytes. 09:36:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:36:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:36:41 INFO - Return code: 0 09:36:41 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 09:36:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:41 INFO - https://queue.taskcluster.net/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:36:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 09:36:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 09:36:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 09:36:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 09:36:43 INFO - Downloaded 18301196 bytes. 09:36:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:36:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:36:46 INFO - Return code: 0 09:36:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:46 INFO - https://queue.taskcluster.net/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:36:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 09:36:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 09:36:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 09:36:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 09:36:47 INFO - Downloaded 10840145 bytes. 09:36:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:36:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:36:47 INFO - Return code: 0 09:36:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:47 INFO - https://queue.taskcluster.net/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 09:36:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.dmg 09:36:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.dmg 09:36:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 09:36:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 09:36:50 INFO - Downloaded 86251939 bytes. 09:36:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.dmg 09:36:50 INFO - mkdir: /builds/slave/test/properties 09:36:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:36:50 INFO - Writing to file /builds/slave/test/properties/build_url 09:36:50 INFO - Contents: 09:36:50 INFO - build_url:https://queue.taskcluster.net/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.dmg 09:36:51 INFO - Running post-action listener: _resource_record_post_action 09:36:51 INFO - Running post-action listener: set_extra_try_arguments 09:36:51 INFO - ##### 09:36:51 INFO - ##### Running populate-webroot step. 09:36:51 INFO - ##### 09:36:51 INFO - Running pre-action listener: _resource_record_pre_action 09:36:51 INFO - Running main action method: populate_webroot 09:36:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:36:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:36:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:36:51 INFO - '--tppagecycles', 09:36:51 INFO - '7'], 09:36:51 INFO - 'tests': ['tsvgm']}}, 09:36:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:36:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:36:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:36:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:36:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:36:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:36:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:51 INFO - 'talos_options': ['--disable-e10s'], 09:36:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:36:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:36:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:51 INFO - 'talos_options': ['--disable-e10s'], 09:36:51 INFO - 'tests': ['damp', 'tps']}, 09:36:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:51 INFO - 'tests': ['damp', 'tps']}, 09:36:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:36:51 INFO - 'tests': ['dromaeo_dom']}, 09:36:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:36:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:36:51 INFO - 'tests': ['a11yr', 09:36:51 INFO - 'ts_paint', 09:36:51 INFO - 'tpaint', 09:36:51 INFO - 'sessionrestore', 09:36:51 INFO - 'sessionrestore_no_auto_restore', 09:36:51 INFO - 'tabpaint']}, 09:36:51 INFO - 'other-e10s': {'tests': ['a11yr', 09:36:51 INFO - 'ts_paint', 09:36:51 INFO - 'tpaint', 09:36:51 INFO - 'sessionrestore', 09:36:51 INFO - 'sessionrestore_no_auto_restore', 09:36:51 INFO - 'tabpaint']}, 09:36:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:36:51 INFO - 'ts_paint', 09:36:51 INFO - 'tpaint', 09:36:51 INFO - 'sessionrestore', 09:36:51 INFO - 'sessionrestore_no_auto_restore', 09:36:51 INFO - 'tabpaint']}, 09:36:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:36:51 INFO - 'ts_paint', 09:36:51 INFO - 'tpaint', 09:36:51 INFO - 'sessionrestore', 09:36:51 INFO - 'sessionrestore_no_auto_restore', 09:36:51 INFO - 'tabpaint']}, 09:36:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:36:51 INFO - 'tests': ['a11yr', 09:36:51 INFO - 'ts_paint', 09:36:51 INFO - 'tpaint', 09:36:51 INFO - 'sessionrestore', 09:36:51 INFO - 'sessionrestore_no_auto_restore', 09:36:51 INFO - 'tabpaint']}, 09:36:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:36:51 INFO - 'tests': ['a11yr', 09:36:51 INFO - 'ts_paint', 09:36:51 INFO - 'tpaint', 09:36:51 INFO - 'sessionrestore', 09:36:51 INFO - 'sessionrestore_no_auto_restore', 09:36:51 INFO - 'tabpaint']}, 09:36:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:36:51 INFO - 'tests': ['tsvgx', 09:36:51 INFO - 'tsvgr_opacity', 09:36:51 INFO - 'tart', 09:36:51 INFO - 'tscrollx', 09:36:51 INFO - 'cart']}, 09:36:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:36:51 INFO - 'tsvgr_opacity', 09:36:51 INFO - 'tart', 09:36:51 INFO - 'tscrollx', 09:36:51 INFO - 'cart']}, 09:36:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:51 INFO - 'talos_options': ['--disable-e10s'], 09:36:51 INFO - 'tests': ['tp5o']}, 09:36:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:51 INFO - 'tests': ['tp5o']}, 09:36:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:51 INFO - 'talos_options': ['--disable-e10s', 09:36:51 INFO - '--xperf_path', 09:36:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:36:51 INFO - 'tests': ['tp5n']}, 09:36:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:51 INFO - 'talos_options': ['--xperf_path', 09:36:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:36:51 INFO - 'tests': ['tp5n']}}, 09:36:51 INFO - 'talos.zip': {'path': '', 09:36:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:36:51 INFO - Running post-action listener: _resource_record_post_action 09:36:51 INFO - ##### 09:36:51 INFO - ##### Running create-virtualenv step. 09:36:51 INFO - ##### 09:36:51 INFO - Running pre-action listener: _resource_record_pre_action 09:36:51 INFO - Running main action method: create_virtualenv 09:36:51 INFO - Creating virtualenv /builds/slave/test/build/venv 09:36:51 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 09:36:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:36:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:36:51 INFO - Using real prefix '/tools/python27' 09:36:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:36:51 INFO - Installing distribute.............................................................................................................................................................................................done. 09:36:54 INFO - Installing pip.................done. 09:36:54 INFO - Return code: 0 09:36:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:36:54 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')]} 09:36:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:54 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')]} 09:36:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:54 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 0x10a579a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a589420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe3055d300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a418e00>, '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 0x10a281db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a413b20>, '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.MgcVFumUzs/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.7aNTjd7kwc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 09:36:54 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 09:36:54 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 09:36:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MgcVFumUzs/Render', 09:36:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:54 INFO - 'HOME': '/Users/cltbld', 09:36:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:54 INFO - 'LOGNAME': 'cltbld', 09:36:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:54 INFO - 'MOZ_NO_REMOTE': '1', 09:36:54 INFO - 'NO_EM_RESTART': '1', 09:36:54 INFO - 'PAGER': '/bin/cat', 09:36:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:54 INFO - 'PWD': '/builds/slave/test', 09:36:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:54 INFO - 'SHELL': '/bin/bash', 09:36:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7aNTjd7kwc/Listeners', 09:36:54 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 09:36:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:54 INFO - 'USER': 'cltbld', 09:36:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:54 INFO - 'XPC_FLAGS': '0x0', 09:36:54 INFO - 'XPC_SERVICE_NAME': '0', 09:36:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:55 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 09:36:55 INFO - Cleaning up... 09:36:55 INFO - Return code: 0 09:36:55 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:36: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')]} 09:36:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36: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')]} 09:36:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:55 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 0x10a579a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a589420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe3055d300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a418e00>, '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 0x10a281db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a413b20>, '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.MgcVFumUzs/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.7aNTjd7kwc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 09:36:55 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 09:36:55 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 09:36:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MgcVFumUzs/Render', 09:36:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:55 INFO - 'HOME': '/Users/cltbld', 09:36:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:55 INFO - 'LOGNAME': 'cltbld', 09:36:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:55 INFO - 'MOZ_NO_REMOTE': '1', 09:36:55 INFO - 'NO_EM_RESTART': '1', 09:36:55 INFO - 'PAGER': '/bin/cat', 09:36:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:55 INFO - 'PWD': '/builds/slave/test', 09:36:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:55 INFO - 'SHELL': '/bin/bash', 09:36:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7aNTjd7kwc/Listeners', 09:36:55 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 09:36:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:55 INFO - 'USER': 'cltbld', 09:36:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:55 INFO - 'XPC_FLAGS': '0x0', 09:36:55 INFO - 'XPC_SERVICE_NAME': '0', 09:36:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:55 INFO - Downloading/unpacking psutil>=0.7.1 09:36:55 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 09:36:55 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 09:36:55 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 09:36:55 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 09:36:55 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 09:36:55 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 09:36:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:36:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:36:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:36:58 INFO - Installing collected packages: psutil 09:36:58 INFO - Running setup.py install for psutil 09:36:58 INFO - building 'psutil._psutil_osx' extension 09:36:58 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 09:36:58 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 09:36:58 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 09:36:59 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 09:36:59 INFO - building 'psutil._psutil_posix' extension 09:36:59 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 09:36:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:36:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:36:59 INFO - ^ 09:36:59 INFO - 1 warning generated. 09:36:59 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 09:36:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:36:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:36:59 INFO - Successfully installed psutil 09:36:59 INFO - Cleaning up... 09:36:59 INFO - Return code: 0 09:36:59 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:36: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')]} 09:36:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36: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')]} 09:36:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:59 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 0x10a579a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a589420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe3055d300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a418e00>, '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 0x10a281db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a413b20>, '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.MgcVFumUzs/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.7aNTjd7kwc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 09:36:59 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 09:36:59 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 09:36:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MgcVFumUzs/Render', 09:36:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:59 INFO - 'HOME': '/Users/cltbld', 09:36:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:59 INFO - 'LOGNAME': 'cltbld', 09:36:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:59 INFO - 'MOZ_NO_REMOTE': '1', 09:36:59 INFO - 'NO_EM_RESTART': '1', 09:36:59 INFO - 'PAGER': '/bin/cat', 09:36:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:59 INFO - 'PWD': '/builds/slave/test', 09:36:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:59 INFO - 'SHELL': '/bin/bash', 09:36:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7aNTjd7kwc/Listeners', 09:36:59 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 09:36:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:59 INFO - 'USER': 'cltbld', 09:36:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:59 INFO - 'XPC_FLAGS': '0x0', 09:36:59 INFO - 'XPC_SERVICE_NAME': '0', 09:36:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:59 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:36:59 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 09:36:59 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 09:36:59 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 09:36:59 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 09:36:59 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 09:36:59 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 09:37:02 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:37:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:37:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:37:02 INFO - Installing collected packages: mozsystemmonitor 09:37:02 INFO - Running setup.py install for mozsystemmonitor 09:37:02 INFO - Successfully installed mozsystemmonitor 09:37:02 INFO - Cleaning up... 09:37:02 INFO - Return code: 0 09:37:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:37:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a579a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a589420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe3055d300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a418e00>, '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 0x10a281db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a413b20>, '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.MgcVFumUzs/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.7aNTjd7kwc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 09:37:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:37:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:37:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MgcVFumUzs/Render', 09:37:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:02 INFO - 'HOME': '/Users/cltbld', 09:37:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:02 INFO - 'LOGNAME': 'cltbld', 09:37:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:02 INFO - 'MOZ_NO_REMOTE': '1', 09:37:02 INFO - 'NO_EM_RESTART': '1', 09:37:02 INFO - 'PAGER': '/bin/cat', 09:37:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:02 INFO - 'PWD': '/builds/slave/test', 09:37:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:02 INFO - 'SHELL': '/bin/bash', 09:37:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7aNTjd7kwc/Listeners', 09:37:02 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 09:37:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:02 INFO - 'USER': 'cltbld', 09:37:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:02 INFO - 'XPC_FLAGS': '0x0', 09:37:02 INFO - 'XPC_SERVICE_NAME': '0', 09:37:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:02 INFO - Downloading/unpacking blobuploader==1.2.4 09:37:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:05 INFO - Downloading blobuploader-1.2.4.tar.gz 09:37:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:37:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:37:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:37:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:37:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:06 INFO - Downloading docopt-0.6.1.tar.gz 09:37:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:37:06 INFO - Installing collected packages: blobuploader, requests, docopt 09:37:06 INFO - Running setup.py install for blobuploader 09:37:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:37:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:37:06 INFO - Running setup.py install for requests 09:37:07 INFO - Running setup.py install for docopt 09:37:07 INFO - Successfully installed blobuploader requests docopt 09:37:07 INFO - Cleaning up... 09:37:07 INFO - Return code: 0 09:37:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:37:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:07 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 0x10a579a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a589420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe3055d300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a418e00>, '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 0x10a281db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a413b20>, '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.MgcVFumUzs/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.7aNTjd7kwc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 09:37:07 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 09:37:07 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 09:37:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MgcVFumUzs/Render', 09:37:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:07 INFO - 'HOME': '/Users/cltbld', 09:37:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:07 INFO - 'LOGNAME': 'cltbld', 09:37:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:07 INFO - 'MOZ_NO_REMOTE': '1', 09:37:07 INFO - 'NO_EM_RESTART': '1', 09:37:07 INFO - 'PAGER': '/bin/cat', 09:37:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:07 INFO - 'PWD': '/builds/slave/test', 09:37:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:07 INFO - 'SHELL': '/bin/bash', 09:37:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7aNTjd7kwc/Listeners', 09:37:07 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 09:37:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:07 INFO - 'USER': 'cltbld', 09:37:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:07 INFO - 'XPC_FLAGS': '0x0', 09:37:07 INFO - 'XPC_SERVICE_NAME': '0', 09:37:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:37:07 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-bqLKhL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:37:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:37:07 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-TtlkMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:37:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:37:07 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-YuK4uq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:37:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:37:08 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-tmYKXA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:37:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:37:08 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-zo_fr7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:37:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:37:08 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-fiVVSB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:37:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:37:08 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-PApQ8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:37:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:37:08 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-uAEiDp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:37:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:37:08 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-z6KIcI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:37:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:37:08 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-mXqAgG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:37:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:37:08 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-dvYfxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:37:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:37:08 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-wKY_x0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:37:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:37:08 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-isd4AH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:37:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:37:09 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-T2cqZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:37:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:09 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-VJd86J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:37:09 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-Btic2h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:37:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:37:09 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-kP3EPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:37:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:37:09 INFO - Running setup.py install for manifestparser 09:37:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:37:09 INFO - Running setup.py install for mozcrash 09:37:09 INFO - Running setup.py install for mozdebug 09:37:09 INFO - Running setup.py install for mozdevice 09:37:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:37:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:37:10 INFO - Running setup.py install for mozfile 09:37:10 INFO - Running setup.py install for mozhttpd 09:37:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:37:10 INFO - Running setup.py install for mozinfo 09:37:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:37:10 INFO - Running setup.py install for mozInstall 09:37:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:37:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:37:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:37:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:37:10 INFO - Running setup.py install for mozleak 09:37:10 INFO - Running setup.py install for mozlog 09:37:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:37:10 INFO - Running setup.py install for moznetwork 09:37:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:37:11 INFO - Running setup.py install for mozprocess 09:37:11 INFO - Running setup.py install for mozprofile 09:37:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:37:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:37:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:37:11 INFO - Running setup.py install for mozrunner 09:37:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:37:11 INFO - Running setup.py install for mozscreenshot 09:37:11 INFO - Running setup.py install for moztest 09:37:11 INFO - Running setup.py install for mozversion 09:37:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:37:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:37:12 INFO - Cleaning up... 09:37:12 INFO - Return code: 0 09:37:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:37:12 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')]} 09:37:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:12 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')]} 09:37:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:12 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 0x10a579a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a589420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe3055d300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a418e00>, '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 0x10a281db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a413b20>, '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.MgcVFumUzs/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.7aNTjd7kwc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 09:37:12 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 09:37:12 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 09:37:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MgcVFumUzs/Render', 09:37:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:12 INFO - 'HOME': '/Users/cltbld', 09:37:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:12 INFO - 'LOGNAME': 'cltbld', 09:37:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:12 INFO - 'MOZ_NO_REMOTE': '1', 09:37:12 INFO - 'NO_EM_RESTART': '1', 09:37:12 INFO - 'PAGER': '/bin/cat', 09:37:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:12 INFO - 'PWD': '/builds/slave/test', 09:37:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:12 INFO - 'SHELL': '/bin/bash', 09:37:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7aNTjd7kwc/Listeners', 09:37:12 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 09:37:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:12 INFO - 'USER': 'cltbld', 09:37:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:12 INFO - 'XPC_FLAGS': '0x0', 09:37:12 INFO - 'XPC_SERVICE_NAME': '0', 09:37:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:37:12 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-hPvhIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:37:12 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)) 09:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:37:12 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-arKRk8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:37:12 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)) 09:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:37:12 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-9Qlp8Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:37:12 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)) 09:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:37:12 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-WACiWm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:37:12 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)) 09:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:37:12 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-h4MBg1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:37:12 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)) 09:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:37:12 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-gnZUbH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:37:12 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)) 09:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:37:12 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-IMDaIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:37:13 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)) 09:37:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:37:13 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-fJCqU0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:37:13 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)) 09:37:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:37:13 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-IhL7OQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:37:13 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)) 09:37:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:37:13 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-1tMsRp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:37:13 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)) 09:37:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:37:13 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-mQv3q0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:37:13 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)) 09:37:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:37:13 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-MOlUAC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:37:13 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)) 09:37:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:37:13 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-DA37th-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:37:13 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)) 09:37:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:37:13 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-QntRly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:37:13 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)) 09:37:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:13 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-60LM3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:13 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)) 09:37:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:37:13 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-_lVyp1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:37:14 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)) 09:37:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:37:14 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-FXxTFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:37:14 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)) 09:37:14 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)) 09:37:14 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)) 09:37:14 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)) 09:37:14 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)) 09:37:14 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)) 09:37:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:37:14 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 09:37:14 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 09:37:14 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 09:37:14 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 09:37:14 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 09:37:14 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 09:37:16 INFO - Downloading blessings-1.6.tar.gz 09:37:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:37:16 INFO - Installing collected packages: blessings 09:37:16 INFO - Running setup.py install for blessings 09:37:16 INFO - Successfully installed blessings 09:37:16 INFO - Cleaning up... 09:37:16 INFO - Return code: 0 09:37:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:37:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:37:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:37:17 INFO - Reading from file tmpfile_stdout 09:37:17 INFO - Current package versions: 09:37:17 INFO - blessings == 1.6 09:37:17 INFO - blobuploader == 1.2.4 09:37:17 INFO - docopt == 0.6.1 09:37:17 INFO - manifestparser == 1.1 09:37:17 INFO - mozInstall == 1.12 09:37:17 INFO - mozcrash == 0.17 09:37:17 INFO - mozdebug == 0.1 09:37:17 INFO - mozdevice == 0.48 09:37:17 INFO - mozfile == 1.2 09:37:17 INFO - mozhttpd == 0.7 09:37:17 INFO - mozinfo == 0.9 09:37:17 INFO - mozleak == 0.1 09:37:17 INFO - mozlog == 3.1 09:37:17 INFO - moznetwork == 0.27 09:37:17 INFO - mozprocess == 0.22 09:37:17 INFO - mozprofile == 0.28 09:37:17 INFO - mozrunner == 6.11 09:37:17 INFO - mozscreenshot == 0.1 09:37:17 INFO - mozsystemmonitor == 0.0 09:37:17 INFO - moztest == 0.7 09:37:17 INFO - mozversion == 1.4 09:37:17 INFO - psutil == 3.1.1 09:37:17 INFO - requests == 1.2.3 09:37:17 INFO - wsgiref == 0.1.2 09:37:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:37: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')]} 09:37:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37: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')]} 09:37:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:17 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 0x10a579a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a589420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe3055d300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a418e00>, '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 0x10a281db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a413b20>, '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.MgcVFumUzs/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.7aNTjd7kwc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 09:37:17 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 09:37:17 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 09:37:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MgcVFumUzs/Render', 09:37:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:17 INFO - 'HOME': '/Users/cltbld', 09:37:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:17 INFO - 'LOGNAME': 'cltbld', 09:37:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:17 INFO - 'MOZ_NO_REMOTE': '1', 09:37:17 INFO - 'NO_EM_RESTART': '1', 09:37:17 INFO - 'PAGER': '/bin/cat', 09:37:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:17 INFO - 'PWD': '/builds/slave/test', 09:37:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:17 INFO - 'SHELL': '/bin/bash', 09:37:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7aNTjd7kwc/Listeners', 09:37:17 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 09:37:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:17 INFO - 'USER': 'cltbld', 09:37:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:17 INFO - 'XPC_FLAGS': '0x0', 09:37:17 INFO - 'XPC_SERVICE_NAME': '0', 09:37:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:17 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)) 09:37:17 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)) 09:37:17 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)) 09:37:17 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)) 09:37:17 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)) 09:37:17 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)) 09:37:17 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)) 09:37:17 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)) 09:37:17 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)) 09:37:17 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)) 09:37:17 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)) 09:37:17 INFO - Cleaning up... 09:37:17 INFO - Return code: 0 09:37:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:37: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')]} 09:37:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37: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')]} 09:37:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a579a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a589420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe3055d300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a418e00>, '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 0x10a281db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a413b20>, '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.MgcVFumUzs/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.7aNTjd7kwc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 09:37: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', 'jsonschema'] in /builds/slave/test/build 09:37: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 jsonschema 09:37:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MgcVFumUzs/Render', 09:37:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:17 INFO - 'HOME': '/Users/cltbld', 09:37:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:17 INFO - 'LOGNAME': 'cltbld', 09:37:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:17 INFO - 'MOZ_NO_REMOTE': '1', 09:37:17 INFO - 'NO_EM_RESTART': '1', 09:37:17 INFO - 'PAGER': '/bin/cat', 09:37:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:17 INFO - 'PWD': '/builds/slave/test', 09:37:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:17 INFO - 'SHELL': '/bin/bash', 09:37:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7aNTjd7kwc/Listeners', 09:37:17 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 09:37:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:17 INFO - 'USER': 'cltbld', 09:37:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:17 INFO - 'XPC_FLAGS': '0x0', 09:37:17 INFO - 'XPC_SERVICE_NAME': '0', 09:37:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:17 INFO - Downloading/unpacking jsonschema 09:37: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 09:37: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 09:37: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 09:37: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 09:37: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 09:37: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 09:37:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:37:20 INFO - Downloading/unpacking functools32 (from jsonschema) 09:37: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 09:37: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 09:37: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 09:37: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 09:37: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 09:37: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 09:37:20 INFO - Downloading functools32-3.2.3-2.tar.gz 09:37:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:37:20 INFO - warning: no files found matching '*.txt' 09:37:20 INFO - no previously-included directories found matching 'build' 09:37:20 INFO - no previously-included directories found matching 'dist' 09:37:20 INFO - no previously-included directories found matching '.git*' 09:37:20 INFO - Installing collected packages: jsonschema, functools32 09:37:20 INFO - Running setup.py install for functools32 09:37:20 INFO - warning: no files found matching '*.txt' 09:37:20 INFO - no previously-included directories found matching 'build' 09:37:20 INFO - no previously-included directories found matching 'dist' 09:37:20 INFO - no previously-included directories found matching '.git*' 09:37:20 INFO - Successfully installed jsonschema functools32 09:37:20 INFO - Cleaning up... 09:37:20 INFO - Return code: 0 09:37:20 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:37: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')]} 09:37:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a579a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a589420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe3055d300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a418e00>, '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 0x10a281db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a413b20>, '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.MgcVFumUzs/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.7aNTjd7kwc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 09:37:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 09:37:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:37:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MgcVFumUzs/Render', 09:37:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:21 INFO - 'HOME': '/Users/cltbld', 09:37:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:21 INFO - 'LOGNAME': 'cltbld', 09:37:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:21 INFO - 'MOZ_NO_REMOTE': '1', 09:37:21 INFO - 'NO_EM_RESTART': '1', 09:37:21 INFO - 'PAGER': '/bin/cat', 09:37:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:21 INFO - 'PWD': '/builds/slave/test', 09:37:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:21 INFO - 'SHELL': '/bin/bash', 09:37:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7aNTjd7kwc/Listeners', 09:37:21 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 09:37:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:21 INFO - 'USER': 'cltbld', 09:37:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:21 INFO - 'XPC_FLAGS': '0x0', 09:37:21 INFO - 'XPC_SERVICE_NAME': '0', 09:37:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:21 INFO - Downloading/unpacking flake8 09:37:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:24 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:37:24 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:37:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:24 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:37:24 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:37:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:25 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:37:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:37:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:37:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:37:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:37:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:37:25 INFO - no previously-included directories found matching 'docs/_build' 09:37:25 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:37:25 INFO - Running setup.py install for pep8 09:37:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:37:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:37:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:37:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:37:26 INFO - no previously-included directories found matching 'docs/_build' 09:37:26 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:37:26 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:37:26 INFO - Cleaning up... 09:37:26 INFO - Return code: 0 09:37:26 INFO - Running post-action listener: _resource_record_post_action 09:37:26 INFO - Running post-action listener: _start_resource_monitoring 09:37:26 INFO - Starting resource monitoring. 09:37:26 INFO - ##### 09:37:26 INFO - ##### Running install step. 09:37:26 INFO - ##### 09:37:26 INFO - Running pre-action listener: _resource_record_pre_action 09:37:26 INFO - Running main action method: install 09:37:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:37:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:37:26 INFO - Reading from file tmpfile_stdout 09:37:26 INFO - Detecting whether we're running mozinstall >=1.0... 09:37:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:37:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:37:26 INFO - Reading from file tmpfile_stdout 09:37:26 INFO - Output received: 09:37:26 INFO - Usage: mozinstall [options] installer 09:37:26 INFO - Options: 09:37:26 INFO - -h, --help show this help message and exit 09:37:26 INFO - -d DEST, --destination=DEST 09:37:26 INFO - Directory to install application into. [default: 09:37:26 INFO - "/builds/slave/test"] 09:37:26 INFO - --app=APP Application being installed. [default: firefox] 09:37:26 INFO - mkdir: /builds/slave/test/build/application 09:37:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:37:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 09:37:48 INFO - Reading from file tmpfile_stdout 09:37:48 INFO - Output received: 09:37:48 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 09:37:48 INFO - Running post-action listener: _resource_record_post_action 09:37:48 INFO - ##### 09:37:48 INFO - ##### Running run-tests step. 09:37:48 INFO - ##### 09:37:48 INFO - Running pre-action listener: _resource_record_pre_action 09:37:48 INFO - Running main action method: run_tests 09:37:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:37:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:37:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:37:48 INFO - 2016-06-23 09:37:48.769 screenresolution[1640:7536] starting screenresolution argv=screenresolution get 09:37:48 INFO - 2016-06-23 09:37:48.771 screenresolution[1640:7536] Display 0: 1600x1200x32@60 09:37:48 INFO - 2016-06-23 09:37:48.777 screenresolution[1641:7538] starting screenresolution argv=screenresolution list 09:37:48 INFO - Available Modes on Display 0 09:37:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:37:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:37:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:37:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:37:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:37:49 INFO - Intel Iris: 09:37:49 INFO - Chipset Model: Intel Iris 09:37:49 INFO - Type: GPU 09:37:49 INFO - Bus: Built-In 09:37:49 INFO - VRAM (Dynamic, Max): 1536 MB 09:37:49 INFO - Vendor: Intel (0x8086) 09:37:49 INFO - Device ID: 0x0a2e 09:37:49 INFO - Revision ID: 0x0009 09:37:49 INFO - Displays: 09:37:49 INFO - Display: 09:37:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:37:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:37:49 INFO - Display Serial Number: 1600x1200 60 09:37:49 INFO - Main Display: Yes 09:37:49 INFO - Mirror: Off 09:37:49 INFO - Online: Yes 09:37:49 INFO - Rotation: Supported 09:37:49 INFO - Return code: 0 09:37:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:37:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:37:49 INFO - Python 2.7.3 09:37:49 INFO - Return code: 0 09:37:49 INFO - grabbing minidump binary from tooltool 09:37:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:49 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 0x10a418e00>, '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 0x10a281db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a413b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:37:49 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 09:37:49 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 09:37:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:37:49 INFO - Return code: 0 09:37:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:37:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:37:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:37:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:37:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:37:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:37:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:37:50 INFO - Return code: 0 09:37:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0210', '--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 09:37:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0210 --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 09:37:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MgcVFumUzs/Render', 09:37:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:50 INFO - 'HOME': '/Users/cltbld', 09:37:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:50 INFO - 'LOGNAME': 'cltbld', 09:37:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:37:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:37:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:50 INFO - 'MOZ_NO_REMOTE': '1', 09:37:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:37:50 INFO - 'NO_EM_RESTART': '1', 09:37:50 INFO - 'PAGER': '/bin/cat', 09:37:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:50 INFO - 'PWD': '/builds/slave/test', 09:37:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:37:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:50 INFO - 'SHELL': '/bin/bash', 09:37:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7aNTjd7kwc/Listeners', 09:37:50 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 09:37:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:50 INFO - 'USER': 'cltbld', 09:37:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:50 INFO - 'XPC_FLAGS': '0x0', 09:37:50 INFO - 'XPC_SERVICE_NAME': '0', 09:37:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0210', '--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 09:37:50 INFO - mozversion application_buildid: 20160623073824 09:37:50 INFO - mozversion application_changeset: 77a913855173eb131614d3ff2325efdece6582a4 09:37:50 INFO - mozversion application_display_name: Firefox 09:37:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:37:50 INFO - mozversion application_name: Firefox 09:37:50 INFO - mozversion application_remotingname: firefox 09:37:50 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:37:50 INFO - mozversion application_vendor: Mozilla 09:37:50 INFO - mozversion application_version: 48.0 09:37:50 INFO - mozversion platform_buildid: 20160623073824 09:37:50 INFO - mozversion platform_changeset: 77a913855173eb131614d3ff2325efdece6582a4 09:37:50 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:37:50 INFO - mozversion platform_version: 48.0 09:37:50 INFO - using testdate: 1466699870 09:37:50 INFO - actual date: 1466699870 09:37:50 INFO - starting webserver on 'localhost:49270' 09:37:50 INFO - SUITE-START | Running 2 tests 09:37:50 INFO - TEST-START | dromaeo_css 09:37:50 INFO - operating with platform_type : mac_ 09:37:50 INFO - Initialising browser for dromaeo_css test... 09:37:50 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/tmp_LutR7/profile http://localhost:49270/getInfo.html) 09:37:52 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 09:37:52 INFO - PROCESS | 1659 | colorDepth:24 09:37:52 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 09:37:52 INFO - PROCESS | 1659 | __metrics 09:37:52 INFO - PROCESS | 1659 | 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" 09:37:52 ERROR - PROCESS | 1659 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:37:53 INFO - TEST-INFO | 1659: exit 0 09:37:53 INFO - Browser initialized. 09:37:53 INFO - Running cycle 1/1 for dromaeo_css test... 09:37:53 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/tmp_LutR7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:38:05 INFO - PROCESS | 1662 | RSS: Main: 161062912 09:38:05 INFO - PROCESS | 1662 | 09:38:56 INFO - PROCESS | 1662 | 1466699936663 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] 09:41:07 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 09:41:08 INFO - PROCESS | 1662 | RSS: Main: 275845120 09:41:08 INFO - PROCESS | 1662 | 09:44:10 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 09:44:11 INFO - PROCESS | 1662 | RSS: Main: 307425280 09:44:11 INFO - PROCESS | 1662 | 09:47:13 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 09:47:14 INFO - PROCESS | 1662 | RSS: Main: 305225728 09:47:14 INFO - PROCESS | 1662 | 09:50:16 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 09:50:16 INFO - PROCESS | 1662 | RSS: Main: 312655872 09:50:16 INFO - PROCESS | 1662 | 09:53:19 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 09:53:20 INFO - PROCESS | 1662 | RSS: Main: 270622720 09:53:20 INFO - PROCESS | 1662 | 09:56:23 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 09:56:23 INFO - PROCESS | 1662 | RSS: Main: 312193024 09:56:23 INFO - PROCESS | 1662 | 09:56:23 INFO - PROCESS | 1662 | __start_tp_report 09:56:23 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 09:56:23 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 09:56:23 INFO - PROCESS | 1662 | |i|pagename|runs| 09:56:23 INFO - PROCESS | 1662 | |0;dojo.html;3588.6770190682837;3639.799811010394;3652.689521390682;3658.304922064809;3726.9937116978417;5974.6713930733795;5996.280446346439;5996.400359964002;6025.427584379488;6051.152838602594;6015.969920150393;6030.517558595313;6039.305479869817;6042.184305118808;6053.122297266894;5309.389420216667;5353.99999999999;5434.864128396802;5448.42791506892;5488.670679759207;5443.020256353855;5475.561955043587;5482.095454250044;5482.150266708647;5486.3690675572425;5385.488378604026;5440.755166017539;5463.890722185554;5468.91796623051;5483.287172667548;5456.345238571377;5457.699826509541;5483.177523371486;5495.917561236584;5497.367802702701;5291.1534154535475;5330.893382132374;5378.91931621026;5381.381141168777;5422.728863556807;5755.971220143914;5839.299284085894;5853.1220316952795;5888.870035632658;5912.408759124096;6259.967507787673;6279.277883043417;6286.779962701284;6295.716692748837;6304.117423560705;6206.193194884681;6246.406591373858;6305.747770089191;6306.211723534559;6322.557420980534;6029.186059881904;6114.174586430864;6119.479844213202;6128.900367973393;6136.386361363917;6381.1066450696935;6400.647964361911;6419.582727122722;6420.940544810054;6424.486041116699;6215.875682486325;6289.899267628147;6303.873922521616;6320.525960552977;6326.082718005863;6299.307076221612;6316.084167795736;6320.494360451153;6321.841953951096;6334.778282760173;6205.193324867739;6226.688665566703;6255.812325630239;6273.341299163613;6288.434040936338;6199.907001394983;6252.155958945576;6253.499720022387;6255.12428260044;6268.780592679234;4998.375203099613;5031.270465782514;5042.773075211624;5046.419661738873;5066.392032956069;4569.109023740339;4593;4605.746683932416;4631.976840115845;4659.603933665609;5995.430434108753;6005.549583781147;6009.248843894513;6022.819315420545;6023.969880150659;6189.257289125334;6263.404976527223;6300.495960323163;6308.400701933309;6318.7472501100365;5933.732982015819;5935.228420305332;6021.879562408727;6044.214252147192;6083.330833608299;5479.616426850162;5503.972480137654;5538.972305138448;5542.75057622408;5545.195946587723;5518.806841760519;6552.574082684706;6559.376859198369;6564.310747371553;6589.703463344161;6268.153799237045;6316.778912738032;6332.746690132343;6335.936640633719;6340.682965851781;6558.868822623616;6612.735490580323;6621.602703837785;6626.337366263528;6637.070810086495;6161.106639537332;6269.592476489013;6274.247090349188;6282.089097080989;6297.496200303873;6133.793750436688;6170.321264660974;6244.687765611792;6263.906041409291;6325.430711236011;6189.783357582463;6196.1635179252025;6202;6214.4407003369915;6218.720157592828;6151.108089327112;6189.133521306931;6206.193194884636;6211.9068213975925;6213.2233470816145;6063.5452341073715;6143.511877689543;6146.8935591593945;6232.314445411092;6234.376562343729;6200.162977997912;6212.471939884979;6213.751449941951;6217.067439884054;6228.439440450381;6109.009944784373;6109.894485367275;6208.844778880564;6216.471599914058;6228.501719862491 09:56:23 INFO - PROCESS | 1662 | |1;ext.html;532.5978885941114;551.0411883323018;552.6628756458562;584.4798129664599;584.9587733833774;32141.67858321416;38595.22809543808;39627;39627.4149034039;39799.20401591966;42499.36250956238;42974.14051718964;43004.924876878096;43021;43164.56835431645;8983.865242021342;9563.665271715492;9820.11618954294;9831.65589204378;9841.852372214422;11771.293722376684;12038.57864974726;12057.758844823098;12069.275843449377;12073.758524829498;11718.121140914423;11796.2332448391;11801.409929503534;11801.940990295037;11830.7633847323;11673.883261167364;11818.468168932419;11847.82228266568;11861.406929653525;11871.931526162687;11379.772404551863;11411.54353825846;11451.083913286917;11509.5971640993;11650.359230242333;41349.79325103385;41780;41801.790991045156;41900;41919.161616767495;3268.2809781847955;3320.186554294189;3347.313800670857;3405.4381027130494;3912.628300311466;28467.715322846714;28584;28803.99186028479;30123.096307110824;30135.794568217247;2747.6840163381266;2763.599278104684;2798.272449163212;2861.756750676195;2877.2662970942274;494032;495740;495751;495765.52117239544;495987;38262;38604.8418547444;38670.03324916843;38771.612283877366;60970.78058438806;37526.81236593799;37759.62240377561;37851.62148378536;37868.810655946545;38007.61992380096;15549.922250388901;15724.60688482797;15739.527814165593;15749.763753543706;15750.133742644264;12044.87955120455;12138.817917731241;12163.452644631012;13130.540431084866;13172.143810652276;8299.336053115736;8342.16578342161;8351.457155284887;8376.581170941428;8415.200555947298;3504.8422820972605;3532.6997205237844;3549.1304630365726;3557.8043207623828;3579.964200358015;984.7981163861391;995.9103680668775;1003.8042581696645;1028.0798685176726;1087.0868470484832;29354.85322573416;29517.85241073824;29652;30250.54624180639;30264.54603180954;1083.7940791249637;1135.8523391959159;1152.504423098076;1202.2846406388185;1247.2516490105863;14185.574432766827;14387.352569134415;14672.559823205322;14720;14957.70084598302;646380.5361946415;652078;655356;656958;657973;58095;58738;58824.41175588275;58831.411685883446;58961;1352.5536572931105;1465.7801329800616;1507;1526.511516314791;1598.5044636162827;28463.573046404734;28643.570346444405;29080.418391632473;29094;29230.415391691622;50672;51618.22572661338;61957.69021154865;62130;62142.378576215466;501168;617267;618354;619808;620221;36997.260054798215;42370.364444533974;43646.78176609224;43903.121937560434;45296.773516132205;43026.35460468033;43971;44010.33984490171;44405.33391999187;45631;36750.44874326834;36810.447843282906;36861.63138368582;37169.4424583626;37330;36063.19338428768;36240.912772616866;36555.45166822553;36711.449328259565;37205.813970931056;37697.43453848249;38184;38288.80855595704;38482.653107141115;38894.4165837507;32999.3500324978;33136.66863331432;33208.33583328368;33644.66355336415;33955.3208935815;25059.87470062638;25285.852011329505;25521.385295235017;25554.488910222062;26082.087126950464 09:56:23 INFO - PROCESS | 1662 | |2;jquery.html;358.4104496557766;411.13045907904774;411.804392913366;412.43496409918396;425.8190269135617;15440.999999999973;21042.158313667453;21675.132994680214;21746.130154793827;21813.890930545345;21345.466363340925;21349.252776152836;21518.246861359818;21585.56828863422;21614.783852161512;5099.439061703229;5817.1565123057135;6141.692915354237;6154.169187159743;6161.168242287279;6015.969920150415;6075.787347442841;6118.051701986177;6126.081087836816;6134.754609815602;4423.137488189805;6399.680015999182;6715.932840671604;6720.260771315151;6741.595504269735;6433.903491447632;6686.398224159825;6687.732490700366;6689.866202675944;6723.966380168117;5898.321693005273;5933.821985340447;5939.168516407706;5939.316978547479;5947.762089516457;12858.807117893239;13471.326433678278;13480.5281815136;13491.797623035662;25789.484210315877;2196.9450763730874;2280.726312842453;2297;2797.782964410488;2890;9737.2697047721;10038.447885366302;10331.708536432945;12401.689957751038;14581.979261451703;1798.1099355818822;1809.3034181839955;1829.9817001830077;1856.4152292027968;1930.3919265431484;111485;112768.99999999919;114309.85690143076;118233.22650160255;121538.99999999911;11677.941610291895;11721.765564688829;11783.469743861548;11804.763904721858;11898.524059037714;13878;14461.349239284258;14485.493007744679;15416.920815543028;15451;7669.884951725729;8006.919930800734;8440.957795211107;8598.699045533376;8763.298936085226;8449.831003379897;8744.825103497895;8756.124387561193;8812.118788121265;8886.644534218689;4646.674732768742;4706.599939005223;4707.882302942386;4709.811607535728;4719.480857105716;1777.5589220712723;1917.6356492266425;2029.9492512687007;2030.9086091125935;2079.5632917087582;889.5685592487768;979.779549601334;1014.7513859104566;1065.648336049102;1110.134095405585;20165.596688066158;20998.80023995207;21193.470163245733;21208.893955530122;21412;966.6423423333412;976.6679329028022;984.2913102566141;1040.453761775059;1061.888501707325;10103.090721834924;11229.034918889161;11244.718882027852;11284.379359135162;11319.886801132048;630552;635291;642499;648848;649459;119453;120671.39664301623;125048;126018.36990814988;129375;1002.8746406699163;1024.6157690865925;1036.4713995862164;1040.0639424517994;1041.2815157541272;18182.09089545491;18318.81681183171;18426.72359914629;18455;19135.04324783783;53466.46533534615;53667.731661341444;55822;55994.880102396906;58256;63135.6843215781;79456.60271698605;79925.60037199777;80997.59501202693;85698.57150714456;8392.370572206986;8598.355123365898;8606.182412670787;8614.052454229904;8615.569221538773;10306;12665;12912.418941147484;13196.14225075367;13347.799783003069;11028.0074793269;11085.778284434427;11285.498134896618;11333.150013748836;11419.37193454368;10750.677479675622;11198.048165905664;11200.10399168081;11242.269252498889;11351.943240283746;9700.27247956392;11204.943975280072;11599.304041757521;11836.881631183922;11970.820437693268;8301.29438997692;8422.915770842214;8544.01743799471;8552.358573106918;8602.967643882774;10808.675739727822;11539.538418463168;11724.00691882365;11724.334003362039;11913.642590722293 09:56:23 INFO - PROCESS | 1662 | |3;mootools.html;1423.4377420918731;1601.2474137155539;1611.677664467106;1867.2904296367378;1966.9016549172538;8205.75382738518;8775.25410340121;8884.156005179497;8886.64453421863;8889.466632002084;8604.698835540757;8860.936687597481;8867.157620026088;8875.423097498675;8875.733727988154;2512.9120480783176;2537.0232460502657;2537.8731063446844;2538.860362680052;2543.326018605071;2473.9010439582485;2538.5557527432625;2545.185540627;2546.2997675639162;2553.6807899012624;2508.3352911478473;2535.5816290312077;2555.0035486160414;2556.7954563634958;2558.756918092778;2503.236512863576;2532.987335063322;2540.6697129373156;2549.5665736824785;2554.3741783263217;2486.962695559568;2510.598304271308;2517.4587463695393;2518.4711210645787;2537.695476542827;8773.429727067783;8967.417117887317;9014.323925705598;9024.413413128126;9036.50299233542;5568.470995255444;5644.209810626516;5650.491455769001;5708.543316534668;5710.229119068914;3390.915227119317;3573.9642603573893;3635.4365073413665;3653.94519082214;3705.8517659293598;1038.8426024593261;1051.0803047333584;1068.0707784227693;1070.7537266428687;1073.9946300268527;11987.041036717043;12301.200421972633;12304.876951230552;12335.568255111119;12340.259584424874;4037.6769858411258;4677.766111694402;4736.360591320196;4820.590249828804;4829.710217386898;9605.18355939739;9612.134907858326;9623.663171788954;9643.566039528097;9656.75858103553;5448.618596698193;5509.090999985037;5519.172124181325;5525.502704756591;5551.666899986014;4566.406367172246;4670.719756814602;4714.976425117852;4724.338592597039;5133.589312854962;3343.9800860737223;3934.1934903344745;4043.534993475729;4048.008237981713;4054.918901621951;1238.8203710462112;1242.2546472116658;1244.6514975806788;1252.1610520950985;1254.5232811531741;940.2759874896292;964.8986856380119;984.2863923655435;993.9503024848729;1006.620929326827;5474.562035037187;5931.228940237827;5954.166416701665;6105.694715264219;6108.3280839107665;852;923.9328575495314;982.8083523712951;995.6863587969766;997.2869398380193;4007.499062617173;4059.1475790083823;4074.959250407458;4078.1232035112594;4232.386303985966;10739.731506712238;11235.438228088562;11246.606368777217;11248;11274.041706455044;10958.890411095947;10973.451327433597;11030.117590592734;11076.501557429934;11113.666590002313;8480.10958849312;8496.1079086695;8501.06488286288;8582.013068497077;8619.655213791504;5336.279602253646;5698.914516282342;5964.045752679551;6041.939580604314;6060.848478787889;9873.40759554429;9956.651517196864;10070.04334588213;10083.697489075339;10132.189424846141;9204.171624553554;10088.092071713581;10361.481925903825;10384.636537721144;10493.317934334244;7265.491415600857;7287.198408174991;7297.890531642136;7301.963490182515;7372.189059203591;8434.198751118634;8523.82952340962;8548.91451085506;8573.571321434029;8576.914230857612;7637.847243054996;7725.652345644347;7766.922330776846;7876.645550950336;7905.367570594223;7599.696012159452;7604.809879752829;7607.961960190164;7632.19861914491;7739.3421559165845;7202.243764404871;7213.062301900719;7249.275072492708;7254.746083887038;7259.20148783644;5020.974895125501;5105.897882042264;5139.897202055863;5243.501867322599;5247.501487358695;5130.769115389742;5212.895742085213;5245.423003469539;5267.499587539178;5443.537299329601 09:56:23 INFO - PROCESS | 1662 | |4;prototype.html;332.7920049968769;336.97978121312724;342.0559256452192;348.08453766593857;351.86277351832774;6933.30666933307;7000.964995175017;7007.054047703565;7031.402330801881;7065.187503437106;6941.54879932804;6987.965060174692;7004.929950700492;7006.2293147753835;7050.6122163281;4964.503549645034;4965.280034395028;5082.796688132489;5146.845594632148;5163.767630456619;4127.752334859918;4853.369062021932;5107.668001579904;5149.304843846089;5167.379914410257;4179.331306990882;5117.795288188487;5134.4095429025765;5142.640015198937;5152.407473140599;4898.632602554805;4998.625103117281;5125.436202017775;5141.974290128563;5146.588272938156;4385.758783266919;4389.626881715058;4392.516823149451;4396.988692600719;4399.604035636776;6689.531732778707;6699.497537684693;6722.495812814059;6722.563033402862;6744.932550674529;5947.821565353046;5954.55340849438;5958.40415958405;5958.702064896781;5964.494912136913;6607.966960165217;6799.912014077773;7434.7769566913075;7438.73964411243;7441.181470038291;4950.925736113961;4985;4985.925211121836;4993.375828021497;5004.599632029429;7823.2176782321885;7903.802404939865;7904.80237994049;7905;7912.367010639193;5057.443681195065;5071.442141364447;5078.415982162025;5078.466760990117;5078.517540833689;5157.097507936096;5166.664166829144;5169.612279079084;5183.844484665466;5190.6107041971245;5835.708214589254;5978.312494063239;6042.365551617105;6042.939570604326;6046.606970546901;6016.2479690038745;7346.63266836656;7414.777556673308;7444.739434119888;7455.552666840007;1767.3990843113147;1794.2464165050449;1796.2545543599522;1796.9191386387645;1797.334986055168;1078.827387617993;1137.9146564007733;1144.3362849547243;1170.1399471388522;1172.7478592102568;6777.152855893013;6789.626570538668;6807.353301436356;6911.136107986502;7047.224805271416;7782.377409807202;7785.104712957969;7786.299233069051;7811.531308121531;7816.921830781733;5783.872144931782;7028.1917579477995;7035;7112.751053713096;7112.751053713198;3971.6028397160053;4094.8976275592754;4129.587041295907;4135;4146.004958809925;8152.4293299469655;8186.140455252232;8187.345012398994;8205.425620206528;8226.218509241613;8074.313683336982;8076.515409075474;8084.555349455719;8089.676412943534;8091.433599647969;8203.466774659628;8214.342852571779;8214.342852571779;8215.753527394188;8227.794305142299;7530.322270995636;7810.258025487569;7832.647530861011;7834.6866125354345;7847.254510821463;7583.469157158946;7621.428392870668;7636.694532218649;7645.5794931279315;7651.081870175614;6718.428933540508;6922.93077069243;7759.797231429137;8148.185181481805;8154.103048664523;4832.903341933212;4863.051704917649;4873.975630121827;4875.756212189447;4894.877628059327;6498.220213574401;7439.851202975802;7666.233376662289;7700.460967732205;7775.028121484815;7351.522151060164;7421.703131874665;7457.216992215956;7457.888131677921;7477.40180785525;7440.921066445443;7447.180810110992;7451.701931922879;7467.58928258951;7472.663730132048;5966.701664916824;7035.437165026683;7280.271972802678;7317.304856038668;7318.597477138808;7463;7512.474126811071;7514.323710866049;7537.283958023979;7551.056117985252;5929.318128415288;7069.681864316015;7248.130224373109;7283.635818209174;7292.41660667156 09:56:23 INFO - PROCESS | 1662 | |5;yui.html;688.8932215506596;755.4673954861823;773.6789232468523;780.0950896959528;817.8078151634367;15642.217889105543;15791.131487768169;15802;15828.287727052282;15893.761593576106;14462.204578748164;14563.271836408163;14567.053141545792;16477.588060298498;16757.581060473494;777.199484530934;803.7749430159536;966.5264020629876;986.8717066781309;987.93578417403;972.1785091597624;983.9803203935917;985.8373368394207;986.4673076538661;986.8815742110957;832.1013305629905;1012.6810054832741;1035.1563475767264;1035.430513217731;1038.2420832792066;1025.8666373371489;1028.5628607841638;1030.6083688198512;1031.3141760729068;1031.3450958641242;762.3862790453683;763.7823220382164;766;766.0883548577231;766.9731559395394;15879.28543215558;16001.199940002953;16017.759733604005;16017.839821601752;16030.919845400815;12195.207311524811;12909.741805163938;13095.738085238241;13189.802152967617;13253.668658283523;10210.832569447144;10434.269601127924;10445.738856528571;10569.365838049742;10756.354618722824;3528.1179705073732;3627.927441451156;3639.2175682228185;3642.307961487309;3645.963540364589;87854;88469;88946;89317.55341223252;89962.95166717513;9574.56914438838;9650.565724542412;9685.467299298607;9738.853917191247;9868.703938881845;22382.104715811187;22439.439014024454;22489.32532024042;22489.662655060187;22492;7518.586477743667;7599.96614270851;7847.254510821463;7862.056553213651;8272.751817445485;4274.636655884285;4278.657707383401;4281.678874084456;4297;4565.5206203348835;3815.1415931415763;3895.0457138000806;3897.1231472918935;3900.6099390060767;3901.2392583445953;628.1174949196386;647.3267801486495;701.421327404893;712.9465290103263;720.1430297945533;516.0839509870011;569.9886002280014;606.0727087556046;627.6234259444388;635.8251480842805;8373.37199710012;8477.488830335731;8507.361947853935;8509.957450212833;8644.870326944974;595.8391234366696;602.87942411517;615.8306465721872;624.7719582352408;659.274797722511;3822.904427389282;4003.6369449661843;4014.5182578090817;4019.979900100481;4075.816588253536;97132.54301185488;99671.50164249277;101350;101387;101410;82726.3454730902;84173;84514.57742711247;84994.57502712446;88025;71358;72907;74077;74542.25457745462;75506.24493754993;6616.548839988128;8077.192280771876;8097.39269554774;8136.105028446985;8240.958795205986;38831.805840970614;52149.7392513035;52618.73690631522;53083.73458132685;54219;70616.6469167651;74119.51760964643;74377;75429.62285188539;75544;9603.703500027406;9883.55524001436;9898.703038908845;9935;10313.9484302578;10777.515011824644;11173.664790056311;11285.887141128813;11529.481173347052;11556.826647600461;8746.095068731604;10539.209559282932;10659.20055995819;10957.232993690364;11408.82886756716;11403.201775875608;11558.30650160993;11603.36181510025;11610.013148882439;12244.142909996215;10910.94544527304;11242.94378528102;11325.71685707864;11341.319520828776;12189.390530473618;9584.616615335308;9781.217502599917;9939.503024848584;9973.152282055817;10001.949990250003;9800.558974846292;10047.547860346156;10051.798964020825;10069.345492542961;10069.395836249849 09:56:23 INFO - PROCESS | 1662 | __end_tp_report 09:56:23 INFO - PROCESS | 1662 | __start_cc_report 09:56:23 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,964 09:56:23 INFO - PROCESS | 1662 | __end_cc_report 09:56:23 INFO - PROCESS | 1662 | __startTimestamp1466700983277__endTimestamp 09:56:23 INFO - PROCESS | 1662 | 09:56:23 INFO - PROCESS | 1662 | ------- Summary: start ------- 09:56:23 INFO - PROCESS | 1662 | Number of tests: 6 09:56:23 INFO - PROCESS | 1662 | 09:56:23 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5911.68 Median:6151.11 stddev:600.21 (9.8%) stddev-sans-first:573.72 09:56:23 INFO - PROCESS | 1662 | Values: 3588.7 3639.8 3652.7 3658.3 3727.0 5974.7 5996.3 5996.4 6025.4 6051.2 6016.0 6030.5 6039.3 6042.2 6053.1 5309.4 5354.0 5434.9 5448.4 5488.7 5443.0 5475.6 5482.1 5482.2 5486.4 5385.5 5440.8 5463.9 5468.9 5483.3 5456.3 5457.7 5483.2 5495.9 5497.4 5291.2 5330.9 5378.9 5381.4 5422.7 5756.0 5839.3 5853.1 5888.9 5912.4 6260.0 6279.3 6286.8 6295.7 6304.1 6206.2 6246.4 6305.7 6306.2 6322.6 6029.2 6114.2 6119.5 6128.9 6136.4 6381.1 6400.6 6419.6 6420.9 6424.5 6215.9 6289.9 6303.9 6320.5 6326.1 6299.3 6316.1 6320.5 6321.8 6334.8 6205.2 6226.7 6255.8 6273.3 6288.4 6199.9 6252.2 6253.5 6255.1 6268.8 4998.4 5031.3 5042.8 5046.4 5066.4 4569.1 4593.0 4605.7 4632.0 4659.6 5995.4 6005.5 6009.2 6022.8 6024.0 6189.3 6263.4 6300.5 6308.4 6318.7 5933.7 5935.2 6021.9 6044.2 6083.3 5479.6 5504.0 5539.0 5542.8 5545.2 5518.8 6552.6 6559.4 6564.3 6589.7 6268.2 6316.8 6332.7 6335.9 6340.7 6558.9 6612.7 6621.6 6626.3 6637.1 6161.1 6269.6 6274.2 6282.1 6297.5 6133.8 6170.3 6244.7 6263.9 6325.4 6189.8 6196.2 6202.0 6214.4 6218.7 6151.1 6189.1 6206.2 6211.9 6213.2 6063.5 6143.5 6146.9 6232.3 6234.4 6200.2 6212.5 6213.8 6217.1 6228.4 6109.0 6109.9 6208.8 6216.5 6228.5 09:56:23 INFO - PROCESS | 1662 | 09:56:23 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:70324.88 Median:36633.45 stddev:156816.39 (428.1%) stddev-sans-first:157168.75 09:56:23 INFO - PROCESS | 1662 | Values: 532.6 551.0 552.7 584.5 585.0 32141.7 38595.2 39627.0 39627.4 39799.2 42499.4 42974.1 43004.9 43021.0 43164.6 8983.9 9563.7 9820.1 9831.7 9841.9 11771.3 12038.6 12057.8 12069.3 12073.8 11718.1 11796.2 11801.4 11801.9 11830.8 11673.9 11818.5 11847.8 11861.4 11871.9 11379.8 11411.5 11451.1 11509.6 11650.4 41349.8 41780.0 41801.8 41900.0 41919.2 3268.3 3320.2 3347.3 3405.4 3912.6 28467.7 28584.0 28804.0 30123.1 30135.8 2747.7 2763.6 2798.3 2861.8 2877.3 494032.0 495740.0 495751.0 495765.5 495987.0 38262.0 38604.8 38670.0 38771.6 60970.8 37526.8 37759.6 37851.6 37868.8 38007.6 15549.9 15724.6 15739.5 15749.8 15750.1 12044.9 12138.8 12163.5 13130.5 13172.1 8299.3 8342.2 8351.5 8376.6 8415.2 3504.8 3532.7 3549.1 3557.8 3580.0 984.8 995.9 1003.8 1028.1 1087.1 29354.9 29517.9 29652.0 30250.5 30264.5 1083.8 1135.9 1152.5 1202.3 1247.3 14185.6 14387.4 14672.6 14720.0 14957.7 646380.5 652078.0 655356.0 656958.0 657973.0 58095.0 58738.0 58824.4 58831.4 58961.0 1352.6 1465.8 1507.0 1526.5 1598.5 28463.6 28643.6 29080.4 29094.0 29230.4 50672.0 51618.2 61957.7 62130.0 62142.4 501168.0 617267.0 618354.0 619808.0 620221.0 36997.3 42370.4 43646.8 43903.1 45296.8 43026.4 43971.0 44010.3 44405.3 45631.0 36750.4 36810.4 36861.6 37169.4 37330.0 36063.2 36240.9 36555.5 36711.4 37205.8 37697.4 38184.0 38288.8 38482.7 38894.4 32999.4 33136.7 33208.3 33644.7 33955.3 25059.9 25285.9 25521.4 25554.5 26082.1 09:56:23 INFO - PROCESS | 1662 | 09:56:23 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:36040.33 Median:21465.12 stddev:106538.27 (496.3%) stddev-sans-first:106803.45 09:56:23 INFO - PROCESS | 1662 | Values: 358.4 411.1 411.8 412.4 425.8 15441.0 21042.2 21675.1 21746.1 21813.9 21345.5 21349.3 21518.2 21585.6 21614.8 5099.4 5817.2 6141.7 6154.2 6161.2 6016.0 6075.8 6118.1 6126.1 6134.8 4423.1 6399.7 6715.9 6720.3 6741.6 6433.9 6686.4 6687.7 6689.9 6724.0 5898.3 5933.8 5939.2 5939.3 5947.8 12858.8 13471.3 13480.5 13491.8 25789.5 2196.9 2280.7 2297.0 2797.8 2890.0 9737.3 10038.4 10331.7 12401.7 14582.0 1798.1 1809.3 1830.0 1856.4 1930.4 111485.0 112769.0 114309.9 118233.2 121539.0 11677.9 11721.8 11783.5 11804.8 11898.5 13878.0 14461.3 14485.5 15416.9 15451.0 7669.9 8006.9 8441.0 8598.7 8763.3 8449.8 8744.8 8756.1 8812.1 8886.6 4646.7 4706.6 4707.9 4709.8 4719.5 1777.6 1917.6 2029.9 2030.9 2079.6 889.6 979.8 1014.8 1065.6 1110.1 20165.6 20998.8 21193.5 21208.9 21412.0 966.6 976.7 984.3 1040.5 1061.9 10103.1 11229.0 11244.7 11284.4 11319.9 630552.0 635291.0 642499.0 648848.0 649459.0 119453.0 120671.4 125048.0 126018.4 129375.0 1002.9 1024.6 1036.5 1040.1 1041.3 18182.1 18318.8 18426.7 18455.0 19135.0 53466.5 53667.7 55822.0 55994.9 58256.0 63135.7 79456.6 79925.6 80997.6 85698.6 8392.4 8598.4 8606.2 8614.1 8615.6 10306.0 12665.0 12912.4 13196.1 13347.8 11028.0 11085.8 11285.5 11333.2 11419.4 10750.7 11198.0 11200.1 11242.3 11351.9 9700.3 11204.9 11599.3 11836.9 11970.8 8301.3 8422.9 8544.0 8552.4 8603.0 10808.7 11539.5 11724.0 11724.3 11913.6 09:56:23 INFO - PROCESS | 1662 | 09:56:23 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5849.40 Median:5136.74 stddev:3252.16 (63.3%) stddev-sans-first:3244.27 09:56:23 INFO - PROCESS | 1662 | Values: 1423.4 1601.2 1611.7 1867.3 1966.9 8205.8 8775.3 8884.2 8886.6 8889.5 8604.7 8860.9 8867.2 8875.4 8875.7 2512.9 2537.0 2537.9 2538.9 2543.3 2473.9 2538.6 2545.2 2546.3 2553.7 2508.3 2535.6 2555.0 2556.8 2558.8 2503.2 2533.0 2540.7 2549.6 2554.4 2487.0 2510.6 2517.5 2518.5 2537.7 8773.4 8967.4 9014.3 9024.4 9036.5 5568.5 5644.2 5650.5 5708.5 5710.2 3390.9 3574.0 3635.4 3653.9 3705.9 1038.8 1051.1 1068.1 1070.8 1074.0 11987.0 12301.2 12304.9 12335.6 12340.3 4037.7 4677.8 4736.4 4820.6 4829.7 9605.2 9612.1 9623.7 9643.6 9656.8 5448.6 5509.1 5519.2 5525.5 5551.7 4566.4 4670.7 4715.0 4724.3 5133.6 3344.0 3934.2 4043.5 4048.0 4054.9 1238.8 1242.3 1244.7 1252.2 1254.5 940.3 964.9 984.3 994.0 1006.6 5474.6 5931.2 5954.2 6105.7 6108.3 852.0 923.9 982.8 995.7 997.3 4007.5 4059.1 4075.0 4078.1 4232.4 10739.7 11235.4 11246.6 11248.0 11274.0 10958.9 10973.5 11030.1 11076.5 11113.7 8480.1 8496.1 8501.1 8582.0 8619.7 5336.3 5698.9 5964.0 6041.9 6060.8 9873.4 9956.7 10070.0 10083.7 10132.2 9204.2 10088.1 10361.5 10384.6 10493.3 7265.5 7287.2 7297.9 7302.0 7372.2 8434.2 8523.8 8548.9 8573.6 8576.9 7637.8 7725.7 7766.9 7876.6 7905.4 7599.7 7604.8 7608.0 7632.2 7739.3 7202.2 7213.1 7249.3 7254.7 7259.2 5021.0 5105.9 5139.9 5243.5 5247.5 5130.8 5212.9 5245.4 5267.5 5443.5 09:56:23 INFO - PROCESS | 1662 | 09:56:23 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:6092.42 Median:6859.24 stddev:1944.47 (28.3%) stddev-sans-first:1901.26 09:56:23 INFO - PROCESS | 1662 | Values: 332.8 337.0 342.1 348.1 351.9 6933.3 7001.0 7007.1 7031.4 7065.2 6941.5 6988.0 7004.9 7006.2 7050.6 4964.5 4965.3 5082.8 5146.8 5163.8 4127.8 4853.4 5107.7 5149.3 5167.4 4179.3 5117.8 5134.4 5142.6 5152.4 4898.6 4998.6 5125.4 5142.0 5146.6 4385.8 4389.6 4392.5 4397.0 4399.6 6689.5 6699.5 6722.5 6722.6 6744.9 5947.8 5954.6 5958.4 5958.7 5964.5 6608.0 6799.9 7434.8 7438.7 7441.2 4950.9 4985.0 4985.9 4993.4 5004.6 7823.2 7903.8 7904.8 7905.0 7912.4 5057.4 5071.4 5078.4 5078.5 5078.5 5157.1 5166.7 5169.6 5183.8 5190.6 5835.7 5978.3 6042.4 6042.9 6046.6 6016.2 7346.6 7414.8 7444.7 7455.6 1767.4 1794.2 1796.3 1796.9 1797.3 1078.8 1137.9 1144.3 1170.1 1172.7 6777.2 6789.6 6807.4 6911.1 7047.2 7782.4 7785.1 7786.3 7811.5 7816.9 5783.9 7028.2 7035.0 7112.8 7112.8 3971.6 4094.9 4129.6 4135.0 4146.0 8152.4 8186.1 8187.3 8205.4 8226.2 8074.3 8076.5 8084.6 8089.7 8091.4 8203.5 8214.3 8214.3 8215.8 8227.8 7530.3 7810.3 7832.6 7834.7 7847.3 7583.5 7621.4 7636.7 7645.6 7651.1 6718.4 6922.9 7759.8 8148.2 8154.1 4832.9 4863.1 4874.0 4875.8 4894.9 6498.2 7439.9 7666.2 7700.5 7775.0 7351.5 7421.7 7457.2 7457.9 7477.4 7440.9 7447.2 7451.7 7467.6 7472.7 5966.7 7035.4 7280.3 7317.3 7318.6 7463.0 7512.5 7514.3 7537.3 7551.1 5929.3 7069.7 7248.1 7283.6 7292.4 09:56:23 INFO - PROCESS | 1662 | 09:56:23 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:19347.16 Median:52851.24 stddev:27879.06 (52.8%) stddev-sans-first:27922.06 09:56:23 INFO - PROCESS | 1662 | Values: 688.9 755.5 773.7 780.1 817.8 15642.2 15791.1 15802.0 15828.3 15893.8 14462.2 14563.3 14567.1 16477.6 16757.6 777.2 803.8 966.5 986.9 987.9 972.2 984.0 985.8 986.5 986.9 832.1 1012.7 1035.2 1035.4 1038.2 1025.9 1028.6 1030.6 1031.3 1031.3 762.4 763.8 766.0 766.1 767.0 15879.3 16001.2 16017.8 16017.8 16030.9 12195.2 12909.7 13095.7 13189.8 13253.7 10210.8 10434.3 10445.7 10569.4 10756.4 3528.1 3627.9 3639.2 3642.3 3646.0 87854.0 88469.0 88946.0 89317.6 89963.0 9574.6 9650.6 9685.5 9738.9 9868.7 22382.1 22439.4 22489.3 22489.7 22492.0 7518.6 7600.0 7847.3 7862.1 8272.8 4274.6 4278.7 4281.7 4297.0 4565.5 3815.1 3895.0 3897.1 3900.6 3901.2 628.1 647.3 701.4 712.9 720.1 516.1 570.0 606.1 627.6 635.8 8373.4 8477.5 8507.4 8510.0 8644.9 595.8 602.9 615.8 624.8 659.3 3822.9 4003.6 4014.5 4020.0 4075.8 97132.5 99671.5 101350.0 101387.0 101410.0 82726.3 84173.0 84514.6 84994.6 88025.0 71358.0 72907.0 74077.0 74542.3 75506.2 6616.5 8077.2 8097.4 8136.1 8241.0 38831.8 52149.7 52618.7 53083.7 54219.0 70616.6 74119.5 74377.0 75429.6 75544.0 9603.7 9883.6 9898.7 9935.0 10313.9 10777.5 11173.7 11285.9 11529.5 11556.8 8746.1 10539.2 10659.2 10957.2 11408.8 11403.2 11558.3 11603.4 11610.0 12244.1 10910.9 11242.9 11325.7 11341.3 12189.4 9584.6 9781.2 9939.5 9973.2 10001.9 9800.6 10047.5 10051.8 10069.3 10069.4 09:56:23 INFO - PROCESS | 1662 | -------- Summary: end -------- 09:56:23 INFO - PROCESS | 1662 | 09:56:23 INFO - TEST-INFO | 1662: exit 0 09:56:23 INFO - TEST-OK | dromaeo_css | took 1113362ms 09:56:23 INFO - TEST-START | kraken 09:56:23 INFO - operating with platform_type : mac_ 09:56:23 INFO - Initialising browser for kraken test... 09:56:23 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/tmpJU4jLb/profile http://localhost:49270/getInfo.html) 09:56:25 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 09:56:25 INFO - PROCESS | 1669 | colorDepth:24 09:56:25 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 09:56:25 INFO - PROCESS | 1669 | __metrics 09:56:25 INFO - PROCESS | 1669 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:56:25 ERROR - PROCESS | 1669 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:56:26 INFO - TEST-INFO | 1669: exit 0 09:56:26 INFO - Browser initialized. 09:56:26 INFO - Running cycle 1/1 for kraken test... 09:56:26 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/tmpJU4jLb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:56:38 INFO - PROCESS | 1672 | RSS: Main: 161034240 09:56:38 INFO - PROCESS | 1672 | 09:56:41 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 09:56:41 INFO - PROCESS | 1672 | RSS: Main: 223956992 09:56:41 INFO - PROCESS | 1672 | 09:56:44 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 09:56:45 INFO - PROCESS | 1672 | RSS: Main: 239284224 09:56:45 INFO - PROCESS | 1672 | 09:56:48 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 09:56:48 INFO - PROCESS | 1672 | RSS: Main: 325582848 09:56:48 INFO - PROCESS | 1672 | 09:56:50 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 09:56:51 INFO - PROCESS | 1672 | RSS: Main: 231706624 09:56:51 INFO - PROCESS | 1672 | 09:56:53 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:56:54 INFO - PROCESS | 1672 | RSS: Main: 231088128 09:56:54 INFO - PROCESS | 1672 | 09:56:57 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 09:56:57 INFO - PROCESS | 1672 | RSS: Main: 268144640 09:56:57 INFO - PROCESS | 1672 | 09:57:00 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 09:57:01 INFO - PROCESS | 1672 | RSS: Main: 269291520 09:57:01 INFO - PROCESS | 1672 | 09:57:04 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 09:57:04 INFO - PROCESS | 1672 | RSS: Main: 268083200 09:57:04 INFO - PROCESS | 1672 | 09:57:06 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:57:06 INFO - PROCESS | 1672 | RSS: Main: 259883008 09:57:06 INFO - PROCESS | 1672 | 09:57:08 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:57:09 INFO - PROCESS | 1672 | RSS: Main: 267517952 09:57:09 INFO - PROCESS | 1672 | 09:57:11 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:57:12 INFO - PROCESS | 1672 | RSS: Main: 270270464 09:57:12 INFO - PROCESS | 1672 | 09:57:14 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:57:15 INFO - PROCESS | 1672 | RSS: Main: 277073920 09:57:15 INFO - PROCESS | 1672 | 09:57:18 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:57:18 INFO - PROCESS | 1672 | RSS: Main: 274153472 09:57:18 INFO - PROCESS | 1672 | 09:57:20 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 09:57:20 INFO - PROCESS | 1672 | RSS: Main: 335314944 09:57:20 INFO - PROCESS | 1672 | 09:57:20 INFO - PROCESS | 1672 | __start_tp_report 09:57:20 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 09:57:20 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 09:57:20 INFO - PROCESS | 1672 | |i|pagename|runs| 09:57:20 INFO - PROCESS | 1672 | |0;ai-astar;114;113;118;114;114;110;112;113;114;133 09:57:20 INFO - PROCESS | 1672 | |1;audio-beat-detection;154;117;141;163;118;138;106;173;148;137 09:57:20 INFO - PROCESS | 1672 | |2;audio-dft;187;184;185;184;187;184;184;184;184;184 09:57:20 INFO - PROCESS | 1672 | |3;audio-fft;73;78;72;151;56;55;58;82;160;45 09:57:20 INFO - PROCESS | 1672 | |4;audio-oscillator;92;90;142;79;84;95;123;79;77;126 09:57:20 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;90;88;90;88;89;86;90;87;89;86 09:57:20 INFO - PROCESS | 1672 | |6;imaging-darkroom;131;147;94;95;95;105;148;149;95;95 09:57:20 INFO - PROCESS | 1672 | |7;imaging-desaturate;84;85;84;83;83;85;83;95;130;132 09:57:20 INFO - PROCESS | 1672 | |8;json-parse-financial;61;64;64;64;62;62;62;77;58;56 09:57:20 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;59;61;62;66;67;71;70;63;64;65 09:57:20 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;80;78;78;80;76;96;78;78;80;83 09:57:20 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;126;126;125;121;115;125;128;122;143;130 09:57:20 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;164;165;161;138;169;210;210;172;161;158 09:57:20 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;68;68;68;68;66;68;66;68;69;65 09:57:20 INFO - PROCESS | 1672 | __end_tp_report 09:57:20 INFO - PROCESS | 1672 | __start_cc_report 09:57:20 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,1902 09:57:20 INFO - PROCESS | 1672 | __end_cc_report 09:57:20 INFO - PROCESS | 1672 | __startTimestamp1466701040773__endTimestamp 09:57:20 INFO - PROCESS | 1672 | 09:57:20 INFO - PROCESS | 1672 | ------- Summary: start ------- 09:57:20 INFO - PROCESS | 1672 | Number of tests: 14 09:57:20 INFO - PROCESS | 1672 | 09:57:20 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:115.50 Median:114.00 stddev:6.47 (5.7%) stddev-sans-first:6.84 09:57:20 INFO - PROCESS | 1672 | Values: 114.0 113.0 118.0 114.0 114.0 110.0 112.0 113.0 114.0 133.0 09:57:20 INFO - PROCESS | 1672 | 09:57:20 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:139.50 Median:144.50 stddev:21.24 (14.7%) stddev-sans-first:21.87 09:57:20 INFO - PROCESS | 1672 | Values: 154.0 117.0 141.0 163.0 118.0 138.0 106.0 173.0 148.0 137.0 09:57:20 INFO - PROCESS | 1672 | 09:57:20 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:184.70 Median:184.00 stddev:1.25 (0.7%) stddev-sans-first:1.01 09:57:20 INFO - PROCESS | 1672 | Values: 187.0 184.0 185.0 184.0 187.0 184.0 184.0 184.0 184.0 184.0 09:57:20 INFO - PROCESS | 1672 | 09:57:20 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:83.00 Median:65.00 stddev:39.97 (61.5%) stddev-sans-first:42.24 09:57:20 INFO - PROCESS | 1672 | Values: 73.0 78.0 72.0 151.0 56.0 55.0 58.0 82.0 160.0 45.0 09:57:20 INFO - PROCESS | 1672 | 09:57:20 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:98.70 Median:81.50 stddev:23.11 (28.4%) stddev-sans-first:24.39 09:57:20 INFO - PROCESS | 1672 | Values: 92.0 90.0 142.0 79.0 84.0 95.0 123.0 79.0 77.0 126.0 09:57:20 INFO - PROCESS | 1672 | 09:57:20 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:88.30 Median:89.00 stddev:1.57 (1.8%) stddev-sans-first:1.54 09:57:20 INFO - PROCESS | 1672 | Values: 90.0 88.0 90.0 88.0 89.0 86.0 90.0 87.0 89.0 86.0 09:57:20 INFO - PROCESS | 1672 | 09:57:20 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:115.40 Median:94.50 stddev:25.09 (26.5%) stddev-sans-first:25.97 09:57:20 INFO - PROCESS | 1672 | Values: 131.0 147.0 94.0 95.0 95.0 105.0 148.0 149.0 95.0 95.0 09:57:20 INFO - PROCESS | 1672 | 09:57:20 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:84.00 stddev:19.62 (23.4%) stddev-sans-first:20.45 09:57:20 INFO - PROCESS | 1672 | Values: 84.0 85.0 84.0 83.0 83.0 85.0 83.0 95.0 130.0 132.0 09:57:20 INFO - PROCESS | 1672 | 09:57:20 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:63.00 Median:63.00 stddev:5.58 (8.9%) stddev-sans-first:5.87 09:57:20 INFO - PROCESS | 1672 | Values: 61.0 64.0 64.0 64.0 62.0 62.0 62.0 77.0 58.0 56.0 09:57:20 INFO - PROCESS | 1672 | 09:57:20 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:64.80 Median:65.50 stddev:3.82 (5.8%) stddev-sans-first:3.43 09:57:20 INFO - PROCESS | 1672 | Values: 59.0 61.0 62.0 66.0 67.0 71.0 70.0 63.0 64.0 65.0 09:57:20 INFO - PROCESS | 1672 | 09:57:20 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:80.70 Median:80.00 stddev:5.70 (7.1%) stddev-sans-first:6.04 09:57:20 INFO - PROCESS | 1672 | Values: 80.0 78.0 78.0 80.0 76.0 96.0 78.0 78.0 80.0 83.0 09:57:20 INFO - PROCESS | 1672 | 09:57:20 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:126.10 Median:126.00 stddev:7.25 (5.8%) stddev-sans-first:7.69 09:57:20 INFO - PROCESS | 1672 | Values: 126.0 126.0 125.0 121.0 115.0 125.0 128.0 122.0 143.0 130.0 09:57:20 INFO - PROCESS | 1672 | 09:57:20 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.80 Median:167.00 stddev:22.58 (13.5%) stddev-sans-first:23.82 09:57:20 INFO - PROCESS | 1672 | Values: 164.0 165.0 161.0 138.0 169.0 210.0 210.0 172.0 161.0 158.0 09:57:20 INFO - PROCESS | 1672 | 09:57:20 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.00 stddev:1.26 (1.9%) stddev-sans-first:1.32 09:57:20 INFO - PROCESS | 1672 | Values: 68.0 68.0 68.0 68.0 66.0 68.0 66.0 68.0 69.0 65.0 09:57:20 INFO - PROCESS | 1672 | -------- Summary: end -------- 09:57:20 INFO - PROCESS | 1672 | 09:57:21 INFO - TEST-INFO | 1672: exit 0 09:57:21 INFO - TEST-OK | kraken | took 57742ms 09:57:21 INFO - SUITE-END | took 1171s 09:57:21 INFO - Completed test suite (00:19:31) 09:57:21 INFO - javascript benchmark 09:57:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3588.6770190682837, 3639.799811010394, 3652.689521390682, 3658.304922064809, 3726.9937116978417, 5974.6713930733795, 5996.280446346439, 5996.400359964002, 6025.427584379488, 6051.152838602594, 6015.969920150393, 6030.517558595313, 6039.305479869817, 6042.184305118808, 6053.122297266894, 5309.389420216667, 5353.99999999999, 5434.864128396802, 5448.42791506892, 5488.670679759207, 5443.020256353855, 5475.561955043587, 5482.095454250044, 5482.150266708647, 5486.3690675572425, 5385.488378604026, 5440.755166017539, 5463.890722185554, 5468.91796623051, 5483.287172667548, 5456.345238571377, 5457.699826509541, 5483.177523371486, 5495.917561236584, 5497.367802702701, 5291.1534154535475, 5330.893382132374, 5378.91931621026, 5381.381141168777, 5422.728863556807, 5755.971220143914, 5839.299284085894, 5853.1220316952795, 5888.870035632658, 5912.408759124096, 6259.967507787673, 6279.277883043417, 6286.779962701284, 6295.716692748837, 6304.117423560705, 6206.193194884681, 6246.406591373858, 6305.747770089191, 6306.211723534559, 6322.557420980534, 6029.186059881904, 6114.174586430864, 6119.479844213202, 6128.900367973393, 6136.386361363917, 6381.1066450696935, 6400.647964361911, 6419.582727122722, 6420.940544810054, 6424.486041116699, 6215.875682486325, 6289.899267628147, 6303.873922521616, 6320.525960552977, 6326.082718005863, 6299.307076221612, 6316.084167795736, 6320.494360451153, 6321.841953951096, 6334.778282760173, 6205.193324867739, 6226.688665566703, 6255.812325630239, 6273.341299163613, 6288.434040936338, 6199.907001394983, 6252.155958945576, 6253.499720022387, 6255.12428260044, 6268.780592679234, 4998.375203099613, 5031.270465782514, 5042.773075211624, 5046.419661738873, 5066.392032956069, 4569.109023740339, 4593.0, 4605.746683932416, 4631.976840115845, 4659.603933665609, 5995.430434108753, 6005.549583781147, 6009.248843894513, 6022.819315420545, 6023.969880150659, 6189.257289125334, 6263.404976527223, 6300.495960323163, 6308.400701933309, 6318.7472501100365, 5933.732982015819, 5935.228420305332, 6021.879562408727, 6044.214252147192, 6083.330833608299, 5479.616426850162, 5503.972480137654, 5538.972305138448, 5542.75057622408, 5545.195946587723, 5518.806841760519, 6552.574082684706, 6559.376859198369, 6564.310747371553, 6589.703463344161, 6268.153799237045, 6316.778912738032, 6332.746690132343, 6335.936640633719, 6340.682965851781, 6558.868822623616, 6612.735490580323, 6621.602703837785, 6626.337366263528, 6637.070810086495, 6161.106639537332, 6269.592476489013, 6274.247090349188, 6282.089097080989, 6297.496200303873, 6133.793750436688, 6170.321264660974, 6244.687765611792, 6263.906041409291, 6325.430711236011, 6189.783357582463, 6196.1635179252025, 6202.0, 6214.4407003369915, 6218.720157592828, 6151.108089327112, 6189.133521306931, 6206.193194884636, 6211.9068213975925, 6213.2233470816145, 6063.5452341073715, 6143.511877689543, 6146.8935591593945, 6232.314445411092, 6234.376562343729, 6200.162977997912, 6212.471939884979, 6213.751449941951, 6217.067439884054, 6228.439440450381, 6109.009944784373, 6109.894485367275, 6208.844778880564, 6216.471599914058, 6228.501719862491], "value": 5876.502460509917, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [532.5978885941114, 551.0411883323018, 552.6628756458562, 584.4798129664599, 584.9587733833774, 32141.67858321416, 38595.22809543808, 39627.0, 39627.4149034039, 39799.20401591966, 42499.36250956238, 42974.14051718964, 43004.924876878096, 43021.0, 43164.56835431645, 8983.865242021342, 9563.665271715492, 9820.11618954294, 9831.65589204378, 9841.852372214422, 11771.293722376684, 12038.57864974726, 12057.758844823098, 12069.275843449377, 12073.758524829498, 11718.121140914423, 11796.2332448391, 11801.409929503534, 11801.940990295037, 11830.7633847323, 11673.883261167364, 11818.468168932419, 11847.82228266568, 11861.406929653525, 11871.931526162687, 11379.772404551863, 11411.54353825846, 11451.083913286917, 11509.5971640993, 11650.359230242333, 41349.79325103385, 41780.0, 41801.790991045156, 41900.0, 41919.161616767495, 3268.2809781847955, 3320.186554294189, 3347.313800670857, 3405.4381027130494, 3912.628300311466, 28467.715322846714, 28584.0, 28803.99186028479, 30123.096307110824, 30135.794568217247, 2747.6840163381266, 2763.599278104684, 2798.272449163212, 2861.756750676195, 2877.2662970942274, 494032.0, 495740.0, 495751.0, 495765.52117239544, 495987.0, 38262.0, 38604.8418547444, 38670.03324916843, 38771.612283877366, 60970.78058438806, 37526.81236593799, 37759.62240377561, 37851.62148378536, 37868.810655946545, 38007.61992380096, 15549.922250388901, 15724.60688482797, 15739.527814165593, 15749.763753543706, 15750.133742644264, 12044.87955120455, 12138.817917731241, 12163.452644631012, 13130.540431084866, 13172.143810652276, 8299.336053115736, 8342.16578342161, 8351.457155284887, 8376.581170941428, 8415.200555947298, 3504.8422820972605, 3532.6997205237844, 3549.1304630365726, 3557.8043207623828, 3579.964200358015, 984.7981163861391, 995.9103680668775, 1003.8042581696645, 1028.0798685176726, 1087.0868470484832, 29354.85322573416, 29517.85241073824, 29652.0, 30250.54624180639, 30264.54603180954, 1083.7940791249637, 1135.8523391959159, 1152.504423098076, 1202.2846406388185, 1247.2516490105863, 14185.574432766827, 14387.352569134415, 14672.559823205322, 14720.0, 14957.70084598302, 646380.5361946415, 652078.0, 655356.0, 656958.0, 657973.0, 58095.0, 58738.0, 58824.41175588275, 58831.411685883446, 58961.0, 1352.5536572931105, 1465.7801329800616, 1507.0, 1526.511516314791, 1598.5044636162827, 28463.573046404734, 28643.570346444405, 29080.418391632473, 29094.0, 29230.415391691622, 50672.0, 51618.22572661338, 61957.69021154865, 62130.0, 62142.378576215466, 501168.0, 617267.0, 618354.0, 619808.0, 620221.0, 36997.260054798215, 42370.364444533974, 43646.78176609224, 43903.121937560434, 45296.773516132205, 43026.35460468033, 43971.0, 44010.33984490171, 44405.33391999187, 45631.0, 36750.44874326834, 36810.447843282906, 36861.63138368582, 37169.4424583626, 37330.0, 36063.19338428768, 36240.912772616866, 36555.45166822553, 36711.449328259565, 37205.813970931056, 37697.43453848249, 38184.0, 38288.80855595704, 38482.653107141115, 38894.4165837507, 32999.3500324978, 33136.66863331432, 33208.33583328368, 33644.66355336415, 33955.3208935815, 25059.87470062638, 25285.852011329505, 25521.385295235017, 25554.488910222062, 26082.087126950464], "value": 19397.417661328236, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.4104496557766, 411.13045907904774, 411.804392913366, 412.43496409918396, 425.8190269135617, 15440.999999999973, 21042.158313667453, 21675.132994680214, 21746.130154793827, 21813.890930545345, 21345.466363340925, 21349.252776152836, 21518.246861359818, 21585.56828863422, 21614.783852161512, 5099.439061703229, 5817.1565123057135, 6141.692915354237, 6154.169187159743, 6161.168242287279, 6015.969920150415, 6075.787347442841, 6118.051701986177, 6126.081087836816, 6134.754609815602, 4423.137488189805, 6399.680015999182, 6715.932840671604, 6720.260771315151, 6741.595504269735, 6433.903491447632, 6686.398224159825, 6687.732490700366, 6689.866202675944, 6723.966380168117, 5898.321693005273, 5933.821985340447, 5939.168516407706, 5939.316978547479, 5947.762089516457, 12858.807117893239, 13471.326433678278, 13480.5281815136, 13491.797623035662, 25789.484210315877, 2196.9450763730874, 2280.726312842453, 2297.0, 2797.782964410488, 2890.0, 9737.2697047721, 10038.447885366302, 10331.708536432945, 12401.689957751038, 14581.979261451703, 1798.1099355818822, 1809.3034181839955, 1829.9817001830077, 1856.4152292027968, 1930.3919265431484, 111485.0, 112768.99999999919, 114309.85690143076, 118233.22650160255, 121538.99999999911, 11677.941610291895, 11721.765564688829, 11783.469743861548, 11804.763904721858, 11898.524059037714, 13878.0, 14461.349239284258, 14485.493007744679, 15416.920815543028, 15451.0, 7669.884951725729, 8006.919930800734, 8440.957795211107, 8598.699045533376, 8763.298936085226, 8449.831003379897, 8744.825103497895, 8756.124387561193, 8812.118788121265, 8886.644534218689, 4646.674732768742, 4706.599939005223, 4707.882302942386, 4709.811607535728, 4719.480857105716, 1777.5589220712723, 1917.6356492266425, 2029.9492512687007, 2030.9086091125935, 2079.5632917087582, 889.5685592487768, 979.779549601334, 1014.7513859104566, 1065.648336049102, 1110.134095405585, 20165.596688066158, 20998.80023995207, 21193.470163245733, 21208.893955530122, 21412.0, 966.6423423333412, 976.6679329028022, 984.2913102566141, 1040.453761775059, 1061.888501707325, 10103.090721834924, 11229.034918889161, 11244.718882027852, 11284.379359135162, 11319.886801132048, 630552.0, 635291.0, 642499.0, 648848.0, 649459.0, 119453.0, 120671.39664301623, 125048.0, 126018.36990814988, 129375.0, 1002.8746406699163, 1024.6157690865925, 1036.4713995862164, 1040.0639424517994, 1041.2815157541272, 18182.09089545491, 18318.81681183171, 18426.72359914629, 18455.0, 19135.04324783783, 53466.46533534615, 53667.731661341444, 55822.0, 55994.880102396906, 58256.0, 63135.6843215781, 79456.60271698605, 79925.60037199777, 80997.59501202693, 85698.57150714456, 8392.370572206986, 8598.355123365898, 8606.182412670787, 8614.052454229904, 8615.569221538773, 10306.0, 12665.0, 12912.418941147484, 13196.14225075367, 13347.799783003069, 11028.0074793269, 11085.778284434427, 11285.498134896618, 11333.150013748836, 11419.37193454368, 10750.677479675622, 11198.048165905664, 11200.10399168081, 11242.269252498889, 11351.943240283746, 9700.27247956392, 11204.943975280072, 11599.304041757521, 11836.881631183922, 11970.820437693268, 8301.29438997692, 8422.915770842214, 8544.01743799471, 8552.358573106918, 8602.967643882774, 10808.675739727822, 11539.538418463168, 11724.00691882365, 11724.334003362039, 11913.642590722293], "value": 9745.290869442035, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1423.4377420918731, 1601.2474137155539, 1611.677664467106, 1867.2904296367378, 1966.9016549172538, 8205.75382738518, 8775.25410340121, 8884.156005179497, 8886.64453421863, 8889.466632002084, 8604.698835540757, 8860.936687597481, 8867.157620026088, 8875.423097498675, 8875.733727988154, 2512.9120480783176, 2537.0232460502657, 2537.8731063446844, 2538.860362680052, 2543.326018605071, 2473.9010439582485, 2538.5557527432625, 2545.185540627, 2546.2997675639162, 2553.6807899012624, 2508.3352911478473, 2535.5816290312077, 2555.0035486160414, 2556.7954563634958, 2558.756918092778, 2503.236512863576, 2532.987335063322, 2540.6697129373156, 2549.5665736824785, 2554.3741783263217, 2486.962695559568, 2510.598304271308, 2517.4587463695393, 2518.4711210645787, 2537.695476542827, 8773.429727067783, 8967.417117887317, 9014.323925705598, 9024.413413128126, 9036.50299233542, 5568.470995255444, 5644.209810626516, 5650.491455769001, 5708.543316534668, 5710.229119068914, 3390.915227119317, 3573.9642603573893, 3635.4365073413665, 3653.94519082214, 3705.8517659293598, 1038.8426024593261, 1051.0803047333584, 1068.0707784227693, 1070.7537266428687, 1073.9946300268527, 11987.041036717043, 12301.200421972633, 12304.876951230552, 12335.568255111119, 12340.259584424874, 4037.6769858411258, 4677.766111694402, 4736.360591320196, 4820.590249828804, 4829.710217386898, 9605.18355939739, 9612.134907858326, 9623.663171788954, 9643.566039528097, 9656.75858103553, 5448.618596698193, 5509.090999985037, 5519.172124181325, 5525.502704756591, 5551.666899986014, 4566.406367172246, 4670.719756814602, 4714.976425117852, 4724.338592597039, 5133.589312854962, 3343.9800860737223, 3934.1934903344745, 4043.534993475729, 4048.008237981713, 4054.918901621951, 1238.8203710462112, 1242.2546472116658, 1244.6514975806788, 1252.1610520950985, 1254.5232811531741, 940.2759874896292, 964.8986856380119, 984.2863923655435, 993.9503024848729, 1006.620929326827, 5474.562035037187, 5931.228940237827, 5954.166416701665, 6105.694715264219, 6108.3280839107665, 852.0, 923.9328575495314, 982.8083523712951, 995.6863587969766, 997.2869398380193, 4007.499062617173, 4059.1475790083823, 4074.959250407458, 4078.1232035112594, 4232.386303985966, 10739.731506712238, 11235.438228088562, 11246.606368777217, 11248.0, 11274.041706455044, 10958.890411095947, 10973.451327433597, 11030.117590592734, 11076.501557429934, 11113.666590002313, 8480.10958849312, 8496.1079086695, 8501.06488286288, 8582.013068497077, 8619.655213791504, 5336.279602253646, 5698.914516282342, 5964.045752679551, 6041.939580604314, 6060.848478787889, 9873.40759554429, 9956.651517196864, 10070.04334588213, 10083.697489075339, 10132.189424846141, 9204.171624553554, 10088.092071713581, 10361.481925903825, 10384.636537721144, 10493.317934334244, 7265.491415600857, 7287.198408174991, 7297.890531642136, 7301.963490182515, 7372.189059203591, 8434.198751118634, 8523.82952340962, 8548.91451085506, 8573.571321434029, 8576.914230857612, 7637.847243054996, 7725.652345644347, 7766.922330776846, 7876.645550950336, 7905.367570594223, 7599.696012159452, 7604.809879752829, 7607.961960190164, 7632.19861914491, 7739.3421559165845, 7202.243764404871, 7213.062301900719, 7249.275072492708, 7254.746083887038, 7259.20148783644, 5020.974895125501, 5105.897882042264, 5139.897202055863, 5243.501867322599, 5247.501487358695, 5130.769115389742, 5212.895742085213, 5245.423003469539, 5267.499587539178, 5443.537299329601], "value": 4720.792846254379, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.7920049968769, 336.97978121312724, 342.0559256452192, 348.08453766593857, 351.86277351832774, 6933.30666933307, 7000.964995175017, 7007.054047703565, 7031.402330801881, 7065.187503437106, 6941.54879932804, 6987.965060174692, 7004.929950700492, 7006.2293147753835, 7050.6122163281, 4964.503549645034, 4965.280034395028, 5082.796688132489, 5146.845594632148, 5163.767630456619, 4127.752334859918, 4853.369062021932, 5107.668001579904, 5149.304843846089, 5167.379914410257, 4179.331306990882, 5117.795288188487, 5134.4095429025765, 5142.640015198937, 5152.407473140599, 4898.632602554805, 4998.625103117281, 5125.436202017775, 5141.974290128563, 5146.588272938156, 4385.758783266919, 4389.626881715058, 4392.516823149451, 4396.988692600719, 4399.604035636776, 6689.531732778707, 6699.497537684693, 6722.495812814059, 6722.563033402862, 6744.932550674529, 5947.821565353046, 5954.55340849438, 5958.40415958405, 5958.702064896781, 5964.494912136913, 6607.966960165217, 6799.912014077773, 7434.7769566913075, 7438.73964411243, 7441.181470038291, 4950.925736113961, 4985.0, 4985.925211121836, 4993.375828021497, 5004.599632029429, 7823.2176782321885, 7903.802404939865, 7904.80237994049, 7905.0, 7912.367010639193, 5057.443681195065, 5071.442141364447, 5078.415982162025, 5078.466760990117, 5078.517540833689, 5157.097507936096, 5166.664166829144, 5169.612279079084, 5183.844484665466, 5190.6107041971245, 5835.708214589254, 5978.312494063239, 6042.365551617105, 6042.939570604326, 6046.606970546901, 6016.2479690038745, 7346.63266836656, 7414.777556673308, 7444.739434119888, 7455.552666840007, 1767.3990843113147, 1794.2464165050449, 1796.2545543599522, 1796.9191386387645, 1797.334986055168, 1078.827387617993, 1137.9146564007733, 1144.3362849547243, 1170.1399471388522, 1172.7478592102568, 6777.152855893013, 6789.626570538668, 6807.353301436356, 6911.136107986502, 7047.224805271416, 7782.377409807202, 7785.104712957969, 7786.299233069051, 7811.531308121531, 7816.921830781733, 5783.872144931782, 7028.1917579477995, 7035.0, 7112.751053713096, 7112.751053713198, 3971.6028397160053, 4094.8976275592754, 4129.587041295907, 4135.0, 4146.004958809925, 8152.4293299469655, 8186.140455252232, 8187.345012398994, 8205.425620206528, 8226.218509241613, 8074.313683336982, 8076.515409075474, 8084.555349455719, 8089.676412943534, 8091.433599647969, 8203.466774659628, 8214.342852571779, 8214.342852571779, 8215.753527394188, 8227.794305142299, 7530.322270995636, 7810.258025487569, 7832.647530861011, 7834.6866125354345, 7847.254510821463, 7583.469157158946, 7621.428392870668, 7636.694532218649, 7645.5794931279315, 7651.081870175614, 6718.428933540508, 6922.93077069243, 7759.797231429137, 8148.185181481805, 8154.103048664523, 4832.903341933212, 4863.051704917649, 4873.975630121827, 4875.756212189447, 4894.877628059327, 6498.220213574401, 7439.851202975802, 7666.233376662289, 7700.460967732205, 7775.028121484815, 7351.522151060164, 7421.703131874665, 7457.216992215956, 7457.888131677921, 7477.40180785525, 7440.921066445443, 7447.180810110992, 7451.701931922879, 7467.58928258951, 7472.663730132048, 5966.701664916824, 7035.437165026683, 7280.271972802678, 7317.304856038668, 7318.597477138808, 7463.0, 7512.474126811071, 7514.323710866049, 7537.283958023979, 7551.056117985252, 5929.318128415288, 7069.681864316015, 7248.130224373109, 7283.635818209174, 7292.41660667156], "value": 5444.952440106113, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [688.8932215506596, 755.4673954861823, 773.6789232468523, 780.0950896959528, 817.8078151634367, 15642.217889105543, 15791.131487768169, 15802.0, 15828.287727052282, 15893.761593576106, 14462.204578748164, 14563.271836408163, 14567.053141545792, 16477.588060298498, 16757.581060473494, 777.199484530934, 803.7749430159536, 966.5264020629876, 986.8717066781309, 987.93578417403, 972.1785091597624, 983.9803203935917, 985.8373368394207, 986.4673076538661, 986.8815742110957, 832.1013305629905, 1012.6810054832741, 1035.1563475767264, 1035.430513217731, 1038.2420832792066, 1025.8666373371489, 1028.5628607841638, 1030.6083688198512, 1031.3141760729068, 1031.3450958641242, 762.3862790453683, 763.7823220382164, 766.0, 766.0883548577231, 766.9731559395394, 15879.28543215558, 16001.199940002953, 16017.759733604005, 16017.839821601752, 16030.919845400815, 12195.207311524811, 12909.741805163938, 13095.738085238241, 13189.802152967617, 13253.668658283523, 10210.832569447144, 10434.269601127924, 10445.738856528571, 10569.365838049742, 10756.354618722824, 3528.1179705073732, 3627.927441451156, 3639.2175682228185, 3642.307961487309, 3645.963540364589, 87854.0, 88469.0, 88946.0, 89317.55341223252, 89962.95166717513, 9574.56914438838, 9650.565724542412, 9685.467299298607, 9738.853917191247, 9868.703938881845, 22382.104715811187, 22439.439014024454, 22489.32532024042, 22489.662655060187, 22492.0, 7518.586477743667, 7599.96614270851, 7847.254510821463, 7862.056553213651, 8272.751817445485, 4274.636655884285, 4278.657707383401, 4281.678874084456, 4297.0, 4565.5206203348835, 3815.1415931415763, 3895.0457138000806, 3897.1231472918935, 3900.6099390060767, 3901.2392583445953, 628.1174949196386, 647.3267801486495, 701.421327404893, 712.9465290103263, 720.1430297945533, 516.0839509870011, 569.9886002280014, 606.0727087556046, 627.6234259444388, 635.8251480842805, 8373.37199710012, 8477.488830335731, 8507.361947853935, 8509.957450212833, 8644.870326944974, 595.8391234366696, 602.87942411517, 615.8306465721872, 624.7719582352408, 659.274797722511, 3822.904427389282, 4003.6369449661843, 4014.5182578090817, 4019.979900100481, 4075.816588253536, 97132.54301185488, 99671.50164249277, 101350.0, 101387.0, 101410.0, 82726.3454730902, 84173.0, 84514.57742711247, 84994.57502712446, 88025.0, 71358.0, 72907.0, 74077.0, 74542.25457745462, 75506.24493754993, 6616.548839988128, 8077.192280771876, 8097.39269554774, 8136.105028446985, 8240.958795205986, 38831.805840970614, 52149.7392513035, 52618.73690631522, 53083.73458132685, 54219.0, 70616.6469167651, 74119.51760964643, 74377.0, 75429.62285188539, 75544.0, 9603.703500027406, 9883.55524001436, 9898.703038908845, 9935.0, 10313.9484302578, 10777.515011824644, 11173.664790056311, 11285.887141128813, 11529.481173347052, 11556.826647600461, 8746.095068731604, 10539.209559282932, 10659.20055995819, 10957.232993690364, 11408.82886756716, 11403.201775875608, 11558.30650160993, 11603.36181510025, 11610.013148882439, 12244.142909996215, 10910.94544527304, 11242.94378528102, 11325.71685707864, 11341.319520828776, 12189.390530473618, 9584.616615335308, 9781.217502599917, 9939.503024848584, 9973.152282055817, 10001.949990250003, 9800.558974846292, 10047.547860346156, 10051.798964020825, 10069.345492542961, 10069.395836249849], "value": 7165.767138381589, "unit": "score"}], "name": "dromaeo_css", "value": 7676.451712032896, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 118.0, 114.0, 114.0, 110.0, 112.0, 113.0, 114.0, 133.0], "value": 115.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 117.0, 141.0, 163.0, 118.0, 138.0, 106.0, 173.0, 148.0, 137.0], "value": 139.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 184.0, 185.0, 184.0, 187.0, 184.0, 184.0, 184.0, 184.0, 184.0], "value": 184.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 78.0, 72.0, 151.0, 56.0, 55.0, 58.0, 82.0, 160.0, 45.0], "value": 83.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 142.0, 79.0, 84.0, 95.0, 123.0, 79.0, 77.0, 126.0], "value": 98.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 90.0, 88.0, 89.0, 86.0, 90.0, 87.0, 89.0, 86.0], "value": 88.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 147.0, 94.0, 95.0, 95.0, 105.0, 148.0, 149.0, 95.0, 95.0], "value": 115.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 84.0, 83.0, 83.0, 85.0, 83.0, 95.0, 130.0, 132.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 64.0, 64.0, 62.0, 62.0, 62.0, 77.0, 58.0, 56.0], "value": 63.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 62.0, 66.0, 67.0, 71.0, 70.0, 63.0, 64.0, 65.0], "value": 64.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 78.0, 78.0, 80.0, 76.0, 96.0, 78.0, 78.0, 80.0, 83.0], "value": 80.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 126.0, 125.0, 121.0, 115.0, 125.0, 128.0, 122.0, 143.0, 130.0], "value": 126.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 161.0, 138.0, 169.0, 210.0, 210.0, 172.0, 161.0, 158.0], "value": 170.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 68.0, 66.0, 68.0, 66.0, 68.0, 69.0, 65.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1492.3, "alertThreshold": 2.0}]} 09:57:22 INFO - Return code: 0 09:57:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:57:22 ERROR - # TBPL SUCCESS # 09:57:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:57:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:57:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:57:22 INFO - 2016-06-23 09:57:22.073 screenresolution[1676:9758] starting screenresolution argv=screenresolution get 09:57:22 INFO - 2016-06-23 09:57:22.075 screenresolution[1676:9758] Display 0: 1600x1200x32@60 09:57:22 INFO - 2016-06-23 09:57:22.081 screenresolution[1677:9761] starting screenresolution argv=screenresolution list 09:57:22 INFO - Available Modes on Display 0 09:57:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:57:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:57:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:57:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:57:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:57:22 INFO - Intel Iris: 09:57:22 INFO - Chipset Model: Intel Iris 09:57:22 INFO - Type: GPU 09:57:22 INFO - Bus: Built-In 09:57:22 INFO - VRAM (Dynamic, Max): 1536 MB 09:57:22 INFO - Vendor: Intel (0x8086) 09:57:22 INFO - Device ID: 0x0a2e 09:57:22 INFO - Revision ID: 0x0009 09:57:22 INFO - Displays: 09:57:22 INFO - Display: 09:57:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:57:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:57:22 INFO - Display Serial Number: 1600x1200 60 09:57:22 INFO - Main Display: Yes 09:57:22 INFO - Mirror: Off 09:57:22 INFO - Online: Yes 09:57:22 INFO - Rotation: Supported 09:57:22 INFO - Return code: 0 09:57:22 INFO - Running post-action listener: _resource_record_post_action 09:57:22 INFO - Running post-run listener: _resource_record_post_run 09:57:23 INFO - Total resource usage - Wall time: 1195s; CPU: 53.0%; Read bytes: 37062144; Write bytes: 495087104; Read time: 239; Write time: 1534 09:57:23 INFO - install - Wall time: 23s; CPU: 55.0%; Read bytes: 194523648; Write bytes: 186676736; Read time: 17394; Write time: 419 09:57:23 INFO - run-tests - Wall time: 1174s; CPU: 53.0%; Read bytes: 29990400; Write bytes: 297621504; Read time: 184; Write time: 1080 09:57:23 INFO - Running post-run listener: _upload_blobber_files 09:57:23 INFO - Blob upload gear active. 09:57:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:57:23 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 09:57:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:57:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:57:23 INFO - (blobuploader) - INFO - Open directory for files ... 09:57:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:57:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:57:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:57:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:57:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:57:24 INFO - (blobuploader) - INFO - Done attempting. 09:57:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:57:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:57:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:57:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:57:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:57:25 INFO - (blobuploader) - INFO - Done attempting. 09:57:25 INFO - (blobuploader) - INFO - Iteration through files over. 09:57:25 INFO - Return code: 0 09:57:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:57:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:57:25 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fe200496a0495e3722891227c1fbdce74472ce6031b373d2cbd874dee27186c4959311adb82768f47bff29996177dfc399fd432e3aad53f27f47138a6c66657f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/32a71b294916c346e53d8cd390d5c57ff83619c3858b49547eb2b1ccd4d6e4d839967e7b07531b0698313a24625eb2fae88fdf44ebf50a0b4cd137f224530899"} 09:57:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:57:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:57:25 INFO - Contents: 09:57:25 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fe200496a0495e3722891227c1fbdce74472ce6031b373d2cbd874dee27186c4959311adb82768f47bff29996177dfc399fd432e3aad53f27f47138a6c66657f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/32a71b294916c346e53d8cd390d5c57ff83619c3858b49547eb2b1ccd4d6e4d839967e7b07531b0698313a24625eb2fae88fdf44ebf50a0b4cd137f224530899"} 09:57:25 INFO - Running post-run listener: copy_logs_to_upload_dir 09:57:25 INFO - Copying logs to upload dir... 09:57:25 INFO - mkdir: /builds/slave/test/build/upload/logs 09:57:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.319138 ========= master_lag: 0.36 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-06-23 09:57:26.179213) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-23 09:57:26.182733) ========= 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.MgcVFumUzs/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.7aNTjd7kwc/Listeners TMPDIR=/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fe200496a0495e3722891227c1fbdce74472ce6031b373d2cbd874dee27186c4959311adb82768f47bff29996177dfc399fd432e3aad53f27f47138a6c66657f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/32a71b294916c346e53d8cd390d5c57ff83619c3858b49547eb2b1ccd4d6e4d839967e7b07531b0698313a24625eb2fae88fdf44ebf50a0b4cd137f224530899"} build_url:https://queue.taskcluster.net/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011063 build_url: 'https://queue.taskcluster.net/v1/task/IBySdxbPRDqQBoZx7vu07A/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fe200496a0495e3722891227c1fbdce74472ce6031b373d2cbd874dee27186c4959311adb82768f47bff29996177dfc399fd432e3aad53f27f47138a6c66657f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/32a71b294916c346e53d8cd390d5c57ff83619c3858b49547eb2b1ccd4d6e4d839967e7b07531b0698313a24625eb2fae88fdf44ebf50a0b4cd137f224530899"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-23 09:57:26.217330) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 09:57:26.217860) ========= 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.MgcVFumUzs/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.7aNTjd7kwc/Listeners TMPDIR=/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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.005379 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 09:57:26.268778) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-23 09:57:26.269373) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-23 09:57:26.270020) ========= ========= Total master_lag: 1.60 =========