builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0019 starttime: 1479191549.07 results: success (0) buildid: 20161114200813 builduid: a084c28fbee94eaa924165c2a82e9cc7 revision: d32b3458265589c2e9bc2f9413b5a3256a6f9ea3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 22:32:29.066231) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 22:32:29.066678) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 22:32:29.066969) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 22:32:29.091894) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 22:32:29.092270) ========= 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.wSsWa9u01w/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.B9fNfQ4qfN/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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-11-14 22:32:29-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.2M=0.001s 2016-11-14 22:32:29 (15.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127265 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 22:32:29.242149) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 22:32:29.242460) ========= 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.wSsWa9u01w/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.B9fNfQ4qfN/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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.026497 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 22:32:29.290797) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-14 22:32:29.291159) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d32b3458265589c2e9bc2f9413b5a3256a6f9ea3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d32b3458265589c2e9bc2f9413b5a3256a6f9ea3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wSsWa9u01w/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.B9fNfQ4qfN/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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-11-14 22:32:29,385 truncating revision to first 12 chars 2016-11-14 22:32:29,385 Setting DEBUG logging. 2016-11-14 22:32:29,385 attempt 1/10 2016-11-14 22:32:29,385 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d32b34582655?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-14 22:32:30,108 unpacking tar archive at: mozilla-aurora-d32b34582655/testing/mozharness/ program finished with exit code 0 elapsedTime=1.014406 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-14 22:32:30.327890) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 22:32:30.328324) ========= script_repo_revision: d32b3458265589c2e9bc2f9413b5a3256a6f9ea3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 22:32:30.328720) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 22:32:30.329002) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 22:32:30.348009) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-14 22:32:30.348441) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wSsWa9u01w/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.B9fNfQ4qfN/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 22:32:30 INFO - MultiFileLogger online at 20161114 22:32:30 in /builds/slave/test 22:32:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 22:32:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:32:30 INFO - {'append_to_log': False, 22:32:30 INFO - 'base_work_dir': '/builds/slave/test', 22:32:30 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 22:32:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:32:30 INFO - 'branch': 'Mozilla-Aurora', 22:32:30 INFO - 'buildbot_json_path': 'buildprops.json', 22:32:30 INFO - 'config_files': ('talos/mac_config.py',), 22:32:30 INFO - 'default_actions': ('clobber', 22:32:30 INFO - 'read-buildbot-config', 22:32:30 INFO - 'download-and-extract', 22:32:30 INFO - 'populate-webroot', 22:32:30 INFO - 'create-virtualenv', 22:32:30 INFO - 'install', 22:32:30 INFO - 'run-tests'), 22:32:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:32:30 INFO - 'download_minidump_stackwalk': True, 22:32:30 INFO - 'download_symbols': 'ondemand', 22:32:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:32:30 INFO - 'tooltool.py': '/tools/tooltool.py', 22:32:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:32:30 INFO - '/tools/misc-python/virtualenv.py')}, 22:32:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:32:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:32:30 INFO - 'installer_path': 'installer.exe', 22:32:30 INFO - 'log_level': 'info', 22:32:30 INFO - 'log_name': 'talos', 22:32:30 INFO - 'log_to_console': True, 22:32:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:32:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:32:30 INFO - 'opt_config_files': (), 22:32:30 INFO - 'pip_index': False, 22:32:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:32:30 INFO - 'cmd': ('bash', 22:32:30 INFO - '-c', 22:32:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:32:30 INFO - 'enabled': True, 22:32:30 INFO - 'halt_on_failure': False, 22:32:30 INFO - 'name': 'check_screen_resolution'},), 22:32:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:32:30 INFO - 'cmd': ('bash', 22:32:30 INFO - '-c', 22:32:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:32:30 INFO - 'enabled': True, 22:32:30 INFO - 'halt_on_failure': False, 22:32:30 INFO - 'name': 'check_screen_resolution'},), 22:32:30 INFO - 'run_cmd_checks_enabled': True, 22:32:30 INFO - 'sps_profile': False, 22:32:30 INFO - 'sps_profile_interval': 0, 22:32:30 INFO - 'suite': 'dromaeojs-e10s', 22:32:30 INFO - 'system_bits': '32', 22:32:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:32:30 INFO - 'title': 't-yosemite-r7-0019', 22:32:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:32:30 INFO - 'use_talos_json': True, 22:32:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:32:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:32:30 INFO - 'work_dir': 'build'} 22:32:30 INFO - [mozharness: 2016-11-15 06:32:30.557828Z] Running clobber step. 22:32:30 INFO - Running pre-action listener: _resource_record_pre_action 22:32:30 INFO - Running main action method: clobber 22:32:30 INFO - rmtree: /builds/slave/test/build 22:32:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:32:33 INFO - Running post-action listener: _resource_record_post_action 22:32:33 INFO - [mozharness: 2016-11-15 06:32:33.123846Z] Finished clobber step (success) 22:32:33 INFO - [mozharness: 2016-11-15 06:32:33.124122Z] Running read-buildbot-config step. 22:32:33 INFO - Running pre-action listener: _resource_record_pre_action 22:32:33 INFO - Running main action method: read_buildbot_config 22:32:33 INFO - Using buildbot properties: 22:32:33 INFO - { 22:32:33 INFO - "project": "", 22:32:33 INFO - "product": "firefox", 22:32:33 INFO - "script_repo_revision": "production", 22:32:33 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 22:32:33 INFO - "repository": "", 22:32:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 22:32:33 INFO - "buildid": "20161114200813", 22:32:33 INFO - "pgo_build": "False", 22:32:33 INFO - "basedir": "/builds/slave/test", 22:32:33 INFO - "buildnumber": 33, 22:32:33 INFO - "slavename": "t-yosemite-r7-0019", 22:32:33 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 22:32:33 INFO - "platform": "yosemite_r7", 22:32:33 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 22:32:33 INFO - "branch": "mozilla-aurora", 22:32:33 INFO - "repo_path": "releases/mozilla-aurora", 22:32:33 INFO - "revision": "d32b3458265589c2e9bc2f9413b5a3256a6f9ea3", 22:32:33 INFO - "stage_platform": "macosx64", 22:32:33 INFO - "builduid": "a084c28fbee94eaa924165c2a82e9cc7", 22:32:33 INFO - "slavebuilddir": "test" 22:32:33 INFO - } 22:32:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 22:32:33 INFO - Running post-action listener: _resource_record_post_action 22:32:33 INFO - [mozharness: 2016-11-15 06:32:33.132022Z] Finished read-buildbot-config step (success) 22:32:33 INFO - [mozharness: 2016-11-15 06:32:33.132293Z] Running download-and-extract step. 22:32:33 INFO - Running pre-action listener: _resource_record_pre_action 22:32:33 INFO - Running main action method: download_and_extract 22:32:33 INFO - mkdir: /builds/slave/test/build/tests 22:32:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:32:33 INFO - trying https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 22:32:33 INFO - Downloading https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 22:32:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 22:32:34 INFO - Downloaded 1199 bytes. 22:32:34 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 22:32:34 INFO - Using the following test package requirements: 22:32:34 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 22:32:34 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:32:34 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 22:32:34 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:32:34 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 22:32:34 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:32:34 INFO - u'jsshell-mac.zip'], 22:32:34 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:32:34 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 22:32:34 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 22:32:34 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:32:34 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 22:32:34 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:32:34 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 22:32:34 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:32:34 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 22:32:34 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:32:34 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 22:32:34 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 22:32:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 22:32:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 22:32:34 INFO - Fetch https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 22:32:35 INFO - Http code: 200 22:32:35 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 22:32:35 INFO - x-amz-cf-id: Lh7OyMeV7qLi9cH1XIOcJ97AWnMJXKAk_8n5KMlTxyKOtchw7qtQ0w== 22:32:35 INFO - x-amz-version-id: WgL7mKdWg65Xy27OSB5kFNEbBKUIOSKb 22:32:35 INFO - Expected file size: 16327482 22:32:35 INFO - Obtained file size: 16327482 22:32:41 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 22:32:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 22:32:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 22:32:41 INFO - Fetch https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 22:32:42 INFO - Http code: 200 22:32:42 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 22:32:42 INFO - x-amz-cf-id: oEm--FaWes5LV1hn4gFZKFU7tqp_eJQVa_u1d-ZLBOMWq4vKdr4L5A== 22:32:42 INFO - x-amz-version-id: WgL7mKdWg65Xy27OSB5kFNEbBKUIOSKb 22:32:42 INFO - Expected file size: 16327482 22:32:42 INFO - Obtained file size: 16327482 22:32:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 22:32:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 22:32:49 INFO - Fetch https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 22:32:50 INFO - Http code: 200 22:32:50 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 22:32:50 INFO - x-amz-cf-id: noEritg0ykEWZnOmtNCYvkgkMIGPQrEX7z2LBz2nlneMQWcAvOV7NA== 22:32:50 INFO - x-amz-version-id: flEjvKsnC5n.IRuRbeKSLjY8wOCk8YgJ 22:32:50 INFO - Expected file size: 14409038 22:32:50 INFO - Obtained file size: 14409038 22:32: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:32:50 INFO - trying https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 22:32:50 INFO - Downloading https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 22:32:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 22:32:53 INFO - Downloaded 114363240 bytes. 22:32:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 22:32:53 INFO - mkdir: /builds/slave/test/properties 22:32:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:32:53 INFO - Writing to file /builds/slave/test/properties/build_url 22:32:53 INFO - Contents: 22:32:53 INFO - build_url:https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 22:32:54 INFO - Running post-action listener: _resource_record_post_action 22:32:54 INFO - Running post-action listener: set_extra_try_arguments 22:32:54 INFO - [mozharness: 2016-11-15 06:32:54.788039Z] Finished download-and-extract step (success) 22:32:54 INFO - [mozharness: 2016-11-15 06:32:54.788168Z] Running populate-webroot step. 22:32:54 INFO - Running pre-action listener: _resource_record_pre_action 22:32:54 INFO - Running main action method: populate_webroot 22:32:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:32:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:32:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:32:54 INFO - '--tppagecycles', 22:32:54 INFO - '7'], 22:32:54 INFO - 'tests': ['tsvgm']}}, 22:32:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:32:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:32:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:32:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:32:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:32:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:32:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:32:54 INFO - 'talos_options': ['--disable-e10s'], 22:32:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:32:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:32:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:32:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:32:54 INFO - 'talos_options': ['--disable-e10s'], 22:32:54 INFO - 'tests': ['damp', 'tps']}, 22:32:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:32:54 INFO - 'tests': ['damp', 'tps']}, 22:32:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:32:54 INFO - 'tests': ['dromaeo_dom']}, 22:32:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:32:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:32:54 INFO - 'tests': ['basic_compositor_video']}, 22:32:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:32:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:32:54 INFO - 'tests': ['a11yr', 22:32:54 INFO - 'ts_paint', 22:32:54 INFO - 'tpaint', 22:32:54 INFO - 'sessionrestore', 22:32:54 INFO - 'sessionrestore_no_auto_restore', 22:32:54 INFO - 'tabpaint']}, 22:32:54 INFO - 'other-e10s': {'tests': ['a11yr', 22:32:54 INFO - 'ts_paint', 22:32:54 INFO - 'tpaint', 22:32:54 INFO - 'sessionrestore', 22:32:54 INFO - 'sessionrestore_no_auto_restore', 22:32:54 INFO - 'tabpaint']}, 22:32:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:32:54 INFO - 'ts_paint', 22:32:54 INFO - 'tpaint', 22:32:54 INFO - 'sessionrestore', 22:32:54 INFO - 'sessionrestore_no_auto_restore', 22:32:54 INFO - 'tabpaint']}, 22:32:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:32:54 INFO - 'ts_paint', 22:32:54 INFO - 'tpaint', 22:32:54 INFO - 'sessionrestore', 22:32:54 INFO - 'sessionrestore_no_auto_restore', 22:32:54 INFO - 'tabpaint']}, 22:32:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:32:54 INFO - 'tests': ['a11yr', 22:32:54 INFO - 'ts_paint', 22:32:54 INFO - 'tpaint', 22:32:54 INFO - 'sessionrestore', 22:32:54 INFO - 'sessionrestore_no_auto_restore', 22:32:54 INFO - 'tabpaint']}, 22:32:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:32:54 INFO - 'tests': ['a11yr', 22:32:54 INFO - 'ts_paint', 22:32:54 INFO - 'tpaint', 22:32:54 INFO - 'sessionrestore', 22:32:54 INFO - 'sessionrestore_no_auto_restore', 22:32:54 INFO - 'tabpaint']}, 22:32:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:32:54 INFO - 'tests': ['tsvgx', 22:32:54 INFO - 'tsvgr_opacity', 22:32:54 INFO - 'tart', 22:32:54 INFO - 'tscrollx', 22:32:54 INFO - 'cart']}, 22:32:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:32:54 INFO - 'tsvgr_opacity', 22:32:54 INFO - 'tart', 22:32:54 INFO - 'tscrollx', 22:32:54 INFO - 'cart']}, 22:32:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:32:54 INFO - 'talos_options': ['--disable-e10s'], 22:32:54 INFO - 'tests': ['tp5o']}, 22:32:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:32:54 INFO - 'tests': ['tp5o']}, 22:32:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:32:54 INFO - 'talos_options': ['--disable-e10s', 22:32:54 INFO - '--xperf_path', 22:32:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:32:54 INFO - 'tests': ['tp5n']}, 22:32:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:32:54 INFO - 'talos_options': ['--xperf_path', 22:32:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:32:54 INFO - 'tests': ['tp5n']}}, 22:32:54 INFO - 'talos.zip': {'path': '', 22:32:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:32:54 INFO - Running post-action listener: _resource_record_post_action 22:32:54 INFO - [mozharness: 2016-11-15 06:32:54.807152Z] Finished populate-webroot step (success) 22:32:54 INFO - [mozharness: 2016-11-15 06:32:54.807273Z] Running create-virtualenv step. 22:32:54 INFO - Running pre-action listener: _resource_record_pre_action 22:32:54 INFO - Running main action method: create_virtualenv 22:32:54 INFO - Creating virtualenv /builds/slave/test/build/venv 22:32:54 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 22:32:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:32:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:32:54 INFO - Using real prefix '/tools/python27' 22:32:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:32:55 INFO - Installing distribute.............................................................................................................................................................................................done. 22:32:58 INFO - Installing pip.................done. 22:32:58 INFO - Return code: 0 22:32:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:32:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:32:58 INFO - Reading from file tmpfile_stdout 22:32:58 INFO - Output received: 22:32:58 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 22:32:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:32:58 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:32:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:32:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:58 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:32:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:32:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:58 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 0x10d5a6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d51c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe252d6f5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3bad30>, '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 0x10d220db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3b2b20>, '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.wSsWa9u01w/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.B9fNfQ4qfN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 22:32:58 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 22:32:58 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 22:32:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wSsWa9u01w/Render', 22:32:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:32:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:32:58 INFO - 'HOME': '/Users/cltbld', 22:32:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:32:58 INFO - 'LOGNAME': 'cltbld', 22:32:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:32:58 INFO - 'MOZ_NO_REMOTE': '1', 22:32:58 INFO - 'NO_EM_RESTART': '1', 22:32:58 INFO - 'PAGER': '/bin/cat', 22:32:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:32:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:32:58 INFO - 'PWD': '/builds/slave/test', 22:32:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:32:58 INFO - 'SHELL': '/bin/bash', 22:32:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B9fNfQ4qfN/Listeners', 22:32:58 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 22:32:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:32:58 INFO - 'USER': 'cltbld', 22:32:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:32:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:32:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:32:58 INFO - 'XPC_FLAGS': '0x0', 22:32:58 INFO - 'XPC_SERVICE_NAME': '0', 22:32:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:32:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:32:59 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 22:32:59 INFO - Cleaning up... 22:32:59 INFO - Return code: 0 22:32:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:32:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:32:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:32:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:32:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d5a6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d51c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe252d6f5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3bad30>, '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 0x10d220db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3b2b20>, '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.wSsWa9u01w/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.B9fNfQ4qfN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 22:32:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:32:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:32:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wSsWa9u01w/Render', 22:32:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:32:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:32:59 INFO - 'HOME': '/Users/cltbld', 22:32:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:32:59 INFO - 'LOGNAME': 'cltbld', 22:32:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:32:59 INFO - 'MOZ_NO_REMOTE': '1', 22:32:59 INFO - 'NO_EM_RESTART': '1', 22:32:59 INFO - 'PAGER': '/bin/cat', 22:32:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:32:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:32:59 INFO - 'PWD': '/builds/slave/test', 22:32:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:32:59 INFO - 'SHELL': '/bin/bash', 22:32:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B9fNfQ4qfN/Listeners', 22:32:59 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 22:32:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:32:59 INFO - 'USER': 'cltbld', 22:32:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:32:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:32:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:32:59 INFO - 'XPC_FLAGS': '0x0', 22:32:59 INFO - 'XPC_SERVICE_NAME': '0', 22:32:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:32:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:32:59 INFO - Downloading/unpacking psutil>=3.1.1 22:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:32:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:32:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:32:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:32:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:33:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:33:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:33:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:33:02 INFO - Installing collected packages: psutil 22:33:02 INFO - Running setup.py install for psutil 22:33:02 INFO - building 'psutil._psutil_osx' extension 22:33:02 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 22:33:03 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 22:33:03 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 22:33:03 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 22:33:03 INFO - building 'psutil._psutil_posix' extension 22:33:03 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 22:33:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:33:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:33:03 INFO - ^ 22:33:03 INFO - 1 warning generated. 22:33:03 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 22:33:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:33:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:33:03 INFO - Successfully installed psutil 22:33:03 INFO - Cleaning up... 22:33:04 INFO - Return code: 0 22:33:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:33:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:33:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:33:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:33:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:33:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:04 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 0x10d5a6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d51c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe252d6f5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3bad30>, '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 0x10d220db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3b2b20>, '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.wSsWa9u01w/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.B9fNfQ4qfN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 22:33:04 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 22:33:04 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 22:33:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wSsWa9u01w/Render', 22:33:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:33:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:33:04 INFO - 'HOME': '/Users/cltbld', 22:33:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:33:04 INFO - 'LOGNAME': 'cltbld', 22:33:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:33:04 INFO - 'MOZ_NO_REMOTE': '1', 22:33:04 INFO - 'NO_EM_RESTART': '1', 22:33:04 INFO - 'PAGER': '/bin/cat', 22:33:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:33:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:33:04 INFO - 'PWD': '/builds/slave/test', 22:33:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:33:04 INFO - 'SHELL': '/bin/bash', 22:33:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B9fNfQ4qfN/Listeners', 22:33:04 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 22:33:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:33:04 INFO - 'USER': 'cltbld', 22:33:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:33:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:33:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:33:04 INFO - 'XPC_FLAGS': '0x0', 22:33:04 INFO - 'XPC_SERVICE_NAME': '0', 22:33:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:33:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:33:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:33:04 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 22:33:04 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 22:33:04 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 22:33:04 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 22:33:04 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 22:33:04 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 22:33:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:33:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:33:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:33:06 INFO - Installing collected packages: mozsystemmonitor 22:33:06 INFO - Running setup.py install for mozsystemmonitor 22:33:06 INFO - Successfully installed mozsystemmonitor 22:33:06 INFO - Cleaning up... 22:33:07 INFO - Return code: 0 22:33:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:33:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:33:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:33:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:33:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:33:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d5a6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d51c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe252d6f5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3bad30>, '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 0x10d220db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3b2b20>, '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.wSsWa9u01w/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.B9fNfQ4qfN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 22:33:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:33:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:33:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wSsWa9u01w/Render', 22:33:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:33:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:33:07 INFO - 'HOME': '/Users/cltbld', 22:33:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:33:07 INFO - 'LOGNAME': 'cltbld', 22:33:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:33:07 INFO - 'MOZ_NO_REMOTE': '1', 22:33:07 INFO - 'NO_EM_RESTART': '1', 22:33:07 INFO - 'PAGER': '/bin/cat', 22:33:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:33:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:33:07 INFO - 'PWD': '/builds/slave/test', 22:33:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:33:07 INFO - 'SHELL': '/bin/bash', 22:33:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B9fNfQ4qfN/Listeners', 22:33:07 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 22:33:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:33:07 INFO - 'USER': 'cltbld', 22:33:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:33:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:33:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:33:07 INFO - 'XPC_FLAGS': '0x0', 22:33:07 INFO - 'XPC_SERVICE_NAME': '0', 22:33:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:33:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:33:07 INFO - Downloading/unpacking jsonschema==2.5.1 22:33:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:33:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:33:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:33:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:33:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:33:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:33:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:33:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:33:09 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 22:33:09 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 22:33:09 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 22:33:09 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 22:33:09 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 22:33:09 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 22:33:10 INFO - Downloading functools32-3.2.3-2.tar.gz 22:33:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:33:10 INFO - warning: no files found matching '*.txt' 22:33:10 INFO - no previously-included directories found matching 'build' 22:33:10 INFO - no previously-included directories found matching 'dist' 22:33:10 INFO - no previously-included directories found matching '.git*' 22:33:10 INFO - Installing collected packages: jsonschema, functools32 22:33:10 INFO - Running setup.py install for functools32 22:33:10 INFO - warning: no files found matching '*.txt' 22:33:10 INFO - no previously-included directories found matching 'build' 22:33:10 INFO - no previously-included directories found matching 'dist' 22:33:10 INFO - no previously-included directories found matching '.git*' 22:33:10 INFO - Successfully installed jsonschema functools32 22:33:10 INFO - Cleaning up... 22:33:10 INFO - Return code: 0 22:33:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:33:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:33:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:33:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:33:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:33:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d51c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe252d6f5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3bad30>, '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 0x10d220db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3b2b20>, '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.wSsWa9u01w/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.B9fNfQ4qfN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 22:33:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:33:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 22:33:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wSsWa9u01w/Render', 22:33:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:33:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:33:10 INFO - 'HOME': '/Users/cltbld', 22:33:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:33:10 INFO - 'LOGNAME': 'cltbld', 22:33:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:33:10 INFO - 'MOZ_NO_REMOTE': '1', 22:33:10 INFO - 'NO_EM_RESTART': '1', 22:33:10 INFO - 'PAGER': '/bin/cat', 22:33:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:33:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:33:10 INFO - 'PWD': '/builds/slave/test', 22:33:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:33:10 INFO - 'SHELL': '/bin/bash', 22:33:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B9fNfQ4qfN/Listeners', 22:33:10 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 22:33:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:33:10 INFO - 'USER': 'cltbld', 22:33:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:33:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:33:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:33:10 INFO - 'XPC_FLAGS': '0x0', 22:33:10 INFO - 'XPC_SERVICE_NAME': '0', 22:33:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:33:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:33:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:33:10 INFO - Cleaning up... 22:33:10 INFO - Return code: 0 22:33:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:33:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:33:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:33:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:33:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:33:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d51c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe252d6f5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3bad30>, '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 0x10d220db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3b2b20>, '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.wSsWa9u01w/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.B9fNfQ4qfN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 22:33:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:33:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:33:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wSsWa9u01w/Render', 22:33:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:33:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:33:10 INFO - 'HOME': '/Users/cltbld', 22:33:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:33:10 INFO - 'LOGNAME': 'cltbld', 22:33:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:33:10 INFO - 'MOZ_NO_REMOTE': '1', 22:33:10 INFO - 'NO_EM_RESTART': '1', 22:33:10 INFO - 'PAGER': '/bin/cat', 22:33:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:33:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:33:10 INFO - 'PWD': '/builds/slave/test', 22:33:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:33:10 INFO - 'SHELL': '/bin/bash', 22:33:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B9fNfQ4qfN/Listeners', 22:33:10 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 22:33:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:33:10 INFO - 'USER': 'cltbld', 22:33:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:33:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:33:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:33:10 INFO - 'XPC_FLAGS': '0x0', 22:33:10 INFO - 'XPC_SERVICE_NAME': '0', 22:33:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:33:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:33:11 INFO - Downloading/unpacking blobuploader==1.2.4 22:33:11 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 22:33:11 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 22:33:11 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 22:33:11 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 22:33:11 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 22:33:11 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 22:33:13 INFO - Downloading blobuploader-1.2.4.tar.gz 22:33:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:33:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:33:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:33:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:33:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:33:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:33:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:33:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:33:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:33:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:33:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:33:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:33:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:33:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:33:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:33:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:33:14 INFO - Downloading docopt-0.6.1.tar.gz 22:33:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:33:15 INFO - Installing collected packages: blobuploader, requests, docopt 22:33:15 INFO - Running setup.py install for blobuploader 22:33:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:33:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:33:15 INFO - Running setup.py install for requests 22:33:15 INFO - Running setup.py install for docopt 22:33:15 INFO - Successfully installed blobuploader requests docopt 22:33:15 INFO - Cleaning up... 22:33:15 INFO - Return code: 0 22:33:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:33:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:33:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:33:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:33:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:33:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:15 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 0x10d5a6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d51c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe252d6f5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3bad30>, '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 0x10d220db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3b2b20>, '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.wSsWa9u01w/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.B9fNfQ4qfN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 22:33:15 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 22:33:15 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 22:33:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wSsWa9u01w/Render', 22:33:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:33:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:33:15 INFO - 'HOME': '/Users/cltbld', 22:33:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:33:15 INFO - 'LOGNAME': 'cltbld', 22:33:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:33:15 INFO - 'MOZ_NO_REMOTE': '1', 22:33:15 INFO - 'NO_EM_RESTART': '1', 22:33:15 INFO - 'PAGER': '/bin/cat', 22:33:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:33:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:33:15 INFO - 'PWD': '/builds/slave/test', 22:33:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:33:15 INFO - 'SHELL': '/bin/bash', 22:33:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B9fNfQ4qfN/Listeners', 22:33:15 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 22:33:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:33:15 INFO - 'USER': 'cltbld', 22:33:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:33:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:33:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:33:15 INFO - 'XPC_FLAGS': '0x0', 22:33:15 INFO - 'XPC_SERVICE_NAME': '0', 22:33:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:33:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:33:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:33:16 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-TEt8UB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:33:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:33:16 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-isTZS7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:33:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:33:16 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-F0pbi9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:33:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:33:16 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-vZUcfX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:33:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:33:16 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-Lha9jF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:33:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:33:16 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-0UoPdC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:33:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:33:16 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-zLlDlc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:33:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:33:16 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-kb1chQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:33:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:33:16 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-AGaBzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:33:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:33:17 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-BCM5yF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:33:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:33:17 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-z1YEsN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:33:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:33:17 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-0cQKVb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:33:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:33:17 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-Stin8C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:33:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:33:17 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-VELmoK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:33:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:33:17 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-7RNvcJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:33:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:33:17 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-uBM_qn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:33:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:33:17 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-bn71t9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:33:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:33:17 INFO - Running setup.py install for manifestparser 22:33:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:33:18 INFO - Running setup.py install for mozcrash 22:33:18 INFO - Running setup.py install for mozdebug 22:33:18 INFO - Running setup.py install for mozdevice 22:33:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:33:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:33:18 INFO - Running setup.py install for mozfile 22:33:18 INFO - Running setup.py install for mozhttpd 22:33:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:33:18 INFO - Running setup.py install for mozinfo 22:33:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:33:18 INFO - Running setup.py install for mozInstall 22:33:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:33:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:33:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:33:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:33:19 INFO - Running setup.py install for mozleak 22:33:19 INFO - Running setup.py install for mozlog 22:33:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:33:19 INFO - Running setup.py install for moznetwork 22:33:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:33:19 INFO - Running setup.py install for mozprocess 22:33:19 INFO - Running setup.py install for mozprofile 22:33:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:33:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:33:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:33:19 INFO - Running setup.py install for mozrunner 22:33:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:33:20 INFO - Running setup.py install for mozscreenshot 22:33:20 INFO - Running setup.py install for moztest 22:33:20 INFO - Running setup.py install for mozversion 22:33:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:33:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:33:20 INFO - Cleaning up... 22:33:20 INFO - Return code: 0 22:33:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:33:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:33:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:33:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:33:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:33:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:20 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 0x10d5a6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d51c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe252d6f5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3bad30>, '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 0x10d220db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3b2b20>, '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.wSsWa9u01w/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.B9fNfQ4qfN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 22:33:20 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 22:33:20 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 22:33:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wSsWa9u01w/Render', 22:33:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:33:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:33:20 INFO - 'HOME': '/Users/cltbld', 22:33:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:33:20 INFO - 'LOGNAME': 'cltbld', 22:33:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:33:20 INFO - 'MOZ_NO_REMOTE': '1', 22:33:20 INFO - 'NO_EM_RESTART': '1', 22:33:20 INFO - 'PAGER': '/bin/cat', 22:33:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:33:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:33:20 INFO - 'PWD': '/builds/slave/test', 22:33:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:33:20 INFO - 'SHELL': '/bin/bash', 22:33:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B9fNfQ4qfN/Listeners', 22:33:20 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 22:33:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:33:20 INFO - 'USER': 'cltbld', 22:33:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:33:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:33:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:33:20 INFO - 'XPC_FLAGS': '0x0', 22:33:20 INFO - 'XPC_SERVICE_NAME': '0', 22:33:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:33:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:33:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:33:20 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-HYL3oF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:33:20 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)) 22:33:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:33:20 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-grTA9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:33:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 22:33:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:33:21 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-Ux9cSm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:33:21 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)) 22:33:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:33:21 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-0c7gJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:33:21 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)) 22:33:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:33:21 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-tgpv5w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:33:21 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)) 22:33:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:33:21 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-5vFyki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:33:21 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)) 22:33:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:33:21 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-1_7Z26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:33:21 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)) 22:33:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:33:21 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-VEiAzk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:33:21 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)) 22:33:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:33:21 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-gYtsUj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:33:21 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)) 22:33:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:33:21 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-VSZq8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:33:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 22:33:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:33:21 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-t6zVEN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:33:21 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)) 22:33:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:33:21 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-z0pxvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:33:22 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)) 22:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:33:22 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-rD0eIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:33:22 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)) 22:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:33:22 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-9u6ujt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:33:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 22:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:33:22 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-hK4WQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:33:22 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)) 22:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:33:22 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-jEb2Db-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:33:22 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)) 22:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:33:22 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-EM9h1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:33:22 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)) 22:33:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:33:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:33:22 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)) 22:33:22 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)) 22:33:22 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)) 22:33:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:33:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:33:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:33:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:33:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:33:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:33:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:33:25 INFO - Downloading blessings-1.6.tar.gz 22:33:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:33:25 INFO - Installing collected packages: blessings 22:33:25 INFO - Running setup.py install for blessings 22:33:25 INFO - Successfully installed blessings 22:33:25 INFO - Cleaning up... 22:33:25 INFO - Return code: 0 22:33:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:33:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:33:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:33:25 INFO - Reading from file tmpfile_stdout 22:33:25 INFO - Current package versions: 22:33:25 INFO - blessings == 1.6 22:33:25 INFO - blobuploader == 1.2.4 22:33:25 INFO - docopt == 0.6.1 22:33:25 INFO - functools32 == 3.2.3-2 22:33:25 INFO - jsonschema == 2.5.1 22:33:25 INFO - manifestparser == 1.1 22:33:25 INFO - mozInstall == 1.12 22:33:25 INFO - mozcrash == 1.0 22:33:25 INFO - mozdebug == 0.1 22:33:25 INFO - mozdevice == 0.48 22:33:25 INFO - mozfile == 1.2 22:33:25 INFO - mozhttpd == 0.7 22:33:25 INFO - mozinfo == 0.9 22:33:25 INFO - mozleak == 0.1 22:33:25 INFO - mozlog == 3.4 22:33:25 INFO - moznetwork == 0.27 22:33:25 INFO - mozprocess == 0.23 22:33:25 INFO - mozprofile == 0.28 22:33:25 INFO - mozrunner == 6.13 22:33:25 INFO - mozscreenshot == 0.1 22:33:25 INFO - mozsystemmonitor == 0.3 22:33:25 INFO - moztest == 0.7 22:33:25 INFO - mozversion == 1.4 22:33:25 INFO - psutil == 3.1.1 22:33:25 INFO - requests == 1.2.3 22:33:25 INFO - wsgiref == 0.1.2 22:33:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:33: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:33:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:33:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:26 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:33:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:33:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:26 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 0x10d5a6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d51c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe252d6f5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3bad30>, '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 0x10d220db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3b2b20>, '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.wSsWa9u01w/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.B9fNfQ4qfN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 22:33:26 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 22:33:26 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 22:33:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wSsWa9u01w/Render', 22:33:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:33:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:33:26 INFO - 'HOME': '/Users/cltbld', 22:33:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:33:26 INFO - 'LOGNAME': 'cltbld', 22:33:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:33:26 INFO - 'MOZ_NO_REMOTE': '1', 22:33:26 INFO - 'NO_EM_RESTART': '1', 22:33:26 INFO - 'PAGER': '/bin/cat', 22:33:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:33:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:33:26 INFO - 'PWD': '/builds/slave/test', 22:33:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:33:26 INFO - 'SHELL': '/bin/bash', 22:33:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B9fNfQ4qfN/Listeners', 22:33:26 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 22:33:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:33:26 INFO - 'USER': 'cltbld', 22:33:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:33:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:33:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:33:26 INFO - 'XPC_FLAGS': '0x0', 22:33:26 INFO - 'XPC_SERVICE_NAME': '0', 22:33:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:33:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:33:27 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)) 22:33:27 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)) 22:33:27 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)) 22:33:27 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)) 22:33:27 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)) 22:33:27 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)) 22:33:27 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)) 22:33:27 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)) 22:33:27 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)) 22:33:27 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)) 22:33:27 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)) 22:33:27 INFO - Cleaning up... 22:33:27 INFO - Return code: 0 22:33:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:33:27 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:33:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:33:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:27 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:33:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:33:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:27 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 0x10d5a6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d51c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe252d6f5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3bad30>, '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 0x10d220db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3b2b20>, '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.wSsWa9u01w/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.B9fNfQ4qfN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 22:33:27 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 22:33:27 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 22:33:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wSsWa9u01w/Render', 22:33:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:33:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:33:27 INFO - 'HOME': '/Users/cltbld', 22:33:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:33:27 INFO - 'LOGNAME': 'cltbld', 22:33:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:33:27 INFO - 'MOZ_NO_REMOTE': '1', 22:33:27 INFO - 'NO_EM_RESTART': '1', 22:33:27 INFO - 'PAGER': '/bin/cat', 22:33:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:33:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:33:27 INFO - 'PWD': '/builds/slave/test', 22:33:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:33:27 INFO - 'SHELL': '/bin/bash', 22:33:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B9fNfQ4qfN/Listeners', 22:33:27 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 22:33:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:33:27 INFO - 'USER': 'cltbld', 22:33:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:33:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:33:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:33:27 INFO - 'XPC_FLAGS': '0x0', 22:33:27 INFO - 'XPC_SERVICE_NAME': '0', 22:33:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:33:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:33:27 INFO - Cleaning up... 22:33:27 INFO - Return code: 0 22:33:27 INFO - Running post-action listener: _resource_record_post_action 22:33:27 INFO - Running post-action listener: _start_resource_monitoring 22:33:27 INFO - Starting resource monitoring. 22:33:27 INFO - [mozharness: 2016-11-15 06:33:27.800313Z] Finished create-virtualenv step (success) 22:33:27 INFO - [mozharness: 2016-11-15 06:33:27.801370Z] Running install step. 22:33:27 INFO - Running pre-action listener: _resource_record_pre_action 22:33:27 INFO - Running main action method: install 22:33:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:33:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:33:28 INFO - Reading from file tmpfile_stdout 22:33:28 INFO - Detecting whether we're running mozinstall >=1.0... 22:33:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:33:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:33:28 INFO - Reading from file tmpfile_stdout 22:33:28 INFO - Output received: 22:33:28 INFO - Usage: mozinstall [options] installer 22:33:28 INFO - Options: 22:33:28 INFO - -h, --help show this help message and exit 22:33:28 INFO - -d DEST, --destination=DEST 22:33:28 INFO - Directory to install application into. [default: 22:33:28 INFO - "/builds/slave/test"] 22:33:28 INFO - --app=APP Application being installed. [default: firefox] 22:33:28 INFO - mkdir: /builds/slave/test/build/application 22:33:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:33:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 22:34:00 INFO - Reading from file tmpfile_stdout 22:34:00 INFO - Output received: 22:34:00 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 22:34:00 INFO - Running post-action listener: _resource_record_post_action 22:34:00 INFO - [mozharness: 2016-11-15 06:34:00.203812Z] Finished install step (success) 22:34:00 INFO - [mozharness: 2016-11-15 06:34:00.203963Z] Running run-tests step. 22:34:00 INFO - Running pre-action listener: _resource_record_pre_action 22:34:00 INFO - Running main action method: run_tests 22:34:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:34:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:34:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:34:00 INFO - 2016-11-14 22:34:00.218 screenresolution[1625:7346] starting screenresolution argv=screenresolution get 22:34:00 INFO - 2016-11-14 22:34:00.221 screenresolution[1625:7346] Display 0: 1600x1200x32@60 22:34:00 INFO - 2016-11-14 22:34:00.227 screenresolution[1626:7349] starting screenresolution argv=screenresolution list 22:34:00 INFO - Available Modes on Display 0 22:34:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:34:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:34:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:34:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:34:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:34:00 INFO - Intel Iris: 22:34:00 INFO - Chipset Model: Intel Iris 22:34:00 INFO - Type: GPU 22:34:00 INFO - Bus: Built-In 22:34:00 INFO - VRAM (Dynamic, Max): 1536 MB 22:34:00 INFO - Vendor: Intel (0x8086) 22:34:00 INFO - Device ID: 0x0a2e 22:34:00 INFO - Revision ID: 0x0009 22:34:00 INFO - Displays: 22:34:00 INFO - Display: 22:34:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:34:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:34:00 INFO - Display Serial Number: 1600x1200 60 22:34:00 INFO - Main Display: Yes 22:34:00 INFO - Mirror: Off 22:34:00 INFO - Online: Yes 22:34:00 INFO - Rotation: Supported 22:34:00 INFO - Return code: 0 22:34:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:34:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:34:00 INFO - Python 2.7.3 22:34:00 INFO - Return code: 0 22:34:00 INFO - grabbing minidump binary from tooltool 22:34:00 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:34:00 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 0x10d3bad30>, '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 0x10d220db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3b2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:34:00 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 22:34:00 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 22:34:00 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 22:34:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:34:00 INFO - Return code: 0 22:34:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:34:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:34:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:34:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:34:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:34:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0019', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 22:34:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0019 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 22:34:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wSsWa9u01w/Render', 22:34:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:34:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:34:00 INFO - 'HOME': '/Users/cltbld', 22:34:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:34:00 INFO - 'LOGNAME': 'cltbld', 22:34:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:34:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:34:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:34:00 INFO - 'MOZ_NO_REMOTE': '1', 22:34:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:34:00 INFO - 'NO_EM_RESTART': '1', 22:34:00 INFO - 'PAGER': '/bin/cat', 22:34:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:34:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:34:00 INFO - 'PWD': '/builds/slave/test', 22:34:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:34:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:34:00 INFO - 'SHELL': '/bin/bash', 22:34:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B9fNfQ4qfN/Listeners', 22:34:00 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 22:34:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:34:00 INFO - 'USER': 'cltbld', 22:34:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:34:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:34:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:34:00 INFO - 'XPC_FLAGS': '0x0', 22:34:00 INFO - 'XPC_SERVICE_NAME': '0', 22:34:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:34:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0019', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 22:34:00 INFO - mozversion application_buildid: 20161114200813 22:34:00 INFO - mozversion application_changeset: d32b3458265589c2e9bc2f9413b5a3256a6f9ea3 22:34:00 INFO - mozversion application_display_name: FirefoxDeveloperEdition 22:34:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:34:00 INFO - mozversion application_name: Firefox 22:34:00 INFO - mozversion application_remotingname: firefox-dev 22:34:00 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:34:00 INFO - mozversion application_vendor: Mozilla 22:34:00 INFO - mozversion application_version: 52.0a2 22:34:00 INFO - mozversion platform_buildid: 20161114200813 22:34:00 INFO - mozversion platform_changeset: d32b3458265589c2e9bc2f9413b5a3256a6f9ea3 22:34:00 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:34:00 INFO - mozversion platform_version: 52.0a2 22:34:00 INFO - using testdate: 1479191640 22:34:00 INFO - actual date: 1479191640 22:34:00 INFO - starting webserver on 'localhost:49249' 22:34:00 INFO - SUITE-START | Running 2 tests 22:34:00 INFO - TEST-START | dromaeo_css 22:34:00 INFO - operating with platform_type : mac_ 22:34:00 INFO - Initialising browser for dromaeo_css test... 22:34:00 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpC615Bb/profile http://localhost:49249/getInfo.html) 22:34:02 ERROR - PROCESS | 1636 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 22:34:03 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 22:34:03 INFO - PROCESS | 1636 | colorDepth:24 22:34:03 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 22:34:03 INFO - PROCESS | 1636 | __metrics 22:34:03 ERROR - PROCESS | 1636 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3178: TypeError: this.tabs is undefined 22:34:04 INFO - TEST-INFO | 1636: exit 0 22:34:04 INFO - Browser initialized. 22:34:04 INFO - Running cycle 1/1 for dromaeo_css test... 22:34:04 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpC615Bb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:34:16 INFO - PROCESS | 1639 | RSS: Main: 224210944 22:34:16 INFO - PROCESS | 1639 | 22:35:06 INFO - PROCESS | 1639 | 1479191706005 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] 22:37:19 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 22:37:21 INFO - PROCESS | 1639 | RSS: Main: 233701376 22:37:21 INFO - PROCESS | 1639 | 22:40:23 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 22:40:24 INFO - PROCESS | 1639 | RSS: Main: 248922112 22:40:24 INFO - PROCESS | 1639 | 22:43:26 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 22:43:26 INFO - PROCESS | 1639 | RSS: Main: 251760640 22:43:26 INFO - PROCESS | 1639 | 22:46:28 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 22:46:29 INFO - PROCESS | 1639 | RSS: Main: 250916864 22:46:29 INFO - PROCESS | 1639 | 22:49:31 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 22:49:32 INFO - PROCESS | 1639 | RSS: Main: 254361600 22:49:32 INFO - PROCESS | 1639 | 22:52:35 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 22:52:35 INFO - PROCESS | 1639 | RSS: Main: 255803392 22:52:35 INFO - PROCESS | 1639 | 22:52:35 INFO - PROCESS | 1639 | __start_tp_report 22:52:35 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 22:52:35 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 22:52:35 INFO - PROCESS | 1639 | |i|pagename|runs| 22:52:35 INFO - PROCESS | 1639 | |0;dojo.html;2701.621772951787;3379.7127244184226;3421.1447138215444;3421.1618153552386;3452.6547345265476;5819.67991760453;5883.44107309806;5886.205362276091;5891.116332550114;5891.558133140021;5932.525397968163;5981.58128930975;6020.397960203977;6034.8793024139495;6067.605605635642;5317.388500322464;5404.054290499166;5413.133898576229;5421.484958928915;5426.864328391802;5417.187421886729;5426.294581704393;5442.292501974738;5457;5460.563154947595;5251.5798736101215;5271.710055946922;5438.972805135969;5445.591580631449;5498.752556134964;4660.277656963176;5342.626016178869;5354.464553544633;5378.8924221515745;5463.562914966793;5234.7120908350025;5337.439568845254;5341.572674186094;5347.358317001985;5389.218563308337;5901.557383196277;5928.703564821785;5962.07587823888;6042.969785151046;6048.243969503812;5167.302414174113;6186.319504854462;6237.906431403487;6257.937420625781;6260.029695397221;6272.623642581414;6291.559590828644;6333.271673757485;6334.5249106317215;6366.745330186787;6039.667818269993;6048.576599638027;6054.969725151346;6068.484178844804;6082.330943596201;6369.490440764727;6379.840503987344;6417.2299324081405;6432.163818703632;6505.804825855232;6251.062340648939;6256.124142620037;6269.592476489013;6273.780417685359;6278.46633036197;6295.96852015737;6311.211098612674;6314.684265786692;6339.778107766206;6341.112244285804;6134.620279381683;6143.788236234952;6181.103739957771;6203.720832562545;6264.968675156595;5296.496832800878;5947.661776100429;6257.9687101564205;6265.028920517327;6286.49708023357;4408.625266852289;4514.954850451519;4964.726940018334;5028.924566131511;5040.243963405518;3463.428534291864;4446.1330040642415;4473.5526447355005;4505.752183629932;4523.344115103358;4875;6117.796956032686;6125.449118208436;6133.202683651097;6144.262688477412;5892.705364731746;6273.1844860167885;6292.748290068346;6323.810285691437;6325.810225693236;5215.348081489814;5937.910931336033;6090.33006369299;6134.38656134383;6143.1706719592285;5533.059379905426;5554.6944918029085;5555.472230138131;5574.637648552831;5598.608097433141;6501.577397469149;6512;6528.412442880163;6529.314421985623;6562.34376562359;6162.661053642093;6230.221222347207;6329.778457753957;6386.712597933197;6393.1688880445245;6431.807045788634;6463.967680161569;6490.188726409199;6540.901886471612;6582.835429114119;6199.225096862892;6237.781677641261;6256.124142619946;6282.811515654537;6288.622682639057;6162.322144564005;6168.938310617016;6343.809685709436;6344.492440604833;6361.554691171574;6137.438273608381;6140.565599444434;6142.621558143092;6143.327652471189;6254.530910181664;6220.72006759706;6228.657423841732;6242.282137554058;6256.186695729526;6290.465310448663;5122.282880396822;6091.42131497507;6137.263528376445;6189.071639253968;6193.566450348432;6125.264968203665;6162.691865406801;6194.752209911553;6217.720202590985;6238.15784869055;6166.907496387468;6169.907451388322;6198.659073750987;6209.441150296495;6220.937790622036 22:52:35 INFO - PROCESS | 1639 | |1;ext.html;518.4167811212386;522.8588281164087;529.1454301303396;532.6244997276917;540.0333403208257;29826.85086574567;39399.60600393995;40591.98520036997;40719.38920916188;42637.93405164872;40993.38509922354;41431.964200894916;41458;41489;41627.37558936611;9328.766780830483;9462.905370946306;9480.241580673564;9527.618895244183;9547.474888881108;9376.953115234448;9391.577379017961;9469.431834089943;9498.572564234626;9517.904820951771;9429.717108486757;9491.525423728786;9501.667441639544;9536.713898583053;9625.614975400975;9418.670346537872;9477.905220947805;9486.525673716356;9491.715248542554;9740.366876153099;7585.469017168801;8054.677812887478;9278.768030799216;9398.577064032135;9400.24798016164;38866.417003744966;38969.83090507289;39516.81449556518;39733.00667483307;39740.40389394161;3207.486802111651;3350.480675495302;3485;3678.9080272993124;3704.3147017801793;30266.54600180999;30320;30458.84770576133;30475.2381190472;31245.218869528217;3093.180307218589;3172.349668317989;3175.6665550117136;3189.9362012759616;3204.903852884417;497483;502708;503501.48249258887;505298;506746.46626767;59629;59766;59807.40192598105;59813;59824.70087649621;62866;62923.37076629267;63173.36826631675;63198;63298.36701632925;15979.920100399424;16028.519144425685;16203.432879849148;16213;16227.269772860249;12324.691882703004;12393.132480726263;12478.81281780774;12485.56300529477;12487.001039916784;12775.936120319338;16901.408450704414;16965;16999.830001699826;17025.744613830804;3177.4280629486916;6835.726570937206;6998.75504357345;7059.8820353893625;7068.151821781316;879.6701237036111;925.5973651461636;1003.2575893838507;1020.8468729690605;1098.3300186885992;29817.552736708967;30036.249093772392;30134.794608215863;30309.090727278217;30714.84642576773;983.3264214013424;1047.418682631147;1067.1249575348295;1073.4203530093662;1123.9775204495863;13454.596362108956;15124;15234.390624375123;15473.381064757508;16219.756703649455;664801;667512;669190.6540467266;669555;674197;57621.71189144111;58527;58756.824863502494;58919.41080589139;59072.227833165074;1320.4322141479256;1356.186288227056;1365.2218235605808;1366.6241783509615;1372.0121512511175;29078.418431630827;29756.702432975395;29768.70231297659;29790.957316494685;30360.544591830698;45253;49700.75748106182;50278.497215028845;50454.99090018252;51250.74374628103;419186.9040654777;496287;498002;602614;604824;26410.207693769218;26615.93536258528;26627.600585990836;26990.055348062724;26993.46013079688;46868.7656561715;47294;47449.81375465641;47465.76267118642;47518.04963900772;34017.82991085029;39354;39427;39570.81287561378;40480.59519404768;33414.33171336511;36940.81529592335;39037.219255615295;39548;40245.59754402418;38286.808565958105;38646.033849153995;38753.612463876125;38906.80546597249;39083;32921;34281.142971425914;34889.65110348864;35004.47493287552;35100.473492897116;25708.614370784828;25750.454972701697;25804.870975645;26349.868250658623;26458.603120952816 22:52:35 INFO - PROCESS | 1639 | |2;jquery.html;369.2799041868357;379.01267198946744;379.8176875099952;387.5039948865452;408.25697231039504;18297.90212587244;19168.616627667438;19752.209911603553;19968.700469492935;20011.499712507193;19092.522686932833;19334.51663708408;19828.008599570036;19892.60214795707;20226.696599551018;5123.487651234875;5590.944090559083;5781.739821708034;5976.581639285252;5981.342052374235;5763.942360576382;5765.4522820332;5780.306363236417;5904.582900103987;5919.37846526115;5565.2486914266665;5732.31212254528;5806.303243610772;5809.215755872967;5834.387389324123;5019.874503137415;5738.139279108125;5740.7703691852275;5769.932562475956;5807.477327040566;3890.8054597270025;4299.871003869889;4966.5778408835295;5014.774335154927;5034.244863270539;24605.87697061503;24673.136440224687;24776.25671229866;24912.750872491226;25020.499590008098;1915.1956178405103;2211.6793065005645;2370.8696021718797;2420.201333559921;2865.6704478984975;14400.927995360062;14873.70252594953;15059.397624095023;15197;15669.529914102595;1790.516560528663;1791.426743442099;1815.863810214226;1843.3363988963963;1857.0807450312047;86823;117373;118456;119250;124094;10339.948300258451;10965.519654846654;11229;11413.714657133472;11646.825297620542;14264.572062838131;14409.78385324221;14415.351309190903;14441.35013924376;14446.638834029234;8164.918350816416;8436.831263374697;8490.108538603481;8590.441621294596;8610.268127209132;7210.387117095001;8216.808562758486;8341.8331633367;8371.288440482627;8547.273481753997;4453.19842428366;4478.462584489817;4492.303692927601;4495.820167193341;4562.361269422284;1798.5054110119822;1930.179673638698;1935.525796179945;1937.3703546347494;1973.9013049347475;824.3940703582862;906.551257127726;935.0789472369711;939.6476321379482;1005.7284533176002;20273;20776;20800.063997120167;20918.16327346889;20981.265655701976;759.6277823866266;872.7469033980075;915.46445329483;947.8293907096651;957.6831856197728;10550.736231594272;10889.782204355868;10915.345079295268;10927.945360273307;10993.780124397466;539553;620664;636753;637507;645816;111672;112290.43854780674;127488;129918.70081299066;140271.29864350613;827.2761333832897;932.5430539035959;989.5448093876897;991.74214704176;1001.1640280365831;17813.821861781737;18239.452816415527;18477.260909563465;18560.443186704422;18837.811621884153;42430.5756942439;50057.749711251214;51730.74134629303;52547.21179182239;55320;76434;78138.76103373455;78200;78348;79249.60375198087;7701.148276585715;8023.518588884685;8065.354771618374;8138.389620778347;8150.510969341622;11759.176857619885;12324.876751232372;12650.05124615639;12682.239065656091;13035.413406396925;8330.291925186428;10079.395236285847;10561.996610322007;10649.467526623794;11096.112311014938;10701.946490267499;10842.891571084503;10975.012248897638;10994.780104398027;11010.623672040994;11177.560941489124;11331.150163737588;11400.828987565028;11571.826422603499;11621.477033533341;5948.375420580776;7081.437024261035;7358.712225360648;7504.587247701429;7819.41354398411;9160.725178244664;9499.287553433382;10853.40306283162;10974.561017559208;11243.212975091665 22:52:35 INFO - PROCESS | 1639 | |3;mootools.html;1426.3937826423771;1464.9671981253214;1593.5219434169746;1613.6772645470908;1770.5131088950545;7551.584662843541;7844.41166912481;7881.80295492613;8524.232819046285;8557.529335886524;8023.4383593148505;8297.460665056768;8320.084790673021;8346;8421.536815475161;2324.953500929985;2360.280114565057;2361.303415492434;2361.7520160383083;2376.0970831084164;2339.4736184358553;2382.868942208178;2385.0459816073535;2386.713594368678;2395.7244916834525;2326.069572171128;2332.4518738096535;2352.2120089769946;2361.2798096580536;2363.196513185517;2310.953780924389;2312.6531020346915;2333.556624241389;2334.206369834256;2337.415646088478;2347.5891718949288;2355.5406695694346;2369.620860662303;2369.7985671217966;2383.8331316807657;8367.83264334716;8398.83202335956;8435.620397082146;8445.493270403735;8469.872951905665;5060.114479966028;5164.070467315882;5211.322528071364;5250.028744682246;5287.07476191665;3411.181316484051;3434.6221915589267;3491.2668339648703;3512.174638959868;3515.8945231643092;783.6121120045557;956.6077908057732;1056.8467572201987;1079.9946000270027;1088.58633719187;10976.396298203596;11031.779364412667;11050.944745276303;11149.442527873654;11169.776604467947;4283.828646854091;4426.690131690815;4495.168393847149;4495.752733599618;4497.875531117221;8578.09929957358;8715.172058654418;8730.781730456789;8733.03936566977;8763.255123314462;4365.978170109192;5088.592912566985;5090.745462726923;5130.743462826844;5142.100132476801;4334.891627709269;4475.992901597115;4636.235021221461;4737.431508218967;4763.76181190939;3623.130448692295;3686.852525898934;3718.851245950186;3727.086863718406;3737.009692431508;974.6929717139078;991.7669347703284;1092.1044743310408;1147.437755499799;1162.2968104296947;795.3716563914443;865.5931712095305;889.8754174415587;900.1358695652116;906.8322360363354;5382.3541175059245;5552.805651802168;5588.624834445426;5676.744546495418;5759.395263497357;433.2288526422972;443.48112708131566;459.864340019695;466.36108531312317;467.72170558517627;2998.385331007138;3782.0355809269013;3879.2823327683905;3909.0618251619408;3920.666743326849;9558.426494410358;10226.590936362461;10232.6418575351;10281.794364112677;10300.021497957681;10074.848877266846;10138.493075346203;10216.744581385525;10237.976202379703;10281.434521101262;7341.935419364162;7372.078490188726;8011.559364235023;8020.959895200486;8056.556889371141;5411.594130440311;5523;5555.2500412445415;5583.664980101207;5590.357108932525;9388.859167112361;9414.905850941403;9477.431354118498;9493.0981556752;9607.375520591138;9559.90440095618;9582.472963987047;9651.806963860543;9695.9819218981;9774.902250977399;6267.091271765659;6291.087792270004;6686.9331306688255;6743.393094621508;6807.3873351398615;6906.240313565403;7985.281324680806;8050.718224862102;8058.879116813369;8089.191080891864;6245.718942647501;6709.698063587052;7130.572165670076;7266.164391095092;7356.117265928123;7075.858482830417;7086.291370863079;7135.250798666066;7137.892931606134;7207.45039816442;6666.966665166837;6673.632950187592;6767.89848152288;6769.443028103466;6784.287649796897;4820;4864.1001414736975;4869.975650121868;4905.730184839868;4916.950830491649;4060.39094135882;4207.5161356444405;4737.692050016738;4774.419185610792;5029.371328583927 22:52:35 INFO - PROCESS | 1639 | |4;prototype.html;304.52038040086853;307.31928777757264;313.97174254317105;319.1812999655884;319.2354311424138;4703.929441058378;4991.725455099968;5262.605304602156;5453.291072160619;5511.145772405279;4152.2941100012995;4378.387025816388;5093.439721630627;5334.5999050071205;5364.900195459931;3482.5820901491857;4049.8084482560557;4106.9794651026705;4107.630313271805;4108.637533418289;3461.0581006144826;3801.61983801619;4096.365063415175;4104.384342348657;4139.068709540359;3054.816710997336;3554.840032198544;4000.6999475039343;4021.1957608478274;4032.3749818778138;3448.293099914524;3540.5837664933974;3579.8568057277676;3819.0161838800495;4056.1684854604737;2709.0382914065617;3388.0852169913987;3488.3197776433603;3526.488659144435;3534.293141371736;3938.1251686268897;5068.391792984866;5173.896522069537;5249.711265880375;5250.44870288618;4014.879553613396;4590.540945905416;4590.770461476913;4598.885027874297;4620.722756634613;5149.124648809711;5463.945360546383;5588.888222235573;5629.577781666391;5633.915491267634;3183.7453003759874;3358.865645374182;3675.72432067596;3840.8463661453507;3847.99952012476;5744.6840423776675;5767.625938825792;5793.768249270066;5797.101449275369;5803.446483547268;3838.846446142123;3850.403187505941;3860.3630400983525;3865.4781604483596;3876.6511014008875;3302.884399046022;3913.6869050475893;3918.7060970427333;3925.4092299082467;3936.2717897189114;3635.4001589737395;4206.516250631155;4359.302511598129;4552.203364411214;4560.224761790503;5657.660540367591;5661.631993920382;5689.2407182516445;5693.011169136236;5758.798442054508;1153.1812413186565;1158.8030034894089;1212.7150119721857;1237.826704261404;1242.6023672424737;815.3762371785589;840.2060053249743;866.540733411293;870.6734974384606;875.8686197070363;4567.748773817472;5058.229619633173;5082.618803589746;5094.643374963791;5105.5149760772665;4535.9546404536195;4890.826201711564;5258.605604579595;5488.92620132719;5494.835154945358;4381.868543943687;4401.977990110029;5015.667714512769;5019.47295533971;5044.344235249394;2630.013744845683;2739.671239451279;2841.1760589428836;2851.5865199546365;2853.2296280004284;4480.686351955397;4894.800773810439;5072.949270507322;5398.2982212312145;5716.685582293014;4381.59649552198;4940.382452193476;5423.186522021729;5629.086110722374;5681.471745073485;5331.226972089025;5580.302462192226;5615.831525054255;5621.100623900238;5702.6863522506665;4726.4004797362;5315.946840531546;5407.486288802557;5422.728863556727;5747.425257474386;4518.90962180748;5122.668106292316;5362.471695566901;5389.784408623768;5459.044667183306;5320.515062633981;5589.413111623221;5636.464535869086;5637.57718171147;5640.887182256414;3698.852045918133;3707.870224542236;3711.183539621279;3715.5727091383756;3731.6828069613302;4524.5927866492175;5426.755795989269;5448.346198456211;5482.671039737628;5551.639143455663;5236.89526209466;5342.75957581902;5419.780549376359;5500.944990550203;5506.834794956158;4655.06898620284;5301.1445993901825;5503.156442591777;5655.377908429987;5689.715514224355;5253.23828044939;5493.258410114583;5521.5306698929435;5547.833564993057;5598.580106492111;5039.445660977216;5157.538728999463;5182.8963420732125;5595.496405323541;5628.521575666115;4306.117245964507;5377.569794416359;5475.561955043667;5544.75048622821;5582.49757521825 22:52:35 INFO - PROCESS | 1639 | |5;yui.html;685.1606781692424;704.2781149321945;758.662395234121;763.5991104670047;791.1060501633153;11531.654050378482;14460.493882714107;15073.773893391608;15099.245037748096;15178.54464366068;10983.011528962392;11231.66305010851;13368.799468007963;13369.465221391158;13417.993650476204;808.486611002015;831.4969443486664;834.1991687979547;834.9749507514754;836.4019725895984;824.0482243009304;824.8927639406869;830.90444598871;831.1397703377006;836.9747059851694;870.138562822804;878.8549889268295;880.5685214245005;885.1723638398087;887.3078998381241;706.2760670312919;850.2517784349764;860.9354298427643;865.8138500222451;866.4194989357114;641.5925887061703;650.9153810004716;660.5442244851038;660.9735610575572;661.24287690594;12419.937900310531;12445.25328480294;12609;12686.80969785454;12701.74596508074;11134;11508;11687.824682629767;11739.295642261406;12019.459124339426;10405;10955.50700218492;11096.167787415896;11994.512704282486;12088.697782555506;4340.934885976713;4362.912741745147;4368.825246990116;4371.27873900806;4450.10997800438;82219;84602;86647;87480.56259718724;91373.54313228391;10088.344257623232;10123.240756943258;10240.180785537139;10449.164066874631;10571.788564228673;16884;17497.387591434494;18818.529536761416;18831.81168188328;18902.243910243436;6352.555321127569;6918.688659010357;7332.0834895638045;7433.219511951275;7461.291177338144;3793.336166170909;3889.027743064234;3889.416587511839;3889.844406223776;3892.8056441190884;3269.983650081734;3365.7139143172617;3381.7294616430627;3387.796732196076;3388.559487266689;622.1973653986307;624.2914292278206;633.1452539072308;646.812424396929;648.0764910003227;535.5340853457438;609.4271384898182;611.8256296955435;612.5344737999063;624.0794827629302;8047.637856296481;8641.611127499278;8671.4363566368;8747.125287471203;8885.155910188521;524.3733738182912;585.2274997003999;591.1192323438132;614.671150934248;628.767356078254;4172.269852775752;4278.187144442546;4299.312110062375;4299.9570004300185;4387.363832244307;81770.18229817745;115468;115835.262471063;117070;117260;67566.66216668885;72331;72746.27253727395;97061.0293897052;106639;84712.57643711865;87316;88294;88971;89837.55081224552;9380.81238375215;9765.121139097515;9775.315727898978;9778.55996480146;9800.411975281506;40105.79947100246;49895.50104498908;50265.74867125641;55538.44461555333;57510;73144.90282645871;73244.63377683077;75074;75383.24616753982;82498.3500329978;11981.221220620631;12023.098267629788;12027.338496382783;12156.027517798379;12345.93827030859;13675.726485470035;13698.45206191741;14407.636774897457;14779.408823646934;14830.777538336717;10844.240903136704;10871.67384978452;12747.93626031901;12878.291693956922;13046.608601741962;10331.690049298533;12102.213356131822;12523.56167534132;12772.233665980071;13632.93183534076;12102.818457722966;12925.870741292843;13324.000699947737;13468.865311347154;13490.595282141552;10301.793964120525;10971;11015.724606884893;11144.275622084539;11223.831642525533;9368.297377696836;11276.887231127585;11286.943565282121;11354.943225283821;11546.018588420078 22:52:35 INFO - PROCESS | 1639 | __end_tp_report 22:52:35 INFO - PROCESS | 1639 | __start_cc_report 22:52:35 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,372 22:52:35 INFO - PROCESS | 1639 | __end_cc_report 22:52:35 INFO - PROCESS | 1639 | __startTimestamp1479192755651__endTimestamp 22:52:35 INFO - PROCESS | 1639 | 22:52:35 INFO - PROCESS | 1639 | ------- Summary: start ------- 22:52:35 INFO - PROCESS | 1639 | Number of tests: 6 22:52:35 INFO - PROCESS | 1639 | 22:52:35 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5841.39 Median:6137.44 stddev:701.73 (11.4%) stddev-sans-first:659.24 22:52:35 INFO - PROCESS | 1639 | Values: 2701.6 3379.7 3421.1 3421.2 3452.7 5819.7 5883.4 5886.2 5891.1 5891.6 5932.5 5981.6 6020.4 6034.9 6067.6 5317.4 5404.1 5413.1 5421.5 5426.9 5417.2 5426.3 5442.3 5457.0 5460.6 5251.6 5271.7 5439.0 5445.6 5498.8 4660.3 5342.6 5354.5 5378.9 5463.6 5234.7 5337.4 5341.6 5347.4 5389.2 5901.6 5928.7 5962.1 6043.0 6048.2 5167.3 6186.3 6237.9 6257.9 6260.0 6272.6 6291.6 6333.3 6334.5 6366.7 6039.7 6048.6 6055.0 6068.5 6082.3 6369.5 6379.8 6417.2 6432.2 6505.8 6251.1 6256.1 6269.6 6273.8 6278.5 6296.0 6311.2 6314.7 6339.8 6341.1 6134.6 6143.8 6181.1 6203.7 6265.0 5296.5 5947.7 6258.0 6265.0 6286.5 4408.6 4515.0 4964.7 5028.9 5040.2 3463.4 4446.1 4473.6 4505.8 4523.3 4875.0 6117.8 6125.4 6133.2 6144.3 5892.7 6273.2 6292.7 6323.8 6325.8 5215.3 5937.9 6090.3 6134.4 6143.2 5533.1 5554.7 5555.5 5574.6 5598.6 6501.6 6512.0 6528.4 6529.3 6562.3 6162.7 6230.2 6329.8 6386.7 6393.2 6431.8 6464.0 6490.2 6540.9 6582.8 6199.2 6237.8 6256.1 6282.8 6288.6 6162.3 6168.9 6343.8 6344.5 6361.6 6137.4 6140.6 6142.6 6143.3 6254.5 6220.7 6228.7 6242.3 6256.2 6290.5 5122.3 6091.4 6137.3 6189.1 6193.6 6125.3 6162.7 6194.8 6217.7 6238.2 6166.9 6169.9 6198.7 6209.4 6220.9 22:52:35 INFO - PROCESS | 1639 | 22:52:35 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:69724.20 Median:39651.91 stddev:152921.82 (385.7%) stddev-sans-first:153262.53 22:52:35 INFO - PROCESS | 1639 | Values: 518.4 522.9 529.1 532.6 540.0 29826.9 39399.6 40592.0 40719.4 42637.9 40993.4 41432.0 41458.0 41489.0 41627.4 9328.8 9462.9 9480.2 9527.6 9547.5 9377.0 9391.6 9469.4 9498.6 9517.9 9429.7 9491.5 9501.7 9536.7 9625.6 9418.7 9477.9 9486.5 9491.7 9740.4 7585.5 8054.7 9278.8 9398.6 9400.2 38866.4 38969.8 39516.8 39733.0 39740.4 3207.5 3350.5 3485.0 3678.9 3704.3 30266.5 30320.0 30458.8 30475.2 31245.2 3093.2 3172.3 3175.7 3189.9 3204.9 497483.0 502708.0 503501.5 505298.0 506746.5 59629.0 59766.0 59807.4 59813.0 59824.7 62866.0 62923.4 63173.4 63198.0 63298.4 15979.9 16028.5 16203.4 16213.0 16227.3 12324.7 12393.1 12478.8 12485.6 12487.0 12775.9 16901.4 16965.0 16999.8 17025.7 3177.4 6835.7 6998.8 7059.9 7068.2 879.7 925.6 1003.3 1020.8 1098.3 29817.6 30036.2 30134.8 30309.1 30714.8 983.3 1047.4 1067.1 1073.4 1124.0 13454.6 15124.0 15234.4 15473.4 16219.8 664801.0 667512.0 669190.7 669555.0 674197.0 57621.7 58527.0 58756.8 58919.4 59072.2 1320.4 1356.2 1365.2 1366.6 1372.0 29078.4 29756.7 29768.7 29791.0 30360.5 45253.0 49700.8 50278.5 50455.0 51250.7 419186.9 496287.0 498002.0 602614.0 604824.0 26410.2 26615.9 26627.6 26990.1 26993.5 46868.8 47294.0 47449.8 47465.8 47518.0 34017.8 39354.0 39427.0 39570.8 40480.6 33414.3 36940.8 39037.2 39548.0 40245.6 38286.8 38646.0 38753.6 38906.8 39083.0 32921.0 34281.1 34889.7 35004.5 35100.5 25708.6 25750.5 25804.9 26349.9 26458.6 22:52:35 INFO - PROCESS | 1639 | 22:52:35 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:35134.80 Median:24966.63 stddev:102718.55 (411.4%) stddev-sans-first:102973.53 22:52:35 INFO - PROCESS | 1639 | Values: 369.3 379.0 379.8 387.5 408.3 18297.9 19168.6 19752.2 19968.7 20011.5 19092.5 19334.5 19828.0 19892.6 20226.7 5123.5 5590.9 5781.7 5976.6 5981.3 5763.9 5765.5 5780.3 5904.6 5919.4 5565.2 5732.3 5806.3 5809.2 5834.4 5019.9 5738.1 5740.8 5769.9 5807.5 3890.8 4299.9 4966.6 5014.8 5034.2 24605.9 24673.1 24776.3 24912.8 25020.5 1915.2 2211.7 2370.9 2420.2 2865.7 14400.9 14873.7 15059.4 15197.0 15669.5 1790.5 1791.4 1815.9 1843.3 1857.1 86823.0 117373.0 118456.0 119250.0 124094.0 10339.9 10965.5 11229.0 11413.7 11646.8 14264.6 14409.8 14415.4 14441.4 14446.6 8164.9 8436.8 8490.1 8590.4 8610.3 7210.4 8216.8 8341.8 8371.3 8547.3 4453.2 4478.5 4492.3 4495.8 4562.4 1798.5 1930.2 1935.5 1937.4 1973.9 824.4 906.6 935.1 939.6 1005.7 20273.0 20776.0 20800.1 20918.2 20981.3 759.6 872.7 915.5 947.8 957.7 10550.7 10889.8 10915.3 10927.9 10993.8 539553.0 620664.0 636753.0 637507.0 645816.0 111672.0 112290.4 127488.0 129918.7 140271.3 827.3 932.5 989.5 991.7 1001.2 17813.8 18239.5 18477.3 18560.4 18837.8 42430.6 50057.7 51730.7 52547.2 55320.0 76434.0 78138.8 78200.0 78348.0 79249.6 7701.1 8023.5 8065.4 8138.4 8150.5 11759.2 12324.9 12650.1 12682.2 13035.4 8330.3 10079.4 10562.0 10649.5 11096.1 10701.9 10842.9 10975.0 10994.8 11010.6 11177.6 11331.2 11400.8 11571.8 11621.5 5948.4 7081.4 7358.7 7504.6 7819.4 9160.7 9499.3 10853.4 10974.6 11243.2 22:52:35 INFO - PROCESS | 1639 | 22:52:35 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:5397.08 Median:5044.74 stddev:3014.37 (59.8%) stddev-sans-first:3008.06 22:52:35 INFO - PROCESS | 1639 | Values: 1426.4 1465.0 1593.5 1613.7 1770.5 7551.6 7844.4 7881.8 8524.2 8557.5 8023.4 8297.5 8320.1 8346.0 8421.5 2325.0 2360.3 2361.3 2361.8 2376.1 2339.5 2382.9 2385.0 2386.7 2395.7 2326.1 2332.5 2352.2 2361.3 2363.2 2311.0 2312.7 2333.6 2334.2 2337.4 2347.6 2355.5 2369.6 2369.8 2383.8 8367.8 8398.8 8435.6 8445.5 8469.9 5060.1 5164.1 5211.3 5250.0 5287.1 3411.2 3434.6 3491.3 3512.2 3515.9 783.6 956.6 1056.8 1080.0 1088.6 10976.4 11031.8 11050.9 11149.4 11169.8 4283.8 4426.7 4495.2 4495.8 4497.9 8578.1 8715.2 8730.8 8733.0 8763.3 4366.0 5088.6 5090.7 5130.7 5142.1 4334.9 4476.0 4636.2 4737.4 4763.8 3623.1 3686.9 3718.9 3727.1 3737.0 974.7 991.8 1092.1 1147.4 1162.3 795.4 865.6 889.9 900.1 906.8 5382.4 5552.8 5588.6 5676.7 5759.4 433.2 443.5 459.9 466.4 467.7 2998.4 3782.0 3879.3 3909.1 3920.7 9558.4 10226.6 10232.6 10281.8 10300.0 10074.8 10138.5 10216.7 10238.0 10281.4 7341.9 7372.1 8011.6 8021.0 8056.6 5411.6 5523.0 5555.3 5583.7 5590.4 9388.9 9414.9 9477.4 9493.1 9607.4 9559.9 9582.5 9651.8 9696.0 9774.9 6267.1 6291.1 6686.9 6743.4 6807.4 6906.2 7985.3 8050.7 8058.9 8089.2 6245.7 6709.7 7130.6 7266.2 7356.1 7075.9 7086.3 7135.3 7137.9 7207.5 6667.0 6673.6 6767.9 6769.4 6784.3 4820.0 4864.1 4870.0 4905.7 4917.0 4060.4 4207.5 4737.7 4774.4 5029.4 22:52:35 INFO - PROCESS | 1639 | 22:52:35 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4399.86 Median:5029.46 stddev:1369.14 (27.2%) stddev-sans-first:1338.03 22:52:35 INFO - PROCESS | 1639 | Values: 304.5 307.3 314.0 319.2 319.2 4703.9 4991.7 5262.6 5453.3 5511.1 4152.3 4378.4 5093.4 5334.6 5364.9 3482.6 4049.8 4107.0 4107.6 4108.6 3461.1 3801.6 4096.4 4104.4 4139.1 3054.8 3554.8 4000.7 4021.2 4032.4 3448.3 3540.6 3579.9 3819.0 4056.2 2709.0 3388.1 3488.3 3526.5 3534.3 3938.1 5068.4 5173.9 5249.7 5250.4 4014.9 4590.5 4590.8 4598.9 4620.7 5149.1 5463.9 5588.9 5629.6 5633.9 3183.7 3358.9 3675.7 3840.8 3848.0 5744.7 5767.6 5793.8 5797.1 5803.4 3838.8 3850.4 3860.4 3865.5 3876.7 3302.9 3913.7 3918.7 3925.4 3936.3 3635.4 4206.5 4359.3 4552.2 4560.2 5657.7 5661.6 5689.2 5693.0 5758.8 1153.2 1158.8 1212.7 1237.8 1242.6 815.4 840.2 866.5 870.7 875.9 4567.7 5058.2 5082.6 5094.6 5105.5 4536.0 4890.8 5258.6 5488.9 5494.8 4381.9 4402.0 5015.7 5019.5 5044.3 2630.0 2739.7 2841.2 2851.6 2853.2 4480.7 4894.8 5072.9 5398.3 5716.7 4381.6 4940.4 5423.2 5629.1 5681.5 5331.2 5580.3 5615.8 5621.1 5702.7 4726.4 5315.9 5407.5 5422.7 5747.4 4518.9 5122.7 5362.5 5389.8 5459.0 5320.5 5589.4 5636.5 5637.6 5640.9 3698.9 3707.9 3711.2 3715.6 3731.7 4524.6 5426.8 5448.3 5482.7 5551.6 5236.9 5342.8 5419.8 5500.9 5506.8 4655.1 5301.1 5503.2 5655.4 5689.7 5253.2 5493.3 5521.5 5547.8 5598.6 5039.4 5157.5 5182.9 5595.5 5628.5 4306.1 5377.6 5475.6 5544.8 5582.5 22:52:35 INFO - PROCESS | 1639 | 22:52:35 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:19940.88 Median:4365.87 stddev:29513.11 (676.0%) stddev-sans-first:29560.48 22:52:35 INFO - PROCESS | 1639 | Values: 685.2 704.3 758.7 763.6 791.1 11531.7 14460.5 15073.8 15099.2 15178.5 10983.0 11231.7 13368.8 13369.5 13418.0 808.5 831.5 834.2 835.0 836.4 824.0 824.9 830.9 831.1 837.0 870.1 878.9 880.6 885.2 887.3 706.3 850.3 860.9 865.8 866.4 641.6 650.9 660.5 661.0 661.2 12419.9 12445.3 12609.0 12686.8 12701.7 11134.0 11508.0 11687.8 11739.3 12019.5 10405.0 10955.5 11096.2 11994.5 12088.7 4340.9 4362.9 4368.8 4371.3 4450.1 82219.0 84602.0 86647.0 87480.6 91373.5 10088.3 10123.2 10240.2 10449.2 10571.8 16884.0 17497.4 18818.5 18831.8 18902.2 6352.6 6918.7 7332.1 7433.2 7461.3 3793.3 3889.0 3889.4 3889.8 3892.8 3270.0 3365.7 3381.7 3387.8 3388.6 622.2 624.3 633.1 646.8 648.1 535.5 609.4 611.8 612.5 624.1 8047.6 8641.6 8671.4 8747.1 8885.2 524.4 585.2 591.1 614.7 628.8 4172.3 4278.2 4299.3 4300.0 4387.4 81770.2 115468.0 115835.3 117070.0 117260.0 67566.7 72331.0 72746.3 97061.0 106639.0 84712.6 87316.0 88294.0 88971.0 89837.6 9380.8 9765.1 9775.3 9778.6 9800.4 40105.8 49895.5 50265.7 55538.4 57510.0 73144.9 73244.6 75074.0 75383.2 82498.4 11981.2 12023.1 12027.3 12156.0 12345.9 13675.7 13698.5 14407.6 14779.4 14830.8 10844.2 10871.7 12747.9 12878.3 13046.6 10331.7 12102.2 12523.6 12772.2 13632.9 12102.8 12925.9 13324.0 13468.9 13490.6 10301.8 10971.0 11015.7 11144.3 11223.8 9368.3 11276.9 11286.9 11354.9 11546.0 22:52:35 INFO - PROCESS | 1639 | -------- Summary: end -------- 22:52:35 INFO - PROCESS | 1639 | 22:52:36 INFO - TEST-INFO | 1639: exit 0 22:52:36 INFO - TEST-OK | dromaeo_css | took 1115489ms 22:52:36 INFO - TEST-START | kraken 22:52:36 INFO - operating with platform_type : mac_ 22:52:36 INFO - Initialising browser for kraken test... 22:52:36 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmp55fsgn/profile http://localhost:49249/getInfo.html) 22:52:37 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 22:52:38 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 22:52:38 INFO - PROCESS | 1650 | colorDepth:24 22:52:38 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 22:52:38 INFO - PROCESS | 1650 | __metrics 22:52:38 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3178: TypeError: this.tabs is undefined 22:52:39 INFO - TEST-INFO | 1650: exit 0 22:52:39 INFO - Browser initialized. 22:52:39 INFO - Running cycle 1/1 for kraken test... 22:52:39 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmp55fsgn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:52:51 INFO - PROCESS | 1652 | RSS: Main: 224288768 22:52:51 INFO - PROCESS | 1652 | 22:52:54 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection) 22:52:55 INFO - PROCESS | 1652 | RSS: Main: 236797952 22:52:55 INFO - PROCESS | 1652 | 22:52:58 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-dft) 22:52:58 INFO - PROCESS | 1652 | RSS: Main: 244330496 22:52:58 INFO - PROCESS | 1652 | 22:53:02 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-fft) 22:53:02 INFO - PROCESS | 1652 | RSS: Main: 248008704 22:53:02 INFO - PROCESS | 1652 | 22:53:04 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator) 22:53:05 INFO - PROCESS | 1652 | RSS: Main: 248430592 22:53:05 INFO - PROCESS | 1652 | 22:53:07 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:53:08 INFO - PROCESS | 1652 | RSS: Main: 249634816 22:53:08 INFO - PROCESS | 1652 | 22:53:11 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom) 22:53:11 INFO - PROCESS | 1652 | RSS: Main: 253431808 22:53:11 INFO - PROCESS | 1652 | 22:53:14 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate) 22:53:15 INFO - PROCESS | 1652 | RSS: Main: 252882944 22:53:15 INFO - PROCESS | 1652 | 22:53:18 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial) 22:53:18 INFO - PROCESS | 1652 | RSS: Main: 252997632 22:53:18 INFO - PROCESS | 1652 | 22:53:20 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:53:21 INFO - PROCESS | 1652 | RSS: Main: 251830272 22:53:21 INFO - PROCESS | 1652 | 22:53:22 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:53:23 INFO - PROCESS | 1652 | RSS: Main: 252010496 22:53:23 INFO - PROCESS | 1652 | 22:53:25 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:53:26 INFO - PROCESS | 1652 | RSS: Main: 252538880 22:53:26 INFO - PROCESS | 1652 | 22:53:29 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:53:30 INFO - PROCESS | 1652 | RSS: Main: 252305408 22:53:30 INFO - PROCESS | 1652 | 22:53:32 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:53:33 INFO - PROCESS | 1652 | RSS: Main: 252391424 22:53:33 INFO - PROCESS | 1652 | 22:53:35 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49249/tests/kraken/driver.html?testName=ai-astar) 22:53:35 INFO - PROCESS | 1652 | RSS: Main: 254427136 22:53:35 INFO - PROCESS | 1652 | 22:53:35 INFO - PROCESS | 1652 | __start_tp_report 22:53:35 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 22:53:35 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 22:53:35 INFO - PROCESS | 1652 | |i|pagename|runs| 22:53:35 INFO - PROCESS | 1652 | |0;ai-astar;105;118;117;122;122;164;119;118;117;118 22:53:35 INFO - PROCESS | 1652 | |1;audio-beat-detection;183;101;146;175;148;141;146;118;181;144 22:53:35 INFO - PROCESS | 1652 | |2;audio-dft;205;198;222;259;200;199;194;195;168;234 22:53:35 INFO - PROCESS | 1652 | |3;audio-fft;84;57;60;93;85;85;85;68;77;58 22:53:35 INFO - PROCESS | 1652 | |4;audio-oscillator;82;71;90;76;71;71;88;69;72;89 22:53:35 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;89;87;88;122;81;119;140;116;89;87 22:53:35 INFO - PROCESS | 1652 | |6;imaging-darkroom;154;95;95;95;95;95;95;95;95;93 22:53:35 INFO - PROCESS | 1652 | |7;imaging-desaturate;107;141;98;87;87;90;90;92;87;87 22:53:35 INFO - PROCESS | 1652 | |8;json-parse-financial;56;56;55;58;64;56;60;58;57;60 22:53:35 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;70;76;69;77;76;71;69;70;73;72 22:53:35 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;85;85;84;85;89;87;87;86;86;85 22:53:35 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;155;146;167;163;170;151;175;152;186;165 22:53:35 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;167;170;168;167;170;169;169;170;173;168 22:53:35 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;68;66;66;70;91;89;89;74;68;68 22:53:35 INFO - PROCESS | 1652 | __end_tp_report 22:53:35 INFO - PROCESS | 1652 | __start_cc_report 22:53:35 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,982 22:53:35 INFO - PROCESS | 1652 | __end_cc_report 22:53:35 INFO - PROCESS | 1652 | __startTimestamp1479192815582__endTimestamp 22:53:35 INFO - PROCESS | 1652 | 22:53:35 INFO - PROCESS | 1652 | ------- Summary: start ------- 22:53:35 INFO - PROCESS | 1652 | Number of tests: 14 22:53:35 INFO - PROCESS | 1652 | 22:53:35 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:122.00 Median:118.50 stddev:15.49 (13.1%) stddev-sans-first:15.16 22:53:35 INFO - PROCESS | 1652 | Values: 105.0 118.0 117.0 122.0 122.0 164.0 119.0 118.0 117.0 118.0 22:53:35 INFO - PROCESS | 1652 | 22:53:35 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:148.30 Median:147.00 stddev:26.30 (17.9%) stddev-sans-first:24.71 22:53:35 INFO - PROCESS | 1652 | Values: 183.0 101.0 146.0 175.0 148.0 141.0 146.0 118.0 181.0 144.0 22:53:35 INFO - PROCESS | 1652 | 22:53:35 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:207.40 Median:202.50 stddev:25.14 (12.4%) stddev-sans-first:26.65 22:53:35 INFO - PROCESS | 1652 | Values: 205.0 198.0 222.0 259.0 200.0 199.0 194.0 195.0 168.0 234.0 22:53:35 INFO - PROCESS | 1652 | 22:53:35 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:75.20 Median:84.50 stddev:13.31 (15.8%) stddev-sans-first:13.74 22:53:35 INFO - PROCESS | 1652 | Values: 84.0 57.0 60.0 93.0 85.0 85.0 85.0 68.0 77.0 58.0 22:53:35 INFO - PROCESS | 1652 | 22:53:35 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:77.90 Median:79.00 stddev:8.49 (10.7%) stddev-sans-first:8.88 22:53:35 INFO - PROCESS | 1652 | Values: 82.0 71.0 90.0 76.0 71.0 71.0 88.0 69.0 72.0 89.0 22:53:35 INFO - PROCESS | 1652 | 22:53:35 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:101.80 Median:87.00 stddev:20.42 (23.5%) stddev-sans-first:21.13 22:53:35 INFO - PROCESS | 1652 | Values: 89.0 87.0 88.0 122.0 81.0 119.0 140.0 116.0 89.0 87.0 22:53:35 INFO - PROCESS | 1652 | 22:53:35 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:100.70 Median:95.00 stddev:18.74 (19.7%) stddev-sans-first:0.67 22:53:35 INFO - PROCESS | 1652 | Values: 154.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 93.0 22:53:35 INFO - PROCESS | 1652 | 22:53:35 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:96.60 Median:88.50 stddev:16.86 (19.1%) stddev-sans-first:17.46 22:53:35 INFO - PROCESS | 1652 | Values: 107.0 141.0 98.0 87.0 87.0 90.0 90.0 92.0 87.0 87.0 22:53:35 INFO - PROCESS | 1652 | 22:53:35 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:58.00 Median:58.00 stddev:2.71 (4.7%) stddev-sans-first:2.77 22:53:35 INFO - PROCESS | 1652 | Values: 56.0 56.0 55.0 58.0 64.0 56.0 60.0 58.0 57.0 60.0 22:53:35 INFO - PROCESS | 1652 | 22:53:35 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:72.30 Median:72.50 stddev:3.06 (4.2%) stddev-sans-first:3.13 22:53:35 INFO - PROCESS | 1652 | Values: 70.0 76.0 69.0 77.0 76.0 71.0 69.0 70.0 73.0 72.0 22:53:35 INFO - PROCESS | 1652 | 22:53:35 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:85.90 Median:86.00 stddev:1.45 (1.7%) stddev-sans-first:1.50 22:53:35 INFO - PROCESS | 1652 | Values: 85.0 85.0 84.0 85.0 89.0 87.0 87.0 86.0 86.0 85.0 22:53:35 INFO - PROCESS | 1652 | 22:53:35 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:163.00 Median:166.00 stddev:12.29 (7.4%) stddev-sans-first:12.69 22:53:35 INFO - PROCESS | 1652 | Values: 155.0 146.0 167.0 163.0 170.0 151.0 175.0 152.0 186.0 165.0 22:53:35 INFO - PROCESS | 1652 | 22:53:35 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.10 Median:169.50 stddev:1.79 (1.1%) stddev-sans-first:1.73 22:53:35 INFO - PROCESS | 1652 | Values: 167.0 170.0 168.0 167.0 170.0 169.0 169.0 170.0 173.0 168.0 22:53:35 INFO - PROCESS | 1652 | 22:53:35 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.90 Median:72.00 stddev:10.45 (14.5%) stddev-sans-first:10.78 22:53:35 INFO - PROCESS | 1652 | Values: 68.0 66.0 66.0 70.0 91.0 89.0 89.0 74.0 68.0 68.0 22:53:35 INFO - PROCESS | 1652 | -------- Summary: end -------- 22:53:35 INFO - PROCESS | 1652 | 22:53:36 INFO - TEST-INFO | 1652: exit 0 22:53:36 INFO - TEST-OK | kraken | took 60133ms 22:53:36 INFO - SUITE-END | took 1175s 22:53:36 INFO - Completed test suite (00:19:35) 22:53:36 INFO - javascript benchmark 22:53:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7115.411629587709, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2701.621772951787, 3379.7127244184226, 3421.1447138215444, 3421.1618153552386, 3452.6547345265476, 5819.67991760453, 5883.44107309806, 5886.205362276091, 5891.116332550114, 5891.558133140021, 5932.525397968163, 5981.58128930975, 6020.397960203977, 6034.8793024139495, 6067.605605635642, 5317.388500322464, 5404.054290499166, 5413.133898576229, 5421.484958928915, 5426.864328391802, 5417.187421886729, 5426.294581704393, 5442.292501974738, 5457.0, 5460.563154947595, 5251.5798736101215, 5271.710055946922, 5438.972805135969, 5445.591580631449, 5498.752556134964, 4660.277656963176, 5342.626016178869, 5354.464553544633, 5378.8924221515745, 5463.562914966793, 5234.7120908350025, 5337.439568845254, 5341.572674186094, 5347.358317001985, 5389.218563308337, 5901.557383196277, 5928.703564821785, 5962.07587823888, 6042.969785151046, 6048.243969503812, 5167.302414174113, 6186.319504854462, 6237.906431403487, 6257.937420625781, 6260.029695397221, 6272.623642581414, 6291.559590828644, 6333.271673757485, 6334.5249106317215, 6366.745330186787, 6039.667818269993, 6048.576599638027, 6054.969725151346, 6068.484178844804, 6082.330943596201, 6369.490440764727, 6379.840503987344, 6417.2299324081405, 6432.163818703632, 6505.804825855232, 6251.062340648939, 6256.124142620037, 6269.592476489013, 6273.780417685359, 6278.46633036197, 6295.96852015737, 6311.211098612674, 6314.684265786692, 6339.778107766206, 6341.112244285804, 6134.620279381683, 6143.788236234952, 6181.103739957771, 6203.720832562545, 6264.968675156595, 5296.496832800878, 5947.661776100429, 6257.9687101564205, 6265.028920517327, 6286.49708023357, 4408.625266852289, 4514.954850451519, 4964.726940018334, 5028.924566131511, 5040.243963405518, 3463.428534291864, 4446.1330040642415, 4473.5526447355005, 4505.752183629932, 4523.344115103358, 4875.0, 6117.796956032686, 6125.449118208436, 6133.202683651097, 6144.262688477412, 5892.705364731746, 6273.1844860167885, 6292.748290068346, 6323.810285691437, 6325.810225693236, 5215.348081489814, 5937.910931336033, 6090.33006369299, 6134.38656134383, 6143.1706719592285, 5533.059379905426, 5554.6944918029085, 5555.472230138131, 5574.637648552831, 5598.608097433141, 6501.577397469149, 6512.0, 6528.412442880163, 6529.314421985623, 6562.34376562359, 6162.661053642093, 6230.221222347207, 6329.778457753957, 6386.712597933197, 6393.1688880445245, 6431.807045788634, 6463.967680161569, 6490.188726409199, 6540.901886471612, 6582.835429114119, 6199.225096862892, 6237.781677641261, 6256.124142619946, 6282.811515654537, 6288.622682639057, 6162.322144564005, 6168.938310617016, 6343.809685709436, 6344.492440604833, 6361.554691171574, 6137.438273608381, 6140.565599444434, 6142.621558143092, 6143.327652471189, 6254.530910181664, 6220.72006759706, 6228.657423841732, 6242.282137554058, 6256.186695729526, 6290.465310448663, 5122.282880396822, 6091.42131497507, 6137.263528376445, 6189.071639253968, 6193.566450348432, 6125.264968203665, 6162.691865406801, 6194.752209911553, 6217.720202590985, 6238.15784869055, 6166.907496387468, 6169.907451388322, 6198.659073750987, 6209.441150296495, 6220.937790622036], "value": 5794.131904324933, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [518.4167811212386, 522.8588281164087, 529.1454301303396, 532.6244997276917, 540.0333403208257, 29826.85086574567, 39399.60600393995, 40591.98520036997, 40719.38920916188, 42637.93405164872, 40993.38509922354, 41431.964200894916, 41458.0, 41489.0, 41627.37558936611, 9328.766780830483, 9462.905370946306, 9480.241580673564, 9527.618895244183, 9547.474888881108, 9376.953115234448, 9391.577379017961, 9469.431834089943, 9498.572564234626, 9517.904820951771, 9429.717108486757, 9491.525423728786, 9501.667441639544, 9536.713898583053, 9625.614975400975, 9418.670346537872, 9477.905220947805, 9486.525673716356, 9491.715248542554, 9740.366876153099, 7585.469017168801, 8054.677812887478, 9278.768030799216, 9398.577064032135, 9400.24798016164, 38866.417003744966, 38969.83090507289, 39516.81449556518, 39733.00667483307, 39740.40389394161, 3207.486802111651, 3350.480675495302, 3485.0, 3678.9080272993124, 3704.3147017801793, 30266.54600180999, 30320.0, 30458.84770576133, 30475.2381190472, 31245.218869528217, 3093.180307218589, 3172.349668317989, 3175.6665550117136, 3189.9362012759616, 3204.903852884417, 497483.0, 502708.0, 503501.48249258887, 505298.0, 506746.46626767, 59629.0, 59766.0, 59807.40192598105, 59813.0, 59824.70087649621, 62866.0, 62923.37076629267, 63173.36826631675, 63198.0, 63298.36701632925, 15979.920100399424, 16028.519144425685, 16203.432879849148, 16213.0, 16227.269772860249, 12324.691882703004, 12393.132480726263, 12478.81281780774, 12485.56300529477, 12487.001039916784, 12775.936120319338, 16901.408450704414, 16965.0, 16999.830001699826, 17025.744613830804, 3177.4280629486916, 6835.726570937206, 6998.75504357345, 7059.8820353893625, 7068.151821781316, 879.6701237036111, 925.5973651461636, 1003.2575893838507, 1020.8468729690605, 1098.3300186885992, 29817.552736708967, 30036.249093772392, 30134.794608215863, 30309.090727278217, 30714.84642576773, 983.3264214013424, 1047.418682631147, 1067.1249575348295, 1073.4203530093662, 1123.9775204495863, 13454.596362108956, 15124.0, 15234.390624375123, 15473.381064757508, 16219.756703649455, 664801.0, 667512.0, 669190.6540467266, 669555.0, 674197.0, 57621.71189144111, 58527.0, 58756.824863502494, 58919.41080589139, 59072.227833165074, 1320.4322141479256, 1356.186288227056, 1365.2218235605808, 1366.6241783509615, 1372.0121512511175, 29078.418431630827, 29756.702432975395, 29768.70231297659, 29790.957316494685, 30360.544591830698, 45253.0, 49700.75748106182, 50278.497215028845, 50454.99090018252, 51250.74374628103, 419186.9040654777, 496287.0, 498002.0, 602614.0, 604824.0, 26410.207693769218, 26615.93536258528, 26627.600585990836, 26990.055348062724, 26993.46013079688, 46868.7656561715, 47294.0, 47449.81375465641, 47465.76267118642, 47518.04963900772, 34017.82991085029, 39354.0, 39427.0, 39570.81287561378, 40480.59519404768, 33414.33171336511, 36940.81529592335, 39037.219255615295, 39548.0, 40245.59754402418, 38286.808565958105, 38646.033849153995, 38753.612463876125, 38906.80546597249, 39083.0, 32921.0, 34281.142971425914, 34889.65110348864, 35004.47493287552, 35100.473492897116, 25708.614370784828, 25750.454972701697, 25804.870975645, 26349.868250658623, 26458.603120952816], "value": 19676.0828496922, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.2799041868357, 379.01267198946744, 379.8176875099952, 387.5039948865452, 408.25697231039504, 18297.90212587244, 19168.616627667438, 19752.209911603553, 19968.700469492935, 20011.499712507193, 19092.522686932833, 19334.51663708408, 19828.008599570036, 19892.60214795707, 20226.696599551018, 5123.487651234875, 5590.944090559083, 5781.739821708034, 5976.581639285252, 5981.342052374235, 5763.942360576382, 5765.4522820332, 5780.306363236417, 5904.582900103987, 5919.37846526115, 5565.2486914266665, 5732.31212254528, 5806.303243610772, 5809.215755872967, 5834.387389324123, 5019.874503137415, 5738.139279108125, 5740.7703691852275, 5769.932562475956, 5807.477327040566, 3890.8054597270025, 4299.871003869889, 4966.5778408835295, 5014.774335154927, 5034.244863270539, 24605.87697061503, 24673.136440224687, 24776.25671229866, 24912.750872491226, 25020.499590008098, 1915.1956178405103, 2211.6793065005645, 2370.8696021718797, 2420.201333559921, 2865.6704478984975, 14400.927995360062, 14873.70252594953, 15059.397624095023, 15197.0, 15669.529914102595, 1790.516560528663, 1791.426743442099, 1815.863810214226, 1843.3363988963963, 1857.0807450312047, 86823.0, 117373.0, 118456.0, 119250.0, 124094.0, 10339.948300258451, 10965.519654846654, 11229.0, 11413.714657133472, 11646.825297620542, 14264.572062838131, 14409.78385324221, 14415.351309190903, 14441.35013924376, 14446.638834029234, 8164.918350816416, 8436.831263374697, 8490.108538603481, 8590.441621294596, 8610.268127209132, 7210.387117095001, 8216.808562758486, 8341.8331633367, 8371.288440482627, 8547.273481753997, 4453.19842428366, 4478.462584489817, 4492.303692927601, 4495.820167193341, 4562.361269422284, 1798.5054110119822, 1930.179673638698, 1935.525796179945, 1937.3703546347494, 1973.9013049347475, 824.3940703582862, 906.551257127726, 935.0789472369711, 939.6476321379482, 1005.7284533176002, 20273.0, 20776.0, 20800.063997120167, 20918.16327346889, 20981.265655701976, 759.6277823866266, 872.7469033980075, 915.46445329483, 947.8293907096651, 957.6831856197728, 10550.736231594272, 10889.782204355868, 10915.345079295268, 10927.945360273307, 10993.780124397466, 539553.0, 620664.0, 636753.0, 637507.0, 645816.0, 111672.0, 112290.43854780674, 127488.0, 129918.70081299066, 140271.29864350613, 827.2761333832897, 932.5430539035959, 989.5448093876897, 991.74214704176, 1001.1640280365831, 17813.821861781737, 18239.452816415527, 18477.260909563465, 18560.443186704422, 18837.811621884153, 42430.5756942439, 50057.749711251214, 51730.74134629303, 52547.21179182239, 55320.0, 76434.0, 78138.76103373455, 78200.0, 78348.0, 79249.60375198087, 7701.148276585715, 8023.518588884685, 8065.354771618374, 8138.389620778347, 8150.510969341622, 11759.176857619885, 12324.876751232372, 12650.05124615639, 12682.239065656091, 13035.413406396925, 8330.291925186428, 10079.395236285847, 10561.996610322007, 10649.467526623794, 11096.112311014938, 10701.946490267499, 10842.891571084503, 10975.012248897638, 10994.780104398027, 11010.623672040994, 11177.560941489124, 11331.150163737588, 11400.828987565028, 11571.826422603499, 11621.477033533341, 5948.375420580776, 7081.437024261035, 7358.712225360648, 7504.587247701429, 7819.41354398411, 9160.725178244664, 9499.287553433382, 10853.40306283162, 10974.561017559208, 11243.212975091665], "value": 9416.705663064136, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1426.3937826423771, 1464.9671981253214, 1593.5219434169746, 1613.6772645470908, 1770.5131088950545, 7551.584662843541, 7844.41166912481, 7881.80295492613, 8524.232819046285, 8557.529335886524, 8023.4383593148505, 8297.460665056768, 8320.084790673021, 8346.0, 8421.536815475161, 2324.953500929985, 2360.280114565057, 2361.303415492434, 2361.7520160383083, 2376.0970831084164, 2339.4736184358553, 2382.868942208178, 2385.0459816073535, 2386.713594368678, 2395.7244916834525, 2326.069572171128, 2332.4518738096535, 2352.2120089769946, 2361.2798096580536, 2363.196513185517, 2310.953780924389, 2312.6531020346915, 2333.556624241389, 2334.206369834256, 2337.415646088478, 2347.5891718949288, 2355.5406695694346, 2369.620860662303, 2369.7985671217966, 2383.8331316807657, 8367.83264334716, 8398.83202335956, 8435.620397082146, 8445.493270403735, 8469.872951905665, 5060.114479966028, 5164.070467315882, 5211.322528071364, 5250.028744682246, 5287.07476191665, 3411.181316484051, 3434.6221915589267, 3491.2668339648703, 3512.174638959868, 3515.8945231643092, 783.6121120045557, 956.6077908057732, 1056.8467572201987, 1079.9946000270027, 1088.58633719187, 10976.396298203596, 11031.779364412667, 11050.944745276303, 11149.442527873654, 11169.776604467947, 4283.828646854091, 4426.690131690815, 4495.168393847149, 4495.752733599618, 4497.875531117221, 8578.09929957358, 8715.172058654418, 8730.781730456789, 8733.03936566977, 8763.255123314462, 4365.978170109192, 5088.592912566985, 5090.745462726923, 5130.743462826844, 5142.100132476801, 4334.891627709269, 4475.992901597115, 4636.235021221461, 4737.431508218967, 4763.76181190939, 3623.130448692295, 3686.852525898934, 3718.851245950186, 3727.086863718406, 3737.009692431508, 974.6929717139078, 991.7669347703284, 1092.1044743310408, 1147.437755499799, 1162.2968104296947, 795.3716563914443, 865.5931712095305, 889.8754174415587, 900.1358695652116, 906.8322360363354, 5382.3541175059245, 5552.805651802168, 5588.624834445426, 5676.744546495418, 5759.395263497357, 433.2288526422972, 443.48112708131566, 459.864340019695, 466.36108531312317, 467.72170558517627, 2998.385331007138, 3782.0355809269013, 3879.2823327683905, 3909.0618251619408, 3920.666743326849, 9558.426494410358, 10226.590936362461, 10232.6418575351, 10281.794364112677, 10300.021497957681, 10074.848877266846, 10138.493075346203, 10216.744581385525, 10237.976202379703, 10281.434521101262, 7341.935419364162, 7372.078490188726, 8011.559364235023, 8020.959895200486, 8056.556889371141, 5411.594130440311, 5523.0, 5555.2500412445415, 5583.664980101207, 5590.357108932525, 9388.859167112361, 9414.905850941403, 9477.431354118498, 9493.0981556752, 9607.375520591138, 9559.90440095618, 9582.472963987047, 9651.806963860543, 9695.9819218981, 9774.902250977399, 6267.091271765659, 6291.087792270004, 6686.9331306688255, 6743.393094621508, 6807.3873351398615, 6906.240313565403, 7985.281324680806, 8050.718224862102, 8058.879116813369, 8089.191080891864, 6245.718942647501, 6709.698063587052, 7130.572165670076, 7266.164391095092, 7356.117265928123, 7075.858482830417, 7086.291370863079, 7135.250798666066, 7137.892931606134, 7207.45039816442, 6666.966665166837, 6673.632950187592, 6767.89848152288, 6769.443028103466, 6784.287649796897, 4820.0, 4864.1001414736975, 4869.975650121868, 4905.730184839868, 4916.950830491649, 4060.39094135882, 4207.5161356444405, 4737.692050016738, 4774.419185610792, 5029.371328583927], "value": 4284.98571178989, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.52038040086853, 307.31928777757264, 313.97174254317105, 319.1812999655884, 319.2354311424138, 4703.929441058378, 4991.725455099968, 5262.605304602156, 5453.291072160619, 5511.145772405279, 4152.2941100012995, 4378.387025816388, 5093.439721630627, 5334.5999050071205, 5364.900195459931, 3482.5820901491857, 4049.8084482560557, 4106.9794651026705, 4107.630313271805, 4108.637533418289, 3461.0581006144826, 3801.61983801619, 4096.365063415175, 4104.384342348657, 4139.068709540359, 3054.816710997336, 3554.840032198544, 4000.6999475039343, 4021.1957608478274, 4032.3749818778138, 3448.293099914524, 3540.5837664933974, 3579.8568057277676, 3819.0161838800495, 4056.1684854604737, 2709.0382914065617, 3388.0852169913987, 3488.3197776433603, 3526.488659144435, 3534.293141371736, 3938.1251686268897, 5068.391792984866, 5173.896522069537, 5249.711265880375, 5250.44870288618, 4014.879553613396, 4590.540945905416, 4590.770461476913, 4598.885027874297, 4620.722756634613, 5149.124648809711, 5463.945360546383, 5588.888222235573, 5629.577781666391, 5633.915491267634, 3183.7453003759874, 3358.865645374182, 3675.72432067596, 3840.8463661453507, 3847.99952012476, 5744.6840423776675, 5767.625938825792, 5793.768249270066, 5797.101449275369, 5803.446483547268, 3838.846446142123, 3850.403187505941, 3860.3630400983525, 3865.4781604483596, 3876.6511014008875, 3302.884399046022, 3913.6869050475893, 3918.7060970427333, 3925.4092299082467, 3936.2717897189114, 3635.4001589737395, 4206.516250631155, 4359.302511598129, 4552.203364411214, 4560.224761790503, 5657.660540367591, 5661.631993920382, 5689.2407182516445, 5693.011169136236, 5758.798442054508, 1153.1812413186565, 1158.8030034894089, 1212.7150119721857, 1237.826704261404, 1242.6023672424737, 815.3762371785589, 840.2060053249743, 866.540733411293, 870.6734974384606, 875.8686197070363, 4567.748773817472, 5058.229619633173, 5082.618803589746, 5094.643374963791, 5105.5149760772665, 4535.9546404536195, 4890.826201711564, 5258.605604579595, 5488.92620132719, 5494.835154945358, 4381.868543943687, 4401.977990110029, 5015.667714512769, 5019.47295533971, 5044.344235249394, 2630.013744845683, 2739.671239451279, 2841.1760589428836, 2851.5865199546365, 2853.2296280004284, 4480.686351955397, 4894.800773810439, 5072.949270507322, 5398.2982212312145, 5716.685582293014, 4381.59649552198, 4940.382452193476, 5423.186522021729, 5629.086110722374, 5681.471745073485, 5331.226972089025, 5580.302462192226, 5615.831525054255, 5621.100623900238, 5702.6863522506665, 4726.4004797362, 5315.946840531546, 5407.486288802557, 5422.728863556727, 5747.425257474386, 4518.90962180748, 5122.668106292316, 5362.471695566901, 5389.784408623768, 5459.044667183306, 5320.515062633981, 5589.413111623221, 5636.464535869086, 5637.57718171147, 5640.887182256414, 3698.852045918133, 3707.870224542236, 3711.183539621279, 3715.5727091383756, 3731.6828069613302, 4524.5927866492175, 5426.755795989269, 5448.346198456211, 5482.671039737628, 5551.639143455663, 5236.89526209466, 5342.75957581902, 5419.780549376359, 5500.944990550203, 5506.834794956158, 4655.06898620284, 5301.1445993901825, 5503.156442591777, 5655.377908429987, 5689.715514224355, 5253.23828044939, 5493.258410114583, 5521.5306698929435, 5547.833564993057, 5598.580106492111, 5039.445660977216, 5157.538728999463, 5182.8963420732125, 5595.496405323541, 5628.521575666115, 4306.117245964507, 5377.569794416359, 5475.561955043667, 5544.75048622821, 5582.49757521825], "value": 3966.61305404703, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [685.1606781692424, 704.2781149321945, 758.662395234121, 763.5991104670047, 791.1060501633153, 11531.654050378482, 14460.493882714107, 15073.773893391608, 15099.245037748096, 15178.54464366068, 10983.011528962392, 11231.66305010851, 13368.799468007963, 13369.465221391158, 13417.993650476204, 808.486611002015, 831.4969443486664, 834.1991687979547, 834.9749507514754, 836.4019725895984, 824.0482243009304, 824.8927639406869, 830.90444598871, 831.1397703377006, 836.9747059851694, 870.138562822804, 878.8549889268295, 880.5685214245005, 885.1723638398087, 887.3078998381241, 706.2760670312919, 850.2517784349764, 860.9354298427643, 865.8138500222451, 866.4194989357114, 641.5925887061703, 650.9153810004716, 660.5442244851038, 660.9735610575572, 661.24287690594, 12419.937900310531, 12445.25328480294, 12609.0, 12686.80969785454, 12701.74596508074, 11134.0, 11508.0, 11687.824682629767, 11739.295642261406, 12019.459124339426, 10405.0, 10955.50700218492, 11096.167787415896, 11994.512704282486, 12088.697782555506, 4340.934885976713, 4362.912741745147, 4368.825246990116, 4371.27873900806, 4450.10997800438, 82219.0, 84602.0, 86647.0, 87480.56259718724, 91373.54313228391, 10088.344257623232, 10123.240756943258, 10240.180785537139, 10449.164066874631, 10571.788564228673, 16884.0, 17497.387591434494, 18818.529536761416, 18831.81168188328, 18902.243910243436, 6352.555321127569, 6918.688659010357, 7332.0834895638045, 7433.219511951275, 7461.291177338144, 3793.336166170909, 3889.027743064234, 3889.416587511839, 3889.844406223776, 3892.8056441190884, 3269.983650081734, 3365.7139143172617, 3381.7294616430627, 3387.796732196076, 3388.559487266689, 622.1973653986307, 624.2914292278206, 633.1452539072308, 646.812424396929, 648.0764910003227, 535.5340853457438, 609.4271384898182, 611.8256296955435, 612.5344737999063, 624.0794827629302, 8047.637856296481, 8641.611127499278, 8671.4363566368, 8747.125287471203, 8885.155910188521, 524.3733738182912, 585.2274997003999, 591.1192323438132, 614.671150934248, 628.767356078254, 4172.269852775752, 4278.187144442546, 4299.312110062375, 4299.9570004300185, 4387.363832244307, 81770.18229817745, 115468.0, 115835.262471063, 117070.0, 117260.0, 67566.66216668885, 72331.0, 72746.27253727395, 97061.0293897052, 106639.0, 84712.57643711865, 87316.0, 88294.0, 88971.0, 89837.55081224552, 9380.81238375215, 9765.121139097515, 9775.315727898978, 9778.55996480146, 9800.411975281506, 40105.79947100246, 49895.50104498908, 50265.74867125641, 55538.44461555333, 57510.0, 73144.90282645871, 73244.63377683077, 75074.0, 75383.24616753982, 82498.3500329978, 11981.221220620631, 12023.098267629788, 12027.338496382783, 12156.027517798379, 12345.93827030859, 13675.726485470035, 13698.45206191741, 14407.636774897457, 14779.408823646934, 14830.777538336717, 10844.240903136704, 10871.67384978452, 12747.93626031901, 12878.291693956922, 13046.608601741962, 10331.690049298533, 12102.213356131822, 12523.56167534132, 12772.233665980071, 13632.93183534076, 12102.818457722966, 12925.870741292843, 13324.000699947737, 13468.865311347154, 13490.595282141552, 10301.793964120525, 10971.0, 11015.724606884893, 11144.275622084539, 11223.831642525533, 9368.297377696836, 11276.887231127585, 11286.943565282121, 11354.943225283821, 11546.018588420078], "value": 7111.41652328733, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1553.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 118.0, 117.0, 122.0, 122.0, 164.0, 119.0, 118.0, 117.0, 118.0], "value": 122.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 101.0, 146.0, 175.0, 148.0, 141.0, 146.0, 118.0, 181.0, 144.0], "value": 148.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 198.0, 222.0, 259.0, 200.0, 199.0, 194.0, 195.0, 168.0, 234.0], "value": 207.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 57.0, 60.0, 93.0, 85.0, 85.0, 85.0, 68.0, 77.0, 58.0], "value": 75.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 71.0, 90.0, 76.0, 71.0, 71.0, 88.0, 69.0, 72.0, 89.0], "value": 77.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 88.0, 122.0, 81.0, 119.0, 140.0, 116.0, 89.0, 87.0], "value": 101.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 93.0], "value": 100.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 141.0, 98.0, 87.0, 87.0, 90.0, 90.0, 92.0, 87.0, 87.0], "value": 96.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 55.0, 58.0, 64.0, 56.0, 60.0, 58.0, 57.0, 60.0], "value": 58.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 76.0, 69.0, 77.0, 76.0, 71.0, 69.0, 70.0, 73.0, 72.0], "value": 72.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 84.0, 85.0, 89.0, 87.0, 87.0, 86.0, 86.0, 85.0], "value": 85.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 146.0, 167.0, 163.0, 170.0, 151.0, 175.0, 152.0, 186.0, 165.0], "value": 163.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 170.0, 168.0, 167.0, 170.0, 169.0, 169.0, 170.0, 173.0, 168.0], "value": 169.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 66.0, 70.0, 91.0, 89.0, 89.0, 74.0, 68.0, 68.0], "value": 74.9, "unit": "score"}]}]} 22:53:36 INFO - Return code: 0 22:53:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:53:36 ERROR - # TBPL SUCCESS # 22:53:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:53:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:53:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:53:36 INFO - 2016-11-14 22:53:36.921 screenresolution[1657:10521] starting screenresolution argv=screenresolution get 22:53:36 INFO - 2016-11-14 22:53:36.923 screenresolution[1657:10521] Display 0: 1600x1200x32@60 22:53:36 INFO - 2016-11-14 22:53:36.929 screenresolution[1658:10524] starting screenresolution argv=screenresolution list 22:53:36 INFO - Available Modes on Display 0 22:53:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:53:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:53:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:53:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:53:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:53:37 INFO - Intel Iris: 22:53:37 INFO - Chipset Model: Intel Iris 22:53:37 INFO - Type: GPU 22:53:37 INFO - Bus: Built-In 22:53:37 INFO - VRAM (Dynamic, Max): 1536 MB 22:53:37 INFO - Vendor: Intel (0x8086) 22:53:37 INFO - Device ID: 0x0a2e 22:53:37 INFO - Revision ID: 0x0009 22:53:37 INFO - Displays: 22:53:37 INFO - Display: 22:53:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:53:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:53:37 INFO - Display Serial Number: 1600x1200 60 22:53:37 INFO - Main Display: Yes 22:53:37 INFO - Mirror: Off 22:53:37 INFO - Online: Yes 22:53:37 INFO - Rotation: Supported 22:53:37 INFO - Return code: 0 22:53:37 INFO - Running post-action listener: _resource_record_post_action 22:53:37 INFO - [mozharness: 2016-11-15 06:53:37.165338Z] Finished run-tests step (success) 22:53:37 INFO - Running post-run listener: _resource_record_post_run 22:53:37 INFO - Total resource usage - Wall time: 1208s; CPU: 55.0%; Read bytes: 38585344; Write bytes: 652715008; Read time: 375; Write time: 1977 22:53:37 INFO - TinderboxPrint: CPU usage
54.5% 22:53:37 INFO - TinderboxPrint: I/O read bytes / time
38,585,344 / 375 22:53:37 INFO - TinderboxPrint: I/O write bytes / time
652,715,008 / 1,977 22:53:37 INFO - TinderboxPrint: CPU idle
2,198.3 (45.5%) 22:53:37 INFO - TinderboxPrint: CPU system
979.1 (20.3%) 22:53:37 INFO - TinderboxPrint: CPU user
1,656.2 (34.3%) 22:53:37 INFO - TinderboxPrint: Swap in / out
391,610,368 / 0 22:53:37 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 350593536; Write bytes: 345823232; Read time: 27282; Write time: 609 22:53:37 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 36525056; Write bytes: 295762944; Read time: 351; Write time: 1336 22:53:37 INFO - Running post-run listener: _upload_blobber_files 22:53:37 INFO - Blob upload gear active. 22:53:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:53:37 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:53:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:53:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:53:38 INFO - (blobuploader) - INFO - Open directory for files ... 22:53:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:53:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:53:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:53:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:53:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:53:38 INFO - (blobuploader) - INFO - Done attempting. 22:53:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:53:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:53:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:53:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:53:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:53:39 INFO - (blobuploader) - INFO - Done attempting. 22:53:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:53:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:53:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:53:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:53:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:53:42 INFO - (blobuploader) - INFO - Done attempting. 22:53:42 INFO - (blobuploader) - INFO - Iteration through files over. 22:53:42 INFO - Return code: 0 22:53:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:53:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:53:42 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/727310d261038ac9a2757c236e82e753515a2b31c25db69aefc9c334ae51c06ff3c8218b135bf529caaa115d9156071d534264306013fabf52d5c8c28f5aa2c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/43fc9c2f7548a5fcf1f553c0cdfb9903657c7efa7c2fb446ebc3adfd351e36ea00381a225d3df1658be53aa82d1a233715ac1ec911366217e55948c0ca57f5e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e697d35e3bb282705c21b0b17cf6f92633d2d4ddda97e27d043ddbb8933de07cad4a6f11c77d76b6909f1d75a299a6b3076b9fdb42094eed5a261c2a5bdd9af4"} 22:53:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:53:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:53:42 INFO - Contents: 22:53:42 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/727310d261038ac9a2757c236e82e753515a2b31c25db69aefc9c334ae51c06ff3c8218b135bf529caaa115d9156071d534264306013fabf52d5c8c28f5aa2c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/43fc9c2f7548a5fcf1f553c0cdfb9903657c7efa7c2fb446ebc3adfd351e36ea00381a225d3df1658be53aa82d1a233715ac1ec911366217e55948c0ca57f5e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e697d35e3bb282705c21b0b17cf6f92633d2d4ddda97e27d043ddbb8933de07cad4a6f11c77d76b6909f1d75a299a6b3076b9fdb42094eed5a261c2a5bdd9af4"} 22:53:42 INFO - Running post-run listener: copy_logs_to_upload_dir 22:53:42 INFO - Copying logs to upload dir... 22:53:42 INFO - mkdir: /builds/slave/test/build/upload/logs 22:53:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.474956 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-14 22:53:42.862185) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 22:53:42.865371) ========= 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.wSsWa9u01w/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.B9fNfQ4qfN/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/727310d261038ac9a2757c236e82e753515a2b31c25db69aefc9c334ae51c06ff3c8218b135bf529caaa115d9156071d534264306013fabf52d5c8c28f5aa2c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/43fc9c2f7548a5fcf1f553c0cdfb9903657c7efa7c2fb446ebc3adfd351e36ea00381a225d3df1658be53aa82d1a233715ac1ec911366217e55948c0ca57f5e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e697d35e3bb282705c21b0b17cf6f92633d2d4ddda97e27d043ddbb8933de07cad4a6f11c77d76b6909f1d75a299a6b3076b9fdb42094eed5a261c2a5bdd9af4"} build_url:https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010784 build_url: 'https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/727310d261038ac9a2757c236e82e753515a2b31c25db69aefc9c334ae51c06ff3c8218b135bf529caaa115d9156071d534264306013fabf52d5c8c28f5aa2c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/43fc9c2f7548a5fcf1f553c0cdfb9903657c7efa7c2fb446ebc3adfd351e36ea00381a225d3df1658be53aa82d1a233715ac1ec911366217e55948c0ca57f5e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e697d35e3bb282705c21b0b17cf6f92633d2d4ddda97e27d043ddbb8933de07cad4a6f11c77d76b6909f1d75a299a6b3076b9fdb42094eed5a261c2a5bdd9af4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 22:53:42.897168) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 22:53:42.897490) ========= 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.wSsWa9u01w/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.B9fNfQ4qfN/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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.005079 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 22:53:42.945488) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 22:53:42.945776) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 22:53:42.946106) ========= ========= Total master_lag: 0.17 =========