builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0170 starttime: 1491521293.42 results: success (0) buildid: 20170406150234 builduid: ecf8f37b228948dd8444ef2fb52226c0 revision: 422bd63b18bc5b11482255aaaef1826285309233 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 16:28:13.418616) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 16:28:13.419039) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 16:28:13.419336) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 16:28:13.444287) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 16:28:13.444582) ========= 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.cXbdiUM1IT/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.XNwtwLRvYw/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 --2017-04-06 16:28:13-- 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% 50.9M=0s 2017-04-06 16:28:13 (50.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.084337 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 16:28:13.553113) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 16:28:13.553475) ========= 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.cXbdiUM1IT/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.XNwtwLRvYw/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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.021746 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 16:28:13.603346) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-06 16:28:13.603882) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 422bd63b18bc5b11482255aaaef1826285309233 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 422bd63b18bc5b11482255aaaef1826285309233 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cXbdiUM1IT/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.XNwtwLRvYw/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 2017-04-06 16:28:13,689 truncating revision to first 12 chars 2017-04-06 16:28:13,689 Setting DEBUG logging. 2017-04-06 16:28:13,689 attempt 1/10 2017-04-06 16:28:13,689 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/422bd63b18bc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-06 16:28:14,957 unpacking tar archive at: mozilla-central-422bd63b18bc/testing/mozharness/ program finished with exit code 0 elapsedTime=1.529428 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-06 16:28:15.157765) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 16:28:15.158136) ========= script_repo_revision: 422bd63b18bc5b11482255aaaef1826285309233 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 16:28:15.158607) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 16:28:15.158923) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 16:28:15.184432) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-04-06 16:28:15.184855) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cXbdiUM1IT/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.XNwtwLRvYw/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 16:28:15 INFO - MultiFileLogger online at 20170406 16:28:15 in /builds/slave/test 16:28:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:28:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:28:15 INFO - {'append_to_log': False, 16:28:15 INFO - 'base_work_dir': '/builds/slave/test', 16:28:15 INFO - 'blob_upload_branch': 'Firefox', 16:28:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:28:15 INFO - 'branch': 'Firefox', 16:28:15 INFO - 'buildbot_json_path': 'buildprops.json', 16:28:15 INFO - 'config_files': ('talos/mac_config.py',), 16:28:15 INFO - 'default_actions': ('clobber', 16:28:15 INFO - 'read-buildbot-config', 16:28:15 INFO - 'download-and-extract', 16:28:15 INFO - 'populate-webroot', 16:28:15 INFO - 'create-virtualenv', 16:28:15 INFO - 'install', 16:28:15 INFO - 'run-tests'), 16:28:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:28:15 INFO - 'download_minidump_stackwalk': True, 16:28:15 INFO - 'download_symbols': 'ondemand', 16:28:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:28:15 INFO - 'tooltool.py': '/tools/tooltool.py', 16:28:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:28:15 INFO - '/tools/misc-python/virtualenv.py')}, 16:28:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:28:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:28:15 INFO - 'gecko_profile': False, 16:28:15 INFO - 'gecko_profile_interval': 0, 16:28:15 INFO - 'installer_path': 'installer.exe', 16:28:15 INFO - 'log_level': 'info', 16:28:15 INFO - 'log_name': 'talos', 16:28:15 INFO - 'log_to_console': True, 16:28:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:28:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:28:15 INFO - 'opt_config_files': (), 16:28:15 INFO - 'pip_index': False, 16:28:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:28:15 INFO - 'cmd': ('bash', 16:28:15 INFO - '-c', 16:28:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:28:15 INFO - 'enabled': True, 16:28:15 INFO - 'halt_on_failure': False, 16:28:15 INFO - 'name': 'check_screen_resolution'},), 16:28:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:28:15 INFO - 'cmd': ('bash', 16:28:15 INFO - '-c', 16:28:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:28:15 INFO - 'enabled': True, 16:28:15 INFO - 'halt_on_failure': False, 16:28:15 INFO - 'name': 'check_screen_resolution'},), 16:28:15 INFO - 'run_cmd_checks_enabled': True, 16:28:15 INFO - 'suite': 'dromaeojs', 16:28:15 INFO - 'system_bits': '32', 16:28:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:28:15 INFO - 'title': 't-yosemite-r7-0170', 16:28:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:28:15 INFO - 'use_talos_json': True, 16:28:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:28:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:28:15 INFO - 'work_dir': 'build'} 16:28:15 INFO - [mozharness: 2017-04-06 23:28:15.398054Z] Running clobber step. 16:28:15 INFO - Running pre-action listener: _resource_record_pre_action 16:28:15 INFO - Running main action method: clobber 16:28:15 INFO - rmtree: /builds/slave/test/build 16:28:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:28:18 INFO - Running post-action listener: _resource_record_post_action 16:28:18 INFO - [mozharness: 2017-04-06 23:28:18.881609Z] Finished clobber step (success) 16:28:18 INFO - [mozharness: 2017-04-06 23:28:18.881776Z] Running read-buildbot-config step. 16:28:18 INFO - Running pre-action listener: _resource_record_pre_action 16:28:18 INFO - Running main action method: read_buildbot_config 16:28:18 INFO - Using buildbot properties: 16:28:18 INFO - { 16:28:18 INFO - "project": "", 16:28:18 INFO - "product": "firefox", 16:28:18 INFO - "script_repo_revision": "production", 16:28:18 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 16:28:18 INFO - "repository": "", 16:28:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 16:28:18 INFO - "buildid": "20170406150234", 16:28:18 INFO - "pgo_build": "False", 16:28:18 INFO - "basedir": "/builds/slave/test", 16:28:18 INFO - "buildnumber": 93, 16:28:18 INFO - "slavename": "t-yosemite-r7-0170", 16:28:18 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 16:28:18 INFO - "platform": "yosemite_r7", 16:28:18 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 16:28:18 INFO - "branch": "mozilla-central", 16:28:18 INFO - "repo_path": "mozilla-central", 16:28:18 INFO - "revision": "422bd63b18bc5b11482255aaaef1826285309233", 16:28:18 INFO - "stage_platform": "macosx64", 16:28:18 INFO - "builduid": "ecf8f37b228948dd8444ef2fb52226c0", 16:28:18 INFO - "slavebuilddir": "test" 16:28:18 INFO - } 16:28:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 16:28:18 INFO - Running post-action listener: _resource_record_post_action 16:28:18 INFO - [mozharness: 2017-04-06 23:28:18.885130Z] Finished read-buildbot-config step (success) 16:28:18 INFO - [mozharness: 2017-04-06 23:28:18.885265Z] Running download-and-extract step. 16:28:18 INFO - Running pre-action listener: _resource_record_pre_action 16:28:18 INFO - Running main action method: download_and_extract 16:28:18 INFO - mkdir: /builds/slave/test/build/tests 16:28:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:28:18 INFO - trying https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 16:28:18 INFO - Downloading https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 16:28:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 16:28:19 INFO - Downloaded 1325 bytes. 16:28:19 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 16:28:19 INFO - Using the following test package requirements: 16:28:19 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:28:19 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 16:28:19 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:28:19 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:28:19 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 16:28:19 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:28:19 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 16:28:19 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:28:19 INFO - u'jsshell-mac.zip'], 16:28:19 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:28:19 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 16:28:19 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:28:19 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:28:19 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 16:28:19 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:28:19 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 16:28:19 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:28:19 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 16:28:19 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:28:19 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 16:28:19 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:28:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:28:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:28:19 INFO - Fetch https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:28:21 INFO - Expected file size: 52518495 16:28:21 INFO - Obtained file size: 52518495 16:28:37 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:28:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:28:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:28:37 INFO - Fetch https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:28:38 INFO - Expected file size: 52518495 16:28:38 INFO - Obtained file size: 52518495 16:28:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 16:28:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:28:54 INFO - Fetch https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 16:28:55 INFO - Expected file size: 14404138 16:28:55 INFO - Obtained file size: 14404138 16:28:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:28:55 INFO - trying https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:28:55 INFO - Downloading https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 16:28:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 16:28:58 INFO - Downloaded 70891149 bytes. 16:28:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:28:58 INFO - mkdir: /builds/slave/test/properties 16:28:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:28:58 INFO - Writing to file /builds/slave/test/properties/build_url 16:28:58 INFO - Contents: 16:28:58 INFO - build_url:https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:28:58 INFO - Running post-action listener: _resource_record_post_action 16:28:58 INFO - Running post-action listener: set_extra_try_arguments 16:28:58 INFO - [mozharness: 2017-04-06 23:28:58.891890Z] Finished download-and-extract step (success) 16:28:58 INFO - [mozharness: 2017-04-06 23:28:58.892013Z] Running populate-webroot step. 16:28:58 INFO - Running pre-action listener: _resource_record_pre_action 16:28:58 INFO - Running main action method: populate_webroot 16:28:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:28:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:28:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:28:58 INFO - '--tppagecycles', 16:28:58 INFO - '7'], 16:28:58 INFO - 'tests': ['tsvgm']}}, 16:28:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:28:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:28:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:28:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:28:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:28:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:28:58 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:28:58 INFO - 'talos_options': ['--disable-e10s'], 16:28:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:28:58 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:28:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:28:58 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:28:58 INFO - 'talos_options': ['--disable-e10s'], 16:28:58 INFO - 'tests': ['damp', 'tps']}, 16:28:58 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:28:58 INFO - 'tests': ['damp', 'tps']}, 16:28:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:28:58 INFO - 'tests': ['dromaeo_dom']}, 16:28:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:28:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:28:58 INFO - 'tests': ['basic_compositor_video']}, 16:28:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:28:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:28:58 INFO - 'tests': ['a11yr', 16:28:58 INFO - 'ts_paint', 16:28:58 INFO - 'tpaint', 16:28:58 INFO - 'sessionrestore', 16:28:58 INFO - 'sessionrestore_no_auto_restore', 16:28:58 INFO - 'tabpaint']}, 16:28:58 INFO - 'other-e10s': {'tests': ['a11yr', 16:28:58 INFO - 'ts_paint', 16:28:58 INFO - 'tpaint', 16:28:58 INFO - 'sessionrestore', 16:28:58 INFO - 'sessionrestore_no_auto_restore', 16:28:58 INFO - 'tabpaint']}, 16:28:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:28:58 INFO - 'ts_paint', 16:28:58 INFO - 'tpaint', 16:28:58 INFO - 'sessionrestore', 16:28:58 INFO - 'sessionrestore_no_auto_restore', 16:28:58 INFO - 'tabpaint']}, 16:28:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:28:58 INFO - 'ts_paint', 16:28:58 INFO - 'tpaint', 16:28:58 INFO - 'sessionrestore', 16:28:58 INFO - 'sessionrestore_no_auto_restore', 16:28:58 INFO - 'tabpaint']}, 16:28:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:28:58 INFO - 'tests': ['a11yr', 16:28:58 INFO - 'ts_paint', 16:28:58 INFO - 'tpaint', 16:28:58 INFO - 'sessionrestore', 16:28:58 INFO - 'sessionrestore_no_auto_restore', 16:28:58 INFO - 'tabpaint']}, 16:28:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:28:58 INFO - 'tests': ['a11yr', 16:28:58 INFO - 'ts_paint', 16:28:58 INFO - 'tpaint', 16:28:58 INFO - 'sessionrestore', 16:28:58 INFO - 'sessionrestore_no_auto_restore', 16:28:58 INFO - 'tabpaint']}, 16:28:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:28:58 INFO - 'tests': ['tsvgx', 16:28:58 INFO - 'tsvgr_opacity', 16:28:58 INFO - 'tart', 16:28:58 INFO - 'tscrollx', 16:28:58 INFO - 'cart', 16:28:58 INFO - 'tsvg_static']}, 16:28:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:28:58 INFO - 'tsvgr_opacity', 16:28:58 INFO - 'tart', 16:28:58 INFO - 'tscrollx', 16:28:58 INFO - 'cart', 16:28:58 INFO - 'tsvg_static']}, 16:28:58 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:28:58 INFO - 'talos_options': ['--disable-e10s'], 16:28:58 INFO - 'tests': ['tp5o']}, 16:28:58 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:28:58 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:28:58 INFO - 'talos_options': ['--disable-e10s', 16:28:58 INFO - '--xperf_path', 16:28:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:28:58 INFO - 'tests': ['tp5n']}, 16:28:58 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:28:58 INFO - 'talos_options': ['--xperf_path', 16:28:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:28:58 INFO - 'tests': ['tp5n']}}, 16:28:58 INFO - 'talos.zip': {'path': '', 16:28:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:28:58 INFO - Running post-action listener: _resource_record_post_action 16:28:58 INFO - [mozharness: 2017-04-06 23:28:58.910819Z] Finished populate-webroot step (success) 16:28:58 INFO - [mozharness: 2017-04-06 23:28:58.910948Z] Running create-virtualenv step. 16:28:58 INFO - Running pre-action listener: _resource_record_pre_action 16:28:58 INFO - Running main action method: create_virtualenv 16:28:58 INFO - Creating virtualenv /builds/slave/test/build/venv 16:28:58 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:28:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:28:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:28:59 INFO - Using real prefix '/tools/python27' 16:28:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:28:59 INFO - Installing distribute.............................................................................................................................................................................................done. 16:29:02 INFO - Installing pip.................done. 16:29:02 INFO - Return code: 0 16:29:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:29:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:29:02 INFO - Reading from file tmpfile_stdout 16:29:02 INFO - Output received: 16:29:02 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) 16:29:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:29:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103fedb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ff0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa81adab150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dcbd30>, '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 0x103cf2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103dc0be8>, '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.cXbdiUM1IT/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.XNwtwLRvYw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:29:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:29:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:29:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cXbdiUM1IT/Render', 16:29:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:02 INFO - 'HOME': '/Users/cltbld', 16:29:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:02 INFO - 'LOGNAME': 'cltbld', 16:29:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:02 INFO - 'MOZ_NO_REMOTE': '1', 16:29:02 INFO - 'NO_EM_RESTART': '1', 16:29:02 INFO - 'PAGER': '/bin/cat', 16:29:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:02 INFO - 'PWD': '/builds/slave/test', 16:29:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:02 INFO - 'SHELL': '/bin/bash', 16:29:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XNwtwLRvYw/Listeners', 16:29:02 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 16:29:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:02 INFO - 'USER': 'cltbld', 16:29:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:02 INFO - 'XPC_FLAGS': '0x0', 16:29:02 INFO - 'XPC_SERVICE_NAME': '0', 16:29:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:03 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:29:03 INFO - Cleaning up... 16:29:03 INFO - Return code: 0 16:29:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:29:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:03 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 0x103fedb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ff0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa81adab150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dcbd30>, '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 0x103cf2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103dc0be8>, '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.cXbdiUM1IT/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.XNwtwLRvYw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:29:03 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 16:29:03 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 16:29:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cXbdiUM1IT/Render', 16:29:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:03 INFO - 'HOME': '/Users/cltbld', 16:29:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:03 INFO - 'LOGNAME': 'cltbld', 16:29:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:03 INFO - 'MOZ_NO_REMOTE': '1', 16:29:03 INFO - 'NO_EM_RESTART': '1', 16:29:03 INFO - 'PAGER': '/bin/cat', 16:29:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:03 INFO - 'PWD': '/builds/slave/test', 16:29:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:03 INFO - 'SHELL': '/bin/bash', 16:29:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XNwtwLRvYw/Listeners', 16:29:03 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 16:29:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:03 INFO - 'USER': 'cltbld', 16:29:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:03 INFO - 'XPC_FLAGS': '0x0', 16:29:03 INFO - 'XPC_SERVICE_NAME': '0', 16:29:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:03 INFO - Downloading/unpacking psutil>=3.1.1 16:29:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:29:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:29:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:29:06 INFO - Installing collected packages: psutil 16:29:06 INFO - Running setup.py install for psutil 16:29:06 INFO - building 'psutil._psutil_osx' extension 16:29:06 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 16:29:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 16:29:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 16:29:07 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 16:29:07 INFO - building 'psutil._psutil_posix' extension 16:29:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 16:29:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:29:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:29:07 INFO - ^ 16:29:07 INFO - 1 warning generated. 16:29:07 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 16:29:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:29:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:29:07 INFO - Successfully installed psutil 16:29:07 INFO - Cleaning up... 16:29:07 INFO - Return code: 0 16:29:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:29: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')]} 16:29:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:08 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')]} 16:29:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:08 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 0x103fedb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ff0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa81adab150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dcbd30>, '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 0x103cf2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103dc0be8>, '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.cXbdiUM1IT/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.XNwtwLRvYw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:29:08 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 16:29:08 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 16:29:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cXbdiUM1IT/Render', 16:29:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:08 INFO - 'HOME': '/Users/cltbld', 16:29:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:08 INFO - 'LOGNAME': 'cltbld', 16:29:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:08 INFO - 'MOZ_NO_REMOTE': '1', 16:29:08 INFO - 'NO_EM_RESTART': '1', 16:29:08 INFO - 'PAGER': '/bin/cat', 16:29:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:08 INFO - 'PWD': '/builds/slave/test', 16:29:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:08 INFO - 'SHELL': '/bin/bash', 16:29:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XNwtwLRvYw/Listeners', 16:29:08 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 16:29:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:08 INFO - 'USER': 'cltbld', 16:29:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:08 INFO - 'XPC_FLAGS': '0x0', 16:29:08 INFO - 'XPC_SERVICE_NAME': '0', 16:29:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:29:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:29:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:29:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:29:11 INFO - Installing collected packages: mozsystemmonitor 16:29:11 INFO - Running setup.py install for mozsystemmonitor 16:29:11 INFO - Successfully installed mozsystemmonitor 16:29:11 INFO - Cleaning up... 16:29:11 INFO - Return code: 0 16:29:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:29:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103fedb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ff0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa81adab150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dcbd30>, '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 0x103cf2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103dc0be8>, '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.cXbdiUM1IT/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.XNwtwLRvYw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:29:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:29:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:29:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cXbdiUM1IT/Render', 16:29:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:11 INFO - 'HOME': '/Users/cltbld', 16:29:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:11 INFO - 'LOGNAME': 'cltbld', 16:29:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:11 INFO - 'MOZ_NO_REMOTE': '1', 16:29:11 INFO - 'NO_EM_RESTART': '1', 16:29:11 INFO - 'PAGER': '/bin/cat', 16:29:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:11 INFO - 'PWD': '/builds/slave/test', 16:29:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:11 INFO - 'SHELL': '/bin/bash', 16:29:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XNwtwLRvYw/Listeners', 16:29:11 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 16:29:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:11 INFO - 'USER': 'cltbld', 16:29:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:11 INFO - 'XPC_FLAGS': '0x0', 16:29:11 INFO - 'XPC_SERVICE_NAME': '0', 16:29:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:12 INFO - Downloading/unpacking jsonschema==2.5.1 16:29:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:29:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:29: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 16:29: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 16:29: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 16:29: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 16:29: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 16:29: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 16:29:15 INFO - Downloading functools32-3.2.3-2.tar.gz 16:29:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:29:15 INFO - warning: no files found matching '*.txt' 16:29:15 INFO - no previously-included directories found matching 'build' 16:29:15 INFO - no previously-included directories found matching 'dist' 16:29:15 INFO - no previously-included directories found matching '.git*' 16:29:15 INFO - Installing collected packages: jsonschema, functools32 16:29:15 INFO - Running setup.py install for functools32 16:29:15 INFO - warning: no files found matching '*.txt' 16:29:15 INFO - no previously-included directories found matching 'build' 16:29:15 INFO - no previously-included directories found matching 'dist' 16:29:15 INFO - no previously-included directories found matching '.git*' 16:29:15 INFO - Successfully installed jsonschema functools32 16:29:15 INFO - Cleaning up... 16:29:15 INFO - Return code: 0 16:29:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:29: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')]} 16:29:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29: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')]} 16:29:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103fedb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ff0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa81adab150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dcbd30>, '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 0x103cf2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103dc0be8>, '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.cXbdiUM1IT/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.XNwtwLRvYw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:29:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:29:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:29:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cXbdiUM1IT/Render', 16:29:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:15 INFO - 'HOME': '/Users/cltbld', 16:29:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:15 INFO - 'LOGNAME': 'cltbld', 16:29:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:15 INFO - 'MOZ_NO_REMOTE': '1', 16:29:15 INFO - 'NO_EM_RESTART': '1', 16:29:15 INFO - 'PAGER': '/bin/cat', 16:29:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:15 INFO - 'PWD': '/builds/slave/test', 16:29:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:15 INFO - 'SHELL': '/bin/bash', 16:29:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XNwtwLRvYw/Listeners', 16:29:15 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 16:29:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:15 INFO - 'USER': 'cltbld', 16:29:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:15 INFO - 'XPC_FLAGS': '0x0', 16:29:15 INFO - 'XPC_SERVICE_NAME': '0', 16:29:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:29:15 INFO - Cleaning up... 16:29:15 INFO - Return code: 0 16:29:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:29: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')]} 16:29:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29: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')]} 16:29:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103fedb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ff0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa81adab150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dcbd30>, '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 0x103cf2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103dc0be8>, '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.cXbdiUM1IT/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.XNwtwLRvYw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:29:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:29:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:29:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cXbdiUM1IT/Render', 16:29:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:15 INFO - 'HOME': '/Users/cltbld', 16:29:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:15 INFO - 'LOGNAME': 'cltbld', 16:29:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:15 INFO - 'MOZ_NO_REMOTE': '1', 16:29:15 INFO - 'NO_EM_RESTART': '1', 16:29:15 INFO - 'PAGER': '/bin/cat', 16:29:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:15 INFO - 'PWD': '/builds/slave/test', 16:29:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:15 INFO - 'SHELL': '/bin/bash', 16:29:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XNwtwLRvYw/Listeners', 16:29:15 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 16:29:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:15 INFO - 'USER': 'cltbld', 16:29:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:15 INFO - 'XPC_FLAGS': '0x0', 16:29:15 INFO - 'XPC_SERVICE_NAME': '0', 16:29:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:16 INFO - Downloading/unpacking blobuploader==1.2.4 16:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:18 INFO - Downloading blobuploader-1.2.4.tar.gz 16:29:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:29:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:29:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:19 INFO - Downloading docopt-0.6.1.tar.gz 16:29:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:29:20 INFO - Installing collected packages: blobuploader, requests, docopt 16:29:20 INFO - Running setup.py install for blobuploader 16:29:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:29:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:29:20 INFO - Running setup.py install for requests 16:29:20 INFO - Running setup.py install for docopt 16:29:20 INFO - Successfully installed blobuploader requests docopt 16:29:20 INFO - Cleaning up... 16:29:20 INFO - Return code: 0 16:29:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:29: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')]} 16:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29: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')]} 16:29:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:20 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 0x103fedb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ff0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa81adab150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dcbd30>, '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 0x103cf2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103dc0be8>, '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.cXbdiUM1IT/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.XNwtwLRvYw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:29:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:29:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:29:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cXbdiUM1IT/Render', 16:29:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:20 INFO - 'HOME': '/Users/cltbld', 16:29:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:20 INFO - 'LOGNAME': 'cltbld', 16:29:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:20 INFO - 'MOZ_NO_REMOTE': '1', 16:29:20 INFO - 'NO_EM_RESTART': '1', 16:29:20 INFO - 'PAGER': '/bin/cat', 16:29:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:20 INFO - 'PWD': '/builds/slave/test', 16:29:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:20 INFO - 'SHELL': '/bin/bash', 16:29:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XNwtwLRvYw/Listeners', 16:29:20 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 16:29:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:20 INFO - 'USER': 'cltbld', 16:29:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:20 INFO - 'XPC_FLAGS': '0x0', 16:29:20 INFO - 'XPC_SERVICE_NAME': '0', 16:29:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:29:21 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-oXWr9c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:29:21 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-FUco0M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:29:21 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-WF_XMf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:29:21 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-Lmyvch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:29:21 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-iDFg_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:29:21 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-b8rGyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:29:21 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-Jl_k3E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:29:21 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-3NxlY2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:29:21 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-WirccN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:29:22 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-vtKp8M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:29:22 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-AgOcdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:29:22 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-4V7_r_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:29:22 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-ERWTsZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:29:22 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-0CvMz_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:22 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-7h8QdP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:29:22 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-w8z9py-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:29:22 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-_fAulf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:29:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:29:22 INFO - Running setup.py install for manifestparser 16:29:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:29:23 INFO - Running setup.py install for mozcrash 16:29:23 INFO - Running setup.py install for mozdebug 16:29:23 INFO - Running setup.py install for mozdevice 16:29:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:29:23 INFO - Running setup.py install for mozfile 16:29:23 INFO - Running setup.py install for mozhttpd 16:29:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:29:23 INFO - Running setup.py install for mozinfo 16:29:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:29:23 INFO - Running setup.py install for mozInstall 16:29:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:29:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:29:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:29:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:29:24 INFO - Running setup.py install for mozleak 16:29:24 INFO - Running setup.py install for mozlog 16:29:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:29:24 INFO - Running setup.py install for moznetwork 16:29:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:29:24 INFO - Running setup.py install for mozprocess 16:29:24 INFO - Running setup.py install for mozprofile 16:29:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:29:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:29:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:29:24 INFO - Running setup.py install for mozrunner 16:29:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:29:24 INFO - Running setup.py install for mozscreenshot 16:29:25 INFO - Running setup.py install for moztest 16:29:25 INFO - Running setup.py install for mozversion 16:29:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:29:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:29:25 INFO - Cleaning up... 16:29:25 INFO - Return code: 0 16:29:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:29: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')]} 16:29:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29: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')]} 16:29:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:25 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 0x103fedb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ff0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa81adab150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dcbd30>, '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 0x103cf2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103dc0be8>, '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.cXbdiUM1IT/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.XNwtwLRvYw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:29:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:29:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:29:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cXbdiUM1IT/Render', 16:29:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:25 INFO - 'HOME': '/Users/cltbld', 16:29:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:25 INFO - 'LOGNAME': 'cltbld', 16:29:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:25 INFO - 'MOZ_NO_REMOTE': '1', 16:29:25 INFO - 'NO_EM_RESTART': '1', 16:29:25 INFO - 'PAGER': '/bin/cat', 16:29:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:25 INFO - 'PWD': '/builds/slave/test', 16:29:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:25 INFO - 'SHELL': '/bin/bash', 16:29:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XNwtwLRvYw/Listeners', 16:29:25 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 16:29:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:25 INFO - 'USER': 'cltbld', 16:29:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:25 INFO - 'XPC_FLAGS': '0x0', 16:29:25 INFO - 'XPC_SERVICE_NAME': '0', 16:29:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:29:25 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-dkJqGX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:29:25 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:29:25 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-g_xn3U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:29:25 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)) 16:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:29:25 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-PZQdEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:29:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:29:26 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-JXIjsP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:29:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:29:26 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-XEJdxV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:29:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:29:26 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-7yEiL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:29:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:29:26 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-nEB5AR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:29:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:29:26 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-6b11oA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:29:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:29:26 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-yksuzl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:29:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:29:26 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-hXbERs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:29:26 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)) 16:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:29:26 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-OUNzXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:29:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:29:26 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-hmtCQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:29:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:29:26 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-qvRlrp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:29:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:29:27 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-2D26vm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:29:27 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)) 16:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:27 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-m7Ksh5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:29:27 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-R8OvUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:29:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:29:27 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-UQY2XQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:29:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:29:27 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)) 16:29:27 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)) 16:29:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:29:27 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:29:27 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:29:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:29:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:30 INFO - Downloading blessings-1.6.tar.gz 16:29:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:29:30 INFO - Installing collected packages: blessings 16:29:30 INFO - Running setup.py install for blessings 16:29:30 INFO - Successfully installed blessings 16:29:30 INFO - Cleaning up... 16:29:30 INFO - Return code: 0 16:29:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:29:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:29:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:29:30 INFO - Reading from file tmpfile_stdout 16:29:30 INFO - Current package versions: 16:29:30 INFO - blessings == 1.6 16:29:30 INFO - blobuploader == 1.2.4 16:29:30 INFO - docopt == 0.6.1 16:29:30 INFO - functools32 == 3.2.3-2 16:29:30 INFO - jsonschema == 2.5.1 16:29:30 INFO - manifestparser == 1.1 16:29:30 INFO - mozInstall == 1.12 16:29:30 INFO - mozcrash == 1.0 16:29:30 INFO - mozdebug == 0.1 16:29:30 INFO - mozdevice == 0.50 16:29:30 INFO - mozfile == 1.2 16:29:30 INFO - mozhttpd == 0.7 16:29:30 INFO - mozinfo == 0.9 16:29:30 INFO - mozleak == 0.1 16:29:30 INFO - mozlog == 3.4 16:29:30 INFO - moznetwork == 0.27 16:29:30 INFO - mozprocess == 0.25 16:29:30 INFO - mozprofile == 0.28 16:29:30 INFO - mozrunner == 6.13 16:29:30 INFO - mozscreenshot == 0.1 16:29:30 INFO - mozsystemmonitor == 0.3 16:29:30 INFO - moztest == 0.8 16:29:30 INFO - mozversion == 1.4 16:29:30 INFO - psutil == 3.1.1 16:29:30 INFO - requests == 1.2.3 16:29:30 INFO - wsgiref == 0.1.2 16:29:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:29:30 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')]} 16:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:30 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')]} 16:29:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:30 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 0x103fedb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ff0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa81adab150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dcbd30>, '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 0x103cf2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103dc0be8>, '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.cXbdiUM1IT/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.XNwtwLRvYw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:29:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:29:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:29:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cXbdiUM1IT/Render', 16:29:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:30 INFO - 'HOME': '/Users/cltbld', 16:29:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:30 INFO - 'LOGNAME': 'cltbld', 16:29:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:30 INFO - 'MOZ_NO_REMOTE': '1', 16:29:30 INFO - 'NO_EM_RESTART': '1', 16:29:30 INFO - 'PAGER': '/bin/cat', 16:29:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:30 INFO - 'PWD': '/builds/slave/test', 16:29:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:30 INFO - 'SHELL': '/bin/bash', 16:29:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XNwtwLRvYw/Listeners', 16:29:30 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 16:29:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:30 INFO - 'USER': 'cltbld', 16:29:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:30 INFO - 'XPC_FLAGS': '0x0', 16:29:30 INFO - 'XPC_SERVICE_NAME': '0', 16:29:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:29:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:29:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:29:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:29:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:29:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:29:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:29:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:29:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:29:30 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:29:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:29:30 INFO - Cleaning up... 16:29:30 INFO - Return code: 0 16:29:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:29:30 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')]} 16:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103fedb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ff0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa81adab150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dcbd30>, '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 0x103cf2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103dc0be8>, '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.cXbdiUM1IT/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.XNwtwLRvYw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:29:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:29:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:29:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cXbdiUM1IT/Render', 16:29:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:31 INFO - 'HOME': '/Users/cltbld', 16:29:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:31 INFO - 'LOGNAME': 'cltbld', 16:29:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:31 INFO - 'MOZ_NO_REMOTE': '1', 16:29:31 INFO - 'NO_EM_RESTART': '1', 16:29:31 INFO - 'PAGER': '/bin/cat', 16:29:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:31 INFO - 'PWD': '/builds/slave/test', 16:29:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:31 INFO - 'SHELL': '/bin/bash', 16:29:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XNwtwLRvYw/Listeners', 16:29:31 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 16:29:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:31 INFO - 'USER': 'cltbld', 16:29:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:31 INFO - 'XPC_FLAGS': '0x0', 16:29:31 INFO - 'XPC_SERVICE_NAME': '0', 16:29:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:29:31 INFO - Cleaning up... 16:29:31 INFO - Return code: 0 16:29:31 INFO - Running post-action listener: _resource_record_post_action 16:29:31 INFO - Running post-action listener: _start_resource_monitoring 16:29:31 INFO - Starting resource monitoring. 16:29:31 INFO - [mozharness: 2017-04-06 23:29:31.290420Z] Finished create-virtualenv step (success) 16:29:31 INFO - [mozharness: 2017-04-06 23:29:31.291440Z] Running install step. 16:29:31 INFO - Running pre-action listener: _resource_record_pre_action 16:29:31 INFO - Running main action method: install 16:29:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:29:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:29:31 INFO - Reading from file tmpfile_stdout 16:29:31 INFO - Detecting whether we're running mozinstall >=1.0... 16:29:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:29:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:29:31 INFO - Reading from file tmpfile_stdout 16:29:31 INFO - Output received: 16:29:31 INFO - Usage: mozinstall [options] installer 16:29:31 INFO - Options: 16:29:31 INFO - -h, --help show this help message and exit 16:29:31 INFO - -d DEST, --destination=DEST 16:29:31 INFO - Directory to install application into. [default: 16:29:31 INFO - "/builds/slave/test"] 16:29:31 INFO - --app=APP Application being installed. [default: firefox] 16:29:31 INFO - mkdir: /builds/slave/test/build/application 16:29:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:29:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:29:51 INFO - Reading from file tmpfile_stdout 16:29:51 INFO - Output received: 16:29:51 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:29:51 INFO - Running post-action listener: _resource_record_post_action 16:29:51 INFO - [mozharness: 2017-04-06 23:29:51.152749Z] Finished install step (success) 16:29:51 INFO - [mozharness: 2017-04-06 23:29:51.152877Z] Running run-tests step. 16:29:51 INFO - Running pre-action listener: _resource_record_pre_action 16:29:51 INFO - Running main action method: run_tests 16:29:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:29:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:29:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:29:51 INFO - 2017-04-06 16:29:51.167 screenresolution[1964:10746] starting screenresolution argv=screenresolution get 16:29:51 INFO - 2017-04-06 16:29:51.170 screenresolution[1964:10746] Display 0: 1600x1200x32@60 16:29:51 INFO - 2017-04-06 16:29:51.176 screenresolution[1965:10749] starting screenresolution argv=screenresolution list 16:29:51 INFO - Available Modes on Display 0 16:29:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:29:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:29:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:29:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:29:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:29:51 INFO - Intel Iris: 16:29:51 INFO - Chipset Model: Intel Iris 16:29:51 INFO - Type: GPU 16:29:51 INFO - Bus: Built-In 16:29:51 INFO - VRAM (Dynamic, Max): 1536 MB 16:29:51 INFO - Vendor: Intel (0x8086) 16:29:51 INFO - Device ID: 0x0a2e 16:29:51 INFO - Revision ID: 0x0009 16:29:51 INFO - Displays: 16:29:51 INFO - Display: 16:29:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:29:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:29:51 INFO - Display Serial Number: 1600x1200 60 16:29:51 INFO - Main Display: Yes 16:29:51 INFO - Mirror: Off 16:29:51 INFO - Online: Yes 16:29:51 INFO - Rotation: Supported 16:29:51 INFO - Return code: 0 16:29:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:29:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:29:51 INFO - Python 2.7.3 16:29:51 INFO - Return code: 0 16:29:51 INFO - grabbing minidump binary from tooltool 16:29:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:51 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 0x103dcbd30>, '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 0x103cf2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103dc0be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:29:51 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:29:51 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 16:29:51 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 16:29:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:29:51 INFO - Return code: 0 16:29:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:29:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:29:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:29:51 INFO - ENV: RUST_BACKTRACE is now 1 16:29:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:29:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:29:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0170', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:29:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0170 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:29:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cXbdiUM1IT/Render', 16:29:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:51 INFO - 'HOME': '/Users/cltbld', 16:29:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:51 INFO - 'LOGNAME': 'cltbld', 16:29:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:29:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:29:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:51 INFO - 'MOZ_NO_REMOTE': '1', 16:29:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:29:51 INFO - 'NO_EM_RESTART': '1', 16:29:51 INFO - 'PAGER': '/bin/cat', 16:29:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:51 INFO - 'PWD': '/builds/slave/test', 16:29:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:29:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:51 INFO - 'RUST_BACKTRACE': '1', 16:29:51 INFO - 'SHELL': '/bin/bash', 16:29:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XNwtwLRvYw/Listeners', 16:29:51 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 16:29:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:51 INFO - 'USER': 'cltbld', 16:29:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:51 INFO - 'XPC_FLAGS': '0x0', 16:29:51 INFO - 'XPC_SERVICE_NAME': '0', 16:29:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0170', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:29:51 INFO - mozversion application_buildid: 20170406150234 16:29:51 INFO - mozversion application_changeset: 422bd63b18bc5b11482255aaaef1826285309233 16:29:51 INFO - mozversion application_display_name: Nightly 16:29:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:29:51 INFO - mozversion application_name: Firefox 16:29:51 INFO - mozversion application_remotingname: firefox 16:29:51 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:29:51 INFO - mozversion application_vendor: Mozilla 16:29:51 INFO - mozversion application_version: 55.0a1 16:29:51 INFO - mozversion platform_buildid: 20170406150234 16:29:51 INFO - mozversion platform_changeset: 422bd63b18bc5b11482255aaaef1826285309233 16:29:51 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:29:51 INFO - mozversion platform_version: 55.0a1 16:29:51 INFO - using testdate: 1491521391 16:29:51 INFO - actual date: 1491521391 16:29:51 INFO - starting webserver on 'localhost:49393' 16:29:51 INFO - SUITE-START | Running 2 tests 16:29:51 INFO - TEST-START | dromaeo_css 16:29:51 INFO - operating with platform_type : mac_ 16:29:51 INFO - Initialising browser for dromaeo_css test... 16:29:51 INFO - TEST-INFO | started process 1975 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpPHNcKW/profile http://localhost:49393/getInfo.html) 16:29:54 INFO - PID 1975 | __metrics Screen width/height:1600/1200 16:29:54 INFO - PID 1975 | colorDepth:24 16:29:54 INFO - PID 1975 | Browser inner width/height: 1024/689 16:29:54 INFO - PID 1975 | __metrics 16:29:54 INFO - PID 1975 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:29:54 INFO - PID 1975 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 304: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:29:55 INFO - TEST-INFO | 1975: exit 0 16:29:55 INFO - Browser initialized. 16:29:55 INFO - Running cycle 1/1 for dromaeo_css test... 16:29:55 INFO - TEST-INFO | started process 1977 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpPHNcKW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:30:07 INFO - PID 1977 | RSS: Main: 240513024 16:30:07 INFO - PID 1977 | 16:33:11 INFO - PID 1977 | Cycle 1(1): loaded http://localhost:49393/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49393/tests/dromaeo/cssquery-ext.html) 16:33:14 INFO - PID 1977 | RSS: Main: 462655488 16:33:14 INFO - PID 1977 | 16:34:57 INFO - PID 1977 | ************************* 16:34:57 INFO - PID 1977 | A coding exception was thrown and uncaught in a Task. 16:34:57 INFO - PID 1977 | 16:34:57 ERROR - PID 1977 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:34:57 INFO - PID 1977 | Full stack: 16:34:57 INFO - PID 1977 | ************************* 16:34:57 INFO - PID 1977 | ************************* 16:34:57 INFO - PID 1977 | A coding exception was thrown and uncaught in a Task. 16:34:57 INFO - PID 1977 | 16:34:57 ERROR - PID 1977 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:34:57 INFO - PID 1977 | Full stack: 16:34:57 INFO - PID 1977 | ************************* 16:34:57 INFO - PID 1977 | ************************* 16:34:57 INFO - PID 1977 | A coding exception was thrown and uncaught in a Task. 16:34:57 INFO - PID 1977 | 16:34:57 ERROR - PID 1977 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:34:57 INFO - PID 1977 | Full stack: 16:34:57 INFO - PID 1977 | ************************* 16:34:57 INFO - PID 1977 | ************************* 16:34:57 INFO - PID 1977 | A coding exception was thrown and uncaught in a Task. 16:34:57 INFO - PID 1977 | 16:34:57 ERROR - PID 1977 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:34:57 INFO - PID 1977 | Full stack: 16:34:57 INFO - PID 1977 | ************************* 16:36:17 INFO - PID 1977 | Cycle 1(1): loaded http://localhost:49393/tests/dromaeo/cssquery-ext.html (next: http://localhost:49393/tests/dromaeo/cssquery-jquery.html) 16:36:17 INFO - PID 1977 | RSS: Main: 372572160 16:36:17 INFO - PID 1977 | 16:39:20 INFO - PID 1977 | Cycle 1(1): loaded http://localhost:49393/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49393/tests/dromaeo/cssquery-mootools.html) 16:39:20 INFO - PID 1977 | RSS: Main: 334848000 16:39:20 INFO - PID 1977 | 16:42:23 INFO - PID 1977 | Cycle 1(1): loaded http://localhost:49393/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49393/tests/dromaeo/cssquery-prototype.html) 16:42:23 INFO - PID 1977 | RSS: Main: 360939520 16:42:23 INFO - PID 1977 | 16:45:27 INFO - PID 1977 | Cycle 1(1): loaded http://localhost:49393/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49393/tests/dromaeo/cssquery-yui.html) 16:45:28 INFO - PID 1977 | RSS: Main: 333144064 16:45:28 INFO - PID 1977 | 16:48:31 INFO - PID 1977 | Cycle 1(1): loaded http://localhost:49393/tests/dromaeo/cssquery-yui.html (next: http://localhost:49393/tests/dromaeo/cssquery-dojo.html) 16:48:31 INFO - PID 1977 | RSS: Main: 404385792 16:48:31 INFO - PID 1977 | 16:48:31 INFO - PID 1977 | __start_tp_report 16:48:31 INFO - PID 1977 | _x_x_mozilla_page_load 16:48:31 INFO - PID 1977 | _x_x_mozilla_page_load_details 16:48:31 INFO - PID 1977 | |i|pagename|runs| 16:48:31 INFO - PID 1977 | |0;dojo.html;3495.423255162898;3497.195645001651;3506.736994725396;3541.397962346401;3577.5885773136097;6060.787872424466;6135.416406698443;6244.1258223848645;6259.155014073099;6259.217597800275;6135.263768347793;6138.602983404998;6146.678464130211;6191.938080619192;6199.876002479948;5392.703401312926;5399.298091248133;5405.675659460425;5418.997485465182;5501.257330260403;447.2651543217645;5311.9468805312035;5378.946210537883;5385.919211211835;5499.7250137492965;4493.393391892086;5357.491038351351;5371.462853714617;5403.783848646089;5477.972610136963;5425.186222066682;5435.076037073667;5437.537809286216;5443.237946687467;5472.179173124063;5379.731013449351;5403.78384864605;5412;5415.9458405415835;5458.153986132156;6079.969600152015;6113.141862489121;6118.527810132473;6146;6252.3122456529745;6581.7367305307735;6626.966865165691;6637.269900310962;6648.534602577822;6678.599284042973;6635;6722.226943901416;6726.966365168192;6736.8989465158065;6749.0551322814845;6139.3388173831;6207.410296021871;6250.593711408744;6340.270868850049;6357.523185761041;6899.862002759916;6905.516613836983;6910.170779506391;6910.827229319207;6915.861682766417;6466;6483.4164925156965;6490.4807615390655;6499.252585952581;6502.804915852532;6415.390537898892;6590.70341834609;6671.699773510204;6684.598924064571;6698.765543206062;6399.168108146005;6417.294097649255;6511.446527045254;6535.607863528204;6573.276939536647;6566.704498297588;6581.979793132072;6592.274849766522;6595.0766892635065;6620.503462240351;5261.500157485033;5305.991861546294;5323.414424413311;5331.653442526223;5373.650712703662;4698.88252793676;4709.293605959065;4742.69172503793;4743;4771.117343291502;6259.624422534663;6267.2792628847355;6270.529710271748;6271.717772700239;6296.244450665949;6586.769463068769;6704.229013663394;6713.1944166700305;6713.597184168965;6734.326567343226;6268.968655156695;6336.714847831859;6349.6507692077375;6352.65060421672;6353.205849268841;5695;5892.911606325909;5893.675847828325;5897.76408943646;5910.083936989773;6755.22314933779;6784.796456106226;6860.348266914635;6915.204751453647;6931.09895713554;6642.701078451385;6693.564918280296;6701.865962680621;6715.764948226789;6730.966345168243;6913.792586222421;6934.791956241321;6945.444364450933;6948.756793512202;6969.860602788017;6359.268684101387;6429.8714025720155;6514.478841692748;6562.639054851838;6576.53964222519;6663.633500157538;6693.330666933268;6697.3637504437;6705.4635629150525;6706.429953454011;6420.357964203542;6435.22777266731;6469.838254043687;6470.935290647221;6480.513961452815;6227.937720622917;6287.1512345832725;6459.9031014533875;6480.351964803482;6486.772962946275;6342.682865856596;6343.302236754045;6345;6352.460040896575;6355.555111142361;6335.65153916539;6377.7767778127545;6422.518311126778;6450.41946224842;6472.255690595453;6273.560850740404;6339.080833279241;6348.365163483615;6350.015747559136;6371.299157092809 16:48:31 INFO - PID 1977 | |1;ext.html;768;807.0758980966795;1011.7420057885238;1134.7787181499605;1153.527053907897;36709.26581468369;37269.44095838565;37440.43839342412;37544.43683344745;37620.30708618112;39583.80208098955;39842;39856.60143398565;39888.80055599718;39943;6185.226846644169;8451.070382257947;8601.440906341099;8610.784730381727;8631.352648551352;6247.062940558907;8356.791080223013;8534.018587862412;8554;8555.828883422328;8579.270761985239;8634.438761480484;8637.222649961503;8638.740837774876;8672.262857657106;8541.273991710712;8585.785355366103;8589.184027517376;8611.56942152896;8614.05245423003;8451.873221901677;8484.32125429964;8490.745277641681;8493.065762766091;8511.74464766058;37466.81266593649;37662.81168594167;37692.62307376919;37715.434268485995;37860;3397.6092749333898;3428.142964258935;3431.3823511767873;3439.2605589798313;3440.3291358185165;29756;29892.402151957056;29894.654740536727;29911.551326730118;29937.101886943427;2768.892443022807;3029.4849875521213;3078.045805800209;3119.906402807919;3137.027521468352;292358.9966652161;296646;300369;302282;303711;39183.608163918;39271.41092883609;46532;46551.30173047407;46646.067078658234;40435.39346909799;48707;49281;49456;49508.75245623749;13944.16335019902;14049.71900561983;14057;14059.015868889079;14095.85904140966;11366.431678416046;11398.088152947745;11408.88591114095;11420.88579114215;11431.428428578538;12952.481900724055;12956.870431295567;12962.981386233198;12975.286359250333;13055.608331750063;5695.145728140812;5720.716683099827;5752.539796816244;5770.4806567408295;5779.450952159538;833.6415341403255;997.1673652500243;999.8801342496305;1010.1262408017012;1015.4212099103588;23637.881810590836;23735.525289494115;23773.40566485817;23778.167764128517;23811.928463219196;1073.3238060022293;1096.6929259807266;1113.3876367997568;1124.2973141786383;1126.3242054767238;11077.889221107685;11146.777064458665;12094;12206.694832629255;12209;386222;387763;387910.060449696;388739;389157;52547.42357729274;52742.47257527452;52779.99999999923;52792.928813186205;52958.47041529613;1482.7553453680239;1528.3275358842075;1535.3551508366513;1538.6153461634592;1542.4138827245574;21025.84357860271;21421.571568568852;23333.649995249747;23677;23902.282931512083;37553.2489350206;37596;37706;37726.811365942995;37814.43278350772;330292;331007;331445;332559;332604;25911.481770364866;26413.075542355924;28608.57087143736;28873;32881;40722.61986956204;40905.79547102245;41552.79223603863;41726.79136604298;41944.74165775032;26502.602460963488;27664.631867447366;27898;29486;29493.26266843346;28261.434771304048;28528.857355713088;29053.564196536645;29801.552976704934;29970.850145749133;33906;34353.48469772905;34419.483707744905;34667.82666086754;34673;28009.719902800713;28729;29384.971525996487;29428.852855735586;29616.703832961393;20706.792932070486;21490.570188595826;23213.41966450852;23317.76682233156;23320 16:48:31 INFO - PID 1977 | |2;jquery.html;407.6840448652296;408.63631368082395;431.58783361889397;441.3757372867846;441.6886095302812;18620.90689546552;20375.184992600298;20905.686414703752;21022.68465973008;21039.948002599867;17617.471475855746;21256.8937155314;21872.562548749014;22618.54762904741;22862.77137228627;5326.334208223972;6243.500519958415;6253.874922501547;6256.155419018442;6258.968705156491;6000.549958753089;6079;6205.81382558524;6216.316205217422;6313.589616674902;6260.843478913018;6266.843328916768;6282.623042617434;6284.277308109557;6292.339304373044;6127.816165515042;6192.318844927055;6205.472534834545;6274.968625156891;6285.591436556632;5417.83746487606;5505.009098362293;5570.220169176318;5570.5822063345;5583.05088135018;27359.316017099532;27855;27959;28169.71830281712;28683.56974645382;2103.379503046605;2174.9782502174935;2175.3256490487997;2177.150911144655;2177.3467959612212;17471.951682899067;17663.293468261254;18117;18135.183916723778;18397.35609253683;2056.763472200701;2065.8760474371434;2130.1798807459086;2133.445304220914;2134.935951921445;136061;157504.21247893688;159833;160157.5740002898;165978;11672;12309.32298723561;12368.319742414255;12382;12397.132200745862;16590.004599723812;16591.502254932373;16623.50129496117;16657;16657.25042373072;7086.873412185574;7509.436792240604;8728.825423491495;8933.862251577373;9046.05016473274;8597;8608.612612432456;8794.736157915273;8803.295736341077;8848.20366167048;6361.491080713532;7628.199039100926;7667.731629393056;7783.455158138989;7843.137254901956;1782.6612943540686;2129.168914733328;2134.6371116910163;2138.946526336854;2370.146747171017;869.0787764969153;908.1735620585235;909.6497848328441;946.068122898944;948.9277116857907;19719.507012324808;20447.693284600744;20471.38585842427;20675.58648827045;20744.096924213005;831.7754206364248;931.213124909464;933.3373304953559;960.6301573893961;962.4610218277787;10123.59505619767;10499.842502362471;10638.840417393745;10642.201834862417;10645.308054976556;707419;711559;721898;732073;733334.3330434221;125312;157343.21328393286;161541;162871;164339;1147.208426185929;1154.7575009248153;1181.4860535667012;1181.8818118188285;1183.3373310945726;15055.849441505883;16597;17816.10919453996;17920.820791792437;18219.089045547404;55506;62130.68934655298;62189.68905155445;62413;62452.375476244655;80281.19718802738;81589.18410815815;82933;83528.58235708783;85641.57179214065;7287.8542429152185;8248.54632995167;8614.526201059001;8953.910460895308;9035.548222588975;13059.673508162372;13509.716576925177;14386.280685965869;14594.978351515292;14866.256687165815;11707.180497365443;11953.820692689442;12188;12270.877291226974;12356;11133.387663678575;11454.828177577176;11586.536538538701;11910.80891910802;11931.522739090686;10852.837207442053;11946.940265298617;11994.160408771302;12222;12332.81500777507;8600.870986935326;8938.37431379797;8979.191872731486;9046.592903319235;9152.039035901134;11641.126915481542;11785.705357366134;12158.270503769802;12223.572174973833;12244.632661020183 16:48:31 INFO - PID 1977 | |3;mootools.html;1165.329935287209;1347.6361382426744;1404.3750531013698;1416.376794210547;1431.971360572788;9754.756131096718;10249.641262555811;11495.252808567448;11827;11828.645140645773;11400.973912347887;11509.079273658108;11566.826497602522;11567.710807229823;11573.594924177656;2480.0823695232743;2519.8740062996867;2521.810864185184;2530.0012989738175;2530.8481491110592;2132.2004248406847;2217.5121473275854;2222.4777177363308;2222.9666555001686;2223.3107736601623;2161.7838216178334;2209.723784526934;2287.2223444029028;2293.7018187635585;2295.472041430472;2480.5410998965067;2498.7626175513296;2514.57252267115;2514.8994040238367;2517.3832411059225;2475.047106863852;2477.7893879020303;2478;2481.8262721609494;2482.8262021658493;11359.318440893572;11627.6511704649;11627.94186029073;11632.534698612046;11642.6507204784;6311.621302721851;6531.615960174502;6545.37818907203;6550.868982620368;6565.770198043093;3947.723659343847;4142.316517774563;4317.201317756194;4348.804303806336;4361.323994780814;1222.443788076423;1224.8040313549877;1237.461704158694;1276.872312768725;1280.775864223757;16732.24704888283;16783.992960422413;16803.91598042002;16807;16827.326906923707;5742.583163017997;5912.363896203804;5913.349531551526;6058.787942421994;6066.969665151646;13752;13771.173729576258;13798.17210967345;13808;13812.93093534546;6779.72881084761;6795.966020169867;6796.796096117125;6796.898046529306;6798.728050878009;5995.940040599626;6155.169052177988;6235.00239961604;6237.500999919996;6247.875042499216;4072.75563466193;4371.584699453547;4533.161365147458;4557.225271703818;4592;1315.9736805263842;1339.5378594384922;1340.7653660609549;1340.8994325425633;1343.4223283987978;651.8012006338113;708.7554793596202;729.9781006569812;739.1979702023365;770.5838847022545;5858.8242352729485;6101.298350689639;6126.417990290915;6171.722272497748;6201.565890387629;885.3669626217286;891.148952750122;900.2617853360182;919.048784508031;930.8968871886835;3418.786330852561;3435.3644575753565;3610.2779444111284;3723.8696645617274;3769.0577355661085;14933.17867517297;14948.252587370587;15293.888730897483;15415.306311216022;15468.92265538665;15133.319000644997;15393.923030384776;15500.922495387676;15511.146886921102;15535;10513.9474302628;10960.643461655782;11035;11039.889601103887;11044.558217671363;6665.700043497957;6715.597064176165;6896.172459304916;6915.239323674492;6930.826729331606;13086.280254586089;13339.132956358193;13366.9331653345;13449.839287767563;13842.031057826258;13732.313384330942;13839.86160138426;13845.792313115513;14048.929755351157;14053.859461405254;9162.816743664956;9437.150656440954;9531.237501000041;9562.18721408688;9572.856407153758;10317;10384.221183411426;10535.525901334306;10588.611644273758;10683.51924163399;8791.692290769792;8840.646374144962;8855;8866.911330886609;8906.332025098272;8479.364047696325;8633.481991080555;8678.78303042429;8746.169113934166;8829.984551776628;7787.688492460465;7854.72508462201;7866.842663146819;7967.362610991223;7981.042274927046;5828.883422331445;5874.2377286814635;5956.255468066492;6070.514358851369;6076.848078798065;4463.709858859163;5898.675572843535;6031;6095.238095238095;6240.4071613196675 16:48:31 INFO - PID 1977 | |4;prototype.html;379.3683516944288;387.96702280306175;399.5964076282954;402.11534623827566;403.796082978096;4732.384789977298;4732.455767586728;5725.312962444513;5763.39484354143;5798.6230894991795;5531.1426728857095;5562.276904002474;5595.888082238353;5629.380768115514;5707.543396528278;3583.372909740785;4377.387165796791;4386.473623165225;4390.3853460515475;4408.20652282589;3590.964090359089;4253.234417804794;4262.744235345874;4402.427684401024;4440.422745043156;3350.5476760637366;3446.758726889119;3541.6812486876174;3886.6979561846815;4350.7607081610495;3766.642168993941;4174.08170202555;4328.39402483651;4341.522432532419;4354.325079612665;3379.6113446953423;3870.554886188096;3887.08653466438;3901;3924.4898163238595;4966.354373931365;5254.789808407659;5335.253064570924;5518.558515318765;5539.750711217965;5167.819126330561;5170.922436163461;5185.870353241199;5186.351706036746;5188;4619.145458090264;5562.193481945095;6004.759809607611;6013.579049466539;6030.517558595302;4071.9389209161886;4104.91790164195;4105.856295029689;4114.156597897424;4114.91770164598;5768.067697321444;6204.348543402923;6311.305756366796;6314.368563143741;6321.87356252877;3941.507311586052;4165.812538435777;4182.1008483176265;4191.637717741746;4200.6185021058345;4189.72766770159;4238.809253583596;4241.130568233505;4254.510731265882;4264.552222016706;4227.027783609787;4792.281157826354;4846.733429661403;4898.8775280618265;4923.261510773341;5675.943240567624;6023.69881505923;6029.88447137281;6038.486728628028;6039.36937027009;1278.520554791953;1402.6072699644117;1439.4098419647894;1462.9634259143393;1464.2751837840337;940.4263399326405;970.2141265574907;978.9755256118654;982.6020461713018;984.3404918704485;5034.953852918931;5244.947550524446;5707.885842283131;5724.252358867767;5758.942410575924;4770.212914869007;5521.641093328921;6236.656983865931;6237.251529816361;6243.8126856194385;5368.865778355573;5686.459786320293;5752.654840709571;5761.596688231783;5793.088280867304;2667.853268070236;3269.983650081734;3275.6396796352383;3280.671932806748;3285.6878596533293;4581.702189357681;5902.6458412495385;6068.029115341524;6456.903146452807;6514.934850651528;6344;6349.174607301114;6356.618602883842;6358.268799088071;6364.331745166691;5766.94233057664;6284;6284.4344009039405;6397.4562162216735;6421.871562568723;5003.749812509433;5852.824415267549;5919.852003699942;6220.968895155676;6292.339304372975;5811.80857924133;6074.42292982166;6219.937800621936;6231.345708700521;6231.844203894939;4866.172750632471;6268.247810262797;6447.580907241015;6499.252585952676;6518.445932095825;4019.954811748908;4101.569335219879;4116.711830171859;4126.91746165069;4130.731502452331;5615.298087739033;5715;6211.534134939807;6215.875682486416;6217.782377616762;4980.377452818398;5179.352580927384;5359.901220249917;6050.45545900871;6071.2714474263375;5096.872578185575;6042.274927008787;6057.939860524302;6064.848378790565;6083.878322433615;5088.422588997422;5876.6180198288685;5879.823605291848;5924.8815023700145;5933.881322373615;5437.564994800486;5479.397266300624;5988.790392336247;6005.948958932257;6040.546958978006;5745.368009519033;5849.736761845812;5903.26209223847;5904.793332233523;5907.409259074058 16:48:31 INFO - PID 1977 | |5;yui.html;801.2748462641308;823.3989187892838;833.2584000239788;837.656560810068;844.2865778417236;15742.370305187793;16180.83819161808;17575.85756925799;18473;18594.442166734985;17535.2109155088;17604.559886002826;17618.647627047485;18170.45488635343;18183.727244091348;975.7902051059039;992.9950350248275;996.3424140067557;996.9501524923761;997.6109317366233;962.7256231973889;989.1789814453986;990.782027953849;997.6957028106425;1015.1320620869161;1056.6724315462193;1060.512164404375;1062.5962134388922;1064.138048180972;1064.7551063255455;1054.2672842374552;1054.9261551691384;1056.8361903904868;1057.265200685524;1058.7617785998088;753.932146106853;780.9609519524001;782.284209947897;782.581318994341;784.9254320839511;16092.034477931362;16404.67190656192;16421.507354779376;16601.91699041509;16683.83316166835;14554.417823287056;14637.487687930978;14700.55898323052;14778.334974926047;14797.92601036988;12999;13045.282509461977;13172.341382930816;13208.537701180412;13297.135686180367;3263.494158405451;3304.6695330467;3323.501474778778;3331.3670402623425;3343.0472315389998;100448;103499.48250258777;103754;105421.47289263581;108296.45851770691;11338.489767960462;11479.655610331703;11532.566093317157;11721.589744359077;12227.449764760608;24005;24266.15068472595;24414.633780493306;24486.632700509508;24549.754502454747;7618.961905190438;7696.923030769621;7782.64978075988;7805.453618246782;7957.482763620346;4094.5086589609436;4129.050318426779;4132.950835888776;4179.665626749853;4312.223799716081;4130.855420060284;4248.235317642854;4249.893752656147;4251.383549385322;4252.255855225385;863.6156910174911;938.1931538876561;945.0643862576001;946.5267366316841;947.6162154327509;556.785637529554;611.8225714542822;640.414021170633;642.8071578526424;656.7143292667705;9463.905360946303;10093.192544596415;10118.443485608215;10274;10319.1744660427;586.9677167755815;608.9474546890451;624.1823211592828;643.9645819479973;649.399305642279;4553.362529245908;4728.078024785203;4735.905281894343;4752.619790416758;4836.927446088312;81687.4229101176;104754;107042;109380;111510;92902.67743306472;94552.52723736475;94714.52642736743;95423.52288238515;96155.51922240484;76276.84677838694;77465;77637.05907352362;77983.8302425464;78156;9318.62725491;9323.813523729623;9329.580168892551;9359.438433694;9470.337076404585;31006.53490197694;40694.1861162781;41058;41373.96565085777;41516.58483415248;55534.44465555293;64250;66504.34469587082;67779.66110169615;69154.65422672854;12740.426680799572;12772.936135319263;12775.61673149807;13228.470861165446;13292.468301267842;14273.785893211818;14316.06945548536;14499.347529360994;14684;15087.547373578811;13429.932850335686;13622.659433514242;13634.86365136336;13942.930285348913;14189.148651080555;13543.458261669817;13607;13624.65938351549;13970.231637260049;14226.857731422553;12846.678833028875;12962.287074211008;13396.93301533486;13418.261995590336;13433.66415839612;9437.292203084658;9448.622055117718;9703.320767546204;9915.553800079158;10167.389956602627;10773.89226107729;11042.396688472038;11129.610463633733;11227.04570111517;11409.942950285196 16:48:31 INFO - PID 1977 | __end_tp_report 16:48:31 INFO - PID 1977 | __start_cc_report 16:48:31 INFO - PID 1977 | _x_x_mozilla_cycle_collect,2738 16:48:31 INFO - PID 1977 | __end_cc_report 16:48:31 INFO - PID 1977 | __startTimestamp1491522511507__endTimestamp 16:48:31 INFO - PID 1977 | 16:48:31 INFO - PID 1977 | ------- Summary: start ------- 16:48:31 INFO - PID 1977 | Number of tests: 6 16:48:31 INFO - PID 1977 | 16:48:31 INFO - PID 1977 | [#0] dojo.html Cycles:165 Average:6104.16 Median:6352.46 stddev:845.78 (13.3%) stddev-sans-first:823.24 16:48:31 INFO - PID 1977 | Values: 3495.4 3497.2 3506.7 3541.4 3577.6 6060.8 6135.4 6244.1 6259.2 6259.2 6135.3 6138.6 6146.7 6191.9 6199.9 5392.7 5399.3 5405.7 5419.0 5501.3 447.3 5311.9 5378.9 5385.9 5499.7 4493.4 5357.5 5371.5 5403.8 5478.0 5425.2 5435.1 5437.5 5443.2 5472.2 5379.7 5403.8 5412.0 5415.9 5458.2 6080.0 6113.1 6118.5 6146.0 6252.3 6581.7 6627.0 6637.3 6648.5 6678.6 6635.0 6722.2 6727.0 6736.9 6749.1 6139.3 6207.4 6250.6 6340.3 6357.5 6899.9 6905.5 6910.2 6910.8 6915.9 6466.0 6483.4 6490.5 6499.3 6502.8 6415.4 6590.7 6671.7 6684.6 6698.8 6399.2 6417.3 6511.4 6535.6 6573.3 6566.7 6582.0 6592.3 6595.1 6620.5 5261.5 5306.0 5323.4 5331.7 5373.7 4698.9 4709.3 4742.7 4743.0 4771.1 6259.6 6267.3 6270.5 6271.7 6296.2 6586.8 6704.2 6713.2 6713.6 6734.3 6269.0 6336.7 6349.7 6352.7 6353.2 5695.0 5892.9 5893.7 5897.8 5910.1 6755.2 6784.8 6860.3 6915.2 6931.1 6642.7 6693.6 6701.9 6715.8 6731.0 6913.8 6934.8 6945.4 6948.8 6969.9 6359.3 6429.9 6514.5 6562.6 6576.5 6663.6 6693.3 6697.4 6705.5 6706.4 6420.4 6435.2 6469.8 6470.9 6480.5 6227.9 6287.2 6459.9 6480.4 6486.8 6342.7 6343.3 6345.0 6352.5 6355.6 6335.7 6377.8 6422.5 6450.4 6472.3 6273.6 6339.1 6348.4 6350.0 6371.3 16:48:31 INFO - PID 1977 | 16:48:31 INFO - PID 1977 | [#1] ext.html Cycles:180 Average:47588.29 Median:18651.80 stddev:90143.62 (483.3%) stddev-sans-first:90327.96 16:48:31 INFO - PID 1977 | Values: 768.0 807.1 1011.7 1134.8 1153.5 36709.3 37269.4 37440.4 37544.4 37620.3 39583.8 39842.0 39856.6 39888.8 39943.0 6185.2 8451.1 8601.4 8610.8 8631.4 6247.1 8356.8 8534.0 8554.0 8555.8 8579.3 8634.4 8637.2 8638.7 8672.3 8541.3 8585.8 8589.2 8611.6 8614.1 8451.9 8484.3 8490.7 8493.1 8511.7 37466.8 37662.8 37692.6 37715.4 37860.0 3397.6 3428.1 3431.4 3439.3 3440.3 29756.0 29892.4 29894.7 29911.6 29937.1 2768.9 3029.5 3078.0 3119.9 3137.0 292359.0 296646.0 300369.0 302282.0 303711.0 39183.6 39271.4 46532.0 46551.3 46646.1 40435.4 48707.0 49281.0 49456.0 49508.8 13944.2 14049.7 14057.0 14059.0 14095.9 11366.4 11398.1 11408.9 11420.9 11431.4 12952.5 12956.9 12963.0 12975.3 13055.6 5695.1 5720.7 5752.5 5770.5 5779.5 833.6 997.2 999.9 1010.1 1015.4 23637.9 23735.5 23773.4 23778.2 23811.9 1073.3 1096.7 1113.4 1124.3 1126.3 11077.9 11146.8 12094.0 12206.7 12209.0 386222.0 387763.0 387910.1 388739.0 389157.0 52547.4 52742.5 52780.0 52792.9 52958.5 1482.8 1528.3 1535.4 1538.6 1542.4 21025.8 21421.6 23333.6 23677.0 23902.3 37553.2 37596.0 37706.0 37726.8 37814.4 330292.0 331007.0 331445.0 332559.0 332604.0 25911.5 26413.1 28608.6 28873.0 32881.0 40722.6 40905.8 41552.8 41726.8 41944.7 26502.6 27664.6 27898.0 29486.0 29493.3 28261.4 28528.9 29053.6 29801.6 29970.9 33906.0 34353.5 34419.5 34667.8 34673.0 28009.7 28729.0 29385.0 29428.9 29616.7 20706.8 21490.6 23213.4 23317.8 23320.0 16:48:31 INFO - PID 1977 | 16:48:31 INFO - PID 1977 | [#2] jquery.html Cycles:180 Average:41200.60 Median:12024.95 stddev:120840.44 (1004.9%) stddev-sans-first:121140.61 16:48:31 INFO - PID 1977 | Values: 407.7 408.6 431.6 441.4 441.7 18620.9 20375.2 20905.7 21022.7 21039.9 17617.5 21256.9 21872.6 22618.5 22862.8 5326.3 6243.5 6253.9 6256.2 6259.0 6000.5 6079.0 6205.8 6216.3 6313.6 6260.8 6266.8 6282.6 6284.3 6292.3 6127.8 6192.3 6205.5 6275.0 6285.6 5417.8 5505.0 5570.2 5570.6 5583.1 27359.3 27855.0 27959.0 28169.7 28683.6 2103.4 2175.0 2175.3 2177.2 2177.3 17472.0 17663.3 18117.0 18135.2 18397.4 2056.8 2065.9 2130.2 2133.4 2134.9 136061.0 157504.2 159833.0 160157.6 165978.0 11672.0 12309.3 12368.3 12382.0 12397.1 16590.0 16591.5 16623.5 16657.0 16657.3 7086.9 7509.4 8728.8 8933.9 9046.1 8597.0 8608.6 8794.7 8803.3 8848.2 6361.5 7628.2 7667.7 7783.5 7843.1 1782.7 2129.2 2134.6 2138.9 2370.1 869.1 908.2 909.6 946.1 948.9 19719.5 20447.7 20471.4 20675.6 20744.1 831.8 931.2 933.3 960.6 962.5 10123.6 10499.8 10638.8 10642.2 10645.3 707419.0 711559.0 721898.0 732073.0 733334.3 125312.0 157343.2 161541.0 162871.0 164339.0 1147.2 1154.8 1181.5 1181.9 1183.3 15055.8 16597.0 17816.1 17920.8 18219.1 55506.0 62130.7 62189.7 62413.0 62452.4 80281.2 81589.2 82933.0 83528.6 85641.6 7287.9 8248.5 8614.5 8953.9 9035.5 13059.7 13509.7 14386.3 14595.0 14866.3 11707.2 11953.8 12188.0 12270.9 12356.0 11133.4 11454.8 11586.5 11910.8 11931.5 10852.8 11946.9 11994.2 12222.0 12332.8 8600.9 8938.4 8979.2 9046.6 9152.0 11641.1 11785.7 12158.3 12223.6 12244.6 16:48:31 INFO - PID 1977 | 16:48:31 INFO - PID 1977 | [#3] mootools.html Cycles:180 Average:7187.22 Median:2520.84 stddev:4665.40 (185.1%) stddev-sans-first:4656.54 16:48:31 INFO - PID 1977 | Values: 1165.3 1347.6 1404.4 1416.4 1432.0 9754.8 10249.6 11495.3 11827.0 11828.6 11401.0 11509.1 11566.8 11567.7 11573.6 2480.1 2519.9 2521.8 2530.0 2530.8 2132.2 2217.5 2222.5 2223.0 2223.3 2161.8 2209.7 2287.2 2293.7 2295.5 2480.5 2498.8 2514.6 2514.9 2517.4 2475.0 2477.8 2478.0 2481.8 2482.8 11359.3 11627.7 11627.9 11632.5 11642.7 6311.6 6531.6 6545.4 6550.9 6565.8 3947.7 4142.3 4317.2 4348.8 4361.3 1222.4 1224.8 1237.5 1276.9 1280.8 16732.2 16784.0 16803.9 16807.0 16827.3 5742.6 5912.4 5913.3 6058.8 6067.0 13752.0 13771.2 13798.2 13808.0 13812.9 6779.7 6796.0 6796.8 6796.9 6798.7 5995.9 6155.2 6235.0 6237.5 6247.9 4072.8 4371.6 4533.2 4557.2 4592.0 1316.0 1339.5 1340.8 1340.9 1343.4 651.8 708.8 730.0 739.2 770.6 5858.8 6101.3 6126.4 6171.7 6201.6 885.4 891.1 900.3 919.0 930.9 3418.8 3435.4 3610.3 3723.9 3769.1 14933.2 14948.3 15293.9 15415.3 15468.9 15133.3 15393.9 15500.9 15511.1 15535.0 10513.9 10960.6 11035.0 11039.9 11044.6 6665.7 6715.6 6896.2 6915.2 6930.8 13086.3 13339.1 13366.9 13449.8 13842.0 13732.3 13839.9 13845.8 14048.9 14053.9 9162.8 9437.2 9531.2 9562.2 9572.9 10317.0 10384.2 10535.5 10588.6 10683.5 8791.7 8840.6 8855.0 8866.9 8906.3 8479.4 8633.5 8678.8 8746.2 8830.0 7787.7 7854.7 7866.8 7967.4 7981.0 5828.9 5874.2 5956.3 6070.5 6076.8 4463.7 5898.7 6031.0 6095.2 6240.4 16:48:31 INFO - PID 1977 | 16:48:31 INFO - PID 1977 | [#4] prototype.html Cycles:180 Average:4859.75 Median:5520.10 stddev:1500.30 (27.2%) stddev-sans-first:1466.34 16:48:31 INFO - PID 1977 | Values: 379.4 388.0 399.6 402.1 403.8 4732.4 4732.5 5725.3 5763.4 5798.6 5531.1 5562.3 5595.9 5629.4 5707.5 3583.4 4377.4 4386.5 4390.4 4408.2 3591.0 4253.2 4262.7 4402.4 4440.4 3350.5 3446.8 3541.7 3886.7 4350.8 3766.6 4174.1 4328.4 4341.5 4354.3 3379.6 3870.6 3887.1 3901.0 3924.5 4966.4 5254.8 5335.3 5518.6 5539.8 5167.8 5170.9 5185.9 5186.4 5188.0 4619.1 5562.2 6004.8 6013.6 6030.5 4071.9 4104.9 4105.9 4114.2 4114.9 5768.1 6204.3 6311.3 6314.4 6321.9 3941.5 4165.8 4182.1 4191.6 4200.6 4189.7 4238.8 4241.1 4254.5 4264.6 4227.0 4792.3 4846.7 4898.9 4923.3 5675.9 6023.7 6029.9 6038.5 6039.4 1278.5 1402.6 1439.4 1463.0 1464.3 940.4 970.2 979.0 982.6 984.3 5035.0 5244.9 5707.9 5724.3 5758.9 4770.2 5521.6 6236.7 6237.3 6243.8 5368.9 5686.5 5752.7 5761.6 5793.1 2667.9 3270.0 3275.6 3280.7 3285.7 4581.7 5902.6 6068.0 6456.9 6514.9 6344.0 6349.2 6356.6 6358.3 6364.3 5766.9 6284.0 6284.4 6397.5 6421.9 5003.7 5852.8 5919.9 6221.0 6292.3 5811.8 6074.4 6219.9 6231.3 6231.8 4866.2 6268.2 6447.6 6499.3 6518.4 4020.0 4101.6 4116.7 4126.9 4130.7 5615.3 5715.0 6211.5 6215.9 6217.8 4980.4 5179.4 5359.9 6050.5 6071.3 5096.9 6042.3 6057.9 6064.8 6083.9 5088.4 5876.6 5879.8 5924.9 5933.9 5437.6 5479.4 5988.8 6005.9 6040.5 5745.4 5849.7 5903.3 5904.8 5907.4 16:48:31 INFO - PID 1977 | 16:48:31 INFO - PID 1977 | [#5] yui.html Cycles:180 Average:20534.91 Median:17135.01 stddev:29246.89 (170.7%) stddev-sans-first:29291.40 16:48:31 INFO - PID 1977 | Values: 801.3 823.4 833.3 837.7 844.3 15742.4 16180.8 17575.9 18473.0 18594.4 17535.2 17604.6 17618.6 18170.5 18183.7 975.8 993.0 996.3 997.0 997.6 962.7 989.2 990.8 997.7 1015.1 1056.7 1060.5 1062.6 1064.1 1064.8 1054.3 1054.9 1056.8 1057.3 1058.8 753.9 781.0 782.3 782.6 784.9 16092.0 16404.7 16421.5 16601.9 16683.8 14554.4 14637.5 14700.6 14778.3 14797.9 12999.0 13045.3 13172.3 13208.5 13297.1 3263.5 3304.7 3323.5 3331.4 3343.0 100448.0 103499.5 103754.0 105421.5 108296.5 11338.5 11479.7 11532.6 11721.6 12227.4 24005.0 24266.2 24414.6 24486.6 24549.8 7619.0 7696.9 7782.6 7805.5 7957.5 4094.5 4129.1 4133.0 4179.7 4312.2 4130.9 4248.2 4249.9 4251.4 4252.3 863.6 938.2 945.1 946.5 947.6 556.8 611.8 640.4 642.8 656.7 9463.9 10093.2 10118.4 10274.0 10319.2 587.0 608.9 624.2 644.0 649.4 4553.4 4728.1 4735.9 4752.6 4836.9 81687.4 104754.0 107042.0 109380.0 111510.0 92902.7 94552.5 94714.5 95423.5 96155.5 76276.8 77465.0 77637.1 77983.8 78156.0 9318.6 9323.8 9329.6 9359.4 9470.3 31006.5 40694.2 41058.0 41374.0 41516.6 55534.4 64250.0 66504.3 67779.7 69154.7 12740.4 12772.9 12775.6 13228.5 13292.5 14273.8 14316.1 14499.3 14684.0 15087.5 13429.9 13622.7 13634.9 13942.9 14189.1 13543.5 13607.0 13624.7 13970.2 14226.9 12846.7 12962.3 13396.9 13418.3 13433.7 9437.3 9448.6 9703.3 9915.6 10167.4 10773.9 11042.4 11129.6 11227.0 11409.9 16:48:31 INFO - PID 1977 | -------- Summary: end -------- 16:48:31 INFO - PID 1977 | 16:48:32 INFO - TEST-INFO | 1977: exit 0 16:48:32 INFO - TEST-OK | dromaeo_css | took 1120468ms 16:48:32 INFO - TEST-START | kraken 16:48:32 INFO - operating with platform_type : mac_ 16:48:32 INFO - Initialising browser for kraken test... 16:48:32 INFO - TEST-INFO | started process 1982 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpI5oeqG/profile http://localhost:49393/getInfo.html) 16:48:34 INFO - PID 1982 | __metrics Screen width/height:1600/1200 16:48:34 INFO - PID 1982 | colorDepth:24 16:48:34 INFO - PID 1982 | Browser inner width/height: 1024/689 16:48:34 INFO - PID 1982 | __metrics 16:48:35 INFO - PID 1982 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:48:35 INFO - PID 1982 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 304: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:48:35 INFO - TEST-INFO | 1982: exit 0 16:48:35 INFO - Browser initialized. 16:48:35 INFO - Running cycle 1/1 for kraken test... 16:48:35 INFO - TEST-INFO | started process 1983 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpI5oeqG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:48:48 INFO - PID 1983 | RSS: Main: 239431680 16:48:48 INFO - PID 1983 | 16:48:51 INFO - PID 1983 | Cycle 1(1): loaded http://localhost:49393/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49393/tests/kraken/driver.html?testName=audio-beat-detection) 16:48:52 INFO - PID 1983 | RSS: Main: 291684352 16:48:52 INFO - PID 1983 | 16:48:55 INFO - PID 1983 | Cycle 1(1): loaded http://localhost:49393/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49393/tests/kraken/driver.html?testName=audio-dft) 16:48:55 INFO - PID 1983 | RSS: Main: 291799040 16:48:55 INFO - PID 1983 | 16:48:59 INFO - PID 1983 | Cycle 1(1): loaded http://localhost:49393/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49393/tests/kraken/driver.html?testName=audio-fft) 16:49:00 INFO - PID 1983 | RSS: Main: 386371584 16:49:00 INFO - PID 1983 | 16:49:02 INFO - PID 1983 | Cycle 1(1): loaded http://localhost:49393/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49393/tests/kraken/driver.html?testName=audio-oscillator) 16:49:02 INFO - PID 1983 | RSS: Main: 290557952 16:49:02 INFO - PID 1983 | 16:49:04 INFO - PID 1983 | Cycle 1(1): loaded http://localhost:49393/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49393/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:49:05 INFO - PID 1983 | RSS: Main: 291651584 16:49:05 INFO - PID 1983 | 16:49:08 INFO - PID 1983 | Cycle 1(1): loaded http://localhost:49393/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49393/tests/kraken/driver.html?testName=imaging-darkroom) 16:49:09 INFO - PID 1983 | RSS: Main: 333250560 16:49:09 INFO - PID 1983 | 16:49:12 INFO - PID 1983 | Cycle 1(1): loaded http://localhost:49393/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49393/tests/kraken/driver.html?testName=imaging-desaturate) 16:49:13 INFO - PID 1983 | RSS: Main: 343621632 16:49:13 INFO - PID 1983 | 16:49:16 INFO - PID 1983 | Cycle 1(1): loaded http://localhost:49393/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49393/tests/kraken/driver.html?testName=json-parse-financial) 16:49:16 INFO - PID 1983 | RSS: Main: 348602368 16:49:16 INFO - PID 1983 | 16:49:18 INFO - PID 1983 | Cycle 1(1): loaded http://localhost:49393/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49393/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:49:19 INFO - PID 1983 | RSS: Main: 310452224 16:49:19 INFO - PID 1983 | 16:49:20 INFO - PID 1983 | Cycle 1(1): loaded http://localhost:49393/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49393/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:49:21 INFO - PID 1983 | RSS: Main: 314933248 16:49:21 INFO - PID 1983 | 16:49:23 INFO - PID 1983 | Cycle 1(1): loaded http://localhost:49393/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49393/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:49:24 INFO - PID 1983 | RSS: Main: 317251584 16:49:24 INFO - PID 1983 | 16:49:27 INFO - PID 1983 | Cycle 1(1): loaded http://localhost:49393/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49393/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:49:28 INFO - PID 1983 | RSS: Main: 333066240 16:49:28 INFO - PID 1983 | 16:49:31 INFO - PID 1983 | Cycle 1(1): loaded http://localhost:49393/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49393/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:49:32 INFO - PID 1983 | RSS: Main: 326643712 16:49:32 INFO - PID 1983 | 16:49:34 INFO - PID 1983 | Cycle 1(1): loaded http://localhost:49393/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49393/tests/kraken/driver.html?testName=ai-astar) 16:49:34 INFO - PID 1983 | RSS: Main: 388886528 16:49:34 INFO - PID 1983 | 16:49:34 INFO - PID 1983 | __start_tp_report 16:49:34 INFO - PID 1983 | _x_x_mozilla_page_load 16:49:34 INFO - PID 1983 | _x_x_mozilla_page_load_details 16:49:34 INFO - PID 1983 | |i|pagename|runs| 16:49:34 INFO - PID 1983 | |0;ai-astar;122;122;124;122;122;123;123;124;124;122 16:49:34 INFO - PID 1983 | |1;audio-beat-detection;153;115;122;201;127;119;122;157;181;116 16:49:34 INFO - PID 1983 | |2;audio-dft;216;216;219;217;212;212;211;213;216;213 16:49:34 INFO - PID 1983 | |3;audio-fft;84;63;65;102;66;64;67;66;87;64 16:49:34 INFO - PID 1983 | |4;audio-oscillator;100;74;96;73;73;75;91;73;75;91 16:49:34 INFO - PID 1983 | |5;imaging-gaussian-blur;94;93;92;93;92;93;95;92;92;92 16:49:34 INFO - PID 1983 | |6;imaging-darkroom;101;98;100;101;99;130;159;101;100;100 16:49:34 INFO - PID 1983 | |7;imaging-desaturate;101;102;98;100;101;102;101;102;100;101 16:49:34 INFO - PID 1983 | |8;json-parse-financial;51;50;50;52;51;52;52;52;52;51 16:49:34 INFO - PID 1983 | |9;json-stringify-tinderbox;58;69;62;78;70;69;64;85;95;82 16:49:34 INFO - PID 1983 | |10;stanford-crypto-aes;89;91;95;88;92;92;91;93;90;94 16:49:34 INFO - PID 1983 | |11;stanford-crypto-ccm;154;157;151;168;177;150;167;173;222;183 16:49:34 INFO - PID 1983 | |12;stanford-crypto-pbkdf2;199;196;215;204;203;204;200;202;201;199 16:49:34 INFO - PID 1983 | |13;stanford-crypto-sha256-iterative;72;75;72;74;74;72;73;78;102;103 16:49:34 INFO - PID 1983 | __end_tp_report 16:49:34 INFO - PID 1983 | __start_cc_report 16:49:34 INFO - PID 1983 | _x_x_mozilla_cycle_collect,2129 16:49:34 INFO - PID 1983 | __end_cc_report 16:49:34 INFO - PID 1983 | __startTimestamp1491522574623__endTimestamp 16:49:34 INFO - PID 1983 | 16:49:34 INFO - PID 1983 | ------- Summary: start ------- 16:49:34 INFO - PID 1983 | Number of tests: 14 16:49:34 INFO - PID 1983 | 16:49:34 INFO - PID 1983 | [#0] ai-astar Cycles:10 Average:122.80 Median:123.00 stddev:0.92 (0.7%) stddev-sans-first:0.93 16:49:34 INFO - PID 1983 | Values: 122.0 122.0 124.0 122.0 122.0 123.0 123.0 124.0 124.0 122.0 16:49:34 INFO - PID 1983 | 16:49:34 INFO - PID 1983 | [#1] audio-beat-detection Cycles:10 Average:141.30 Median:140.00 stddev:30.37 (21.7%) stddev-sans-first:31.92 16:49:34 INFO - PID 1983 | Values: 153.0 115.0 122.0 201.0 127.0 119.0 122.0 157.0 181.0 116.0 16:49:34 INFO - PID 1983 | 16:49:34 INFO - PID 1983 | [#2] audio-dft Cycles:10 Average:214.50 Median:216.00 stddev:2.64 (1.2%) stddev-sans-first:2.74 16:49:34 INFO - PID 1983 | Values: 216.0 216.0 219.0 217.0 212.0 212.0 211.0 213.0 216.0 213.0 16:49:34 INFO - PID 1983 | 16:49:34 INFO - PID 1983 | [#3] audio-fft Cycles:10 Average:72.80 Median:66.00 stddev:13.41 (20.3%) stddev-sans-first:13.59 16:49:34 INFO - PID 1983 | Values: 84.0 63.0 65.0 102.0 66.0 64.0 67.0 66.0 87.0 64.0 16:49:34 INFO - PID 1983 | 16:49:34 INFO - PID 1983 | [#4] audio-oscillator Cycles:10 Average:82.10 Median:75.00 stddev:10.99 (14.7%) stddev-sans-first:9.56 16:49:34 INFO - PID 1983 | Values: 100.0 74.0 96.0 73.0 73.0 75.0 91.0 73.0 75.0 91.0 16:49:34 INFO - PID 1983 | 16:49:34 INFO - PID 1983 | [#5] imaging-gaussian-blur Cycles:10 Average:92.80 Median:93.00 stddev:1.03 (1.1%) stddev-sans-first:1.00 16:49:34 INFO - PID 1983 | Values: 94.0 93.0 92.0 93.0 92.0 93.0 95.0 92.0 92.0 92.0 16:49:34 INFO - PID 1983 | 16:49:34 INFO - PID 1983 | [#6] imaging-darkroom Cycles:10 Average:108.90 Median:115.50 stddev:19.99 (17.3%) stddev-sans-first:21.00 16:49:34 INFO - PID 1983 | Values: 101.0 98.0 100.0 101.0 99.0 130.0 159.0 101.0 100.0 100.0 16:49:34 INFO - PID 1983 | 16:49:34 INFO - PID 1983 | [#7] imaging-desaturate Cycles:10 Average:100.80 Median:101.50 stddev:1.23 (1.2%) stddev-sans-first:1.30 16:49:34 INFO - PID 1983 | Values: 101.0 102.0 98.0 100.0 101.0 102.0 101.0 102.0 100.0 101.0 16:49:34 INFO - PID 1983 | 16:49:34 INFO - PID 1983 | [#8] json-parse-financial Cycles:10 Average:51.30 Median:52.00 stddev:0.82 (1.6%) stddev-sans-first:0.87 16:49:34 INFO - PID 1983 | Values: 51.0 50.0 50.0 52.0 51.0 52.0 52.0 52.0 52.0 51.0 16:49:34 INFO - PID 1983 | 16:49:34 INFO - PID 1983 | [#9] json-stringify-tinderbox Cycles:10 Average:73.20 Median:74.00 stddev:11.55 (15.6%) stddev-sans-first:10.87 16:49:34 INFO - PID 1983 | Values: 58.0 69.0 62.0 78.0 70.0 69.0 64.0 85.0 95.0 82.0 16:49:34 INFO - PID 1983 | 16:49:34 INFO - PID 1983 | [#10] stanford-crypto-aes Cycles:10 Average:91.50 Median:92.00 stddev:2.17 (2.4%) stddev-sans-first:2.11 16:49:34 INFO - PID 1983 | Values: 89.0 91.0 95.0 88.0 92.0 92.0 91.0 93.0 90.0 94.0 16:49:34 INFO - PID 1983 | 16:49:34 INFO - PID 1983 | [#11] stanford-crypto-ccm Cycles:10 Average:170.20 Median:170.50 stddev:21.42 (12.6%) stddev-sans-first:21.90 16:49:34 INFO - PID 1983 | Values: 154.0 157.0 151.0 168.0 177.0 150.0 167.0 173.0 222.0 183.0 16:49:34 INFO - PID 1983 | 16:49:34 INFO - PID 1983 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:202.30 Median:202.50 stddev:5.12 (2.5%) stddev-sans-first:5.29 16:49:34 INFO - PID 1983 | Values: 199.0 196.0 215.0 204.0 203.0 204.0 200.0 202.0 201.0 199.0 16:49:34 INFO - PID 1983 | 16:49:34 INFO - PID 1983 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.50 Median:73.50 stddev:12.26 (16.7%) stddev-sans-first:12.70 16:49:34 INFO - PID 1983 | Values: 72.0 75.0 72.0 74.0 74.0 72.0 73.0 78.0 102.0 103.0 16:49:34 INFO - PID 1983 | -------- Summary: end -------- 16:49:34 INFO - PID 1983 | 16:49:35 INFO - TEST-INFO | 1983: exit 0 16:49:35 INFO - TEST-OK | kraken | took 63499ms 16:49:35 INFO - SUITE-END | took 1183s 16:49:36 INFO - Completed test suite (00:19:44) 16:49:36 INFO - javascript benchmark 16:49:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7654.346916089188, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3495.423255162898, 3497.195645001651, 3506.736994725396, 3541.397962346401, 3577.5885773136097, 6060.787872424466, 6135.416406698443, 6244.1258223848645, 6259.155014073099, 6259.217597800275, 6135.263768347793, 6138.602983404998, 6146.678464130211, 6191.938080619192, 6199.876002479948, 5392.703401312926, 5399.298091248133, 5405.675659460425, 5418.997485465182, 5501.257330260403, 447.2651543217645, 5311.9468805312035, 5378.946210537883, 5385.919211211835, 5499.7250137492965, 4493.393391892086, 5357.491038351351, 5371.462853714617, 5403.783848646089, 5477.972610136963, 5425.186222066682, 5435.076037073667, 5437.537809286216, 5443.237946687467, 5472.179173124063, 5379.731013449351, 5403.78384864605, 5412.0, 5415.9458405415835, 5458.153986132156, 6079.969600152015, 6113.141862489121, 6118.527810132473, 6146.0, 6252.3122456529745, 6581.7367305307735, 6626.966865165691, 6637.269900310962, 6648.534602577822, 6678.599284042973, 6635.0, 6722.226943901416, 6726.966365168192, 6736.8989465158065, 6749.0551322814845, 6139.3388173831, 6207.410296021871, 6250.593711408744, 6340.270868850049, 6357.523185761041, 6899.862002759916, 6905.516613836983, 6910.170779506391, 6910.827229319207, 6915.861682766417, 6466.0, 6483.4164925156965, 6490.4807615390655, 6499.252585952581, 6502.804915852532, 6415.390537898892, 6590.70341834609, 6671.699773510204, 6684.598924064571, 6698.765543206062, 6399.168108146005, 6417.294097649255, 6511.446527045254, 6535.607863528204, 6573.276939536647, 6566.704498297588, 6581.979793132072, 6592.274849766522, 6595.0766892635065, 6620.503462240351, 5261.500157485033, 5305.991861546294, 5323.414424413311, 5331.653442526223, 5373.650712703662, 4698.88252793676, 4709.293605959065, 4742.69172503793, 4743.0, 4771.117343291502, 6259.624422534663, 6267.2792628847355, 6270.529710271748, 6271.717772700239, 6296.244450665949, 6586.769463068769, 6704.229013663394, 6713.1944166700305, 6713.597184168965, 6734.326567343226, 6268.968655156695, 6336.714847831859, 6349.6507692077375, 6352.65060421672, 6353.205849268841, 5695.0, 5892.911606325909, 5893.675847828325, 5897.76408943646, 5910.083936989773, 6755.22314933779, 6784.796456106226, 6860.348266914635, 6915.204751453647, 6931.09895713554, 6642.701078451385, 6693.564918280296, 6701.865962680621, 6715.764948226789, 6730.966345168243, 6913.792586222421, 6934.791956241321, 6945.444364450933, 6948.756793512202, 6969.860602788017, 6359.268684101387, 6429.8714025720155, 6514.478841692748, 6562.639054851838, 6576.53964222519, 6663.633500157538, 6693.330666933268, 6697.3637504437, 6705.4635629150525, 6706.429953454011, 6420.357964203542, 6435.22777266731, 6469.838254043687, 6470.935290647221, 6480.513961452815, 6227.937720622917, 6287.1512345832725, 6459.9031014533875, 6480.351964803482, 6486.772962946275, 6342.682865856596, 6343.302236754045, 6345.0, 6352.460040896575, 6355.555111142361, 6335.65153916539, 6377.7767778127545, 6422.518311126778, 6450.41946224842, 6472.255690595453, 6273.560850740404, 6339.080833279241, 6348.365163483615, 6350.015747559136, 6371.299157092809], "value": 6047.155829881063, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [768.0, 807.0758980966795, 1011.7420057885238, 1134.7787181499605, 1153.527053907897, 36709.26581468369, 37269.44095838565, 37440.43839342412, 37544.43683344745, 37620.30708618112, 39583.80208098955, 39842.0, 39856.60143398565, 39888.80055599718, 39943.0, 6185.226846644169, 8451.070382257947, 8601.440906341099, 8610.784730381727, 8631.352648551352, 6247.062940558907, 8356.791080223013, 8534.018587862412, 8554.0, 8555.828883422328, 8579.270761985239, 8634.438761480484, 8637.222649961503, 8638.740837774876, 8672.262857657106, 8541.273991710712, 8585.785355366103, 8589.184027517376, 8611.56942152896, 8614.05245423003, 8451.873221901677, 8484.32125429964, 8490.745277641681, 8493.065762766091, 8511.74464766058, 37466.81266593649, 37662.81168594167, 37692.62307376919, 37715.434268485995, 37860.0, 3397.6092749333898, 3428.142964258935, 3431.3823511767873, 3439.2605589798313, 3440.3291358185165, 29756.0, 29892.402151957056, 29894.654740536727, 29911.551326730118, 29937.101886943427, 2768.892443022807, 3029.4849875521213, 3078.045805800209, 3119.906402807919, 3137.027521468352, 292358.9966652161, 296646.0, 300369.0, 302282.0, 303711.0, 39183.608163918, 39271.41092883609, 46532.0, 46551.30173047407, 46646.067078658234, 40435.39346909799, 48707.0, 49281.0, 49456.0, 49508.75245623749, 13944.16335019902, 14049.71900561983, 14057.0, 14059.015868889079, 14095.85904140966, 11366.431678416046, 11398.088152947745, 11408.88591114095, 11420.88579114215, 11431.428428578538, 12952.481900724055, 12956.870431295567, 12962.981386233198, 12975.286359250333, 13055.608331750063, 5695.145728140812, 5720.716683099827, 5752.539796816244, 5770.4806567408295, 5779.450952159538, 833.6415341403255, 997.1673652500243, 999.8801342496305, 1010.1262408017012, 1015.4212099103588, 23637.881810590836, 23735.525289494115, 23773.40566485817, 23778.167764128517, 23811.928463219196, 1073.3238060022293, 1096.6929259807266, 1113.3876367997568, 1124.2973141786383, 1126.3242054767238, 11077.889221107685, 11146.777064458665, 12094.0, 12206.694832629255, 12209.0, 386222.0, 387763.0, 387910.060449696, 388739.0, 389157.0, 52547.42357729274, 52742.47257527452, 52779.99999999923, 52792.928813186205, 52958.47041529613, 1482.7553453680239, 1528.3275358842075, 1535.3551508366513, 1538.6153461634592, 1542.4138827245574, 21025.84357860271, 21421.571568568852, 23333.649995249747, 23677.0, 23902.282931512083, 37553.2489350206, 37596.0, 37706.0, 37726.811365942995, 37814.43278350772, 330292.0, 331007.0, 331445.0, 332559.0, 332604.0, 25911.481770364866, 26413.075542355924, 28608.57087143736, 28873.0, 32881.0, 40722.61986956204, 40905.79547102245, 41552.79223603863, 41726.79136604298, 41944.74165775032, 26502.602460963488, 27664.631867447366, 27898.0, 29486.0, 29493.26266843346, 28261.434771304048, 28528.857355713088, 29053.564196536645, 29801.552976704934, 29970.850145749133, 33906.0, 34353.48469772905, 34419.483707744905, 34667.82666086754, 34673.0, 28009.719902800713, 28729.0, 29384.971525996487, 29428.852855735586, 29616.703832961393, 20706.792932070486, 21490.570188595826, 23213.41966450852, 23317.76682233156, 23320.0], "value": 17192.63221534672, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.6840448652296, 408.63631368082395, 431.58783361889397, 441.3757372867846, 441.6886095302812, 18620.90689546552, 20375.184992600298, 20905.686414703752, 21022.68465973008, 21039.948002599867, 17617.471475855746, 21256.8937155314, 21872.562548749014, 22618.54762904741, 22862.77137228627, 5326.334208223972, 6243.500519958415, 6253.874922501547, 6256.155419018442, 6258.968705156491, 6000.549958753089, 6079.0, 6205.81382558524, 6216.316205217422, 6313.589616674902, 6260.843478913018, 6266.843328916768, 6282.623042617434, 6284.277308109557, 6292.339304373044, 6127.816165515042, 6192.318844927055, 6205.472534834545, 6274.968625156891, 6285.591436556632, 5417.83746487606, 5505.009098362293, 5570.220169176318, 5570.5822063345, 5583.05088135018, 27359.316017099532, 27855.0, 27959.0, 28169.71830281712, 28683.56974645382, 2103.379503046605, 2174.9782502174935, 2175.3256490487997, 2177.150911144655, 2177.3467959612212, 17471.951682899067, 17663.293468261254, 18117.0, 18135.183916723778, 18397.35609253683, 2056.763472200701, 2065.8760474371434, 2130.1798807459086, 2133.445304220914, 2134.935951921445, 136061.0, 157504.21247893688, 159833.0, 160157.5740002898, 165978.0, 11672.0, 12309.32298723561, 12368.319742414255, 12382.0, 12397.132200745862, 16590.004599723812, 16591.502254932373, 16623.50129496117, 16657.0, 16657.25042373072, 7086.873412185574, 7509.436792240604, 8728.825423491495, 8933.862251577373, 9046.05016473274, 8597.0, 8608.612612432456, 8794.736157915273, 8803.295736341077, 8848.20366167048, 6361.491080713532, 7628.199039100926, 7667.731629393056, 7783.455158138989, 7843.137254901956, 1782.6612943540686, 2129.168914733328, 2134.6371116910163, 2138.946526336854, 2370.146747171017, 869.0787764969153, 908.1735620585235, 909.6497848328441, 946.068122898944, 948.9277116857907, 19719.507012324808, 20447.693284600744, 20471.38585842427, 20675.58648827045, 20744.096924213005, 831.7754206364248, 931.213124909464, 933.3373304953559, 960.6301573893961, 962.4610218277787, 10123.59505619767, 10499.842502362471, 10638.840417393745, 10642.201834862417, 10645.308054976556, 707419.0, 711559.0, 721898.0, 732073.0, 733334.3330434221, 125312.0, 157343.21328393286, 161541.0, 162871.0, 164339.0, 1147.208426185929, 1154.7575009248153, 1181.4860535667012, 1181.8818118188285, 1183.3373310945726, 15055.849441505883, 16597.0, 17816.10919453996, 17920.820791792437, 18219.089045547404, 55506.0, 62130.68934655298, 62189.68905155445, 62413.0, 62452.375476244655, 80281.19718802738, 81589.18410815815, 82933.0, 83528.58235708783, 85641.57179214065, 7287.8542429152185, 8248.54632995167, 8614.526201059001, 8953.910460895308, 9035.548222588975, 13059.673508162372, 13509.716576925177, 14386.280685965869, 14594.978351515292, 14866.256687165815, 11707.180497365443, 11953.820692689442, 12188.0, 12270.877291226974, 12356.0, 11133.387663678575, 11454.828177577176, 11586.536538538701, 11910.80891910802, 11931.522739090686, 10852.837207442053, 11946.940265298617, 11994.160408771302, 12222.0, 12332.81500777507, 8600.870986935326, 8938.37431379797, 8979.191872731486, 9046.592903319235, 9152.039035901134, 11641.126915481542, 11785.705357366134, 12158.270503769802, 12223.572174973833, 12244.632661020183], "value": 10476.867912288315, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1165.329935287209, 1347.6361382426744, 1404.3750531013698, 1416.376794210547, 1431.971360572788, 9754.756131096718, 10249.641262555811, 11495.252808567448, 11827.0, 11828.645140645773, 11400.973912347887, 11509.079273658108, 11566.826497602522, 11567.710807229823, 11573.594924177656, 2480.0823695232743, 2519.8740062996867, 2521.810864185184, 2530.0012989738175, 2530.8481491110592, 2132.2004248406847, 2217.5121473275854, 2222.4777177363308, 2222.9666555001686, 2223.3107736601623, 2161.7838216178334, 2209.723784526934, 2287.2223444029028, 2293.7018187635585, 2295.472041430472, 2480.5410998965067, 2498.7626175513296, 2514.57252267115, 2514.8994040238367, 2517.3832411059225, 2475.047106863852, 2477.7893879020303, 2478.0, 2481.8262721609494, 2482.8262021658493, 11359.318440893572, 11627.6511704649, 11627.94186029073, 11632.534698612046, 11642.6507204784, 6311.621302721851, 6531.615960174502, 6545.37818907203, 6550.868982620368, 6565.770198043093, 3947.723659343847, 4142.316517774563, 4317.201317756194, 4348.804303806336, 4361.323994780814, 1222.443788076423, 1224.8040313549877, 1237.461704158694, 1276.872312768725, 1280.775864223757, 16732.24704888283, 16783.992960422413, 16803.91598042002, 16807.0, 16827.326906923707, 5742.583163017997, 5912.363896203804, 5913.349531551526, 6058.787942421994, 6066.969665151646, 13752.0, 13771.173729576258, 13798.17210967345, 13808.0, 13812.93093534546, 6779.72881084761, 6795.966020169867, 6796.796096117125, 6796.898046529306, 6798.728050878009, 5995.940040599626, 6155.169052177988, 6235.00239961604, 6237.500999919996, 6247.875042499216, 4072.75563466193, 4371.584699453547, 4533.161365147458, 4557.225271703818, 4592.0, 1315.9736805263842, 1339.5378594384922, 1340.7653660609549, 1340.8994325425633, 1343.4223283987978, 651.8012006338113, 708.7554793596202, 729.9781006569812, 739.1979702023365, 770.5838847022545, 5858.8242352729485, 6101.298350689639, 6126.417990290915, 6171.722272497748, 6201.565890387629, 885.3669626217286, 891.148952750122, 900.2617853360182, 919.048784508031, 930.8968871886835, 3418.786330852561, 3435.3644575753565, 3610.2779444111284, 3723.8696645617274, 3769.0577355661085, 14933.17867517297, 14948.252587370587, 15293.888730897483, 15415.306311216022, 15468.92265538665, 15133.319000644997, 15393.923030384776, 15500.922495387676, 15511.146886921102, 15535.0, 10513.9474302628, 10960.643461655782, 11035.0, 11039.889601103887, 11044.558217671363, 6665.700043497957, 6715.597064176165, 6896.172459304916, 6915.239323674492, 6930.826729331606, 13086.280254586089, 13339.132956358193, 13366.9331653345, 13449.839287767563, 13842.031057826258, 13732.313384330942, 13839.86160138426, 13845.792313115513, 14048.929755351157, 14053.859461405254, 9162.816743664956, 9437.150656440954, 9531.237501000041, 9562.18721408688, 9572.856407153758, 10317.0, 10384.221183411426, 10535.525901334306, 10588.611644273758, 10683.51924163399, 8791.692290769792, 8840.646374144962, 8855.0, 8866.911330886609, 8906.332025098272, 8479.364047696325, 8633.481991080555, 8678.78303042429, 8746.169113934166, 8829.984551776628, 7787.688492460465, 7854.72508462201, 7866.842663146819, 7967.362610991223, 7981.042274927046, 5828.883422331445, 5874.2377286814635, 5956.255468066492, 6070.514358851369, 6076.848078798065, 4463.709858859163, 5898.675572843535, 6031.0, 6095.238095238095, 6240.4071613196675], "value": 5356.266639486743, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.3683516944288, 387.96702280306175, 399.5964076282954, 402.11534623827566, 403.796082978096, 4732.384789977298, 4732.455767586728, 5725.312962444513, 5763.39484354143, 5798.6230894991795, 5531.1426728857095, 5562.276904002474, 5595.888082238353, 5629.380768115514, 5707.543396528278, 3583.372909740785, 4377.387165796791, 4386.473623165225, 4390.3853460515475, 4408.20652282589, 3590.964090359089, 4253.234417804794, 4262.744235345874, 4402.427684401024, 4440.422745043156, 3350.5476760637366, 3446.758726889119, 3541.6812486876174, 3886.6979561846815, 4350.7607081610495, 3766.642168993941, 4174.08170202555, 4328.39402483651, 4341.522432532419, 4354.325079612665, 3379.6113446953423, 3870.554886188096, 3887.08653466438, 3901.0, 3924.4898163238595, 4966.354373931365, 5254.789808407659, 5335.253064570924, 5518.558515318765, 5539.750711217965, 5167.819126330561, 5170.922436163461, 5185.870353241199, 5186.351706036746, 5188.0, 4619.145458090264, 5562.193481945095, 6004.759809607611, 6013.579049466539, 6030.517558595302, 4071.9389209161886, 4104.91790164195, 4105.856295029689, 4114.156597897424, 4114.91770164598, 5768.067697321444, 6204.348543402923, 6311.305756366796, 6314.368563143741, 6321.87356252877, 3941.507311586052, 4165.812538435777, 4182.1008483176265, 4191.637717741746, 4200.6185021058345, 4189.72766770159, 4238.809253583596, 4241.130568233505, 4254.510731265882, 4264.552222016706, 4227.027783609787, 4792.281157826354, 4846.733429661403, 4898.8775280618265, 4923.261510773341, 5675.943240567624, 6023.69881505923, 6029.88447137281, 6038.486728628028, 6039.36937027009, 1278.520554791953, 1402.6072699644117, 1439.4098419647894, 1462.9634259143393, 1464.2751837840337, 940.4263399326405, 970.2141265574907, 978.9755256118654, 982.6020461713018, 984.3404918704485, 5034.953852918931, 5244.947550524446, 5707.885842283131, 5724.252358867767, 5758.942410575924, 4770.212914869007, 5521.641093328921, 6236.656983865931, 6237.251529816361, 6243.8126856194385, 5368.865778355573, 5686.459786320293, 5752.654840709571, 5761.596688231783, 5793.088280867304, 2667.853268070236, 3269.983650081734, 3275.6396796352383, 3280.671932806748, 3285.6878596533293, 4581.702189357681, 5902.6458412495385, 6068.029115341524, 6456.903146452807, 6514.934850651528, 6344.0, 6349.174607301114, 6356.618602883842, 6358.268799088071, 6364.331745166691, 5766.94233057664, 6284.0, 6284.4344009039405, 6397.4562162216735, 6421.871562568723, 5003.749812509433, 5852.824415267549, 5919.852003699942, 6220.968895155676, 6292.339304372975, 5811.80857924133, 6074.42292982166, 6219.937800621936, 6231.345708700521, 6231.844203894939, 4866.172750632471, 6268.247810262797, 6447.580907241015, 6499.252585952676, 6518.445932095825, 4019.954811748908, 4101.569335219879, 4116.711830171859, 4126.91746165069, 4130.731502452331, 5615.298087739033, 5715.0, 6211.534134939807, 6215.875682486416, 6217.782377616762, 4980.377452818398, 5179.352580927384, 5359.901220249917, 6050.45545900871, 6071.2714474263375, 5096.872578185575, 6042.274927008787, 6057.939860524302, 6064.848378790565, 6083.878322433615, 5088.422588997422, 5876.6180198288685, 5879.823605291848, 5924.8815023700145, 5933.881322373615, 5437.564994800486, 5479.397266300624, 5988.790392336247, 6005.948958932257, 6040.546958978006, 5745.368009519033, 5849.736761845812, 5903.26209223847, 5904.793332233523, 5907.409259074058], "value": 4404.19909858541, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [801.2748462641308, 823.3989187892838, 833.2584000239788, 837.656560810068, 844.2865778417236, 15742.370305187793, 16180.83819161808, 17575.85756925799, 18473.0, 18594.442166734985, 17535.2109155088, 17604.559886002826, 17618.647627047485, 18170.45488635343, 18183.727244091348, 975.7902051059039, 992.9950350248275, 996.3424140067557, 996.9501524923761, 997.6109317366233, 962.7256231973889, 989.1789814453986, 990.782027953849, 997.6957028106425, 1015.1320620869161, 1056.6724315462193, 1060.512164404375, 1062.5962134388922, 1064.138048180972, 1064.7551063255455, 1054.2672842374552, 1054.9261551691384, 1056.8361903904868, 1057.265200685524, 1058.7617785998088, 753.932146106853, 780.9609519524001, 782.284209947897, 782.581318994341, 784.9254320839511, 16092.034477931362, 16404.67190656192, 16421.507354779376, 16601.91699041509, 16683.83316166835, 14554.417823287056, 14637.487687930978, 14700.55898323052, 14778.334974926047, 14797.92601036988, 12999.0, 13045.282509461977, 13172.341382930816, 13208.537701180412, 13297.135686180367, 3263.494158405451, 3304.6695330467, 3323.501474778778, 3331.3670402623425, 3343.0472315389998, 100448.0, 103499.48250258777, 103754.0, 105421.47289263581, 108296.45851770691, 11338.489767960462, 11479.655610331703, 11532.566093317157, 11721.589744359077, 12227.449764760608, 24005.0, 24266.15068472595, 24414.633780493306, 24486.632700509508, 24549.754502454747, 7618.961905190438, 7696.923030769621, 7782.64978075988, 7805.453618246782, 7957.482763620346, 4094.5086589609436, 4129.050318426779, 4132.950835888776, 4179.665626749853, 4312.223799716081, 4130.855420060284, 4248.235317642854, 4249.893752656147, 4251.383549385322, 4252.255855225385, 863.6156910174911, 938.1931538876561, 945.0643862576001, 946.5267366316841, 947.6162154327509, 556.785637529554, 611.8225714542822, 640.414021170633, 642.8071578526424, 656.7143292667705, 9463.905360946303, 10093.192544596415, 10118.443485608215, 10274.0, 10319.1744660427, 586.9677167755815, 608.9474546890451, 624.1823211592828, 643.9645819479973, 649.399305642279, 4553.362529245908, 4728.078024785203, 4735.905281894343, 4752.619790416758, 4836.927446088312, 81687.4229101176, 104754.0, 107042.0, 109380.0, 111510.0, 92902.67743306472, 94552.52723736475, 94714.52642736743, 95423.52288238515, 96155.51922240484, 76276.84677838694, 77465.0, 77637.05907352362, 77983.8302425464, 78156.0, 9318.62725491, 9323.813523729623, 9329.580168892551, 9359.438433694, 9470.337076404585, 31006.53490197694, 40694.1861162781, 41058.0, 41373.96565085777, 41516.58483415248, 55534.44465555293, 64250.0, 66504.34469587082, 67779.66110169615, 69154.65422672854, 12740.426680799572, 12772.936135319263, 12775.61673149807, 13228.470861165446, 13292.468301267842, 14273.785893211818, 14316.06945548536, 14499.347529360994, 14684.0, 15087.547373578811, 13429.932850335686, 13622.659433514242, 13634.86365136336, 13942.930285348913, 14189.148651080555, 13543.458261669817, 13607.0, 13624.65938351549, 13970.231637260049, 14226.857731422553, 12846.678833028875, 12962.287074211008, 13396.93301533486, 13418.261995590336, 13433.66415839612, 9437.292203084658, 9448.622055117718, 9703.320767546204, 9915.553800079158, 10167.389956602627, 10773.89226107729, 11042.396688472038, 11129.610463633733, 11227.04570111517, 11409.942950285196], "value": 7826.389219896451, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1603.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 122.0, 124.0, 122.0, 122.0, 123.0, 123.0, 124.0, 124.0, 122.0], "value": 122.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 115.0, 122.0, 201.0, 127.0, 119.0, 122.0, 157.0, 181.0, 116.0], "value": 141.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [216.0, 216.0, 219.0, 217.0, 212.0, 212.0, 211.0, 213.0, 216.0, 213.0], "value": 214.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 63.0, 65.0, 102.0, 66.0, 64.0, 67.0, 66.0, 87.0, 64.0], "value": 72.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 74.0, 96.0, 73.0, 73.0, 75.0, 91.0, 73.0, 75.0, 91.0], "value": 82.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 92.0, 93.0, 92.0, 93.0, 95.0, 92.0, 92.0, 92.0], "value": 92.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 98.0, 100.0, 101.0, 99.0, 130.0, 159.0, 101.0, 100.0, 100.0], "value": 108.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 102.0, 98.0, 100.0, 101.0, 102.0, 101.0, 102.0, 100.0, 101.0], "value": 100.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 50.0, 52.0, 51.0, 52.0, 52.0, 52.0, 52.0, 51.0], "value": 51.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 69.0, 62.0, 78.0, 70.0, 69.0, 64.0, 85.0, 95.0, 82.0], "value": 73.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 91.0, 95.0, 88.0, 92.0, 92.0, 91.0, 93.0, 90.0, 94.0], "value": 91.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 157.0, 151.0, 168.0, 177.0, 150.0, 167.0, 173.0, 222.0, 183.0], "value": 170.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 196.0, 215.0, 204.0, 203.0, 204.0, 200.0, 202.0, 201.0, 199.0], "value": 202.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 75.0, 72.0, 74.0, 74.0, 72.0, 73.0, 78.0, 102.0, 103.0], "value": 79.5, "unit": "score"}]}]} 16:49:36 INFO - Return code: 0 16:49:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:49:36 ERROR - # TBPL SUCCESS # 16:49:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:49:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:49:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:49:36 INFO - 2017-04-06 16:49:36.371 screenresolution[1987:12743] starting screenresolution argv=screenresolution get 16:49:36 INFO - 2017-04-06 16:49:36.374 screenresolution[1987:12743] Display 0: 1600x1200x32@60 16:49:36 INFO - 2017-04-06 16:49:36.380 screenresolution[1988:12746] starting screenresolution argv=screenresolution list 16:49:36 INFO - Available Modes on Display 0 16:49:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:49:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:49:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:49:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:49:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:49:36 INFO - Intel Iris: 16:49:36 INFO - Chipset Model: Intel Iris 16:49:36 INFO - Type: GPU 16:49:36 INFO - Bus: Built-In 16:49:36 INFO - VRAM (Dynamic, Max): 1536 MB 16:49:36 INFO - Vendor: Intel (0x8086) 16:49:36 INFO - Device ID: 0x0a2e 16:49:36 INFO - Revision ID: 0x0009 16:49:36 INFO - Displays: 16:49:36 INFO - Display: 16:49:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:49:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:49:36 INFO - Display Serial Number: 1600x1200 60 16:49:36 INFO - Main Display: Yes 16:49:36 INFO - Mirror: Off 16:49:36 INFO - Online: Yes 16:49:36 INFO - Rotation: Supported 16:49:36 INFO - Return code: 0 16:49:36 INFO - Running post-action listener: _resource_record_post_action 16:49:36 INFO - [mozharness: 2017-04-06 23:49:36.617263Z] Finished run-tests step (success) 16:49:36 INFO - Running post-run listener: _resource_record_post_run 16:49:36 INFO - Total resource usage - Wall time: 1204s; CPU: 53.0%; Read bytes: 471040; Write bytes: 517571584; Read time: 10; Write time: 1642 16:49:36 INFO - TinderboxPrint: CPU usage
53.3% 16:49:36 INFO - TinderboxPrint: I/O read bytes / time
471,040 / 10 16:49:36 INFO - TinderboxPrint: I/O write bytes / time
517,571,584 / 1,642 16:49:36 INFO - TinderboxPrint: CPU idle
2,249.2 (46.7%) 16:49:36 INFO - TinderboxPrint: CPU system
956.0 (19.8%) 16:49:36 INFO - TinderboxPrint: CPU user
1,612.3 (33.5%) 16:49:36 INFO - TinderboxPrint: Swap in / out
207,851,520 / 0 16:49:36 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 193720832; Write bytes: 191537152; Read time: 14931; Write time: 360 16:49:36 INFO - run-tests - Wall time: 1185s; CPU: 53.0%; Read bytes: 471040; Write bytes: 309191680; Read time: 10; Write time: 1232 16:49:37 INFO - Running post-run listener: _upload_blobber_files 16:49:37 INFO - Blob upload gear active. 16:49:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:49:37 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:49: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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:49: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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:49:37 INFO - (blobuploader) - INFO - Open directory for files ... 16:49:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:49:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:49:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:49:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:49:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:49:38 INFO - (blobuploader) - INFO - Done attempting. 16:49:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:49:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:49:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:49:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:49:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:49:39 INFO - (blobuploader) - INFO - Done attempting. 16:49:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:49:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:49:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:49:40 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:49:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:49:40 INFO - (blobuploader) - INFO - Done attempting. 16:49:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:49:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:49:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:49:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:49:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:49:43 INFO - (blobuploader) - INFO - Done attempting. 16:49:43 INFO - (blobuploader) - INFO - Iteration through files over. 16:49:43 INFO - Return code: 0 16:49:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:49:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:49:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a6c2045e42710bb31bb42bb5f8eee3fb6234739fca2b366c5e259fab7e5c557e2b4bd5fe6b21645f8e2dbf4fed003e07d22f5cd756394846320b607dfaef86a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cfac94350c72e4fa8abbe070b56fa5c432878b1d153c051309306e076b296614c07ae778e01465af86fc107226c4a92143e6d1c1d1881a9472bd15a00a4cacf9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57b16c4a34f68dc4edda4156fe2dc978403a26af851212822dc2ee153a76dee0cba8ee9ea00c0b31c6b96e6958f9d53ebc9983af19a8cabfa426ba61148b76fe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/74f5d4b33994779cd058bfede74da98b2f8483bcaacf3e03548535769d0b47583742beb0f4474ea22d7779542afb1bd4cf34f311cdaa68cbbed7cf4226c6b266"} 16:49:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:49:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:49:43 INFO - Contents: 16:49:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a6c2045e42710bb31bb42bb5f8eee3fb6234739fca2b366c5e259fab7e5c557e2b4bd5fe6b21645f8e2dbf4fed003e07d22f5cd756394846320b607dfaef86a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cfac94350c72e4fa8abbe070b56fa5c432878b1d153c051309306e076b296614c07ae778e01465af86fc107226c4a92143e6d1c1d1881a9472bd15a00a4cacf9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57b16c4a34f68dc4edda4156fe2dc978403a26af851212822dc2ee153a76dee0cba8ee9ea00c0b31c6b96e6958f9d53ebc9983af19a8cabfa426ba61148b76fe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/74f5d4b33994779cd058bfede74da98b2f8483bcaacf3e03548535769d0b47583742beb0f4474ea22d7779542afb1bd4cf34f311cdaa68cbbed7cf4226c6b266"} 16:49:43 INFO - Running post-run listener: copy_logs_to_upload_dir 16:49:43 INFO - Copying logs to upload dir... 16:49:43 INFO - mkdir: /builds/slave/test/build/upload/logs 16:49:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.877874 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-04-06 16:49:43.111424) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 16:49:43.114660) ========= 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.cXbdiUM1IT/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.XNwtwLRvYw/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a6c2045e42710bb31bb42bb5f8eee3fb6234739fca2b366c5e259fab7e5c557e2b4bd5fe6b21645f8e2dbf4fed003e07d22f5cd756394846320b607dfaef86a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cfac94350c72e4fa8abbe070b56fa5c432878b1d153c051309306e076b296614c07ae778e01465af86fc107226c4a92143e6d1c1d1881a9472bd15a00a4cacf9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57b16c4a34f68dc4edda4156fe2dc978403a26af851212822dc2ee153a76dee0cba8ee9ea00c0b31c6b96e6958f9d53ebc9983af19a8cabfa426ba61148b76fe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/74f5d4b33994779cd058bfede74da98b2f8483bcaacf3e03548535769d0b47583742beb0f4474ea22d7779542afb1bd4cf34f311cdaa68cbbed7cf4226c6b266"} build_url:https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011408 build_url: 'https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a6c2045e42710bb31bb42bb5f8eee3fb6234739fca2b366c5e259fab7e5c557e2b4bd5fe6b21645f8e2dbf4fed003e07d22f5cd756394846320b607dfaef86a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cfac94350c72e4fa8abbe070b56fa5c432878b1d153c051309306e076b296614c07ae778e01465af86fc107226c4a92143e6d1c1d1881a9472bd15a00a4cacf9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57b16c4a34f68dc4edda4156fe2dc978403a26af851212822dc2ee153a76dee0cba8ee9ea00c0b31c6b96e6958f9d53ebc9983af19a8cabfa426ba61148b76fe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/74f5d4b33994779cd058bfede74da98b2f8483bcaacf3e03548535769d0b47583742beb0f4474ea22d7779542afb1bd4cf34f311cdaa68cbbed7cf4226c6b266"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 16:49:43.153823) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 16:49:43.154384) ========= 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.cXbdiUM1IT/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.XNwtwLRvYw/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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.005334 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 16:49:43.203072) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 16:49:43.206483) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 16:49:43.206872) ========= ========= Total master_lag: 0.20 =========