builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0309 starttime: 1471305050.92 results: success (0) buildid: 20160815140817 builduid: 5a8d5c719c27437c810c60667552cf8e revision: 7b74ee1d97dbe7afa04e6b522174be2c529349b9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-15 16:50:50.924573) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-15 16:50:50.924968) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-15 16:50:50.925238) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-15 16:50:50.949612) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 16:50:50.949886) ========= 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.e9vNNjGJHm/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.obua2dfv2i/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-15 16:50:50-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 35.0M=0s 2016-08-15 16:50:51 (35.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.126711 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 16:50:51.098827) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 16:50:51.099138) ========= 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.e9vNNjGJHm/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.obua2dfv2i/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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.026529 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 16:50:51.151600) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-08-15 16:50:51.152025) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7b74ee1d97dbe7afa04e6b522174be2c529349b9 --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 7b74ee1d97dbe7afa04e6b522174be2c529349b9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.e9vNNjGJHm/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.obua2dfv2i/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-15 16:50:51,237 truncating revision to first 12 chars 2016-08-15 16:50:51,237 Setting DEBUG logging. 2016-08-15 16:50:51,237 attempt 1/10 2016-08-15 16:50:51,237 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7b74ee1d97db?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-15 16:50:51,915 unpacking tar archive at: mozilla-central-7b74ee1d97db/testing/mozharness/ program finished with exit code 0 elapsedTime=0.941665 ========= master_lag: 2.42 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-08-15 16:50:54.515542) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-15 16:50:54.515874) ========= script_repo_revision: 7b74ee1d97dbe7afa04e6b522174be2c529349b9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-15 16:50:54.516259) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-15 16:50:54.516522) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-15 16:50:54.543959) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-15 16:50:54.544226) ========= /tools/buildbot/bin/python 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', '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.e9vNNjGJHm/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.obua2dfv2i/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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:50:54 INFO - MultiFileLogger online at 20160815 16:50:54 in /builds/slave/test 16:50:54 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:50:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:50:54 INFO - {'append_to_log': False, 16:50:54 INFO - 'base_work_dir': '/builds/slave/test', 16:50:54 INFO - 'blob_upload_branch': 'Firefox', 16:50:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:50:54 INFO - 'branch': 'Firefox', 16:50:54 INFO - 'buildbot_json_path': 'buildprops.json', 16:50:54 INFO - 'config_files': ('talos/mac_config.py',), 16:50:54 INFO - 'default_actions': ('clobber', 16:50:54 INFO - 'read-buildbot-config', 16:50:54 INFO - 'download-and-extract', 16:50:54 INFO - 'populate-webroot', 16:50:54 INFO - 'create-virtualenv', 16:50:54 INFO - 'install', 16:50:54 INFO - 'run-tests'), 16:50:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:50:54 INFO - 'download_minidump_stackwalk': True, 16:50:54 INFO - 'download_symbols': 'ondemand', 16:50:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:50:54 INFO - 'tooltool.py': '/tools/tooltool.py', 16:50:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:50:54 INFO - '/tools/misc-python/virtualenv.py')}, 16:50:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:50:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:50:54 INFO - 'installer_path': 'installer.exe', 16:50:54 INFO - 'log_level': 'info', 16:50:54 INFO - 'log_name': 'talos', 16:50:54 INFO - 'log_to_console': True, 16:50:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:50:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:50:54 INFO - 'opt_config_files': (), 16:50:54 INFO - 'pip_index': False, 16:50:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:50:54 INFO - 'cmd': ('bash', 16:50:54 INFO - '-c', 16:50:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:50:54 INFO - 'enabled': True, 16:50:54 INFO - 'halt_on_failure': False, 16:50:54 INFO - 'name': 'check_screen_resolution'},), 16:50:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:50:54 INFO - 'cmd': ('bash', 16:50:54 INFO - '-c', 16:50:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:50:54 INFO - 'enabled': True, 16:50:54 INFO - 'halt_on_failure': False, 16:50:54 INFO - 'name': 'check_screen_resolution'},), 16:50:54 INFO - 'run_cmd_checks_enabled': True, 16:50:54 INFO - 'sps_profile': False, 16:50:54 INFO - 'sps_profile_interval': 0, 16:50:54 INFO - 'suite': 'dromaeojs', 16:50:54 INFO - 'system_bits': '32', 16:50:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:50:54 INFO - 'title': 't-yosemite-r7-0309', 16:50:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:50:54 INFO - 'use_talos_json': True, 16:50:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:50:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:50:54 INFO - 'work_dir': 'build'} 16:50:54 INFO - [mozharness: 2016-08-15 23:50:54.741707Z] Running clobber step. 16:50:54 INFO - Running pre-action listener: _resource_record_pre_action 16:50:54 INFO - Running main action method: clobber 16:50:54 INFO - rmtree: /builds/slave/test/build 16:50:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:50:56 INFO - Running post-action listener: _resource_record_post_action 16:50:56 INFO - [mozharness: 2016-08-15 23:50:56.716803Z] Finished clobber step (success) 16:50:56 INFO - [mozharness: 2016-08-15 23:50:56.717041Z] Running read-buildbot-config step. 16:50:56 INFO - Running pre-action listener: _resource_record_pre_action 16:50:56 INFO - Running main action method: read_buildbot_config 16:50:56 INFO - Using buildbot properties: 16:50:56 INFO - { 16:50:56 INFO - "project": "", 16:50:56 INFO - "product": "firefox", 16:50:56 INFO - "script_repo_revision": "production", 16:50:56 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 16:50:56 INFO - "repository": "", 16:50:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 16:50:56 INFO - "buildid": "20160815140817", 16:50:56 INFO - "pgo_build": "False", 16:50:56 INFO - "basedir": "/builds/slave/test", 16:50:56 INFO - "buildnumber": 43, 16:50:56 INFO - "slavename": "t-yosemite-r7-0309", 16:50:56 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 16:50:56 INFO - "platform": "yosemite_r7", 16:50:56 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 16:50:56 INFO - "branch": "mozilla-central", 16:50:56 INFO - "repo_path": "mozilla-central", 16:50:56 INFO - "revision": "7b74ee1d97dbe7afa04e6b522174be2c529349b9", 16:50:56 INFO - "stage_platform": "macosx64", 16:50:56 INFO - "builduid": "5a8d5c719c27437c810c60667552cf8e", 16:50:56 INFO - "slavebuilddir": "test" 16:50:56 INFO - } 16:50:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/TU43vLrZSzaf1gBv1xBHog/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 16:50:56 INFO - Running post-action listener: _resource_record_post_action 16:50:56 INFO - [mozharness: 2016-08-15 23:50:56.723037Z] Finished read-buildbot-config step (success) 16:50:56 INFO - [mozharness: 2016-08-15 23:50:56.723230Z] Running download-and-extract step. 16:50:56 INFO - Running pre-action listener: _resource_record_pre_action 16:50:56 INFO - Running main action method: download_and_extract 16:50:56 INFO - mkdir: /builds/slave/test/build/tests 16:50:56 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:50:56 INFO - trying https://queue.taskcluster.net/v1/task/TU43vLrZSzaf1gBv1xBHog/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 16:50:56 INFO - Downloading https://queue.taskcluster.net/v1/task/TU43vLrZSzaf1gBv1xBHog/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 16:50:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TU43vLrZSzaf1gBv1xBHog/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 16:50:57 INFO - Downloaded 1199 bytes. 16:50:57 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 16:50:57 INFO - Using the following test package requirements: 16:50:57 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 16:50:57 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:50:57 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 16:50:57 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:50:57 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 16:50:57 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:50:57 INFO - u'jsshell-mac.zip'], 16:50:57 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:50:57 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 16:50:57 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 16:50:57 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:50:57 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 16:50:57 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:50:57 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 16:50:57 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:50:57 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 16:50:57 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:50:57 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 16:50:57 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:50:57 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:50:57 INFO - trying https://queue.taskcluster.net/v1/task/TU43vLrZSzaf1gBv1xBHog/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:50:57 INFO - Downloading https://queue.taskcluster.net/v1/task/TU43vLrZSzaf1gBv1xBHog/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:50:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TU43vLrZSzaf1gBv1xBHog/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:50:58 INFO - Downloaded 16331560 bytes. 16:50:58 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 16:51:05 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:51:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:51:05 INFO - trying https://queue.taskcluster.net/v1/task/TU43vLrZSzaf1gBv1xBHog/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:51:05 INFO - Downloading https://queue.taskcluster.net/v1/task/TU43vLrZSzaf1gBv1xBHog/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:51:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TU43vLrZSzaf1gBv1xBHog/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:51:06 INFO - Downloaded 16331560 bytes. 16:51:06 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 16:51:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:51:12 INFO - trying https://queue.taskcluster.net/v1/task/TU43vLrZSzaf1gBv1xBHog/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 16:51:12 INFO - Downloading https://queue.taskcluster.net/v1/task/TU43vLrZSzaf1gBv1xBHog/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 16:51:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TU43vLrZSzaf1gBv1xBHog/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:51:13 INFO - Downloaded 14413719 bytes. 16:51:13 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 16:51:14 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:51:14 INFO - trying https://queue.taskcluster.net/v1/task/TU43vLrZSzaf1gBv1xBHog/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:51:14 INFO - Downloading https://queue.taskcluster.net/v1/task/TU43vLrZSzaf1gBv1xBHog/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 16:51:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TU43vLrZSzaf1gBv1xBHog/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 16:51:16 INFO - Downloaded 111033325 bytes. 16:51:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TU43vLrZSzaf1gBv1xBHog/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:51:16 INFO - mkdir: /builds/slave/test/properties 16:51:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:51:16 INFO - Writing to file /builds/slave/test/properties/build_url 16:51:16 INFO - Contents: 16:51:16 INFO - build_url:https://queue.taskcluster.net/v1/task/TU43vLrZSzaf1gBv1xBHog/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:51:17 INFO - Running post-action listener: _resource_record_post_action 16:51:17 INFO - Running post-action listener: set_extra_try_arguments 16:51:17 INFO - [mozharness: 2016-08-15 23:51:17.649492Z] Finished download-and-extract step (success) 16:51:17 INFO - [mozharness: 2016-08-15 23:51:17.649615Z] Running populate-webroot step. 16:51:17 INFO - Running pre-action listener: _resource_record_pre_action 16:51:17 INFO - Running main action method: populate_webroot 16:51:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:51:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:51:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:51:17 INFO - '--tppagecycles', 16:51:17 INFO - '7'], 16:51:17 INFO - 'tests': ['tsvgm']}}, 16:51:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:51:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:51:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:51:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:51:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:51:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:51:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:51:17 INFO - 'talos_options': ['--disable-e10s'], 16:51:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:51:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:51:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:51:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:51:17 INFO - 'talos_options': ['--disable-e10s'], 16:51:17 INFO - 'tests': ['damp', 'tps']}, 16:51:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:51:17 INFO - 'tests': ['damp', 'tps']}, 16:51:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:51:17 INFO - 'tests': ['dromaeo_dom']}, 16:51:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:51:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:51:17 INFO - 'tests': ['basic_compositor_video']}, 16:51:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:51:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:51:17 INFO - 'tests': ['a11yr', 16:51:17 INFO - 'ts_paint', 16:51:17 INFO - 'tpaint', 16:51:17 INFO - 'sessionrestore', 16:51:17 INFO - 'sessionrestore_no_auto_restore', 16:51:17 INFO - 'tabpaint']}, 16:51:17 INFO - 'other-e10s': {'tests': ['a11yr', 16:51:17 INFO - 'ts_paint', 16:51:17 INFO - 'tpaint', 16:51:17 INFO - 'sessionrestore', 16:51:17 INFO - 'sessionrestore_no_auto_restore', 16:51:17 INFO - 'tabpaint']}, 16:51:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:51:17 INFO - 'ts_paint', 16:51:17 INFO - 'tpaint', 16:51:17 INFO - 'sessionrestore', 16:51:17 INFO - 'sessionrestore_no_auto_restore', 16:51:17 INFO - 'tabpaint']}, 16:51:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:51:17 INFO - 'ts_paint', 16:51:17 INFO - 'tpaint', 16:51:17 INFO - 'sessionrestore', 16:51:17 INFO - 'sessionrestore_no_auto_restore', 16:51:17 INFO - 'tabpaint']}, 16:51:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:51:17 INFO - 'tests': ['a11yr', 16:51:17 INFO - 'ts_paint', 16:51:17 INFO - 'tpaint', 16:51:17 INFO - 'sessionrestore', 16:51:17 INFO - 'sessionrestore_no_auto_restore', 16:51:17 INFO - 'tabpaint']}, 16:51:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:51:17 INFO - 'tests': ['a11yr', 16:51:17 INFO - 'ts_paint', 16:51:17 INFO - 'tpaint', 16:51:17 INFO - 'sessionrestore', 16:51:17 INFO - 'sessionrestore_no_auto_restore', 16:51:17 INFO - 'tabpaint']}, 16:51:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:51:17 INFO - 'tests': ['tsvgx', 16:51:17 INFO - 'tsvgr_opacity', 16:51:17 INFO - 'tart', 16:51:17 INFO - 'tscrollx', 16:51:17 INFO - 'cart']}, 16:51:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:51:17 INFO - 'tsvgr_opacity', 16:51:17 INFO - 'tart', 16:51:17 INFO - 'tscrollx', 16:51:17 INFO - 'cart']}, 16:51:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:51:17 INFO - 'talos_options': ['--disable-e10s'], 16:51:17 INFO - 'tests': ['tp5o']}, 16:51:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:51:17 INFO - 'tests': ['tp5o']}, 16:51:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:51:17 INFO - 'talos_options': ['--disable-e10s', 16:51:17 INFO - '--xperf_path', 16:51:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:51:17 INFO - 'tests': ['tp5n']}, 16:51:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:51:17 INFO - 'talos_options': ['--xperf_path', 16:51:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:51:17 INFO - 'tests': ['tp5n']}}, 16:51:17 INFO - 'talos.zip': {'path': '', 16:51:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:51:17 INFO - Running post-action listener: _resource_record_post_action 16:51:17 INFO - [mozharness: 2016-08-15 23:51:17.668658Z] Finished populate-webroot step (success) 16:51:17 INFO - [mozharness: 2016-08-15 23:51:17.668775Z] Running create-virtualenv step. 16:51:17 INFO - Running pre-action listener: _resource_record_pre_action 16:51:17 INFO - Running main action method: create_virtualenv 16:51:17 INFO - Creating virtualenv /builds/slave/test/build/venv 16:51:17 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:51:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:51:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:51:17 INFO - Using real prefix '/tools/python27' 16:51:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:51:18 INFO - Installing distribute.............................................................................................................................................................................................done. 16:51:21 INFO - Installing pip.................done. 16:51:21 INFO - Return code: 0 16:51:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:51:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:51:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:51:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:51:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:51:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10510fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051a4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9ba57b610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105073d30>, '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 0x104dd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10506bb20>, '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.e9vNNjGJHm/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.obua2dfv2i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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:51:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:51:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:51:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e9vNNjGJHm/Render', 16:51:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:51:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:51:21 INFO - 'HOME': '/Users/cltbld', 16:51:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:51:21 INFO - 'LOGNAME': 'cltbld', 16:51:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:51:21 INFO - 'MOZ_NO_REMOTE': '1', 16:51:21 INFO - 'NO_EM_RESTART': '1', 16:51:21 INFO - 'PAGER': '/bin/cat', 16:51:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:51:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:51:21 INFO - 'PWD': '/builds/slave/test', 16:51:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:51:21 INFO - 'SHELL': '/bin/bash', 16:51:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.obua2dfv2i/Listeners', 16:51:21 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 16:51:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:51:21 INFO - 'USER': 'cltbld', 16:51:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:51:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:51:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:51:21 INFO - 'XPC_FLAGS': '0x0', 16:51:21 INFO - 'XPC_SERVICE_NAME': '0', 16:51:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:51:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:51:21 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:51:21 INFO - Cleaning up... 16:51:21 INFO - Return code: 0 16:51:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:51:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:51:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:51:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:51:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:51:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10510fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051a4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9ba57b610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105073d30>, '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 0x104dd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10506bb20>, '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.e9vNNjGJHm/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.obua2dfv2i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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:51:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:51:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:51:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e9vNNjGJHm/Render', 16:51:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:51:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:51:21 INFO - 'HOME': '/Users/cltbld', 16:51:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:51:21 INFO - 'LOGNAME': 'cltbld', 16:51:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:51:21 INFO - 'MOZ_NO_REMOTE': '1', 16:51:21 INFO - 'NO_EM_RESTART': '1', 16:51:21 INFO - 'PAGER': '/bin/cat', 16:51:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:51:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:51:21 INFO - 'PWD': '/builds/slave/test', 16:51:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:51:21 INFO - 'SHELL': '/bin/bash', 16:51:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.obua2dfv2i/Listeners', 16:51:21 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 16:51:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:51:21 INFO - 'USER': 'cltbld', 16:51:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:51:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:51:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:51:21 INFO - 'XPC_FLAGS': '0x0', 16:51:21 INFO - 'XPC_SERVICE_NAME': '0', 16:51:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:51:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:51:21 INFO - Downloading/unpacking psutil>=3.1.1 16:51:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:51:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:51:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:51:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:51:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:51:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:51:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:51:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:51:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:51:24 INFO - Installing collected packages: psutil 16:51:24 INFO - Running setup.py install for psutil 16:51:24 INFO - building 'psutil._psutil_osx' extension 16:51:24 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:51:25 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:51:25 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:51:25 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:51:25 INFO - building 'psutil._psutil_posix' extension 16:51:25 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:51:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:51:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:51:25 INFO - ^ 16:51:25 INFO - 1 warning generated. 16:51:25 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:51:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:51:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:51:25 INFO - Successfully installed psutil 16:51:25 INFO - Cleaning up... 16:51:26 INFO - Return code: 0 16:51:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:51:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:51:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:51:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:51:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:51:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:26 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 0x10510fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051a4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9ba57b610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105073d30>, '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 0x104dd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10506bb20>, '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.e9vNNjGJHm/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.obua2dfv2i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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:51:26 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:51:26 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:51:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e9vNNjGJHm/Render', 16:51:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:51:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:51:26 INFO - 'HOME': '/Users/cltbld', 16:51:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:51:26 INFO - 'LOGNAME': 'cltbld', 16:51:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:51:26 INFO - 'MOZ_NO_REMOTE': '1', 16:51:26 INFO - 'NO_EM_RESTART': '1', 16:51:26 INFO - 'PAGER': '/bin/cat', 16:51:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:51:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:51:26 INFO - 'PWD': '/builds/slave/test', 16:51:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:51:26 INFO - 'SHELL': '/bin/bash', 16:51:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.obua2dfv2i/Listeners', 16:51:26 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 16:51:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:51:26 INFO - 'USER': 'cltbld', 16:51:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:51:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:51:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:51:26 INFO - 'XPC_FLAGS': '0x0', 16:51:26 INFO - 'XPC_SERVICE_NAME': '0', 16:51:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:51:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:51:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:51:26 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:51:26 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:51:26 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:51:26 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:51:26 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:51:26 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:51:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:51:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:51:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:51:28 INFO - Installing collected packages: mozsystemmonitor 16:51:28 INFO - Running setup.py install for mozsystemmonitor 16:51:28 INFO - Successfully installed mozsystemmonitor 16:51:28 INFO - Cleaning up... 16:51:28 INFO - Return code: 0 16:51:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:51:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:51:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:51:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:51:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:51:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10510fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051a4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9ba57b610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105073d30>, '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 0x104dd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10506bb20>, '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.e9vNNjGJHm/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.obua2dfv2i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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:51:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:51:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:51:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e9vNNjGJHm/Render', 16:51:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:51:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:51:28 INFO - 'HOME': '/Users/cltbld', 16:51:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:51:28 INFO - 'LOGNAME': 'cltbld', 16:51:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:51:28 INFO - 'MOZ_NO_REMOTE': '1', 16:51:28 INFO - 'NO_EM_RESTART': '1', 16:51:28 INFO - 'PAGER': '/bin/cat', 16:51:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:51:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:51:28 INFO - 'PWD': '/builds/slave/test', 16:51:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:51:28 INFO - 'SHELL': '/bin/bash', 16:51:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.obua2dfv2i/Listeners', 16:51:28 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 16:51:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:51:28 INFO - 'USER': 'cltbld', 16:51:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:51:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:51:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:51:28 INFO - 'XPC_FLAGS': '0x0', 16:51:28 INFO - 'XPC_SERVICE_NAME': '0', 16:51:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:51:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:51:29 INFO - Downloading/unpacking jsonschema==2.5.1 16:51:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:51:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:51:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:51:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:51:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:51:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:51:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:51:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:51:31 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:51:31 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:51:31 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:51:31 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:51:31 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:51:31 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:51:31 INFO - Downloading functools32-3.2.3-2.tar.gz 16:51:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:51:32 INFO - warning: no files found matching '*.txt' 16:51:32 INFO - no previously-included directories found matching 'build' 16:51:32 INFO - no previously-included directories found matching 'dist' 16:51:32 INFO - no previously-included directories found matching '.git*' 16:51:32 INFO - Installing collected packages: jsonschema, functools32 16:51:32 INFO - Running setup.py install for functools32 16:51:32 INFO - warning: no files found matching '*.txt' 16:51:32 INFO - no previously-included directories found matching 'build' 16:51:32 INFO - no previously-included directories found matching 'dist' 16:51:32 INFO - no previously-included directories found matching '.git*' 16:51:32 INFO - Successfully installed jsonschema functools32 16:51:32 INFO - Cleaning up... 16:51:32 INFO - Return code: 0 16:51:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:51:32 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:51:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:51:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:32 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:51:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:51:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:32 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 0x10510fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051a4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9ba57b610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105073d30>, '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 0x104dd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10506bb20>, '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.e9vNNjGJHm/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.obua2dfv2i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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:51:32 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:51:32 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:51:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e9vNNjGJHm/Render', 16:51:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:51:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:51:32 INFO - 'HOME': '/Users/cltbld', 16:51:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:51:32 INFO - 'LOGNAME': 'cltbld', 16:51:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:51:32 INFO - 'MOZ_NO_REMOTE': '1', 16:51:32 INFO - 'NO_EM_RESTART': '1', 16:51:32 INFO - 'PAGER': '/bin/cat', 16:51:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:51:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:51:32 INFO - 'PWD': '/builds/slave/test', 16:51:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:51:32 INFO - 'SHELL': '/bin/bash', 16:51:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.obua2dfv2i/Listeners', 16:51:32 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 16:51:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:51:32 INFO - 'USER': 'cltbld', 16:51:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:51:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:51:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:51:32 INFO - 'XPC_FLAGS': '0x0', 16:51:32 INFO - 'XPC_SERVICE_NAME': '0', 16:51:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:51:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:51:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:51:32 INFO - Cleaning up... 16:51:32 INFO - Return code: 0 16:51:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:51:32 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:51:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:51:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:32 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:51:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:51:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:32 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 0x10510fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051a4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9ba57b610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105073d30>, '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 0x104dd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10506bb20>, '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.e9vNNjGJHm/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.obua2dfv2i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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:51:32 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:51:32 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:51:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e9vNNjGJHm/Render', 16:51:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:51:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:51:32 INFO - 'HOME': '/Users/cltbld', 16:51:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:51:32 INFO - 'LOGNAME': 'cltbld', 16:51:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:51:32 INFO - 'MOZ_NO_REMOTE': '1', 16:51:32 INFO - 'NO_EM_RESTART': '1', 16:51:32 INFO - 'PAGER': '/bin/cat', 16:51:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:51:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:51:32 INFO - 'PWD': '/builds/slave/test', 16:51:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:51:32 INFO - 'SHELL': '/bin/bash', 16:51:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.obua2dfv2i/Listeners', 16:51:32 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 16:51:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:51:32 INFO - 'USER': 'cltbld', 16:51:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:51:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:51:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:51:32 INFO - 'XPC_FLAGS': '0x0', 16:51:32 INFO - 'XPC_SERVICE_NAME': '0', 16:51:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:51:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:51:32 INFO - Downloading/unpacking blobuploader==1.2.4 16:51:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:51:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:51:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:51:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:51:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:51:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:51:35 INFO - Downloading blobuploader-1.2.4.tar.gz 16:51:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:51:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:51:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:51:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:51:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:51:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:51:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:51:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:51:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:51:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:51:36 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:51:36 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:51:36 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:51:36 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:51:36 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:51:36 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:51:36 INFO - Downloading docopt-0.6.1.tar.gz 16:51:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:51:36 INFO - Installing collected packages: blobuploader, requests, docopt 16:51:36 INFO - Running setup.py install for blobuploader 16:51:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:51:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:51:36 INFO - Running setup.py install for requests 16:51:37 INFO - Running setup.py install for docopt 16:51:37 INFO - Successfully installed blobuploader requests docopt 16:51:37 INFO - Cleaning up... 16:51:37 INFO - Return code: 0 16:51:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:51:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:51:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:51:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:51:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:51:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:37 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 0x10510fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051a4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9ba57b610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105073d30>, '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 0x104dd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10506bb20>, '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.e9vNNjGJHm/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.obua2dfv2i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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:51:37 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:51:37 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:51:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e9vNNjGJHm/Render', 16:51:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:51:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:51:37 INFO - 'HOME': '/Users/cltbld', 16:51:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:51:37 INFO - 'LOGNAME': 'cltbld', 16:51:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:51:37 INFO - 'MOZ_NO_REMOTE': '1', 16:51:37 INFO - 'NO_EM_RESTART': '1', 16:51:37 INFO - 'PAGER': '/bin/cat', 16:51:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:51:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:51:37 INFO - 'PWD': '/builds/slave/test', 16:51:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:51:37 INFO - 'SHELL': '/bin/bash', 16:51:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.obua2dfv2i/Listeners', 16:51:37 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 16:51:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:51:37 INFO - 'USER': 'cltbld', 16:51:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:51:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:51:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:51:37 INFO - 'XPC_FLAGS': '0x0', 16:51:37 INFO - 'XPC_SERVICE_NAME': '0', 16:51:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:51:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:51:37 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-K6NnL_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:51:37 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-WP1R0C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:51:38 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-LnFseO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:51:38 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-_PGtng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:51:38 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-BrWoHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:51:38 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-4jzdH4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:51:38 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-I99M2o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:51:38 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-kp95aA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:51:38 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-hvUcfQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:51:38 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-l50wyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:51:38 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-12NlRo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:51:38 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-LCVwyF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:51:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:51:39 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-Sdjaio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:51:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:51:39 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-MFxY5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:51:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:51:39 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-04rd2m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:51:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:51:39 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-Npx9IO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:51:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:51:39 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-Q4dm3_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:51:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:51:39 INFO - Running setup.py install for manifestparser 16:51:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:51:39 INFO - Running setup.py install for mozcrash 16:51:39 INFO - Running setup.py install for mozdebug 16:51:40 INFO - Running setup.py install for mozdevice 16:51:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:51:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:51:40 INFO - Running setup.py install for mozfile 16:51:40 INFO - Running setup.py install for mozhttpd 16:51:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:51:40 INFO - Running setup.py install for mozinfo 16:51:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:51:40 INFO - Running setup.py install for mozInstall 16:51:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:51:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:51:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:51:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:51:40 INFO - Running setup.py install for mozleak 16:51:40 INFO - Running setup.py install for mozlog 16:51:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:51:41 INFO - Running setup.py install for moznetwork 16:51:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:51:41 INFO - Running setup.py install for mozprocess 16:51:41 INFO - Running setup.py install for mozprofile 16:51:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:51:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:51:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:51:41 INFO - Running setup.py install for mozrunner 16:51:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:51:41 INFO - Running setup.py install for mozscreenshot 16:51:41 INFO - Running setup.py install for moztest 16:51:42 INFO - Running setup.py install for mozversion 16:51:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:51:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:51:42 INFO - Cleaning up... 16:51:42 INFO - Return code: 0 16:51:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:51:42 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:51:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:51:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:42 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:51:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:51:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:42 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 0x10510fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051a4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9ba57b610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105073d30>, '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 0x104dd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10506bb20>, '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.e9vNNjGJHm/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.obua2dfv2i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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:51:42 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:51:42 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:51:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e9vNNjGJHm/Render', 16:51:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:51:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:51:42 INFO - 'HOME': '/Users/cltbld', 16:51:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:51:42 INFO - 'LOGNAME': 'cltbld', 16:51:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:51:42 INFO - 'MOZ_NO_REMOTE': '1', 16:51:42 INFO - 'NO_EM_RESTART': '1', 16:51:42 INFO - 'PAGER': '/bin/cat', 16:51:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:51:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:51:42 INFO - 'PWD': '/builds/slave/test', 16:51:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:51:42 INFO - 'SHELL': '/bin/bash', 16:51:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.obua2dfv2i/Listeners', 16:51:42 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 16:51:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:51:42 INFO - 'USER': 'cltbld', 16:51:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:51:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:51:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:51:42 INFO - 'XPC_FLAGS': '0x0', 16:51:42 INFO - 'XPC_SERVICE_NAME': '0', 16:51:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:51:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:51:42 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-fvGfv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:51:42 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:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:51:42 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-WAiVVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:51:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:51:42 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-HHu0vH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:51:42 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:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:51:42 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-DvxnJp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:51:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:51:42 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-Fn0v4Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:51:43 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:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:51:43 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-ABBd35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:51:43 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:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:51:43 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-ox0WLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:51:43 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:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:51:43 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-9vIovF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:51:43 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:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:51:43 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-7XtvCn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:51:43 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:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:51:43 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-EKQhtt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:51:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:51:43 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-5DOH2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:51:43 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:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:51:43 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-i1hbpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:51:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:51:43 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-hRuv4Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:51:43 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:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:51:43 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-wiF4ZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:51:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:51:44 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-TUAwkL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:51:44 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:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:51:44 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-5Hviqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:51:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:51:44 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-pcuGSU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:51:44 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:51:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:51:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:51:44 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:51:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:51:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:51:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:51:44 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:51:44 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:51:44 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:51:44 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:51:44 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:51:44 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:51:46 INFO - Downloading blessings-1.6.tar.gz 16:51:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:51:46 INFO - Installing collected packages: blessings 16:51:46 INFO - Running setup.py install for blessings 16:51:47 INFO - Successfully installed blessings 16:51:47 INFO - Cleaning up... 16:51:47 INFO - Return code: 0 16:51:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:51:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:51:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:51:47 INFO - Reading from file tmpfile_stdout 16:51:47 INFO - Current package versions: 16:51:47 INFO - blessings == 1.6 16:51:47 INFO - blobuploader == 1.2.4 16:51:47 INFO - docopt == 0.6.1 16:51:47 INFO - functools32 == 3.2.3-2 16:51:47 INFO - jsonschema == 2.5.1 16:51:47 INFO - manifestparser == 1.1 16:51:47 INFO - mozInstall == 1.12 16:51:47 INFO - mozcrash == 0.17 16:51:47 INFO - mozdebug == 0.1 16:51:47 INFO - mozdevice == 0.48 16:51:47 INFO - mozfile == 1.2 16:51:47 INFO - mozhttpd == 0.7 16:51:47 INFO - mozinfo == 0.9 16:51:47 INFO - mozleak == 0.1 16:51:47 INFO - mozlog == 3.3 16:51:47 INFO - moznetwork == 0.27 16:51:47 INFO - mozprocess == 0.23 16:51:47 INFO - mozprofile == 0.28 16:51:47 INFO - mozrunner == 6.12 16:51:47 INFO - mozscreenshot == 0.1 16:51:47 INFO - mozsystemmonitor == 0.3 16:51:47 INFO - moztest == 0.7 16:51:47 INFO - mozversion == 1.4 16:51:47 INFO - psutil == 3.1.1 16:51:47 INFO - requests == 1.2.3 16:51:47 INFO - wsgiref == 0.1.2 16:51:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:51:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:51:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:51:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:51:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:51:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:47 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 0x10510fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051a4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9ba57b610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105073d30>, '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 0x104dd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10506bb20>, '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.e9vNNjGJHm/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.obua2dfv2i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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:51:47 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:51:47 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:51:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e9vNNjGJHm/Render', 16:51:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:51:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:51:47 INFO - 'HOME': '/Users/cltbld', 16:51:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:51:47 INFO - 'LOGNAME': 'cltbld', 16:51:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:51:47 INFO - 'MOZ_NO_REMOTE': '1', 16:51:47 INFO - 'NO_EM_RESTART': '1', 16:51:47 INFO - 'PAGER': '/bin/cat', 16:51:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:51:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:51:47 INFO - 'PWD': '/builds/slave/test', 16:51:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:51:47 INFO - 'SHELL': '/bin/bash', 16:51:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.obua2dfv2i/Listeners', 16:51:47 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 16:51:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:51:47 INFO - 'USER': 'cltbld', 16:51:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:51:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:51:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:51:47 INFO - 'XPC_FLAGS': '0x0', 16:51:47 INFO - 'XPC_SERVICE_NAME': '0', 16:51:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:51:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:51:48 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:51:48 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:51:48 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:51:48 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:51:48 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:51:48 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:51:48 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:51:48 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:51:48 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:51:48 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:51:48 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:51:48 INFO - Cleaning up... 16:51:48 INFO - Return code: 0 16:51:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:51:48 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:51:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:51:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:48 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:51:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:51:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:48 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 0x10510fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051a4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9ba57b610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105073d30>, '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 0x104dd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10506bb20>, '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.e9vNNjGJHm/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.obua2dfv2i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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:51:48 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:51:48 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:51:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e9vNNjGJHm/Render', 16:51:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:51:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:51:48 INFO - 'HOME': '/Users/cltbld', 16:51:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:51:48 INFO - 'LOGNAME': 'cltbld', 16:51:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:51:48 INFO - 'MOZ_NO_REMOTE': '1', 16:51:48 INFO - 'NO_EM_RESTART': '1', 16:51:48 INFO - 'PAGER': '/bin/cat', 16:51:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:51:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:51:48 INFO - 'PWD': '/builds/slave/test', 16:51:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:51:48 INFO - 'SHELL': '/bin/bash', 16:51:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.obua2dfv2i/Listeners', 16:51:48 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 16:51:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:51:48 INFO - 'USER': 'cltbld', 16:51:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:51:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:51:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:51:48 INFO - 'XPC_FLAGS': '0x0', 16:51:48 INFO - 'XPC_SERVICE_NAME': '0', 16:51:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:51:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:51:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:51:48 INFO - Cleaning up... 16:51:48 INFO - Return code: 0 16:51:48 INFO - Running post-action listener: _resource_record_post_action 16:51:48 INFO - Running post-action listener: _start_resource_monitoring 16:51:48 INFO - Starting resource monitoring. 16:51:48 INFO - [mozharness: 2016-08-15 23:51:48.386103Z] Finished create-virtualenv step (success) 16:51:48 INFO - [mozharness: 2016-08-15 23:51:48.387127Z] Running install step. 16:51:48 INFO - Running pre-action listener: _resource_record_pre_action 16:51:48 INFO - Running main action method: install 16:51:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:51:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:51:48 INFO - Reading from file tmpfile_stdout 16:51:48 INFO - Detecting whether we're running mozinstall >=1.0... 16:51:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:51:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:51:48 INFO - Reading from file tmpfile_stdout 16:51:48 INFO - Output received: 16:51:48 INFO - Usage: mozinstall [options] installer 16:51:48 INFO - Options: 16:51:48 INFO - -h, --help show this help message and exit 16:51:48 INFO - -d DEST, --destination=DEST 16:51:48 INFO - Directory to install application into. [default: 16:51:48 INFO - "/builds/slave/test"] 16:51:48 INFO - --app=APP Application being installed. [default: firefox] 16:51:48 INFO - mkdir: /builds/slave/test/build/application 16:51:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:51:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:52:23 INFO - Reading from file tmpfile_stdout 16:52:23 INFO - Output received: 16:52:23 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:52:23 INFO - Running post-action listener: _resource_record_post_action 16:52:23 INFO - [mozharness: 2016-08-15 23:52:23.085947Z] Finished install step (success) 16:52:23 INFO - [mozharness: 2016-08-15 23:52:23.086090Z] Running run-tests step. 16:52:23 INFO - Running pre-action listener: _resource_record_pre_action 16:52:23 INFO - Running main action method: run_tests 16:52:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:52:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:52:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:52:23 INFO - 2016-08-15 16:52:23.100 screenresolution[1634:7496] starting screenresolution argv=screenresolution get 16:52:23 INFO - 2016-08-15 16:52:23.103 screenresolution[1634:7496] Display 0: 1600x1200x32@60 16:52:23 INFO - 2016-08-15 16:52:23.109 screenresolution[1635:7498] starting screenresolution argv=screenresolution list 16:52:23 INFO - Available Modes on Display 0 16:52:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:52:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:52:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:52:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:52:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:52:23 INFO - Intel Iris: 16:52:23 INFO - Chipset Model: Intel Iris 16:52:23 INFO - Type: GPU 16:52:23 INFO - Bus: Built-In 16:52:23 INFO - VRAM (Dynamic, Max): 1536 MB 16:52:23 INFO - Vendor: Intel (0x8086) 16:52:23 INFO - Device ID: 0x0a2e 16:52:23 INFO - Revision ID: 0x0009 16:52:23 INFO - Displays: 16:52:23 INFO - Display: 16:52:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:52:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:52:23 INFO - Display Serial Number: 1600x1200 60 16:52:23 INFO - Main Display: Yes 16:52:23 INFO - Mirror: Off 16:52:23 INFO - Online: Yes 16:52:23 INFO - Rotation: Supported 16:52:23 INFO - Return code: 0 16:52:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:52:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:52:23 INFO - Python 2.7.3 16:52:23 INFO - Return code: 0 16:52:23 INFO - grabbing minidump binary from tooltool 16:52:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:52:23 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 0x105073d30>, '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 0x104dd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10506bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:52:23 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:52:23 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:52:23 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:52:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:52:23 INFO - Return code: 0 16:52:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:52:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:52:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:52:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:52:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:52:23 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/TU43vLrZSzaf1gBv1xBHog/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0309', '--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:52:23 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/TU43vLrZSzaf1gBv1xBHog/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0309 --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:52:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e9vNNjGJHm/Render', 16:52:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:52:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:52:23 INFO - 'HOME': '/Users/cltbld', 16:52:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:52:23 INFO - 'LOGNAME': 'cltbld', 16:52:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:52:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:52:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:52:23 INFO - 'MOZ_NO_REMOTE': '1', 16:52:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:52:23 INFO - 'NO_EM_RESTART': '1', 16:52:23 INFO - 'PAGER': '/bin/cat', 16:52:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:52:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:52:23 INFO - 'PWD': '/builds/slave/test', 16:52:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:52:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:52:23 INFO - 'SHELL': '/bin/bash', 16:52:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.obua2dfv2i/Listeners', 16:52:23 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 16:52:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:52:23 INFO - 'USER': 'cltbld', 16:52:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:52:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:52:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:52:23 INFO - 'XPC_FLAGS': '0x0', 16:52:23 INFO - 'XPC_SERVICE_NAME': '0', 16:52:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:52:23 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/TU43vLrZSzaf1gBv1xBHog/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0309', '--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:52:23 INFO - mozversion application_buildid: 20160815140817 16:52:23 INFO - mozversion application_changeset: 7b74ee1d97dbe7afa04e6b522174be2c529349b9 16:52:23 INFO - mozversion application_display_name: Nightly 16:52:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:52:23 INFO - mozversion application_name: Firefox 16:52:23 INFO - mozversion application_remotingname: firefox 16:52:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:52:23 INFO - mozversion application_vendor: Mozilla 16:52:23 INFO - mozversion application_version: 51.0a1 16:52:23 INFO - mozversion platform_buildid: 20160815140817 16:52:23 INFO - mozversion platform_changeset: 7b74ee1d97dbe7afa04e6b522174be2c529349b9 16:52:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:52:23 INFO - mozversion platform_version: 51.0a1 16:52:23 INFO - using testdate: 1471305143 16:52:23 INFO - actual date: 1471305143 16:52:23 INFO - starting webserver on 'localhost:49275' 16:52:23 INFO - SUITE-START | Running 2 tests 16:52:23 INFO - TEST-START | dromaeo_css 16:52:23 INFO - operating with platform_type : mac_ 16:52:23 INFO - Initialising browser for dromaeo_css test... 16:52:23 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/tmpoqxe8_/profile http://localhost:49275/getInfo.html) 16:52:26 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 16:52:26 INFO - PROCESS | 1645 | colorDepth:24 16:52:26 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 16:52:26 INFO - PROCESS | 1645 | __metrics 16:52:26 INFO - PROCESS | 1645 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:52:26 INFO - PROCESS | 1645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:52:26 INFO - TEST-INFO | 1645: exit 0 16:52:26 INFO - Browser initialized. 16:52:26 INFO - Running cycle 1/1 for dromaeo_css test... 16:52:26 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/tmpoqxe8_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:52:38 INFO - PROCESS | 1647 | RSS: Main: 228401152 16:52:38 INFO - PROCESS | 1647 | 16:53:30 INFO - PROCESS | 1647 | 1471305210073 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 16:55:42 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 16:55:43 INFO - PROCESS | 1647 | RSS: Main: 363446272 16:55:43 INFO - PROCESS | 1647 | 16:58:46 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 16:58:47 INFO - PROCESS | 1647 | RSS: Main: 377643008 16:58:47 INFO - PROCESS | 1647 | 17:01:49 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 17:01:50 INFO - PROCESS | 1647 | RSS: Main: 340340736 17:01:50 INFO - PROCESS | 1647 | 17:04:52 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 17:04:53 INFO - PROCESS | 1647 | RSS: Main: 337190912 17:04:53 INFO - PROCESS | 1647 | 17:07:56 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 17:07:57 INFO - PROCESS | 1647 | RSS: Main: 335970304 17:07:57 INFO - PROCESS | 1647 | 17:11:00 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 17:11:01 INFO - PROCESS | 1647 | RSS: Main: 437198848 17:11:01 INFO - PROCESS | 1647 | 17:11:01 INFO - PROCESS | 1647 | __start_tp_report 17:11:01 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 17:11:01 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 17:11:01 INFO - PROCESS | 1647 | |i|pagename|runs| 17:11:01 INFO - PROCESS | 1647 | |0;dojo.html;2807.3964097718995;3143.3556120995186;3352.0781785009135;3356.697897189253;3387.441072223083;5477.917831232535;5564.248826408434;5581.134924086763;5663.518600918922;5702.144678298262;5547.25112109865;5595.524380427669;5670.4896559309655;5711.086226203808;5720.370759216483;5092.643514953955;5120.692758434488;5122.769475373617;5136.332276804011;5284.2866213061325;5191.714455704935;5233.555147812441;5246.055709972204;5246.501582349671;5251.343582052244;5063.721495317756;5134.229865520165;5140.254663073833;5175.404828444739;5233.712145831978;5133.30700355451;5145.974270128663;5161.896762064737;5163.535281824654;5174.327337446146;5050.116229659795;5093.745312734385;5108.2593024011685;5112.923306150411;5132.948670513284;5541.695206763627;5575.358833734132;5575.832725018256;5584.804531841366;5592.412796656333;6068;6095.902737507247;6108.861898335592;6139.846503837395;6145.569810113294;6164.660943648098;6171.845703857394;6250.718717657716;6261.5929964552615;6286.18279623646;5580.162975553618;5673.773049078073;5737.770489180386;5767.221425107598;5785.247917770705;5273.525382715574;6298.212723409574;6346.682665866688;6351.4283714465;6366.904496432558;6026.186464827279;6039.66781826995;6044.455998960027;6059.878802423927;6062.42406971337;5981.671008094597;6027.242221472144;6139.754409823557;6141.477974372139;6161.784337548164;5069.518395752398;6117.571769976148;6137.846553836101;6143.938560614426;6152.046432802923;6126.326104128542;6141.907871381933;6145.754169833157;6153.169322141453;6158.846028849225;4782.808687652525;4872.634552408584;4927.926081108786;4930.975345123252;4959.231319145538;4435.911281774411;4458.331250312479;4478.6864919455975;4479.283314669702;4484.887877803016;5488.121900495901;5628.2683251177905;5640.464155905182;5644.8306550803545;5703.600747947604;5204.9219261711105;6260.624362538262;6278.560500764994;6283.277423096402;6291.874162516724;5474.562035037267;5604.495595396434;5616.803411880564;5637.098064309773;5659.745311460994;5302.522772950453;5358.973205134027;5368.543673787772;5370.2750128732905;5374.48942350476;6273;6276.403741644628;6297.779577714758;6304.464120549621;6353.205849268841;6265.749370025148;6279.748810047547;6288.811335659938;6290.528210384148;6300.054991751091;6288.6855657217875;6300.401461861115;6302.936970630235;6303.243610766737;6307.810765677037;5863.824085277449;5946.07835785454;6046.7278972445465;6095.177151084724;6112.72492737819;5082.186850103965;6055.333913269625;6229.813105606839;6237.656928868956;6258;6026.397360263939;6054.757809687563;6076.088586712029;6083.209182806207;6093.329733729375;5828.0966450200285;5908.172855800105;5939.079442686218;6035.1249068885645;6071.210742603434;5839.270091238595;5908.113782932594;5916.467517923235;5918.201042859159;5925.525957923442;6004.5796794225735;6048.818535443944;6050;6051.9711649020655;6056.454919057306;5675.716214189191;5793.102069179284;5865.237519122486;5869.295684517885;5876.001079816527 17:11:01 INFO - PROCESS | 1647 | |1;ext.html;483.66868694943946;510.58897587442107;526.7463437019892;545.2802300962728;558.2463674040046;30239.848800755994;38528.614713852854;39696.404553931716;39702.801485992604;40577.3913391299;40993.77018689444;41393;41435.72103534304;41437;41451;8757.7810554736;9555.856662150074;9755.560999754993;9761.267904907161;9769.804603907953;8704.695335663253;8897.199252067312;8897.33270004749;8909.198172164504;11798.882011179907;11286.92774186451;11885.465154068044;12078.577249796215;12101.697457563587;12162.635120946386;10965.177611679115;11991.400429978554;12012.39938003105;12038.638840834701;12064.819027714591;11787.941060294643;11848.763024739543;11865.991390731799;11866.050715942703;11888.940555297255;38953.22093558141;39233.60766392328;39236.60763392358;39250.80374598137;39278.214435711125;3437.329720704464;3564.7148228141687;3587.3004764071015;3680.8527658893613;4129.545749967501;31830.522542161885;31866.840665796524;31899.681003189904;31913.84043079793;31937;3213.80717156969;3309.8179600122226;3361.2437201629436;3388.5764279465066;3409.7954122752712;510952;513074;513356;514346;516669.4164879233;56428;56781.148282774964;56797.43202567921;56847.14729279064;56914.715426422605;59048;59073;59170.408295917354;59178;59196;15741.976771510044;15954.68090638204;15977.363734983597;15993.1203783793;16041;12449.25304481734;12495.375231238402;12497.437615307334;12510.874891251153;12513.249205047545;13203.867961320457;13696.93151534236;14693.485727999469;15070.643642072047;15314.234288285541;5531.446855314436;5587.82074990754;6615.239247486505;6685.866282674319;6792.8301792454595;850.7575341027778;875.5447167472878;974.2060220919927;1017.659084206799;1018.7300365403174;28903.85548072246;29088;29326.560101598494;30204.84897575498;30246.54630180549;813.4590497319216;1001.619384633849;1004.7136566078779;1097.286763603664;1098.4343063322394;15083.773743393638;15148.939574229918;15361.70975580615;15436.768448473282;15658.373665053497;191297;191325;191712.0414397919;191924;192533.03733481243;48203.03593928102;55852.16221756596;57087.714561427754;58153.70923145357;58205.68709006539;1342.9529966451323;1375.587323802855;1395.741787769266;1396.1274203622736;1400.838903526087;29358.559621605265;30243.243918901324;30837;31358;31724.524132138496;48292.7585362071;48759.75620121877;48869.2669609949;48883.75558122329;48961.75519122381;840369;848139;860115;863276;863999;37347;37824.62175378211;38200.808995954845;38233.617663823;38327.61672383352;44767.104657906006;45230.09539809251;45721.77139114283;45747.77126114348;45858;31826.681733183297;35217.94346169619;37424.438633421065;37782.2443551133;38455.61544384521;36501;36953.815230924745;38153.42769858399;38269.80865095768;38426.61573384342;36561.45157822688;38747.8375648731;38938;39039.6096039036;39101.80449097736;30665.386692266475;33773.3245335087;34473.48289775706;34606.826965866014;34692.30615387729;25110.49779004446;25248.873755631103;25285.74714252834;25419.36451588725;25712 17:11:01 INFO - PROCESS | 1647 | |2;jquery.html;346.00178485070563;351.9190586165182;363.6054880454707;376.77770115631773;399.315174475774;15504.767428488582;16974.91512542437;20651.864147471882;20876.89561552194;20994.790052099455;19870.602587948233;20796.272130475438;21044.158233670634;21135.260265890698;21172;5057.8619810542705;5365.570754339644;5794.304683437983;6060.636361818283;6074.908876366858;4161.334186530155;5944.346121926585;6046.093086037107;6059.515238780887;6071.908921366183;6323.715432805535;6625.635590042546;6661.367170118831;6669.9666501667425;6690.33096690332;5701.800436984727;6007.849803754897;6063.514918806485;6614.305497922721;6670.899936500932;5791.102379131242;5835.183074369592;5836.97081514594;5837.416258374171;5842.065269556851;15499;24618.507629847303;25085;25412;25501.107461238767;2173.9782302317885;2316.444053427182;2376.954140178333;2457.5576396248666;2550.4516528946365;10317;11830.645080647595;12313.75372492554;14573.6356591085;15966.680666386723;1758.7273972534279;1814.6642871068896;1843.4653950354382;1876.249500199931;1901.4105627255594;108119.45940270249;115996.42001790021;122457.93855153603;123922.89002429717;135092;11121;11146.609868654557;11276.774464510663;11427.714307142389;11435.485403156878;14073.648158795908;14106.390317791242;14120.646983825487;14123.78814317786;14132.081414708011;8152.673893044331;8309.335253179732;8319.875201871977;8429.831403371898;8433.746987590259;6726.663666816639;8207.261346478726;8268.710595129141;8385.6226469809;8477.533735644476;3713.517242758424;3817.3892177251505;4195.202911446815;4349.586789255016;4354.8911277217685;1627.396166134176;1847.5288801355568;1897.8861268323944;1898.1553208821942;1898.1837809741944;863.9913600863911;872.9563521823883;904.5251243097426;947.4982153286979;951.5860600638606;21344.57310853774;21505.139794408362;22782.43043923882;23018;23083.769162308497;890.8930928288647;891.5765011619429;918.5177781664546;939.9483028433365;956.93779904306;10876.61931832398;11325.980661740483;11326.093912486982;11390;11408.486618102206;617131;621575;623329;636102;637531;78089;124425;139884;140212;144827;430.3544682975537;453.5396572478871;998.013952235047;1037.8547003419528;1042.6246551241547;10603.734906627396;10785;11308.660740177807;11390.658280251606;11627.709307267385;52902.73548632232;58443.70778146082;59700.10449843169;60142;60166.69916650389;67051;86850.1314986842;87473.56263218643;87500;87785.56107219424;6797.830054248683;7981.72063977758;7984.520928744294;8274.379421543288;8426.452280601741;11536.884631153582;12415.255084694947;12735.872641273469;12859.357032148542;13295.401706923405;11041.83437248458;11104;11245.662630121109;11416.828747568627;11459.4270286487;10787.67636970892;11001.724956876142;11155.386453745123;11203.327800332007;11246.718832028937;9258.907410925805;11463.197576169589;11507.769844602893;11559;11820.76358472843;8067.959660201662;8085.838283234185;8103.83792324162;8235.21765432283;8235.464694794819;10576.36541807494;10886.61896833607;11118.166137539554;11151.776964460503;11248.381339026431 17:11:01 INFO - PROCESS | 1647 | |3;mootools.html;1345.1861623717643;1509.3509790789956;1544.8995815272008;1688.7635730997654;1839.1815642039298;7890.447668663196;8692.73921782346;8942;8950.820983580325;9026.323025773077;8786.253168480687;8874.556272186397;8880.733577992654;8900.866487002684;8902.554872256378;2362.834601577886;2389.163792672568;2391.2109004028716;2395.4490467192554;2395.5089206712582;2311.514581937795;2351.49442869783;2358.84667496613;2360.693109895711;2365.3968238099346;2321.7562155973633;2354.482013956927;2356.493353928905;2364.4207169243473;2368.52629474105;2301.355620426267;2321.5008773113777;2329.6272596384497;2334.1713691639393;2344.988275058614;2322.6167682332393;2330.860148391102;2332.0322066342446;2333.5099629077913;2339.3099035784485;8840.469571825712;8921.15249051339;8987.775305617412;8988.955055224747;8999.100089991014;5191.8182863599595;5404.486573775485;5414.945850541483;5440.156775699733;5460.071787796084;3623.6376362363817;3630.2013557017412;3664.8900532984053;3708.592054873962;3716.962830371716;1090.4438736244492;1103.2828661370093;1121.2039451989097;1140.0423644138964;1145.4215621111368;11813.881861181364;11844.407779610985;11894.821577676255;11904.166708330507;11913.404329783563;4368.803403846771;4413.183561041217;4458.910821783611;4644.837430689977;4647.95352046482;8363.749087527383;8973.32700047486;8984.146506081912;8991.235744961752;8998.685046023358;5229.686218826882;5235.424103348629;5236.9738151309;5237.16205407133;5244.921326180111;4403.691741578123;4425.535318791545;4575.084983003413;4723.929141062887;4920.434150072716;3726.4782930389765;3730.962690373116;3850.537935447708;3871.8064096795047;3890.105275786585;1146.6846617180174;1162.4129814443652;1165.7551914097965;1174.5947648061406;1179.1451197881468;802.1497212954287;889.301898010059;907.120093509295;928.535732133933;982.8771403574553;5639.379668236491;5937.7921772737745;6013.098035294741;6221.2845522764555;6242;901.6213190460022;936.8266870628955;965.7826988927435;967.1972263021676;990.960361585543;3856.305864944337;3896.532416110085;4008.2584721826556;4058.228936502055;4119.361498967664;10778.137748980063;11028.338299701883;11161.497732602053;11185.66443006711;11192.44037798107;11094.833577496343;11102.888971110186;11107.888921110685;11113.722156946142;11115.110791136689;7894;8281.130481299377;8385.916140838513;8411.6026932324;8441.95779021101;5902.822915312548;6081.239845019372;6106.419890110433;6148.354422785543;6203.627782333075;9885.654002109892;10029.04724051214;10219.284650074424;10328.638497652546;10351.585936562453;10105.9494702526;10225;10473.057424831803;10563.154947604327;10614.0447359738;7102.289771022856;7314;7484.887726683995;7777.300042996158;7790.571518566307;8308.5430301334;8530.445521041112;8539.487630742175;8655.134486551546;8664;7539.208383119693;7655.57894315818;7658.655360508902;7756.263155000266;7815.413843961839;5924.289085309791;6415.71129299192;7524;7534.585597792174;7610.277023682741;7017;7050.823729406806;7147.1780745214965;7156.785296441115;7199.1001124859395;5018.372703412074;5077.568406685473;5113.054084994288;5137.126688463045;5196.662216955886;5003.249512573143;5010.198368261061;5145.665531740575;5167.354080739908;5285.365756109291 17:11:01 INFO - PROCESS | 1647 | |4;prototype.html;291.3037839563444;296.9257685578605;304.4200797480799;309.25778132482054;309.92097015261106;4893.266010098487;5288.444713305105;5376.650517716345;5393.83818485445;5426.810061647843;5019.32239147715;5019.447860735326;5190.8702282442955;5271.42014378418;5343.572514198864;3378.493226016105;3475.7566970312087;3973.562908080116;3999.320115580358;4039.454673619053;3313.608284520495;3486.738494612914;3828;4033.899152521181;4066.7763273019973;3046.695330466947;3305.223272530953;3805.9429108563395;3841.733377307388;4002.2795896738576;3311.337732453519;3985.581513941038;4014.578469260744;4019.291800984599;4024.5774193709676;2878.8992385266624;2887.061704945901;3477.1307173206696;3502.912427189315;3543.4684797280356;4874.2444921037295;5186.688798672054;5221.6083793715625;5228.215767634885;5232.241325007891;4120.896977575555;4307.255561497567;4582.379919863337;4607.493175750665;4614.707881793103;5173.637845350827;5457.772001299676;5525.751341189656;5557.916631250535;5621.634593751393;3263.7878537894962;3870.206607645454;3873.4964454620717;3904.9931552704543;3909.902252443683;4970.254461830718;5568.972155139199;5648.74580643872;5826.796062137805;5885.970570147122;3297.422950983568;3335.633080361158;3710.1634690828214;3890.8054597270025;3902.843886244518;3461.3423449544507;3915.7846318452757;3994.5206575210577;4012.217617564547;4027.234825383168;4040.171879684233;4294.012377153273;4420.182266280748;4564.657650676212;4642.837500687459;4608.124456353282;5424.376196737426;5595.692236927008;5623.550115990711;5670.432956704297;1183.408295852074;1222.712662524306;1281.8783564381167;1363.5023216525897;1366.8381875405826;741.1476801678006;835.5947365527715;872.7512658892185;899.40189773801;921.5069937583361;4336.696431249782;4779.880502987453;4883.047805677929;4998.550130488274;5147.794088236428;5167.870803229949;5172.612054095958;5642.266505354278;5659.6321239119325;5685.914711279334;4220.091064598048;4443.822247110079;4887.877803054952;5002.499750024968;5089.465606111379;2483.8509689418333;2511.73157555437;2620.9516193522745;2827.915162545127;2987.3875855449583;5104.642675012788;5879.824035192893;5881.558883083786;5886.145219568506;5910.674912879833;4419.668524860648;4886;5780.132980052942;5826.0678291474005;5847.152162936351;4935.703857768473;5856.531477481876;5867.119932010232;5881.647101173943;5891.675957822361;5346.204195021578;5651.660900345993;5669.489745922887;5673.858153546194;5690.743916523684;5104.285400043923;5180.507851754077;5191.377034755854;5572.247746554325;5593.272874526286;4519.23173060587;5089.3638295213095;5497.752601133038;5702.7453960128705;5827.621204621686;3309.8841540545964;3732.4401339799247;3818.7517811342173;3893.922121557496;3902.2585708715255;4707.952920470751;5610;5618.52242559387;5620.156976453564;5672.914906276492;4951;5027.3464449621315;5323.881984783239;5502.422245664147;5530.281063461885;5167.405748338989;5174.6119041072825;5468.2344471773185;5498.340199176125;5515.641483303572;5332.840014799563;5370.489803468664;5387.919181212206;5389.487998640123;5389.730513474389;5275.8681032972945;5436.3476382834315;5480.780768769205;5528.253685752372;5610.5231055360755;4978.7510624467905;5157.793688252578;5387.676739395649;5519.31008623922;5528.7235638218735 17:11:01 INFO - PROCESS | 1647 | |5;yui.html;600.7536909866956;748.3115533708984;775.6354513378714;806.101197165161;813.6262651197235;12558.309292988883;15032.624184395396;17566.648667026668;17868.017259050746;18312.992785396797;16422.26099825508;17176.570585735364;17180.742288865677;17191.140442977834;17243.137843107826;822.8765685147216;848.817504236589;849.647396330522;849.7153453593054;849.902261239956;665.3878431842717;690.6063543780048;823.4030328012203;841.1672444280149;847.4025811802665;879.1120967822513;893.3433926064338;893.964241430342;894.0835643465467;896.4845214001942;889.1375365895071;889.9332550058772;890.0209769253802;890.7238755985666;891.4963045879058;508.6464906889713;643.5559463969893;660.5904339309659;661.9838547829107;665.527475492401;15355.232238388036;15373.846261537465;15397.307121179574;15417.306221220071;15420.845791542053;13621.795673064911;13932.51236206738;13976.440942362293;14037.929810350985;14242.163527767738;13085.728567858036;13270.270135142564;13417.731645367136;13458.259795711232;13586.59240222785;4506.819727210907;4588.908221835544;4670.766461676936;4735.763211839394;4826.565609095198;87283.12716872814;87462;87555.56222218848;87721;87931.56034219787;9322.906770932339;10532.57869685204;10803.081738052197;10828;11352.035077018381;21193.682094768592;21388.465288367915;21422.892885535784;21435.356939291847;21616.675749863763;7937.801554961172;7958.801029974297;8003.4797738146835;8028.11690714021;8047.195280472023;4409.536998615164;4425.668074894395;4492.146492166543;4504.684672072989;4584.220682483919;3008.714172153642;3434.862605495752;3526.612072672004;3546.964530354715;3549.2369140634887;638.3807706524664;684.9760258390933;723.1502983993786;729.1177675013188;730.4631096144329;567.9261695979496;585.6983653418492;644.9580777249557;673.7170388436869;688.2738710660301;8464.44981076228;9123.726288211365;9515.476648784244;9801.558929848174;10098.899011009942;508.8600634825453;650.4276236911583;664.7436345307373;666.8530128179524;667.2693400726197;3670.889873303805;3830.9233815324096;4414.763866117492;4573.314002899591;4779.4264688236935;120725;160947;165230;169899;170006;139977;142381;142831;142983;151268;124001;125892;128597.35701321434;128755;130362.52643578137;9046.964433002522;9159.587818548049;9198.95400522993;9237.445753254826;9541.236701064037;68803;68882.31117688758;69965;70160.29839701537;71295.64352178414;97914.5104274474;105671;106335;107378.92621074001;107795.91530635077;11928.701782455508;11995.520179192734;12016.879831201577;12096;12265.386730663253;13481.123726957716;13578.93210533941;13585.116967397087;14039.087459315111;14175.220362879729;12439.129260951646;12500.624981250578;12551.93724031374;12803.423845927142;12929.806052909402;8930.642774288955;13634.18194908269;13834.792478113037;14165.220912849893;14689.559313220621;13660.043796934118;13780.586582402544;13790.586282411543;13794.724105518035;13886.375113119731;10753.085987691135;11059.179909123444;11326.83009754871;11447.542098315975;11537.134714896583;9153.450792952444;9418.475930229155;10359.171266298526;10391.116755075904;10443.895561044596 17:11:01 INFO - PROCESS | 1647 | __end_tp_report 17:11:01 INFO - PROCESS | 1647 | __start_cc_report 17:11:01 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,1223 17:11:01 INFO - PROCESS | 1647 | __end_cc_report 17:11:01 INFO - PROCESS | 1647 | __startTimestamp1471306261176__endTimestamp 17:11:01 INFO - PROCESS | 1647 | 17:11:01 INFO - PROCESS | 1647 | ------- Summary: start ------- 17:11:01 INFO - PROCESS | 1647 | Number of tests: 6 17:11:01 INFO - PROCESS | 1647 | 17:11:01 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5662.33 Median:5839.27 stddev:647.07 (11.1%) stddev-sans-first:609.06 17:11:01 INFO - PROCESS | 1647 | Values: 2807.4 3143.4 3352.1 3356.7 3387.4 5477.9 5564.2 5581.1 5663.5 5702.1 5547.3 5595.5 5670.5 5711.1 5720.4 5092.6 5120.7 5122.8 5136.3 5284.3 5191.7 5233.6 5246.1 5246.5 5251.3 5063.7 5134.2 5140.3 5175.4 5233.7 5133.3 5146.0 5161.9 5163.5 5174.3 5050.1 5093.7 5108.3 5112.9 5132.9 5541.7 5575.4 5575.8 5584.8 5592.4 6068.0 6095.9 6108.9 6139.8 6145.6 6164.7 6171.8 6250.7 6261.6 6286.2 5580.2 5673.8 5737.8 5767.2 5785.2 5273.5 6298.2 6346.7 6351.4 6366.9 6026.2 6039.7 6044.5 6059.9 6062.4 5981.7 6027.2 6139.8 6141.5 6161.8 5069.5 6117.6 6137.8 6143.9 6152.0 6126.3 6141.9 6145.8 6153.2 6158.8 4782.8 4872.6 4927.9 4931.0 4959.2 4435.9 4458.3 4478.7 4479.3 4484.9 5488.1 5628.3 5640.5 5644.8 5703.6 5204.9 6260.6 6278.6 6283.3 6291.9 5474.6 5604.5 5616.8 5637.1 5659.7 5302.5 5359.0 5368.5 5370.3 5374.5 6273.0 6276.4 6297.8 6304.5 6353.2 6265.7 6279.7 6288.8 6290.5 6300.1 6288.7 6300.4 6302.9 6303.2 6307.8 5863.8 5946.1 6046.7 6095.2 6112.7 5082.2 6055.3 6229.8 6237.7 6258.0 6026.4 6054.8 6076.1 6083.2 6093.3 5828.1 5908.2 5939.1 6035.1 6071.2 5839.3 5908.1 5916.5 5918.2 5925.5 6004.6 6048.8 6050.0 6052.0 6056.5 5675.7 5793.1 5865.2 5869.3 5876.0 17:11:01 INFO - PROCESS | 1647 | 17:11:01 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:65992.58 Median:20317.33 stddev:159083.69 (783.0%) stddev-sans-first:159453.92 17:11:01 INFO - PROCESS | 1647 | Values: 483.7 510.6 526.7 545.3 558.2 30239.8 38528.6 39696.4 39702.8 40577.4 40993.8 41393.0 41435.7 41437.0 41451.0 8757.8 9555.9 9755.6 9761.3 9769.8 8704.7 8897.2 8897.3 8909.2 11798.9 11286.9 11885.5 12078.6 12101.7 12162.6 10965.2 11991.4 12012.4 12038.6 12064.8 11787.9 11848.8 11866.0 11866.1 11888.9 38953.2 39233.6 39236.6 39250.8 39278.2 3437.3 3564.7 3587.3 3680.9 4129.5 31830.5 31866.8 31899.7 31913.8 31937.0 3213.8 3309.8 3361.2 3388.6 3409.8 510952.0 513074.0 513356.0 514346.0 516669.4 56428.0 56781.1 56797.4 56847.1 56914.7 59048.0 59073.0 59170.4 59178.0 59196.0 15742.0 15954.7 15977.4 15993.1 16041.0 12449.3 12495.4 12497.4 12510.9 12513.2 13203.9 13696.9 14693.5 15070.6 15314.2 5531.4 5587.8 6615.2 6685.9 6792.8 850.8 875.5 974.2 1017.7 1018.7 28903.9 29088.0 29326.6 30204.8 30246.5 813.5 1001.6 1004.7 1097.3 1098.4 15083.8 15148.9 15361.7 15436.8 15658.4 191297.0 191325.0 191712.0 191924.0 192533.0 48203.0 55852.2 57087.7 58153.7 58205.7 1343.0 1375.6 1395.7 1396.1 1400.8 29358.6 30243.2 30837.0 31358.0 31724.5 48292.8 48759.8 48869.3 48883.8 48961.8 840369.0 848139.0 860115.0 863276.0 863999.0 37347.0 37824.6 38200.8 38233.6 38327.6 44767.1 45230.1 45721.8 45747.8 45858.0 31826.7 35217.9 37424.4 37782.2 38455.6 36501.0 36953.8 38153.4 38269.8 38426.6 36561.5 38747.8 38938.0 39039.6 39101.8 30665.4 33773.3 34473.5 34606.8 34692.3 25110.5 25248.9 25285.7 25419.4 25712.0 17:11:01 INFO - PROCESS | 1647 | 17:11:01 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:35944.48 Median:24851.75 stddev:104654.81 (421.1%) stddev-sans-first:104914.26 17:11:01 INFO - PROCESS | 1647 | Values: 346.0 351.9 363.6 376.8 399.3 15504.8 16974.9 20651.9 20876.9 20994.8 19870.6 20796.3 21044.2 21135.3 21172.0 5057.9 5365.6 5794.3 6060.6 6074.9 4161.3 5944.3 6046.1 6059.5 6071.9 6323.7 6625.6 6661.4 6670.0 6690.3 5701.8 6007.8 6063.5 6614.3 6670.9 5791.1 5835.2 5837.0 5837.4 5842.1 15499.0 24618.5 25085.0 25412.0 25501.1 2174.0 2316.4 2377.0 2457.6 2550.5 10317.0 11830.6 12313.8 14573.6 15966.7 1758.7 1814.7 1843.5 1876.2 1901.4 108119.5 115996.4 122457.9 123922.9 135092.0 11121.0 11146.6 11276.8 11427.7 11435.5 14073.6 14106.4 14120.6 14123.8 14132.1 8152.7 8309.3 8319.9 8429.8 8433.7 6726.7 8207.3 8268.7 8385.6 8477.5 3713.5 3817.4 4195.2 4349.6 4354.9 1627.4 1847.5 1897.9 1898.2 1898.2 864.0 873.0 904.5 947.5 951.6 21344.6 21505.1 22782.4 23018.0 23083.8 890.9 891.6 918.5 939.9 956.9 10876.6 11326.0 11326.1 11390.0 11408.5 617131.0 621575.0 623329.0 636102.0 637531.0 78089.0 124425.0 139884.0 140212.0 144827.0 430.4 453.5 998.0 1037.9 1042.6 10603.7 10785.0 11308.7 11390.7 11627.7 52902.7 58443.7 59700.1 60142.0 60166.7 67051.0 86850.1 87473.6 87500.0 87785.6 6797.8 7981.7 7984.5 8274.4 8426.5 11536.9 12415.3 12735.9 12859.4 13295.4 11041.8 11104.0 11245.7 11416.8 11459.4 10787.7 11001.7 11155.4 11203.3 11246.7 9258.9 11463.2 11507.8 11559.0 11820.8 8068.0 8085.8 8103.8 8235.2 8235.5 10576.4 10886.6 11118.2 11151.8 11248.4 17:11:01 INFO - PROCESS | 1647 | 17:11:01 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5753.20 Median:5014.29 stddev:3253.76 (64.9%) stddev-sans-first:3246.03 17:11:01 INFO - PROCESS | 1647 | Values: 1345.2 1509.4 1544.9 1688.8 1839.2 7890.4 8692.7 8942.0 8950.8 9026.3 8786.3 8874.6 8880.7 8900.9 8902.6 2362.8 2389.2 2391.2 2395.4 2395.5 2311.5 2351.5 2358.8 2360.7 2365.4 2321.8 2354.5 2356.5 2364.4 2368.5 2301.4 2321.5 2329.6 2334.2 2345.0 2322.6 2330.9 2332.0 2333.5 2339.3 8840.5 8921.2 8987.8 8989.0 8999.1 5191.8 5404.5 5414.9 5440.2 5460.1 3623.6 3630.2 3664.9 3708.6 3717.0 1090.4 1103.3 1121.2 1140.0 1145.4 11813.9 11844.4 11894.8 11904.2 11913.4 4368.8 4413.2 4458.9 4644.8 4648.0 8363.7 8973.3 8984.1 8991.2 8998.7 5229.7 5235.4 5237.0 5237.2 5244.9 4403.7 4425.5 4575.1 4723.9 4920.4 3726.5 3731.0 3850.5 3871.8 3890.1 1146.7 1162.4 1165.8 1174.6 1179.1 802.1 889.3 907.1 928.5 982.9 5639.4 5937.8 6013.1 6221.3 6242.0 901.6 936.8 965.8 967.2 991.0 3856.3 3896.5 4008.3 4058.2 4119.4 10778.1 11028.3 11161.5 11185.7 11192.4 11094.8 11102.9 11107.9 11113.7 11115.1 7894.0 8281.1 8385.9 8411.6 8442.0 5902.8 6081.2 6106.4 6148.4 6203.6 9885.7 10029.0 10219.3 10328.6 10351.6 10105.9 10225.0 10473.1 10563.2 10614.0 7102.3 7314.0 7484.9 7777.3 7790.6 8308.5 8530.4 8539.5 8655.1 8664.0 7539.2 7655.6 7658.7 7756.3 7815.4 5924.3 6415.7 7524.0 7534.6 7610.3 7017.0 7050.8 7147.2 7156.8 7199.1 5018.4 5077.6 5113.1 5137.1 5196.7 5003.2 5010.2 5145.7 5167.4 5285.4 17:11:01 INFO - PROCESS | 1647 | 17:11:01 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4431.50 Median:5023.40 stddev:1392.27 (27.7%) stddev-sans-first:1361.06 17:11:01 INFO - PROCESS | 1647 | Values: 291.3 296.9 304.4 309.3 309.9 4893.3 5288.4 5376.7 5393.8 5426.8 5019.3 5019.4 5190.9 5271.4 5343.6 3378.5 3475.8 3973.6 3999.3 4039.5 3313.6 3486.7 3828.0 4033.9 4066.8 3046.7 3305.2 3805.9 3841.7 4002.3 3311.3 3985.6 4014.6 4019.3 4024.6 2878.9 2887.1 3477.1 3502.9 3543.5 4874.2 5186.7 5221.6 5228.2 5232.2 4120.9 4307.3 4582.4 4607.5 4614.7 5173.6 5457.8 5525.8 5557.9 5621.6 3263.8 3870.2 3873.5 3905.0 3909.9 4970.3 5569.0 5648.7 5826.8 5886.0 3297.4 3335.6 3710.2 3890.8 3902.8 3461.3 3915.8 3994.5 4012.2 4027.2 4040.2 4294.0 4420.2 4564.7 4642.8 4608.1 5424.4 5595.7 5623.6 5670.4 1183.4 1222.7 1281.9 1363.5 1366.8 741.1 835.6 872.8 899.4 921.5 4336.7 4779.9 4883.0 4998.6 5147.8 5167.9 5172.6 5642.3 5659.6 5685.9 4220.1 4443.8 4887.9 5002.5 5089.5 2483.9 2511.7 2621.0 2827.9 2987.4 5104.6 5879.8 5881.6 5886.1 5910.7 4419.7 4886.0 5780.1 5826.1 5847.2 4935.7 5856.5 5867.1 5881.6 5891.7 5346.2 5651.7 5669.5 5673.9 5690.7 5104.3 5180.5 5191.4 5572.2 5593.3 4519.2 5089.4 5497.8 5702.7 5827.6 3309.9 3732.4 3818.8 3893.9 3902.3 4708.0 5610.0 5618.5 5620.2 5672.9 4951.0 5027.3 5323.9 5502.4 5530.3 5167.4 5174.6 5468.2 5498.3 5515.6 5332.8 5370.5 5387.9 5389.5 5389.7 5275.9 5436.3 5480.8 5528.3 5610.5 4978.8 5157.8 5387.7 5519.3 5528.7 17:11:01 INFO - PROCESS | 1647 | 17:11:01 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:25996.93 Median:3610.06 stddev:42444.90 (1175.7%) stddev-sans-first:42521.13 17:11:01 INFO - PROCESS | 1647 | Values: 600.8 748.3 775.6 806.1 813.6 12558.3 15032.6 17566.6 17868.0 18313.0 16422.3 17176.6 17180.7 17191.1 17243.1 822.9 848.8 849.6 849.7 849.9 665.4 690.6 823.4 841.2 847.4 879.1 893.3 894.0 894.1 896.5 889.1 889.9 890.0 890.7 891.5 508.6 643.6 660.6 662.0 665.5 15355.2 15373.8 15397.3 15417.3 15420.8 13621.8 13932.5 13976.4 14037.9 14242.2 13085.7 13270.3 13417.7 13458.3 13586.6 4506.8 4588.9 4670.8 4735.8 4826.6 87283.1 87462.0 87555.6 87721.0 87931.6 9322.9 10532.6 10803.1 10828.0 11352.0 21193.7 21388.5 21422.9 21435.4 21616.7 7937.8 7958.8 8003.5 8028.1 8047.2 4409.5 4425.7 4492.1 4504.7 4584.2 3008.7 3434.9 3526.6 3547.0 3549.2 638.4 685.0 723.2 729.1 730.5 567.9 585.7 645.0 673.7 688.3 8464.4 9123.7 9515.5 9801.6 10098.9 508.9 650.4 664.7 666.9 667.3 3670.9 3830.9 4414.8 4573.3 4779.4 120725.0 160947.0 165230.0 169899.0 170006.0 139977.0 142381.0 142831.0 142983.0 151268.0 124001.0 125892.0 128597.4 128755.0 130362.5 9047.0 9159.6 9199.0 9237.4 9541.2 68803.0 68882.3 69965.0 70160.3 71295.6 97914.5 105671.0 106335.0 107378.9 107795.9 11928.7 11995.5 12016.9 12096.0 12265.4 13481.1 13578.9 13585.1 14039.1 14175.2 12439.1 12500.6 12551.9 12803.4 12929.8 8930.6 13634.2 13834.8 14165.2 14689.6 13660.0 13780.6 13790.6 13794.7 13886.4 10753.1 11059.2 11326.8 11447.5 11537.1 9153.5 9418.5 10359.2 10391.1 10443.9 17:11:01 INFO - PROCESS | 1647 | -------- Summary: end -------- 17:11:01 INFO - PROCESS | 1647 | 17:11:01 INFO - TEST-INFO | 1647: exit 0 17:11:01 INFO - TEST-OK | dromaeo_css | took 1118162ms 17:11:01 INFO - TEST-START | kraken 17:11:01 INFO - operating with platform_type : mac_ 17:11:01 INFO - Initialising browser for kraken test... 17:11:01 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/tmp6uEdF6/profile http://localhost:49275/getInfo.html) 17:11:04 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 17:11:04 INFO - PROCESS | 1656 | colorDepth:24 17:11:04 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 17:11:04 INFO - PROCESS | 1656 | __metrics 17:11:04 INFO - PROCESS | 1656 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:11:04 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:11:04 INFO - TEST-INFO | 1656: exit 0 17:11:04 INFO - Browser initialized. 17:11:04 INFO - Running cycle 1/1 for kraken test... 17:11:05 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/tmp6uEdF6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:11:16 INFO - PROCESS | 1657 | RSS: Main: 231559168 17:11:16 INFO - PROCESS | 1657 | 17:11:19 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 17:11:20 INFO - PROCESS | 1657 | RSS: Main: 288423936 17:11:20 INFO - PROCESS | 1657 | 17:11:23 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 17:11:23 INFO - PROCESS | 1657 | RSS: Main: 294322176 17:11:23 INFO - PROCESS | 1657 | 17:11:27 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 17:11:28 INFO - PROCESS | 1657 | RSS: Main: 388747264 17:11:28 INFO - PROCESS | 1657 | 17:11:30 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 17:11:30 INFO - PROCESS | 1657 | RSS: Main: 294162432 17:11:30 INFO - PROCESS | 1657 | 17:11:32 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:11:33 INFO - PROCESS | 1657 | RSS: Main: 298532864 17:11:33 INFO - PROCESS | 1657 | 17:11:36 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 17:11:36 INFO - PROCESS | 1657 | RSS: Main: 334925824 17:11:36 INFO - PROCESS | 1657 | 17:11:39 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 17:11:40 INFO - PROCESS | 1657 | RSS: Main: 337960960 17:11:40 INFO - PROCESS | 1657 | 17:11:43 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 17:11:44 INFO - PROCESS | 1657 | RSS: Main: 334528512 17:11:44 INFO - PROCESS | 1657 | 17:11:45 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:11:46 INFO - PROCESS | 1657 | RSS: Main: 303546368 17:11:46 INFO - PROCESS | 1657 | 17:11:48 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:11:48 INFO - PROCESS | 1657 | RSS: Main: 310087680 17:11:48 INFO - PROCESS | 1657 | 17:11:51 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:11:51 INFO - PROCESS | 1657 | RSS: Main: 311754752 17:11:51 INFO - PROCESS | 1657 | 17:11:54 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:11:55 INFO - PROCESS | 1657 | RSS: Main: 329904128 17:11:55 INFO - PROCESS | 1657 | 17:11:58 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:11:58 INFO - PROCESS | 1657 | RSS: Main: 323780608 17:11:58 INFO - PROCESS | 1657 | 17:12:00 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 17:12:00 INFO - PROCESS | 1657 | RSS: Main: 366829568 17:12:00 INFO - PROCESS | 1657 | 17:12:00 INFO - PROCESS | 1657 | __start_tp_report 17:12:00 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 17:12:00 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 17:12:00 INFO - PROCESS | 1657 | |i|pagename|runs| 17:12:00 INFO - PROCESS | 1657 | |0;ai-astar;101;111;112;112;107;127;106;113;112;113 17:12:00 INFO - PROCESS | 1657 | |1;audio-beat-detection;137;108;106;161;107;107;128;106;138;126 17:12:00 INFO - PROCESS | 1657 | |2;audio-dft;183;182;186;186;184;181;183;184;181;183 17:12:00 INFO - PROCESS | 1657 | |3;audio-fft;80;60;70;98;80;57;62;57;100;74 17:12:00 INFO - PROCESS | 1657 | |4;audio-oscillator;83;96;94;70;70;68;97;70;67;104 17:12:00 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;115;107;88;85;87;85;89;86;88;85 17:12:00 INFO - PROCESS | 1657 | |6;imaging-darkroom;93;93;94;94;93;93;93;94;93;93 17:12:00 INFO - PROCESS | 1657 | |7;imaging-desaturate;85;89;69;114;132;133;86;87;103;133 17:12:00 INFO - PROCESS | 1657 | |8;json-parse-financial;55;55;56;55;54;55;56;54;56;54 17:12:00 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;66;66;75;72;74;69;68;69;69;68 17:12:00 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;87;84;80;88;88;89;88;89;89;126 17:12:00 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;140;138;139;139;137;135;137;136;139;145 17:12:00 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;170;171;169;166;169;205;168;168;169;171 17:12:00 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;67;66;68;68;68;68;67;67;67;65 17:12:00 INFO - PROCESS | 1657 | __end_tp_report 17:12:00 INFO - PROCESS | 1657 | __start_cc_report 17:12:00 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,2075 17:12:00 INFO - PROCESS | 1657 | __end_cc_report 17:12:00 INFO - PROCESS | 1657 | __startTimestamp1471306320795__endTimestamp 17:12:00 INFO - PROCESS | 1657 | 17:12:00 INFO - PROCESS | 1657 | ------- Summary: start ------- 17:12:00 INFO - PROCESS | 1657 | Number of tests: 14 17:12:00 INFO - PROCESS | 1657 | 17:12:00 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:111.40 Median:112.00 stddev:6.72 (6.0%) stddev-sans-first:5.98 17:12:00 INFO - PROCESS | 1657 | Values: 101.0 111.0 112.0 112.0 107.0 127.0 106.0 113.0 112.0 113.0 17:12:00 INFO - PROCESS | 1657 | 17:12:00 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:122.40 Median:127.00 stddev:18.89 (14.9%) stddev-sans-first:19.28 17:12:00 INFO - PROCESS | 1657 | Values: 137.0 108.0 106.0 161.0 107.0 107.0 128.0 106.0 138.0 126.0 17:12:00 INFO - PROCESS | 1657 | 17:12:00 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:183.30 Median:183.50 stddev:1.77 (1.0%) stddev-sans-first:1.87 17:12:00 INFO - PROCESS | 1657 | Values: 183.0 182.0 186.0 186.0 184.0 181.0 183.0 184.0 181.0 183.0 17:12:00 INFO - PROCESS | 1657 | 17:12:00 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:73.80 Median:72.00 stddev:15.84 (22.0%) stddev-sans-first:16.64 17:12:00 INFO - PROCESS | 1657 | Values: 80.0 60.0 70.0 98.0 80.0 57.0 62.0 57.0 100.0 74.0 17:12:00 INFO - PROCESS | 1657 | 17:12:00 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:81.90 Median:76.50 stddev:14.54 (19.0%) stddev-sans-first:15.42 17:12:00 INFO - PROCESS | 1657 | Values: 83.0 96.0 94.0 70.0 70.0 68.0 97.0 70.0 67.0 104.0 17:12:00 INFO - PROCESS | 1657 | 17:12:00 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:91.50 Median:86.50 stddev:10.54 (12.2%) stddev-sans-first:6.95 17:12:00 INFO - PROCESS | 1657 | Values: 115.0 107.0 88.0 85.0 87.0 85.0 89.0 86.0 88.0 85.0 17:12:00 INFO - PROCESS | 1657 | 17:12:00 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:93.30 Median:93.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 17:12:00 INFO - PROCESS | 1657 | Values: 93.0 93.0 94.0 94.0 93.0 93.0 93.0 94.0 93.0 93.0 17:12:00 INFO - PROCESS | 1657 | 17:12:00 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:103.10 Median:77.00 stddev:23.53 (30.6%) stddev-sans-first:24.03 17:12:00 INFO - PROCESS | 1657 | Values: 85.0 89.0 69.0 114.0 132.0 133.0 86.0 87.0 103.0 133.0 17:12:00 INFO - PROCESS | 1657 | 17:12:00 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:55.00 stddev:0.82 (1.5%) stddev-sans-first:0.87 17:12:00 INFO - PROCESS | 1657 | Values: 55.0 55.0 56.0 55.0 54.0 55.0 56.0 54.0 56.0 54.0 17:12:00 INFO - PROCESS | 1657 | 17:12:00 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:69.60 Median:69.00 stddev:3.10 (4.5%) stddev-sans-first:3.00 17:12:00 INFO - PROCESS | 1657 | Values: 66.0 66.0 75.0 72.0 74.0 69.0 68.0 69.0 69.0 68.0 17:12:00 INFO - PROCESS | 1657 | 17:12:00 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:90.80 Median:88.00 stddev:12.69 (14.4%) stddev-sans-first:13.39 17:12:00 INFO - PROCESS | 1657 | Values: 87.0 84.0 80.0 88.0 88.0 89.0 88.0 89.0 89.0 126.0 17:12:00 INFO - PROCESS | 1657 | 17:12:00 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:138.50 Median:139.00 stddev:2.76 (2.0%) stddev-sans-first:2.87 17:12:00 INFO - PROCESS | 1657 | Values: 140.0 138.0 139.0 139.0 137.0 135.0 137.0 136.0 139.0 145.0 17:12:00 INFO - PROCESS | 1657 | 17:12:00 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.60 Median:169.50 stddev:11.48 (6.8%) stddev-sans-first:12.14 17:12:00 INFO - PROCESS | 1657 | Values: 170.0 171.0 169.0 166.0 169.0 205.0 168.0 168.0 169.0 171.0 17:12:00 INFO - PROCESS | 1657 | 17:12:00 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.50 stddev:0.99 (1.5%) stddev-sans-first:1.05 17:12:00 INFO - PROCESS | 1657 | Values: 67.0 66.0 68.0 68.0 68.0 68.0 67.0 67.0 67.0 65.0 17:12:00 INFO - PROCESS | 1657 | -------- Summary: end -------- 17:12:00 INFO - PROCESS | 1657 | 17:12:01 INFO - TEST-INFO | 1657: exit 0 17:12:01 INFO - TEST-OK | kraken | took 59835ms 17:12:01 INFO - SUITE-END | took 1177s 17:12:01 INFO - Completed test suite (00:19:38) 17:12:01 INFO - javascript benchmark 17:12:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2807.3964097718995, 3143.3556120995186, 3352.0781785009135, 3356.697897189253, 3387.441072223083, 5477.917831232535, 5564.248826408434, 5581.134924086763, 5663.518600918922, 5702.144678298262, 5547.25112109865, 5595.524380427669, 5670.4896559309655, 5711.086226203808, 5720.370759216483, 5092.643514953955, 5120.692758434488, 5122.769475373617, 5136.332276804011, 5284.2866213061325, 5191.714455704935, 5233.555147812441, 5246.055709972204, 5246.501582349671, 5251.343582052244, 5063.721495317756, 5134.229865520165, 5140.254663073833, 5175.404828444739, 5233.712145831978, 5133.30700355451, 5145.974270128663, 5161.896762064737, 5163.535281824654, 5174.327337446146, 5050.116229659795, 5093.745312734385, 5108.2593024011685, 5112.923306150411, 5132.948670513284, 5541.695206763627, 5575.358833734132, 5575.832725018256, 5584.804531841366, 5592.412796656333, 6068.0, 6095.902737507247, 6108.861898335592, 6139.846503837395, 6145.569810113294, 6164.660943648098, 6171.845703857394, 6250.718717657716, 6261.5929964552615, 6286.18279623646, 5580.162975553618, 5673.773049078073, 5737.770489180386, 5767.221425107598, 5785.247917770705, 5273.525382715574, 6298.212723409574, 6346.682665866688, 6351.4283714465, 6366.904496432558, 6026.186464827279, 6039.66781826995, 6044.455998960027, 6059.878802423927, 6062.42406971337, 5981.671008094597, 6027.242221472144, 6139.754409823557, 6141.477974372139, 6161.784337548164, 5069.518395752398, 6117.571769976148, 6137.846553836101, 6143.938560614426, 6152.046432802923, 6126.326104128542, 6141.907871381933, 6145.754169833157, 6153.169322141453, 6158.846028849225, 4782.808687652525, 4872.634552408584, 4927.926081108786, 4930.975345123252, 4959.231319145538, 4435.911281774411, 4458.331250312479, 4478.6864919455975, 4479.283314669702, 4484.887877803016, 5488.121900495901, 5628.2683251177905, 5640.464155905182, 5644.8306550803545, 5703.600747947604, 5204.9219261711105, 6260.624362538262, 6278.560500764994, 6283.277423096402, 6291.874162516724, 5474.562035037267, 5604.495595396434, 5616.803411880564, 5637.098064309773, 5659.745311460994, 5302.522772950453, 5358.973205134027, 5368.543673787772, 5370.2750128732905, 5374.48942350476, 6273.0, 6276.403741644628, 6297.779577714758, 6304.464120549621, 6353.205849268841, 6265.749370025148, 6279.748810047547, 6288.811335659938, 6290.528210384148, 6300.054991751091, 6288.6855657217875, 6300.401461861115, 6302.936970630235, 6303.243610766737, 6307.810765677037, 5863.824085277449, 5946.07835785454, 6046.7278972445465, 6095.177151084724, 6112.72492737819, 5082.186850103965, 6055.333913269625, 6229.813105606839, 6237.656928868956, 6258.0, 6026.397360263939, 6054.757809687563, 6076.088586712029, 6083.209182806207, 6093.329733729375, 5828.0966450200285, 5908.172855800105, 5939.079442686218, 6035.1249068885645, 6071.210742603434, 5839.270091238595, 5908.113782932594, 5916.467517923235, 5918.201042859159, 5925.525957923442, 6004.5796794225735, 6048.818535443944, 6050.0, 6051.9711649020655, 6056.454919057306, 5675.716214189191, 5793.102069179284, 5865.237519122486, 5869.295684517885, 5876.001079816527], "value": 5620.569885052837, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [483.66868694943946, 510.58897587442107, 526.7463437019892, 545.2802300962728, 558.2463674040046, 30239.848800755994, 38528.614713852854, 39696.404553931716, 39702.801485992604, 40577.3913391299, 40993.77018689444, 41393.0, 41435.72103534304, 41437.0, 41451.0, 8757.7810554736, 9555.856662150074, 9755.560999754993, 9761.267904907161, 9769.804603907953, 8704.695335663253, 8897.199252067312, 8897.33270004749, 8909.198172164504, 11798.882011179907, 11286.92774186451, 11885.465154068044, 12078.577249796215, 12101.697457563587, 12162.635120946386, 10965.177611679115, 11991.400429978554, 12012.39938003105, 12038.638840834701, 12064.819027714591, 11787.941060294643, 11848.763024739543, 11865.991390731799, 11866.050715942703, 11888.940555297255, 38953.22093558141, 39233.60766392328, 39236.60763392358, 39250.80374598137, 39278.214435711125, 3437.329720704464, 3564.7148228141687, 3587.3004764071015, 3680.8527658893613, 4129.545749967501, 31830.522542161885, 31866.840665796524, 31899.681003189904, 31913.84043079793, 31937.0, 3213.80717156969, 3309.8179600122226, 3361.2437201629436, 3388.5764279465066, 3409.7954122752712, 510952.0, 513074.0, 513356.0, 514346.0, 516669.4164879233, 56428.0, 56781.148282774964, 56797.43202567921, 56847.14729279064, 56914.715426422605, 59048.0, 59073.0, 59170.408295917354, 59178.0, 59196.0, 15741.976771510044, 15954.68090638204, 15977.363734983597, 15993.1203783793, 16041.0, 12449.25304481734, 12495.375231238402, 12497.437615307334, 12510.874891251153, 12513.249205047545, 13203.867961320457, 13696.93151534236, 14693.485727999469, 15070.643642072047, 15314.234288285541, 5531.446855314436, 5587.82074990754, 6615.239247486505, 6685.866282674319, 6792.8301792454595, 850.7575341027778, 875.5447167472878, 974.2060220919927, 1017.659084206799, 1018.7300365403174, 28903.85548072246, 29088.0, 29326.560101598494, 30204.84897575498, 30246.54630180549, 813.4590497319216, 1001.619384633849, 1004.7136566078779, 1097.286763603664, 1098.4343063322394, 15083.773743393638, 15148.939574229918, 15361.70975580615, 15436.768448473282, 15658.373665053497, 191297.0, 191325.0, 191712.0414397919, 191924.0, 192533.03733481243, 48203.03593928102, 55852.16221756596, 57087.714561427754, 58153.70923145357, 58205.68709006539, 1342.9529966451323, 1375.587323802855, 1395.741787769266, 1396.1274203622736, 1400.838903526087, 29358.559621605265, 30243.243918901324, 30837.0, 31358.0, 31724.524132138496, 48292.7585362071, 48759.75620121877, 48869.2669609949, 48883.75558122329, 48961.75519122381, 840369.0, 848139.0, 860115.0, 863276.0, 863999.0, 37347.0, 37824.62175378211, 38200.808995954845, 38233.617663823, 38327.61672383352, 44767.104657906006, 45230.09539809251, 45721.77139114283, 45747.77126114348, 45858.0, 31826.681733183297, 35217.94346169619, 37424.438633421065, 37782.2443551133, 38455.61544384521, 36501.0, 36953.815230924745, 38153.42769858399, 38269.80865095768, 38426.61573384342, 36561.45157822688, 38747.8375648731, 38938.0, 39039.6096039036, 39101.80449097736, 30665.386692266475, 33773.3245335087, 34473.48289775706, 34606.826965866014, 34692.30615387729, 25110.49779004446, 25248.873755631103, 25285.74714252834, 25419.36451588725, 25712.0], "value": 19652.207451190738, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.00178485070563, 351.9190586165182, 363.6054880454707, 376.77770115631773, 399.315174475774, 15504.767428488582, 16974.91512542437, 20651.864147471882, 20876.89561552194, 20994.790052099455, 19870.602587948233, 20796.272130475438, 21044.158233670634, 21135.260265890698, 21172.0, 5057.8619810542705, 5365.570754339644, 5794.304683437983, 6060.636361818283, 6074.908876366858, 4161.334186530155, 5944.346121926585, 6046.093086037107, 6059.515238780887, 6071.908921366183, 6323.715432805535, 6625.635590042546, 6661.367170118831, 6669.9666501667425, 6690.33096690332, 5701.800436984727, 6007.849803754897, 6063.514918806485, 6614.305497922721, 6670.899936500932, 5791.102379131242, 5835.183074369592, 5836.97081514594, 5837.416258374171, 5842.065269556851, 15499.0, 24618.507629847303, 25085.0, 25412.0, 25501.107461238767, 2173.9782302317885, 2316.444053427182, 2376.954140178333, 2457.5576396248666, 2550.4516528946365, 10317.0, 11830.645080647595, 12313.75372492554, 14573.6356591085, 15966.680666386723, 1758.7273972534279, 1814.6642871068896, 1843.4653950354382, 1876.249500199931, 1901.4105627255594, 108119.45940270249, 115996.42001790021, 122457.93855153603, 123922.89002429717, 135092.0, 11121.0, 11146.609868654557, 11276.774464510663, 11427.714307142389, 11435.485403156878, 14073.648158795908, 14106.390317791242, 14120.646983825487, 14123.78814317786, 14132.081414708011, 8152.673893044331, 8309.335253179732, 8319.875201871977, 8429.831403371898, 8433.746987590259, 6726.663666816639, 8207.261346478726, 8268.710595129141, 8385.6226469809, 8477.533735644476, 3713.517242758424, 3817.3892177251505, 4195.202911446815, 4349.586789255016, 4354.8911277217685, 1627.396166134176, 1847.5288801355568, 1897.8861268323944, 1898.1553208821942, 1898.1837809741944, 863.9913600863911, 872.9563521823883, 904.5251243097426, 947.4982153286979, 951.5860600638606, 21344.57310853774, 21505.139794408362, 22782.43043923882, 23018.0, 23083.769162308497, 890.8930928288647, 891.5765011619429, 918.5177781664546, 939.9483028433365, 956.93779904306, 10876.61931832398, 11325.980661740483, 11326.093912486982, 11390.0, 11408.486618102206, 617131.0, 621575.0, 623329.0, 636102.0, 637531.0, 78089.0, 124425.0, 139884.0, 140212.0, 144827.0, 430.3544682975537, 453.5396572478871, 998.013952235047, 1037.8547003419528, 1042.6246551241547, 10603.734906627396, 10785.0, 11308.660740177807, 11390.658280251606, 11627.709307267385, 52902.73548632232, 58443.70778146082, 59700.10449843169, 60142.0, 60166.69916650389, 67051.0, 86850.1314986842, 87473.56263218643, 87500.0, 87785.56107219424, 6797.830054248683, 7981.72063977758, 7984.520928744294, 8274.379421543288, 8426.452280601741, 11536.884631153582, 12415.255084694947, 12735.872641273469, 12859.357032148542, 13295.401706923405, 11041.83437248458, 11104.0, 11245.662630121109, 11416.828747568627, 11459.4270286487, 10787.67636970892, 11001.724956876142, 11155.386453745123, 11203.327800332007, 11246.718832028937, 9258.907410925805, 11463.197576169589, 11507.769844602893, 11559.0, 11820.76358472843, 8067.959660201662, 8085.838283234185, 8103.83792324162, 8235.21765432283, 8235.464694794819, 10576.36541807494, 10886.61896833607, 11118.166137539554, 11151.776964460503, 11248.381339026431], "value": 9437.622526405394, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1345.1861623717643, 1509.3509790789956, 1544.8995815272008, 1688.7635730997654, 1839.1815642039298, 7890.447668663196, 8692.73921782346, 8942.0, 8950.820983580325, 9026.323025773077, 8786.253168480687, 8874.556272186397, 8880.733577992654, 8900.866487002684, 8902.554872256378, 2362.834601577886, 2389.163792672568, 2391.2109004028716, 2395.4490467192554, 2395.5089206712582, 2311.514581937795, 2351.49442869783, 2358.84667496613, 2360.693109895711, 2365.3968238099346, 2321.7562155973633, 2354.482013956927, 2356.493353928905, 2364.4207169243473, 2368.52629474105, 2301.355620426267, 2321.5008773113777, 2329.6272596384497, 2334.1713691639393, 2344.988275058614, 2322.6167682332393, 2330.860148391102, 2332.0322066342446, 2333.5099629077913, 2339.3099035784485, 8840.469571825712, 8921.15249051339, 8987.775305617412, 8988.955055224747, 8999.100089991014, 5191.8182863599595, 5404.486573775485, 5414.945850541483, 5440.156775699733, 5460.071787796084, 3623.6376362363817, 3630.2013557017412, 3664.8900532984053, 3708.592054873962, 3716.962830371716, 1090.4438736244492, 1103.2828661370093, 1121.2039451989097, 1140.0423644138964, 1145.4215621111368, 11813.881861181364, 11844.407779610985, 11894.821577676255, 11904.166708330507, 11913.404329783563, 4368.803403846771, 4413.183561041217, 4458.910821783611, 4644.837430689977, 4647.95352046482, 8363.749087527383, 8973.32700047486, 8984.146506081912, 8991.235744961752, 8998.685046023358, 5229.686218826882, 5235.424103348629, 5236.9738151309, 5237.16205407133, 5244.921326180111, 4403.691741578123, 4425.535318791545, 4575.084983003413, 4723.929141062887, 4920.434150072716, 3726.4782930389765, 3730.962690373116, 3850.537935447708, 3871.8064096795047, 3890.105275786585, 1146.6846617180174, 1162.4129814443652, 1165.7551914097965, 1174.5947648061406, 1179.1451197881468, 802.1497212954287, 889.301898010059, 907.120093509295, 928.535732133933, 982.8771403574553, 5639.379668236491, 5937.7921772737745, 6013.098035294741, 6221.2845522764555, 6242.0, 901.6213190460022, 936.8266870628955, 965.7826988927435, 967.1972263021676, 990.960361585543, 3856.305864944337, 3896.532416110085, 4008.2584721826556, 4058.228936502055, 4119.361498967664, 10778.137748980063, 11028.338299701883, 11161.497732602053, 11185.66443006711, 11192.44037798107, 11094.833577496343, 11102.888971110186, 11107.888921110685, 11113.722156946142, 11115.110791136689, 7894.0, 8281.130481299377, 8385.916140838513, 8411.6026932324, 8441.95779021101, 5902.822915312548, 6081.239845019372, 6106.419890110433, 6148.354422785543, 6203.627782333075, 9885.654002109892, 10029.04724051214, 10219.284650074424, 10328.638497652546, 10351.585936562453, 10105.9494702526, 10225.0, 10473.057424831803, 10563.154947604327, 10614.0447359738, 7102.289771022856, 7314.0, 7484.887726683995, 7777.300042996158, 7790.571518566307, 8308.5430301334, 8530.445521041112, 8539.487630742175, 8655.134486551546, 8664.0, 7539.208383119693, 7655.57894315818, 7658.655360508902, 7756.263155000266, 7815.413843961839, 5924.289085309791, 6415.71129299192, 7524.0, 7534.585597792174, 7610.277023682741, 7017.0, 7050.823729406806, 7147.1780745214965, 7156.785296441115, 7199.1001124859395, 5018.372703412074, 5077.568406685473, 5113.054084994288, 5137.126688463045, 5196.662216955886, 5003.249512573143, 5010.198368261061, 5145.665531740575, 5167.354080739908, 5285.365756109291], "value": 4607.185005120238, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.3037839563444, 296.9257685578605, 304.4200797480799, 309.25778132482054, 309.92097015261106, 4893.266010098487, 5288.444713305105, 5376.650517716345, 5393.83818485445, 5426.810061647843, 5019.32239147715, 5019.447860735326, 5190.8702282442955, 5271.42014378418, 5343.572514198864, 3378.493226016105, 3475.7566970312087, 3973.562908080116, 3999.320115580358, 4039.454673619053, 3313.608284520495, 3486.738494612914, 3828.0, 4033.899152521181, 4066.7763273019973, 3046.695330466947, 3305.223272530953, 3805.9429108563395, 3841.733377307388, 4002.2795896738576, 3311.337732453519, 3985.581513941038, 4014.578469260744, 4019.291800984599, 4024.5774193709676, 2878.8992385266624, 2887.061704945901, 3477.1307173206696, 3502.912427189315, 3543.4684797280356, 4874.2444921037295, 5186.688798672054, 5221.6083793715625, 5228.215767634885, 5232.241325007891, 4120.896977575555, 4307.255561497567, 4582.379919863337, 4607.493175750665, 4614.707881793103, 5173.637845350827, 5457.772001299676, 5525.751341189656, 5557.916631250535, 5621.634593751393, 3263.7878537894962, 3870.206607645454, 3873.4964454620717, 3904.9931552704543, 3909.902252443683, 4970.254461830718, 5568.972155139199, 5648.74580643872, 5826.796062137805, 5885.970570147122, 3297.422950983568, 3335.633080361158, 3710.1634690828214, 3890.8054597270025, 3902.843886244518, 3461.3423449544507, 3915.7846318452757, 3994.5206575210577, 4012.217617564547, 4027.234825383168, 4040.171879684233, 4294.012377153273, 4420.182266280748, 4564.657650676212, 4642.837500687459, 4608.124456353282, 5424.376196737426, 5595.692236927008, 5623.550115990711, 5670.432956704297, 1183.408295852074, 1222.712662524306, 1281.8783564381167, 1363.5023216525897, 1366.8381875405826, 741.1476801678006, 835.5947365527715, 872.7512658892185, 899.40189773801, 921.5069937583361, 4336.696431249782, 4779.880502987453, 4883.047805677929, 4998.550130488274, 5147.794088236428, 5167.870803229949, 5172.612054095958, 5642.266505354278, 5659.6321239119325, 5685.914711279334, 4220.091064598048, 4443.822247110079, 4887.877803054952, 5002.499750024968, 5089.465606111379, 2483.8509689418333, 2511.73157555437, 2620.9516193522745, 2827.915162545127, 2987.3875855449583, 5104.642675012788, 5879.824035192893, 5881.558883083786, 5886.145219568506, 5910.674912879833, 4419.668524860648, 4886.0, 5780.132980052942, 5826.0678291474005, 5847.152162936351, 4935.703857768473, 5856.531477481876, 5867.119932010232, 5881.647101173943, 5891.675957822361, 5346.204195021578, 5651.660900345993, 5669.489745922887, 5673.858153546194, 5690.743916523684, 5104.285400043923, 5180.507851754077, 5191.377034755854, 5572.247746554325, 5593.272874526286, 4519.23173060587, 5089.3638295213095, 5497.752601133038, 5702.7453960128705, 5827.621204621686, 3309.8841540545964, 3732.4401339799247, 3818.7517811342173, 3893.922121557496, 3902.2585708715255, 4707.952920470751, 5610.0, 5618.52242559387, 5620.156976453564, 5672.914906276492, 4951.0, 5027.3464449621315, 5323.881984783239, 5502.422245664147, 5530.281063461885, 5167.405748338989, 5174.6119041072825, 5468.2344471773185, 5498.340199176125, 5515.641483303572, 5332.840014799563, 5370.489803468664, 5387.919181212206, 5389.487998640123, 5389.730513474389, 5275.8681032972945, 5436.3476382834315, 5480.780768769205, 5528.253685752372, 5610.5231055360755, 4978.7510624467905, 5157.793688252578, 5387.676739395649, 5519.31008623922, 5528.7235638218735], "value": 3988.421348389637, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [600.7536909866956, 748.3115533708984, 775.6354513378714, 806.101197165161, 813.6262651197235, 12558.309292988883, 15032.624184395396, 17566.648667026668, 17868.017259050746, 18312.992785396797, 16422.26099825508, 17176.570585735364, 17180.742288865677, 17191.140442977834, 17243.137843107826, 822.8765685147216, 848.817504236589, 849.647396330522, 849.7153453593054, 849.902261239956, 665.3878431842717, 690.6063543780048, 823.4030328012203, 841.1672444280149, 847.4025811802665, 879.1120967822513, 893.3433926064338, 893.964241430342, 894.0835643465467, 896.4845214001942, 889.1375365895071, 889.9332550058772, 890.0209769253802, 890.7238755985666, 891.4963045879058, 508.6464906889713, 643.5559463969893, 660.5904339309659, 661.9838547829107, 665.527475492401, 15355.232238388036, 15373.846261537465, 15397.307121179574, 15417.306221220071, 15420.845791542053, 13621.795673064911, 13932.51236206738, 13976.440942362293, 14037.929810350985, 14242.163527767738, 13085.728567858036, 13270.270135142564, 13417.731645367136, 13458.259795711232, 13586.59240222785, 4506.819727210907, 4588.908221835544, 4670.766461676936, 4735.763211839394, 4826.565609095198, 87283.12716872814, 87462.0, 87555.56222218848, 87721.0, 87931.56034219787, 9322.906770932339, 10532.57869685204, 10803.081738052197, 10828.0, 11352.035077018381, 21193.682094768592, 21388.465288367915, 21422.892885535784, 21435.356939291847, 21616.675749863763, 7937.801554961172, 7958.801029974297, 8003.4797738146835, 8028.11690714021, 8047.195280472023, 4409.536998615164, 4425.668074894395, 4492.146492166543, 4504.684672072989, 4584.220682483919, 3008.714172153642, 3434.862605495752, 3526.612072672004, 3546.964530354715, 3549.2369140634887, 638.3807706524664, 684.9760258390933, 723.1502983993786, 729.1177675013188, 730.4631096144329, 567.9261695979496, 585.6983653418492, 644.9580777249557, 673.7170388436869, 688.2738710660301, 8464.44981076228, 9123.726288211365, 9515.476648784244, 9801.558929848174, 10098.899011009942, 508.8600634825453, 650.4276236911583, 664.7436345307373, 666.8530128179524, 667.2693400726197, 3670.889873303805, 3830.9233815324096, 4414.763866117492, 4573.314002899591, 4779.4264688236935, 120725.0, 160947.0, 165230.0, 169899.0, 170006.0, 139977.0, 142381.0, 142831.0, 142983.0, 151268.0, 124001.0, 125892.0, 128597.35701321434, 128755.0, 130362.52643578137, 9046.964433002522, 9159.587818548049, 9198.95400522993, 9237.445753254826, 9541.236701064037, 68803.0, 68882.31117688758, 69965.0, 70160.29839701537, 71295.64352178414, 97914.5104274474, 105671.0, 106335.0, 107378.92621074001, 107795.91530635077, 11928.701782455508, 11995.520179192734, 12016.879831201577, 12096.0, 12265.386730663253, 13481.123726957716, 13578.93210533941, 13585.116967397087, 14039.087459315111, 14175.220362879729, 12439.129260951646, 12500.624981250578, 12551.93724031374, 12803.423845927142, 12929.806052909402, 8930.642774288955, 13634.18194908269, 13834.792478113037, 14165.220912849893, 14689.559313220621, 13660.043796934118, 13780.586582402544, 13790.586282411543, 13794.724105518035, 13886.375113119731, 10753.085987691135, 11059.179909123444, 11326.83009754871, 11447.542098315975, 11537.134714896583, 9153.450792952444, 9418.475930229155, 10359.171266298526, 10391.116755075904, 10443.895561044596], "value": 7898.564062311449, "unit": "score"}], "name": "dromaeo_css", "value": 7299.855758418667, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 111.0, 112.0, 112.0, 107.0, 127.0, 106.0, 113.0, 112.0, 113.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 108.0, 106.0, 161.0, 107.0, 107.0, 128.0, 106.0, 138.0, 126.0], "value": 122.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 182.0, 186.0, 186.0, 184.0, 181.0, 183.0, 184.0, 181.0, 183.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 60.0, 70.0, 98.0, 80.0, 57.0, 62.0, 57.0, 100.0, 74.0], "value": 73.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 96.0, 94.0, 70.0, 70.0, 68.0, 97.0, 70.0, 67.0, 104.0], "value": 81.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 107.0, 88.0, 85.0, 87.0, 85.0, 89.0, 86.0, 88.0, 85.0], "value": 91.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 94.0, 93.0, 93.0, 93.0, 94.0, 93.0, 93.0], "value": 93.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 89.0, 69.0, 114.0, 132.0, 133.0, 86.0, 87.0, 103.0, 133.0], "value": 103.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 56.0, 55.0, 54.0, 55.0, 56.0, 54.0, 56.0, 54.0], "value": 55.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 75.0, 72.0, 74.0, 69.0, 68.0, 69.0, 69.0, 68.0], "value": 69.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 80.0, 88.0, 88.0, 89.0, 88.0, 89.0, 89.0, 126.0], "value": 90.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 138.0, 139.0, 139.0, 137.0, 135.0, 137.0, 136.0, 139.0, 145.0], "value": 138.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 171.0, 169.0, 166.0, 169.0, 205.0, 168.0, 168.0, 169.0, 171.0], "value": 172.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 68.0, 68.0, 68.0, 68.0, 67.0, 67.0, 67.0, 65.0], "value": 67.1, "unit": "score"}], "name": "kraken", "value": 1454.3, "alertThreshold": 2.0}]} 17:12:02 INFO - Return code: 0 17:12:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:12:02 INFO - # TBPL SUCCESS # 17:12:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:12:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:12:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:12:02 INFO - 2016-08-15 17:12:02.123 screenresolution[1661:9813] starting screenresolution argv=screenresolution get 17:12:02 INFO - 2016-08-15 17:12:02.126 screenresolution[1661:9813] Display 0: 1600x1200x32@60 17:12:02 INFO - 2016-08-15 17:12:02.132 screenresolution[1662:9816] starting screenresolution argv=screenresolution list 17:12:02 INFO - Available Modes on Display 0 17:12:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:12:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:12:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:12:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:12:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:12:02 INFO - Intel Iris: 17:12:02 INFO - Chipset Model: Intel Iris 17:12:02 INFO - Type: GPU 17:12:02 INFO - Bus: Built-In 17:12:02 INFO - VRAM (Dynamic, Max): 1536 MB 17:12:02 INFO - Vendor: Intel (0x8086) 17:12:02 INFO - Device ID: 0x0a2e 17:12:02 INFO - Revision ID: 0x0009 17:12:02 INFO - Displays: 17:12:02 INFO - Display: 17:12:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:12:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:12:02 INFO - Display Serial Number: 1600x1200 60 17:12:02 INFO - Main Display: Yes 17:12:02 INFO - Mirror: Off 17:12:02 INFO - Online: Yes 17:12:02 INFO - Rotation: Supported 17:12:02 INFO - Return code: 0 17:12:02 INFO - Running post-action listener: _resource_record_post_action 17:12:02 INFO - [mozharness: 2016-08-16 00:12:02.368895Z] Finished run-tests step (success) 17:12:02 INFO - Running post-run listener: _resource_record_post_run 17:12:02 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 37102592; Write bytes: 703217152; Read time: 238; Write time: 1940 17:12:02 INFO - TinderboxPrint: CPU usage
53.5% 17:12:02 INFO - TinderboxPrint: I/O read bytes / time
37,102,592 / 238 17:12:02 INFO - TinderboxPrint: I/O write bytes / time
703,217,152 / 1,940 17:12:02 INFO - TinderboxPrint: CPU idle
2,259.1 (46.5%) 17:12:02 INFO - TinderboxPrint: CPU system
972.2 (20.0%) 17:12:02 INFO - TinderboxPrint: CPU user
1,622.3 (33.4%) 17:12:02 INFO - TinderboxPrint: Swap in / out
441,810,944 / 0 17:12:02 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 392096256; Write bytes: 385926656; Read time: 29375; Write time: 771 17:12:02 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 36135936; Write bytes: 296032256; Read time: 226; Write time: 1109 17:12:02 INFO - Running post-run listener: _upload_blobber_files 17:12:02 INFO - Blob upload gear active. 17:12:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:12:02 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 17:12:02 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'] 17:12:02 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 17:12:03 INFO - (blobuploader) - INFO - Open directory for files ... 17:12:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:12:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:12:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:12:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:12:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:12:03 INFO - (blobuploader) - INFO - Done attempting. 17:12:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:12:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:12:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:12:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:12:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:12:05 INFO - (blobuploader) - INFO - Done attempting. 17:12:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:12:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:12:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:12:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:12:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:12:07 INFO - (blobuploader) - INFO - Done attempting. 17:12:07 INFO - (blobuploader) - INFO - Iteration through files over. 17:12:07 INFO - Return code: 0 17:12:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:12:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:12:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a89da85eb998d8911e8b210c38f82ec13d81c1e3c599ba1cb8623585234d161ae10b84e274dee65a83137fd4f627c1d142dc71a43111235b30ca207e5495b234", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8df881f1e8528a482f0dfeb0ee8aa0cc53bb3b8bd52568e478780984d586aaa83c18828e7660fbad29c3924235f2fef0bb338db2e1a08b00ec96755a739c9744", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/990453a2b81ecd2aba2fec27ade8c55fd224b40bab41559ca43af87514853fd624cc42ba6799578157a03ff46c6f2505985b497ecc9b23976930c9439c14c531"} 17:12:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:12:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:12:07 INFO - Contents: 17:12:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a89da85eb998d8911e8b210c38f82ec13d81c1e3c599ba1cb8623585234d161ae10b84e274dee65a83137fd4f627c1d142dc71a43111235b30ca207e5495b234", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8df881f1e8528a482f0dfeb0ee8aa0cc53bb3b8bd52568e478780984d586aaa83c18828e7660fbad29c3924235f2fef0bb338db2e1a08b00ec96755a739c9744", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/990453a2b81ecd2aba2fec27ade8c55fd224b40bab41559ca43af87514853fd624cc42ba6799578157a03ff46c6f2505985b497ecc9b23976930c9439c14c531"} 17:12:07 INFO - Running post-run listener: copy_logs_to_upload_dir 17:12:07 INFO - Copying logs to upload dir... 17:12:07 INFO - mkdir: /builds/slave/test/build/upload/logs 17:12:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.087325 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-15 17:12:07.674738) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-15 17:12:07.678105) ========= 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.e9vNNjGJHm/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.obua2dfv2i/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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/a89da85eb998d8911e8b210c38f82ec13d81c1e3c599ba1cb8623585234d161ae10b84e274dee65a83137fd4f627c1d142dc71a43111235b30ca207e5495b234", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8df881f1e8528a482f0dfeb0ee8aa0cc53bb3b8bd52568e478780984d586aaa83c18828e7660fbad29c3924235f2fef0bb338db2e1a08b00ec96755a739c9744", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/990453a2b81ecd2aba2fec27ade8c55fd224b40bab41559ca43af87514853fd624cc42ba6799578157a03ff46c6f2505985b497ecc9b23976930c9439c14c531"} build_url:https://queue.taskcluster.net/v1/task/TU43vLrZSzaf1gBv1xBHog/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011543 build_url: 'https://queue.taskcluster.net/v1/task/TU43vLrZSzaf1gBv1xBHog/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a89da85eb998d8911e8b210c38f82ec13d81c1e3c599ba1cb8623585234d161ae10b84e274dee65a83137fd4f627c1d142dc71a43111235b30ca207e5495b234", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8df881f1e8528a482f0dfeb0ee8aa0cc53bb3b8bd52568e478780984d586aaa83c18828e7660fbad29c3924235f2fef0bb338db2e1a08b00ec96755a739c9744", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/990453a2b81ecd2aba2fec27ade8c55fd224b40bab41559ca43af87514853fd624cc42ba6799578157a03ff46c6f2505985b497ecc9b23976930c9439c14c531"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-15 17:12:07.711307) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 17:12:07.711646) ========= 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.e9vNNjGJHm/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.obua2dfv2i/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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.005400 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 17:12:07.761392) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-15 17:12:07.761808) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-15 17:12:07.762320) ========= ========= Total master_lag: 2.58 =========