builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0088 starttime: 1462834835.27 results: success (0) buildid: 20160509134909 builduid: 86d329654490450a809378ee1a4ed0ce revision: beaf4d37d01c85e87cee2682604083a889beeb29 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 16:00:35.271114) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 16:00:35.272100) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 16:00:35.272736) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 16:00:35.293625) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 16:00:35.294056) ========= 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.GQ21CCjpJP/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.0FEX6JSRzu/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-09 16:00:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.5M=0.001s 2016-05-09 16:00:35 (14.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.130865 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 16:00:35.444517) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 16:00:35.444995) ========= 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.GQ21CCjpJP/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.0FEX6JSRzu/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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.026996 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 16:00:35.493982) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 16:00:35.494373) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev beaf4d37d01c85e87cee2682604083a889beeb29 --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 beaf4d37d01c85e87cee2682604083a889beeb29 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.GQ21CCjpJP/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.0FEX6JSRzu/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-09 16:00:35,568 truncating revision to first 12 chars 2016-05-09 16:00:35,568 Setting DEBUG logging. 2016-05-09 16:00:35,568 attempt 1/10 2016-05-09 16:00:35,568 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/beaf4d37d01c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-09 16:00:36,155 unpacking tar archive at: mozilla-beta-beaf4d37d01c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.837425 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 16:00:36.349769) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 16:00:36.350241) ========= script_repo_revision: beaf4d37d01c85e87cee2682604083a889beeb29 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 16:00:36.350800) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 16:00:36.351117) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 16:00:36.365746) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-05-09 16:00:36.366055) ========= /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.GQ21CCjpJP/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.0FEX6JSRzu/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 16:00:36 INFO - MultiFileLogger online at 20160509 16:00:36 in /builds/slave/test 16:00:36 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 16:00:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:00:36 INFO - {'append_to_log': False, 16:00:36 INFO - 'base_work_dir': '/builds/slave/test', 16:00:36 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:00:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:00:36 INFO - 'branch': 'Mozilla-Beta', 16:00:36 INFO - 'buildbot_json_path': 'buildprops.json', 16:00:36 INFO - 'config_files': ('talos/mac_config.py',), 16:00:36 INFO - 'default_actions': ('clobber', 16:00:36 INFO - 'read-buildbot-config', 16:00:36 INFO - 'download-and-extract', 16:00:36 INFO - 'populate-webroot', 16:00:36 INFO - 'create-virtualenv', 16:00:36 INFO - 'install', 16:00:36 INFO - 'run-tests'), 16:00:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:00:36 INFO - 'download_minidump_stackwalk': True, 16:00:36 INFO - 'download_symbols': 'ondemand', 16:00:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:00:36 INFO - 'tooltool.py': '/tools/tooltool.py', 16:00:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:00:36 INFO - '/tools/misc-python/virtualenv.py')}, 16:00:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:00:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:00:36 INFO - 'installer_path': 'installer.exe', 16:00:36 INFO - 'log_level': 'info', 16:00:36 INFO - 'log_name': 'talos', 16:00:36 INFO - 'log_to_console': True, 16:00:36 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:00:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:00:36 INFO - 'opt_config_files': (), 16:00:36 INFO - 'pip_index': False, 16:00:36 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:00:36 INFO - 'cmd': ('bash', 16:00:36 INFO - '-c', 16:00:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:00:36 INFO - 'enabled': True, 16:00:36 INFO - 'halt_on_failure': False, 16:00:36 INFO - 'name': 'check_screen_resolution'},), 16:00:36 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:00:36 INFO - 'cmd': ('bash', 16:00:36 INFO - '-c', 16:00:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:00:36 INFO - 'enabled': True, 16:00:36 INFO - 'halt_on_failure': False, 16:00:36 INFO - 'name': 'check_screen_resolution'},), 16:00:36 INFO - 'run_cmd_checks_enabled': True, 16:00:36 INFO - 'sps_profile': False, 16:00:36 INFO - 'sps_profile_interval': 0, 16:00:36 INFO - 'suite': 'dromaeojs', 16:00:36 INFO - 'system_bits': '32', 16:00:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:00:36 INFO - 'title': 't-yosemite-r7-0088', 16:00:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:00:36 INFO - 'use_talos_json': True, 16:00:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:00:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:00:36 INFO - 'work_dir': 'build'} 16:00:36 INFO - ##### 16:00:36 INFO - ##### Running clobber step. 16:00:36 INFO - ##### 16:00:36 INFO - Running pre-action listener: _resource_record_pre_action 16:00:36 INFO - Running main action method: clobber 16:00:36 INFO - rmtree: /builds/slave/test/build 16:00:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:00:38 INFO - Running post-action listener: _resource_record_post_action 16:00:38 INFO - ##### 16:00:38 INFO - ##### Running read-buildbot-config step. 16:00:38 INFO - ##### 16:00:38 INFO - Running pre-action listener: _resource_record_pre_action 16:00:38 INFO - Running main action method: read_buildbot_config 16:00:38 INFO - Using buildbot properties: 16:00:38 INFO - { 16:00:38 INFO - "project": "", 16:00:38 INFO - "product": "firefox", 16:00:38 INFO - "script_repo_revision": "production", 16:00:38 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 16:00:38 INFO - "repository": "", 16:00:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 16:00:38 INFO - "buildid": "20160509134909", 16:00:38 INFO - "pgo_build": "False", 16:00:38 INFO - "basedir": "/builds/slave/test", 16:00:38 INFO - "buildnumber": 63, 16:00:38 INFO - "slavename": "t-yosemite-r7-0088", 16:00:38 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 16:00:38 INFO - "platform": "yosemite_r7", 16:00:38 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 16:00:38 INFO - "branch": "mozilla-beta", 16:00:38 INFO - "repo_path": "releases/mozilla-beta", 16:00:38 INFO - "revision": "beaf4d37d01c85e87cee2682604083a889beeb29", 16:00:38 INFO - "stage_platform": "macosx64", 16:00:38 INFO - "builduid": "86d329654490450a809378ee1a4ed0ce", 16:00:38 INFO - "slavebuilddir": "test" 16:00:38 INFO - } 16:00:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg. 16:00:38 INFO - Running post-action listener: _resource_record_post_action 16:00:38 INFO - ##### 16:00:38 INFO - ##### Running download-and-extract step. 16:00:38 INFO - ##### 16:00:38 INFO - Running pre-action listener: _resource_record_pre_action 16:00:38 INFO - Running main action method: download_and_extract 16:00:38 INFO - mkdir: /builds/slave/test/build/tests 16:00:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:38 INFO - https://queue.taskcluster.net/v1/task/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:00:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 16:00:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 16:00:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 16:00:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json'}, attempt #1 16:00:45 INFO - Downloaded 1241 bytes. 16:00:45 INFO - Reading from file /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 16:00:45 INFO - Using the following test package requirements: 16:00:45 INFO - {u'common': [u'firefox-47.0.en-US.mac.common.tests.zip'], 16:00:45 INFO - u'cppunittest': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:00:45 INFO - u'firefox-47.0.en-US.mac.cppunittest.tests.zip'], 16:00:45 INFO - u'gtest': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:00:45 INFO - u'firefox-47.0.en-US.mac.gtest.tests.zip'], 16:00:45 INFO - u'jittest': [u'firefox-47.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 16:00:45 INFO - u'mochitest': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:00:45 INFO - u'firefox-47.0.en-US.mac.mochitest.tests.zip'], 16:00:45 INFO - u'mozbase': [u'firefox-47.0.en-US.mac.common.tests.zip'], 16:00:45 INFO - u'reftest': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:00:45 INFO - u'firefox-47.0.en-US.mac.reftest.tests.zip'], 16:00:45 INFO - u'talos': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:00:45 INFO - u'firefox-47.0.en-US.mac.talos.tests.zip'], 16:00:45 INFO - u'web-platform': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:00:45 INFO - u'firefox-47.0.en-US.mac.web-platform.tests.zip'], 16:00:45 INFO - u'webapprt': [u'firefox-47.0.en-US.mac.common.tests.zip'], 16:00:45 INFO - u'xpcshell': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:00:45 INFO - u'firefox-47.0.en-US.mac.xpcshell.tests.zip']} 16:00:45 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip'] for test suite category: common 16:00:45 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')]} 16:00:45 INFO - https://queue.taskcluster.net/v1/task/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:00:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 16:00:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 16:00:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 16:00:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 16:00:48 INFO - Downloaded 17747492 bytes. 16:00:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:00:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:00:51 INFO - Return code: 0 16:00:51 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip', u'firefox-47.0.en-US.mac.talos.tests.zip'] for test suite category: talos 16:00:51 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')]} 16:00:51 INFO - https://queue.taskcluster.net/v1/task/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:00:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 16:00:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 16:00:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 16:00:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 16:00:59 INFO - Downloaded 17747492 bytes. 16:00:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:00:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:01:03 INFO - Return code: 0 16:01:03 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')]} 16:01:03 INFO - https://queue.taskcluster.net/v1/task/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:01:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 16:01:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 16:01:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip 16:01:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip'}, attempt #1 16:01:04 INFO - Downloaded 10845194 bytes. 16:01:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:01:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 16:01:04 INFO - Return code: 0 16:01:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:04 INFO - https://queue.taskcluster.net/v1/task/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg matches https://queue.taskcluster.net 16:01:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg 16:01:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg 16:01:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0.en-US.mac.dmg 16:01:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg'}, attempt #1 16:01:07 INFO - Downloaded 88341506 bytes. 16:01:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg 16:01:07 INFO - mkdir: /builds/slave/test/properties 16:01:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:01:07 INFO - Writing to file /builds/slave/test/properties/build_url 16:01:07 INFO - Contents: 16:01:07 INFO - build_url:https://queue.taskcluster.net/v1/task/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg 16:01:07 INFO - Running post-action listener: _resource_record_post_action 16:01:07 INFO - Running post-action listener: set_extra_try_arguments 16:01:07 INFO - ##### 16:01:07 INFO - ##### Running populate-webroot step. 16:01:07 INFO - ##### 16:01:07 INFO - Running pre-action listener: _resource_record_pre_action 16:01:07 INFO - Running main action method: populate_webroot 16:01:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:01:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:01:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:01:07 INFO - '--tppagecycles', 16:01:07 INFO - '7'], 16:01:07 INFO - 'tests': ['tsvgm']}}, 16:01:07 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 16:01:07 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 16:01:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:01:07 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 16:01:07 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 16:01:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:01:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:01:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:07 INFO - 'talos_options': ['--e10s'], 16:01:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:01:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:07 INFO - 'tests': ['damp', 'tps']}, 16:01:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:07 INFO - 'talos_options': ['--e10s'], 16:01:07 INFO - 'tests': ['damp', 'tps']}, 16:01:07 INFO - 'g3': {'tests': ['dromaeo_dom']}, 16:01:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:01:07 INFO - 'other': {'tests': ['a11yr', 16:01:07 INFO - 'ts_paint', 16:01:07 INFO - 'tpaint', 16:01:07 INFO - 'sessionrestore', 16:01:07 INFO - 'sessionrestore_no_auto_restore', 16:01:07 INFO - 'tabpaint']}, 16:01:07 INFO - 'other-e10s': {'talos_options': ['--e10s'], 16:01:07 INFO - 'tests': ['a11yr', 16:01:07 INFO - 'ts_paint', 16:01:07 INFO - 'tpaint', 16:01:07 INFO - 'sessionrestore', 16:01:07 INFO - 'sessionrestore_no_auto_restore', 16:01:07 INFO - 'tabpaint']}, 16:01:07 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 16:01:07 INFO - 'tests': ['a11yr', 16:01:07 INFO - 'ts_paint', 16:01:07 INFO - 'tpaint', 16:01:07 INFO - 'sessionrestore', 16:01:07 INFO - 'sessionrestore_no_auto_restore', 16:01:07 INFO - 'tabpaint']}, 16:01:07 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 16:01:07 INFO - 'tests': ['a11yr', 16:01:07 INFO - 'ts_paint', 16:01:07 INFO - 'tpaint', 16:01:07 INFO - 'sessionrestore', 16:01:07 INFO - 'sessionrestore_no_auto_restore', 16:01:07 INFO - 'tabpaint']}, 16:01:07 INFO - 'other_l64': {'tests': ['a11yr', 16:01:07 INFO - 'ts_paint', 16:01:07 INFO - 'tpaint', 16:01:07 INFO - 'sessionrestore', 16:01:07 INFO - 'sessionrestore_no_auto_restore', 16:01:07 INFO - 'tabpaint']}, 16:01:07 INFO - 'other_nol64': {'tests': ['a11yr', 16:01:07 INFO - 'ts_paint', 16:01:07 INFO - 'tpaint', 16:01:07 INFO - 'sessionrestore', 16:01:07 INFO - 'sessionrestore_no_auto_restore', 16:01:07 INFO - 'tabpaint']}, 16:01:07 INFO - 'svgr': {'tests': ['tsvgx', 16:01:07 INFO - 'tsvgr_opacity', 16:01:07 INFO - 'tart', 16:01:07 INFO - 'tscrollx', 16:01:07 INFO - 'cart']}, 16:01:07 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 16:01:07 INFO - 'tests': ['tsvgx', 16:01:07 INFO - 'tsvgr_opacity', 16:01:07 INFO - 'tart', 16:01:07 INFO - 'tscrollx', 16:01:07 INFO - 'cart']}, 16:01:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:07 INFO - 'tests': ['tp5o']}, 16:01:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:07 INFO - 'talos_options': ['--e10s'], 16:01:07 INFO - 'tests': ['tp5o']}, 16:01:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:07 INFO - 'talos_options': ['--xperf_path', 16:01:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:01:07 INFO - 'tests': ['tp5n']}, 16:01:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:07 INFO - 'talos_options': ['--e10s', 16:01:07 INFO - '--xperf_path', 16:01:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:01:07 INFO - 'tests': ['tp5n']}}, 16:01:07 INFO - 'talos.zip': {'path': '', 16:01:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:01:07 INFO - Running post-action listener: _resource_record_post_action 16:01:07 INFO - ##### 16:01:07 INFO - ##### Running create-virtualenv step. 16:01:07 INFO - ##### 16:01:07 INFO - Running pre-action listener: _resource_record_pre_action 16:01:07 INFO - Running main action method: create_virtualenv 16:01:07 INFO - Creating virtualenv /builds/slave/test/build/venv 16:01:07 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 16:01:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:01:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:01:08 INFO - Using real prefix '/tools/python27' 16:01:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:01:08 INFO - Installing distribute.............................................................................................................................................................................................done. 16:01:11 INFO - Installing pip.................done. 16:01:11 INFO - Return code: 0 16:01:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:01:11 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')]} 16:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:11 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')]} 16:01:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:11 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 0x10371ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037254c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac8a596650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035c2d30>, '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 0x103426db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1035b9b20>, '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.GQ21CCjpJP/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.0FEX6JSRzu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 16:01:11 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 16:01:11 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 16:01:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GQ21CCjpJP/Render', 16:01:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:11 INFO - 'HOME': '/Users/cltbld', 16:01:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:11 INFO - 'LOGNAME': 'cltbld', 16:01:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:11 INFO - 'MOZ_NO_REMOTE': '1', 16:01:11 INFO - 'NO_EM_RESTART': '1', 16:01:11 INFO - 'PAGER': '/bin/cat', 16:01:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:11 INFO - 'PWD': '/builds/slave/test', 16:01:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:11 INFO - 'SHELL': '/bin/bash', 16:01:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0FEX6JSRzu/Listeners', 16:01:11 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 16:01:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:11 INFO - 'USER': 'cltbld', 16:01:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:11 INFO - 'XPC_FLAGS': '0x0', 16:01:11 INFO - 'XPC_SERVICE_NAME': '0', 16:01:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:11 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 16:01:11 INFO - Cleaning up... 16:01:11 INFO - Return code: 0 16:01:11 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 16:01:11 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')]} 16:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:11 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')]} 16:01:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:11 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 0x10371ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037254c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac8a596650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035c2d30>, '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 0x103426db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1035b9b20>, '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.GQ21CCjpJP/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.0FEX6JSRzu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 16:01:11 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 16:01:11 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 16:01:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GQ21CCjpJP/Render', 16:01:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:11 INFO - 'HOME': '/Users/cltbld', 16:01:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:11 INFO - 'LOGNAME': 'cltbld', 16:01:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:11 INFO - 'MOZ_NO_REMOTE': '1', 16:01:11 INFO - 'NO_EM_RESTART': '1', 16:01:11 INFO - 'PAGER': '/bin/cat', 16:01:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:11 INFO - 'PWD': '/builds/slave/test', 16:01:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:11 INFO - 'SHELL': '/bin/bash', 16:01:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0FEX6JSRzu/Listeners', 16:01:11 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 16:01:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:11 INFO - 'USER': 'cltbld', 16:01:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:11 INFO - 'XPC_FLAGS': '0x0', 16:01:11 INFO - 'XPC_SERVICE_NAME': '0', 16:01:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:12 INFO - Downloading/unpacking psutil>=0.7.1 16:01:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:01:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:01:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:01:14 INFO - Installing collected packages: psutil 16:01:14 INFO - Running setup.py install for psutil 16:01:14 INFO - building 'psutil._psutil_osx' extension 16:01:14 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 16:01:15 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 16:01:15 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 16:01:15 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 16:01:15 INFO - building 'psutil._psutil_posix' extension 16:01:15 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 16:01:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:01:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:01:15 INFO - ^ 16:01:15 INFO - 1 warning generated. 16:01:15 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 16:01:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:01:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:01:15 INFO - Successfully installed psutil 16:01:15 INFO - Cleaning up... 16:01:15 INFO - Return code: 0 16:01:15 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 16:01:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:15 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 0x10371ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037254c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac8a596650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035c2d30>, '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 0x103426db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1035b9b20>, '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.GQ21CCjpJP/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.0FEX6JSRzu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 16:01:15 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 16:01:15 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 16:01:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GQ21CCjpJP/Render', 16:01:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:15 INFO - 'HOME': '/Users/cltbld', 16:01:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:15 INFO - 'LOGNAME': 'cltbld', 16:01:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:15 INFO - 'MOZ_NO_REMOTE': '1', 16:01:15 INFO - 'NO_EM_RESTART': '1', 16:01:15 INFO - 'PAGER': '/bin/cat', 16:01:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:15 INFO - 'PWD': '/builds/slave/test', 16:01:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:15 INFO - 'SHELL': '/bin/bash', 16:01:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0FEX6JSRzu/Listeners', 16:01:15 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 16:01:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:15 INFO - 'USER': 'cltbld', 16:01:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:15 INFO - 'XPC_FLAGS': '0x0', 16:01:15 INFO - 'XPC_SERVICE_NAME': '0', 16:01:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:16 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:01:16 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 16:01:16 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 16:01:16 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 16:01:16 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 16:01:16 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 16:01:16 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 16:01:18 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:01:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:01:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:01:18 INFO - Installing collected packages: mozsystemmonitor 16:01:18 INFO - Running setup.py install for mozsystemmonitor 16:01:18 INFO - Successfully installed mozsystemmonitor 16:01:18 INFO - Cleaning up... 16:01:18 INFO - Return code: 0 16:01:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:01:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:18 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 0x10371ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037254c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac8a596650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035c2d30>, '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 0x103426db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1035b9b20>, '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.GQ21CCjpJP/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.0FEX6JSRzu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 16:01:18 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 16:01:18 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 16:01:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GQ21CCjpJP/Render', 16:01:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:18 INFO - 'HOME': '/Users/cltbld', 16:01:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:18 INFO - 'LOGNAME': 'cltbld', 16:01:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:18 INFO - 'MOZ_NO_REMOTE': '1', 16:01:18 INFO - 'NO_EM_RESTART': '1', 16:01:18 INFO - 'PAGER': '/bin/cat', 16:01:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:18 INFO - 'PWD': '/builds/slave/test', 16:01:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:18 INFO - 'SHELL': '/bin/bash', 16:01:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0FEX6JSRzu/Listeners', 16:01:18 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 16:01:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:18 INFO - 'USER': 'cltbld', 16:01:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:18 INFO - 'XPC_FLAGS': '0x0', 16:01:18 INFO - 'XPC_SERVICE_NAME': '0', 16:01:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:19 INFO - Downloading/unpacking blobuploader==1.2.4 16:01:19 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 16:01:19 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 16:01:19 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 16:01:19 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 16:01:19 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 16:01:19 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 16:01:21 INFO - Downloading blobuploader-1.2.4.tar.gz 16:01:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:01:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:01: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 16:01: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 16:01: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 16:01: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 16:01: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 16:01: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 16:01:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:01:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:01:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:22 INFO - Downloading docopt-0.6.1.tar.gz 16:01:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:01:22 INFO - Installing collected packages: blobuploader, requests, docopt 16:01:22 INFO - Running setup.py install for blobuploader 16:01:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:01:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:01:23 INFO - Running setup.py install for requests 16:01:23 INFO - Running setup.py install for docopt 16:01:23 INFO - Successfully installed blobuploader requests docopt 16:01:23 INFO - Cleaning up... 16:01:23 INFO - Return code: 0 16:01:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:01:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:23 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 0x10371ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037254c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac8a596650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035c2d30>, '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 0x103426db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1035b9b20>, '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.GQ21CCjpJP/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.0FEX6JSRzu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 16:01:23 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 16:01:23 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 16:01:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GQ21CCjpJP/Render', 16:01:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:23 INFO - 'HOME': '/Users/cltbld', 16:01:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:23 INFO - 'LOGNAME': 'cltbld', 16:01:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:23 INFO - 'MOZ_NO_REMOTE': '1', 16:01:23 INFO - 'NO_EM_RESTART': '1', 16:01:23 INFO - 'PAGER': '/bin/cat', 16:01:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:23 INFO - 'PWD': '/builds/slave/test', 16:01:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:23 INFO - 'SHELL': '/bin/bash', 16:01:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0FEX6JSRzu/Listeners', 16:01:23 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 16:01:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:23 INFO - 'USER': 'cltbld', 16:01:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:23 INFO - 'XPC_FLAGS': '0x0', 16:01:23 INFO - 'XPC_SERVICE_NAME': '0', 16:01:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:01:24 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-LJU9Xi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:01:24 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-cntcSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:01:24 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-wigJ2A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:01:24 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-nK4gFe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:01:24 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-4cXhru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:01:24 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-ShqJE_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:01:24 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-D0pNeV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:01:24 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-d192kY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:01:24 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-AwPZwc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:01:24 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-918pWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:01:25 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-wXpUgz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:01:25 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-zsYyoQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:01:25 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-mq7Wud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:01:25 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-K0QNzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:25 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-OgeraM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:01:25 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-2CgcQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:01:25 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-9pTMzs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:01:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:01:25 INFO - Running setup.py install for manifestparser 16:01:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:01:25 INFO - Running setup.py install for mozcrash 16:01:26 INFO - Running setup.py install for mozdebug 16:01:26 INFO - Running setup.py install for mozdevice 16:01:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:01:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:01:26 INFO - Running setup.py install for mozfile 16:01:26 INFO - Running setup.py install for mozhttpd 16:01:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:01:26 INFO - Running setup.py install for mozinfo 16:01:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:01:26 INFO - Running setup.py install for mozInstall 16:01:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:01:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:01:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:01:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:01:26 INFO - Running setup.py install for mozleak 16:01:27 INFO - Running setup.py install for mozlog 16:01:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:01:27 INFO - Running setup.py install for moznetwork 16:01:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:01:27 INFO - Running setup.py install for mozprocess 16:01:27 INFO - Running setup.py install for mozprofile 16:01:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:01:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:01:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:01:27 INFO - Running setup.py install for mozrunner 16:01:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:01:27 INFO - Running setup.py install for mozscreenshot 16:01:28 INFO - Running setup.py install for moztest 16:01:28 INFO - Running setup.py install for mozversion 16:01:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:01:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:01:28 INFO - Cleaning up... 16:01:28 INFO - Return code: 0 16:01:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:01:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:28 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 0x10371ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037254c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac8a596650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035c2d30>, '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 0x103426db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1035b9b20>, '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.GQ21CCjpJP/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.0FEX6JSRzu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 16:01:28 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 16:01:28 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 16:01:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GQ21CCjpJP/Render', 16:01:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:28 INFO - 'HOME': '/Users/cltbld', 16:01:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:28 INFO - 'LOGNAME': 'cltbld', 16:01:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:28 INFO - 'MOZ_NO_REMOTE': '1', 16:01:28 INFO - 'NO_EM_RESTART': '1', 16:01:28 INFO - 'PAGER': '/bin/cat', 16:01:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:28 INFO - 'PWD': '/builds/slave/test', 16:01:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:28 INFO - 'SHELL': '/bin/bash', 16:01:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0FEX6JSRzu/Listeners', 16:01:28 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 16:01:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:28 INFO - 'USER': 'cltbld', 16:01:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:28 INFO - 'XPC_FLAGS': '0x0', 16:01:28 INFO - 'XPC_SERVICE_NAME': '0', 16:01:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:01:28 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-kml5El-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:01:28 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)) 16:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:01:28 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-yua77H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:01:28 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)) 16:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:01:28 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-qODTzK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:01:28 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)) 16:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:01:28 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-Czmc7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:01:29 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)) 16:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:01:29 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-fFefjH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:01:29 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)) 16:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:01:29 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-vpfxZp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:01:29 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)) 16:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:01:29 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-Q9iHst-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:01:29 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)) 16:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:01:29 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-6hiSnK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:01:29 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)) 16:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:01:29 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-3gO_if-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:01:29 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)) 16:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:01:29 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-hN865c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:01:29 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)) 16:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:01:29 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-jUg1Aa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:01:29 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)) 16:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:01:29 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-2UiUNx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:01:29 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)) 16:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:01:29 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-XHJPKD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:01:29 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)) 16:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:01:29 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-r7V1Lg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:01:30 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)) 16:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:30 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-FPCLwr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:30 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)) 16:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:01:30 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-CNEJ0z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:01:30 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)) 16:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:01:30 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-6C21Vo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:01:30 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)) 16:01:30 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)) 16:01:30 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)) 16:01:30 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)) 16:01:30 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)) 16:01:30 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)) 16:01:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:01:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:32 INFO - Downloading blessings-1.6.tar.gz 16:01:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:01:32 INFO - Installing collected packages: blessings 16:01:32 INFO - Running setup.py install for blessings 16:01:33 INFO - Successfully installed blessings 16:01:33 INFO - Cleaning up... 16:01:33 INFO - Return code: 0 16:01:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:01:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:01:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:01:33 INFO - Reading from file tmpfile_stdout 16:01:33 INFO - Current package versions: 16:01:33 INFO - blessings == 1.6 16:01:33 INFO - blobuploader == 1.2.4 16:01:33 INFO - docopt == 0.6.1 16:01:33 INFO - manifestparser == 1.1 16:01:33 INFO - mozInstall == 1.12 16:01:33 INFO - mozcrash == 0.17 16:01:33 INFO - mozdebug == 0.1 16:01:33 INFO - mozdevice == 0.48 16:01:33 INFO - mozfile == 1.2 16:01:33 INFO - mozhttpd == 0.7 16:01:33 INFO - mozinfo == 0.9 16:01:33 INFO - mozleak == 0.1 16:01:33 INFO - mozlog == 3.1 16:01:33 INFO - moznetwork == 0.27 16:01:33 INFO - mozprocess == 0.22 16:01:33 INFO - mozprofile == 0.28 16:01:33 INFO - mozrunner == 6.11 16:01:33 INFO - mozscreenshot == 0.1 16:01:33 INFO - mozsystemmonitor == 0.0 16:01:33 INFO - moztest == 0.7 16:01:33 INFO - mozversion == 1.4 16:01:33 INFO - psutil == 3.1.1 16:01:33 INFO - requests == 1.2.3 16:01:33 INFO - wsgiref == 0.1.2 16:01:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:01:33 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')]} 16:01:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:33 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')]} 16:01:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:33 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 0x10371ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037254c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac8a596650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035c2d30>, '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 0x103426db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1035b9b20>, '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.GQ21CCjpJP/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.0FEX6JSRzu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 16:01:33 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 16:01:33 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 16:01:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GQ21CCjpJP/Render', 16:01:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:33 INFO - 'HOME': '/Users/cltbld', 16:01:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:33 INFO - 'LOGNAME': 'cltbld', 16:01:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:33 INFO - 'MOZ_NO_REMOTE': '1', 16:01:33 INFO - 'NO_EM_RESTART': '1', 16:01:33 INFO - 'PAGER': '/bin/cat', 16:01:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:33 INFO - 'PWD': '/builds/slave/test', 16:01:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:33 INFO - 'SHELL': '/bin/bash', 16:01:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0FEX6JSRzu/Listeners', 16:01:33 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 16:01:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:33 INFO - 'USER': 'cltbld', 16:01:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:33 INFO - 'XPC_FLAGS': '0x0', 16:01:33 INFO - 'XPC_SERVICE_NAME': '0', 16:01:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:34 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)) 16:01:34 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)) 16:01:34 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)) 16:01:34 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)) 16:01:34 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)) 16:01:34 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)) 16:01:34 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)) 16:01:34 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)) 16:01:34 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)) 16:01:34 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)) 16:01:34 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)) 16:01:34 INFO - Cleaning up... 16:01:34 INFO - Return code: 0 16:01:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:01:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:34 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 0x10371ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037254c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac8a596650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035c2d30>, '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 0x103426db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1035b9b20>, '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.GQ21CCjpJP/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.0FEX6JSRzu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 16:01:34 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 16:01:34 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 16:01:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GQ21CCjpJP/Render', 16:01:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:34 INFO - 'HOME': '/Users/cltbld', 16:01:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:34 INFO - 'LOGNAME': 'cltbld', 16:01:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:34 INFO - 'MOZ_NO_REMOTE': '1', 16:01:34 INFO - 'NO_EM_RESTART': '1', 16:01:34 INFO - 'PAGER': '/bin/cat', 16:01:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:34 INFO - 'PWD': '/builds/slave/test', 16:01:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:34 INFO - 'SHELL': '/bin/bash', 16:01:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0FEX6JSRzu/Listeners', 16:01:34 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 16:01:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:34 INFO - 'USER': 'cltbld', 16:01:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:34 INFO - 'XPC_FLAGS': '0x0', 16:01:34 INFO - 'XPC_SERVICE_NAME': '0', 16:01:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:34 INFO - Downloading/unpacking jsonschema 16:01:34 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 16:01:34 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 16:01:34 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 16:01:34 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 16:01:34 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 16:01:34 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 16:01:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:01:37 INFO - Downloading/unpacking functools32 (from jsonschema) 16:01:37 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 16:01:37 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 16:01:37 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 16:01:37 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 16:01:37 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 16:01:37 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 16:01:37 INFO - Downloading functools32-3.2.3-2.tar.gz 16:01:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:01:37 INFO - warning: no files found matching '*.txt' 16:01:37 INFO - no previously-included directories found matching 'build' 16:01:37 INFO - no previously-included directories found matching 'dist' 16:01:37 INFO - no previously-included directories found matching '.git*' 16:01:37 INFO - Installing collected packages: jsonschema, functools32 16:01:37 INFO - Running setup.py install for functools32 16:01:37 INFO - warning: no files found matching '*.txt' 16:01:37 INFO - no previously-included directories found matching 'build' 16:01:37 INFO - no previously-included directories found matching 'dist' 16:01:37 INFO - no previously-included directories found matching '.git*' 16:01:37 INFO - Successfully installed jsonschema functools32 16:01:37 INFO - Cleaning up... 16:01:37 INFO - Return code: 0 16:01:37 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:01:37 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')]} 16:01:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:37 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')]} 16:01:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:37 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 0x10371ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037254c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac8a596650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035c2d30>, '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 0x103426db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1035b9b20>, '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.GQ21CCjpJP/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.0FEX6JSRzu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 16:01:37 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 16:01:37 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 16:01:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GQ21CCjpJP/Render', 16:01:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:37 INFO - 'HOME': '/Users/cltbld', 16:01:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:37 INFO - 'LOGNAME': 'cltbld', 16:01:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:37 INFO - 'MOZ_NO_REMOTE': '1', 16:01:37 INFO - 'NO_EM_RESTART': '1', 16:01:37 INFO - 'PAGER': '/bin/cat', 16:01:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:37 INFO - 'PWD': '/builds/slave/test', 16:01:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:37 INFO - 'SHELL': '/bin/bash', 16:01:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0FEX6JSRzu/Listeners', 16:01:37 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 16:01:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:37 INFO - 'USER': 'cltbld', 16:01:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:37 INFO - 'XPC_FLAGS': '0x0', 16:01:37 INFO - 'XPC_SERVICE_NAME': '0', 16:01:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:38 INFO - Downloading/unpacking flake8 16:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:40 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:01:41 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:01:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:41 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:01:41 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:01:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:41 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:01:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:01:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:01:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:01:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:01:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:01:42 INFO - no previously-included directories found matching 'docs/_build' 16:01:42 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:01:42 INFO - Running setup.py install for pep8 16:01:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:01:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:01:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:01:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:01:42 INFO - no previously-included directories found matching 'docs/_build' 16:01:42 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:01:42 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:01:42 INFO - Cleaning up... 16:01:42 INFO - Return code: 0 16:01:42 INFO - Running post-action listener: _resource_record_post_action 16:01:42 INFO - Running post-action listener: _start_resource_monitoring 16:01:42 INFO - Starting resource monitoring. 16:01:42 INFO - ##### 16:01:42 INFO - ##### Running install step. 16:01:42 INFO - ##### 16:01:42 INFO - Running pre-action listener: _resource_record_pre_action 16:01:42 INFO - Running main action method: install 16:01:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:01:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:01:43 INFO - Reading from file tmpfile_stdout 16:01:43 INFO - Detecting whether we're running mozinstall >=1.0... 16:01:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:01:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:01:43 INFO - Reading from file tmpfile_stdout 16:01:43 INFO - Output received: 16:01:43 INFO - Usage: mozinstall [options] installer 16:01:43 INFO - Options: 16:01:43 INFO - -h, --help show this help message and exit 16:01:43 INFO - -d DEST, --destination=DEST 16:01:43 INFO - Directory to install application into. [default: 16:01:43 INFO - "/builds/slave/test"] 16:01:43 INFO - --app=APP Application being installed. [default: firefox] 16:01:43 INFO - mkdir: /builds/slave/test/build/application 16:01:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:01:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0.en-US.mac.dmg --destination /builds/slave/test/build/application 16:02:05 INFO - Reading from file tmpfile_stdout 16:02:05 INFO - Output received: 16:02:05 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 16:02:05 INFO - Running post-action listener: _resource_record_post_action 16:02:05 INFO - ##### 16:02:05 INFO - ##### Running run-tests step. 16:02:05 INFO - ##### 16:02:05 INFO - Running pre-action listener: _resource_record_pre_action 16:02:05 INFO - Running main action method: run_tests 16:02:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:02:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:02:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:02:05 INFO - 2016-05-09 16:02:05.804 screenresolution[1637:7546] starting screenresolution argv=screenresolution get 16:02:05 INFO - 2016-05-09 16:02:05.806 screenresolution[1637:7546] Display 0: 1600x1200x32@60 16:02:05 INFO - 2016-05-09 16:02:05.812 screenresolution[1638:7549] starting screenresolution argv=screenresolution list 16:02:05 INFO - Available Modes on Display 0 16:02:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:02:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:02:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:02:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:02:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:02:06 INFO - Intel Iris: 16:02:06 INFO - Chipset Model: Intel Iris 16:02:06 INFO - Type: GPU 16:02:06 INFO - Bus: Built-In 16:02:06 INFO - VRAM (Dynamic, Max): 1536 MB 16:02:06 INFO - Vendor: Intel (0x8086) 16:02:06 INFO - Device ID: 0x0a2e 16:02:06 INFO - Revision ID: 0x0009 16:02:06 INFO - Displays: 16:02:06 INFO - Display: 16:02:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:02:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:02:06 INFO - Display Serial Number: 1600x1200 60 16:02:06 INFO - Main Display: Yes 16:02:06 INFO - Mirror: Off 16:02:06 INFO - Online: Yes 16:02:06 INFO - Rotation: Supported 16:02:06 INFO - Return code: 0 16:02:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:02:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:02:06 INFO - Python 2.7.3 16:02:06 INFO - Return code: 0 16:02:06 INFO - grabbing minidump binary from tooltool 16:02:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:06 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035c2d30>, '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 0x103426db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1035b9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:02:06 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:02:06 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 16:02:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:02:06 INFO - Return code: 0 16:02:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:02:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:02:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:02:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:02:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:02:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:02:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:02:07 INFO - Return code: 0 16:02:07 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/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0088', '--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 16:02:07 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/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0088 --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 16:02:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GQ21CCjpJP/Render', 16:02:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:02:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:02:07 INFO - 'HOME': '/Users/cltbld', 16:02:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:02:07 INFO - 'LOGNAME': 'cltbld', 16:02:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:02:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:02:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:07 INFO - 'MOZ_NO_REMOTE': '1', 16:02:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:02:07 INFO - 'NO_EM_RESTART': '1', 16:02:07 INFO - 'PAGER': '/bin/cat', 16:02:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:02:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:07 INFO - 'PWD': '/builds/slave/test', 16:02:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:02:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:02:07 INFO - 'SHELL': '/bin/bash', 16:02:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0FEX6JSRzu/Listeners', 16:02:07 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 16:02:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:02:07 INFO - 'USER': 'cltbld', 16:02:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:02:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:02:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:07 INFO - 'XPC_FLAGS': '0x0', 16:02:07 INFO - 'XPC_SERVICE_NAME': '0', 16:02:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:02:07 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/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0088', '--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 16:02:07 INFO - mozversion application_buildid: 20160509134909 16:02:07 INFO - mozversion application_changeset: beaf4d37d01c85e87cee2682604083a889beeb29 16:02:07 INFO - mozversion application_display_name: Firefox 16:02:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:02:07 INFO - mozversion application_name: Firefox 16:02:07 INFO - mozversion application_remotingname: firefox 16:02:07 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:02:07 INFO - mozversion application_vendor: Mozilla 16:02:07 INFO - mozversion application_version: 47.0 16:02:07 INFO - mozversion platform_buildid: 20160509134909 16:02:07 INFO - mozversion platform_changeset: beaf4d37d01c85e87cee2682604083a889beeb29 16:02:07 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:02:07 INFO - mozversion platform_version: 47.0 16:02:07 INFO - using testdate: 1462834927 16:02:07 INFO - actual date: 1462834927 16:02:07 INFO - starting webserver on 'localhost:49275' 16:02:07 INFO - SUITE-START | Running 2 tests 16:02:07 INFO - TEST-START | dromaeo_css 16:02:07 INFO - operating with platform_type : mac_ 16:02:07 INFO - Initialising browser for dromaeo_css test... 16:02:07 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmpcgE_db/profile http://localhost:49275/getInfo.html) 16:02:09 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 16:02:09 INFO - PROCESS | 1656 | colorDepth:24 16:02:09 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 16:02:09 INFO - PROCESS | 1656 | __metrics 16:02:09 INFO - PROCESS | 1656 | 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" 16:02:09 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:02:10 INFO - TEST-INFO | 1656: exit 0 16:02:10 INFO - Browser initialized. 16:02:10 INFO - Running cycle 1/1 for dromaeo_css test... 16:02:10 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmpcgE_db/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:02:22 INFO - PROCESS | 1659 | RSS: Main: 156872704 16:02:22 INFO - PROCESS | 1659 | 16:03:13 INFO - PROCESS | 1659 | 1462834993111 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] 16:05:22 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 16:05:24 INFO - PROCESS | 1659 | RSS: Main: 309477376 16:05:24 INFO - PROCESS | 1659 | 16:08:26 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 16:08:27 INFO - PROCESS | 1659 | RSS: Main: 292470784 16:08:27 INFO - PROCESS | 1659 | 16:11:29 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 16:11:30 INFO - PROCESS | 1659 | RSS: Main: 297029632 16:11:30 INFO - PROCESS | 1659 | 16:14:31 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 16:14:32 INFO - PROCESS | 1659 | RSS: Main: 253820928 16:14:32 INFO - PROCESS | 1659 | 16:17:35 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 16:17:36 INFO - PROCESS | 1659 | RSS: Main: 256114688 16:17:36 INFO - PROCESS | 1659 | 16:20:38 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 16:20:38 INFO - PROCESS | 1659 | RSS: Main: 303476736 16:20:38 INFO - PROCESS | 1659 | 16:20:38 INFO - PROCESS | 1659 | __start_tp_report 16:20:38 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 16:20:38 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 16:20:38 INFO - PROCESS | 1659 | |i|pagename|runs| 16:20:38 INFO - PROCESS | 1659 | |0;dojo.html;3587.4618807178927;3647.069997150726;3651.2514934438427;3689.3359195344838;3725.5343082114737;5937.614055086417;5962.970185149074;5970.7313170907355;6002.369751176118;6009.1286763419275;5938.7624495020145;5966.224390829186;5972.9402705972925;5976.073708575167;5991.550633702468;5227.372715274172;5317.574594032468;5341.30563026806;5389.407165211824;5437.429069947658;5409.43200963897;5419.403865574784;5425.83722488324;5426.294581704373;5434.673919564819;5352.116900711378;5404.135338345865;5407.459254074581;5419.972900135494;5420.674759514442;5423.593230507708;5426.538744206747;5445.53712934401;5456.699881506515;5502.504774570268;5183.559397451221;5359;5360.088784906575;5363.195520671891;5364.007658583175;5789.478946894757;5830.300363956352;5855.180274761537;5866.384029676865;5897.292324920994;6200.100985357143;6327.715252813635;6330.81007569769;6334.334894836022;6342.556021078526;6278.529110316745;6283.528735344822;6290.182276304097;6307.0539419087045;6335.176427064498;6011.27864656244;6044.546659000593;6047.304559975615;6059.969700151471;6063.514918806485;6268.2478102627065;6423.357664233633;6443.67781610927;6446.613203207822;6456.483481321577;6202.255729312511;6322.241331040213;6328.651924144124;6330.240371155399;6339.714712837934;6305.936940630535;6331.525135614847;6338.619682819046;6343.873122537523;6345.555811093272;6225.968870155621;6289.339619339995;6316.715747791361;6321.747130114836;6358.618482890949;5683.8010669626365;5756.251687280626;6216.875662486725;6311.1795466589965;6329.968350158219;5057.266696329012;5068.087744206078;5068.189089745623;5071.644984851025;5086.771095300646;4553.977230113895;4568;4576.954230457719;4588.426446694163;4625.930611040837;5885.293764748172;5906.645601263938;5936.673482958478;6020.187274717945;6068.605540639845;6304.810855674337;6329.145565348619;6338.080978258126;6339.2392912850755;6353.459955903708;5980.8803823924145;6022.277326720734;6036.9698151508965;6038.969805150946;6045.637261764308;5426.945730542723;5461.5357694596405;5507.421720719267;5572.247746554244;5582.469665381782;6523.934760652428;6551.6069035857045;6556.508261880474;6562.967185164043;6564.6389448580785;5158.865049689062;6232.844178895564;6337.96831015842;6341.302456729663;6346.3019067903415;5433.212184233185;6078.118672792509;6387.361263873576;6508.674566271573;6520.967395162994;5629.240052593012;6279.183706118175;6282.937170628235;6342.904856427065;6345;6293.402126797947;6322.272938612118;6328.525360598066;6345.302016778059;6368.363163683594;6128.747575574743;6232.127502149793;6238.344973777869;6241.563090583797;6279.591826531261;5103.566196873159;6237.5321850860455;6238.095476155841;6270.529710271657;6285.779997700059;6178.320384757763;6180.876382472416;6184.257889053342;6186.19579454668;6193.783217587363;5754.367019627922;6191.288001879661;6221.066839973859;6263.185785847811;6280.497560195082;6162.383761623981;6214.533909956681;6227.844303892439;6232;6234.251889773075 16:20:38 INFO - PROCESS | 1659 | |1;ext.html;503.87403149212696;576.2710171632885;600.2137200267653;668.7592466711984;682.7610336382266;31996.720131194754;32413.86551470699;32445.513317300258;41125;41793.58206417935;44696.776516117454;44920.65238042864;45098;45288.54711452885;45291;9809.411435313887;10051.698449046504;10057;10075.345102568346;10081.949590252076;9765.414075155479;9897.75255618608;9920.652777152802;9923.553440095213;9969.252306077036;9885.851712224323;9971.551280192372;9978.550965206547;10040.196784257241;10079.042490963348;7517.812054698622;9525.809483810284;9971.501424928761;10035.096841284283;10040;9863.556139973718;9867.50662466881;9954.054364835329;9958.452285124315;9963.900360996442;43102.78448607737;43388;43393.34909976353;43428.78285608552;43441;3398.915027124317;3527.6825085742407;3574.964250357515;3588.3540962626716;3606.332828426723;22635.54728905429;27433.725662743316;28330.858345708344;28855;28975.420491590052;2787.456445993032;2794.9798323611735;2825.4349130174046;2837.276494493891;2867.842268675222;496032;500190;500508;500941;501272;49456.75271623619;49985;75347;75916;76118.61940690261;41213.793931030756;48048;48165;48208.27687584689;48312.7584362083;16745.91627041857;17463.65072698539;17483.912580437016;17491.825081749274;17537.912310438365;14039.438422462987;14115.576532704035;14143.504977325743;14158.433662653584;14165;15420.46028389001;15674.451394201149;15681.05913645162;15697.921510392374;15730.056196628238;5595.328560572757;6017.969910150421;6203.286622038433;6551.541392102602;6650.9667451662435;971.8007808399261;1015.1726343030407;1062.946852657364;1065.9093977012042;1100.2738192792879;29943.401131977673;31200.195844303835;31297.8435107823;31334.529982050288;32233.51649725256;1181.6691326428613;1185.4191446191305;1188.2810899405736;1193.8806119387991;1212.175720510044;15085.170315632746;15916.442924497587;15920.203989800464;15970;16245.675086498204;310739;313266;315112;315734;315864;64635.353646463875;65081;65141.02288465677;65181.674091630186;65459;1441.1929319581068;1553.3320672111101;1569.1526575649023;1580.217792192849;1583.406222666477;29871;30205.395892082473;30324.69675303219;30697.61433979533;31629.8418507906;52076.47923520716;52209.738951305;52700.20949685835;52755.208671869186;52816.73591632017;382122;382794;383407;383482;383825;30508.847455762578;35011.29977400489;35563.46654800232;35677.929662110175;36122.27755444929;46297;46850.29724554066;47386.28920566263;47479.252074792246;47505.04989900113;38293.80853095717;38348;38537.03657408587;38651.61348386593;39180.2163956725;37716.86849394523;37963.810180948916;38532.036699081626;38762.80618596889;38967.6103238964;38906.4164037534;40106.59893401029;40441;41079.794601028;42027;31737.514239463093;34374.31251375008;34673.30653386968;34941.126471838405;35704.107397315274;23743.52512949766;24163.51672966566;25655.230343089737;25770.484590308464;25897 16:20:38 INFO - PROCESS | 1659 | |2;jquery.html;346.14329534402356;360.2093404976077;370.7738279649415;377.62803638416153;384.22770231834005;20525.794742052592;20896.582068358643;21214.787852121473;22037.11851525941;22072.78599677019;21202.681959770616;21274.36176914695;21603.783962160374;21608.243711470102;21646.350609481742;5715.657060576368;6014.909776353358;6040.063790112539;6042.758289668408;6049.939500605004;4162.8126734296875;4357.956420435803;5679.290088738908;5910.734016969225;6167.074938759177;5304.496072873071;5994.5204383649425;6132.080187971817;6138.662373569452;6148.323684394713;6104.4200800923845;6330.778422755205;6598.241202261754;6629.867402651968;6637.3030831762935;5846.824595262149;5862.736176872008;5863.325717542494;5872.647641141502;5878.265216847894;10972.890271097267;11666.941665291704;14099.36552855124;14110.50613228532;14121.858781412157;2220.400491867203;2242.372135801978;2338.4504641409426;2429.8056155507516;2637.709851916307;10105;10688.625898093607;15538.689226215525;15670.921645391814;16279.755803662954;1883.9340623078126;1908.885466871992;1921.8270355668058;1933.7002764571514;1956.3739603326942;116748;121124;121505.78494215033;125075.37462312721;133612;20044.69932951007;20386.28647997313;20584.073716682786;21126.154953801677;21233.575328493345;31043;31187;31870;31877.681223187934;31937.680623193934;11628.127890408254;11743.236689615147;11837.467313970892;11851.051915846712;11862.703432414259;8468.49189048659;9903.405795652285;10048.447335396624;10049.24630652704;10167.949160254151;7727.768166955;8909.55452227386;9050.86423703645;9366.438013719198;9440.716778496657;3028.6668466468673;3490.616032236452;3567;3661.649590624602;3679.3745063339297;923.7921467669855;937.6530683647094;1038.99480502597;1042.3224903812431;1127.610974213895;20175.39473815788;20683;20857.923713708464;20898.895505522374;20918.89540552318;986.5990293782702;1059.6171449038864;1068.3589846092439;1089.1722291058852;1092.186321190718;9819.607215711292;11333.07337752584;11335;11355.716107097389;11362.659120226406;348568;675578;684478;702520;706253;121051.39474302572;124699.3765031169;124878.37560812138;125110;125335;912.4616476279027;1006.731518286949;1064.0529928363762;1085.6580177244143;1087.2335003822286;14825;17764.46706598804;18653;18776.906115469883;18849.528761781065;48153;48422.5157748418;49635.00729985452;50407;50687.493125069755;71182;73165.6341718288;73558;74172.62913685397;74503.88244176234;8393.916060839558;8497.192766687154;8585.484870907765;8614.82770344602;8899.510526921082;10066.44634545107;10686.25196236256;13090;13364.398602063126;13853.653658658613;11519.8272025918;11542.769144617228;11709.66714444993;11790.351530665896;12034.819477707666;11228.663140105484;11453.770924581628;11602.94198529002;11980;12047.518099276282;10132.138768204784;11615.070794336601;11945.641630751092;11971.820422693841;12076.637700868989;8427.915720842713;8456.873146902679;8596;8610.61252243638;8681.696140635047;8643.913560864563;11113.166512511432;11854.99232565207;11863.34751588671;12039.939800300943 16:20:38 INFO - PROCESS | 1659 | |3;mootools.html;1519.4226194046262;1667.4747454551818;1699.3202718912437;2035.4199053269813;2092.9895350523257;7373.668184931677;8307.83384332313;9136.771580710469;9148.99999999999;9168.358214924958;8740.825183496327;8987.865182022259;8993.685221017266;8996.460212387261;9005;2573.4338445541953;2596.9610455843085;2600.921972340833;2602.0632572273967;2606.817522773407;2534.087728417768;2603.5834266517363;2607.778338841201;2614.712381638018;2621.318457201132;2598.8310759800647;2602.9219123426233;2620.6724159480063;2628.68455785306;2636.314558214868;2588.8964441422413;2602.6096085587124;2623.0163688616767;2623.5933430318332;2628.7108418073994;2586.2112055822968;2616.515944550264;2624.9868750656315;2626.3828000419885;2629.1192450529097;9071.410358326688;9094.317926155565;9168.495732734697;9173.03683113277;9174.587143578488;5607.663540187602;5764.250647415851;5769.24999750034;5822.737976791054;5828.912566311508;3659.1766852457986;3723.311187430334;3794.0704527390967;3808.428735689641;3822.9235415291537;1071.608862765093;1077.805994920914;1084.078533246742;1087.7335052912088;1126.8535090438272;12410;12442.56451024219;12448.377581120907;12450.128491005724;12459.688507787287;5040.57155141817;5079.492050794891;5169.715665638426;5196.402413722471;5196.558292545176;10575.15398768112;10575.312604680672;10615.89384106149;10616.787664246673;10617.734556636145;5739.196512488255;5750.1949727038245;5766.163906233573;5790.507806836466;5815.563832712563;4622.399088118523;4635.884102897455;4787.664863459524;4790.808367665324;5239.528442440199;4102.159057393227;4119.176164767058;4180.080382315886;4180.247555439989;4215.683823713233;1198.8681245063035;1359.463012110211;1384.9097379873558;1392.5126205828083;1401.5655146904492;1419.5386499387741;1438.3024233246867;1475.2255066090174;1490.6124407654088;1498.5204734485078;8417.789555261044;8448.281896038905;8466.66133354659;8478.982522097265;8494.235518803338;530.9893802123936;548.4460694698383;550.5127961753797;551.8565173054955;552.2958228258922;6339.778107766206;6375.458086062737;6403.519736019817;6430.646314452656;6439.710213040425;8780.42927209742;11223.494942727597;11230.831537526943;11239;11239.775204495865;10683.786324273471;10949.233553651327;11032;11070.391128487849;11086.334819910831;8604.139586041345;8634.309255259564;8693.304535637133;8708.69519566812;8709.564521773886;8110;8159.673613055411;8186.8771968421715;8238.340932725487;8260.586970651564;9614.51927403613;9915.801683966425;9932.900670993198;9979;10051.74870628211;10092.798144037224;10363.32638378503;10434.843477347695;10469;10471.528781204712;6522.869542609026;7098.929010710033;7259.455540834564;7553.5845528494265;7731.729389471567;7969.960150199212;8049.758507244792;8330.750077497685;8572.185642363966;8593.742187734379;6484.675766211765;7441.55350678961;7510.436717246119;7552.39580833543;7707.421943354158;7450.664720087718;7466.813329666802;7480.401567874448;7510.248975102446;7550.5469671819865;7988.121306656147;8041.316488098577;8054.993125859268;8056.758297251092;8057.274845263954;7101.39638130744;7275.745348912763;7368.263173682589;7368.557886526825;7377.889331660137;7333.156686981066;7410.703571857065;7428.814279642836;7432.591207483641;7438.070241219848 16:20:38 INFO - PROCESS | 1659 | |4;prototype.html;332.43983887150154;336.8517852145056;341.16584949797726;341.7436922308268;345.6957877068181;6482.870342593157;6483.222013358398;6501.80494585162;6525.706343214555;6536.248331441886;6407.551471397004;6431.581947173442;6432.871342573146;6435.195600549931;6444.838879028027;4625.098105869357;4705.647076469261;4745.928811067837;4822.541858523452;4839.201531747258;4838.129136755382;4850.320955066293;4859.975700121495;4869.14789911764;4888.926666099994;4802.735849528292;4804.399450068741;4809.278608208761;4810.206315957863;4814.807407703687;4567.748773817439;4651.534846515338;4748.978969521535;4752.097101550694;4768.1178981888115;4125.195586860564;4126.442930204414;4139.675303902753;4142.357934520154;4142.772147531885;6116.388361163892;6122.602030867979;6123.846903827395;6139.416755408274;6146.446819786241;5609.1305847593685;5624.184493248497;5628.831135065913;5633.352164501094;5633.352164501094;6764.323567643246;6809.761658341934;6811.114555107804;6820.556663816836;6840.555363901381;4371.409859668967;4573.268277075652;4578.954210457886;4579.5878370946775;4597.42532183477;7113.715451381939;7232.0959880015525;7263.89104163438;7282.016927714797;7306.342429181399;4614.446266448048;4615.792289346988;4616;4618.676692631483;4622.46841613212;3829.9425508616837;4644.9767751161025;4656.8602941911795;4662.277347011219;4669.392978912788;5495.505404513613;5497.147942068906;5497.945020549743;5508.118701007819;5512.641678290898;6580.901286480706;6821.079154314203;6839.863202736017;6849.006894000442;6871.312868713088;1591.3316407109041;1606.2290100751704;1619.8866079374566;1620.9513714588581;1622.8945118567256;983.3165949665057;1010.2524132142162;1034.270839058462;1037.9377237365782;1060.8833028366873;6289.308176100625;6405.455536279375;6413.935860641427;6438.130852334969;6439.774607888795;5775.942240577624;7084.610346430893;7084.681189346492;7132.465065120136;7145.249748776304;6391.776287829904;6392.520560957947;6398.232212134573;6412.743490260337;6509.251436084816;3730.515033045741;3776.905577360588;3783.319002579507;3785.5078839750654;3796.867109651149;7161.4986950912935;7458;7498.4376171787335;7522.887156692654;7529.849403011909;7289.2346303638415;7347.742829000959;7352.926470735224;7365.558066516026;7366.337029667356;5976.521878249817;7441.144268409094;7449.553026818517;7455.179930207672;7479.214682458372;5753.223314852441;6045.697715114139;7109.928900710926;7128.0754196367725;7131.144262688511;6758.155230596176;6995.965020174866;7019.789406317818;7029.578225306498;7038.648067596498;7430.554166750012;7432.479726419098;7465.7013719450615;7469.701211951461;7471.103467583925;4613.746243956481;4624.722516649012;4625.121226966866;4628.722276663411;4628.976855115838;5740.9953258180485;6699.162604674416;6872.140982377203;7118.25258347866;7145.356917877416;5845.561582881216;6572.572782769104;6891.37977582;6902.792916212522;6903.1716194057035;6792.72829086831;6848.931510684829;6862.451003919774;6899.551529150589;6912.377885990285;6135.202423684892;6280.466160376419;6718.529702920749;6742.123523941857;6759.695813688298;6851.657417129024;6858.359531580477;6880.208775990825;6905.792826215222;7054.6472676367;5978.073398623221;6213;6527.911068458237;6614.735410583523;6715.731370745311 16:20:38 INFO - PROCESS | 1659 | |5;yui.html;685.5098604497783;767.6660652616109;770.8535378278128;778.9415793815465;794.2335646101515;15829.20853957302;15949;16014.679706405894;16035.919820400897;16128.677426451479;14620.926895365508;14685.265736713174;14699.779503307433;14714.264286785672;14719.04326218795;827.2472050434106;840.5713086325957;989.1098011789411;989.4113002763344;1017.9134773544257;1009.6213919780082;1021.764994051365;1022.7852151048289;1023.52406131149;1025.174734338857;1040.15747244732;1051.1223128687554;1059.9576016959313;1066.1311031509333;1067.0503252105632;859.1365677494141;1037.9896201038043;1055.2613170780446;1058.3015209961427;1062.6705721226406;799.5202878273047;800.8878756974028;801.6993627389729;802.3541049455187;802.3661307567015;15731;15781;15786.763198552031;15800.762988555181;15829.44596939113;13386.062975591713;13396.39716212763;13649.181049137083;13679.931600342034;13733.656658583515;10370.79258414835;10470.110040646554;10542.262041657086;10625.84061239082;10674.039336459678;4107.589241075899;4218.4094226807965;4228.78856057199;4230.978845105786;4323.632491238249;79720.80418793723;79946.60026699888;81020.18979810244;81275.56173314751;81467;9630.711078667651;9734.36726612768;9778.119969202806;9966.501674916226;10031.69904902854;22474.887625562096;22524.887375563016;22549;22590.661140082913;22619.773802261767;7668.539887606761;7711.768646940601;7936.6031698414845;8107.027156741229;8156.265936065783;4277.593628605277;4346.239408103569;4366.454193225847;4368;4445.2887537993765;3726.888193354204;3751.4935483709896;3781.111438811877;3783.564890037626;3783.621637836194;590.5275779376482;670.4301343857779;674.9493787965922;689.5517913356358;696.5472442912147;615.8860610787019;616.852366997406;622.4180391334118;629.235478893147;632.1592282264577;7720.7683769487;8440.240378365976;8545.102764209792;8567.186117318844;8583;558.7262241501635;580.451473357673;589.7965202005302;620.2061361457341;633.2116514938931;3105.2702614885484;3357.1942733743726;3833.3291673957506;4015.558288588253;4225.640820530228;91539.5423022894;91561;92906;93334;93658.53170734103;63547.72904541883;83877.58061209654;84318.31363372698;84628.73056904151;85066;72519;74230.25769742341;74557.6272118625;75074;76294;7701.691932322644;8648.697295594624;8670.869936951078;8748.387612867038;8899.332550058642;52547;52617.738226177076;54009.72995134999;54216;54468.727656361465;64007.71984560376;69493.65253173701;74020.62989685232;75044.62477687576;76432.61783691046;11157.888421115686;11178.832317515082;11399;11428.371439570903;11655.475503602189;12014.159008869296;12117.81823272634;12184.634460966185;12427.316497592357;12910.612681619567;11651.359175245103;11707.590234341757;11728.179027467995;11976.940115299367;12069.517219311481;11609.187356884937;11636.36000020007;11904.107191960811;11978.880211197777;12030.458629361525;11462.713432164262;11940.343281119274;11944;12011.939940300243;12085.697857553632;9956.900430995598;9985.051420115078;10068.748281293027;10225.233107517115;10259.897401025893;9463.763405914908;9999.650012249536;10086.495675216356;10119.645812396826;10248 16:20:38 INFO - PROCESS | 1659 | __end_tp_report 16:20:38 INFO - PROCESS | 1659 | __start_cc_report 16:20:38 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1331 16:20:38 INFO - PROCESS | 1659 | __end_cc_report 16:20:38 INFO - PROCESS | 1659 | __startTimestamp1462836038827__endTimestamp 16:20:38 INFO - PROCESS | 1659 | 16:20:38 INFO - PROCESS | 1659 | ------- Summary: start ------- 16:20:38 INFO - PROCESS | 1659 | Number of tests: 6 16:20:38 INFO - PROCESS | 1659 | 16:20:38 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5880.64 Median:6078.12 stddev:611.29 (10.1%) stddev-sans-first:586.09 16:20:38 INFO - PROCESS | 1659 | Values: 3587.5 3647.1 3651.3 3689.3 3725.5 5937.6 5963.0 5970.7 6002.4 6009.1 5938.8 5966.2 5972.9 5976.1 5991.6 5227.4 5317.6 5341.3 5389.4 5437.4 5409.4 5419.4 5425.8 5426.3 5434.7 5352.1 5404.1 5407.5 5420.0 5420.7 5423.6 5426.5 5445.5 5456.7 5502.5 5183.6 5359.0 5360.1 5363.2 5364.0 5789.5 5830.3 5855.2 5866.4 5897.3 6200.1 6327.7 6330.8 6334.3 6342.6 6278.5 6283.5 6290.2 6307.1 6335.2 6011.3 6044.5 6047.3 6060.0 6063.5 6268.2 6423.4 6443.7 6446.6 6456.5 6202.3 6322.2 6328.7 6330.2 6339.7 6305.9 6331.5 6338.6 6343.9 6345.6 6226.0 6289.3 6316.7 6321.7 6358.6 5683.8 5756.3 6216.9 6311.2 6330.0 5057.3 5068.1 5068.2 5071.6 5086.8 4554.0 4568.0 4577.0 4588.4 4625.9 5885.3 5906.6 5936.7 6020.2 6068.6 6304.8 6329.1 6338.1 6339.2 6353.5 5980.9 6022.3 6037.0 6039.0 6045.6 5426.9 5461.5 5507.4 5572.2 5582.5 6523.9 6551.6 6556.5 6563.0 6564.6 5158.9 6232.8 6338.0 6341.3 6346.3 5433.2 6078.1 6387.4 6508.7 6521.0 5629.2 6279.2 6282.9 6342.9 6345.0 6293.4 6322.3 6328.5 6345.3 6368.4 6128.7 6232.1 6238.3 6241.6 6279.6 5103.6 6237.5 6238.1 6270.5 6285.8 6178.3 6180.9 6184.3 6186.2 6193.8 5754.4 6191.3 6221.1 6263.2 6280.5 6162.4 6214.5 6227.8 6232.0 6234.3 16:20:38 INFO - PROCESS | 1659 | 16:20:38 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:56328.56 Median:37840.34 stddev:107527.66 (284.2%) stddev-sans-first:107747.62 16:20:38 INFO - PROCESS | 1659 | Values: 503.9 576.3 600.2 668.8 682.8 31996.7 32413.9 32445.5 41125.0 41793.6 44696.8 44920.7 45098.0 45288.5 45291.0 9809.4 10051.7 10057.0 10075.3 10081.9 9765.4 9897.8 9920.7 9923.6 9969.3 9885.9 9971.6 9978.6 10040.2 10079.0 7517.8 9525.8 9971.5 10035.1 10040.0 9863.6 9867.5 9954.1 9958.5 9963.9 43102.8 43388.0 43393.3 43428.8 43441.0 3398.9 3527.7 3575.0 3588.4 3606.3 22635.5 27433.7 28330.9 28855.0 28975.4 2787.5 2795.0 2825.4 2837.3 2867.8 496032.0 500190.0 500508.0 500941.0 501272.0 49456.8 49985.0 75347.0 75916.0 76118.6 41213.8 48048.0 48165.0 48208.3 48312.8 16745.9 17463.7 17483.9 17491.8 17537.9 14039.4 14115.6 14143.5 14158.4 14165.0 15420.5 15674.5 15681.1 15697.9 15730.1 5595.3 6018.0 6203.3 6551.5 6651.0 971.8 1015.2 1062.9 1065.9 1100.3 29943.4 31200.2 31297.8 31334.5 32233.5 1181.7 1185.4 1188.3 1193.9 1212.2 15085.2 15916.4 15920.2 15970.0 16245.7 310739.0 313266.0 315112.0 315734.0 315864.0 64635.4 65081.0 65141.0 65181.7 65459.0 1441.2 1553.3 1569.2 1580.2 1583.4 29871.0 30205.4 30324.7 30697.6 31629.8 52076.5 52209.7 52700.2 52755.2 52816.7 382122.0 382794.0 383407.0 383482.0 383825.0 30508.8 35011.3 35563.5 35677.9 36122.3 46297.0 46850.3 47386.3 47479.3 47505.0 38293.8 38348.0 38537.0 38651.6 39180.2 37716.9 37963.8 38532.0 38762.8 38967.6 38906.4 40106.6 40441.0 41079.8 42027.0 31737.5 34374.3 34673.3 34941.1 35704.1 23743.5 24163.5 25655.2 25770.5 25897.0 16:20:38 INFO - PROCESS | 1659 | 16:20:38 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:36441.40 Median:21253.97 stddev:106155.61 (499.5%) stddev-sans-first:106418.80 16:20:38 INFO - PROCESS | 1659 | Values: 346.1 360.2 370.8 377.6 384.2 20525.8 20896.6 21214.8 22037.1 22072.8 21202.7 21274.4 21603.8 21608.2 21646.4 5715.7 6014.9 6040.1 6042.8 6049.9 4162.8 4358.0 5679.3 5910.7 6167.1 5304.5 5994.5 6132.1 6138.7 6148.3 6104.4 6330.8 6598.2 6629.9 6637.3 5846.8 5862.7 5863.3 5872.6 5878.3 10972.9 11666.9 14099.4 14110.5 14121.9 2220.4 2242.4 2338.5 2429.8 2637.7 10105.0 10688.6 15538.7 15670.9 16279.8 1883.9 1908.9 1921.8 1933.7 1956.4 116748.0 121124.0 121505.8 125075.4 133612.0 20044.7 20386.3 20584.1 21126.2 21233.6 31043.0 31187.0 31870.0 31877.7 31937.7 11628.1 11743.2 11837.5 11851.1 11862.7 8468.5 9903.4 10048.4 10049.2 10167.9 7727.8 8909.6 9050.9 9366.4 9440.7 3028.7 3490.6 3567.0 3661.6 3679.4 923.8 937.7 1039.0 1042.3 1127.6 20175.4 20683.0 20857.9 20898.9 20918.9 986.6 1059.6 1068.4 1089.2 1092.2 9819.6 11333.1 11335.0 11355.7 11362.7 348568.0 675578.0 684478.0 702520.0 706253.0 121051.4 124699.4 124878.4 125110.0 125335.0 912.5 1006.7 1064.1 1085.7 1087.2 14825.0 17764.5 18653.0 18776.9 18849.5 48153.0 48422.5 49635.0 50407.0 50687.5 71182.0 73165.6 73558.0 74172.6 74503.9 8393.9 8497.2 8585.5 8614.8 8899.5 10066.4 10686.3 13090.0 13364.4 13853.7 11519.8 11542.8 11709.7 11790.4 12034.8 11228.7 11453.8 11602.9 11980.0 12047.5 10132.1 11615.1 11945.6 11971.8 12076.6 8427.9 8456.9 8596.0 8610.6 8681.7 8643.9 11113.2 11855.0 11863.3 12039.9 16:20:38 INFO - PROCESS | 1659 | 16:20:38 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:6257.00 Median:539.72 stddev:3291.72 (609.9%) stddev-sans-first:3281.69 16:20:38 INFO - PROCESS | 1659 | Values: 1519.4 1667.5 1699.3 2035.4 2093.0 7373.7 8307.8 9136.8 9149.0 9168.4 8740.8 8987.9 8993.7 8996.5 9005.0 2573.4 2597.0 2600.9 2602.1 2606.8 2534.1 2603.6 2607.8 2614.7 2621.3 2598.8 2602.9 2620.7 2628.7 2636.3 2588.9 2602.6 2623.0 2623.6 2628.7 2586.2 2616.5 2625.0 2626.4 2629.1 9071.4 9094.3 9168.5 9173.0 9174.6 5607.7 5764.3 5769.2 5822.7 5828.9 3659.2 3723.3 3794.1 3808.4 3822.9 1071.6 1077.8 1084.1 1087.7 1126.9 12410.0 12442.6 12448.4 12450.1 12459.7 5040.6 5079.5 5169.7 5196.4 5196.6 10575.2 10575.3 10615.9 10616.8 10617.7 5739.2 5750.2 5766.2 5790.5 5815.6 4622.4 4635.9 4787.7 4790.8 5239.5 4102.2 4119.2 4180.1 4180.2 4215.7 1198.9 1359.5 1384.9 1392.5 1401.6 1419.5 1438.3 1475.2 1490.6 1498.5 8417.8 8448.3 8466.7 8479.0 8494.2 531.0 548.4 550.5 551.9 552.3 6339.8 6375.5 6403.5 6430.6 6439.7 8780.4 11223.5 11230.8 11239.0 11239.8 10683.8 10949.2 11032.0 11070.4 11086.3 8604.1 8634.3 8693.3 8708.7 8709.6 8110.0 8159.7 8186.9 8238.3 8260.6 9614.5 9915.8 9932.9 9979.0 10051.7 10092.8 10363.3 10434.8 10469.0 10471.5 6522.9 7098.9 7259.5 7553.6 7731.7 7970.0 8049.8 8330.8 8572.2 8593.7 6484.7 7441.6 7510.4 7552.4 7707.4 7450.7 7466.8 7480.4 7510.2 7550.5 7988.1 8041.3 8055.0 8056.8 8057.3 7101.4 7275.7 7368.3 7368.6 7377.9 7333.2 7410.7 7428.8 7432.6 7438.1 16:20:38 INFO - PROCESS | 1659 | 16:20:38 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:5611.62 Median:6392.15 stddev:1763.20 (27.6%) stddev-sans-first:1723.05 16:20:38 INFO - PROCESS | 1659 | Values: 332.4 336.9 341.2 341.7 345.7 6482.9 6483.2 6501.8 6525.7 6536.2 6407.6 6431.6 6432.9 6435.2 6444.8 4625.1 4705.6 4745.9 4822.5 4839.2 4838.1 4850.3 4860.0 4869.1 4888.9 4802.7 4804.4 4809.3 4810.2 4814.8 4567.7 4651.5 4749.0 4752.1 4768.1 4125.2 4126.4 4139.7 4142.4 4142.8 6116.4 6122.6 6123.8 6139.4 6146.4 5609.1 5624.2 5628.8 5633.4 5633.4 6764.3 6809.8 6811.1 6820.6 6840.6 4371.4 4573.3 4579.0 4579.6 4597.4 7113.7 7232.1 7263.9 7282.0 7306.3 4614.4 4615.8 4616.0 4618.7 4622.5 3829.9 4645.0 4656.9 4662.3 4669.4 5495.5 5497.1 5497.9 5508.1 5512.6 6580.9 6821.1 6839.9 6849.0 6871.3 1591.3 1606.2 1619.9 1621.0 1622.9 983.3 1010.3 1034.3 1037.9 1060.9 6289.3 6405.5 6413.9 6438.1 6439.8 5775.9 7084.6 7084.7 7132.5 7145.2 6391.8 6392.5 6398.2 6412.7 6509.3 3730.5 3776.9 3783.3 3785.5 3796.9 7161.5 7458.0 7498.4 7522.9 7529.8 7289.2 7347.7 7352.9 7365.6 7366.3 5976.5 7441.1 7449.6 7455.2 7479.2 5753.2 6045.7 7109.9 7128.1 7131.1 6758.2 6996.0 7019.8 7029.6 7038.6 7430.6 7432.5 7465.7 7469.7 7471.1 4613.7 4624.7 4625.1 4628.7 4629.0 5741.0 6699.2 6872.1 7118.3 7145.4 5845.6 6572.6 6891.4 6902.8 6903.2 6792.7 6848.9 6862.5 6899.6 6912.4 6135.2 6280.5 6718.5 6742.1 6759.7 6851.7 6858.4 6880.2 6905.8 7054.6 5978.1 6213.0 6527.9 6614.7 6715.7 16:20:38 INFO - PROCESS | 1659 | 16:20:38 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:19002.85 Median:4254.29 stddev:26430.49 (621.3%) stddev-sans-first:26468.85 16:20:38 INFO - PROCESS | 1659 | Values: 685.5 767.7 770.9 778.9 794.2 15829.2 15949.0 16014.7 16035.9 16128.7 14620.9 14685.3 14699.8 14714.3 14719.0 827.2 840.6 989.1 989.4 1017.9 1009.6 1021.8 1022.8 1023.5 1025.2 1040.2 1051.1 1060.0 1066.1 1067.1 859.1 1038.0 1055.3 1058.3 1062.7 799.5 800.9 801.7 802.4 802.4 15731.0 15781.0 15786.8 15800.8 15829.4 13386.1 13396.4 13649.2 13679.9 13733.7 10370.8 10470.1 10542.3 10625.8 10674.0 4107.6 4218.4 4228.8 4231.0 4323.6 79720.8 79946.6 81020.2 81275.6 81467.0 9630.7 9734.4 9778.1 9966.5 10031.7 22474.9 22524.9 22549.0 22590.7 22619.8 7668.5 7711.8 7936.6 8107.0 8156.3 4277.6 4346.2 4366.5 4368.0 4445.3 3726.9 3751.5 3781.1 3783.6 3783.6 590.5 670.4 674.9 689.6 696.5 615.9 616.9 622.4 629.2 632.2 7720.8 8440.2 8545.1 8567.2 8583.0 558.7 580.5 589.8 620.2 633.2 3105.3 3357.2 3833.3 4015.6 4225.6 91539.5 91561.0 92906.0 93334.0 93658.5 63547.7 83877.6 84318.3 84628.7 85066.0 72519.0 74230.3 74557.6 75074.0 76294.0 7701.7 8648.7 8670.9 8748.4 8899.3 52547.0 52617.7 54009.7 54216.0 54468.7 64007.7 69493.7 74020.6 75044.6 76432.6 11157.9 11178.8 11399.0 11428.4 11655.5 12014.2 12117.8 12184.6 12427.3 12910.6 11651.4 11707.6 11728.2 11976.9 12069.5 11609.2 11636.4 11904.1 11978.9 12030.5 11462.7 11940.3 11944.0 12011.9 12085.7 9956.9 9985.1 10068.7 10225.2 10259.9 9463.8 9999.7 10086.5 10119.6 10248.0 16:20:38 INFO - PROCESS | 1659 | -------- Summary: end -------- 16:20:38 INFO - PROCESS | 1659 | 16:20:39 INFO - TEST-INFO | 1659: exit 0 16:20:39 INFO - TEST-OK | dromaeo_css | took 1111780ms 16:20:39 INFO - TEST-START | kraken 16:20:39 INFO - operating with platform_type : mac_ 16:20:39 INFO - Initialising browser for kraken test... 16:20:39 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmp6P6xES/profile http://localhost:49275/getInfo.html) 16:20:41 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 16:20:41 INFO - PROCESS | 1668 | colorDepth:24 16:20:41 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 16:20:41 INFO - PROCESS | 1668 | __metrics 16:20:41 INFO - PROCESS | 1668 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:20:41 INFO - PROCESS | 1668 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:20:42 INFO - TEST-INFO | 1668: exit 0 16:20:42 INFO - Browser initialized. 16:20:42 INFO - Running cycle 1/1 for kraken test... 16:20:42 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmp6P6xES/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:20:54 INFO - PROCESS | 1671 | RSS: Main: 156876800 16:20:54 INFO - PROCESS | 1671 | 16:20:56 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 16:20:57 INFO - PROCESS | 1671 | RSS: Main: 212967424 16:20:57 INFO - PROCESS | 1671 | 16:21:00 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 16:21:00 INFO - PROCESS | 1671 | RSS: Main: 223313920 16:21:00 INFO - PROCESS | 1671 | 16:21:03 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 16:21:04 INFO - PROCESS | 1671 | RSS: Main: 212926464 16:21:04 INFO - PROCESS | 1671 | 16:21:06 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 16:21:07 INFO - PROCESS | 1671 | RSS: Main: 213876736 16:21:07 INFO - PROCESS | 1671 | 16:21:09 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:21:09 INFO - PROCESS | 1671 | RSS: Main: 213721088 16:21:09 INFO - PROCESS | 1671 | 16:21:12 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 16:21:13 INFO - PROCESS | 1671 | RSS: Main: 213590016 16:21:13 INFO - PROCESS | 1671 | 16:21:15 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 16:21:16 INFO - PROCESS | 1671 | RSS: Main: 213741568 16:21:16 INFO - PROCESS | 1671 | 16:21:19 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 16:21:19 INFO - PROCESS | 1671 | RSS: Main: 215191552 16:21:19 INFO - PROCESS | 1671 | 16:21:21 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:21:21 INFO - PROCESS | 1671 | RSS: Main: 250200064 16:21:21 INFO - PROCESS | 1671 | 16:21:23 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:21:24 INFO - PROCESS | 1671 | RSS: Main: 250761216 16:21:24 INFO - PROCESS | 1671 | 16:21:26 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:21:26 INFO - PROCESS | 1671 | RSS: Main: 255512576 16:21:26 INFO - PROCESS | 1671 | 16:21:29 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:21:29 INFO - PROCESS | 1671 | RSS: Main: 257425408 16:21:29 INFO - PROCESS | 1671 | 16:21:32 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:21:32 INFO - PROCESS | 1671 | RSS: Main: 257515520 16:21:32 INFO - PROCESS | 1671 | 16:21:34 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 16:21:34 INFO - PROCESS | 1671 | RSS: Main: 320135168 16:21:34 INFO - PROCESS | 1671 | 16:21:34 INFO - PROCESS | 1671 | __start_tp_report 16:21:34 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 16:21:34 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 16:21:34 INFO - PROCESS | 1671 | |i|pagename|runs| 16:21:34 INFO - PROCESS | 1671 | |0;ai-astar;112;108;112;111;114;150;176;122;110;112 16:21:34 INFO - PROCESS | 1671 | |1;audio-beat-detection;155;120;159;161;116;138;120;146;147;119 16:21:34 INFO - PROCESS | 1671 | |2;audio-dft;182;184;181;182;186;184;182;185;187;257 16:21:34 INFO - PROCESS | 1671 | |3;audio-fft;76;74;72;113;73;82;83;85;152;56 16:21:34 INFO - PROCESS | 1671 | |4;audio-oscillator;88;92;124;78;79;83;116;76;78;116 16:21:34 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;88;84;87;84;88;83;89;84;87;84 16:21:34 INFO - PROCESS | 1671 | |6;imaging-darkroom;92;93;93;93;92;93;105;151;117;92 16:21:34 INFO - PROCESS | 1671 | |7;imaging-desaturate;84;88;82;87;84;84;85;85;90;84 16:21:34 INFO - PROCESS | 1671 | |8;json-parse-financial;58;59;59;57;58;56;58;67;56;51 16:21:34 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;63;60;73;69;73;70;67;67;65;66 16:21:34 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;81;82;80;80;80;78;79;78;82;84 16:21:34 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;129;119;130;117;117;115;116;110;116;116 16:21:34 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;135;124;134;131;135;122;134;133;134;134 16:21:34 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;65;66;65;65;65;65;63;74;80;91 16:21:34 INFO - PROCESS | 1671 | __end_tp_report 16:21:34 INFO - PROCESS | 1671 | __start_cc_report 16:21:34 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1154 16:21:34 INFO - PROCESS | 1671 | __end_cc_report 16:21:34 INFO - PROCESS | 1671 | __startTimestamp1462836094937__endTimestamp 16:21:34 INFO - PROCESS | 1671 | 16:21:34 INFO - PROCESS | 1671 | ------- Summary: start ------- 16:21:34 INFO - PROCESS | 1671 | Number of tests: 14 16:21:34 INFO - PROCESS | 1671 | 16:21:34 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:122.70 Median:113.00 stddev:22.41 (19.8%) stddev-sans-first:23.43 16:21:34 INFO - PROCESS | 1671 | Values: 112.0 108.0 112.0 111.0 114.0 150.0 176.0 122.0 110.0 112.0 16:21:34 INFO - PROCESS | 1671 | 16:21:34 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:138.10 Median:146.50 stddev:17.94 (12.2%) stddev-sans-first:17.96 16:21:34 INFO - PROCESS | 1671 | Values: 155.0 120.0 159.0 161.0 116.0 138.0 120.0 146.0 147.0 119.0 16:21:34 INFO - PROCESS | 1671 | 16:21:34 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:191.00 Median:184.50 stddev:23.27 (12.6%) stddev-sans-first:24.45 16:21:34 INFO - PROCESS | 1671 | Values: 182.0 184.0 181.0 182.0 186.0 184.0 182.0 185.0 187.0 257.0 16:21:34 INFO - PROCESS | 1671 | 16:21:34 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:86.60 Median:75.00 stddev:27.11 (36.2%) stddev-sans-first:28.49 16:21:34 INFO - PROCESS | 1671 | Values: 76.0 74.0 72.0 113.0 73.0 82.0 83.0 85.0 152.0 56.0 16:21:34 INFO - PROCESS | 1671 | 16:21:34 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:93.00 Median:78.50 stddev:18.50 (23.6%) stddev-sans-first:19.53 16:21:34 INFO - PROCESS | 1671 | Values: 88.0 92.0 124.0 78.0 79.0 83.0 116.0 76.0 78.0 116.0 16:21:34 INFO - PROCESS | 1671 | 16:21:34 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:85.80 Median:87.00 stddev:2.20 (2.5%) stddev-sans-first:2.19 16:21:34 INFO - PROCESS | 1671 | Values: 88.0 84.0 87.0 84.0 88.0 83.0 89.0 84.0 87.0 84.0 16:21:34 INFO - PROCESS | 1671 | 16:21:34 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:102.10 Median:92.50 stddev:19.03 (20.6%) stddev-sans-first:19.83 16:21:34 INFO - PROCESS | 1671 | Values: 92.0 93.0 93.0 93.0 92.0 93.0 105.0 151.0 117.0 92.0 16:21:34 INFO - PROCESS | 1671 | 16:21:34 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:85.00 stddev:2.36 (2.8%) stddev-sans-first:2.46 16:21:34 INFO - PROCESS | 1671 | Values: 84.0 88.0 82.0 87.0 84.0 84.0 85.0 85.0 90.0 84.0 16:21:34 INFO - PROCESS | 1671 | 16:21:34 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:57.90 Median:58.00 stddev:3.96 (6.8%) stddev-sans-first:4.20 16:21:34 INFO - PROCESS | 1671 | Values: 58.0 59.0 59.0 57.0 58.0 56.0 58.0 67.0 56.0 51.0 16:21:34 INFO - PROCESS | 1671 | 16:21:34 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:67.30 Median:68.00 stddev:4.14 (6.1%) stddev-sans-first:4.09 16:21:34 INFO - PROCESS | 1671 | Values: 63.0 60.0 73.0 69.0 73.0 70.0 67.0 67.0 65.0 66.0 16:21:34 INFO - PROCESS | 1671 | 16:21:34 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:80.40 Median:80.50 stddev:1.90 (2.4%) stddev-sans-first:2.00 16:21:34 INFO - PROCESS | 1671 | Values: 81.0 82.0 80.0 80.0 80.0 78.0 79.0 78.0 82.0 84.0 16:21:34 INFO - PROCESS | 1671 | 16:21:34 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:118.50 Median:117.00 stddev:6.24 (5.3%) stddev-sans-first:5.34 16:21:34 INFO - PROCESS | 1671 | Values: 129.0 119.0 130.0 117.0 117.0 115.0 116.0 110.0 116.0 116.0 16:21:34 INFO - PROCESS | 1671 | 16:21:34 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:131.60 Median:134.00 stddev:4.70 (3.5%) stddev-sans-first:4.82 16:21:34 INFO - PROCESS | 1671 | Values: 135.0 124.0 134.0 131.0 135.0 122.0 134.0 133.0 134.0 134.0 16:21:34 INFO - PROCESS | 1671 | 16:21:34 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.90 Median:65.50 stddev:9.11 (13.9%) stddev-sans-first:9.49 16:21:34 INFO - PROCESS | 1671 | Values: 65.0 66.0 65.0 65.0 65.0 65.0 63.0 74.0 80.0 91.0 16:21:34 INFO - PROCESS | 1671 | -------- Summary: end -------- 16:21:34 INFO - PROCESS | 1671 | 16:21:35 INFO - TEST-INFO | 1671: exit 0 16:21:35 INFO - TEST-OK | kraken | took 56321ms 16:21:35 INFO - SUITE-END | took 1168s 16:21:36 INFO - Completed test suite (00:19:28) 16:21:36 INFO - javascript benchmark 16:21:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3587.4618807178927, 3647.069997150726, 3651.2514934438427, 3689.3359195344838, 3725.5343082114737, 5937.614055086417, 5962.970185149074, 5970.7313170907355, 6002.369751176118, 6009.1286763419275, 5938.7624495020145, 5966.224390829186, 5972.9402705972925, 5976.073708575167, 5991.550633702468, 5227.372715274172, 5317.574594032468, 5341.30563026806, 5389.407165211824, 5437.429069947658, 5409.43200963897, 5419.403865574784, 5425.83722488324, 5426.294581704373, 5434.673919564819, 5352.116900711378, 5404.135338345865, 5407.459254074581, 5419.972900135494, 5420.674759514442, 5423.593230507708, 5426.538744206747, 5445.53712934401, 5456.699881506515, 5502.504774570268, 5183.559397451221, 5359.0, 5360.088784906575, 5363.195520671891, 5364.007658583175, 5789.478946894757, 5830.300363956352, 5855.180274761537, 5866.384029676865, 5897.292324920994, 6200.100985357143, 6327.715252813635, 6330.81007569769, 6334.334894836022, 6342.556021078526, 6278.529110316745, 6283.528735344822, 6290.182276304097, 6307.0539419087045, 6335.176427064498, 6011.27864656244, 6044.546659000593, 6047.304559975615, 6059.969700151471, 6063.514918806485, 6268.2478102627065, 6423.357664233633, 6443.67781610927, 6446.613203207822, 6456.483481321577, 6202.255729312511, 6322.241331040213, 6328.651924144124, 6330.240371155399, 6339.714712837934, 6305.936940630535, 6331.525135614847, 6338.619682819046, 6343.873122537523, 6345.555811093272, 6225.968870155621, 6289.339619339995, 6316.715747791361, 6321.747130114836, 6358.618482890949, 5683.8010669626365, 5756.251687280626, 6216.875662486725, 6311.1795466589965, 6329.968350158219, 5057.266696329012, 5068.087744206078, 5068.189089745623, 5071.644984851025, 5086.771095300646, 4553.977230113895, 4568.0, 4576.954230457719, 4588.426446694163, 4625.930611040837, 5885.293764748172, 5906.645601263938, 5936.673482958478, 6020.187274717945, 6068.605540639845, 6304.810855674337, 6329.145565348619, 6338.080978258126, 6339.2392912850755, 6353.459955903708, 5980.8803823924145, 6022.277326720734, 6036.9698151508965, 6038.969805150946, 6045.637261764308, 5426.945730542723, 5461.5357694596405, 5507.421720719267, 5572.247746554244, 5582.469665381782, 6523.934760652428, 6551.6069035857045, 6556.508261880474, 6562.967185164043, 6564.6389448580785, 5158.865049689062, 6232.844178895564, 6337.96831015842, 6341.302456729663, 6346.3019067903415, 5433.212184233185, 6078.118672792509, 6387.361263873576, 6508.674566271573, 6520.967395162994, 5629.240052593012, 6279.183706118175, 6282.937170628235, 6342.904856427065, 6345.0, 6293.402126797947, 6322.272938612118, 6328.525360598066, 6345.302016778059, 6368.363163683594, 6128.747575574743, 6232.127502149793, 6238.344973777869, 6241.563090583797, 6279.591826531261, 5103.566196873159, 6237.5321850860455, 6238.095476155841, 6270.529710271657, 6285.779997700059, 6178.320384757763, 6180.876382472416, 6184.257889053342, 6186.19579454668, 6193.783217587363, 5754.367019627922, 6191.288001879661, 6221.066839973859, 6263.185785847811, 6280.497560195082, 6162.383761623981, 6214.533909956681, 6227.844303892439, 6232.0, 6234.251889773075], "unit": "score", "name": "dojo.html", "value": 5846.001811219514}, {"lowerIsBetter": false, "replicates": [503.87403149212696, 576.2710171632885, 600.2137200267653, 668.7592466711984, 682.7610336382266, 31996.720131194754, 32413.86551470699, 32445.513317300258, 41125.0, 41793.58206417935, 44696.776516117454, 44920.65238042864, 45098.0, 45288.54711452885, 45291.0, 9809.411435313887, 10051.698449046504, 10057.0, 10075.345102568346, 10081.949590252076, 9765.414075155479, 9897.75255618608, 9920.652777152802, 9923.553440095213, 9969.252306077036, 9885.851712224323, 9971.551280192372, 9978.550965206547, 10040.196784257241, 10079.042490963348, 7517.812054698622, 9525.809483810284, 9971.501424928761, 10035.096841284283, 10040.0, 9863.556139973718, 9867.50662466881, 9954.054364835329, 9958.452285124315, 9963.900360996442, 43102.78448607737, 43388.0, 43393.34909976353, 43428.78285608552, 43441.0, 3398.915027124317, 3527.6825085742407, 3574.964250357515, 3588.3540962626716, 3606.332828426723, 22635.54728905429, 27433.725662743316, 28330.858345708344, 28855.0, 28975.420491590052, 2787.456445993032, 2794.9798323611735, 2825.4349130174046, 2837.276494493891, 2867.842268675222, 496032.0, 500190.0, 500508.0, 500941.0, 501272.0, 49456.75271623619, 49985.0, 75347.0, 75916.0, 76118.61940690261, 41213.793931030756, 48048.0, 48165.0, 48208.27687584689, 48312.7584362083, 16745.91627041857, 17463.65072698539, 17483.912580437016, 17491.825081749274, 17537.912310438365, 14039.438422462987, 14115.576532704035, 14143.504977325743, 14158.433662653584, 14165.0, 15420.46028389001, 15674.451394201149, 15681.05913645162, 15697.921510392374, 15730.056196628238, 5595.328560572757, 6017.969910150421, 6203.286622038433, 6551.541392102602, 6650.9667451662435, 971.8007808399261, 1015.1726343030407, 1062.946852657364, 1065.9093977012042, 1100.2738192792879, 29943.401131977673, 31200.195844303835, 31297.8435107823, 31334.529982050288, 32233.51649725256, 1181.6691326428613, 1185.4191446191305, 1188.2810899405736, 1193.8806119387991, 1212.175720510044, 15085.170315632746, 15916.442924497587, 15920.203989800464, 15970.0, 16245.675086498204, 310739.0, 313266.0, 315112.0, 315734.0, 315864.0, 64635.353646463875, 65081.0, 65141.02288465677, 65181.674091630186, 65459.0, 1441.1929319581068, 1553.3320672111101, 1569.1526575649023, 1580.217792192849, 1583.406222666477, 29871.0, 30205.395892082473, 30324.69675303219, 30697.61433979533, 31629.8418507906, 52076.47923520716, 52209.738951305, 52700.20949685835, 52755.208671869186, 52816.73591632017, 382122.0, 382794.0, 383407.0, 383482.0, 383825.0, 30508.847455762578, 35011.29977400489, 35563.46654800232, 35677.929662110175, 36122.27755444929, 46297.0, 46850.29724554066, 47386.28920566263, 47479.252074792246, 47505.04989900113, 38293.80853095717, 38348.0, 38537.03657408587, 38651.61348386593, 39180.2163956725, 37716.86849394523, 37963.810180948916, 38532.036699081626, 38762.80618596889, 38967.6103238964, 38906.4164037534, 40106.59893401029, 40441.0, 41079.794601028, 42027.0, 31737.514239463093, 34374.31251375008, 34673.30653386968, 34941.126471838405, 35704.107397315274, 23743.52512949766, 24163.51672966566, 25655.230343089737, 25770.484590308464, 25897.0], "unit": "score", "name": "ext.html", "value": 19690.594310134107}, {"lowerIsBetter": false, "replicates": [346.14329534402356, 360.2093404976077, 370.7738279649415, 377.62803638416153, 384.22770231834005, 20525.794742052592, 20896.582068358643, 21214.787852121473, 22037.11851525941, 22072.78599677019, 21202.681959770616, 21274.36176914695, 21603.783962160374, 21608.243711470102, 21646.350609481742, 5715.657060576368, 6014.909776353358, 6040.063790112539, 6042.758289668408, 6049.939500605004, 4162.8126734296875, 4357.956420435803, 5679.290088738908, 5910.734016969225, 6167.074938759177, 5304.496072873071, 5994.5204383649425, 6132.080187971817, 6138.662373569452, 6148.323684394713, 6104.4200800923845, 6330.778422755205, 6598.241202261754, 6629.867402651968, 6637.3030831762935, 5846.824595262149, 5862.736176872008, 5863.325717542494, 5872.647641141502, 5878.265216847894, 10972.890271097267, 11666.941665291704, 14099.36552855124, 14110.50613228532, 14121.858781412157, 2220.400491867203, 2242.372135801978, 2338.4504641409426, 2429.8056155507516, 2637.709851916307, 10105.0, 10688.625898093607, 15538.689226215525, 15670.921645391814, 16279.755803662954, 1883.9340623078126, 1908.885466871992, 1921.8270355668058, 1933.7002764571514, 1956.3739603326942, 116748.0, 121124.0, 121505.78494215033, 125075.37462312721, 133612.0, 20044.69932951007, 20386.28647997313, 20584.073716682786, 21126.154953801677, 21233.575328493345, 31043.0, 31187.0, 31870.0, 31877.681223187934, 31937.680623193934, 11628.127890408254, 11743.236689615147, 11837.467313970892, 11851.051915846712, 11862.703432414259, 8468.49189048659, 9903.405795652285, 10048.447335396624, 10049.24630652704, 10167.949160254151, 7727.768166955, 8909.55452227386, 9050.86423703645, 9366.438013719198, 9440.716778496657, 3028.6668466468673, 3490.616032236452, 3567.0, 3661.649590624602, 3679.3745063339297, 923.7921467669855, 937.6530683647094, 1038.99480502597, 1042.3224903812431, 1127.610974213895, 20175.39473815788, 20683.0, 20857.923713708464, 20898.895505522374, 20918.89540552318, 986.5990293782702, 1059.6171449038864, 1068.3589846092439, 1089.1722291058852, 1092.186321190718, 9819.607215711292, 11333.07337752584, 11335.0, 11355.716107097389, 11362.659120226406, 348568.0, 675578.0, 684478.0, 702520.0, 706253.0, 121051.39474302572, 124699.3765031169, 124878.37560812138, 125110.0, 125335.0, 912.4616476279027, 1006.731518286949, 1064.0529928363762, 1085.6580177244143, 1087.2335003822286, 14825.0, 17764.46706598804, 18653.0, 18776.906115469883, 18849.528761781065, 48153.0, 48422.5157748418, 49635.00729985452, 50407.0, 50687.493125069755, 71182.0, 73165.6341718288, 73558.0, 74172.62913685397, 74503.88244176234, 8393.916060839558, 8497.192766687154, 8585.484870907765, 8614.82770344602, 8899.510526921082, 10066.44634545107, 10686.25196236256, 13090.0, 13364.398602063126, 13853.653658658613, 11519.8272025918, 11542.769144617228, 11709.66714444993, 11790.351530665896, 12034.819477707666, 11228.663140105484, 11453.770924581628, 11602.94198529002, 11980.0, 12047.518099276282, 10132.138768204784, 11615.070794336601, 11945.641630751092, 11971.820422693841, 12076.637700868989, 8427.915720842713, 8456.873146902679, 8596.0, 8610.61252243638, 8681.696140635047, 8643.913560864563, 11113.166512511432, 11854.99232565207, 11863.34751588671, 12039.939800300943], "unit": "score", "name": "jquery.html", "value": 10486.94852657231}, {"lowerIsBetter": false, "replicates": [1519.4226194046262, 1667.4747454551818, 1699.3202718912437, 2035.4199053269813, 2092.9895350523257, 7373.668184931677, 8307.83384332313, 9136.771580710469, 9148.99999999999, 9168.358214924958, 8740.825183496327, 8987.865182022259, 8993.685221017266, 8996.460212387261, 9005.0, 2573.4338445541953, 2596.9610455843085, 2600.921972340833, 2602.0632572273967, 2606.817522773407, 2534.087728417768, 2603.5834266517363, 2607.778338841201, 2614.712381638018, 2621.318457201132, 2598.8310759800647, 2602.9219123426233, 2620.6724159480063, 2628.68455785306, 2636.314558214868, 2588.8964441422413, 2602.6096085587124, 2623.0163688616767, 2623.5933430318332, 2628.7108418073994, 2586.2112055822968, 2616.515944550264, 2624.9868750656315, 2626.3828000419885, 2629.1192450529097, 9071.410358326688, 9094.317926155565, 9168.495732734697, 9173.03683113277, 9174.587143578488, 5607.663540187602, 5764.250647415851, 5769.24999750034, 5822.737976791054, 5828.912566311508, 3659.1766852457986, 3723.311187430334, 3794.0704527390967, 3808.428735689641, 3822.9235415291537, 1071.608862765093, 1077.805994920914, 1084.078533246742, 1087.7335052912088, 1126.8535090438272, 12410.0, 12442.56451024219, 12448.377581120907, 12450.128491005724, 12459.688507787287, 5040.57155141817, 5079.492050794891, 5169.715665638426, 5196.402413722471, 5196.558292545176, 10575.15398768112, 10575.312604680672, 10615.89384106149, 10616.787664246673, 10617.734556636145, 5739.196512488255, 5750.1949727038245, 5766.163906233573, 5790.507806836466, 5815.563832712563, 4622.399088118523, 4635.884102897455, 4787.664863459524, 4790.808367665324, 5239.528442440199, 4102.159057393227, 4119.176164767058, 4180.080382315886, 4180.247555439989, 4215.683823713233, 1198.8681245063035, 1359.463012110211, 1384.9097379873558, 1392.5126205828083, 1401.5655146904492, 1419.5386499387741, 1438.3024233246867, 1475.2255066090174, 1490.6124407654088, 1498.5204734485078, 8417.789555261044, 8448.281896038905, 8466.66133354659, 8478.982522097265, 8494.235518803338, 530.9893802123936, 548.4460694698383, 550.5127961753797, 551.8565173054955, 552.2958228258922, 6339.778107766206, 6375.458086062737, 6403.519736019817, 6430.646314452656, 6439.710213040425, 8780.42927209742, 11223.494942727597, 11230.831537526943, 11239.0, 11239.775204495865, 10683.786324273471, 10949.233553651327, 11032.0, 11070.391128487849, 11086.334819910831, 8604.139586041345, 8634.309255259564, 8693.304535637133, 8708.69519566812, 8709.564521773886, 8110.0, 8159.673613055411, 8186.8771968421715, 8238.340932725487, 8260.586970651564, 9614.51927403613, 9915.801683966425, 9932.900670993198, 9979.0, 10051.74870628211, 10092.798144037224, 10363.32638378503, 10434.843477347695, 10469.0, 10471.528781204712, 6522.869542609026, 7098.929010710033, 7259.455540834564, 7553.5845528494265, 7731.729389471567, 7969.960150199212, 8049.758507244792, 8330.750077497685, 8572.185642363966, 8593.742187734379, 6484.675766211765, 7441.55350678961, 7510.436717246119, 7552.39580833543, 7707.421943354158, 7450.664720087718, 7466.813329666802, 7480.401567874448, 7510.248975102446, 7550.5469671819865, 7988.121306656147, 8041.316488098577, 8054.993125859268, 8056.758297251092, 8057.274845263954, 7101.39638130744, 7275.745348912763, 7368.263173682589, 7368.557886526825, 7377.889331660137, 7333.156686981066, 7410.703571857065, 7428.814279642836, 7432.591207483641, 7438.070241219848], "unit": "score", "name": "mootools.html", "value": 5047.601130727204}, {"lowerIsBetter": false, "replicates": [332.43983887150154, 336.8517852145056, 341.16584949797726, 341.7436922308268, 345.6957877068181, 6482.870342593157, 6483.222013358398, 6501.80494585162, 6525.706343214555, 6536.248331441886, 6407.551471397004, 6431.581947173442, 6432.871342573146, 6435.195600549931, 6444.838879028027, 4625.098105869357, 4705.647076469261, 4745.928811067837, 4822.541858523452, 4839.201531747258, 4838.129136755382, 4850.320955066293, 4859.975700121495, 4869.14789911764, 4888.926666099994, 4802.735849528292, 4804.399450068741, 4809.278608208761, 4810.206315957863, 4814.807407703687, 4567.748773817439, 4651.534846515338, 4748.978969521535, 4752.097101550694, 4768.1178981888115, 4125.195586860564, 4126.442930204414, 4139.675303902753, 4142.357934520154, 4142.772147531885, 6116.388361163892, 6122.602030867979, 6123.846903827395, 6139.416755408274, 6146.446819786241, 5609.1305847593685, 5624.184493248497, 5628.831135065913, 5633.352164501094, 5633.352164501094, 6764.323567643246, 6809.761658341934, 6811.114555107804, 6820.556663816836, 6840.555363901381, 4371.409859668967, 4573.268277075652, 4578.954210457886, 4579.5878370946775, 4597.42532183477, 7113.715451381939, 7232.0959880015525, 7263.89104163438, 7282.016927714797, 7306.342429181399, 4614.446266448048, 4615.792289346988, 4616.0, 4618.676692631483, 4622.46841613212, 3829.9425508616837, 4644.9767751161025, 4656.8602941911795, 4662.277347011219, 4669.392978912788, 5495.505404513613, 5497.147942068906, 5497.945020549743, 5508.118701007819, 5512.641678290898, 6580.901286480706, 6821.079154314203, 6839.863202736017, 6849.006894000442, 6871.312868713088, 1591.3316407109041, 1606.2290100751704, 1619.8866079374566, 1620.9513714588581, 1622.8945118567256, 983.3165949665057, 1010.2524132142162, 1034.270839058462, 1037.9377237365782, 1060.8833028366873, 6289.308176100625, 6405.455536279375, 6413.935860641427, 6438.130852334969, 6439.774607888795, 5775.942240577624, 7084.610346430893, 7084.681189346492, 7132.465065120136, 7145.249748776304, 6391.776287829904, 6392.520560957947, 6398.232212134573, 6412.743490260337, 6509.251436084816, 3730.515033045741, 3776.905577360588, 3783.319002579507, 3785.5078839750654, 3796.867109651149, 7161.4986950912935, 7458.0, 7498.4376171787335, 7522.887156692654, 7529.849403011909, 7289.2346303638415, 7347.742829000959, 7352.926470735224, 7365.558066516026, 7366.337029667356, 5976.521878249817, 7441.144268409094, 7449.553026818517, 7455.179930207672, 7479.214682458372, 5753.223314852441, 6045.697715114139, 7109.928900710926, 7128.0754196367725, 7131.144262688511, 6758.155230596176, 6995.965020174866, 7019.789406317818, 7029.578225306498, 7038.648067596498, 7430.554166750012, 7432.479726419098, 7465.7013719450615, 7469.701211951461, 7471.103467583925, 4613.746243956481, 4624.722516649012, 4625.121226966866, 4628.722276663411, 4628.976855115838, 5740.9953258180485, 6699.162604674416, 6872.140982377203, 7118.25258347866, 7145.356917877416, 5845.561582881216, 6572.572782769104, 6891.37977582, 6902.792916212522, 6903.1716194057035, 6792.72829086831, 6848.931510684829, 6862.451003919774, 6899.551529150589, 6912.377885990285, 6135.202423684892, 6280.466160376419, 6718.529702920749, 6742.123523941857, 6759.695813688298, 6851.657417129024, 6858.359531580477, 6880.208775990825, 6905.792826215222, 7054.6472676367, 5978.073398623221, 6213.0, 6527.911068458237, 6614.735410583523, 6715.731370745311], "unit": "score", "name": "prototype.html", "value": 5027.499343956046}, {"lowerIsBetter": false, "replicates": [685.5098604497783, 767.6660652616109, 770.8535378278128, 778.9415793815465, 794.2335646101515, 15829.20853957302, 15949.0, 16014.679706405894, 16035.919820400897, 16128.677426451479, 14620.926895365508, 14685.265736713174, 14699.779503307433, 14714.264286785672, 14719.04326218795, 827.2472050434106, 840.5713086325957, 989.1098011789411, 989.4113002763344, 1017.9134773544257, 1009.6213919780082, 1021.764994051365, 1022.7852151048289, 1023.52406131149, 1025.174734338857, 1040.15747244732, 1051.1223128687554, 1059.9576016959313, 1066.1311031509333, 1067.0503252105632, 859.1365677494141, 1037.9896201038043, 1055.2613170780446, 1058.3015209961427, 1062.6705721226406, 799.5202878273047, 800.8878756974028, 801.6993627389729, 802.3541049455187, 802.3661307567015, 15731.0, 15781.0, 15786.763198552031, 15800.762988555181, 15829.44596939113, 13386.062975591713, 13396.39716212763, 13649.181049137083, 13679.931600342034, 13733.656658583515, 10370.79258414835, 10470.110040646554, 10542.262041657086, 10625.84061239082, 10674.039336459678, 4107.589241075899, 4218.4094226807965, 4228.78856057199, 4230.978845105786, 4323.632491238249, 79720.80418793723, 79946.60026699888, 81020.18979810244, 81275.56173314751, 81467.0, 9630.711078667651, 9734.36726612768, 9778.119969202806, 9966.501674916226, 10031.69904902854, 22474.887625562096, 22524.887375563016, 22549.0, 22590.661140082913, 22619.773802261767, 7668.539887606761, 7711.768646940601, 7936.6031698414845, 8107.027156741229, 8156.265936065783, 4277.593628605277, 4346.239408103569, 4366.454193225847, 4368.0, 4445.2887537993765, 3726.888193354204, 3751.4935483709896, 3781.111438811877, 3783.564890037626, 3783.621637836194, 590.5275779376482, 670.4301343857779, 674.9493787965922, 689.5517913356358, 696.5472442912147, 615.8860610787019, 616.852366997406, 622.4180391334118, 629.235478893147, 632.1592282264577, 7720.7683769487, 8440.240378365976, 8545.102764209792, 8567.186117318844, 8583.0, 558.7262241501635, 580.451473357673, 589.7965202005302, 620.2061361457341, 633.2116514938931, 3105.2702614885484, 3357.1942733743726, 3833.3291673957506, 4015.558288588253, 4225.640820530228, 91539.5423022894, 91561.0, 92906.0, 93334.0, 93658.53170734103, 63547.72904541883, 83877.58061209654, 84318.31363372698, 84628.73056904151, 85066.0, 72519.0, 74230.25769742341, 74557.6272118625, 75074.0, 76294.0, 7701.691932322644, 8648.697295594624, 8670.869936951078, 8748.387612867038, 8899.332550058642, 52547.0, 52617.738226177076, 54009.72995134999, 54216.0, 54468.727656361465, 64007.71984560376, 69493.65253173701, 74020.62989685232, 75044.62477687576, 76432.61783691046, 11157.888421115686, 11178.832317515082, 11399.0, 11428.371439570903, 11655.475503602189, 12014.159008869296, 12117.81823272634, 12184.634460966185, 12427.316497592357, 12910.612681619567, 11651.359175245103, 11707.590234341757, 11728.179027467995, 11976.940115299367, 12069.517219311481, 11609.187356884937, 11636.36000020007, 11904.107191960811, 11978.880211197777, 12030.458629361525, 11462.713432164262, 11940.343281119274, 11944.0, 12011.939940300243, 12085.697857553632, 9956.900430995598, 9985.051420115078, 10068.748281293027, 10225.233107517115, 10259.897401025893, 9463.763405914908, 9999.650012249536, 10086.495675216356, 10119.645812396826, 10248.0], "unit": "score", "name": "yui.html", "value": 7276.393177894422}], "name": "dromaeo_css", "value": 7786.803628177633}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 108.0, 112.0, 111.0, 114.0, 150.0, 176.0, 122.0, 110.0, 112.0], "unit": "score", "name": "ai-astar", "value": 122.7}, {"lowerIsBetter": true, "replicates": [155.0, 120.0, 159.0, 161.0, 116.0, 138.0, 120.0, 146.0, 147.0, 119.0], "unit": "score", "name": "audio-beat-detection", "value": 138.1}, {"lowerIsBetter": true, "replicates": [182.0, 184.0, 181.0, 182.0, 186.0, 184.0, 182.0, 185.0, 187.0, 257.0], "unit": "score", "name": "audio-dft", "value": 191.0}, {"lowerIsBetter": true, "replicates": [76.0, 74.0, 72.0, 113.0, 73.0, 82.0, 83.0, 85.0, 152.0, 56.0], "unit": "score", "name": "audio-fft", "value": 86.6}, {"lowerIsBetter": true, "replicates": [88.0, 92.0, 124.0, 78.0, 79.0, 83.0, 116.0, 76.0, 78.0, 116.0], "unit": "score", "name": "audio-oscillator", "value": 93.0}, {"lowerIsBetter": true, "replicates": [88.0, 84.0, 87.0, 84.0, 88.0, 83.0, 89.0, 84.0, 87.0, 84.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 85.8}, {"lowerIsBetter": true, "replicates": [92.0, 93.0, 93.0, 93.0, 92.0, 93.0, 105.0, 151.0, 117.0, 92.0], "unit": "score", "name": "imaging-darkroom", "value": 102.1}, {"lowerIsBetter": true, "replicates": [84.0, 88.0, 82.0, 87.0, 84.0, 84.0, 85.0, 85.0, 90.0, 84.0], "unit": "score", "name": "imaging-desaturate", "value": 85.3}, {"lowerIsBetter": true, "replicates": [58.0, 59.0, 59.0, 57.0, 58.0, 56.0, 58.0, 67.0, 56.0, 51.0], "unit": "score", "name": "json-parse-financial", "value": 57.9}, {"lowerIsBetter": true, "replicates": [63.0, 60.0, 73.0, 69.0, 73.0, 70.0, 67.0, 67.0, 65.0, 66.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.3}, {"lowerIsBetter": true, "replicates": [81.0, 82.0, 80.0, 80.0, 80.0, 78.0, 79.0, 78.0, 82.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 80.4}, {"lowerIsBetter": true, "replicates": [129.0, 119.0, 130.0, 117.0, 117.0, 115.0, 116.0, 110.0, 116.0, 116.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 118.5}, {"lowerIsBetter": true, "replicates": [135.0, 124.0, 134.0, 131.0, 135.0, 122.0, 134.0, 133.0, 134.0, 134.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 131.6}, {"lowerIsBetter": true, "replicates": [65.0, 66.0, 65.0, 65.0, 65.0, 65.0, 63.0, 74.0, 80.0, 91.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 69.9}], "name": "kraken", "value": 1430.2}]} 16:21:36 INFO - Return code: 0 16:21:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:21:36 INFO - # TBPL SUCCESS # 16:21:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:21:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:21:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:21:36 INFO - 2016-05-09 16:21:36.206 screenresolution[1675:9996] starting screenresolution argv=screenresolution get 16:21:36 INFO - 2016-05-09 16:21:36.208 screenresolution[1675:9996] Display 0: 1600x1200x32@60 16:21:36 INFO - 2016-05-09 16:21:36.214 screenresolution[1676:9999] starting screenresolution argv=screenresolution list 16:21:36 INFO - Available Modes on Display 0 16:21:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:21:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:21:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:21:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:21:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:21:36 INFO - Intel Iris: 16:21:36 INFO - Chipset Model: Intel Iris 16:21:36 INFO - Type: GPU 16:21:36 INFO - Bus: Built-In 16:21:36 INFO - VRAM (Dynamic, Max): 1536 MB 16:21:36 INFO - Vendor: Intel (0x8086) 16:21:36 INFO - Device ID: 0x0a2e 16:21:36 INFO - Revision ID: 0x0009 16:21:36 INFO - Displays: 16:21:36 INFO - Display: 16:21:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:21:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:21:36 INFO - Display Serial Number: 1600x1200 60 16:21:36 INFO - Main Display: Yes 16:21:36 INFO - Mirror: Off 16:21:36 INFO - Online: Yes 16:21:36 INFO - Rotation: Supported 16:21:36 INFO - Return code: 0 16:21:36 INFO - Running post-action listener: _resource_record_post_action 16:21:36 INFO - Running post-run listener: _resource_record_post_run 16:21:37 INFO - Total resource usage - Wall time: 1193s; CPU: 53.0%; Read bytes: 37819904; Write bytes: 510972928; Read time: 389; Write time: 1578 16:21:37 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 195627520; Write bytes: 198190592; Read time: 18246; Write time: 429 16:21:37 INFO - run-tests - Wall time: 1171s; CPU: 53.0%; Read bytes: 36865536; Write bytes: 301866496; Read time: 373; Write time: 1113 16:21:37 INFO - Running post-run listener: _upload_blobber_files 16:21:37 INFO - Blob upload gear active. 16:21:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:21:37 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 16:21:37 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'] 16:21:37 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 16:21:37 INFO - (blobuploader) - INFO - Open directory for files ... 16:21:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:21:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:21:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:21:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:21:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:21:39 INFO - (blobuploader) - INFO - Done attempting. 16:21:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:21:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:21:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:21:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:21:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:21:40 INFO - (blobuploader) - INFO - Done attempting. 16:21:40 INFO - (blobuploader) - INFO - Iteration through files over. 16:21:40 INFO - Return code: 0 16:21:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:21:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:21:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9cb98e12978be8d7f89407837c8b64534bcab6ce87ac9e932822f8bbc83eb014b9377a15f70fcb2303082b9372d07b02a5e73f3e634b9daf9f20856ae6d1d25c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/95dc1b5cbc783ba9406e7a028d704849c399f5c3885dc1aadd1f76f3680a00464f1112dc87723c3acbca73bb7baedbc4379bd3aa12f9acc9bdc8d8a906b2cab8"} 16:21:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:21:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:21:40 INFO - Contents: 16:21:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9cb98e12978be8d7f89407837c8b64534bcab6ce87ac9e932822f8bbc83eb014b9377a15f70fcb2303082b9372d07b02a5e73f3e634b9daf9f20856ae6d1d25c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/95dc1b5cbc783ba9406e7a028d704849c399f5c3885dc1aadd1f76f3680a00464f1112dc87723c3acbca73bb7baedbc4379bd3aa12f9acc9bdc8d8a906b2cab8"} 16:21:40 INFO - Running post-run listener: copy_logs_to_upload_dir 16:21:40 INFO - Copying logs to upload dir... 16:21:40 INFO - mkdir: /builds/slave/test/build/upload/logs 16:21:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.067354 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-05-09 16:21:40.475811) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 16:21:40.478883) ========= 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.GQ21CCjpJP/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.0FEX6JSRzu/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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/9cb98e12978be8d7f89407837c8b64534bcab6ce87ac9e932822f8bbc83eb014b9377a15f70fcb2303082b9372d07b02a5e73f3e634b9daf9f20856ae6d1d25c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/95dc1b5cbc783ba9406e7a028d704849c399f5c3885dc1aadd1f76f3680a00464f1112dc87723c3acbca73bb7baedbc4379bd3aa12f9acc9bdc8d8a906b2cab8"} build_url:https://queue.taskcluster.net/v1/task/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010695 build_url: 'https://queue.taskcluster.net/v1/task/Vsz4U4rdQlSIT2XzlAlhbQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9cb98e12978be8d7f89407837c8b64534bcab6ce87ac9e932822f8bbc83eb014b9377a15f70fcb2303082b9372d07b02a5e73f3e634b9daf9f20856ae6d1d25c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/95dc1b5cbc783ba9406e7a028d704849c399f5c3885dc1aadd1f76f3680a00464f1112dc87723c3acbca73bb7baedbc4379bd3aa12f9acc9bdc8d8a906b2cab8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 16:21:40.509519) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 16:21:40.510225) ========= 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.GQ21CCjpJP/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.0FEX6JSRzu/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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.004618 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 16:21:40.560796) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 16:21:40.561515) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 16:21:40.562135) ========= ========= Total master_lag: 0.17 =========