builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0300 starttime: 1471622421.93 results: success (0) buildid: 20160819064623 builduid: 0ca448d09548435c9032dc916da87731 revision: 3da4d64410c002418eab4b025dfb24a6f4f57fd6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-19 09:00:21.930956) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-19 09:00:21.931388) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-19 09:00:21.931686) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-19 09:00:21.956553) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 09:00:21.956921) ========= 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.pUWtkckLDn/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.Z22FxKtoWF/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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-19 09:00:21-- 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% 28.9M=0s 2016-08-19 09:00:22 (28.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.099557 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 09:00:22.079556) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 09:00:22.079913) ========= 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.pUWtkckLDn/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.Z22FxKtoWF/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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.026626 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 09:00:22.128403) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-19 09:00:22.128941) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3da4d64410c002418eab4b025dfb24a6f4f57fd6 --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 3da4d64410c002418eab4b025dfb24a6f4f57fd6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pUWtkckLDn/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.Z22FxKtoWF/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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-19 09:00:22,212 truncating revision to first 12 chars 2016-08-19 09:00:22,213 Setting DEBUG logging. 2016-08-19 09:00:22,213 attempt 1/10 2016-08-19 09:00:22,213 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3da4d64410c0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-19 09:00:23,246 unpacking tar archive at: mozilla-central-3da4d64410c0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.297629 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-19 09:00:23.450423) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-19 09:00:23.451611) ========= script_repo_revision: 3da4d64410c002418eab4b025dfb24a6f4f57fd6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-19 09:00:23.452334) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-19 09:00:23.452839) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-19 09:00:23.473530) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-19 09:00:23.473943) ========= /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.pUWtkckLDn/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.Z22FxKtoWF/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 09:00:23 INFO - MultiFileLogger online at 20160819 09:00:23 in /builds/slave/test 09:00:23 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 09:00:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:00:23 INFO - {'append_to_log': False, 09:00:23 INFO - 'base_work_dir': '/builds/slave/test', 09:00:23 INFO - 'blob_upload_branch': 'Firefox', 09:00:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:00:23 INFO - 'branch': 'Firefox', 09:00:23 INFO - 'buildbot_json_path': 'buildprops.json', 09:00:23 INFO - 'config_files': ('talos/mac_config.py',), 09:00:23 INFO - 'default_actions': ('clobber', 09:00:23 INFO - 'read-buildbot-config', 09:00:23 INFO - 'download-and-extract', 09:00:23 INFO - 'populate-webroot', 09:00:23 INFO - 'create-virtualenv', 09:00:23 INFO - 'install', 09:00:23 INFO - 'run-tests'), 09:00:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:00:23 INFO - 'download_minidump_stackwalk': True, 09:00:23 INFO - 'download_symbols': 'ondemand', 09:00:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:00:23 INFO - 'tooltool.py': '/tools/tooltool.py', 09:00:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:00:23 INFO - '/tools/misc-python/virtualenv.py')}, 09:00:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:00:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:00:23 INFO - 'installer_path': 'installer.exe', 09:00:23 INFO - 'log_level': 'info', 09:00:23 INFO - 'log_name': 'talos', 09:00:23 INFO - 'log_to_console': True, 09:00:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:00:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:00:23 INFO - 'opt_config_files': (), 09:00:23 INFO - 'pip_index': False, 09:00:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:00:23 INFO - 'cmd': ('bash', 09:00:23 INFO - '-c', 09:00:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:00:23 INFO - 'enabled': True, 09:00:23 INFO - 'halt_on_failure': False, 09:00:23 INFO - 'name': 'check_screen_resolution'},), 09:00:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:00:23 INFO - 'cmd': ('bash', 09:00:23 INFO - '-c', 09:00:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:00:23 INFO - 'enabled': True, 09:00:23 INFO - 'halt_on_failure': False, 09:00:23 INFO - 'name': 'check_screen_resolution'},), 09:00:23 INFO - 'run_cmd_checks_enabled': True, 09:00:23 INFO - 'sps_profile': False, 09:00:23 INFO - 'sps_profile_interval': 0, 09:00:23 INFO - 'suite': 'dromaeojs', 09:00:23 INFO - 'system_bits': '32', 09:00:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:00:23 INFO - 'title': 't-yosemite-r7-0300', 09:00:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:00:23 INFO - 'use_talos_json': True, 09:00:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:00:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:00:23 INFO - 'work_dir': 'build'} 09:00:23 INFO - [mozharness: 2016-08-19 16:00:23.687706Z] Running clobber step. 09:00:23 INFO - Running pre-action listener: _resource_record_pre_action 09:00:23 INFO - Running main action method: clobber 09:00:23 INFO - rmtree: /builds/slave/test/build 09:00:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:00:25 INFO - Running post-action listener: _resource_record_post_action 09:00:25 INFO - [mozharness: 2016-08-19 16:00:25.741379Z] Finished clobber step (success) 09:00:25 INFO - [mozharness: 2016-08-19 16:00:25.741513Z] Running read-buildbot-config step. 09:00:25 INFO - Running pre-action listener: _resource_record_pre_action 09:00:25 INFO - Running main action method: read_buildbot_config 09:00:25 INFO - Using buildbot properties: 09:00:25 INFO - { 09:00:25 INFO - "project": "", 09:00:25 INFO - "product": "firefox", 09:00:25 INFO - "script_repo_revision": "production", 09:00:25 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:00:25 INFO - "repository": "", 09:00:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:00:25 INFO - "buildid": "20160819064623", 09:00:25 INFO - "pgo_build": "False", 09:00:25 INFO - "basedir": "/builds/slave/test", 09:00:25 INFO - "buildnumber": 46, 09:00:25 INFO - "slavename": "t-yosemite-r7-0300", 09:00:25 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 09:00:25 INFO - "platform": "yosemite_r7", 09:00:25 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:00:25 INFO - "branch": "mozilla-central", 09:00:25 INFO - "repo_path": "mozilla-central", 09:00:25 INFO - "revision": "3da4d64410c002418eab4b025dfb24a6f4f57fd6", 09:00:25 INFO - "stage_platform": "macosx64", 09:00:25 INFO - "builduid": "0ca448d09548435c9032dc916da87731", 09:00:25 INFO - "slavebuilddir": "test" 09:00:25 INFO - } 09:00:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/UxB2rMrORJO7T7bvTzV67w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 09:00:25 INFO - Running post-action listener: _resource_record_post_action 09:00:25 INFO - [mozharness: 2016-08-19 16:00:25.745122Z] Finished read-buildbot-config step (success) 09:00:25 INFO - [mozharness: 2016-08-19 16:00:25.745254Z] Running download-and-extract step. 09:00:25 INFO - Running pre-action listener: _resource_record_pre_action 09:00:25 INFO - Running main action method: download_and_extract 09:00:25 INFO - mkdir: /builds/slave/test/build/tests 09:00:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:25 INFO - trying https://queue.taskcluster.net/v1/task/UxB2rMrORJO7T7bvTzV67w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 09:00:25 INFO - Downloading https://queue.taskcluster.net/v1/task/UxB2rMrORJO7T7bvTzV67w/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 09:00:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UxB2rMrORJO7T7bvTzV67w/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 09:00:27 INFO - Downloaded 1199 bytes. 09:00:27 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 09:00:27 INFO - Using the following test package requirements: 09:00:27 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:00:27 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:00:27 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 09:00:27 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:00:27 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 09:00:27 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:00:27 INFO - u'jsshell-mac.zip'], 09:00:27 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:00:27 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 09:00:27 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:00:27 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:00:27 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 09:00:27 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:00:27 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 09:00:27 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:00:27 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 09:00:27 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:00:27 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 09:00:27 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:00:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:27 INFO - trying https://queue.taskcluster.net/v1/task/UxB2rMrORJO7T7bvTzV67w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:00:27 INFO - Downloading https://queue.taskcluster.net/v1/task/UxB2rMrORJO7T7bvTzV67w/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 09:00:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UxB2rMrORJO7T7bvTzV67w/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 09:00:28 INFO - Downloaded 16339392 bytes. 09:00:28 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 09:00:34 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 09:00:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:34 INFO - trying https://queue.taskcluster.net/v1/task/UxB2rMrORJO7T7bvTzV67w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:00:34 INFO - Downloading https://queue.taskcluster.net/v1/task/UxB2rMrORJO7T7bvTzV67w/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 09:00:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UxB2rMrORJO7T7bvTzV67w/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 09:00:35 INFO - Downloaded 16339392 bytes. 09:00:35 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 09:00: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')]} 09:00:42 INFO - trying https://queue.taskcluster.net/v1/task/UxB2rMrORJO7T7bvTzV67w/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 09:00:42 INFO - Downloading https://queue.taskcluster.net/v1/task/UxB2rMrORJO7T7bvTzV67w/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 09:00:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UxB2rMrORJO7T7bvTzV67w/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 09:00:42 INFO - Downloaded 14413981 bytes. 09:00:42 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 09:00:43 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')]} 09:00:43 INFO - trying https://queue.taskcluster.net/v1/task/UxB2rMrORJO7T7bvTzV67w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:00:43 INFO - Downloading https://queue.taskcluster.net/v1/task/UxB2rMrORJO7T7bvTzV67w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 09:00:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UxB2rMrORJO7T7bvTzV67w/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 09:00:46 INFO - Downloaded 111265432 bytes. 09:00:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UxB2rMrORJO7T7bvTzV67w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:00:46 INFO - mkdir: /builds/slave/test/properties 09:00:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:00:46 INFO - Writing to file /builds/slave/test/properties/build_url 09:00:46 INFO - Contents: 09:00:46 INFO - build_url:https://queue.taskcluster.net/v1/task/UxB2rMrORJO7T7bvTzV67w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:00:46 INFO - Running post-action listener: _resource_record_post_action 09:00:46 INFO - Running post-action listener: set_extra_try_arguments 09:00:46 INFO - [mozharness: 2016-08-19 16:00:46.869198Z] Finished download-and-extract step (success) 09:00:46 INFO - [mozharness: 2016-08-19 16:00:46.869349Z] Running populate-webroot step. 09:00:46 INFO - Running pre-action listener: _resource_record_pre_action 09:00:46 INFO - Running main action method: populate_webroot 09:00:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:00:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:00:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:00:46 INFO - '--tppagecycles', 09:00:46 INFO - '7'], 09:00:46 INFO - 'tests': ['tsvgm']}}, 09:00:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:00:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:00:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:00:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:00:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:00:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:00:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:46 INFO - 'talos_options': ['--disable-e10s'], 09:00:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:00:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:00:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:46 INFO - 'talos_options': ['--disable-e10s'], 09:00:46 INFO - 'tests': ['damp', 'tps']}, 09:00:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:46 INFO - 'tests': ['damp', 'tps']}, 09:00:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:00:46 INFO - 'tests': ['dromaeo_dom']}, 09:00:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:00:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:00:46 INFO - 'tests': ['basic_compositor_video']}, 09:00:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:00:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:00:46 INFO - 'tests': ['a11yr', 09:00:46 INFO - 'ts_paint', 09:00:46 INFO - 'tpaint', 09:00:46 INFO - 'sessionrestore', 09:00:46 INFO - 'sessionrestore_no_auto_restore', 09:00:46 INFO - 'tabpaint']}, 09:00:46 INFO - 'other-e10s': {'tests': ['a11yr', 09:00:46 INFO - 'ts_paint', 09:00:46 INFO - 'tpaint', 09:00:46 INFO - 'sessionrestore', 09:00:46 INFO - 'sessionrestore_no_auto_restore', 09:00:46 INFO - 'tabpaint']}, 09:00:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:00:46 INFO - 'ts_paint', 09:00:46 INFO - 'tpaint', 09:00:46 INFO - 'sessionrestore', 09:00:46 INFO - 'sessionrestore_no_auto_restore', 09:00:46 INFO - 'tabpaint']}, 09:00:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:00:46 INFO - 'ts_paint', 09:00:46 INFO - 'tpaint', 09:00:46 INFO - 'sessionrestore', 09:00:46 INFO - 'sessionrestore_no_auto_restore', 09:00:46 INFO - 'tabpaint']}, 09:00:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:00:46 INFO - 'tests': ['a11yr', 09:00:46 INFO - 'ts_paint', 09:00:46 INFO - 'tpaint', 09:00:46 INFO - 'sessionrestore', 09:00:46 INFO - 'sessionrestore_no_auto_restore', 09:00:46 INFO - 'tabpaint']}, 09:00:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:00:46 INFO - 'tests': ['a11yr', 09:00:46 INFO - 'ts_paint', 09:00:46 INFO - 'tpaint', 09:00:46 INFO - 'sessionrestore', 09:00:46 INFO - 'sessionrestore_no_auto_restore', 09:00:46 INFO - 'tabpaint']}, 09:00:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:00:46 INFO - 'tests': ['tsvgx', 09:00:46 INFO - 'tsvgr_opacity', 09:00:46 INFO - 'tart', 09:00:46 INFO - 'tscrollx', 09:00:46 INFO - 'cart']}, 09:00:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:00:46 INFO - 'tsvgr_opacity', 09:00:46 INFO - 'tart', 09:00:46 INFO - 'tscrollx', 09:00:46 INFO - 'cart']}, 09:00:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:46 INFO - 'talos_options': ['--disable-e10s'], 09:00:46 INFO - 'tests': ['tp5o']}, 09:00:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:46 INFO - 'tests': ['tp5o']}, 09:00:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:46 INFO - 'talos_options': ['--disable-e10s', 09:00:46 INFO - '--xperf_path', 09:00:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:00:46 INFO - 'tests': ['tp5n']}, 09:00:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:46 INFO - 'talos_options': ['--xperf_path', 09:00:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:00:46 INFO - 'tests': ['tp5n']}}, 09:00:46 INFO - 'talos.zip': {'path': '', 09:00:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:00:46 INFO - Running post-action listener: _resource_record_post_action 09:00:46 INFO - [mozharness: 2016-08-19 16:00:46.888216Z] Finished populate-webroot step (success) 09:00:46 INFO - [mozharness: 2016-08-19 16:00:46.888329Z] Running create-virtualenv step. 09:00:46 INFO - Running pre-action listener: _resource_record_pre_action 09:00:46 INFO - Running main action method: create_virtualenv 09:00:46 INFO - Creating virtualenv /builds/slave/test/build/venv 09:00:46 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 09:00:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:00:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:00:47 INFO - Using real prefix '/tools/python27' 09:00:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:00:47 INFO - Installing distribute.............................................................................................................................................................................................done. 09:00:50 INFO - Installing pip.................done. 09:00:50 INFO - Return code: 0 09:00:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:00:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10442da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044c2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e00c93eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104391d30>, '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 0x1040f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104389b20>, '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.pUWtkckLDn/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.Z22FxKtoWF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 09:00:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:00:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:00:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pUWtkckLDn/Render', 09:00:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:50 INFO - 'HOME': '/Users/cltbld', 09:00:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:50 INFO - 'LOGNAME': 'cltbld', 09:00:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:50 INFO - 'MOZ_NO_REMOTE': '1', 09:00:50 INFO - 'NO_EM_RESTART': '1', 09:00:50 INFO - 'PAGER': '/bin/cat', 09:00:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:50 INFO - 'PWD': '/builds/slave/test', 09:00:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:50 INFO - 'SHELL': '/bin/bash', 09:00:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z22FxKtoWF/Listeners', 09:00:50 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 09:00:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:50 INFO - 'USER': 'cltbld', 09:00:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:50 INFO - 'XPC_FLAGS': '0x0', 09:00:50 INFO - 'XPC_SERVICE_NAME': '0', 09:00:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:50 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 09:00:50 INFO - Cleaning up... 09:00:50 INFO - Return code: 0 09:00:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:00:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10442da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044c2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e00c93eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104391d30>, '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 0x1040f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104389b20>, '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.pUWtkckLDn/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.Z22FxKtoWF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 09:00:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:00:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:00:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pUWtkckLDn/Render', 09:00:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:50 INFO - 'HOME': '/Users/cltbld', 09:00:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:50 INFO - 'LOGNAME': 'cltbld', 09:00:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:50 INFO - 'MOZ_NO_REMOTE': '1', 09:00:50 INFO - 'NO_EM_RESTART': '1', 09:00:50 INFO - 'PAGER': '/bin/cat', 09:00:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:50 INFO - 'PWD': '/builds/slave/test', 09:00:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:50 INFO - 'SHELL': '/bin/bash', 09:00:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z22FxKtoWF/Listeners', 09:00:50 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 09:00:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:50 INFO - 'USER': 'cltbld', 09:00:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:50 INFO - 'XPC_FLAGS': '0x0', 09:00:50 INFO - 'XPC_SERVICE_NAME': '0', 09:00:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:51 INFO - Downloading/unpacking psutil>=3.1.1 09:00:51 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 09:00:51 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 09:00:51 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 09:00:51 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 09:00:51 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 09:00:51 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 09:00:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:00:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:00:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:00:54 INFO - Installing collected packages: psutil 09:00:54 INFO - Running setup.py install for psutil 09:00:54 INFO - building 'psutil._psutil_osx' extension 09:00:54 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 09:00:55 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 09:00:55 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 09:00:55 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 09:00:55 INFO - building 'psutil._psutil_posix' extension 09:00:55 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 09:00:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:00:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:00:55 INFO - ^ 09:00:55 INFO - 1 warning generated. 09:00:55 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 09:00:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:00:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:00:55 INFO - Successfully installed psutil 09:00:55 INFO - Cleaning up... 09:00:55 INFO - Return code: 0 09:00:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:55 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 0x10442da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044c2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e00c93eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104391d30>, '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 0x1040f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104389b20>, '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.pUWtkckLDn/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.Z22FxKtoWF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 09:00:55 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 09:00:55 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 09:00:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pUWtkckLDn/Render', 09:00:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:55 INFO - 'HOME': '/Users/cltbld', 09:00:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:55 INFO - 'LOGNAME': 'cltbld', 09:00:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:55 INFO - 'MOZ_NO_REMOTE': '1', 09:00:55 INFO - 'NO_EM_RESTART': '1', 09:00:55 INFO - 'PAGER': '/bin/cat', 09:00:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:55 INFO - 'PWD': '/builds/slave/test', 09:00:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:55 INFO - 'SHELL': '/bin/bash', 09:00:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z22FxKtoWF/Listeners', 09:00:55 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 09:00:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:55 INFO - 'USER': 'cltbld', 09:00:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:55 INFO - 'XPC_FLAGS': '0x0', 09:00:55 INFO - 'XPC_SERVICE_NAME': '0', 09:00:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:00:55 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 09:00:55 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 09:00:55 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 09:00:55 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 09:00:55 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 09:00:55 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 09:00:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:00:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:00:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:00:58 INFO - Installing collected packages: mozsystemmonitor 09:00:58 INFO - Running setup.py install for mozsystemmonitor 09:00:58 INFO - Successfully installed mozsystemmonitor 09:00:58 INFO - Cleaning up... 09:00:58 INFO - Return code: 0 09:00:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:00:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10442da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044c2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e00c93eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104391d30>, '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 0x1040f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104389b20>, '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.pUWtkckLDn/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.Z22FxKtoWF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 09:00:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:00:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:00:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pUWtkckLDn/Render', 09:00:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:58 INFO - 'HOME': '/Users/cltbld', 09:00:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:58 INFO - 'LOGNAME': 'cltbld', 09:00:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:58 INFO - 'MOZ_NO_REMOTE': '1', 09:00:58 INFO - 'NO_EM_RESTART': '1', 09:00:58 INFO - 'PAGER': '/bin/cat', 09:00:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:58 INFO - 'PWD': '/builds/slave/test', 09:00:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:58 INFO - 'SHELL': '/bin/bash', 09:00:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z22FxKtoWF/Listeners', 09:00:58 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 09:00:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:58 INFO - 'USER': 'cltbld', 09:00:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:58 INFO - 'XPC_FLAGS': '0x0', 09:00:58 INFO - 'XPC_SERVICE_NAME': '0', 09:00:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:58 INFO - Downloading/unpacking jsonschema==2.5.1 09:00:58 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 09:00:58 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 09:00:58 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 09:00:58 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 09:00:58 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 09:00:58 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 09:01:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:01:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:01:01 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 09:01:01 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 09:01:01 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 09:01:01 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 09:01:01 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 09:01:01 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 09:01:01 INFO - Downloading functools32-3.2.3-2.tar.gz 09:01:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:01:01 INFO - warning: no files found matching '*.txt' 09:01:01 INFO - no previously-included directories found matching 'build' 09:01:01 INFO - no previously-included directories found matching 'dist' 09:01:01 INFO - no previously-included directories found matching '.git*' 09:01:01 INFO - Installing collected packages: jsonschema, functools32 09:01:01 INFO - Running setup.py install for functools32 09:01:01 INFO - warning: no files found matching '*.txt' 09:01:01 INFO - no previously-included directories found matching 'build' 09:01:01 INFO - no previously-included directories found matching 'dist' 09:01:01 INFO - no previously-included directories found matching '.git*' 09:01:02 INFO - Successfully installed jsonschema functools32 09:01:02 INFO - Cleaning up... 09:01:02 INFO - Return code: 0 09:01:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10442da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044c2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e00c93eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104391d30>, '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 0x1040f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104389b20>, '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.pUWtkckLDn/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.Z22FxKtoWF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 09:01:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:01:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:01:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pUWtkckLDn/Render', 09:01:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:02 INFO - 'HOME': '/Users/cltbld', 09:01:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:02 INFO - 'LOGNAME': 'cltbld', 09:01:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:02 INFO - 'MOZ_NO_REMOTE': '1', 09:01:02 INFO - 'NO_EM_RESTART': '1', 09:01:02 INFO - 'PAGER': '/bin/cat', 09:01:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:02 INFO - 'PWD': '/builds/slave/test', 09:01:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:02 INFO - 'SHELL': '/bin/bash', 09:01:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z22FxKtoWF/Listeners', 09:01:02 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 09:01:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:02 INFO - 'USER': 'cltbld', 09:01:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:02 INFO - 'XPC_FLAGS': '0x0', 09:01:02 INFO - 'XPC_SERVICE_NAME': '0', 09:01:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:01:02 INFO - Cleaning up... 09:01:02 INFO - Return code: 0 09:01:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10442da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044c2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e00c93eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104391d30>, '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 0x1040f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104389b20>, '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.pUWtkckLDn/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.Z22FxKtoWF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 09:01:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:01:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:01:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pUWtkckLDn/Render', 09:01:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:02 INFO - 'HOME': '/Users/cltbld', 09:01:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:02 INFO - 'LOGNAME': 'cltbld', 09:01:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:02 INFO - 'MOZ_NO_REMOTE': '1', 09:01:02 INFO - 'NO_EM_RESTART': '1', 09:01:02 INFO - 'PAGER': '/bin/cat', 09:01:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:02 INFO - 'PWD': '/builds/slave/test', 09:01:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:02 INFO - 'SHELL': '/bin/bash', 09:01:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z22FxKtoWF/Listeners', 09:01:02 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 09:01:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:02 INFO - 'USER': 'cltbld', 09:01:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:02 INFO - 'XPC_FLAGS': '0x0', 09:01:02 INFO - 'XPC_SERVICE_NAME': '0', 09:01:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:02 INFO - Downloading/unpacking blobuploader==1.2.4 09:01:02 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 09:01:02 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 09:01:02 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 09:01:02 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 09:01:02 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 09:01:02 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 09:01:05 INFO - Downloading blobuploader-1.2.4.tar.gz 09:01:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:01:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:01:05 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 09:01:05 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 09:01:05 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 09:01:05 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 09:01:05 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 09:01:05 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 09:01:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:01:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:01:05 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 09:01:05 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 09:01:05 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 09:01:05 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 09:01:05 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 09:01:05 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 09:01:06 INFO - Downloading docopt-0.6.1.tar.gz 09:01:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:01:06 INFO - Installing collected packages: blobuploader, requests, docopt 09:01:06 INFO - Running setup.py install for blobuploader 09:01:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:01:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:01:06 INFO - Running setup.py install for requests 09:01:06 INFO - Running setup.py install for docopt 09:01:07 INFO - Successfully installed blobuploader requests docopt 09:01:07 INFO - Cleaning up... 09:01:07 INFO - Return code: 0 09:01:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:01:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:07 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 0x10442da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044c2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e00c93eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104391d30>, '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 0x1040f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104389b20>, '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.pUWtkckLDn/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.Z22FxKtoWF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 09:01:07 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 09:01:07 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 09:01:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pUWtkckLDn/Render', 09:01:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:07 INFO - 'HOME': '/Users/cltbld', 09:01:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:07 INFO - 'LOGNAME': 'cltbld', 09:01:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:07 INFO - 'MOZ_NO_REMOTE': '1', 09:01:07 INFO - 'NO_EM_RESTART': '1', 09:01:07 INFO - 'PAGER': '/bin/cat', 09:01:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:07 INFO - 'PWD': '/builds/slave/test', 09:01:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:07 INFO - 'SHELL': '/bin/bash', 09:01:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z22FxKtoWF/Listeners', 09:01:07 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 09:01:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:07 INFO - 'USER': 'cltbld', 09:01:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:07 INFO - 'XPC_FLAGS': '0x0', 09:01:07 INFO - 'XPC_SERVICE_NAME': '0', 09:01:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:01:07 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-PCrqHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:01:07 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-JQQWTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:01:07 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-Lwo837-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:01:07 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-qFVXar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:01:07 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-Q2zdfW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:01:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:01:08 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-sc9rE6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:01:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:01:08 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-pASrdo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:01:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:01:08 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-d_E250-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:01:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:01:08 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-y6ENqZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:01:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:01:08 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-jUXU2Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:01:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:01:08 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-c2OT1r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:01:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:01:08 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-WxUrGI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:01:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:01:08 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-PEZ5wR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:01:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:01:08 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-cUrdGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:01:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:08 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-icQuTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:01:09 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-eIA59R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:01:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:01:09 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-CKDFUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:01:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:01:09 INFO - Running setup.py install for manifestparser 09:01:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:01:09 INFO - Running setup.py install for mozcrash 09:01:09 INFO - Running setup.py install for mozdebug 09:01:09 INFO - Running setup.py install for mozdevice 09:01:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:01:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:01:09 INFO - Running setup.py install for mozfile 09:01:09 INFO - Running setup.py install for mozhttpd 09:01:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:01:10 INFO - Running setup.py install for mozinfo 09:01:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:01:10 INFO - Running setup.py install for mozInstall 09:01:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:01:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:01:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:01:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:01:10 INFO - Running setup.py install for mozleak 09:01:10 INFO - Running setup.py install for mozlog 09:01:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:01:10 INFO - Running setup.py install for moznetwork 09:01:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:01:10 INFO - Running setup.py install for mozprocess 09:01:11 INFO - Running setup.py install for mozprofile 09:01:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:01:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:01:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:01:11 INFO - Running setup.py install for mozrunner 09:01:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:01:11 INFO - Running setup.py install for mozscreenshot 09:01:11 INFO - Running setup.py install for moztest 09:01:11 INFO - Running setup.py install for mozversion 09:01:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:01:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:01:11 INFO - Cleaning up... 09:01:11 INFO - Return code: 0 09:01:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:01:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:11 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 0x10442da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044c2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e00c93eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104391d30>, '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 0x1040f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104389b20>, '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.pUWtkckLDn/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.Z22FxKtoWF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 09:01:11 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 09:01:11 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 09:01:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pUWtkckLDn/Render', 09:01:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:11 INFO - 'HOME': '/Users/cltbld', 09:01:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:11 INFO - 'LOGNAME': 'cltbld', 09:01:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:11 INFO - 'MOZ_NO_REMOTE': '1', 09:01:11 INFO - 'NO_EM_RESTART': '1', 09:01:11 INFO - 'PAGER': '/bin/cat', 09:01:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:11 INFO - 'PWD': '/builds/slave/test', 09:01:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:11 INFO - 'SHELL': '/bin/bash', 09:01:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z22FxKtoWF/Listeners', 09:01:11 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 09:01:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:11 INFO - 'USER': 'cltbld', 09:01:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:11 INFO - 'XPC_FLAGS': '0x0', 09:01:11 INFO - 'XPC_SERVICE_NAME': '0', 09:01:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:01:12 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-8M7_J1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:01:12 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)) 09:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:01:12 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-8VXeWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:01:12 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)) 09:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:01:12 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-PMt1Vw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:01:12 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)) 09:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:01:12 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-OANmXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:01:12 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)) 09:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:01:12 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-RSfdss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:01:12 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)) 09:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:01:12 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-BLwXe2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:01:12 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)) 09:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:01:12 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-0JchUW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:01:12 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)) 09:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:01:12 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-t1JsoX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:01:12 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)) 09:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:01:12 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-E5nHnt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:01:13 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)) 09:01:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:01:13 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-zX9Oyn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:01:13 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)) 09:01:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:01:13 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-X_PfGw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:01:13 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)) 09:01:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:01:13 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-ZqX9m0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:01:13 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)) 09:01:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:01:13 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-UmPgfj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:01:13 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)) 09:01:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:01:13 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-5wkfeK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:01:13 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)) 09:01:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:13 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-eYNcNf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:13 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)) 09:01:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:01:13 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-HD6VwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:01:13 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)) 09:01:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:01:13 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-3ZnOjm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:01:13 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)) 09:01:13 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)) 09:01:13 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)) 09:01:13 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)) 09:01:13 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)) 09:01:13 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)) 09:01:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:16 INFO - Downloading blessings-1.6.tar.gz 09:01:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:01:16 INFO - Installing collected packages: blessings 09:01:16 INFO - Running setup.py install for blessings 09:01:16 INFO - Successfully installed blessings 09:01:16 INFO - Cleaning up... 09:01:16 INFO - Return code: 0 09:01:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:01:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:01:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:01:16 INFO - Reading from file tmpfile_stdout 09:01:16 INFO - Current package versions: 09:01:16 INFO - blessings == 1.6 09:01:16 INFO - blobuploader == 1.2.4 09:01:16 INFO - docopt == 0.6.1 09:01:16 INFO - functools32 == 3.2.3-2 09:01:16 INFO - jsonschema == 2.5.1 09:01:16 INFO - manifestparser == 1.1 09:01:16 INFO - mozInstall == 1.12 09:01:16 INFO - mozcrash == 0.17 09:01:16 INFO - mozdebug == 0.1 09:01:16 INFO - mozdevice == 0.48 09:01:16 INFO - mozfile == 1.2 09:01:16 INFO - mozhttpd == 0.7 09:01:16 INFO - mozinfo == 0.9 09:01:16 INFO - mozleak == 0.1 09:01:16 INFO - mozlog == 3.3 09:01:16 INFO - moznetwork == 0.27 09:01:16 INFO - mozprocess == 0.23 09:01:16 INFO - mozprofile == 0.28 09:01:16 INFO - mozrunner == 6.12 09:01:16 INFO - mozscreenshot == 0.1 09:01:16 INFO - mozsystemmonitor == 0.3 09:01:16 INFO - moztest == 0.7 09:01:16 INFO - mozversion == 1.4 09:01:16 INFO - psutil == 3.1.1 09:01:16 INFO - requests == 1.2.3 09:01:16 INFO - wsgiref == 0.1.2 09:01:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:01:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:17 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')]} 09:01:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:17 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 0x10442da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044c2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e00c93eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104391d30>, '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 0x1040f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104389b20>, '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.pUWtkckLDn/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.Z22FxKtoWF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 09:01:17 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 09:01:17 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 09:01:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pUWtkckLDn/Render', 09:01:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:17 INFO - 'HOME': '/Users/cltbld', 09:01:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:17 INFO - 'LOGNAME': 'cltbld', 09:01:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:17 INFO - 'MOZ_NO_REMOTE': '1', 09:01:17 INFO - 'NO_EM_RESTART': '1', 09:01:17 INFO - 'PAGER': '/bin/cat', 09:01:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:17 INFO - 'PWD': '/builds/slave/test', 09:01:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:17 INFO - 'SHELL': '/bin/bash', 09:01:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z22FxKtoWF/Listeners', 09:01:17 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 09:01:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:17 INFO - 'USER': 'cltbld', 09:01:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:17 INFO - 'XPC_FLAGS': '0x0', 09:01:17 INFO - 'XPC_SERVICE_NAME': '0', 09:01:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:17 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)) 09:01:17 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)) 09:01:17 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)) 09:01:17 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)) 09:01:17 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)) 09:01:17 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)) 09:01:17 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)) 09:01:17 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)) 09:01:17 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)) 09:01:17 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)) 09:01:17 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)) 09:01:17 INFO - Cleaning up... 09:01:17 INFO - Return code: 0 09:01:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:01:17 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')]} 09:01:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:17 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')]} 09:01:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:17 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 0x10442da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044c2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e00c93eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104391d30>, '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 0x1040f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104389b20>, '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.pUWtkckLDn/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.Z22FxKtoWF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 09:01:17 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 09:01:17 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 09:01:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pUWtkckLDn/Render', 09:01:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:18 INFO - 'HOME': '/Users/cltbld', 09:01:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:18 INFO - 'LOGNAME': 'cltbld', 09:01:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:18 INFO - 'MOZ_NO_REMOTE': '1', 09:01:18 INFO - 'NO_EM_RESTART': '1', 09:01:18 INFO - 'PAGER': '/bin/cat', 09:01:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:18 INFO - 'PWD': '/builds/slave/test', 09:01:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:18 INFO - 'SHELL': '/bin/bash', 09:01:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z22FxKtoWF/Listeners', 09:01:18 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 09:01:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:18 INFO - 'USER': 'cltbld', 09:01:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:18 INFO - 'XPC_FLAGS': '0x0', 09:01:18 INFO - 'XPC_SERVICE_NAME': '0', 09:01:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:01:18 INFO - Cleaning up... 09:01:18 INFO - Return code: 0 09:01:18 INFO - Running post-action listener: _resource_record_post_action 09:01:18 INFO - Running post-action listener: _start_resource_monitoring 09:01:18 INFO - Starting resource monitoring. 09:01:18 INFO - [mozharness: 2016-08-19 16:01:18.294219Z] Finished create-virtualenv step (success) 09:01:18 INFO - [mozharness: 2016-08-19 16:01:18.295179Z] Running install step. 09:01:18 INFO - Running pre-action listener: _resource_record_pre_action 09:01:18 INFO - Running main action method: install 09:01:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:01:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:01:18 INFO - Reading from file tmpfile_stdout 09:01:18 INFO - Detecting whether we're running mozinstall >=1.0... 09:01:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:01:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:01:18 INFO - Reading from file tmpfile_stdout 09:01:18 INFO - Output received: 09:01:18 INFO - Usage: mozinstall [options] installer 09:01:18 INFO - Options: 09:01:18 INFO - -h, --help show this help message and exit 09:01:18 INFO - -d DEST, --destination=DEST 09:01:18 INFO - Directory to install application into. [default: 09:01:18 INFO - "/builds/slave/test"] 09:01:18 INFO - --app=APP Application being installed. [default: firefox] 09:01:18 INFO - mkdir: /builds/slave/test/build/application 09:01:18 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'] 09:01:18 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 09:01:53 INFO - Reading from file tmpfile_stdout 09:01:53 INFO - Output received: 09:01:53 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:01:53 INFO - Running post-action listener: _resource_record_post_action 09:01:53 INFO - [mozharness: 2016-08-19 16:01:53.075098Z] Finished install step (success) 09:01:53 INFO - [mozharness: 2016-08-19 16:01:53.075264Z] Running run-tests step. 09:01:53 INFO - Running pre-action listener: _resource_record_pre_action 09:01:53 INFO - Running main action method: run_tests 09:01:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:01:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:01:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:01:53 INFO - 2016-08-19 09:01:53.090 screenresolution[1631:7545] starting screenresolution argv=screenresolution get 09:01:53 INFO - 2016-08-19 09:01:53.092 screenresolution[1631:7545] Display 0: 1600x1200x32@60 09:01:53 INFO - 2016-08-19 09:01:53.099 screenresolution[1632:7547] starting screenresolution argv=screenresolution list 09:01:53 INFO - Available Modes on Display 0 09:01:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:01:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:01:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:01:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:01:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:01:53 INFO - Intel Iris: 09:01:53 INFO - Chipset Model: Intel Iris 09:01:53 INFO - Type: GPU 09:01:53 INFO - Bus: Built-In 09:01:53 INFO - VRAM (Dynamic, Max): 1536 MB 09:01:53 INFO - Vendor: Intel (0x8086) 09:01:53 INFO - Device ID: 0x0a2e 09:01:53 INFO - Revision ID: 0x0009 09:01:53 INFO - Displays: 09:01:53 INFO - Display: 09:01:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:01:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:01:53 INFO - Display Serial Number: 1600x1200 60 09:01:53 INFO - Main Display: Yes 09:01:53 INFO - Mirror: Off 09:01:53 INFO - Online: Yes 09:01:53 INFO - Rotation: Supported 09:01:53 INFO - Return code: 0 09:01:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:01:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:01:53 INFO - Python 2.7.3 09:01:53 INFO - Return code: 0 09:01:53 INFO - grabbing minidump binary from tooltool 09:01:53 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')]} 09:01:53 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 0x104391d30>, '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 0x1040f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104389b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:01:53 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 09:01:53 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 09:01:53 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 09:01:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:01:53 INFO - Return code: 0 09:01:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:01:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:01:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:01:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:01:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:01:53 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/UxB2rMrORJO7T7bvTzV67w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0300', '--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 09:01:53 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/UxB2rMrORJO7T7bvTzV67w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0300 --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 09:01:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pUWtkckLDn/Render', 09:01:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:53 INFO - 'HOME': '/Users/cltbld', 09:01:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:53 INFO - 'LOGNAME': 'cltbld', 09:01:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:01:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:01:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:53 INFO - 'MOZ_NO_REMOTE': '1', 09:01:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:01:53 INFO - 'NO_EM_RESTART': '1', 09:01:53 INFO - 'PAGER': '/bin/cat', 09:01:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:53 INFO - 'PWD': '/builds/slave/test', 09:01:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:01:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:53 INFO - 'SHELL': '/bin/bash', 09:01:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z22FxKtoWF/Listeners', 09:01:53 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 09:01:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:53 INFO - 'USER': 'cltbld', 09:01:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:53 INFO - 'XPC_FLAGS': '0x0', 09:01:53 INFO - 'XPC_SERVICE_NAME': '0', 09:01:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:53 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/UxB2rMrORJO7T7bvTzV67w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0300', '--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 09:01:53 INFO - mozversion application_buildid: 20160819064623 09:01:53 INFO - mozversion application_changeset: 3da4d64410c002418eab4b025dfb24a6f4f57fd6 09:01:53 INFO - mozversion application_display_name: Nightly 09:01:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:01:53 INFO - mozversion application_name: Firefox 09:01:53 INFO - mozversion application_remotingname: firefox 09:01:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:01:53 INFO - mozversion application_vendor: Mozilla 09:01:53 INFO - mozversion application_version: 51.0a1 09:01:53 INFO - mozversion platform_buildid: 20160819064623 09:01:53 INFO - mozversion platform_changeset: 3da4d64410c002418eab4b025dfb24a6f4f57fd6 09:01:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:01:53 INFO - mozversion platform_version: 51.0a1 09:01:53 INFO - using testdate: 1471622513 09:01:53 INFO - actual date: 1471622513 09:01:53 INFO - starting webserver on 'localhost:49267' 09:01:53 INFO - SUITE-START | Running 2 tests 09:01:53 INFO - TEST-START | dromaeo_css 09:01:53 INFO - operating with platform_type : mac_ 09:01:53 INFO - Initialising browser for dromaeo_css test... 09:01:53 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/tmpog6lNH/profile http://localhost:49267/getInfo.html) 09:01:56 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 09:01:56 INFO - PROCESS | 1642 | colorDepth:24 09:01:56 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 09:01:56 INFO - PROCESS | 1642 | __metrics 09:01:56 INFO - PROCESS | 1642 | 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" 09:01:56 INFO - PROCESS | 1642 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:01:56 INFO - TEST-INFO | 1642: exit 0 09:01:56 INFO - Browser initialized. 09:01:56 INFO - Running cycle 1/1 for dromaeo_css test... 09:01:56 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/tmpog6lNH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:02:08 INFO - PROCESS | 1644 | RSS: Main: 228241408 09:02:08 INFO - PROCESS | 1644 | 09:03:00 INFO - PROCESS | 1644 | 1471622580051 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] 09:05:12 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 09:05:13 INFO - PROCESS | 1644 | RSS: Main: 377085952 09:05:13 INFO - PROCESS | 1644 | 09:08:16 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 09:08:17 INFO - PROCESS | 1644 | RSS: Main: 369643520 09:08:17 INFO - PROCESS | 1644 | 09:11:19 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 09:11:20 INFO - PROCESS | 1644 | RSS: Main: 341561344 09:11:20 INFO - PROCESS | 1644 | 09:14:22 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 09:14:23 INFO - PROCESS | 1644 | RSS: Main: 337256448 09:14:23 INFO - PROCESS | 1644 | 09:17:26 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 09:17:27 INFO - PROCESS | 1644 | RSS: Main: 339816448 09:17:27 INFO - PROCESS | 1644 | 09:20:30 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 09:20:31 INFO - PROCESS | 1644 | RSS: Main: 391729152 09:20:31 INFO - PROCESS | 1644 | 09:20:31 INFO - PROCESS | 1644 | __start_tp_report 09:20:31 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 09:20:31 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 09:20:31 INFO - PROCESS | 1644 | |i|pagename|runs| 09:20:31 INFO - PROCESS | 1644 | |0;dojo.html;2776.8056236063485;3165;3357.681020303071;3362.125847279707;3384.424647809872;5644.717764111788;5781.682007489587;5796.681182534959;5882.88234235315;5908.497777688891;5794.536437085034;5817.912731309023;5890.2931648202275;5918.378570250126;5980.342162362148;5219.530242278194;5257.263983042377;5287.47125287472;5303.443138470463;5376.542993845528;5387.218853266294;5398.325209348832;5401.540869026137;5406.297181366417;5422.6475279106735;4166.500019997619;5170.689758614458;5238.345206849144;5322.361316642008;5349.411564727877;5224.582033437316;5237.371515418175;5240.685558866442;5253.106971814801;5258.6318957672975;5252.317198764136;5265.684058956436;5267.3679158500845;5338.866528336784;5372.462753724635;5533.22534845122;5743.339515955635;5816.563757718146;5864.208331875185;5947;6219.1915051043525;6242.157308763304;6258.624482531017;6273.435390814849;6290.402411770874;6243.43809057187;6283.5287353448675;6291.1506946562085;6295.96852015737;6302.968485157545;5816.592838501307;5893.499052580493;5899.115132730039;5953.017752070947;6052.878942421171;6173.043178307369;6232.563720539519;6261.217347831521;6265.937340626627;6267.561270711007;6176.258848938066;6221.346758590283;6232;6254.249490061222;6281.685915704196;6220.937790622036;6286;6290.968545157337;6295.527835412362;6309.400606942333;6210.31686514483;6241.594296370721;6243.625382477066;6245.437910587978;6258.342873998165;5243.528082472596;6170.629762214281;6219.191505104308;6227.626342419469;6239.719212635442;4761.262004389325;4774.379330686988;5035.420926593415;5077.1876499759865;5084.898302034013;4403.625691816232;4454.643628509712;4457.64338852891;4512.796924138422;4562.977185114053;5722.113072473773;5762.9135562966585;5777.971110144423;5909.556783241188;5928.466438020512;6191.349908259658;6256.749730010749;6301.6219026858535;6304.3695630437505;6307.274663413675;5938.495227905676;5938.762449501972;5943.10853371989;5971.193888824953;6001.789937352171;5405.675659460445;5468.261784659099;5532.446755324436;5545.639533430395;5564.998300305984;6325.3674632537295;6426.164598602152;6429.453496452854;6468.482521398183;6515.54391192612;6209.379062093754;6257.7184026719815;6265.530085243716;6283.7800676976085;6284.528660350584;6224.844378890564;6470.450011749054;6499.545031847725;6501.934980650133;6510.121133646897;6118.4059889896;6161.44546990773;6165.568410211242;6232.376762323732;6239.532035097477;4891.021795640957;6255.468285195628;6258.468030217483;6268.1851359323;6280.811575652738;6049.334573196857;6233.594816336924;6244.375562443901;6260.123582698335;6261.436470717657;5151.948480515297;6098.237720284965;6154.415330543591;6201.937980620136;6262.3111457738705;6117.96941015292;6159.168512250789;6177.62934223948;6179.412955769195;6186.505079593711;6125.84685382869;6126.670355029888;6264.5301602379095;6270.529710271839;6298.1812364392335;6037.124616930432;6083.452489275986;6088.665123418077;6130.386961303834;6158.199434073721 09:20:31 INFO - PROCESS | 1644 | |1;ext.html;424.9766262855543;527.3592585009213;550.243415303957;551.2282804074297;556.8190338140106;30952;31088.689113108863;42318.57681423185;42444.938876528104;44928.77535612325;44565;44940.77529612355;44943.55056449443;44972.55027449725;45018.87452813681;9788.951055244732;9990.600375984952;9991.150752186037;10005.449700266483;10006.59973601055;8858.069902660225;9101.36290459668;12041.217320874159;12313.507459701601;12335.5065797368;12204.267743935392;12241.143119981605;12263.75472490554;12273.815892761615;12289.754204915896;12223.327716975562;12265.754684906342;12290.815637765441;12295.139340246187;12296.754064918741;11998.820017699742;12091.576794812227;12096.395180241041;12112.454939527654;12114.909658130722;41666.58333416688;42010.78994605008;42065.78967105145;42226;42237.78881105575;3431.159365955357;3440.82795860206;3569.8750543731103;3635.0367152704553;3690.3541880170865;26987.46025079507;31849.681503184904;32899.67100329014;33007.174820629436;33403.99788006364;3186.522021696741;3224.0972527692284;3343.5319055332275;3352.9664703352896;3421.4867769834477;499347;503017;506154.4692276552;507480;513030;62656.06015909765;62783;63023;63026.36973630205;63074;65544.01683974745;65596;65596.34403655998;65658.67170664117;65668.67165664237;16609;16821.663566728595;16828.74756878648;16832.905861118987;16833.41083062087;11546;11780.057595392347;12866;12888.097833151776;12911.483540658268;17245;17903.641927161643;17944.730829037573;18276.45170644883;18439;5510.972445137749;5631.887362252814;5918.615290006136;6639.933600664028;6747.426468750211;854.8674955381925;971.1162841813915;1014.761531040205;1046.2885238038207;1049.375621505203;29327.853360733487;30243.848780755954;30728.924487642824;30887.8455607725;30966.53550196749;937.7843096087938;1077.466654006272;1107.5680484611007;1107.5791199344199;1125.5272785430138;15082.039334886615;15880.602984925239;15909;15978.360865565479;16296;657326;661732;661931;662807;663611;61162.33026018453;61230.693846530485;61558.38441615616;61578;61580.69209654013;1259.4836117191908;1390.8817750491119;1407.1768015710925;1424.401751264492;1428.264443811438;31243.25080990296;31913.68086319107;32030.19924501906;32317.35365292728;32847.67152328446;52229;52754;52823.47176528186;52893.47106528885;52926.73536632292;550940.245298771;553989;554977;555178;555544;26399.472010560065;27119.72880271172;27130;27911.720882790913;28029.57955630708;47826.5217347822;47983.522755472564;48768.756156218995;48863.755681222785;49471.01057978892;38958;39193.804030980806;39318;40038.599614003484;40879;39440.60559394369;40051;40123.79938100291;41050.79474602608;41470.37794433146;38966.22067558576;39620.40569391519;39648;39715.602843972345;39804.40293395659;30260.638934311424;32776;33440;33774.83112584421;35468;25648.358791030372;25822.61266081048;25925.35186620274;26185;26241.34396640023 09:20:31 INFO - PROCESS | 1644 | |2;jquery.html;331.6666749916334;356.3727839003353;369.6063692167842;377.10625816814144;394.1525719702639;14751.114933104021;17186.968781873096;20687.793122068775;20703.896480517615;20868.478288042825;20217.393478195678;20579.794202057976;20663.793362066375;20939.476513087182;20959.895200523977;5782.421757824215;5784.768609255625;5955.612885162473;6027.126066720323;6032;4925.408950925894;5944.791932282371;5953.047512398017;5959.940400596003;5960.731767070492;4093;6216.627002379849;6470.902936455934;6520.641364724938;6595.67021648918;6289.308176100625;6450.774222902222;6517.739290428377;6534.281229064799;6563.310852360505;5665.575081868835;5672.5178359839465;5672.659640421588;5675.290588676416;5677.091665333527;25845.870770646216;26055.869720651466;26123.085692000877;26481.602775958378;26901;2481.007596961212;2678.517866783978;2679.5846643770246;2785.56823692328;2812.198523420815;15304.464343748026;15639.609009774733;16507.752383714254;16636.33454661812;16679.749803752955;1560.3680509393714;1842.0973722875829;1896.2604584212168;1929.25723596415;1939.398786376228;67551.66224168897;77137.61431192808;92720;119449;121806.39096804548;8701.434406763667;10031;10264.948675256575;10818.080463160719;11062.059724923472;13423.127496712877;13426.865731342757;13438;13445.798313025116;13447.932760336136;7083.787486375417;8195.95902020498;8458.661653533913;8496.872546911678;8663.740087797376;8579.056303806576;8622.698205562776;8674.132586741276;8791.428557143765;8826.77933051666;5486.147203454181;6467.676616169173;6575.178102737158;6584.209894812653;6691.297413771484;1518.9316480758393;1762.3919747687028;1809.2401191499603;1810.5021119192327;1815.1650240889076;868.7046404222594;872.4329186029132;892.9241014513839;962.0908241711516;1003.5032658833778;21899.781002189775;22751.658725119138;22777.772222277566;22792.31623051311;22845.31464056081;905.7998152448033;989.9109080182818;1036.5439206749006;1050.6847945616285;1070.4425061535446;11612.12909031826;11832.76334473323;11884.643460696192;12010.879891200977;12062.336571488653;573171;573798;585846;591146;613169;109154;111732;112230.43884780524;118913;129547;779.9376049915993;802.7872613757243;822.5393779483509;824.6206744897295;826.2687521543477;18158.72761908599;19471.610567788848;19568.804311956697;19624.803751962296;19696;39797.49416521331;53260.73369633127;57198;57586.7120664394;58391;60301;70674.29325706678;80499.59750201211;83511;89155.55422222847;6641.535092543476;7702.691892324245;8198.467099638505;8252.46358986664;8375.37184711137;10513.949779792867;12672.619821405373;13501.662458438617;13898.58304250874;14000.789988150389;11371.829422558503;11509.827352589886;11860.406979651156;11941.223820451643;12039.03687704964;11007.394593297446;11381.089512838787;11480.425978700865;11597.942010289895;12018.939905300767;11602.6519204424;11687.415629218674;12038.819417708917;12083.879161208628;12163.695907602027;6866.553674011173;8192.467489613156;8279.917200827915;8394.286485648789;8408.327333813158;9297.256219502558;11647.068234541048;11867.643970680894;11867.76264474723;11970 09:20:31 INFO - PROCESS | 1644 | |3;mootools.html;1334.566265963562;1417.6668482906516;1499.4452052740485;1636.664483780825;1876.3057668662598;7638.312551870331;8398.202170793773;8612.22489975902;8619.655213791457;8777.736667899957;8527.360447966412;8606.139386061392;8613.784655383603;8630.91369086309;8633.827323453526;2353.705786776657;2381.249906279525;2383.8212134089927;2388.080588973249;2391;2332.463533387322;2377.9881100594557;2385.7375688674233;2390.605550084234;2392.617181251;2065.7417822772154;2169.056460439706;2359.1860808021206;2376.097083108425;2382.3091303522046;2314.90740370385;2343.660169275454;2351.388638953867;2358.5518751437344;2369.18263199196;2332.8483648562787;2333.171724037959;2334.171369163956;2338.5556744218543;2341.4497593065616;8422.831543369099;8552.358573107042;8655.913440865574;8673.566321683891;8673.653053877837;5122.231665250205;5257.658252213594;5285.524302812727;5290.629655924087;5304.814331498417;3562.5012498250267;3575.3743094958277;3635.8545658173643;3652.7443078984484;3659.304732100892;1070.9303895246785;1074.8925107489267;1076.0853274716508;1094.261373572835;1103.9613613523568;11195.440227988567;11245.775084498346;11390.316581005167;11413.315201087962;11431.485583148778;4558.772061396917;4600.930986035212;4783.425988881357;4804.135255654016;4816.95183048165;9835.803283934281;9848.310618256653;9848.556814943344;9854.50727463624;9869.605215791431;5305.098133317269;5307.442718514578;5312.62811603184;5312.787488500494;5313.149896016619;4334.393184954109;4499.21263778844;4675.462321833033;4807.278908163734;4848.151573474628;3816.4275358695863;3849.364854798983;3962.821673024721;3999.34010888206;4008.6392224699334;1275.2603489975793;1289.258676261135;1295.184034058537;1304.308716380301;1311.0953442125094;910.4491782471509;934.3508707131917;934.7756538430727;941.8493041113389;988.6292640259902;5716.114002329646;5838.153467747238;5879.353271140171;5885.911711324334;6179.938200618026;421.93670949357227;429.4202826184626;437.7482947305312;443.5453659998463;452.88451444881343;3969.384745364473;3972.523297204354;4072.6945479088595;4080.755154690728;4123.546409894909;9499.24006079512;10275.537600807833;10461;10568.313059651251;10587.523561439753;10536.209784266057;10539.104176145114;10552.050315471644;10552.788944221073;10553.683389498327;7742.419318551131;7900.920990790019;7994.400419968526;8006.3995200360205;8020.197980201933;4747.8367799887865;5806.361300257053;5833.970830145822;5855.151003104611;5935.5548333875995;9452.196563291922;9784.657536986171;9868.950655246677;9873.358231714916;10002.799944001225;9789.706308810748;9976.650817221362;10033.648822291476;10067.043630855058;10185.236107291834;6492.28584855656;6878.69045892926;7090.184628767758;7463.962680186564;7566.205548417557;8232.588370581327;8407.747767566983;8412.663493460437;8416.3266938646;8424.494530327955;7274.381677557467;7376.188619251985;7464.1789403166695;7498.775036748906;7520.285572870568;7256.2018177999325;7283.831309216546;7306.926930730838;7313.378362839218;7396.482246242711;6858.931410685829;6883.724651013903;6959.652017399211;6963.7214511421;6964.477664175309;4975.527324904128;5005.62457815658;5044.369453818273;5059.620528460454;5135.6405051646025;4949.9363279981735;5069.591943826653;5087.847364579068;5250.868728281671;5252.2646829444675 09:20:31 INFO - PROCESS | 1644 | |4;prototype.html;263.7170167136878;298.46574631409777;304.5188602008825;309.7521982414068;315.922598963254;4769.952300476994;5450.972745136274;5473.589480788937;5506.100395363598;5508.2288479612835;5283.947160528393;5390.8921821563545;5463.093887870172;5468.835934921949;5490.478404551562;3185.936281274373;3606.621304763008;3707.2400157967686;3923.489946306976;4113.4241206231;3846.4807251021175;4083;4103.974006498375;4112.773797441139;4168.791560421982;3537.787732736031;4069.633732964033;4074.1851629674184;4116.917661646765;4117.938230926538;3285.1458620758776;3537.8054207018604;3899.74651647642;3915.6671682906986;4125.587441255881;2635.472905418924;2936.8678409471627;3066.953995690066;3540.6105328413855;3540.7875527468436;4804.879878003043;4885.706857588556;4951.8266860659705;5258.947410525922;5296.470352964711;4151.4395556599775;4375.628071613916;4706.22347312693;4711.670183087151;4720.645951553613;5132.8460146195675;5709.914351284734;5722.713864306768;5724.513416359614;5727.914081288784;3211.3577284542953;3243.4486137356707;3891.9610803892165;3904.0239940014994;3909.9413508797393;4834.6374021948495;5434.6630948692045;5667.319921609392;5899.077113719414;5964.284285885678;3432.2792213634916;3901.765894046366;3901.8049097545577;3927.9607203928167;3937.9761262071493;3523.9295214095573;3646.6507392264502;3967;3974.5230572331507;3979.243943650697;4614.307853821953;4651.37206477128;4664.883377915511;4673.3690951721765;4686.835960741358;4536.6143877770755;5552.9449404613;5664.5076166486615;5682.318121825408;5728.851680619768;1183.644906528038;1346.4412268908488;1347.8247827782325;1350.8041334006514;1356.572679605921;778.4706399648294;813.8372325534916;827.6234313387395;828.7099515169763;830.9376796740269;4506.369108324837;4666.556677115668;5187.792488300426;5267.26258323835;5425.341582624485;5877.766448749448;5936.673482958392;5938.406159384027;5966.850828729316;5970.701464926737;4802.711837289774;5022.137637928821;5106.693598384109;5391.487808658171;5409.594280428983;2520.760527749861;3081.2142903559447;3082.5222090576;3096.4581198290207;3108.735757460596;5506.724663766796;5990.460858522754;6090.23872015998;6093.207882975274;6094.939050609526;5399.244105825188;5813.424743782628;5888.764449422061;6019.608725432833;6037.879242415127;4538.2511885538515;5094.133997220481;5525.889482210333;5821.476067153976;6069.575129740964;5316.415194328698;5676.744546495335;5906.763729450774;5916.136417028622;5967.6121052131475;5540.473655002768;5749.310082790092;5790.525684589213;5809.738561764646;5852.297724273115;6059.152677857608;6076.787312444043;6085.66528840918;6112.266528016667;6175.876482470415;3539;3853.2100919310915;3920.2159568085926;3925.0776067623515;3937.192875460581;5914;5929.436703513159;5931.733072011856;5942.7920022799;5952.0476723724;4757.904841903073;5152.872891473008;5655.575831812548;5818.2436283282905;5856.150858125633;4525.34382514527;5461.15352120422;5676.375598684224;5758.62568933018;5794.739236734273;5087.516685914832;5465;5652.85867853307;5670;5688.184348747396;4745.1221524018165;4941.827036053721;5268.367795864521;5509.228707980806;5794.82615521535;5096.847094587169;5637.46444087811;5639.436056394327;5696.686682232351;5702.918434771195 09:20:31 INFO - PROCESS | 1644 | |5;yui.html;722.3571021790607;729.7737701312593;735.8785800342944;749.5914726474072;825.6325934958944;15305.84694153058;16133.515994520169;16406.999999999985;16528.42150524732;16538;14772.778408323857;14866.925665371658;14915.179665118414;14929.776053359208;14941.402343906258;666.6333516565869;684.274668851017;825.0511911301991;827.3670641958876;847.2501835875264;833.4082801211148;850.3239924260197;854.8076682746395;855.8673405622108;856.533189411769;906.2478143141177;907.6732376344511;908.500324821349;908.5275656658533;908.6501696846728;700.8388070743706;807.1081454992208;897.9236764874928;903.9502827344527;904.2133343990704;668.4719071933216;668.735849339513;668.8294484906318;669.3172963577124;669.8559809640902;15128.924355378263;15136.54590362291;15166.848331516765;15178.089314641047;15216.391344346213;11785.88214117865;13994.230317332542;14465.20441375724;14484.565463036126;14569.781453278209;13237.470501180038;13291.53479628218;13436.731265374736;13544.45822167112;13843.238621875793;4612.907741845178;4691.15559199344;4841.080194763019;4917.0903382874285;4928.67963582366;120831;123880;123933;125123.37438312841;125360;9361.765955851022;11101.611443599435;11518.136139789549;11643.825342619868;11990.640280791591;23008.309750707507;23057.84710764455;23080;23098;23132.190373336853;7749.7287594933905;7932.246436588515;8019.879701804478;8102.554359510284;8252.587370631445;4376.540463251376;4462.866114016585;4514.751688657156;4521.932171017437;4669.089527542097;3516.49010893419;3676.852925882988;3677.632236776301;3679.9080022999105;3683.4290684943876;650.6616559389091;655.4035827397046;663.9701213445406;664.1134085995154;673.1719984419192;554.8723793527512;590.7577893063823;633.4742164003866;658.3646780856401;662.7448432353482;9187.95406022979;9608.90391096094;9719.65981190655;9782.461964592016;9835.16401105914;623.6040114527308;628.6510986402499;645.4481418387305;653.9574927629689;696.7526528082458;4707.058588282357;4792.329073929653;4793.161196790617;4795.640326975491;4807.375041244686;94335;105882.4117638236;131268.0619581395;161052;161333;113505.43247283711;135095;138502;141639;144897;121872;122738.99999999821;124333;126214.7378526203;127078;7658.846823063396;8842;9062.637494500146;9163.954180229057;9200.585973631069;61794;62495.375046248955;63285.683571581845;63631.36368636254;63941;100694.4965275169;102498;103439.44840827544;103602;105078.47460762953;11796.587119450778;11889;11897.82153267719;12038.578649747216;12375.443105060114;11677.532898683958;13864.722705545631;14617.488387906373;14650.90118241115;15130.621734456727;12659.113862029937;13912.58262252134;13978.37097330643;14201.112639827868;14695;13526.154830198659;13888.902723890591;14014.509492167725;14061.156330620195;14086.506972255922;13715.314234288446;13770;13825.79261311061;13848.861511384757;13978.231197284247;10439.217058720778;10559.841602376124;10637.202209834139;10775.08411785007;10813.783724325627;9137.954310228406;9314.301427393108;10685.839712404164;10939.671809845717;11385 09:20:31 INFO - PROCESS | 1644 | __end_tp_report 09:20:31 INFO - PROCESS | 1644 | __start_cc_report 09:20:31 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,1412 09:20:31 INFO - PROCESS | 1644 | __end_cc_report 09:20:31 INFO - PROCESS | 1644 | __startTimestamp1471623631151__endTimestamp 09:20:31 INFO - PROCESS | 1644 | 09:20:31 INFO - PROCESS | 1644 | ------- Summary: start ------- 09:20:31 INFO - PROCESS | 1644 | Number of tests: 6 09:20:31 INFO - PROCESS | 1644 | 09:20:31 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5799.60 Median:6098.24 stddev:680.39 (11.2%) stddev-sans-first:639.83 09:20:31 INFO - PROCESS | 1644 | Values: 2776.8 3165.0 3357.7 3362.1 3384.4 5644.7 5781.7 5796.7 5882.9 5908.5 5794.5 5817.9 5890.3 5918.4 5980.3 5219.5 5257.3 5287.5 5303.4 5376.5 5387.2 5398.3 5401.5 5406.3 5422.6 4166.5 5170.7 5238.3 5322.4 5349.4 5224.6 5237.4 5240.7 5253.1 5258.6 5252.3 5265.7 5267.4 5338.9 5372.5 5533.2 5743.3 5816.6 5864.2 5947.0 6219.2 6242.2 6258.6 6273.4 6290.4 6243.4 6283.5 6291.2 6296.0 6303.0 5816.6 5893.5 5899.1 5953.0 6052.9 6173.0 6232.6 6261.2 6265.9 6267.6 6176.3 6221.3 6232.0 6254.2 6281.7 6220.9 6286.0 6291.0 6295.5 6309.4 6210.3 6241.6 6243.6 6245.4 6258.3 5243.5 6170.6 6219.2 6227.6 6239.7 4761.3 4774.4 5035.4 5077.2 5084.9 4403.6 4454.6 4457.6 4512.8 4563.0 5722.1 5762.9 5778.0 5909.6 5928.5 6191.3 6256.7 6301.6 6304.4 6307.3 5938.5 5938.8 5943.1 5971.2 6001.8 5405.7 5468.3 5532.4 5545.6 5565.0 6325.4 6426.2 6429.5 6468.5 6515.5 6209.4 6257.7 6265.5 6283.8 6284.5 6224.8 6470.5 6499.5 6501.9 6510.1 6118.4 6161.4 6165.6 6232.4 6239.5 4891.0 6255.5 6258.5 6268.2 6280.8 6049.3 6233.6 6244.4 6260.1 6261.4 5151.9 6098.2 6154.4 6201.9 6262.3 6118.0 6159.2 6177.6 6179.4 6186.5 6125.8 6126.7 6264.5 6270.5 6298.2 6037.1 6083.5 6088.7 6130.4 6158.2 09:20:31 INFO - PROCESS | 1644 | 09:20:31 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:71542.82 Median:19518.94 stddev:154149.80 (789.7%) stddev-sans-first:154489.76 09:20:31 INFO - PROCESS | 1644 | Values: 425.0 527.4 550.2 551.2 556.8 30952.0 31088.7 42318.6 42444.9 44928.8 44565.0 44940.8 44943.6 44972.6 45018.9 9789.0 9990.6 9991.2 10005.4 10006.6 8858.1 9101.4 12041.2 12313.5 12335.5 12204.3 12241.1 12263.8 12273.8 12289.8 12223.3 12265.8 12290.8 12295.1 12296.8 11998.8 12091.6 12096.4 12112.5 12114.9 41666.6 42010.8 42065.8 42226.0 42237.8 3431.2 3440.8 3569.9 3635.0 3690.4 26987.5 31849.7 32899.7 33007.2 33404.0 3186.5 3224.1 3343.5 3353.0 3421.5 499347.0 503017.0 506154.5 507480.0 513030.0 62656.1 62783.0 63023.0 63026.4 63074.0 65544.0 65596.0 65596.3 65658.7 65668.7 16609.0 16821.7 16828.7 16832.9 16833.4 11546.0 11780.1 12866.0 12888.1 12911.5 17245.0 17903.6 17944.7 18276.5 18439.0 5511.0 5631.9 5918.6 6639.9 6747.4 854.9 971.1 1014.8 1046.3 1049.4 29327.9 30243.8 30728.9 30887.8 30966.5 937.8 1077.5 1107.6 1107.6 1125.5 15082.0 15880.6 15909.0 15978.4 16296.0 657326.0 661732.0 661931.0 662807.0 663611.0 61162.3 61230.7 61558.4 61578.0 61580.7 1259.5 1390.9 1407.2 1424.4 1428.3 31243.3 31913.7 32030.2 32317.4 32847.7 52229.0 52754.0 52823.5 52893.5 52926.7 550940.2 553989.0 554977.0 555178.0 555544.0 26399.5 27119.7 27130.0 27911.7 28029.6 47826.5 47983.5 48768.8 48863.8 49471.0 38958.0 39193.8 39318.0 40038.6 40879.0 39440.6 40051.0 40123.8 41050.8 41470.4 38966.2 39620.4 39648.0 39715.6 39804.4 30260.6 32776.0 33440.0 33774.8 35468.0 25648.4 25822.6 25925.4 26185.0 26241.3 09:20:31 INFO - PROCESS | 1644 | 09:20:31 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:34071.45 Median:14790.29 stddev:97435.74 (658.8%) stddev-sans-first:97676.14 09:20:31 INFO - PROCESS | 1644 | Values: 331.7 356.4 369.6 377.1 394.2 14751.1 17187.0 20687.8 20703.9 20868.5 20217.4 20579.8 20663.8 20939.5 20959.9 5782.4 5784.8 5955.6 6027.1 6032.0 4925.4 5944.8 5953.0 5959.9 5960.7 4093.0 6216.6 6470.9 6520.6 6595.7 6289.3 6450.8 6517.7 6534.3 6563.3 5665.6 5672.5 5672.7 5675.3 5677.1 25845.9 26055.9 26123.1 26481.6 26901.0 2481.0 2678.5 2679.6 2785.6 2812.2 15304.5 15639.6 16507.8 16636.3 16679.7 1560.4 1842.1 1896.3 1929.3 1939.4 67551.7 77137.6 92720.0 119449.0 121806.4 8701.4 10031.0 10264.9 10818.1 11062.1 13423.1 13426.9 13438.0 13445.8 13447.9 7083.8 8196.0 8458.7 8496.9 8663.7 8579.1 8622.7 8674.1 8791.4 8826.8 5486.1 6467.7 6575.2 6584.2 6691.3 1518.9 1762.4 1809.2 1810.5 1815.2 868.7 872.4 892.9 962.1 1003.5 21899.8 22751.7 22777.8 22792.3 22845.3 905.8 989.9 1036.5 1050.7 1070.4 11612.1 11832.8 11884.6 12010.9 12062.3 573171.0 573798.0 585846.0 591146.0 613169.0 109154.0 111732.0 112230.4 118913.0 129547.0 779.9 802.8 822.5 824.6 826.3 18158.7 19471.6 19568.8 19624.8 19696.0 39797.5 53260.7 57198.0 57586.7 58391.0 60301.0 70674.3 80499.6 83511.0 89155.6 6641.5 7702.7 8198.5 8252.5 8375.4 10513.9 12672.6 13501.7 13898.6 14000.8 11371.8 11509.8 11860.4 11941.2 12039.0 11007.4 11381.1 11480.4 11597.9 12018.9 11602.7 11687.4 12038.8 12083.9 12163.7 6866.6 8192.5 8279.9 8394.3 8408.3 9297.3 11647.1 11867.6 11867.8 11970.0 09:20:31 INFO - PROCESS | 1644 | 09:20:31 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:5631.08 Median:4990.58 stddev:3140.36 (62.9%) stddev-sans-first:3132.56 09:20:31 INFO - PROCESS | 1644 | Values: 1334.6 1417.7 1499.4 1636.7 1876.3 7638.3 8398.2 8612.2 8619.7 8777.7 8527.4 8606.1 8613.8 8630.9 8633.8 2353.7 2381.2 2383.8 2388.1 2391.0 2332.5 2378.0 2385.7 2390.6 2392.6 2065.7 2169.1 2359.2 2376.1 2382.3 2314.9 2343.7 2351.4 2358.6 2369.2 2332.8 2333.2 2334.2 2338.6 2341.4 8422.8 8552.4 8655.9 8673.6 8673.7 5122.2 5257.7 5285.5 5290.6 5304.8 3562.5 3575.4 3635.9 3652.7 3659.3 1070.9 1074.9 1076.1 1094.3 1104.0 11195.4 11245.8 11390.3 11413.3 11431.5 4558.8 4600.9 4783.4 4804.1 4817.0 9835.8 9848.3 9848.6 9854.5 9869.6 5305.1 5307.4 5312.6 5312.8 5313.1 4334.4 4499.2 4675.5 4807.3 4848.2 3816.4 3849.4 3962.8 3999.3 4008.6 1275.3 1289.3 1295.2 1304.3 1311.1 910.4 934.4 934.8 941.8 988.6 5716.1 5838.2 5879.4 5885.9 6179.9 421.9 429.4 437.7 443.5 452.9 3969.4 3972.5 4072.7 4080.8 4123.5 9499.2 10275.5 10461.0 10568.3 10587.5 10536.2 10539.1 10552.1 10552.8 10553.7 7742.4 7900.9 7994.4 8006.4 8020.2 4747.8 5806.4 5834.0 5855.2 5935.6 9452.2 9784.7 9869.0 9873.4 10002.8 9789.7 9976.7 10033.6 10067.0 10185.2 6492.3 6878.7 7090.2 7464.0 7566.2 8232.6 8407.7 8412.7 8416.3 8424.5 7274.4 7376.2 7464.2 7498.8 7520.3 7256.2 7283.8 7306.9 7313.4 7396.5 6858.9 6883.7 6959.7 6963.7 6964.5 4975.5 5005.6 5044.4 5059.6 5135.6 4949.9 5069.6 5087.8 5250.9 5252.3 09:20:31 INFO - PROCESS | 1644 | 09:20:31 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4577.73 Median:5170.33 stddev:1461.05 (28.3%) stddev-sans-first:1428.82 09:20:31 INFO - PROCESS | 1644 | Values: 263.7 298.5 304.5 309.8 315.9 4770.0 5451.0 5473.6 5506.1 5508.2 5283.9 5390.9 5463.1 5468.8 5490.5 3185.9 3606.6 3707.2 3923.5 4113.4 3846.5 4083.0 4104.0 4112.8 4168.8 3537.8 4069.6 4074.2 4116.9 4117.9 3285.1 3537.8 3899.7 3915.7 4125.6 2635.5 2936.9 3067.0 3540.6 3540.8 4804.9 4885.7 4951.8 5258.9 5296.5 4151.4 4375.6 4706.2 4711.7 4720.6 5132.8 5709.9 5722.7 5724.5 5727.9 3211.4 3243.4 3892.0 3904.0 3909.9 4834.6 5434.7 5667.3 5899.1 5964.3 3432.3 3901.8 3901.8 3928.0 3938.0 3523.9 3646.7 3967.0 3974.5 3979.2 4614.3 4651.4 4664.9 4673.4 4686.8 4536.6 5552.9 5664.5 5682.3 5728.9 1183.6 1346.4 1347.8 1350.8 1356.6 778.5 813.8 827.6 828.7 830.9 4506.4 4666.6 5187.8 5267.3 5425.3 5877.8 5936.7 5938.4 5966.9 5970.7 4802.7 5022.1 5106.7 5391.5 5409.6 2520.8 3081.2 3082.5 3096.5 3108.7 5506.7 5990.5 6090.2 6093.2 6094.9 5399.2 5813.4 5888.8 6019.6 6037.9 4538.3 5094.1 5525.9 5821.5 6069.6 5316.4 5676.7 5906.8 5916.1 5967.6 5540.5 5749.3 5790.5 5809.7 5852.3 6059.2 6076.8 6085.7 6112.3 6175.9 3539.0 3853.2 3920.2 3925.1 3937.2 5914.0 5929.4 5931.7 5942.8 5952.0 4757.9 5152.9 5655.6 5818.2 5856.2 4525.3 5461.2 5676.4 5758.6 5794.7 5087.5 5465.0 5652.9 5670.0 5688.2 4745.1 4941.8 5268.4 5509.2 5794.8 5096.8 5637.5 5639.4 5696.7 5702.9 09:20:31 INFO - PROCESS | 1644 | 09:20:31 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:25751.87 Median:13324.34 stddev:41241.32 (309.5%) stddev-sans-first:41314.20 09:20:31 INFO - PROCESS | 1644 | Values: 722.4 729.8 735.9 749.6 825.6 15305.8 16133.5 16407.0 16528.4 16538.0 14772.8 14866.9 14915.2 14929.8 14941.4 666.6 684.3 825.1 827.4 847.3 833.4 850.3 854.8 855.9 856.5 906.2 907.7 908.5 908.5 908.7 700.8 807.1 897.9 904.0 904.2 668.5 668.7 668.8 669.3 669.9 15128.9 15136.5 15166.8 15178.1 15216.4 11785.9 13994.2 14465.2 14484.6 14569.8 13237.5 13291.5 13436.7 13544.5 13843.2 4612.9 4691.2 4841.1 4917.1 4928.7 120831.0 123880.0 123933.0 125123.4 125360.0 9361.8 11101.6 11518.1 11643.8 11990.6 23008.3 23057.8 23080.0 23098.0 23132.2 7749.7 7932.2 8019.9 8102.6 8252.6 4376.5 4462.9 4514.8 4521.9 4669.1 3516.5 3676.9 3677.6 3679.9 3683.4 650.7 655.4 664.0 664.1 673.2 554.9 590.8 633.5 658.4 662.7 9188.0 9608.9 9719.7 9782.5 9835.2 623.6 628.7 645.4 654.0 696.8 4707.1 4792.3 4793.2 4795.6 4807.4 94335.0 105882.4 131268.1 161052.0 161333.0 113505.4 135095.0 138502.0 141639.0 144897.0 121872.0 122739.0 124333.0 126214.7 127078.0 7658.8 8842.0 9062.6 9164.0 9200.6 61794.0 62495.4 63285.7 63631.4 63941.0 100694.5 102498.0 103439.4 103602.0 105078.5 11796.6 11889.0 11897.8 12038.6 12375.4 11677.5 13864.7 14617.5 14650.9 15130.6 12659.1 13912.6 13978.4 14201.1 14695.0 13526.2 13888.9 14014.5 14061.2 14086.5 13715.3 13770.0 13825.8 13848.9 13978.2 10439.2 10559.8 10637.2 10775.1 10813.8 9138.0 9314.3 10685.8 10939.7 11385.0 09:20:31 INFO - PROCESS | 1644 | -------- Summary: end -------- 09:20:31 INFO - PROCESS | 1644 | 09:20:31 INFO - TEST-INFO | 1644: exit 0 09:20:31 INFO - TEST-OK | dromaeo_css | took 1118160ms 09:20:31 INFO - TEST-START | kraken 09:20:31 INFO - operating with platform_type : mac_ 09:20:31 INFO - Initialising browser for kraken test... 09:20:31 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/tmpFNEUwF/profile http://localhost:49267/getInfo.html) 09:20:34 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 09:20:34 INFO - PROCESS | 1654 | colorDepth:24 09:20:34 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 09:20:34 INFO - PROCESS | 1654 | __metrics 09:20:34 INFO - PROCESS | 1654 | 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" 09:20:34 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:20:34 INFO - TEST-INFO | 1654: exit 0 09:20:34 INFO - Browser initialized. 09:20:34 INFO - Running cycle 1/1 for kraken test... 09:20:34 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/tmpFNEUwF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:20:46 INFO - PROCESS | 1655 | RSS: Main: 228278272 09:20:46 INFO - PROCESS | 1655 | 09:20:49 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 09:20:50 INFO - PROCESS | 1655 | RSS: Main: 284872704 09:20:50 INFO - PROCESS | 1655 | 09:20:53 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 09:20:53 INFO - PROCESS | 1655 | RSS: Main: 293724160 09:20:53 INFO - PROCESS | 1655 | 09:20:57 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 09:20:57 INFO - PROCESS | 1655 | RSS: Main: 387461120 09:20:57 INFO - PROCESS | 1655 | 09:20:59 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 09:21:00 INFO - PROCESS | 1655 | RSS: Main: 290344960 09:21:00 INFO - PROCESS | 1655 | 09:21:02 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:21:03 INFO - PROCESS | 1655 | RSS: Main: 294551552 09:21:03 INFO - PROCESS | 1655 | 09:21:06 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 09:21:06 INFO - PROCESS | 1655 | RSS: Main: 330043392 09:21:06 INFO - PROCESS | 1655 | 09:21:09 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 09:21:10 INFO - PROCESS | 1655 | RSS: Main: 332312576 09:21:10 INFO - PROCESS | 1655 | 09:21:13 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 09:21:13 INFO - PROCESS | 1655 | RSS: Main: 331448320 09:21:13 INFO - PROCESS | 1655 | 09:21:15 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:21:16 INFO - PROCESS | 1655 | RSS: Main: 300437504 09:21:16 INFO - PROCESS | 1655 | 09:21:17 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:21:18 INFO - PROCESS | 1655 | RSS: Main: 307720192 09:21:18 INFO - PROCESS | 1655 | 09:21:20 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:21:21 INFO - PROCESS | 1655 | RSS: Main: 306720768 09:21:21 INFO - PROCESS | 1655 | 09:21:24 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:21:25 INFO - PROCESS | 1655 | RSS: Main: 313462784 09:21:25 INFO - PROCESS | 1655 | 09:21:28 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:21:28 INFO - PROCESS | 1655 | RSS: Main: 316100608 09:21:28 INFO - PROCESS | 1655 | 09:21:30 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 09:21:30 INFO - PROCESS | 1655 | RSS: Main: 358961152 09:21:30 INFO - PROCESS | 1655 | 09:21:30 INFO - PROCESS | 1655 | __start_tp_report 09:21:30 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 09:21:30 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 09:21:30 INFO - PROCESS | 1655 | |i|pagename|runs| 09:21:30 INFO - PROCESS | 1655 | |0;ai-astar;102;111;112;113;108;113;112;112;111;111 09:21:30 INFO - PROCESS | 1655 | |1;audio-beat-detection;142;115;107;162;126;138;131;107;161;108 09:21:30 INFO - PROCESS | 1655 | |2;audio-dft;184;185;182;183;182;181;185;185;184;183 09:21:30 INFO - PROCESS | 1655 | |3;audio-fft;74;78;57;87;63;56;58;59;127;82 09:21:30 INFO - PROCESS | 1655 | |4;audio-oscillator;82;91;97;73;72;68;97;70;68;103 09:21:30 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;88;86;90;86;88;85;89;91;133;129 09:21:30 INFO - PROCESS | 1655 | |6;imaging-darkroom;93;93;93;95;94;94;100;142;93;94 09:21:30 INFO - PROCESS | 1655 | |7;imaging-desaturate;84;85;84;87;86;86;87;86;84;83 09:21:30 INFO - PROCESS | 1655 | |8;json-parse-financial;55;54;55;54;53;54;55;53;55;52 09:21:30 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;65;72;79;74;72;68;67;70;70;70 09:21:30 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;86;88;85;88;80;82;87;85;89;93 09:21:30 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;140;149;158;163;136;137;136;144;228;135 09:21:30 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;156;170;167;172;230;200;167;167;167;169 09:21:30 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;68;64;72;86;72;67;66;68;67;67 09:21:30 INFO - PROCESS | 1655 | __end_tp_report 09:21:30 INFO - PROCESS | 1655 | __start_cc_report 09:21:30 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,2102 09:21:30 INFO - PROCESS | 1655 | __end_cc_report 09:21:30 INFO - PROCESS | 1655 | __startTimestamp1471623690757__endTimestamp 09:21:30 INFO - PROCESS | 1655 | 09:21:30 INFO - PROCESS | 1655 | ------- Summary: start ------- 09:21:30 INFO - PROCESS | 1655 | Number of tests: 14 09:21:30 INFO - PROCESS | 1655 | 09:21:30 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:110.50 Median:112.00 stddev:3.31 (3.0%) stddev-sans-first:1.51 09:21:30 INFO - PROCESS | 1655 | Values: 102.0 111.0 112.0 113.0 108.0 113.0 112.0 112.0 111.0 111.0 09:21:30 INFO - PROCESS | 1655 | 09:21:30 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:129.70 Median:134.50 stddev:21.02 (15.6%) stddev-sans-first:21.82 09:21:30 INFO - PROCESS | 1655 | Values: 142.0 115.0 107.0 162.0 126.0 138.0 131.0 107.0 161.0 108.0 09:21:30 INFO - PROCESS | 1655 | 09:21:30 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:183.40 Median:184.00 stddev:1.43 (0.8%) stddev-sans-first:1.50 09:21:30 INFO - PROCESS | 1655 | Values: 184.0 185.0 182.0 183.0 182.0 181.0 185.0 185.0 184.0 183.0 09:21:30 INFO - PROCESS | 1655 | 09:21:30 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:74.10 Median:68.50 stddev:21.79 (31.8%) stddev-sans-first:23.11 09:21:30 INFO - PROCESS | 1655 | Values: 74.0 78.0 57.0 87.0 63.0 56.0 58.0 59.0 127.0 82.0 09:21:30 INFO - PROCESS | 1655 | 09:21:30 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:82.10 Median:77.50 stddev:13.70 (17.7%) stddev-sans-first:14.53 09:21:30 INFO - PROCESS | 1655 | Values: 82.0 91.0 97.0 73.0 72.0 68.0 97.0 70.0 68.0 103.0 09:21:30 INFO - PROCESS | 1655 | 09:21:30 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:96.50 Median:88.00 stddev:18.30 (20.8%) stddev-sans-first:19.15 09:21:30 INFO - PROCESS | 1655 | Values: 88.0 86.0 90.0 86.0 88.0 85.0 89.0 91.0 133.0 129.0 09:21:30 INFO - PROCESS | 1655 | 09:21:30 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:99.10 Median:93.50 stddev:15.22 (16.3%) stddev-sans-first:15.98 09:21:30 INFO - PROCESS | 1655 | Values: 93.0 93.0 93.0 95.0 94.0 94.0 100.0 142.0 93.0 94.0 09:21:30 INFO - PROCESS | 1655 | 09:21:30 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:85.20 Median:86.00 stddev:1.40 (1.6%) stddev-sans-first:1.41 09:21:30 INFO - PROCESS | 1655 | Values: 84.0 85.0 84.0 87.0 86.0 86.0 87.0 86.0 84.0 83.0 09:21:30 INFO - PROCESS | 1655 | 09:21:30 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.50 stddev:1.05 (1.9%) stddev-sans-first:1.05 09:21:30 INFO - PROCESS | 1655 | Values: 55.0 54.0 55.0 54.0 53.0 54.0 55.0 53.0 55.0 52.0 09:21:30 INFO - PROCESS | 1655 | 09:21:30 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:71.00 stddev:3.92 (5.5%) stddev-sans-first:3.57 09:21:30 INFO - PROCESS | 1655 | Values: 65.0 72.0 79.0 74.0 72.0 68.0 67.0 70.0 70.0 70.0 09:21:30 INFO - PROCESS | 1655 | 09:21:30 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:86.30 Median:87.50 stddev:3.65 (4.2%) stddev-sans-first:3.87 09:21:30 INFO - PROCESS | 1655 | Values: 86.0 88.0 85.0 88.0 80.0 82.0 87.0 85.0 89.0 93.0 09:21:30 INFO - PROCESS | 1655 | 09:21:30 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:152.60 Median:146.50 stddev:28.23 (19.3%) stddev-sans-first:29.57 09:21:30 INFO - PROCESS | 1655 | Values: 140.0 149.0 158.0 163.0 136.0 137.0 136.0 144.0 228.0 135.0 09:21:30 INFO - PROCESS | 1655 | 09:21:30 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.50 Median:169.50 stddev:21.89 (12.9%) stddev-sans-first:21.93 09:21:30 INFO - PROCESS | 1655 | Values: 156.0 170.0 167.0 172.0 230.0 200.0 167.0 167.0 167.0 169.0 09:21:30 INFO - PROCESS | 1655 | 09:21:30 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.70 Median:68.00 stddev:6.24 (9.2%) stddev-sans-first:6.58 09:21:30 INFO - PROCESS | 1655 | Values: 68.0 64.0 72.0 86.0 72.0 67.0 66.0 68.0 67.0 67.0 09:21:30 INFO - PROCESS | 1655 | -------- Summary: end -------- 09:21:30 INFO - PROCESS | 1655 | 09:21:31 INFO - TEST-INFO | 1655: exit 0 09:21:31 INFO - TEST-OK | kraken | took 59806ms 09:21:31 INFO - SUITE-END | took 1177s 09:21:31 INFO - Completed test suite (00:19:38) 09:21:31 INFO - javascript benchmark 09:21:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2776.8056236063485, 3165.0, 3357.681020303071, 3362.125847279707, 3384.424647809872, 5644.717764111788, 5781.682007489587, 5796.681182534959, 5882.88234235315, 5908.497777688891, 5794.536437085034, 5817.912731309023, 5890.2931648202275, 5918.378570250126, 5980.342162362148, 5219.530242278194, 5257.263983042377, 5287.47125287472, 5303.443138470463, 5376.542993845528, 5387.218853266294, 5398.325209348832, 5401.540869026137, 5406.297181366417, 5422.6475279106735, 4166.500019997619, 5170.689758614458, 5238.345206849144, 5322.361316642008, 5349.411564727877, 5224.582033437316, 5237.371515418175, 5240.685558866442, 5253.106971814801, 5258.6318957672975, 5252.317198764136, 5265.684058956436, 5267.3679158500845, 5338.866528336784, 5372.462753724635, 5533.22534845122, 5743.339515955635, 5816.563757718146, 5864.208331875185, 5947.0, 6219.1915051043525, 6242.157308763304, 6258.624482531017, 6273.435390814849, 6290.402411770874, 6243.43809057187, 6283.5287353448675, 6291.1506946562085, 6295.96852015737, 6302.968485157545, 5816.592838501307, 5893.499052580493, 5899.115132730039, 5953.017752070947, 6052.878942421171, 6173.043178307369, 6232.563720539519, 6261.217347831521, 6265.937340626627, 6267.561270711007, 6176.258848938066, 6221.346758590283, 6232.0, 6254.249490061222, 6281.685915704196, 6220.937790622036, 6286.0, 6290.968545157337, 6295.527835412362, 6309.400606942333, 6210.31686514483, 6241.594296370721, 6243.625382477066, 6245.437910587978, 6258.342873998165, 5243.528082472596, 6170.629762214281, 6219.191505104308, 6227.626342419469, 6239.719212635442, 4761.262004389325, 4774.379330686988, 5035.420926593415, 5077.1876499759865, 5084.898302034013, 4403.625691816232, 4454.643628509712, 4457.64338852891, 4512.796924138422, 4562.977185114053, 5722.113072473773, 5762.9135562966585, 5777.971110144423, 5909.556783241188, 5928.466438020512, 6191.349908259658, 6256.749730010749, 6301.6219026858535, 6304.3695630437505, 6307.274663413675, 5938.495227905676, 5938.762449501972, 5943.10853371989, 5971.193888824953, 6001.789937352171, 5405.675659460445, 5468.261784659099, 5532.446755324436, 5545.639533430395, 5564.998300305984, 6325.3674632537295, 6426.164598602152, 6429.453496452854, 6468.482521398183, 6515.54391192612, 6209.379062093754, 6257.7184026719815, 6265.530085243716, 6283.7800676976085, 6284.528660350584, 6224.844378890564, 6470.450011749054, 6499.545031847725, 6501.934980650133, 6510.121133646897, 6118.4059889896, 6161.44546990773, 6165.568410211242, 6232.376762323732, 6239.532035097477, 4891.021795640957, 6255.468285195628, 6258.468030217483, 6268.1851359323, 6280.811575652738, 6049.334573196857, 6233.594816336924, 6244.375562443901, 6260.123582698335, 6261.436470717657, 5151.948480515297, 6098.237720284965, 6154.415330543591, 6201.937980620136, 6262.3111457738705, 6117.96941015292, 6159.168512250789, 6177.62934223948, 6179.412955769195, 6186.505079593711, 6125.84685382869, 6126.670355029888, 6264.5301602379095, 6270.529710271839, 6298.1812364392335, 6037.124616930432, 6083.452489275986, 6088.665123418077, 6130.386961303834, 6158.199434073721], "value": 5753.847037540109, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.9766262855543, 527.3592585009213, 550.243415303957, 551.2282804074297, 556.8190338140106, 30952.0, 31088.689113108863, 42318.57681423185, 42444.938876528104, 44928.77535612325, 44565.0, 44940.77529612355, 44943.55056449443, 44972.55027449725, 45018.87452813681, 9788.951055244732, 9990.600375984952, 9991.150752186037, 10005.449700266483, 10006.59973601055, 8858.069902660225, 9101.36290459668, 12041.217320874159, 12313.507459701601, 12335.5065797368, 12204.267743935392, 12241.143119981605, 12263.75472490554, 12273.815892761615, 12289.754204915896, 12223.327716975562, 12265.754684906342, 12290.815637765441, 12295.139340246187, 12296.754064918741, 11998.820017699742, 12091.576794812227, 12096.395180241041, 12112.454939527654, 12114.909658130722, 41666.58333416688, 42010.78994605008, 42065.78967105145, 42226.0, 42237.78881105575, 3431.159365955357, 3440.82795860206, 3569.8750543731103, 3635.0367152704553, 3690.3541880170865, 26987.46025079507, 31849.681503184904, 32899.67100329014, 33007.174820629436, 33403.99788006364, 3186.522021696741, 3224.0972527692284, 3343.5319055332275, 3352.9664703352896, 3421.4867769834477, 499347.0, 503017.0, 506154.4692276552, 507480.0, 513030.0, 62656.06015909765, 62783.0, 63023.0, 63026.36973630205, 63074.0, 65544.01683974745, 65596.0, 65596.34403655998, 65658.67170664117, 65668.67165664237, 16609.0, 16821.663566728595, 16828.74756878648, 16832.905861118987, 16833.41083062087, 11546.0, 11780.057595392347, 12866.0, 12888.097833151776, 12911.483540658268, 17245.0, 17903.641927161643, 17944.730829037573, 18276.45170644883, 18439.0, 5510.972445137749, 5631.887362252814, 5918.615290006136, 6639.933600664028, 6747.426468750211, 854.8674955381925, 971.1162841813915, 1014.761531040205, 1046.2885238038207, 1049.375621505203, 29327.853360733487, 30243.848780755954, 30728.924487642824, 30887.8455607725, 30966.53550196749, 937.7843096087938, 1077.466654006272, 1107.5680484611007, 1107.5791199344199, 1125.5272785430138, 15082.039334886615, 15880.602984925239, 15909.0, 15978.360865565479, 16296.0, 657326.0, 661732.0, 661931.0, 662807.0, 663611.0, 61162.33026018453, 61230.693846530485, 61558.38441615616, 61578.0, 61580.69209654013, 1259.4836117191908, 1390.8817750491119, 1407.1768015710925, 1424.401751264492, 1428.264443811438, 31243.25080990296, 31913.68086319107, 32030.19924501906, 32317.35365292728, 32847.67152328446, 52229.0, 52754.0, 52823.47176528186, 52893.47106528885, 52926.73536632292, 550940.245298771, 553989.0, 554977.0, 555178.0, 555544.0, 26399.472010560065, 27119.72880271172, 27130.0, 27911.720882790913, 28029.57955630708, 47826.5217347822, 47983.522755472564, 48768.756156218995, 48863.755681222785, 49471.01057978892, 38958.0, 39193.804030980806, 39318.0, 40038.599614003484, 40879.0, 39440.60559394369, 40051.0, 40123.79938100291, 41050.79474602608, 41470.37794433146, 38966.22067558576, 39620.40569391519, 39648.0, 39715.602843972345, 39804.40293395659, 30260.638934311424, 32776.0, 33440.0, 33774.83112584421, 35468.0, 25648.358791030372, 25822.61266081048, 25925.35186620274, 26185.0, 26241.34396640023], "value": 20747.51848821173, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.6666749916334, 356.3727839003353, 369.6063692167842, 377.10625816814144, 394.1525719702639, 14751.114933104021, 17186.968781873096, 20687.793122068775, 20703.896480517615, 20868.478288042825, 20217.393478195678, 20579.794202057976, 20663.793362066375, 20939.476513087182, 20959.895200523977, 5782.421757824215, 5784.768609255625, 5955.612885162473, 6027.126066720323, 6032.0, 4925.408950925894, 5944.791932282371, 5953.047512398017, 5959.940400596003, 5960.731767070492, 4093.0, 6216.627002379849, 6470.902936455934, 6520.641364724938, 6595.67021648918, 6289.308176100625, 6450.774222902222, 6517.739290428377, 6534.281229064799, 6563.310852360505, 5665.575081868835, 5672.5178359839465, 5672.659640421588, 5675.290588676416, 5677.091665333527, 25845.870770646216, 26055.869720651466, 26123.085692000877, 26481.602775958378, 26901.0, 2481.007596961212, 2678.517866783978, 2679.5846643770246, 2785.56823692328, 2812.198523420815, 15304.464343748026, 15639.609009774733, 16507.752383714254, 16636.33454661812, 16679.749803752955, 1560.3680509393714, 1842.0973722875829, 1896.2604584212168, 1929.25723596415, 1939.398786376228, 67551.66224168897, 77137.61431192808, 92720.0, 119449.0, 121806.39096804548, 8701.434406763667, 10031.0, 10264.948675256575, 10818.080463160719, 11062.059724923472, 13423.127496712877, 13426.865731342757, 13438.0, 13445.798313025116, 13447.932760336136, 7083.787486375417, 8195.95902020498, 8458.661653533913, 8496.872546911678, 8663.740087797376, 8579.056303806576, 8622.698205562776, 8674.132586741276, 8791.428557143765, 8826.77933051666, 5486.147203454181, 6467.676616169173, 6575.178102737158, 6584.209894812653, 6691.297413771484, 1518.9316480758393, 1762.3919747687028, 1809.2401191499603, 1810.5021119192327, 1815.1650240889076, 868.7046404222594, 872.4329186029132, 892.9241014513839, 962.0908241711516, 1003.5032658833778, 21899.781002189775, 22751.658725119138, 22777.772222277566, 22792.31623051311, 22845.31464056081, 905.7998152448033, 989.9109080182818, 1036.5439206749006, 1050.6847945616285, 1070.4425061535446, 11612.12909031826, 11832.76334473323, 11884.643460696192, 12010.879891200977, 12062.336571488653, 573171.0, 573798.0, 585846.0, 591146.0, 613169.0, 109154.0, 111732.0, 112230.43884780524, 118913.0, 129547.0, 779.9376049915993, 802.7872613757243, 822.5393779483509, 824.6206744897295, 826.2687521543477, 18158.72761908599, 19471.610567788848, 19568.804311956697, 19624.803751962296, 19696.0, 39797.49416521331, 53260.73369633127, 57198.0, 57586.7120664394, 58391.0, 60301.0, 70674.29325706678, 80499.59750201211, 83511.0, 89155.55422222847, 6641.535092543476, 7702.691892324245, 8198.467099638505, 8252.46358986664, 8375.37184711137, 10513.949779792867, 12672.619821405373, 13501.662458438617, 13898.58304250874, 14000.789988150389, 11371.829422558503, 11509.827352589886, 11860.406979651156, 11941.223820451643, 12039.03687704964, 11007.394593297446, 11381.089512838787, 11480.425978700865, 11597.942010289895, 12018.939905300767, 11602.6519204424, 11687.415629218674, 12038.819417708917, 12083.879161208628, 12163.695907602027, 6866.553674011173, 8192.467489613156, 8279.917200827915, 8394.286485648789, 8408.327333813158, 9297.256219502558, 11647.068234541048, 11867.643970680894, 11867.76264474723, 11970.0], "value": 9730.769243655768, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1334.566265963562, 1417.6668482906516, 1499.4452052740485, 1636.664483780825, 1876.3057668662598, 7638.312551870331, 8398.202170793773, 8612.22489975902, 8619.655213791457, 8777.736667899957, 8527.360447966412, 8606.139386061392, 8613.784655383603, 8630.91369086309, 8633.827323453526, 2353.705786776657, 2381.249906279525, 2383.8212134089927, 2388.080588973249, 2391.0, 2332.463533387322, 2377.9881100594557, 2385.7375688674233, 2390.605550084234, 2392.617181251, 2065.7417822772154, 2169.056460439706, 2359.1860808021206, 2376.097083108425, 2382.3091303522046, 2314.90740370385, 2343.660169275454, 2351.388638953867, 2358.5518751437344, 2369.18263199196, 2332.8483648562787, 2333.171724037959, 2334.171369163956, 2338.5556744218543, 2341.4497593065616, 8422.831543369099, 8552.358573107042, 8655.913440865574, 8673.566321683891, 8673.653053877837, 5122.231665250205, 5257.658252213594, 5285.524302812727, 5290.629655924087, 5304.814331498417, 3562.5012498250267, 3575.3743094958277, 3635.8545658173643, 3652.7443078984484, 3659.304732100892, 1070.9303895246785, 1074.8925107489267, 1076.0853274716508, 1094.261373572835, 1103.9613613523568, 11195.440227988567, 11245.775084498346, 11390.316581005167, 11413.315201087962, 11431.485583148778, 4558.772061396917, 4600.930986035212, 4783.425988881357, 4804.135255654016, 4816.95183048165, 9835.803283934281, 9848.310618256653, 9848.556814943344, 9854.50727463624, 9869.605215791431, 5305.098133317269, 5307.442718514578, 5312.62811603184, 5312.787488500494, 5313.149896016619, 4334.393184954109, 4499.21263778844, 4675.462321833033, 4807.278908163734, 4848.151573474628, 3816.4275358695863, 3849.364854798983, 3962.821673024721, 3999.34010888206, 4008.6392224699334, 1275.2603489975793, 1289.258676261135, 1295.184034058537, 1304.308716380301, 1311.0953442125094, 910.4491782471509, 934.3508707131917, 934.7756538430727, 941.8493041113389, 988.6292640259902, 5716.114002329646, 5838.153467747238, 5879.353271140171, 5885.911711324334, 6179.938200618026, 421.93670949357227, 429.4202826184626, 437.7482947305312, 443.5453659998463, 452.88451444881343, 3969.384745364473, 3972.523297204354, 4072.6945479088595, 4080.755154690728, 4123.546409894909, 9499.24006079512, 10275.537600807833, 10461.0, 10568.313059651251, 10587.523561439753, 10536.209784266057, 10539.104176145114, 10552.050315471644, 10552.788944221073, 10553.683389498327, 7742.419318551131, 7900.920990790019, 7994.400419968526, 8006.3995200360205, 8020.197980201933, 4747.8367799887865, 5806.361300257053, 5833.970830145822, 5855.151003104611, 5935.5548333875995, 9452.196563291922, 9784.657536986171, 9868.950655246677, 9873.358231714916, 10002.799944001225, 9789.706308810748, 9976.650817221362, 10033.648822291476, 10067.043630855058, 10185.236107291834, 6492.28584855656, 6878.69045892926, 7090.184628767758, 7463.962680186564, 7566.205548417557, 8232.588370581327, 8407.747767566983, 8412.663493460437, 8416.3266938646, 8424.494530327955, 7274.381677557467, 7376.188619251985, 7464.1789403166695, 7498.775036748906, 7520.285572870568, 7256.2018177999325, 7283.831309216546, 7306.926930730838, 7313.378362839218, 7396.482246242711, 6858.931410685829, 6883.724651013903, 6959.652017399211, 6963.7214511421, 6964.477664175309, 4975.527324904128, 5005.62457815658, 5044.369453818273, 5059.620528460454, 5135.6405051646025, 4949.9363279981735, 5069.591943826653, 5087.847364579068, 5250.868728281671, 5252.2646829444675], "value": 4466.085423835399, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [263.7170167136878, 298.46574631409777, 304.5188602008825, 309.7521982414068, 315.922598963254, 4769.952300476994, 5450.972745136274, 5473.589480788937, 5506.100395363598, 5508.2288479612835, 5283.947160528393, 5390.8921821563545, 5463.093887870172, 5468.835934921949, 5490.478404551562, 3185.936281274373, 3606.621304763008, 3707.2400157967686, 3923.489946306976, 4113.4241206231, 3846.4807251021175, 4083.0, 4103.974006498375, 4112.773797441139, 4168.791560421982, 3537.787732736031, 4069.633732964033, 4074.1851629674184, 4116.917661646765, 4117.938230926538, 3285.1458620758776, 3537.8054207018604, 3899.74651647642, 3915.6671682906986, 4125.587441255881, 2635.472905418924, 2936.8678409471627, 3066.953995690066, 3540.6105328413855, 3540.7875527468436, 4804.879878003043, 4885.706857588556, 4951.8266860659705, 5258.947410525922, 5296.470352964711, 4151.4395556599775, 4375.628071613916, 4706.22347312693, 4711.670183087151, 4720.645951553613, 5132.8460146195675, 5709.914351284734, 5722.713864306768, 5724.513416359614, 5727.914081288784, 3211.3577284542953, 3243.4486137356707, 3891.9610803892165, 3904.0239940014994, 3909.9413508797393, 4834.6374021948495, 5434.6630948692045, 5667.319921609392, 5899.077113719414, 5964.284285885678, 3432.2792213634916, 3901.765894046366, 3901.8049097545577, 3927.9607203928167, 3937.9761262071493, 3523.9295214095573, 3646.6507392264502, 3967.0, 3974.5230572331507, 3979.243943650697, 4614.307853821953, 4651.37206477128, 4664.883377915511, 4673.3690951721765, 4686.835960741358, 4536.6143877770755, 5552.9449404613, 5664.5076166486615, 5682.318121825408, 5728.851680619768, 1183.644906528038, 1346.4412268908488, 1347.8247827782325, 1350.8041334006514, 1356.572679605921, 778.4706399648294, 813.8372325534916, 827.6234313387395, 828.7099515169763, 830.9376796740269, 4506.369108324837, 4666.556677115668, 5187.792488300426, 5267.26258323835, 5425.341582624485, 5877.766448749448, 5936.673482958392, 5938.406159384027, 5966.850828729316, 5970.701464926737, 4802.711837289774, 5022.137637928821, 5106.693598384109, 5391.487808658171, 5409.594280428983, 2520.760527749861, 3081.2142903559447, 3082.5222090576, 3096.4581198290207, 3108.735757460596, 5506.724663766796, 5990.460858522754, 6090.23872015998, 6093.207882975274, 6094.939050609526, 5399.244105825188, 5813.424743782628, 5888.764449422061, 6019.608725432833, 6037.879242415127, 4538.2511885538515, 5094.133997220481, 5525.889482210333, 5821.476067153976, 6069.575129740964, 5316.415194328698, 5676.744546495335, 5906.763729450774, 5916.136417028622, 5967.6121052131475, 5540.473655002768, 5749.310082790092, 5790.525684589213, 5809.738561764646, 5852.297724273115, 6059.152677857608, 6076.787312444043, 6085.66528840918, 6112.266528016667, 6175.876482470415, 3539.0, 3853.2100919310915, 3920.2159568085926, 3925.0776067623515, 3937.192875460581, 5914.0, 5929.436703513159, 5931.733072011856, 5942.7920022799, 5952.0476723724, 4757.904841903073, 5152.872891473008, 5655.575831812548, 5818.2436283282905, 5856.150858125633, 4525.34382514527, 5461.15352120422, 5676.375598684224, 5758.62568933018, 5794.739236734273, 5087.516685914832, 5465.0, 5652.85867853307, 5670.0, 5688.184348747396, 4745.1221524018165, 4941.827036053721, 5268.367795864521, 5509.228707980806, 5794.82615521535, 5096.847094587169, 5637.46444087811, 5639.436056394327, 5696.686682232351, 5702.918434771195], "value": 4103.264287673597, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [722.3571021790607, 729.7737701312593, 735.8785800342944, 749.5914726474072, 825.6325934958944, 15305.84694153058, 16133.515994520169, 16406.999999999985, 16528.42150524732, 16538.0, 14772.778408323857, 14866.925665371658, 14915.179665118414, 14929.776053359208, 14941.402343906258, 666.6333516565869, 684.274668851017, 825.0511911301991, 827.3670641958876, 847.2501835875264, 833.4082801211148, 850.3239924260197, 854.8076682746395, 855.8673405622108, 856.533189411769, 906.2478143141177, 907.6732376344511, 908.500324821349, 908.5275656658533, 908.6501696846728, 700.8388070743706, 807.1081454992208, 897.9236764874928, 903.9502827344527, 904.2133343990704, 668.4719071933216, 668.735849339513, 668.8294484906318, 669.3172963577124, 669.8559809640902, 15128.924355378263, 15136.54590362291, 15166.848331516765, 15178.089314641047, 15216.391344346213, 11785.88214117865, 13994.230317332542, 14465.20441375724, 14484.565463036126, 14569.781453278209, 13237.470501180038, 13291.53479628218, 13436.731265374736, 13544.45822167112, 13843.238621875793, 4612.907741845178, 4691.15559199344, 4841.080194763019, 4917.0903382874285, 4928.67963582366, 120831.0, 123880.0, 123933.0, 125123.37438312841, 125360.0, 9361.765955851022, 11101.611443599435, 11518.136139789549, 11643.825342619868, 11990.640280791591, 23008.309750707507, 23057.84710764455, 23080.0, 23098.0, 23132.190373336853, 7749.7287594933905, 7932.246436588515, 8019.879701804478, 8102.554359510284, 8252.587370631445, 4376.540463251376, 4462.866114016585, 4514.751688657156, 4521.932171017437, 4669.089527542097, 3516.49010893419, 3676.852925882988, 3677.632236776301, 3679.9080022999105, 3683.4290684943876, 650.6616559389091, 655.4035827397046, 663.9701213445406, 664.1134085995154, 673.1719984419192, 554.8723793527512, 590.7577893063823, 633.4742164003866, 658.3646780856401, 662.7448432353482, 9187.95406022979, 9608.90391096094, 9719.65981190655, 9782.461964592016, 9835.16401105914, 623.6040114527308, 628.6510986402499, 645.4481418387305, 653.9574927629689, 696.7526528082458, 4707.058588282357, 4792.329073929653, 4793.161196790617, 4795.640326975491, 4807.375041244686, 94335.0, 105882.4117638236, 131268.0619581395, 161052.0, 161333.0, 113505.43247283711, 135095.0, 138502.0, 141639.0, 144897.0, 121872.0, 122738.99999999821, 124333.0, 126214.7378526203, 127078.0, 7658.846823063396, 8842.0, 9062.637494500146, 9163.954180229057, 9200.585973631069, 61794.0, 62495.375046248955, 63285.683571581845, 63631.36368636254, 63941.0, 100694.4965275169, 102498.0, 103439.44840827544, 103602.0, 105078.47460762953, 11796.587119450778, 11889.0, 11897.82153267719, 12038.578649747216, 12375.443105060114, 11677.532898683958, 13864.722705545631, 14617.488387906373, 14650.90118241115, 15130.621734456727, 12659.113862029937, 13912.58262252134, 13978.37097330643, 14201.112639827868, 14695.0, 13526.154830198659, 13888.902723890591, 14014.509492167725, 14061.156330620195, 14086.506972255922, 13715.314234288446, 13770.0, 13825.79261311061, 13848.861511384757, 13978.231197284247, 10439.217058720778, 10559.841602376124, 10637.202209834139, 10775.08411785007, 10813.783724325627, 9137.954310228406, 9314.301427393108, 10685.839712404164, 10939.671809845717, 11385.0], "value": 7953.654480279725, "unit": "score"}], "name": "dromaeo_css", "value": 7438.017767412645, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 112.0, 113.0, 108.0, 113.0, 112.0, 112.0, 111.0, 111.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 115.0, 107.0, 162.0, 126.0, 138.0, 131.0, 107.0, 161.0, 108.0], "value": 129.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 182.0, 183.0, 182.0, 181.0, 185.0, 185.0, 184.0, 183.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 78.0, 57.0, 87.0, 63.0, 56.0, 58.0, 59.0, 127.0, 82.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 91.0, 97.0, 73.0, 72.0, 68.0, 97.0, 70.0, 68.0, 103.0], "value": 82.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 90.0, 86.0, 88.0, 85.0, 89.0, 91.0, 133.0, 129.0], "value": 96.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 95.0, 94.0, 94.0, 100.0, 142.0, 93.0, 94.0], "value": 99.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 84.0, 87.0, 86.0, 86.0, 87.0, 86.0, 84.0, 83.0], "value": 85.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 54.0, 53.0, 54.0, 55.0, 53.0, 55.0, 52.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 79.0, 74.0, 72.0, 68.0, 67.0, 70.0, 70.0, 70.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 85.0, 88.0, 80.0, 82.0, 87.0, 85.0, 89.0, 93.0], "value": 86.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 149.0, 158.0, 163.0, 136.0, 137.0, 136.0, 144.0, 228.0, 135.0], "value": 152.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 170.0, 167.0, 172.0, 230.0, 200.0, 167.0, 167.0, 167.0, 169.0], "value": 176.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 64.0, 72.0, 86.0, 72.0, 67.0, 66.0, 68.0, 67.0, 67.0], "value": 69.7, "unit": "score"}], "name": "kraken", "value": 1470.4, "alertThreshold": 2.0}]} 09:21:32 INFO - Return code: 0 09:21:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:21:32 INFO - # TBPL SUCCESS # 09:21:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:21:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:21:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:21:32 INFO - 2016-08-19 09:21:32.097 screenresolution[1659:9911] starting screenresolution argv=screenresolution get 09:21:32 INFO - 2016-08-19 09:21:32.099 screenresolution[1659:9911] Display 0: 1600x1200x32@60 09:21:32 INFO - 2016-08-19 09:21:32.105 screenresolution[1660:9914] starting screenresolution argv=screenresolution list 09:21:32 INFO - Available Modes on Display 0 09:21:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:21:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:21:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:21:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:21:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:21:32 INFO - Intel Iris: 09:21:32 INFO - Chipset Model: Intel Iris 09:21:32 INFO - Type: GPU 09:21:32 INFO - Bus: Built-In 09:21:32 INFO - VRAM (Dynamic, Max): 1536 MB 09:21:32 INFO - Vendor: Intel (0x8086) 09:21:32 INFO - Device ID: 0x0a2e 09:21:32 INFO - Revision ID: 0x0009 09:21:32 INFO - Displays: 09:21:32 INFO - Display: 09:21:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:21:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:21:32 INFO - Display Serial Number: 1600x1200 60 09:21:32 INFO - Main Display: Yes 09:21:32 INFO - Mirror: Off 09:21:32 INFO - Online: Yes 09:21:32 INFO - Rotation: Supported 09:21:32 INFO - Return code: 0 09:21:32 INFO - Running post-action listener: _resource_record_post_action 09:21:32 INFO - [mozharness: 2016-08-19 16:21:32.338420Z] Finished run-tests step (success) 09:21:32 INFO - Running post-run listener: _resource_record_post_run 09:21:32 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 36754432; Write bytes: 703354368; Read time: 235; Write time: 1979 09:21:32 INFO - TinderboxPrint: CPU usage
53.4% 09:21:32 INFO - TinderboxPrint: I/O read bytes / time
36,754,432 / 235 09:21:32 INFO - TinderboxPrint: I/O write bytes / time
703,354,368 / 1,979 09:21:32 INFO - TinderboxPrint: CPU idle
2,262.0 (46.6%) 09:21:32 INFO - TinderboxPrint: CPU system
969.7 (20.0%) 09:21:32 INFO - TinderboxPrint: CPU user
1,622.0 (33.4%) 09:21:32 INFO - TinderboxPrint: Swap in / out
442,658,816 / 0 09:21:32 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 390529536; Write bytes: 384189952; Read time: 29601; Write time: 791 09:21:32 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 35795968; Write bytes: 295800832; Read time: 223; Write time: 1126 09:21:32 INFO - Running post-run listener: _upload_blobber_files 09:21:32 INFO - Blob upload gear active. 09:21:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:21:32 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 09:21:32 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'] 09:21:32 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 09:21:33 INFO - (blobuploader) - INFO - Open directory for files ... 09:21:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:21:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:21:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:21:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:21:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:21:34 INFO - (blobuploader) - INFO - Done attempting. 09:21:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:21:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:21:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:21:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:21:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:21:35 INFO - (blobuploader) - INFO - Done attempting. 09:21:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:21:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:21:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:21:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:21:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:21:37 INFO - (blobuploader) - INFO - Done attempting. 09:21:37 INFO - (blobuploader) - INFO - Iteration through files over. 09:21:37 INFO - Return code: 0 09:21:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:21:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:21:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/961515e714120a96de6eadb8dd8b16489f601ee8a18c4eb478f4623beb99a6d3dcc09442edef52041bbdcad0b5974323672faab5971845384327563e93c54a07", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d22ecbb5d5b82382e46668fd3bb85f11f8cf9dfe7caa9f320ef20a3183106848079176a1c4973724284dd12aa8e1492d433520fd3bdd6f4a367db779f9a8cb0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/522c7a28d95c86d23a79fa9fdc8eefdf41ade34e1bdb307a092cb5d20c8840a4e2ffaa3af281c25725618dfcfa87e856f59ef2bef4378119422f4e240a470b16"} 09:21:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:21:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:21:37 INFO - Contents: 09:21:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/961515e714120a96de6eadb8dd8b16489f601ee8a18c4eb478f4623beb99a6d3dcc09442edef52041bbdcad0b5974323672faab5971845384327563e93c54a07", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d22ecbb5d5b82382e46668fd3bb85f11f8cf9dfe7caa9f320ef20a3183106848079176a1c4973724284dd12aa8e1492d433520fd3bdd6f4a367db779f9a8cb0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/522c7a28d95c86d23a79fa9fdc8eefdf41ade34e1bdb307a092cb5d20c8840a4e2ffaa3af281c25725618dfcfa87e856f59ef2bef4378119422f4e240a470b16"} 09:21:37 INFO - Running post-run listener: copy_logs_to_upload_dir 09:21:37 INFO - Copying logs to upload dir... 09:21:37 INFO - mkdir: /builds/slave/test/build/upload/logs 09:21:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.187290 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-19 09:21:37.699387) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-19 09:21:37.704950) ========= 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.pUWtkckLDn/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.Z22FxKtoWF/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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/961515e714120a96de6eadb8dd8b16489f601ee8a18c4eb478f4623beb99a6d3dcc09442edef52041bbdcad0b5974323672faab5971845384327563e93c54a07", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d22ecbb5d5b82382e46668fd3bb85f11f8cf9dfe7caa9f320ef20a3183106848079176a1c4973724284dd12aa8e1492d433520fd3bdd6f4a367db779f9a8cb0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/522c7a28d95c86d23a79fa9fdc8eefdf41ade34e1bdb307a092cb5d20c8840a4e2ffaa3af281c25725618dfcfa87e856f59ef2bef4378119422f4e240a470b16"} build_url:https://queue.taskcluster.net/v1/task/UxB2rMrORJO7T7bvTzV67w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011068 build_url: 'https://queue.taskcluster.net/v1/task/UxB2rMrORJO7T7bvTzV67w/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/961515e714120a96de6eadb8dd8b16489f601ee8a18c4eb478f4623beb99a6d3dcc09442edef52041bbdcad0b5974323672faab5971845384327563e93c54a07", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d22ecbb5d5b82382e46668fd3bb85f11f8cf9dfe7caa9f320ef20a3183106848079176a1c4973724284dd12aa8e1492d433520fd3bdd6f4a367db779f9a8cb0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/522c7a28d95c86d23a79fa9fdc8eefdf41ade34e1bdb307a092cb5d20c8840a4e2ffaa3af281c25725618dfcfa87e856f59ef2bef4378119422f4e240a470b16"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-19 09:21:37.737816) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 09:21:37.738161) ========= 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.pUWtkckLDn/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.Z22FxKtoWF/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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.005137 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 09:21:37.787729) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-19 09:21:37.788040) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-19 09:21:37.788382) ========= ========= Total master_lag: 0.17 =========