builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0361 starttime: 1468264258.84 results: success (0) buildid: 20160711101826 builduid: 96de0173acc748758f2e6afb1002a98d revision: 2e5b22652d834a59b920901b806c35c7943e678c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 12:10:58.837178) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 12:10:58.837603) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 12:10:58.837941) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 12:10:58.879820) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 12:10:58.880078) ========= 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.P65Ifs0rx8/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.wpbVAtuRlm/Listeners TMPDIR=/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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-07-11 12:10:58-- 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% 18.1M=0.001s 2016-07-11 12:10:59 (18.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.154821 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 12:10:59.067444) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 12:10:59.067766) ========= 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.P65Ifs0rx8/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.wpbVAtuRlm/Listeners TMPDIR=/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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.041164 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 12:10:59.129541) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-11 12:10:59.129919) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 2e5b22652d834a59b920901b806c35c7943e678c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 2e5b22652d834a59b920901b806c35c7943e678c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.P65Ifs0rx8/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.wpbVAtuRlm/Listeners TMPDIR=/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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-07-11 12:10:59,212 truncating revision to first 12 chars 2016-07-11 12:10:59,212 Setting DEBUG logging. 2016-07-11 12:10:59,212 attempt 1/10 2016-07-11 12:10:59,212 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/2e5b22652d83?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-11 12:11:00,006 unpacking tar archive at: fx-team-2e5b22652d83/testing/mozharness/ program finished with exit code 0 elapsedTime=1.077497 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-11 12:11:00.237285) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 12:11:00.237648) ========= script_repo_revision: 2e5b22652d834a59b920901b806c35c7943e678c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 12:11:00.238236) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 12:11:00.238543) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 12:11:00.262889) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-11 12:11:00.263394) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.P65Ifs0rx8/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.wpbVAtuRlm/Listeners TMPDIR=/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 12:11:00 INFO - MultiFileLogger online at 20160711 12:11:00 in /builds/slave/test 12:11:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 12:11:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:11:00 INFO - {'append_to_log': False, 12:11:00 INFO - 'base_work_dir': '/builds/slave/test', 12:11:00 INFO - 'blob_upload_branch': 'Fx-Team', 12:11:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:11:00 INFO - 'branch': 'Fx-Team', 12:11:00 INFO - 'buildbot_json_path': 'buildprops.json', 12:11:00 INFO - 'config_files': ('talos/mac_config.py',), 12:11:00 INFO - 'default_actions': ('clobber', 12:11:00 INFO - 'read-buildbot-config', 12:11:00 INFO - 'download-and-extract', 12:11:00 INFO - 'populate-webroot', 12:11:00 INFO - 'create-virtualenv', 12:11:00 INFO - 'install', 12:11:00 INFO - 'run-tests'), 12:11:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:11:00 INFO - 'download_minidump_stackwalk': True, 12:11:00 INFO - 'download_symbols': 'ondemand', 12:11:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:11:00 INFO - 'tooltool.py': '/tools/tooltool.py', 12:11:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:11:00 INFO - '/tools/misc-python/virtualenv.py')}, 12:11:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:11:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:11:00 INFO - 'installer_path': 'installer.exe', 12:11:00 INFO - 'log_level': 'info', 12:11:00 INFO - 'log_name': 'talos', 12:11:00 INFO - 'log_to_console': True, 12:11:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:11:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:11:00 INFO - 'opt_config_files': (), 12:11:00 INFO - 'pip_index': False, 12:11:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:11:00 INFO - 'cmd': ('bash', 12:11:00 INFO - '-c', 12:11:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:11:00 INFO - 'enabled': True, 12:11:00 INFO - 'halt_on_failure': False, 12:11:00 INFO - 'name': 'check_screen_resolution'},), 12:11:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:11:00 INFO - 'cmd': ('bash', 12:11:00 INFO - '-c', 12:11:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:11:00 INFO - 'enabled': True, 12:11:00 INFO - 'halt_on_failure': False, 12:11:00 INFO - 'name': 'check_screen_resolution'},), 12:11:00 INFO - 'run_cmd_checks_enabled': True, 12:11:00 INFO - 'sps_profile': False, 12:11:00 INFO - 'sps_profile_interval': 0, 12:11:00 INFO - 'suite': 'dromaeojs', 12:11:00 INFO - 'system_bits': '32', 12:11:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:11:00 INFO - 'title': 't-yosemite-r7-0361', 12:11:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:11:00 INFO - 'use_talos_json': True, 12:11:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:11:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:11:00 INFO - 'work_dir': 'build'} 12:11:00 INFO - ##### 12:11:00 INFO - ##### Running clobber step. 12:11:00 INFO - ##### 12:11:00 INFO - Running pre-action listener: _resource_record_pre_action 12:11:00 INFO - Running main action method: clobber 12:11:00 INFO - rmtree: /builds/slave/test/build 12:11:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:11:02 INFO - Running post-action listener: _resource_record_post_action 12:11:02 INFO - ##### 12:11:02 INFO - ##### Finished clobber step (success) 12:11:02 INFO - ##### 12:11:02 INFO - ##### 12:11:02 INFO - ##### Running read-buildbot-config step. 12:11:02 INFO - ##### 12:11:02 INFO - Running pre-action listener: _resource_record_pre_action 12:11:02 INFO - Running main action method: read_buildbot_config 12:11:02 INFO - Using buildbot properties: 12:11:02 INFO - { 12:11:02 INFO - "project": "", 12:11:02 INFO - "product": "firefox", 12:11:02 INFO - "script_repo_revision": "production", 12:11:02 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 12:11:02 INFO - "repository": "", 12:11:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 12:11:02 INFO - "buildid": "20160711101826", 12:11:02 INFO - "pgo_build": "False", 12:11:02 INFO - "basedir": "/builds/slave/test", 12:11:02 INFO - "buildnumber": 840, 12:11:02 INFO - "slavename": "t-yosemite-r7-0361", 12:11:02 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 12:11:02 INFO - "platform": "yosemite_r7", 12:11:02 INFO - "scheduler": "tests-fx-team-macosx64-talos", 12:11:02 INFO - "branch": "fx-team", 12:11:02 INFO - "repo_path": "integration/fx-team", 12:11:02 INFO - "revision": "2e5b22652d834a59b920901b806c35c7943e678c", 12:11:02 INFO - "stage_platform": "macosx64", 12:11:02 INFO - "builduid": "96de0173acc748758f2e6afb1002a98d", 12:11:02 INFO - "slavebuilddir": "test" 12:11:02 INFO - } 12:11:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 12:11:02 INFO - Running post-action listener: _resource_record_post_action 12:11:02 INFO - ##### 12:11:02 INFO - ##### Finished read-buildbot-config step (success) 12:11:02 INFO - ##### 12:11:02 INFO - ##### 12:11:02 INFO - ##### Running download-and-extract step. 12:11:02 INFO - ##### 12:11:02 INFO - Running pre-action listener: _resource_record_pre_action 12:11:02 INFO - Running main action method: download_and_extract 12:11:02 INFO - mkdir: /builds/slave/test/build/tests 12:11:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:02 INFO - https://queue.taskcluster.net/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:11:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 12:11:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 12:11:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 12:11:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 12:11:05 INFO - Downloaded 1199 bytes. 12:11:05 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 12:11:05 INFO - Using the following test package requirements: 12:11:05 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 12:11:05 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:11:05 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 12:11:05 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:11:05 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 12:11:05 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:11:05 INFO - u'jsshell-mac.zip'], 12:11:05 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:11:05 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 12:11:05 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 12:11:05 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:11:05 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 12:11:05 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:11:05 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 12:11:05 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:11:05 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 12:11:05 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:11:05 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 12:11:05 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:11:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:05 INFO - https://queue.taskcluster.net/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:11:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:11:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:11:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:11:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:11:06 INFO - Downloaded 18772160 bytes. 12:11:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:11:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:11:09 INFO - Return code: 0 12:11:09 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:11:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:09 INFO - https://queue.taskcluster.net/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:11:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:11:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:11:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:11:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:11:10 INFO - Downloaded 18772160 bytes. 12:11:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:11:10 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:11:13 INFO - Return code: 0 12:11:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:13 INFO - https://queue.taskcluster.net/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:11:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 12:11:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 12:11:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 12:11:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:11:15 INFO - Downloaded 14403333 bytes. 12:11:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:11:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 12:11:16 INFO - Return code: 0 12:11:16 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')]} 12:11:16 INFO - https://queue.taskcluster.net/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 12:11:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:11:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:11:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 12:11:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 12:11:19 INFO - Downloaded 112390302 bytes. 12:11:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:11:19 INFO - mkdir: /builds/slave/test/properties 12:11:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:11:19 INFO - Writing to file /builds/slave/test/properties/build_url 12:11:19 INFO - Contents: 12:11:19 INFO - build_url:https://queue.taskcluster.net/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:11:20 INFO - Running post-action listener: _resource_record_post_action 12:11:20 INFO - Running post-action listener: set_extra_try_arguments 12:11:20 INFO - ##### 12:11:20 INFO - ##### Finished download-and-extract step (success) 12:11:20 INFO - ##### 12:11:20 INFO - ##### 12:11:20 INFO - ##### Running populate-webroot step. 12:11:20 INFO - ##### 12:11:20 INFO - Running pre-action listener: _resource_record_pre_action 12:11:20 INFO - Running main action method: populate_webroot 12:11:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:11:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:11:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:11:20 INFO - '--tppagecycles', 12:11:20 INFO - '7'], 12:11:20 INFO - 'tests': ['tsvgm']}}, 12:11:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:11:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:11:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:11:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:11:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:11:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:11:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:20 INFO - 'talos_options': ['--disable-e10s'], 12:11:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:11:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:11:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:20 INFO - 'talos_options': ['--disable-e10s'], 12:11:20 INFO - 'tests': ['damp', 'tps']}, 12:11:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:20 INFO - 'tests': ['damp', 'tps']}, 12:11:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:11:20 INFO - 'tests': ['dromaeo_dom']}, 12:11:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:11:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:11:20 INFO - 'tests': ['basic_compositor_video']}, 12:11:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:11:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:11:20 INFO - 'tests': ['a11yr', 12:11:20 INFO - 'ts_paint', 12:11:20 INFO - 'tpaint', 12:11:20 INFO - 'sessionrestore', 12:11:20 INFO - 'sessionrestore_no_auto_restore', 12:11:20 INFO - 'tabpaint']}, 12:11:20 INFO - 'other-e10s': {'tests': ['a11yr', 12:11:20 INFO - 'ts_paint', 12:11:20 INFO - 'tpaint', 12:11:20 INFO - 'sessionrestore', 12:11:20 INFO - 'sessionrestore_no_auto_restore', 12:11:20 INFO - 'tabpaint']}, 12:11:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:11:20 INFO - 'ts_paint', 12:11:20 INFO - 'tpaint', 12:11:20 INFO - 'sessionrestore', 12:11:20 INFO - 'sessionrestore_no_auto_restore', 12:11:20 INFO - 'tabpaint']}, 12:11:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:11:20 INFO - 'ts_paint', 12:11:20 INFO - 'tpaint', 12:11:20 INFO - 'sessionrestore', 12:11:20 INFO - 'sessionrestore_no_auto_restore', 12:11:20 INFO - 'tabpaint']}, 12:11:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:11:20 INFO - 'tests': ['a11yr', 12:11:20 INFO - 'ts_paint', 12:11:20 INFO - 'tpaint', 12:11:20 INFO - 'sessionrestore', 12:11:20 INFO - 'sessionrestore_no_auto_restore', 12:11:20 INFO - 'tabpaint']}, 12:11:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:11:20 INFO - 'tests': ['a11yr', 12:11:20 INFO - 'ts_paint', 12:11:20 INFO - 'tpaint', 12:11:20 INFO - 'sessionrestore', 12:11:20 INFO - 'sessionrestore_no_auto_restore', 12:11:20 INFO - 'tabpaint']}, 12:11:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:11:20 INFO - 'tests': ['tsvgx', 12:11:20 INFO - 'tsvgr_opacity', 12:11:20 INFO - 'tart', 12:11:20 INFO - 'tscrollx', 12:11:20 INFO - 'cart']}, 12:11:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:11:20 INFO - 'tsvgr_opacity', 12:11:20 INFO - 'tart', 12:11:20 INFO - 'tscrollx', 12:11:20 INFO - 'cart']}, 12:11:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:20 INFO - 'talos_options': ['--disable-e10s'], 12:11:20 INFO - 'tests': ['tp5o']}, 12:11:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:20 INFO - 'tests': ['tp5o']}, 12:11:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:20 INFO - 'talos_options': ['--disable-e10s', 12:11:20 INFO - '--xperf_path', 12:11:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:11:20 INFO - 'tests': ['tp5n']}, 12:11:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:20 INFO - 'talos_options': ['--xperf_path', 12:11:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:11:20 INFO - 'tests': ['tp5n']}}, 12:11:20 INFO - 'talos.zip': {'path': '', 12:11:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:11:20 INFO - Running post-action listener: _resource_record_post_action 12:11:20 INFO - ##### 12:11:20 INFO - ##### Finished populate-webroot step (success) 12:11:20 INFO - ##### 12:11:20 INFO - ##### 12:11:20 INFO - ##### Running create-virtualenv step. 12:11:20 INFO - ##### 12:11:20 INFO - Running pre-action listener: _resource_record_pre_action 12:11:20 INFO - Running main action method: create_virtualenv 12:11:20 INFO - Creating virtualenv /builds/slave/test/build/venv 12:11:20 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 12:11:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:11:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:11:20 INFO - Using real prefix '/tools/python27' 12:11:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:11:21 INFO - Installing distribute.............................................................................................................................................................................................done. 12:11:24 INFO - Installing pip.................done. 12:11:24 INFO - Return code: 0 12:11:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:11:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f203a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f298618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c3ac99250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f167d30>, '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 0x10eeccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f15fb20>, '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.P65Ifs0rx8/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.wpbVAtuRlm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 12:11:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:11:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:11:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P65Ifs0rx8/Render', 12:11:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:24 INFO - 'HOME': '/Users/cltbld', 12:11:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:24 INFO - 'LOGNAME': 'cltbld', 12:11:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:24 INFO - 'MOZ_NO_REMOTE': '1', 12:11:24 INFO - 'NO_EM_RESTART': '1', 12:11:24 INFO - 'PAGER': '/bin/cat', 12:11:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:24 INFO - 'PWD': '/builds/slave/test', 12:11:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:24 INFO - 'SHELL': '/bin/bash', 12:11:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wpbVAtuRlm/Listeners', 12:11:24 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 12:11:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:24 INFO - 'USER': 'cltbld', 12:11:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:24 INFO - 'XPC_FLAGS': '0x0', 12:11:24 INFO - 'XPC_SERVICE_NAME': '0', 12:11:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:24 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 12:11:24 INFO - Cleaning up... 12:11:24 INFO - Return code: 0 12:11:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:11:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f203a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f298618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c3ac99250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f167d30>, '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 0x10eeccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f15fb20>, '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.P65Ifs0rx8/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.wpbVAtuRlm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 12:11:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:11:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:11:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P65Ifs0rx8/Render', 12:11:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:24 INFO - 'HOME': '/Users/cltbld', 12:11:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:24 INFO - 'LOGNAME': 'cltbld', 12:11:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:24 INFO - 'MOZ_NO_REMOTE': '1', 12:11:24 INFO - 'NO_EM_RESTART': '1', 12:11:24 INFO - 'PAGER': '/bin/cat', 12:11:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:24 INFO - 'PWD': '/builds/slave/test', 12:11:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:24 INFO - 'SHELL': '/bin/bash', 12:11:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wpbVAtuRlm/Listeners', 12:11:24 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 12:11:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:24 INFO - 'USER': 'cltbld', 12:11:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:24 INFO - 'XPC_FLAGS': '0x0', 12:11:24 INFO - 'XPC_SERVICE_NAME': '0', 12:11:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:24 INFO - Downloading/unpacking psutil>=3.1.1 12:11:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:11:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:11:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:11:27 INFO - Installing collected packages: psutil 12:11:27 INFO - Running setup.py install for psutil 12:11:27 INFO - building 'psutil._psutil_osx' extension 12:11:27 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 12:11:28 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 12:11:28 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 12:11:28 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 12:11:28 INFO - building 'psutil._psutil_posix' extension 12:11:28 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 12:11:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:11:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:11:28 INFO - ^ 12:11:28 INFO - 1 warning generated. 12:11:28 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 12:11:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:11:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:11:28 INFO - Successfully installed psutil 12:11:28 INFO - Cleaning up... 12:11:28 INFO - Return code: 0 12:11:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:11: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')]} 12:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11: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')]} 12:11:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f203a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f298618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c3ac99250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f167d30>, '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 0x10eeccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f15fb20>, '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.P65Ifs0rx8/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.wpbVAtuRlm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 12:11:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:11:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:11:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P65Ifs0rx8/Render', 12:11:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:28 INFO - 'HOME': '/Users/cltbld', 12:11:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:28 INFO - 'LOGNAME': 'cltbld', 12:11:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:28 INFO - 'MOZ_NO_REMOTE': '1', 12:11:28 INFO - 'NO_EM_RESTART': '1', 12:11:28 INFO - 'PAGER': '/bin/cat', 12:11:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:28 INFO - 'PWD': '/builds/slave/test', 12:11:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:28 INFO - 'SHELL': '/bin/bash', 12:11:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wpbVAtuRlm/Listeners', 12:11:28 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 12:11:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:28 INFO - 'USER': 'cltbld', 12:11:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:28 INFO - 'XPC_FLAGS': '0x0', 12:11:28 INFO - 'XPC_SERVICE_NAME': '0', 12:11:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:11:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:11:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:11:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:11:31 INFO - Installing collected packages: mozsystemmonitor 12:11:31 INFO - Running setup.py install for mozsystemmonitor 12:11:31 INFO - Successfully installed mozsystemmonitor 12:11:31 INFO - Cleaning up... 12:11:31 INFO - Return code: 0 12:11:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:11:31 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')]} 12:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:31 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')]} 12:11:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:31 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f203a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f298618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c3ac99250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f167d30>, '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 0x10eeccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f15fb20>, '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.P65Ifs0rx8/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.wpbVAtuRlm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 12:11:31 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==2.5.1'] in /builds/slave/test/build 12:11:31 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==2.5.1 12:11:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P65Ifs0rx8/Render', 12:11:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:31 INFO - 'HOME': '/Users/cltbld', 12:11:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:31 INFO - 'LOGNAME': 'cltbld', 12:11:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:31 INFO - 'MOZ_NO_REMOTE': '1', 12:11:31 INFO - 'NO_EM_RESTART': '1', 12:11:31 INFO - 'PAGER': '/bin/cat', 12:11:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:31 INFO - 'PWD': '/builds/slave/test', 12:11:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:31 INFO - 'SHELL': '/bin/bash', 12:11:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wpbVAtuRlm/Listeners', 12:11:31 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 12:11:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:31 INFO - 'USER': 'cltbld', 12:11:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:31 INFO - 'XPC_FLAGS': '0x0', 12:11:31 INFO - 'XPC_SERVICE_NAME': '0', 12:11:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:32 INFO - Downloading/unpacking jsonschema==2.5.1 12:11:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:11:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:11: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 12:11: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 12:11: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 12:11: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 12:11: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 12:11: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 12:11:34 INFO - Downloading functools32-3.2.3-2.tar.gz 12:11:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:11:35 INFO - warning: no files found matching '*.txt' 12:11:35 INFO - no previously-included directories found matching 'build' 12:11:35 INFO - no previously-included directories found matching 'dist' 12:11:35 INFO - no previously-included directories found matching '.git*' 12:11:35 INFO - Installing collected packages: jsonschema, functools32 12:11:35 INFO - Running setup.py install for functools32 12:11:35 INFO - warning: no files found matching '*.txt' 12:11:35 INFO - no previously-included directories found matching 'build' 12:11:35 INFO - no previously-included directories found matching 'dist' 12:11:35 INFO - no previously-included directories found matching '.git*' 12:11:35 INFO - Successfully installed jsonschema functools32 12:11:35 INFO - Cleaning up... 12:11:35 INFO - Return code: 0 12:11:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:11:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f203a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f298618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c3ac99250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f167d30>, '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 0x10eeccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f15fb20>, '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.P65Ifs0rx8/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.wpbVAtuRlm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 12:11:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:11:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:11:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P65Ifs0rx8/Render', 12:11:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:35 INFO - 'HOME': '/Users/cltbld', 12:11:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:35 INFO - 'LOGNAME': 'cltbld', 12:11:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:35 INFO - 'MOZ_NO_REMOTE': '1', 12:11:35 INFO - 'NO_EM_RESTART': '1', 12:11:35 INFO - 'PAGER': '/bin/cat', 12:11:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:35 INFO - 'PWD': '/builds/slave/test', 12:11:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:35 INFO - 'SHELL': '/bin/bash', 12:11:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wpbVAtuRlm/Listeners', 12:11:35 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 12:11:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:35 INFO - 'USER': 'cltbld', 12:11:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:35 INFO - 'XPC_FLAGS': '0x0', 12:11:35 INFO - 'XPC_SERVICE_NAME': '0', 12:11:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:35 INFO - Downloading/unpacking blobuploader==1.2.4 12:11:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:38 INFO - Downloading blobuploader-1.2.4.tar.gz 12:11:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:11:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:11: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 12:11: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 12:11: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 12:11: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 12:11: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 12:11: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 12:11:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:11:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:11: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 12:11: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 12:11: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 12:11: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 12:11: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 12:11: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 12:11:39 INFO - Downloading docopt-0.6.1.tar.gz 12:11:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:11:39 INFO - Installing collected packages: blobuploader, requests, docopt 12:11:39 INFO - Running setup.py install for blobuploader 12:11:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:11:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:11:39 INFO - Running setup.py install for requests 12:11:39 INFO - Running setup.py install for docopt 12:11:40 INFO - Successfully installed blobuploader requests docopt 12:11:40 INFO - Cleaning up... 12:11:40 INFO - Return code: 0 12:11:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:11:40 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')]} 12:11:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:40 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')]} 12:11:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:40 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 0x10f203a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f298618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c3ac99250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f167d30>, '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 0x10eeccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f15fb20>, '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.P65Ifs0rx8/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.wpbVAtuRlm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 12:11:40 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 12:11:40 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 12:11:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P65Ifs0rx8/Render', 12:11:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:40 INFO - 'HOME': '/Users/cltbld', 12:11:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:40 INFO - 'LOGNAME': 'cltbld', 12:11:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:40 INFO - 'MOZ_NO_REMOTE': '1', 12:11:40 INFO - 'NO_EM_RESTART': '1', 12:11:40 INFO - 'PAGER': '/bin/cat', 12:11:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:40 INFO - 'PWD': '/builds/slave/test', 12:11:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:40 INFO - 'SHELL': '/bin/bash', 12:11:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wpbVAtuRlm/Listeners', 12:11:40 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 12:11:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:40 INFO - 'USER': 'cltbld', 12:11:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:40 INFO - 'XPC_FLAGS': '0x0', 12:11:40 INFO - 'XPC_SERVICE_NAME': '0', 12:11:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:11:40 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-euYICz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:11:40 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-PWjCKO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:11:40 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-U8vsE8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:11:40 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-Q5tC9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:11:40 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-AvWn7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:11:40 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-6FA3j1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:11:41 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-3khsHC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:11:41 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-IlhpQq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:11:41 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-Q4LTJ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:11:41 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-kAwl5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:11:41 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-m1AcH_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:11:41 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-rEsJaT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:11:41 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-Hms3ZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:11:41 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-CxJRXv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:41 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-tt2qgx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:11:42 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-R8Rj83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:11:42 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-lMNoPk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:11:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:11:42 INFO - Running setup.py install for manifestparser 12:11:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:11:42 INFO - Running setup.py install for mozcrash 12:11:42 INFO - Running setup.py install for mozdebug 12:11:42 INFO - Running setup.py install for mozdevice 12:11:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:11:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:11:42 INFO - Running setup.py install for mozfile 12:11:42 INFO - Running setup.py install for mozhttpd 12:11:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:11:43 INFO - Running setup.py install for mozinfo 12:11:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:11:43 INFO - Running setup.py install for mozInstall 12:11:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:11:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:11:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:11:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:11:43 INFO - Running setup.py install for mozleak 12:11:43 INFO - Running setup.py install for mozlog 12:11:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:11:43 INFO - Running setup.py install for moznetwork 12:11:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:11:43 INFO - Running setup.py install for mozprocess 12:11:44 INFO - Running setup.py install for mozprofile 12:11:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:11:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:11:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:11:44 INFO - Running setup.py install for mozrunner 12:11:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:11:44 INFO - Running setup.py install for mozscreenshot 12:11:44 INFO - Running setup.py install for moztest 12:11:44 INFO - Running setup.py install for mozversion 12:11:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:11:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:11:44 INFO - Cleaning up... 12:11:44 INFO - Return code: 0 12:11:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:11:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:44 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 0x10f203a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f298618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c3ac99250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f167d30>, '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 0x10eeccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f15fb20>, '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.P65Ifs0rx8/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.wpbVAtuRlm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 12:11:44 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 12:11:44 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 12:11:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P65Ifs0rx8/Render', 12:11:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:44 INFO - 'HOME': '/Users/cltbld', 12:11:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:44 INFO - 'LOGNAME': 'cltbld', 12:11:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:44 INFO - 'MOZ_NO_REMOTE': '1', 12:11:44 INFO - 'NO_EM_RESTART': '1', 12:11:44 INFO - 'PAGER': '/bin/cat', 12:11:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:44 INFO - 'PWD': '/builds/slave/test', 12:11:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:44 INFO - 'SHELL': '/bin/bash', 12:11:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wpbVAtuRlm/Listeners', 12:11:44 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 12:11:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:44 INFO - 'USER': 'cltbld', 12:11:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:44 INFO - 'XPC_FLAGS': '0x0', 12:11:44 INFO - 'XPC_SERVICE_NAME': '0', 12:11:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:11:45 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-u0AP9G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:11:45 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)) 12:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:11:45 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-jXCHsh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:11:45 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)) 12:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:11:45 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-_Xxk0P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:11:45 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)) 12:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:11:45 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-dwkTTK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:11:45 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)) 12:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:11:45 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-2WQK16-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:11:45 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)) 12:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:11:45 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-BWnB5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:11:45 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)) 12:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:11:45 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-_eanix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:11:45 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)) 12:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:11:45 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-bpidyE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:11:45 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)) 12:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:11:45 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-BIq9Vr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:11:46 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)) 12:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:11:46 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-kBqiOt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:11:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 12:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:11:46 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-dO6Q8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:11:46 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)) 12:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:11:46 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-UWcBoV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:11:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 12:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:11:46 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-AckmA5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:11:46 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)) 12:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:11:46 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-wipima-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:11:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 12:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:46 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-G0KVKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:46 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)) 12:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:11:46 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-onS_6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:11:46 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)) 12:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:11:46 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-GqvDwk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:11:46 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)) 12:11:46 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)) 12:11:46 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)) 12:11:46 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)) 12:11:46 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)) 12:11:46 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)) 12:11:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:11:46 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 12:11:46 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 12:11:46 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 12:11:46 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 12:11:46 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 12:11:46 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 12:11:49 INFO - Downloading blessings-1.6.tar.gz 12:11:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:11:49 INFO - Installing collected packages: blessings 12:11:49 INFO - Running setup.py install for blessings 12:11:49 INFO - Successfully installed blessings 12:11:49 INFO - Cleaning up... 12:11:49 INFO - Return code: 0 12:11:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:11:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:11:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:11:49 INFO - Reading from file tmpfile_stdout 12:11:49 INFO - Current package versions: 12:11:49 INFO - blessings == 1.6 12:11:49 INFO - blobuploader == 1.2.4 12:11:49 INFO - docopt == 0.6.1 12:11:49 INFO - functools32 == 3.2.3-2 12:11:49 INFO - jsonschema == 2.5.1 12:11:49 INFO - manifestparser == 1.1 12:11:49 INFO - mozInstall == 1.12 12:11:49 INFO - mozcrash == 0.17 12:11:49 INFO - mozdebug == 0.1 12:11:49 INFO - mozdevice == 0.48 12:11:49 INFO - mozfile == 1.2 12:11:49 INFO - mozhttpd == 0.7 12:11:49 INFO - mozinfo == 0.9 12:11:49 INFO - mozleak == 0.1 12:11:49 INFO - mozlog == 3.2 12:11:49 INFO - moznetwork == 0.27 12:11:49 INFO - mozprocess == 0.23 12:11:49 INFO - mozprofile == 0.28 12:11:49 INFO - mozrunner == 6.12 12:11:49 INFO - mozscreenshot == 0.1 12:11:49 INFO - mozsystemmonitor == 0.3 12:11:49 INFO - moztest == 0.7 12:11:49 INFO - mozversion == 1.4 12:11:49 INFO - psutil == 3.1.1 12:11:49 INFO - requests == 1.2.3 12:11:49 INFO - wsgiref == 0.1.2 12:11:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:11:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:49 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 0x10f203a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f298618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c3ac99250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f167d30>, '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 0x10eeccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f15fb20>, '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.P65Ifs0rx8/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.wpbVAtuRlm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 12:11:49 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 12:11:49 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 12:11:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P65Ifs0rx8/Render', 12:11:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:49 INFO - 'HOME': '/Users/cltbld', 12:11:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:49 INFO - 'LOGNAME': 'cltbld', 12:11:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:49 INFO - 'MOZ_NO_REMOTE': '1', 12:11:49 INFO - 'NO_EM_RESTART': '1', 12:11:49 INFO - 'PAGER': '/bin/cat', 12:11:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:49 INFO - 'PWD': '/builds/slave/test', 12:11:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:49 INFO - 'SHELL': '/bin/bash', 12:11:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wpbVAtuRlm/Listeners', 12:11:49 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 12:11:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:49 INFO - 'USER': 'cltbld', 12:11:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:49 INFO - 'XPC_FLAGS': '0x0', 12:11:49 INFO - 'XPC_SERVICE_NAME': '0', 12:11:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:50 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)) 12:11:50 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)) 12:11:50 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)) 12:11:50 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)) 12:11:50 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)) 12:11:50 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)) 12:11:50 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)) 12:11:50 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)) 12:11:50 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)) 12:11:50 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)) 12:11:50 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)) 12:11:50 INFO - Cleaning up... 12:11:50 INFO - Return code: 0 12:11:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:11:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:50 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 0x10f203a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f298618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c3ac99250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f167d30>, '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 0x10eeccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f15fb20>, '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.P65Ifs0rx8/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.wpbVAtuRlm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 12:11:50 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 12:11:50 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 12:11:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P65Ifs0rx8/Render', 12:11:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:50 INFO - 'HOME': '/Users/cltbld', 12:11:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:50 INFO - 'LOGNAME': 'cltbld', 12:11:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:50 INFO - 'MOZ_NO_REMOTE': '1', 12:11:50 INFO - 'NO_EM_RESTART': '1', 12:11:50 INFO - 'PAGER': '/bin/cat', 12:11:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:50 INFO - 'PWD': '/builds/slave/test', 12:11:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:50 INFO - 'SHELL': '/bin/bash', 12:11:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wpbVAtuRlm/Listeners', 12:11:50 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 12:11:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:50 INFO - 'USER': 'cltbld', 12:11:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:50 INFO - 'XPC_FLAGS': '0x0', 12:11:50 INFO - 'XPC_SERVICE_NAME': '0', 12:11:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:11:50 INFO - Cleaning up... 12:11:50 INFO - Return code: 0 12:11:50 INFO - Running post-action listener: _resource_record_post_action 12:11:50 INFO - Running post-action listener: _start_resource_monitoring 12:11:50 INFO - Starting resource monitoring. 12:11:50 INFO - ##### 12:11:50 INFO - ##### Finished create-virtualenv step (success) 12:11:50 INFO - ##### 12:11:50 INFO - ##### 12:11:50 INFO - ##### Running install step. 12:11:50 INFO - ##### 12:11:50 INFO - Running pre-action listener: _resource_record_pre_action 12:11:50 INFO - Running main action method: install 12:11:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:11:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:11:50 INFO - Reading from file tmpfile_stdout 12:11:50 INFO - Detecting whether we're running mozinstall >=1.0... 12:11:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:11:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:11:50 INFO - Reading from file tmpfile_stdout 12:11:50 INFO - Output received: 12:11:50 INFO - Usage: mozinstall [options] installer 12:11:50 INFO - Options: 12:11:50 INFO - -h, --help show this help message and exit 12:11:50 INFO - -d DEST, --destination=DEST 12:11:50 INFO - Directory to install application into. [default: 12:11:50 INFO - "/builds/slave/test"] 12:11:50 INFO - --app=APP Application being installed. [default: firefox] 12:11:50 INFO - mkdir: /builds/slave/test/build/application 12:11:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:11:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:12:25 INFO - Reading from file tmpfile_stdout 12:12:25 INFO - Output received: 12:12:25 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:12:25 INFO - Running post-action listener: _resource_record_post_action 12:12:25 INFO - ##### 12:12:25 INFO - ##### Finished install step (success) 12:12:25 INFO - ##### 12:12:25 INFO - ##### 12:12:25 INFO - ##### Running run-tests step. 12:12:25 INFO - ##### 12:12:25 INFO - Running pre-action listener: _resource_record_pre_action 12:12:25 INFO - Running main action method: run_tests 12:12:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:12:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:12:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:12:25 INFO - 2016-07-11 12:12:25.024 screenresolution[1647:8177] starting screenresolution argv=screenresolution get 12:12:25 INFO - 2016-07-11 12:12:25.026 screenresolution[1647:8177] Display 0: 1600x1200x32@60 12:12:25 INFO - 2016-07-11 12:12:25.032 screenresolution[1648:8179] starting screenresolution argv=screenresolution list 12:12:25 INFO - Available Modes on Display 0 12:12:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:12:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:12:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:12:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:12:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:12:25 INFO - Intel Iris: 12:12:25 INFO - Chipset Model: Intel Iris 12:12:25 INFO - Type: GPU 12:12:25 INFO - Bus: Built-In 12:12:25 INFO - VRAM (Dynamic, Max): 1536 MB 12:12:25 INFO - Vendor: Intel (0x8086) 12:12:25 INFO - Device ID: 0x0a2e 12:12:25 INFO - Revision ID: 0x0009 12:12:25 INFO - Displays: 12:12:25 INFO - Display: 12:12:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:12:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:12:25 INFO - Display Serial Number: 1600x1200 60 12:12:25 INFO - Main Display: Yes 12:12:25 INFO - Mirror: Off 12:12:25 INFO - Online: Yes 12:12:25 INFO - Rotation: Supported 12:12:25 INFO - Return code: 0 12:12:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:12:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:12:25 INFO - Python 2.7.3 12:12:25 INFO - Return code: 0 12:12:25 INFO - grabbing minidump binary from tooltool 12:12:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:12:25 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f167d30>, '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 0x10eeccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f15fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:12:25 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 12:12:25 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 12:12:25 INFO - Calling ['/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'] with output_timeout 600 12:12:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:12:25 INFO - Return code: 0 12:12:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:12:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:12:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:12:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:12:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:12:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0361', '--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 12:12:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0361 --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 12:12:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P65Ifs0rx8/Render', 12:12:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:12:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:12:25 INFO - 'HOME': '/Users/cltbld', 12:12:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:12:25 INFO - 'LOGNAME': 'cltbld', 12:12:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:12:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:12:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:25 INFO - 'MOZ_NO_REMOTE': '1', 12:12:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:12:25 INFO - 'NO_EM_RESTART': '1', 12:12:25 INFO - 'PAGER': '/bin/cat', 12:12:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:12:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:25 INFO - 'PWD': '/builds/slave/test', 12:12:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:12:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:12:25 INFO - 'SHELL': '/bin/bash', 12:12:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wpbVAtuRlm/Listeners', 12:12:25 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 12:12:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:12:25 INFO - 'USER': 'cltbld', 12:12:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:12:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:12:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:25 INFO - 'XPC_FLAGS': '0x0', 12:12:25 INFO - 'XPC_SERVICE_NAME': '0', 12:12:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:12:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0361', '--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 12:12:25 INFO - mozversion application_buildid: 20160711101826 12:12:25 INFO - mozversion application_changeset: 2e5b22652d834a59b920901b806c35c7943e678c 12:12:25 INFO - mozversion application_display_name: Nightly 12:12:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:12:25 INFO - mozversion application_name: Firefox 12:12:25 INFO - mozversion application_remotingname: firefox 12:12:25 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 12:12:25 INFO - mozversion application_vendor: Mozilla 12:12:25 INFO - mozversion application_version: 50.0a1 12:12:25 INFO - mozversion platform_buildid: 20160711101826 12:12:25 INFO - mozversion platform_changeset: 2e5b22652d834a59b920901b806c35c7943e678c 12:12:25 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 12:12:25 INFO - mozversion platform_version: 50.0a1 12:12:25 INFO - using testdate: 1468264345 12:12:25 INFO - actual date: 1468264345 12:12:25 INFO - starting webserver on 'localhost:49281' 12:12:25 INFO - SUITE-START | Running 2 tests 12:12:25 INFO - TEST-START | dromaeo_css 12:12:25 INFO - operating with platform_type : mac_ 12:12:25 INFO - Initialising browser for dromaeo_css test... 12:12:25 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/tmpEfh46Y/profile http://localhost:49281/getInfo.html) 12:12:27 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 12:12:27 INFO - PROCESS | 1658 | colorDepth:24 12:12:27 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 12:12:27 INFO - PROCESS | 1658 | __metrics 12:12:28 INFO - PROCESS | 1658 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:12:28 INFO - PROCESS | 1658 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:12:28 INFO - TEST-INFO | 1658: exit 0 12:12:28 INFO - Browser initialized. 12:12:28 INFO - Running cycle 1/1 for dromaeo_css test... 12:12:28 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/tmpEfh46Y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:12:40 INFO - PROCESS | 1661 | RSS: Main: 243535872 12:12:40 INFO - PROCESS | 1661 | 12:13:32 INFO - PROCESS | 1661 | 1468264412371 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] 12:15:42 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49281/tests/dromaeo/cssquery-ext.html) 12:15:43 INFO - PROCESS | 1661 | RSS: Main: 354484224 12:15:43 INFO - PROCESS | 1661 | 12:18:46 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-ext.html (next: http://localhost:49281/tests/dromaeo/cssquery-jquery.html) 12:18:47 INFO - PROCESS | 1661 | RSS: Main: 381571072 12:18:47 INFO - PROCESS | 1661 | 12:21:49 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49281/tests/dromaeo/cssquery-mootools.html) 12:21:50 INFO - PROCESS | 1661 | RSS: Main: 371695616 12:21:50 INFO - PROCESS | 1661 | 12:24:52 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49281/tests/dromaeo/cssquery-prototype.html) 12:24:53 INFO - PROCESS | 1661 | RSS: Main: 371286016 12:24:53 INFO - PROCESS | 1661 | 12:27:56 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49281/tests/dromaeo/cssquery-yui.html) 12:27:57 INFO - PROCESS | 1661 | RSS: Main: 339968000 12:27:57 INFO - PROCESS | 1661 | 12:31:00 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-yui.html (next: http://localhost:49281/tests/dromaeo/cssquery-dojo.html) 12:31:00 INFO - PROCESS | 1661 | RSS: Main: 380112896 12:31:00 INFO - PROCESS | 1661 | 12:31:00 INFO - PROCESS | 1661 | __start_tp_report 12:31:00 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 12:31:00 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 12:31:00 INFO - PROCESS | 1661 | |i|pagename|runs| 12:31:00 INFO - PROCESS | 1661 | |0;dojo.html;2855.514562524371;3375.0381141374696;3386.50895620135;3391.6099648540426;3432.5022871683605;5651.971740141304;5693.2883389576255;5711.885762284752;5848.2689663792025;5862.501687356575;5692.260006199188;5716.256886604746;5821.18503409522;5838.211841401409;5841.941580584193;5196.92204616931;5232.372115346164;5259.184826351921;5275.841724748264;5370.248165256848;5302.787888484456;5371.194320851884;5378.596605254602;5391.245225668409;5420.430854760252;4218.936715949263;5186.351706036764;5239.1093514102695;5239.685618862881;5305.920411193835;4102.343625020012;5201.609879259071;5204.661696989721;5206.479352064801;5310.70791106489;5172.32759741235;5189.584833213372;5194.662346947474;5251.238570407308;5307.973460132714;5542.224088627595;5828.358880523139;5846.883062338729;5859.941400585983;5862.941370586324;6180.3201647818705;6228.532860035515;6264.123022776815;6287.962486189773;6295.716692748837;6268;6290.3709629037185;6328.240611126648;6342.968285158591;6345.555811093179;5757.85605359861;5820.82537523875;5871.266091738533;5925.4074592541265;5979.880402391971;5303.893922121536;6273.843153921097;6302.243730752339;6327.462165715873;6341.270753863365;6160.291566469824;6195.535334849904;6202.34875338083;6203.069539569011;6222.875542489125;6297;6323.241211054703;6353.745850166034;6358.936410635927;6370.8088757337355;6325.272593651789;6328.335524769833;6338.841528961812;6345.841353966095;6351.333110023474;6151.84620384494;6194.5044396448175;6263.655498947602;6266.091416744546;6324.146240257506;4625.098105869391;5022.447530771612;5037.974810125999;5045.571126454219;5081.3140226069745;4207.326827707552;4494.528074552125;4501.18978583858;4505.977470112629;4555.316702494652;4711.646626503026;4853.975730121327;5688.260526131659;5717.456841600041;5760.8271751847515;6323.494120470351;6329.7468101275435;6338.271098823602;6358.1734374531015;6368.586041907354;5815.651060936358;5816.796412125555;5822.243108395966;5836.708164591839;5846.356900740915;5386.218998245311;5447.101228297287;5480.862978425492;5487.972560137174;5524.558035357162;6368.10846481493;6375.330590288046;6376.904346434807;6378.1070650108095;6378.840528986812;6190.41191086846;6270.516580644861;6316.968415158079;6326.525510586633;6335.239771227482;6233.0338797486465;6263.65549894742;6285.685715714288;6292.590981586182;6347.333529979471;6253.843653908689;6280.685965701788;6284.088807123033;6292.716827742855;6293.4335909766505;5097.566706829961;6018.398160183947;6335.461485773761;6343.3022367538615;6347.936520634735;5842.269716285465;5891.1457838614015;5892.616979896293;5911.142884281841;5954.136650185612;5878.706064696663;5884.117382392676;5885.852853678693;5885.911106445321;5898.233229680114;5623.522000629992;5781.161731548986;5933.495652869555;6040.516758659209;6064.272287325549;6228.564000520101;6239.126522286792;6246.843828904313;6248.968755156195;6261.624302541862;6121.32665406796;6130.601510901777;6134.4172303631085;6135.355787642234;6142.14010038586 12:31:00 INFO - PROCESS | 1661 | |1;ext.html;457.544742980734;479.9808007679693;491.8426103646834;521.6583138044581;547.8986387518307;30205;30823.537646935314;42430.36354454685;42993.785031074876;43013.13973720532;43997.68006959788;44183.77908110463;44493;44495;44599.331010034795;9724.902750972506;9911.306208565367;9912.900870991305;9914.652987145451;9923.503824808768;9682.322237443383;9779.657711980082;9794.706158815212;9804.362716423446;9814.018598140201;9750.36622619531;9804.01959804021;9805.803883922317;9807.852882206773;9832.852507212398;9752.512374381287;9805.95097024514;9806.313558050939;9806.705798826046;9827.950860245724;9754.756131096708;9787.90212097877;9794.314397992144;9801.852972205423;9803.411795292233;41412.1717565647;41696.37455438141;41813.58186418158;41839.7908010461;41864.581354186375;3509.0525558099425;3975.542812576533;4204.915901681979;4315.978420107911;4364.847230346954;31457.05628831139;32583;32845.34309313824;32927.50608740871;33169.50245746316;3229.6770322967513;3317.2204531934976;3386.136535183537;3402.812845293508;3429.108431807723;482470;488061;491447;492424;496203;59683;59884.10173847396;60340.09489857656;60573.39426605766;60589.394106059255;63403;63670.68164659147;63869.36130638634;63898.36101639017;64004.67997660075;16504.83495165033;16713.665726685398;16717.91641041787;16718.24767885448;16723.91638041802;12919.22484650924;12968.67578310531;12975.545855894998;12981.805272920914;12985.480580776852;15584.45454409113;16359.263833127538;16684.249208785634;16701.164941752864;16742.58143546421;5973.431424487512;6151.246894635007;7091.680874360666;7161.212266650664;7238.638068096574;880.9956649419667;999.0459111548599;1026.2918586175515;1037.9221558383151;1109.4840898982013;29902.551461728093;30264.638091285946;30339.696603033688;30876.53685194724;30984.380312394078;1064.3932958213743;1107.1751545098791;1145.6104924325768;1203.2312500624068;1236.839210902595;15353.539393818204;15759.763603545956;15772;15845.52463426099;16008.599785005468;620341;624893;630902;632345;634040;58759;58767;58827.70586147042;58883.11675324874;59031.11453328204;1259.5213818748816;1374.3815283122635;1405.6907480354305;1421.0976030220677;1425.5153247895764;31506.684933151293;31601;31838.52242216415;31871.52192717065;32533;49791.50208497869;50155;50821.74589127031;50892.74553627208;51005;542004;554589.2270538622;555039;556877;557092;34781.47827782535;35695.10762230965;40901;41744.79127604343;41873.58126418697;47205;47845.76077119731;48003.039939200324;48734.0253194927;49015;39286;39418;39745.40381894332;40034.39948400835;40580.78257652275;39312;39386.40920386139;39877.401838971855;40020.599794002854;40675.389869152576;38146;38197.427038595;38700.6129938697;38702.41946370863;38956;33629.14525922404;34270.80052198261;34748.65251347555;34879;34988;24735.62896556589;24814.25557233286;25401.74598253994;25819;25861.353466163495 12:31:00 INFO - PROCESS | 1661 | |2;jquery.html;358.06723485320725;382.0734718308103;385.11423725431507;387.92047630235805;410.0118713899503;15697.686046279068;15715.371385144595;20866.582668346626;20957;21019.264325748565;21372.8931355343;21895.562088758255;22184;22230.444238894037;22243.44391390216;5343.1985202219785;6042.969785151068;6234.064890266451;6250.656213908233;6266.905996410058;6128.233970753656;6215.5338349623735;6244.344343843899;6250.406211409909;6253.562250642457;5891.381404952483;6167.105769663396;6167.414095660905;6169.537284703642;6171.382861713815;5903.734331955073;6127.203463549732;6170.321264660884;6223.284322302925;6225.626462412247;5325.813596524103;5345.572354211693;5353;5360.088784906575;5363.812266570691;26192.34519137018;26377.3405664858;27173.184804455897;27175.45649087027;27304.863475682694;2477.962830557543;2497.2633073243437;2634.3414146463383;2775.4244635682285;2926.2830606501543;12563.874361256361;16549.751753723704;16634.58413539659;16964.3214271429;17013.319467221296;1455.745244582194;1895.8293753562107;1934.9226030958885;2179.2917301876796;2269.807066399358;103381;116237;116362.25456618072;136262.31868840693;150498.7425188612;17878.731819022723;18896.905515472336;21362.78637213639;21763.12947482083;22034;36452.81773591115;36458.45312320317;36507.26985460329;36514.63485365165;36554.63445365566;10245.948770256102;10836.837447438294;11270.436478176221;11279.94360028211;11407;7906.486078404885;7930.325922296554;9023.548822558845;9273.675421360354;9406.247500199968;8092.595370231465;9830.311878168459;9837.704868853945;9981.500924953723;9984.850227246598;3171.7304029157317;3267.986924053551;3724.273766615538;3810.741815747496;3869.5936926622862;844.2233145506149;910.5902343945252;930.0141849639404;950.619752099152;962.629387685746;21633.459163521038;21970.560588788136;22032.33902982913;22069.779302207095;22262.554748905255;798.273571050353;893.7676204186828;927.3137877970254;970.8495183246608;1075.9731006724894;10704.785904282027;11464.4267786612;11741.530338786524;11814.35021073832;11842.88157118435;511529;518436;519533;548764.2561787246;580630;91444.5427772857;135118.3244083793;138256;156250.21874890552;157365.21317393339;889.621910687968;926.0276709455045;933.118203297877;982.0964712464549;1011.3780025284398;16914.66170676604;19718.507037324183;19780;20370.388888333375;20461.488462788548;55461.445385547246;58318.7084064577;61090.38909610847;61940.69029654823;62350.68824655848;86630;93055.53472232596;93275;94264;95608.52195738976;7948.443608947318;8289.917100829156;8443.197896199852;8501.574921253788;8591.398602097794;12648.810267845805;13468.124571902796;13508.932455337661;13758.31208439594;13805.447782088604;9259.953700231456;11516;11695.941520292685;11736.941315293368;11788.29270243788;11390.202685811913;11507.827382589436;11546.769064618493;11626.53493860236;11730.354830484064;9877.814662240577;11296.322220666787;11868.525258989544;11946.880531194576;12049.216800908263;8229.876551851607;8365.749027529184;8372.455790373606;8501.10738372462;8553.187447192508;11536.769264614493;11606.535738570363;11776.587819426279;11824.940875295568;11882.989945854699 12:31:00 INFO - PROCESS | 1661 | |3;mootools.html;1347.5351003903656;1580.6838632273545;1723.8189990051044;1883.8116188381155;1907.1036612791984;8443.36674749394;8713.825723485526;8900.332475064371;8910.643574257021;8934.642614295428;7059.7882063538045;7765.029371328584;7980.164562150709;8634.309255259579;8710.60802263898;2378.298401971418;2399.568077746005;2405.194259922928;2406.5307265083316;2406.568091985275;2380.5238952209543;2384.7972922301624;2416.927492175229;2417.419819243378;2420.891059902309;2105.3368189020484;2184.399290195201;2320.187934223017;2396.2691379129446;2397.244867866625;2364.7280562735245;2381.630847218684;2393.0547433763654;2398.3404563744934;2414;2381.4403615150427;2391.8445301055376;2394.2817154853647;2400.699912510936;2406.4585468269674;8808.383413161082;8955;8964.19322260993;8967.955160224223;8969.686060987835;5248.81629142982;5425.9728701356635;5435.1847222916485;5439.428859969686;5445.782168713246;3487.099530755744;3586.4799604057525;3656.4332528457867;3673.504076949631;3684.078980254936;1081.118888106191;1094.9890501095047;1103.3269705479652;1117.5138814615586;1120.2046546951592;11679.416029198506;11681.591144309994;11692.298462092216;11700.06399488039;11717.648470545897;4530.821986283524;4668.276417155346;4787.54518320759;4811.195801574409;4813.374261346003;9895.455749933823;9901.306908516479;9906.702798916043;9919.900800991898;9923.65267215644;5372.086745253317;5377.085895397791;5383.300170977748;5383.734822316753;5384.0847056000575;4114.280000999813;4936.185529387611;4940.629452791055;4945.431275403303;4952.455229924705;3918.8628398005935;4002.139539998914;4036.6770658347264;4082.8775136745944;4121.361189015707;1170.3329102411544;1189.339916346436;1274.7323062156865;1295.5530342031984;1310.960671179866;770.3836930455614;903.8734577159203;943.580106852444;966.9274804389698;979.28136121107;5868.735906884201;5943.3462319144855;5954.791582294599;6039.094135879654;6104.145419641254;971.6259240192577;979.1579241852002;988.907257480488;992.3847214726916;1041.953112109957;3876.457295978565;3974.344233201547;4024.195160967818;4039.6162364575316;4086.0602061526015;10420.11429028541;10718.839217411745;10774.084202842847;10788.946055269831;10837.295575787548;9733.56198971048;10784.568617255221;10788.352698838095;10801.567937282422;10829.458527073615;7911.406644501685;8251.216134467217;8272;8379;8391.160883911682;5950.642961422329;6000.729967151401;6046.818595442144;6124;6180.6291622502795;9513.334066615547;10102.09081182697;10123.544440500047;10125.341852779546;10132;10056.145227655732;10212.13196878244;10251.897481025095;10264.846027309746;10267.229957753048;7433.2195119513835;7458.627068646438;7545.622718863925;7616.8476630468185;7617.162112167546;8148.185181481805;8185.549794761345;8259.21537453941;8263.00843898736;8274.544900030556;6931.376176144171;6955.582665040114;7355.448341374311;7468.925310746823;7615.428842836699;7371.889421658572;7399.260073992557;7411.664176456833;7436.219196984239;7522.623868806647;6754.831129221809;6872.553284036521;6877.4841886857685;6885.1049363582415;6900.516963812485;4821.083994041121;4925.679830810986;4938.9259161111895;4945.505449455026;5050.646454748133;4917.827876024322;4946.678465899705;4952.826651067196;4955.331030310862;5056.949430505648 12:31:00 INFO - PROCESS | 1661 | |4;prototype.html;305.6973596139822;311.1971114524527;314.63030938647086;319.47765403565177;323.08566756080296;4218.7257828241145;4687;5010.874728131798;5284.682919024856;5361.437049109846;5025.748712564376;5315.707636080024;5322.8137015204475;5340.572754179666;5343.759530821113;3863.517060367454;3881.9029524261837;4041.4746083009313;4049.7165198436123;4080.510338759353;3289.6545862684434;4048.67610591152;4088.5707000764787;4092.386142078697;4115.176964607075;4019.2966230909474;4029.435878976946;4059.4113853491226;4061.878143655695;4064;3385.6106547747077;3584.874529391485;3664.725145614063;3908.1402091539817;4036.8183431745497;3042.7413669838093;3422.3839708852397;3516.4549494828343;3532.434810430319;3536.7347448941173;4639.605633521155;4961.18140506816;5119.875251770511;5155.587552995751;5159.097157997373;3514.6485351464903;4149.775816134248;4417.955820441786;4629.953700462986;4638.930416043762;5543.805966791143;5587.664740115606;5598.440155984369;5612.522935550483;5658.349289831681;3678.705703543737;3842.155765232676;3846.74996125251;3850.26844899471;3863.3818589025623;5054.519820617035;5354.491323324278;5483.396826349099;5533.399302327899;5849.736761845727;3421.6219727289795;3865.4588357629955;3868.516435445569;3869.632384923484;3886.514185726784;3153.921151971219;3306.5205545195813;3482.2861313430685;3937.586553411908;3949.8025098745525;3607.3867442534834;4539.341795439643;4580.587747102777;4588.334691469719;4592.196365636;5183.481651834862;5333.439988801198;5652.3217213934595;5659.490645841894;5762.567807414461;1220.6887243752785;1287.3884904670206;1338.8728070833256;1355.2952464718292;1355.9897876082382;788.511123103668;813.8535063688474;814.1370147643516;834.4909605140859;836.4897412578322;4883.560479556857;4978.90042199154;5136.406114911945;5152.6650767699975;5172.043172013115;4525.389072475174;4841.564259216688;4863.395079623716;5465.398806131322;5786.739596718157;4052.452918855976;4974.806046548802;5041.7227052511735;5271.683698978073;5283.2075188722;2591.922242332733;2854.5432730762977;2960.2895305126613;3007.338385555174;3043.345680678665;5930.733117009745;5931.911021334683;5955.28536575605;5961.5230781537375;6016.608920420246;5546.223528705985;5618.314505648289;5923.156884695284;5944.554158438134;5964.135200396005;5323.562638087695;5418.8103416379445;5743.506688261082;6013.504395675745;6022.969885150634;5595.669662809721;5812.244408226903;5834.504067154339;5846;5882.852928676817;5071.594272458121;5738.971305143447;5774.40151931624;5778.94221057784;5828.901595425728;5963.522918166623;5982.341942386247;5989.1914591529585;6053.576249662481;6083.452489275986;3487.9651203487642;3783.39465685489;4032.548282618314;4045.2920738871167;4145.54399016114;5241.8246427732065;5463.972680136574;5765.625234359753;5772.7690892363835;5802.82591522255;5247.921281180862;5542;5617.241672374341;5650.689212093374;5659.207710920557;4479.888002799956;4648.372469716545;5623.690697011703;5694.241800411708;5721.456461636138;4757.833475828329;5363.80853392231;5524.502794748492;5545.086945004128;5559;5260.921511090294;5343.446608005633;5365.704886231295;5611;5630.239917611234;4601.6548758842555;4899.534544218293;5251.36983561975;5545.334559852843;5608.831735047955 12:31:00 INFO - PROCESS | 1661 | |5;yui.html;749.56899782625;791.0783936713729;796.8087658961849;798.5129071266526;855.8587833007558;16016.919915400422;16797.244124014418;16819.83180168198;16852.32590696372;16856;14827.777583336232;14994.550163495112;15002.849971500282;15025.248737563106;15045.77431338528;709.2446544430192;726.1467775363952;888.2538667519283;888.4180861535705;914;909.486140330711;909.586138307072;910.45372776334;912.2200518556605;914.9039350868163;950.6292545907102;963.9614415423409;967.6468089147481;968.3366893677818;971.2666936462961;741.5550669598252;937.5874615169304;958.4297343080891;960.2701946520625;960.2701946520659;715.9391451726609;718.3140101203338;718.5724493926106;719.352582675591;720.5100531638484;15293.61765955849;15308.158051307173;15344.386224551115;15360.462383816624;15376;14039.64900877476;14217.928910355486;14217.928910355486;14231.928840355731;14249.145051296851;12673.17624354414;12732.23606583608;12799.74400511994;13009.739805203842;13227.603171904762;4555.521670224612;4568.977155114237;4605.585497305258;4691.507391723854;4730.692504987165;104920;105915.47042264817;106240;106816.93183068225;107696;11086.722831929104;11148.275362101438;11216.495257713423;11407.771844563062;11725.003374713246;22036.779632203474;22285;22307.888460557595;22311.665325020138;22604.886975565016;7990.880136797953;8035.276825085653;8077.151899050633;8207.794805129919;8227.670893164206;4237.8304867804945;4283.550227226158;4452.1763473757455;4465.709728867613;4634.9536504634525;3438.346714124308;3580.695640870503;3588.946165807515;3590.389633762267;3595.442706380515;642.1683919324486;654.3456543456543;660.7158921663731;661.3155384177353;663.6947004377944;537.3149234725756;538.8114160043954;566.8044524638993;569.9287589051369;587.7208326045094;7321.963390183121;7805.790102534116;8586.957065214634;8871.379003469825;9071.637134514678;547.1191381875178;555.2226882364641;584.7514806207345;611.7461253579714;613.310026220502;4968.155413579628;4978.551930326288;5025.447200807908;5056.191009438472;5105.46165665591;168522;169228.3077169237;170787;172066.27933720752;173966;106392;142939;143936;144014;146955.5304446963;121179.18231226539;123426.76573234511;123788;124400;125460;10020.148287395361;10090.94954525252;10129;10189.082982531607;10210.795784084425;50677.23984140167;62948;65313.02030469452;65595;66545.00364989058;97243.64723467862;102261.18367876673;105563;106665;107219;11024.228304018641;13461.259630720404;13591.980601454732;13669.38487768033;14239.64400889986;15259.23703814827;15556;15585.298661560484;15738.921305393858;16560.420385286456;8391.930046045498;14403.639909002359;14788.852111478747;14940.252987350807;15074.849251507783;14219.217943013233;14310.642233944234;14584.270786460847;14936.477223297132;15021.549353519413;13862.861371386158;14127.293635318398;14330.426782928565;14389.136651800925;14764;9191.724248272563;11525.135614828754;11747.236429632047;12014.699632509257;12055.397230138282;9369.765755856159;11387.42741531588;11641.708957276136;11674.591389301673;11839.230450020721 12:31:00 INFO - PROCESS | 1661 | __end_tp_report 12:31:00 INFO - PROCESS | 1661 | __start_cc_report 12:31:00 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,1135 12:31:00 INFO - PROCESS | 1661 | __end_cc_report 12:31:00 INFO - PROCESS | 1661 | __startTimestamp1468265460528__endTimestamp 12:31:00 INFO - PROCESS | 1661 | 12:31:00 INFO - PROCESS | 1661 | ------- Summary: start ------- 12:31:00 INFO - PROCESS | 1661 | Number of tests: 6 12:31:00 INFO - PROCESS | 1661 | 12:31:00 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5754.17 Median:5891.15 stddev:686.72 (11.7%) stddev-sans-first:650.09 12:31:00 INFO - PROCESS | 1661 | Values: 2855.5 3375.0 3386.5 3391.6 3432.5 5652.0 5693.3 5711.9 5848.3 5862.5 5692.3 5716.3 5821.2 5838.2 5841.9 5196.9 5232.4 5259.2 5275.8 5370.2 5302.8 5371.2 5378.6 5391.2 5420.4 4218.9 5186.4 5239.1 5239.7 5305.9 4102.3 5201.6 5204.7 5206.5 5310.7 5172.3 5189.6 5194.7 5251.2 5308.0 5542.2 5828.4 5846.9 5859.9 5862.9 6180.3 6228.5 6264.1 6288.0 6295.7 6268.0 6290.4 6328.2 6343.0 6345.6 5757.9 5820.8 5871.3 5925.4 5979.9 5303.9 6273.8 6302.2 6327.5 6341.3 6160.3 6195.5 6202.3 6203.1 6222.9 6297.0 6323.2 6353.7 6358.9 6370.8 6325.3 6328.3 6338.8 6345.8 6351.3 6151.8 6194.5 6263.7 6266.1 6324.1 4625.1 5022.4 5038.0 5045.6 5081.3 4207.3 4494.5 4501.2 4506.0 4555.3 4711.6 4854.0 5688.3 5717.5 5760.8 6323.5 6329.7 6338.3 6358.2 6368.6 5815.7 5816.8 5822.2 5836.7 5846.4 5386.2 5447.1 5480.9 5488.0 5524.6 6368.1 6375.3 6376.9 6378.1 6378.8 6190.4 6270.5 6317.0 6326.5 6335.2 6233.0 6263.7 6285.7 6292.6 6347.3 6253.8 6280.7 6284.1 6292.7 6293.4 5097.6 6018.4 6335.5 6343.3 6347.9 5842.3 5891.1 5892.6 5911.1 5954.1 5878.7 5884.1 5885.9 5885.9 5898.2 5623.5 5781.2 5933.5 6040.5 6064.3 6228.6 6239.1 6246.8 6249.0 6261.6 6121.3 6130.6 6134.4 6135.4 6142.1 12:31:00 INFO - PROCESS | 1661 | 12:31:00 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:69951.65 Median:41869.08 stddev:149380.87 (356.8%) stddev-sans-first:149708.80 12:31:00 INFO - PROCESS | 1661 | Values: 457.5 480.0 491.8 521.7 547.9 30205.0 30823.5 42430.4 42993.8 43013.1 43997.7 44183.8 44493.0 44495.0 44599.3 9724.9 9911.3 9912.9 9914.7 9923.5 9682.3 9779.7 9794.7 9804.4 9814.0 9750.4 9804.0 9805.8 9807.9 9832.9 9752.5 9806.0 9806.3 9806.7 9828.0 9754.8 9787.9 9794.3 9801.9 9803.4 41412.2 41696.4 41813.6 41839.8 41864.6 3509.1 3975.5 4204.9 4316.0 4364.8 31457.1 32583.0 32845.3 32927.5 33169.5 3229.7 3317.2 3386.1 3402.8 3429.1 482470.0 488061.0 491447.0 492424.0 496203.0 59683.0 59884.1 60340.1 60573.4 60589.4 63403.0 63670.7 63869.4 63898.4 64004.7 16504.8 16713.7 16717.9 16718.2 16723.9 12919.2 12968.7 12975.5 12981.8 12985.5 15584.5 16359.3 16684.2 16701.2 16742.6 5973.4 6151.2 7091.7 7161.2 7238.6 881.0 999.0 1026.3 1037.9 1109.5 29902.6 30264.6 30339.7 30876.5 30984.4 1064.4 1107.2 1145.6 1203.2 1236.8 15353.5 15759.8 15772.0 15845.5 16008.6 620341.0 624893.0 630902.0 632345.0 634040.0 58759.0 58767.0 58827.7 58883.1 59031.1 1259.5 1374.4 1405.7 1421.1 1425.5 31506.7 31601.0 31838.5 31871.5 32533.0 49791.5 50155.0 50821.7 50892.7 51005.0 542004.0 554589.2 555039.0 556877.0 557092.0 34781.5 35695.1 40901.0 41744.8 41873.6 47205.0 47845.8 48003.0 48734.0 49015.0 39286.0 39418.0 39745.4 40034.4 40580.8 39312.0 39386.4 39877.4 40020.6 40675.4 38146.0 38197.4 38700.6 38702.4 38956.0 33629.1 34270.8 34748.7 34879.0 34988.0 24735.6 24814.3 25401.7 25819.0 25861.4 12:31:00 INFO - PROCESS | 1661 | 12:31:00 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:35789.86 Median:1813.03 stddev:90693.36 (5002.3%) stddev-sans-first:90908.76 12:31:00 INFO - PROCESS | 1661 | Values: 358.1 382.1 385.1 387.9 410.0 15697.7 15715.4 20866.6 20957.0 21019.3 21372.9 21895.6 22184.0 22230.4 22243.4 5343.2 6043.0 6234.1 6250.7 6266.9 6128.2 6215.5 6244.3 6250.4 6253.6 5891.4 6167.1 6167.4 6169.5 6171.4 5903.7 6127.2 6170.3 6223.3 6225.6 5325.8 5345.6 5353.0 5360.1 5363.8 26192.3 26377.3 27173.2 27175.5 27304.9 2478.0 2497.3 2634.3 2775.4 2926.3 12563.9 16549.8 16634.6 16964.3 17013.3 1455.7 1895.8 1934.9 2179.3 2269.8 103381.0 116237.0 116362.3 136262.3 150498.7 17878.7 18896.9 21362.8 21763.1 22034.0 36452.8 36458.5 36507.3 36514.6 36554.6 10245.9 10836.8 11270.4 11279.9 11407.0 7906.5 7930.3 9023.5 9273.7 9406.2 8092.6 9830.3 9837.7 9981.5 9984.9 3171.7 3268.0 3724.3 3810.7 3869.6 844.2 910.6 930.0 950.6 962.6 21633.5 21970.6 22032.3 22069.8 22262.6 798.3 893.8 927.3 970.8 1076.0 10704.8 11464.4 11741.5 11814.4 11842.9 511529.0 518436.0 519533.0 548764.3 580630.0 91444.5 135118.3 138256.0 156250.2 157365.2 889.6 926.0 933.1 982.1 1011.4 16914.7 19718.5 19780.0 20370.4 20461.5 55461.4 58318.7 61090.4 61940.7 62350.7 86630.0 93055.5 93275.0 94264.0 95608.5 7948.4 8289.9 8443.2 8501.6 8591.4 12648.8 13468.1 13508.9 13758.3 13805.4 9260.0 11516.0 11695.9 11736.9 11788.3 11390.2 11507.8 11546.8 11626.5 11730.4 9877.8 11296.3 11868.5 11946.9 12049.2 8229.9 8365.7 8372.5 8501.1 8553.2 11536.8 11606.5 11776.6 11824.9 11883.0 12:31:00 INFO - PROCESS | 1661 | 12:31:00 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:5724.91 Median:4943.03 stddev:3177.97 (64.3%) stddev-sans-first:3169.86 12:31:00 INFO - PROCESS | 1661 | Values: 1347.5 1580.7 1723.8 1883.8 1907.1 8443.4 8713.8 8900.3 8910.6 8934.6 7059.8 7765.0 7980.2 8634.3 8710.6 2378.3 2399.6 2405.2 2406.5 2406.6 2380.5 2384.8 2416.9 2417.4 2420.9 2105.3 2184.4 2320.2 2396.3 2397.2 2364.7 2381.6 2393.1 2398.3 2414.0 2381.4 2391.8 2394.3 2400.7 2406.5 8808.4 8955.0 8964.2 8968.0 8969.7 5248.8 5426.0 5435.2 5439.4 5445.8 3487.1 3586.5 3656.4 3673.5 3684.1 1081.1 1095.0 1103.3 1117.5 1120.2 11679.4 11681.6 11692.3 11700.1 11717.6 4530.8 4668.3 4787.5 4811.2 4813.4 9895.5 9901.3 9906.7 9919.9 9923.7 5372.1 5377.1 5383.3 5383.7 5384.1 4114.3 4936.2 4940.6 4945.4 4952.5 3918.9 4002.1 4036.7 4082.9 4121.4 1170.3 1189.3 1274.7 1295.6 1311.0 770.4 903.9 943.6 966.9 979.3 5868.7 5943.3 5954.8 6039.1 6104.1 971.6 979.2 988.9 992.4 1042.0 3876.5 3974.3 4024.2 4039.6 4086.1 10420.1 10718.8 10774.1 10788.9 10837.3 9733.6 10784.6 10788.4 10801.6 10829.5 7911.4 8251.2 8272.0 8379.0 8391.2 5950.6 6000.7 6046.8 6124.0 6180.6 9513.3 10102.1 10123.5 10125.3 10132.0 10056.1 10212.1 10251.9 10264.8 10267.2 7433.2 7458.6 7545.6 7616.8 7617.2 8148.2 8185.5 8259.2 8263.0 8274.5 6931.4 6955.6 7355.4 7468.9 7615.4 7371.9 7399.3 7411.7 7436.2 7522.6 6754.8 6872.6 6877.5 6885.1 6900.5 4821.1 4925.7 4938.9 4945.5 5050.6 4917.8 4946.7 4952.8 4955.3 5056.9 12:31:00 INFO - PROCESS | 1661 | 12:31:00 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:4507.03 Median:5095.73 stddev:1414.15 (27.8%) stddev-sans-first:1382.51 12:31:00 INFO - PROCESS | 1661 | Values: 305.7 311.2 314.6 319.5 323.1 4218.7 4687.0 5010.9 5284.7 5361.4 5025.7 5315.7 5322.8 5340.6 5343.8 3863.5 3881.9 4041.5 4049.7 4080.5 3289.7 4048.7 4088.6 4092.4 4115.2 4019.3 4029.4 4059.4 4061.9 4064.0 3385.6 3584.9 3664.7 3908.1 4036.8 3042.7 3422.4 3516.5 3532.4 3536.7 4639.6 4961.2 5119.9 5155.6 5159.1 3514.6 4149.8 4418.0 4630.0 4638.9 5543.8 5587.7 5598.4 5612.5 5658.3 3678.7 3842.2 3846.7 3850.3 3863.4 5054.5 5354.5 5483.4 5533.4 5849.7 3421.6 3865.5 3868.5 3869.6 3886.5 3153.9 3306.5 3482.3 3937.6 3949.8 3607.4 4539.3 4580.6 4588.3 4592.2 5183.5 5333.4 5652.3 5659.5 5762.6 1220.7 1287.4 1338.9 1355.3 1356.0 788.5 813.9 814.1 834.5 836.5 4883.6 4978.9 5136.4 5152.7 5172.0 4525.4 4841.6 4863.4 5465.4 5786.7 4052.5 4974.8 5041.7 5271.7 5283.2 2591.9 2854.5 2960.3 3007.3 3043.3 5930.7 5931.9 5955.3 5961.5 6016.6 5546.2 5618.3 5923.2 5944.6 5964.1 5323.6 5418.8 5743.5 6013.5 6023.0 5595.7 5812.2 5834.5 5846.0 5882.9 5071.6 5739.0 5774.4 5778.9 5828.9 5963.5 5982.3 5989.2 6053.6 6083.5 3488.0 3783.4 4032.5 4045.3 4145.5 5241.8 5464.0 5765.6 5772.8 5802.8 5247.9 5542.0 5617.2 5650.7 5659.2 4479.9 4648.4 5623.7 5694.2 5721.5 4757.8 5363.8 5524.5 5545.1 5559.0 5260.9 5343.4 5365.7 5611.0 5630.2 4601.7 4899.5 5251.4 5545.3 5608.8 12:31:00 INFO - PROCESS | 1661 | 12:31:00 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:26566.89 Median:98001.39 stddev:43388.02 (44.3%) stddev-sans-first:43466.43 12:31:00 INFO - PROCESS | 1661 | Values: 749.6 791.1 796.8 798.5 855.9 16016.9 16797.2 16819.8 16852.3 16856.0 14827.8 14994.6 15002.8 15025.2 15045.8 709.2 726.1 888.3 888.4 914.0 909.5 909.6 910.5 912.2 914.9 950.6 964.0 967.6 968.3 971.3 741.6 937.6 958.4 960.3 960.3 715.9 718.3 718.6 719.4 720.5 15293.6 15308.2 15344.4 15360.5 15376.0 14039.6 14217.9 14217.9 14231.9 14249.1 12673.2 12732.2 12799.7 13009.7 13227.6 4555.5 4569.0 4605.6 4691.5 4730.7 104920.0 105915.5 106240.0 106816.9 107696.0 11086.7 11148.3 11216.5 11407.8 11725.0 22036.8 22285.0 22307.9 22311.7 22604.9 7990.9 8035.3 8077.2 8207.8 8227.7 4237.8 4283.6 4452.2 4465.7 4635.0 3438.3 3580.7 3588.9 3590.4 3595.4 642.2 654.3 660.7 661.3 663.7 537.3 538.8 566.8 569.9 587.7 7322.0 7805.8 8587.0 8871.4 9071.6 547.1 555.2 584.8 611.7 613.3 4968.2 4978.6 5025.4 5056.2 5105.5 168522.0 169228.3 170787.0 172066.3 173966.0 106392.0 142939.0 143936.0 144014.0 146955.5 121179.2 123426.8 123788.0 124400.0 125460.0 10020.1 10090.9 10129.0 10189.1 10210.8 50677.2 62948.0 65313.0 65595.0 66545.0 97243.6 102261.2 105563.0 106665.0 107219.0 11024.2 13461.3 13592.0 13669.4 14239.6 15259.2 15556.0 15585.3 15738.9 16560.4 8391.9 14403.6 14788.9 14940.3 15074.8 14219.2 14310.6 14584.3 14936.5 15021.5 13862.9 14127.3 14330.4 14389.1 14764.0 9191.7 11525.1 11747.2 12014.7 12055.4 9369.8 11387.4 11641.7 11674.6 11839.2 12:31:00 INFO - PROCESS | 1661 | -------- Summary: end -------- 12:31:00 INFO - PROCESS | 1661 | 12:31:01 INFO - TEST-INFO | 1661: exit 0 12:31:01 INFO - TEST-OK | dromaeo_css | took 1115550ms 12:31:01 INFO - TEST-START | kraken 12:31:01 INFO - operating with platform_type : mac_ 12:31:01 INFO - Initialising browser for kraken test... 12:31:01 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/tmpsMbwPN/profile http://localhost:49281/getInfo.html) 12:31:03 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 12:31:03 INFO - PROCESS | 1667 | colorDepth:24 12:31:03 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 12:31:03 INFO - PROCESS | 1667 | __metrics 12:31:03 INFO - PROCESS | 1667 | 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" 12:31:03 INFO - PROCESS | 1667 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:31:04 INFO - TEST-INFO | 1667: exit 0 12:31:04 INFO - Browser initialized. 12:31:04 INFO - Running cycle 1/1 for kraken test... 12:31:04 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/tmpsMbwPN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:31:16 INFO - PROCESS | 1668 | RSS: Main: 243683328 12:31:16 INFO - PROCESS | 1668 | 12:31:18 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-beat-detection) 12:31:19 INFO - PROCESS | 1668 | RSS: Main: 294129664 12:31:19 INFO - PROCESS | 1668 | 12:31:22 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-dft) 12:31:22 INFO - PROCESS | 1668 | RSS: Main: 304156672 12:31:22 INFO - PROCESS | 1668 | 12:31:26 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-fft) 12:31:27 INFO - PROCESS | 1668 | RSS: Main: 387801088 12:31:27 INFO - PROCESS | 1668 | 12:31:29 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-oscillator) 12:31:29 INFO - PROCESS | 1668 | RSS: Main: 291627008 12:31:29 INFO - PROCESS | 1668 | 12:31:31 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:31:32 INFO - PROCESS | 1668 | RSS: Main: 295428096 12:31:32 INFO - PROCESS | 1668 | 12:31:35 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-darkroom) 12:31:35 INFO - PROCESS | 1668 | RSS: Main: 331354112 12:31:35 INFO - PROCESS | 1668 | 12:31:38 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-desaturate) 12:31:39 INFO - PROCESS | 1668 | RSS: Main: 332795904 12:31:39 INFO - PROCESS | 1668 | 12:31:42 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49281/tests/kraken/driver.html?testName=json-parse-financial) 12:31:42 INFO - PROCESS | 1668 | RSS: Main: 332447744 12:31:42 INFO - PROCESS | 1668 | 12:31:44 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49281/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:31:45 INFO - PROCESS | 1668 | RSS: Main: 313790464 12:31:45 INFO - PROCESS | 1668 | 12:31:46 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:31:47 INFO - PROCESS | 1668 | RSS: Main: 320946176 12:31:47 INFO - PROCESS | 1668 | 12:31:49 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:31:50 INFO - PROCESS | 1668 | RSS: Main: 325431296 12:31:50 INFO - PROCESS | 1668 | 12:31:53 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:31:53 INFO - PROCESS | 1668 | RSS: Main: 326832128 12:31:53 INFO - PROCESS | 1668 | 12:31:57 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:31:57 INFO - PROCESS | 1668 | RSS: Main: 327012352 12:31:57 INFO - PROCESS | 1668 | 12:31:59 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49281/tests/kraken/driver.html?testName=ai-astar) 12:31:59 INFO - PROCESS | 1668 | RSS: Main: 370565120 12:31:59 INFO - PROCESS | 1668 | 12:31:59 INFO - PROCESS | 1668 | __start_tp_report 12:31:59 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 12:31:59 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 12:31:59 INFO - PROCESS | 1668 | |i|pagename|runs| 12:31:59 INFO - PROCESS | 1668 | |0;ai-astar;101;113;113;111;112;112;112;112;112;111 12:31:59 INFO - PROCESS | 1668 | |1;audio-beat-detection;143;111;106;157;106;105;127;109;147;138 12:31:59 INFO - PROCESS | 1668 | |2;audio-dft;183;185;184;182;191;227;219;181;182;181 12:31:59 INFO - PROCESS | 1668 | |3;audio-fft;79;78;74;131;82;60;63;60;98;58 12:31:59 INFO - PROCESS | 1668 | |4;audio-oscillator;84;90;91;70;69;67;96;69;70;100 12:31:59 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;88;85;87;85;87;83;89;83;86;84 12:31:59 INFO - PROCESS | 1668 | |6;imaging-darkroom;92;92;93;93;92;93;93;93;93;92 12:31:59 INFO - PROCESS | 1668 | |7;imaging-desaturate;85;114;135;90;134;142;116;85;86;85 12:31:59 INFO - PROCESS | 1668 | |8;json-parse-financial;54;54;56;57;56;55;54;53;54;54 12:31:59 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;66;68;78;77;74;73;71;71;71;73 12:31:59 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;83;82;85;85;83;81;84;82;85;106 12:31:59 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;146;148;143;135;134;124;133;134;152;138 12:31:59 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;192;143;205;229;185;214;146;212;226;207 12:31:59 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;66;66;66;67;66;65;66;67;66;64 12:31:59 INFO - PROCESS | 1668 | __end_tp_report 12:31:59 INFO - PROCESS | 1668 | __start_cc_report 12:31:59 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,1990 12:31:59 INFO - PROCESS | 1668 | __end_cc_report 12:31:59 INFO - PROCESS | 1668 | __startTimestamp1468265519656__endTimestamp 12:31:59 INFO - PROCESS | 1668 | 12:31:59 INFO - PROCESS | 1668 | ------- Summary: start ------- 12:31:59 INFO - PROCESS | 1668 | Number of tests: 14 12:31:59 INFO - PROCESS | 1668 | 12:31:59 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:110.90 Median:112.00 stddev:3.54 (3.2%) stddev-sans-first:0.71 12:31:59 INFO - PROCESS | 1668 | Values: 101.0 113.0 113.0 111.0 112.0 112.0 112.0 112.0 112.0 111.0 12:31:59 INFO - PROCESS | 1668 | 12:31:59 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:124.90 Median:132.50 stddev:19.94 (15.1%) stddev-sans-first:20.05 12:31:59 INFO - PROCESS | 1668 | Values: 143.0 111.0 106.0 157.0 106.0 105.0 127.0 109.0 147.0 138.0 12:31:59 INFO - PROCESS | 1668 | 12:31:59 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:191.50 Median:184.50 stddev:16.96 (9.2%) stddev-sans-first:17.71 12:31:59 INFO - PROCESS | 1668 | Values: 183.0 185.0 184.0 182.0 191.0 227.0 219.0 181.0 182.0 181.0 12:31:59 INFO - PROCESS | 1668 | 12:31:59 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:78.30 Median:76.00 stddev:22.40 (29.5%) stddev-sans-first:23.75 12:31:59 INFO - PROCESS | 1668 | Values: 79.0 78.0 74.0 131.0 82.0 60.0 63.0 60.0 98.0 58.0 12:31:59 INFO - PROCESS | 1668 | 12:31:59 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:80.60 Median:77.00 stddev:12.91 (16.8%) stddev-sans-first:13.64 12:31:59 INFO - PROCESS | 1668 | Values: 84.0 90.0 91.0 70.0 69.0 67.0 96.0 69.0 70.0 100.0 12:31:59 INFO - PROCESS | 1668 | 12:31:59 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:85.70 Median:86.50 stddev:2.06 (2.4%) stddev-sans-first:2.01 12:31:59 INFO - PROCESS | 1668 | Values: 88.0 85.0 87.0 85.0 87.0 83.0 89.0 83.0 86.0 84.0 12:31:59 INFO - PROCESS | 1668 | 12:31:59 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:92.60 Median:93.00 stddev:0.52 (0.6%) stddev-sans-first:0.50 12:31:59 INFO - PROCESS | 1668 | Values: 92.0 92.0 93.0 93.0 92.0 93.0 93.0 93.0 93.0 92.0 12:31:59 INFO - PROCESS | 1668 | 12:31:59 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:107.20 Median:85.00 stddev:23.69 (27.9%) stddev-sans-first:23.72 12:31:59 INFO - PROCESS | 1668 | Values: 85.0 114.0 135.0 90.0 134.0 142.0 116.0 85.0 86.0 85.0 12:31:59 INFO - PROCESS | 1668 | 12:31:59 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:54.50 stddev:1.25 (2.3%) stddev-sans-first:1.30 12:31:59 INFO - PROCESS | 1668 | Values: 54.0 54.0 56.0 57.0 56.0 55.0 54.0 53.0 54.0 54.0 12:31:59 INFO - PROCESS | 1668 | 12:31:59 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:72.20 Median:73.00 stddev:3.68 (5.0%) stddev-sans-first:3.14 12:31:59 INFO - PROCESS | 1668 | Values: 66.0 68.0 78.0 77.0 74.0 73.0 71.0 71.0 71.0 73.0 12:31:59 INFO - PROCESS | 1668 | 12:31:59 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:85.60 Median:83.50 stddev:7.31 (8.7%) stddev-sans-first:7.69 12:31:59 INFO - PROCESS | 1668 | Values: 83.0 82.0 85.0 85.0 83.0 81.0 84.0 82.0 85.0 106.0 12:31:59 INFO - PROCESS | 1668 | 12:31:59 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:138.70 Median:140.50 stddev:8.45 (6.0%) stddev-sans-first:8.54 12:31:59 INFO - PROCESS | 1668 | Values: 146.0 148.0 143.0 135.0 134.0 124.0 133.0 134.0 152.0 138.0 12:31:59 INFO - PROCESS | 1668 | 12:31:59 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.90 Median:209.50 stddev:30.22 (14.4%) stddev-sans-first:32.02 12:31:59 INFO - PROCESS | 1668 | Values: 192.0 143.0 205.0 229.0 185.0 214.0 146.0 212.0 226.0 207.0 12:31:59 INFO - PROCESS | 1668 | 12:31:59 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:66.00 stddev:0.88 (1.3%) stddev-sans-first:0.93 12:31:59 INFO - PROCESS | 1668 | Values: 66.0 66.0 66.0 67.0 66.0 65.0 66.0 67.0 66.0 64.0 12:31:59 INFO - PROCESS | 1668 | -------- Summary: end -------- 12:31:59 INFO - PROCESS | 1668 | 12:32:00 INFO - TEST-INFO | 1668: exit 0 12:32:00 INFO - TEST-OK | kraken | took 59318ms 12:32:00 INFO - SUITE-END | took 1174s 12:32:00 INFO - Completed test suite (00:19:35) 12:32:00 INFO - javascript benchmark 12:32:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2855.514562524371, 3375.0381141374696, 3386.50895620135, 3391.6099648540426, 3432.5022871683605, 5651.971740141304, 5693.2883389576255, 5711.885762284752, 5848.2689663792025, 5862.501687356575, 5692.260006199188, 5716.256886604746, 5821.18503409522, 5838.211841401409, 5841.941580584193, 5196.92204616931, 5232.372115346164, 5259.184826351921, 5275.841724748264, 5370.248165256848, 5302.787888484456, 5371.194320851884, 5378.596605254602, 5391.245225668409, 5420.430854760252, 4218.936715949263, 5186.351706036764, 5239.1093514102695, 5239.685618862881, 5305.920411193835, 4102.343625020012, 5201.609879259071, 5204.661696989721, 5206.479352064801, 5310.70791106489, 5172.32759741235, 5189.584833213372, 5194.662346947474, 5251.238570407308, 5307.973460132714, 5542.224088627595, 5828.358880523139, 5846.883062338729, 5859.941400585983, 5862.941370586324, 6180.3201647818705, 6228.532860035515, 6264.123022776815, 6287.962486189773, 6295.716692748837, 6268.0, 6290.3709629037185, 6328.240611126648, 6342.968285158591, 6345.555811093179, 5757.85605359861, 5820.82537523875, 5871.266091738533, 5925.4074592541265, 5979.880402391971, 5303.893922121536, 6273.843153921097, 6302.243730752339, 6327.462165715873, 6341.270753863365, 6160.291566469824, 6195.535334849904, 6202.34875338083, 6203.069539569011, 6222.875542489125, 6297.0, 6323.241211054703, 6353.745850166034, 6358.936410635927, 6370.8088757337355, 6325.272593651789, 6328.335524769833, 6338.841528961812, 6345.841353966095, 6351.333110023474, 6151.84620384494, 6194.5044396448175, 6263.655498947602, 6266.091416744546, 6324.146240257506, 4625.098105869391, 5022.447530771612, 5037.974810125999, 5045.571126454219, 5081.3140226069745, 4207.326827707552, 4494.528074552125, 4501.18978583858, 4505.977470112629, 4555.316702494652, 4711.646626503026, 4853.975730121327, 5688.260526131659, 5717.456841600041, 5760.8271751847515, 6323.494120470351, 6329.7468101275435, 6338.271098823602, 6358.1734374531015, 6368.586041907354, 5815.651060936358, 5816.796412125555, 5822.243108395966, 5836.708164591839, 5846.356900740915, 5386.218998245311, 5447.101228297287, 5480.862978425492, 5487.972560137174, 5524.558035357162, 6368.10846481493, 6375.330590288046, 6376.904346434807, 6378.1070650108095, 6378.840528986812, 6190.41191086846, 6270.516580644861, 6316.968415158079, 6326.525510586633, 6335.239771227482, 6233.0338797486465, 6263.65549894742, 6285.685715714288, 6292.590981586182, 6347.333529979471, 6253.843653908689, 6280.685965701788, 6284.088807123033, 6292.716827742855, 6293.4335909766505, 5097.566706829961, 6018.398160183947, 6335.461485773761, 6343.3022367538615, 6347.936520634735, 5842.269716285465, 5891.1457838614015, 5892.616979896293, 5911.142884281841, 5954.136650185612, 5878.706064696663, 5884.117382392676, 5885.852853678693, 5885.911106445321, 5898.233229680114, 5623.522000629992, 5781.161731548986, 5933.495652869555, 6040.516758659209, 6064.272287325549, 6228.564000520101, 6239.126522286792, 6246.843828904313, 6248.968755156195, 6261.624302541862, 6121.32665406796, 6130.601510901777, 6134.4172303631085, 6135.355787642234, 6142.14010038586], "value": 5709.045314248468, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [457.544742980734, 479.9808007679693, 491.8426103646834, 521.6583138044581, 547.8986387518307, 30205.0, 30823.537646935314, 42430.36354454685, 42993.785031074876, 43013.13973720532, 43997.68006959788, 44183.77908110463, 44493.0, 44495.0, 44599.331010034795, 9724.902750972506, 9911.306208565367, 9912.900870991305, 9914.652987145451, 9923.503824808768, 9682.322237443383, 9779.657711980082, 9794.706158815212, 9804.362716423446, 9814.018598140201, 9750.36622619531, 9804.01959804021, 9805.803883922317, 9807.852882206773, 9832.852507212398, 9752.512374381287, 9805.95097024514, 9806.313558050939, 9806.705798826046, 9827.950860245724, 9754.756131096708, 9787.90212097877, 9794.314397992144, 9801.852972205423, 9803.411795292233, 41412.1717565647, 41696.37455438141, 41813.58186418158, 41839.7908010461, 41864.581354186375, 3509.0525558099425, 3975.542812576533, 4204.915901681979, 4315.978420107911, 4364.847230346954, 31457.05628831139, 32583.0, 32845.34309313824, 32927.50608740871, 33169.50245746316, 3229.6770322967513, 3317.2204531934976, 3386.136535183537, 3402.812845293508, 3429.108431807723, 482470.0, 488061.0, 491447.0, 492424.0, 496203.0, 59683.0, 59884.10173847396, 60340.09489857656, 60573.39426605766, 60589.394106059255, 63403.0, 63670.68164659147, 63869.36130638634, 63898.36101639017, 64004.67997660075, 16504.83495165033, 16713.665726685398, 16717.91641041787, 16718.24767885448, 16723.91638041802, 12919.22484650924, 12968.67578310531, 12975.545855894998, 12981.805272920914, 12985.480580776852, 15584.45454409113, 16359.263833127538, 16684.249208785634, 16701.164941752864, 16742.58143546421, 5973.431424487512, 6151.246894635007, 7091.680874360666, 7161.212266650664, 7238.638068096574, 880.9956649419667, 999.0459111548599, 1026.2918586175515, 1037.9221558383151, 1109.4840898982013, 29902.551461728093, 30264.638091285946, 30339.696603033688, 30876.53685194724, 30984.380312394078, 1064.3932958213743, 1107.1751545098791, 1145.6104924325768, 1203.2312500624068, 1236.839210902595, 15353.539393818204, 15759.763603545956, 15772.0, 15845.52463426099, 16008.599785005468, 620341.0, 624893.0, 630902.0, 632345.0, 634040.0, 58759.0, 58767.0, 58827.70586147042, 58883.11675324874, 59031.11453328204, 1259.5213818748816, 1374.3815283122635, 1405.6907480354305, 1421.0976030220677, 1425.5153247895764, 31506.684933151293, 31601.0, 31838.52242216415, 31871.52192717065, 32533.0, 49791.50208497869, 50155.0, 50821.74589127031, 50892.74553627208, 51005.0, 542004.0, 554589.2270538622, 555039.0, 556877.0, 557092.0, 34781.47827782535, 35695.10762230965, 40901.0, 41744.79127604343, 41873.58126418697, 47205.0, 47845.76077119731, 48003.039939200324, 48734.0253194927, 49015.0, 39286.0, 39418.0, 39745.40381894332, 40034.39948400835, 40580.78257652275, 39312.0, 39386.40920386139, 39877.401838971855, 40020.599794002854, 40675.389869152576, 38146.0, 38197.427038595, 38700.6129938697, 38702.41946370863, 38956.0, 33629.14525922404, 34270.80052198261, 34748.65251347555, 34879.0, 34988.0, 24735.62896556589, 24814.25557233286, 25401.74598253994, 25819.0, 25861.353466163495], "value": 20484.217224619177, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.06723485320725, 382.0734718308103, 385.11423725431507, 387.92047630235805, 410.0118713899503, 15697.686046279068, 15715.371385144595, 20866.582668346626, 20957.0, 21019.264325748565, 21372.8931355343, 21895.562088758255, 22184.0, 22230.444238894037, 22243.44391390216, 5343.1985202219785, 6042.969785151068, 6234.064890266451, 6250.656213908233, 6266.905996410058, 6128.233970753656, 6215.5338349623735, 6244.344343843899, 6250.406211409909, 6253.562250642457, 5891.381404952483, 6167.105769663396, 6167.414095660905, 6169.537284703642, 6171.382861713815, 5903.734331955073, 6127.203463549732, 6170.321264660884, 6223.284322302925, 6225.626462412247, 5325.813596524103, 5345.572354211693, 5353.0, 5360.088784906575, 5363.812266570691, 26192.34519137018, 26377.3405664858, 27173.184804455897, 27175.45649087027, 27304.863475682694, 2477.962830557543, 2497.2633073243437, 2634.3414146463383, 2775.4244635682285, 2926.2830606501543, 12563.874361256361, 16549.751753723704, 16634.58413539659, 16964.3214271429, 17013.319467221296, 1455.745244582194, 1895.8293753562107, 1934.9226030958885, 2179.2917301876796, 2269.807066399358, 103381.0, 116237.0, 116362.25456618072, 136262.31868840693, 150498.7425188612, 17878.731819022723, 18896.905515472336, 21362.78637213639, 21763.12947482083, 22034.0, 36452.81773591115, 36458.45312320317, 36507.26985460329, 36514.63485365165, 36554.63445365566, 10245.948770256102, 10836.837447438294, 11270.436478176221, 11279.94360028211, 11407.0, 7906.486078404885, 7930.325922296554, 9023.548822558845, 9273.675421360354, 9406.247500199968, 8092.595370231465, 9830.311878168459, 9837.704868853945, 9981.500924953723, 9984.850227246598, 3171.7304029157317, 3267.986924053551, 3724.273766615538, 3810.741815747496, 3869.5936926622862, 844.2233145506149, 910.5902343945252, 930.0141849639404, 950.619752099152, 962.629387685746, 21633.459163521038, 21970.560588788136, 22032.33902982913, 22069.779302207095, 22262.554748905255, 798.273571050353, 893.7676204186828, 927.3137877970254, 970.8495183246608, 1075.9731006724894, 10704.785904282027, 11464.4267786612, 11741.530338786524, 11814.35021073832, 11842.88157118435, 511529.0, 518436.0, 519533.0, 548764.2561787246, 580630.0, 91444.5427772857, 135118.3244083793, 138256.0, 156250.21874890552, 157365.21317393339, 889.621910687968, 926.0276709455045, 933.118203297877, 982.0964712464549, 1011.3780025284398, 16914.66170676604, 19718.507037324183, 19780.0, 20370.388888333375, 20461.488462788548, 55461.445385547246, 58318.7084064577, 61090.38909610847, 61940.69029654823, 62350.68824655848, 86630.0, 93055.53472232596, 93275.0, 94264.0, 95608.52195738976, 7948.443608947318, 8289.917100829156, 8443.197896199852, 8501.574921253788, 8591.398602097794, 12648.810267845805, 13468.124571902796, 13508.932455337661, 13758.31208439594, 13805.447782088604, 9259.953700231456, 11516.0, 11695.941520292685, 11736.941315293368, 11788.29270243788, 11390.202685811913, 11507.827382589436, 11546.769064618493, 11626.53493860236, 11730.354830484064, 9877.814662240577, 11296.322220666787, 11868.525258989544, 11946.880531194576, 12049.216800908263, 8229.876551851607, 8365.749027529184, 8372.455790373606, 8501.10738372462, 8553.187447192508, 11536.769264614493, 11606.535738570363, 11776.587819426279, 11824.940875295568, 11882.989945854699], "value": 10887.399912815632, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1347.5351003903656, 1580.6838632273545, 1723.8189990051044, 1883.8116188381155, 1907.1036612791984, 8443.36674749394, 8713.825723485526, 8900.332475064371, 8910.643574257021, 8934.642614295428, 7059.7882063538045, 7765.029371328584, 7980.164562150709, 8634.309255259579, 8710.60802263898, 2378.298401971418, 2399.568077746005, 2405.194259922928, 2406.5307265083316, 2406.568091985275, 2380.5238952209543, 2384.7972922301624, 2416.927492175229, 2417.419819243378, 2420.891059902309, 2105.3368189020484, 2184.399290195201, 2320.187934223017, 2396.2691379129446, 2397.244867866625, 2364.7280562735245, 2381.630847218684, 2393.0547433763654, 2398.3404563744934, 2414.0, 2381.4403615150427, 2391.8445301055376, 2394.2817154853647, 2400.699912510936, 2406.4585468269674, 8808.383413161082, 8955.0, 8964.19322260993, 8967.955160224223, 8969.686060987835, 5248.81629142982, 5425.9728701356635, 5435.1847222916485, 5439.428859969686, 5445.782168713246, 3487.099530755744, 3586.4799604057525, 3656.4332528457867, 3673.504076949631, 3684.078980254936, 1081.118888106191, 1094.9890501095047, 1103.3269705479652, 1117.5138814615586, 1120.2046546951592, 11679.416029198506, 11681.591144309994, 11692.298462092216, 11700.06399488039, 11717.648470545897, 4530.821986283524, 4668.276417155346, 4787.54518320759, 4811.195801574409, 4813.374261346003, 9895.455749933823, 9901.306908516479, 9906.702798916043, 9919.900800991898, 9923.65267215644, 5372.086745253317, 5377.085895397791, 5383.300170977748, 5383.734822316753, 5384.0847056000575, 4114.280000999813, 4936.185529387611, 4940.629452791055, 4945.431275403303, 4952.455229924705, 3918.8628398005935, 4002.139539998914, 4036.6770658347264, 4082.8775136745944, 4121.361189015707, 1170.3329102411544, 1189.339916346436, 1274.7323062156865, 1295.5530342031984, 1310.960671179866, 770.3836930455614, 903.8734577159203, 943.580106852444, 966.9274804389698, 979.28136121107, 5868.735906884201, 5943.3462319144855, 5954.791582294599, 6039.094135879654, 6104.145419641254, 971.6259240192577, 979.1579241852002, 988.907257480488, 992.3847214726916, 1041.953112109957, 3876.457295978565, 3974.344233201547, 4024.195160967818, 4039.6162364575316, 4086.0602061526015, 10420.11429028541, 10718.839217411745, 10774.084202842847, 10788.946055269831, 10837.295575787548, 9733.56198971048, 10784.568617255221, 10788.352698838095, 10801.567937282422, 10829.458527073615, 7911.406644501685, 8251.216134467217, 8272.0, 8379.0, 8391.160883911682, 5950.642961422329, 6000.729967151401, 6046.818595442144, 6124.0, 6180.6291622502795, 9513.334066615547, 10102.09081182697, 10123.544440500047, 10125.341852779546, 10132.0, 10056.145227655732, 10212.13196878244, 10251.897481025095, 10264.846027309746, 10267.229957753048, 7433.2195119513835, 7458.627068646438, 7545.622718863925, 7616.8476630468185, 7617.162112167546, 8148.185181481805, 8185.549794761345, 8259.21537453941, 8263.00843898736, 8274.544900030556, 6931.376176144171, 6955.582665040114, 7355.448341374311, 7468.925310746823, 7615.428842836699, 7371.889421658572, 7399.260073992557, 7411.664176456833, 7436.219196984239, 7522.623868806647, 6754.831129221809, 6872.553284036521, 6877.4841886857685, 6885.1049363582415, 6900.516963812485, 4821.083994041121, 4925.679830810986, 4938.9259161111895, 4945.505449455026, 5050.646454748133, 4917.827876024322, 4946.678465899705, 4952.826651067196, 4955.331030310862, 5056.949430505648], "value": 4623.02047573301, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.6973596139822, 311.1971114524527, 314.63030938647086, 319.47765403565177, 323.08566756080296, 4218.7257828241145, 4687.0, 5010.874728131798, 5284.682919024856, 5361.437049109846, 5025.748712564376, 5315.707636080024, 5322.8137015204475, 5340.572754179666, 5343.759530821113, 3863.517060367454, 3881.9029524261837, 4041.4746083009313, 4049.7165198436123, 4080.510338759353, 3289.6545862684434, 4048.67610591152, 4088.5707000764787, 4092.386142078697, 4115.176964607075, 4019.2966230909474, 4029.435878976946, 4059.4113853491226, 4061.878143655695, 4064.0, 3385.6106547747077, 3584.874529391485, 3664.725145614063, 3908.1402091539817, 4036.8183431745497, 3042.7413669838093, 3422.3839708852397, 3516.4549494828343, 3532.434810430319, 3536.7347448941173, 4639.605633521155, 4961.18140506816, 5119.875251770511, 5155.587552995751, 5159.097157997373, 3514.6485351464903, 4149.775816134248, 4417.955820441786, 4629.953700462986, 4638.930416043762, 5543.805966791143, 5587.664740115606, 5598.440155984369, 5612.522935550483, 5658.349289831681, 3678.705703543737, 3842.155765232676, 3846.74996125251, 3850.26844899471, 3863.3818589025623, 5054.519820617035, 5354.491323324278, 5483.396826349099, 5533.399302327899, 5849.736761845727, 3421.6219727289795, 3865.4588357629955, 3868.516435445569, 3869.632384923484, 3886.514185726784, 3153.921151971219, 3306.5205545195813, 3482.2861313430685, 3937.586553411908, 3949.8025098745525, 3607.3867442534834, 4539.341795439643, 4580.587747102777, 4588.334691469719, 4592.196365636, 5183.481651834862, 5333.439988801198, 5652.3217213934595, 5659.490645841894, 5762.567807414461, 1220.6887243752785, 1287.3884904670206, 1338.8728070833256, 1355.2952464718292, 1355.9897876082382, 788.511123103668, 813.8535063688474, 814.1370147643516, 834.4909605140859, 836.4897412578322, 4883.560479556857, 4978.90042199154, 5136.406114911945, 5152.6650767699975, 5172.043172013115, 4525.389072475174, 4841.564259216688, 4863.395079623716, 5465.398806131322, 5786.739596718157, 4052.452918855976, 4974.806046548802, 5041.7227052511735, 5271.683698978073, 5283.2075188722, 2591.922242332733, 2854.5432730762977, 2960.2895305126613, 3007.338385555174, 3043.345680678665, 5930.733117009745, 5931.911021334683, 5955.28536575605, 5961.5230781537375, 6016.608920420246, 5546.223528705985, 5618.314505648289, 5923.156884695284, 5944.554158438134, 5964.135200396005, 5323.562638087695, 5418.8103416379445, 5743.506688261082, 6013.504395675745, 6022.969885150634, 5595.669662809721, 5812.244408226903, 5834.504067154339, 5846.0, 5882.852928676817, 5071.594272458121, 5738.971305143447, 5774.40151931624, 5778.94221057784, 5828.901595425728, 5963.522918166623, 5982.341942386247, 5989.1914591529585, 6053.576249662481, 6083.452489275986, 3487.9651203487642, 3783.39465685489, 4032.548282618314, 4045.2920738871167, 4145.54399016114, 5241.8246427732065, 5463.972680136574, 5765.625234359753, 5772.7690892363835, 5802.82591522255, 5247.921281180862, 5542.0, 5617.241672374341, 5650.689212093374, 5659.207710920557, 4479.888002799956, 4648.372469716545, 5623.690697011703, 5694.241800411708, 5721.456461636138, 4757.833475828329, 5363.80853392231, 5524.502794748492, 5545.086945004128, 5559.0, 5260.921511090294, 5343.446608005633, 5365.704886231295, 5611.0, 5630.239917611234, 4601.6548758842555, 4899.534544218293, 5251.36983561975, 5545.334559852843, 5608.831735047955], "value": 4056.051095745952, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [749.56899782625, 791.0783936713729, 796.8087658961849, 798.5129071266526, 855.8587833007558, 16016.919915400422, 16797.244124014418, 16819.83180168198, 16852.32590696372, 16856.0, 14827.777583336232, 14994.550163495112, 15002.849971500282, 15025.248737563106, 15045.77431338528, 709.2446544430192, 726.1467775363952, 888.2538667519283, 888.4180861535705, 914.0, 909.486140330711, 909.586138307072, 910.45372776334, 912.2200518556605, 914.9039350868163, 950.6292545907102, 963.9614415423409, 967.6468089147481, 968.3366893677818, 971.2666936462961, 741.5550669598252, 937.5874615169304, 958.4297343080891, 960.2701946520625, 960.2701946520659, 715.9391451726609, 718.3140101203338, 718.5724493926106, 719.352582675591, 720.5100531638484, 15293.61765955849, 15308.158051307173, 15344.386224551115, 15360.462383816624, 15376.0, 14039.64900877476, 14217.928910355486, 14217.928910355486, 14231.928840355731, 14249.145051296851, 12673.17624354414, 12732.23606583608, 12799.74400511994, 13009.739805203842, 13227.603171904762, 4555.521670224612, 4568.977155114237, 4605.585497305258, 4691.507391723854, 4730.692504987165, 104920.0, 105915.47042264817, 106240.0, 106816.93183068225, 107696.0, 11086.722831929104, 11148.275362101438, 11216.495257713423, 11407.771844563062, 11725.003374713246, 22036.779632203474, 22285.0, 22307.888460557595, 22311.665325020138, 22604.886975565016, 7990.880136797953, 8035.276825085653, 8077.151899050633, 8207.794805129919, 8227.670893164206, 4237.8304867804945, 4283.550227226158, 4452.1763473757455, 4465.709728867613, 4634.9536504634525, 3438.346714124308, 3580.695640870503, 3588.946165807515, 3590.389633762267, 3595.442706380515, 642.1683919324486, 654.3456543456543, 660.7158921663731, 661.3155384177353, 663.6947004377944, 537.3149234725756, 538.8114160043954, 566.8044524638993, 569.9287589051369, 587.7208326045094, 7321.963390183121, 7805.790102534116, 8586.957065214634, 8871.379003469825, 9071.637134514678, 547.1191381875178, 555.2226882364641, 584.7514806207345, 611.7461253579714, 613.310026220502, 4968.155413579628, 4978.551930326288, 5025.447200807908, 5056.191009438472, 5105.46165665591, 168522.0, 169228.3077169237, 170787.0, 172066.27933720752, 173966.0, 106392.0, 142939.0, 143936.0, 144014.0, 146955.5304446963, 121179.18231226539, 123426.76573234511, 123788.0, 124400.0, 125460.0, 10020.148287395361, 10090.94954525252, 10129.0, 10189.082982531607, 10210.795784084425, 50677.23984140167, 62948.0, 65313.02030469452, 65595.0, 66545.00364989058, 97243.64723467862, 102261.18367876673, 105563.0, 106665.0, 107219.0, 11024.228304018641, 13461.259630720404, 13591.980601454732, 13669.38487768033, 14239.64400889986, 15259.23703814827, 15556.0, 15585.298661560484, 15738.921305393858, 16560.420385286456, 8391.930046045498, 14403.639909002359, 14788.852111478747, 14940.252987350807, 15074.849251507783, 14219.217943013233, 14310.642233944234, 14584.270786460847, 14936.477223297132, 15021.549353519413, 13862.861371386158, 14127.293635318398, 14330.426782928565, 14389.136651800925, 14764.0, 9191.724248272563, 11525.135614828754, 11747.236429632047, 12014.699632509257, 12055.397230138282, 9369.765755856159, 11387.42741531588, 11641.708957276136, 11674.591389301673, 11839.230450020721], "value": 8111.691586066878, "unit": "score"}], "name": "dromaeo_css", "value": 7606.463849567621, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 113.0, 111.0, 112.0, 112.0, 112.0, 112.0, 112.0, 111.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 111.0, 106.0, 157.0, 106.0, 105.0, 127.0, 109.0, 147.0, 138.0], "value": 124.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 184.0, 182.0, 191.0, 227.0, 219.0, 181.0, 182.0, 181.0], "value": 191.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 74.0, 131.0, 82.0, 60.0, 63.0, 60.0, 98.0, 58.0], "value": 78.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 90.0, 91.0, 70.0, 69.0, 67.0, 96.0, 69.0, 70.0, 100.0], "value": 80.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 87.0, 85.0, 87.0, 83.0, 89.0, 83.0, 86.0, 84.0], "value": 85.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 93.0, 93.0, 92.0, 93.0, 93.0, 93.0, 93.0, 92.0], "value": 92.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 114.0, 135.0, 90.0, 134.0, 142.0, 116.0, 85.0, 86.0, 85.0], "value": 107.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 56.0, 57.0, 56.0, 55.0, 54.0, 53.0, 54.0, 54.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 78.0, 77.0, 74.0, 73.0, 71.0, 71.0, 71.0, 73.0], "value": 72.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 85.0, 85.0, 83.0, 81.0, 84.0, 82.0, 85.0, 106.0], "value": 85.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 148.0, 143.0, 135.0, 134.0, 124.0, 133.0, 134.0, 152.0, 138.0], "value": 138.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 143.0, 205.0, 229.0, 185.0, 214.0, 146.0, 212.0, 226.0, 207.0], "value": 195.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 67.0, 66.0, 65.0, 66.0, 67.0, 66.0, 64.0], "value": 65.9, "unit": "score"}], "name": "kraken", "value": 1484.7000000000005, "alertThreshold": 2.0}]} 12:32:00 INFO - Return code: 0 12:32:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:32:00 INFO - # TBPL SUCCESS # 12:32:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:32:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:32:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:32:00 INFO - 2016-07-11 12:32:00.965 screenresolution[1672:10162] starting screenresolution argv=screenresolution get 12:32:00 INFO - 2016-07-11 12:32:00.968 screenresolution[1672:10162] Display 0: 1600x1200x32@60 12:32:00 INFO - 2016-07-11 12:32:00.974 screenresolution[1673:10165] starting screenresolution argv=screenresolution list 12:32:00 INFO - Available Modes on Display 0 12:32:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:32:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:32:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:32:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:32:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:32:01 INFO - Intel Iris: 12:32:01 INFO - Chipset Model: Intel Iris 12:32:01 INFO - Type: GPU 12:32:01 INFO - Bus: Built-In 12:32:01 INFO - VRAM (Dynamic, Max): 1536 MB 12:32:01 INFO - Vendor: Intel (0x8086) 12:32:01 INFO - Device ID: 0x0a2e 12:32:01 INFO - Revision ID: 0x0009 12:32:01 INFO - Displays: 12:32:01 INFO - Display: 12:32:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:32:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:32:01 INFO - Display Serial Number: 1600x1200 60 12:32:01 INFO - Main Display: Yes 12:32:01 INFO - Mirror: Off 12:32:01 INFO - Online: Yes 12:32:01 INFO - Rotation: Supported 12:32:01 INFO - Return code: 0 12:32:01 INFO - Running post-action listener: _resource_record_post_action 12:32:01 INFO - ##### 12:32:01 INFO - ##### Finished run-tests step (success) 12:32:01 INFO - ##### 12:32:01 INFO - Running post-run listener: _resource_record_post_run 12:32:01 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 31587840; Write bytes: 705293824; Read time: 196; Write time: 1975 12:32:01 INFO - TinderboxPrint: CPU usage
53.3% 12:32:01 INFO - TinderboxPrint: I/O read bytes / time
31,587,840 / 196 12:32:01 INFO - TinderboxPrint: I/O write bytes / time
705,293,824 / 1,975 12:32:01 INFO - TinderboxPrint: CPU idle
2,261.0 (46.7%) 12:32:01 INFO - TinderboxPrint: CPU system
963.6 (19.9%) 12:32:01 INFO - TinderboxPrint: CPU user
1,617.0 (33.4%) 12:32:01 INFO - TinderboxPrint: Swap in / out
433,569,792 / 0 12:32:01 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 398019072; Write bytes: 392837120; Read time: 29477; Write time: 857 12:32:01 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 29621760; Write bytes: 294864384; Read time: 180; Write time: 1074 12:32:01 INFO - Running post-run listener: _upload_blobber_files 12:32:01 INFO - Blob upload gear active. 12:32:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:32:01 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 12:32:01 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:32:01 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:32:02 INFO - (blobuploader) - INFO - Open directory for files ... 12:32:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:32:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:32:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:32:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:32:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:32:03 INFO - (blobuploader) - INFO - Done attempting. 12:32:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:32:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:32:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:32:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:32:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:32:03 INFO - (blobuploader) - INFO - Done attempting. 12:32:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:32:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:32:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:32:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:32:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:32:06 INFO - (blobuploader) - INFO - Done attempting. 12:32:06 INFO - (blobuploader) - INFO - Iteration through files over. 12:32:06 INFO - Return code: 0 12:32:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:32:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:32:06 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/586c02d293a53dd6056a5c23e903f7366a9bb2c39f76d2cd46d7115a3f8bb081993029033679ecc7751df45d40faa8d89eeb1a76a10c66d634e7aceca5fcc062", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ae62a1e0c4848f123d8ec5f68a1c96fc4335272612cce09c6c91cdd9737755f5ade5d8e983cccf4db5522135a046efd5e7c3d363aef514dba18804802fc7995f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/911f110e21cf6037c6d4a95a6213e060989398c2dc98f4c288f7b78422f93f675dc5fce5e691522347ae76cc912ebfa2dea587112fa63bcd9af9aa90fa230d41"} 12:32:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:32:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:32:06 INFO - Contents: 12:32:06 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/586c02d293a53dd6056a5c23e903f7366a9bb2c39f76d2cd46d7115a3f8bb081993029033679ecc7751df45d40faa8d89eeb1a76a10c66d634e7aceca5fcc062", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ae62a1e0c4848f123d8ec5f68a1c96fc4335272612cce09c6c91cdd9737755f5ade5d8e983cccf4db5522135a046efd5e7c3d363aef514dba18804802fc7995f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/911f110e21cf6037c6d4a95a6213e060989398c2dc98f4c288f7b78422f93f675dc5fce5e691522347ae76cc912ebfa2dea587112fa63bcd9af9aa90fa230d41"} 12:32:06 INFO - Running post-run listener: copy_logs_to_upload_dir 12:32:06 INFO - Copying logs to upload dir... 12:32:06 INFO - mkdir: /builds/slave/test/build/upload/logs 12:32:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.094930 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-11 12:32:06.396028) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 12:32:06.399475) ========= 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.P65Ifs0rx8/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.wpbVAtuRlm/Listeners TMPDIR=/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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/Fx-Team/sha512/586c02d293a53dd6056a5c23e903f7366a9bb2c39f76d2cd46d7115a3f8bb081993029033679ecc7751df45d40faa8d89eeb1a76a10c66d634e7aceca5fcc062", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ae62a1e0c4848f123d8ec5f68a1c96fc4335272612cce09c6c91cdd9737755f5ade5d8e983cccf4db5522135a046efd5e7c3d363aef514dba18804802fc7995f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/911f110e21cf6037c6d4a95a6213e060989398c2dc98f4c288f7b78422f93f675dc5fce5e691522347ae76cc912ebfa2dea587112fa63bcd9af9aa90fa230d41"} build_url:https://queue.taskcluster.net/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011213 build_url: 'https://queue.taskcluster.net/v1/task/Vn_15I7bS4ibUIq8-vOIYQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/586c02d293a53dd6056a5c23e903f7366a9bb2c39f76d2cd46d7115a3f8bb081993029033679ecc7751df45d40faa8d89eeb1a76a10c66d634e7aceca5fcc062", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ae62a1e0c4848f123d8ec5f68a1c96fc4335272612cce09c6c91cdd9737755f5ade5d8e983cccf4db5522135a046efd5e7c3d363aef514dba18804802fc7995f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/911f110e21cf6037c6d4a95a6213e060989398c2dc98f4c288f7b78422f93f675dc5fce5e691522347ae76cc912ebfa2dea587112fa63bcd9af9aa90fa230d41"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 12:32:06.431543) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 12:32:06.431863) ========= 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.P65Ifs0rx8/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.wpbVAtuRlm/Listeners TMPDIR=/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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.004320 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 12:32:06.479852) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 12:32:06.480171) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 12:32:06.480488) ========= ========= Total master_lag: 0.19 =========