builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0045 starttime: 1468862504.45 results: success (0) buildid: 20160718080853 builduid: e8d7db434dfb4e04a16936b3b1a2a1a1 revision: cde56ead650fd302be1d440507485b9abf7c163a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 10:21:44.451572) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 10:21:44.452299) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2016-07-18 10:21:44.452822) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2016-07-18 10:21:46.725906) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-18 10:21:46.726298) ========= 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.Gk7XUNOyyq/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.NNgosTQjhj/Listeners TMPDIR=/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-18 10:21:46-- 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% 39.8M=0s 2016-07-18 10:21:46 (39.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.168304 ========= master_lag: 2.27 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-18 10:21:49.160929) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 10:21:49.161275) ========= 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.Gk7XUNOyyq/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.NNgosTQjhj/Listeners TMPDIR=/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/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.028569 ========= master_lag: 0.48 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 10:21:49.673665) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-18 10:21:49.674033) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cde56ead650fd302be1d440507485b9abf7c163a --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 cde56ead650fd302be1d440507485b9abf7c163a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Gk7XUNOyyq/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.NNgosTQjhj/Listeners TMPDIR=/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-18 10:21:49,749 truncating revision to first 12 chars 2016-07-18 10:21:49,749 Setting DEBUG logging. 2016-07-18 10:21:49,749 attempt 1/10 2016-07-18 10:21:49,750 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cde56ead650f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-18 10:21:50,454 unpacking tar archive at: mozilla-central-cde56ead650f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.986681 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-18 10:21:50.682831) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 10:21:50.683148) ========= script_repo_revision: cde56ead650fd302be1d440507485b9abf7c163a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 10:21:50.683542) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 10:21:50.683804) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 10:21:50.701340) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-07-18 10:21:50.701732) ========= /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.Gk7XUNOyyq/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.NNgosTQjhj/Listeners TMPDIR=/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/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 10:21:50 INFO - MultiFileLogger online at 20160718 10:21:50 in /builds/slave/test 10:21:50 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 10:21:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:21:50 INFO - {'append_to_log': False, 10:21:50 INFO - 'base_work_dir': '/builds/slave/test', 10:21:50 INFO - 'blob_upload_branch': 'Firefox', 10:21:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:21:50 INFO - 'branch': 'Firefox', 10:21:50 INFO - 'buildbot_json_path': 'buildprops.json', 10:21:50 INFO - 'config_files': ('talos/mac_config.py',), 10:21:50 INFO - 'default_actions': ('clobber', 10:21:50 INFO - 'read-buildbot-config', 10:21:50 INFO - 'download-and-extract', 10:21:50 INFO - 'populate-webroot', 10:21:50 INFO - 'create-virtualenv', 10:21:50 INFO - 'install', 10:21:50 INFO - 'run-tests'), 10:21:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:21:50 INFO - 'download_minidump_stackwalk': True, 10:21:50 INFO - 'download_symbols': 'ondemand', 10:21:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:21:50 INFO - 'tooltool.py': '/tools/tooltool.py', 10:21:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:21:50 INFO - '/tools/misc-python/virtualenv.py')}, 10:21:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:21:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:21:50 INFO - 'installer_path': 'installer.exe', 10:21:50 INFO - 'log_level': 'info', 10:21:50 INFO - 'log_name': 'talos', 10:21:50 INFO - 'log_to_console': True, 10:21:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:21:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:21:50 INFO - 'opt_config_files': (), 10:21:50 INFO - 'pip_index': False, 10:21:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:21:50 INFO - 'cmd': ('bash', 10:21:50 INFO - '-c', 10:21:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:21:50 INFO - 'enabled': True, 10:21:50 INFO - 'halt_on_failure': False, 10:21:50 INFO - 'name': 'check_screen_resolution'},), 10:21:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:21:50 INFO - 'cmd': ('bash', 10:21:50 INFO - '-c', 10:21:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:21:50 INFO - 'enabled': True, 10:21:50 INFO - 'halt_on_failure': False, 10:21:50 INFO - 'name': 'check_screen_resolution'},), 10:21:50 INFO - 'run_cmd_checks_enabled': True, 10:21:50 INFO - 'sps_profile': False, 10:21:50 INFO - 'sps_profile_interval': 0, 10:21:50 INFO - 'suite': 'dromaeojs', 10:21:50 INFO - 'system_bits': '32', 10:21:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:21:50 INFO - 'title': 't-yosemite-r7-0045', 10:21:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:21:50 INFO - 'use_talos_json': True, 10:21:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:21:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:21:50 INFO - 'work_dir': 'build'} 10:21:50 INFO - ##### 10:21:50 INFO - ##### Running clobber step. 10:21:50 INFO - ##### 10:21:50 INFO - Running pre-action listener: _resource_record_pre_action 10:21:50 INFO - Running main action method: clobber 10:21:50 INFO - rmtree: /builds/slave/test/build 10:21:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:21:52 INFO - Running post-action listener: _resource_record_post_action 10:21:52 INFO - ##### 10:21:52 INFO - ##### Finished clobber step (success) 10:21:52 INFO - ##### 10:21:52 INFO - ##### 10:21:52 INFO - ##### Running read-buildbot-config step. 10:21:52 INFO - ##### 10:21:52 INFO - Running pre-action listener: _resource_record_pre_action 10:21:52 INFO - Running main action method: read_buildbot_config 10:21:52 INFO - Using buildbot properties: 10:21:52 INFO - { 10:21:52 INFO - "project": "", 10:21:52 INFO - "product": "firefox", 10:21:52 INFO - "script_repo_revision": "production", 10:21:52 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 10:21:52 INFO - "repository": "", 10:21:52 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 10:21:52 INFO - "buildid": "20160718080853", 10:21:52 INFO - "pgo_build": "False", 10:21:52 INFO - "basedir": "/builds/slave/test", 10:21:52 INFO - "buildnumber": 14, 10:21:52 INFO - "slavename": "t-yosemite-r7-0045", 10:21:52 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 10:21:52 INFO - "platform": "yosemite_r7", 10:21:52 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 10:21:52 INFO - "branch": "mozilla-central", 10:21:52 INFO - "repo_path": "mozilla-central", 10:21:52 INFO - "revision": "cde56ead650fd302be1d440507485b9abf7c163a", 10:21:52 INFO - "stage_platform": "macosx64", 10:21:52 INFO - "builduid": "e8d7db434dfb4e04a16936b3b1a2a1a1", 10:21:52 INFO - "slavebuilddir": "test" 10:21:52 INFO - } 10:21:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 10:21:52 INFO - Running post-action listener: _resource_record_post_action 10:21:52 INFO - ##### 10:21:52 INFO - ##### Finished read-buildbot-config step (success) 10:21:52 INFO - ##### 10:21:52 INFO - ##### 10:21:52 INFO - ##### Running download-and-extract step. 10:21:52 INFO - ##### 10:21:52 INFO - Running pre-action listener: _resource_record_pre_action 10:21:52 INFO - Running main action method: download_and_extract 10:21:52 INFO - mkdir: /builds/slave/test/build/tests 10:21:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:21:52 INFO - https://queue.taskcluster.net/v1/task/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:21:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 10:21:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 10:21:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 10:21:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 10:21:53 INFO - Downloaded 1199 bytes. 10:21:53 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 10:21:53 INFO - Using the following test package requirements: 10:21:53 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 10:21:53 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:21:53 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 10:21:53 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:21:53 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 10:21:53 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:21:53 INFO - u'jsshell-mac.zip'], 10:21:53 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:21:53 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 10:21:53 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 10:21:53 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:21:53 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 10:21:53 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:21:53 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 10:21:53 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:21:53 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 10:21:53 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:21:53 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 10:21:53 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:21: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:21:53 INFO - https://queue.taskcluster.net/v1/task/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:21:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:21:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:21:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:21:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:21:59 INFO - Downloaded 18835404 bytes. 10:21:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:21:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:22:02 INFO - Return code: 0 10:22:02 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:22:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:22:02 INFO - https://queue.taskcluster.net/v1/task/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:22:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:22:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:22:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:22:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:22:05 INFO - Downloaded 18835404 bytes. 10:22:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:22:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:22:08 INFO - Return code: 0 10:22:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:22:08 INFO - https://queue.taskcluster.net/v1/task/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:22:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 10:22:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 10:22:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 10:22:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:22:09 INFO - Downloaded 14403317 bytes. 10:22:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:22:09 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 10:22:10 INFO - Return code: 0 10:22:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:22:10 INFO - https://queue.taskcluster.net/v1/task/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 10:22:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:22:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:22:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 10:22:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 10:22:13 INFO - Downloaded 112883226 bytes. 10:22:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:22:13 INFO - mkdir: /builds/slave/test/properties 10:22:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:22:13 INFO - Writing to file /builds/slave/test/properties/build_url 10:22:13 INFO - Contents: 10:22:13 INFO - build_url:https://queue.taskcluster.net/v1/task/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:22:14 INFO - Running post-action listener: _resource_record_post_action 10:22:14 INFO - Running post-action listener: set_extra_try_arguments 10:22:14 INFO - ##### 10:22:14 INFO - ##### Finished download-and-extract step (success) 10:22:14 INFO - ##### 10:22:14 INFO - ##### 10:22:14 INFO - ##### Running populate-webroot step. 10:22:14 INFO - ##### 10:22:14 INFO - Running pre-action listener: _resource_record_pre_action 10:22:14 INFO - Running main action method: populate_webroot 10:22:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:22:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:22:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:22:14 INFO - '--tppagecycles', 10:22:14 INFO - '7'], 10:22:14 INFO - 'tests': ['tsvgm']}}, 10:22:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:22:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:22:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:22:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:22:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:22:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:22:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:22:14 INFO - 'talos_options': ['--disable-e10s'], 10:22:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:22:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:22:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:22:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:22:14 INFO - 'talos_options': ['--disable-e10s'], 10:22:14 INFO - 'tests': ['damp', 'tps']}, 10:22:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:22:14 INFO - 'tests': ['damp', 'tps']}, 10:22:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:22:14 INFO - 'tests': ['dromaeo_dom']}, 10:22:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:22:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:22:14 INFO - 'tests': ['basic_compositor_video']}, 10:22:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:22:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:22:14 INFO - 'tests': ['a11yr', 10:22:14 INFO - 'ts_paint', 10:22:14 INFO - 'tpaint', 10:22:14 INFO - 'sessionrestore', 10:22:14 INFO - 'sessionrestore_no_auto_restore', 10:22:14 INFO - 'tabpaint']}, 10:22:14 INFO - 'other-e10s': {'tests': ['a11yr', 10:22:14 INFO - 'ts_paint', 10:22:14 INFO - 'tpaint', 10:22:14 INFO - 'sessionrestore', 10:22:14 INFO - 'sessionrestore_no_auto_restore', 10:22:14 INFO - 'tabpaint']}, 10:22:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:22:14 INFO - 'ts_paint', 10:22:14 INFO - 'tpaint', 10:22:14 INFO - 'sessionrestore', 10:22:14 INFO - 'sessionrestore_no_auto_restore', 10:22:14 INFO - 'tabpaint']}, 10:22:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:22:14 INFO - 'ts_paint', 10:22:14 INFO - 'tpaint', 10:22:14 INFO - 'sessionrestore', 10:22:14 INFO - 'sessionrestore_no_auto_restore', 10:22:14 INFO - 'tabpaint']}, 10:22:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:22:14 INFO - 'tests': ['a11yr', 10:22:14 INFO - 'ts_paint', 10:22:14 INFO - 'tpaint', 10:22:14 INFO - 'sessionrestore', 10:22:14 INFO - 'sessionrestore_no_auto_restore', 10:22:14 INFO - 'tabpaint']}, 10:22:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:22:14 INFO - 'tests': ['a11yr', 10:22:14 INFO - 'ts_paint', 10:22:14 INFO - 'tpaint', 10:22:14 INFO - 'sessionrestore', 10:22:14 INFO - 'sessionrestore_no_auto_restore', 10:22:14 INFO - 'tabpaint']}, 10:22:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:22:14 INFO - 'tests': ['tsvgx', 10:22:14 INFO - 'tsvgr_opacity', 10:22:14 INFO - 'tart', 10:22:14 INFO - 'tscrollx', 10:22:14 INFO - 'cart']}, 10:22:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:22:14 INFO - 'tsvgr_opacity', 10:22:14 INFO - 'tart', 10:22:14 INFO - 'tscrollx', 10:22:14 INFO - 'cart']}, 10:22:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:22:14 INFO - 'talos_options': ['--disable-e10s'], 10:22:14 INFO - 'tests': ['tp5o']}, 10:22:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:22:14 INFO - 'tests': ['tp5o']}, 10:22:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:22:14 INFO - 'talos_options': ['--disable-e10s', 10:22:14 INFO - '--xperf_path', 10:22:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:22:14 INFO - 'tests': ['tp5n']}, 10:22:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:22:14 INFO - 'talos_options': ['--xperf_path', 10:22:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:22:14 INFO - 'tests': ['tp5n']}}, 10:22:14 INFO - 'talos.zip': {'path': '', 10:22:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:22:14 INFO - Running post-action listener: _resource_record_post_action 10:22:14 INFO - ##### 10:22:14 INFO - ##### Finished populate-webroot step (success) 10:22:14 INFO - ##### 10:22:14 INFO - ##### 10:22:14 INFO - ##### Running create-virtualenv step. 10:22:14 INFO - ##### 10:22:14 INFO - Running pre-action listener: _resource_record_pre_action 10:22:14 INFO - Running main action method: create_virtualenv 10:22:14 INFO - Creating virtualenv /builds/slave/test/build/venv 10:22:14 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 10:22:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:22:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:22:14 INFO - Using real prefix '/tools/python27' 10:22:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:22:15 INFO - Installing distribute.............................................................................................................................................................................................done. 10:22:18 INFO - Installing pip.................done. 10:22:18 INFO - Return code: 0 10:22:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:22:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:22:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:22:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:18 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 0x10c521a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5b6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa1146e200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c485d30>, '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 0x10c1eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c47db20>, '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.Gk7XUNOyyq/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.NNgosTQjhj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/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 10:22:18 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 10:22:18 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 10:22:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gk7XUNOyyq/Render', 10:22:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:22:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:22:18 INFO - 'HOME': '/Users/cltbld', 10:22:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:22:18 INFO - 'LOGNAME': 'cltbld', 10:22:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:18 INFO - 'MOZ_NO_REMOTE': '1', 10:22:18 INFO - 'NO_EM_RESTART': '1', 10:22:18 INFO - 'PAGER': '/bin/cat', 10:22:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:22:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:18 INFO - 'PWD': '/builds/slave/test', 10:22:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:22:18 INFO - 'SHELL': '/bin/bash', 10:22:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NNgosTQjhj/Listeners', 10:22:18 INFO - 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', 10:22:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:22:18 INFO - 'USER': 'cltbld', 10:22:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:22:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:22:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:18 INFO - 'XPC_FLAGS': '0x0', 10:22:18 INFO - 'XPC_SERVICE_NAME': '0', 10:22:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:22:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:18 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 10:22:18 INFO - Cleaning up... 10:22:18 INFO - Return code: 0 10:22:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:22:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:22:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:22:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:18 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 0x10c521a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5b6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa1146e200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c485d30>, '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 0x10c1eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c47db20>, '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.Gk7XUNOyyq/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.NNgosTQjhj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/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 10:22:18 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 10:22:18 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 10:22:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gk7XUNOyyq/Render', 10:22:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:22:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:22:18 INFO - 'HOME': '/Users/cltbld', 10:22:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:22:18 INFO - 'LOGNAME': 'cltbld', 10:22:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:18 INFO - 'MOZ_NO_REMOTE': '1', 10:22:18 INFO - 'NO_EM_RESTART': '1', 10:22:18 INFO - 'PAGER': '/bin/cat', 10:22:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:22:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:18 INFO - 'PWD': '/builds/slave/test', 10:22:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:22:18 INFO - 'SHELL': '/bin/bash', 10:22:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NNgosTQjhj/Listeners', 10:22:18 INFO - 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', 10:22:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:22:18 INFO - 'USER': 'cltbld', 10:22:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:22:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:22:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:18 INFO - 'XPC_FLAGS': '0x0', 10:22:18 INFO - 'XPC_SERVICE_NAME': '0', 10:22:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:22:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:18 INFO - Downloading/unpacking psutil>=3.1.1 10:22:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:22:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:22:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:22:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:22:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:22:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:22:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:22:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:22:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:22:21 INFO - Installing collected packages: psutil 10:22:21 INFO - Running setup.py install for psutil 10:22:21 INFO - building 'psutil._psutil_osx' extension 10:22:21 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 10:22:22 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 10:22:22 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 10:22:22 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 10:22:22 INFO - building 'psutil._psutil_posix' extension 10:22:22 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 10:22:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:22:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:22:22 INFO - ^ 10:22:22 INFO - 1 warning generated. 10:22:22 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 10:22:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:22:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:22:22 INFO - Successfully installed psutil 10:22:22 INFO - Cleaning up... 10:22:22 INFO - Return code: 0 10:22:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:22:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:22:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:22:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:22 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 0x10c521a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5b6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa1146e200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c485d30>, '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 0x10c1eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c47db20>, '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.Gk7XUNOyyq/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.NNgosTQjhj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/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 10:22:22 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 10:22:22 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 10:22:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gk7XUNOyyq/Render', 10:22:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:22:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:22:22 INFO - 'HOME': '/Users/cltbld', 10:22:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:22:22 INFO - 'LOGNAME': 'cltbld', 10:22:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:22 INFO - 'MOZ_NO_REMOTE': '1', 10:22:22 INFO - 'NO_EM_RESTART': '1', 10:22:22 INFO - 'PAGER': '/bin/cat', 10:22:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:22:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:22 INFO - 'PWD': '/builds/slave/test', 10:22:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:22:22 INFO - 'SHELL': '/bin/bash', 10:22:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NNgosTQjhj/Listeners', 10:22:22 INFO - 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', 10:22:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:22:22 INFO - 'USER': 'cltbld', 10:22:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:22:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:22:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:22 INFO - 'XPC_FLAGS': '0x0', 10:22:22 INFO - 'XPC_SERVICE_NAME': '0', 10:22:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:22:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:22:23 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 10:22:23 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 10:22:23 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 10:22:23 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 10:22:23 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 10:22:23 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 10:22:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:22:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:22:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:22:25 INFO - Installing collected packages: mozsystemmonitor 10:22:25 INFO - Running setup.py install for mozsystemmonitor 10:22:25 INFO - Successfully installed mozsystemmonitor 10:22:25 INFO - Cleaning up... 10:22:25 INFO - Return code: 0 10:22:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:22:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:22:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:22:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:25 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 0x10c521a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5b6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa1146e200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c485d30>, '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 0x10c1eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c47db20>, '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.Gk7XUNOyyq/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.NNgosTQjhj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/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 10:22:25 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 10:22:25 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 10:22:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gk7XUNOyyq/Render', 10:22:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:22:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:22:25 INFO - 'HOME': '/Users/cltbld', 10:22:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:22:25 INFO - 'LOGNAME': 'cltbld', 10:22:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:25 INFO - 'MOZ_NO_REMOTE': '1', 10:22:25 INFO - 'NO_EM_RESTART': '1', 10:22:25 INFO - 'PAGER': '/bin/cat', 10:22:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:22:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:25 INFO - 'PWD': '/builds/slave/test', 10:22:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:22:25 INFO - 'SHELL': '/bin/bash', 10:22:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NNgosTQjhj/Listeners', 10:22:25 INFO - 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', 10:22:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:22:25 INFO - 'USER': 'cltbld', 10:22:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:22:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:22:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:25 INFO - 'XPC_FLAGS': '0x0', 10:22:25 INFO - 'XPC_SERVICE_NAME': '0', 10:22:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:22:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:26 INFO - Downloading/unpacking jsonschema==2.5.1 10:22:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:22:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:22:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:22:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:22:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:22:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:22:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:22:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:22:28 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 10:22:28 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 10:22:28 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 10:22:28 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 10:22:28 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 10:22:28 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 10:22:28 INFO - Downloading functools32-3.2.3-2.tar.gz 10:22:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:22:28 INFO - warning: no files found matching '*.txt' 10:22:28 INFO - no previously-included directories found matching 'build' 10:22:28 INFO - no previously-included directories found matching 'dist' 10:22:28 INFO - no previously-included directories found matching '.git*' 10:22:28 INFO - Installing collected packages: jsonschema, functools32 10:22:28 INFO - Running setup.py install for functools32 10:22:29 INFO - warning: no files found matching '*.txt' 10:22:29 INFO - no previously-included directories found matching 'build' 10:22:29 INFO - no previously-included directories found matching 'dist' 10:22:29 INFO - no previously-included directories found matching '.git*' 10:22:29 INFO - Successfully installed jsonschema functools32 10:22:29 INFO - Cleaning up... 10:22:29 INFO - Return code: 0 10:22:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:22:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:22:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:22:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:29 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 0x10c521a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5b6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa1146e200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c485d30>, '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 0x10c1eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c47db20>, '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.Gk7XUNOyyq/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.NNgosTQjhj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/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 10:22:29 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 10:22:29 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 10:22:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gk7XUNOyyq/Render', 10:22:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:22:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:22:29 INFO - 'HOME': '/Users/cltbld', 10:22:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:22:29 INFO - 'LOGNAME': 'cltbld', 10:22:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:29 INFO - 'MOZ_NO_REMOTE': '1', 10:22:29 INFO - 'NO_EM_RESTART': '1', 10:22:29 INFO - 'PAGER': '/bin/cat', 10:22:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:22:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:29 INFO - 'PWD': '/builds/slave/test', 10:22:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:22:29 INFO - 'SHELL': '/bin/bash', 10:22:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NNgosTQjhj/Listeners', 10:22:29 INFO - 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', 10:22:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:22:29 INFO - 'USER': 'cltbld', 10:22:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:22:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:22:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:29 INFO - 'XPC_FLAGS': '0x0', 10:22:29 INFO - 'XPC_SERVICE_NAME': '0', 10:22:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:22:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:29 INFO - Downloading/unpacking blobuploader==1.2.4 10:22:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:22:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:22:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:22:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:22:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:22:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:22:31 INFO - Downloading blobuploader-1.2.4.tar.gz 10:22:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:22:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:22:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:22:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:22:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:22:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:22:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:22:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:22:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:22:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:22:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:22:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:22:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:22:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:22:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:22:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:22:33 INFO - Downloading docopt-0.6.1.tar.gz 10:22:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:22:33 INFO - Installing collected packages: blobuploader, requests, docopt 10:22:33 INFO - Running setup.py install for blobuploader 10:22:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:22:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:22:33 INFO - Running setup.py install for requests 10:22:33 INFO - Running setup.py install for docopt 10:22:33 INFO - Successfully installed blobuploader requests docopt 10:22:33 INFO - Cleaning up... 10:22:34 INFO - Return code: 0 10:22:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:22: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:22:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:22:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:34 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 0x10c521a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5b6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa1146e200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c485d30>, '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 0x10c1eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c47db20>, '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.Gk7XUNOyyq/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.NNgosTQjhj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/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 10:22:34 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 10:22:34 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 10:22:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gk7XUNOyyq/Render', 10:22:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:22:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:22:34 INFO - 'HOME': '/Users/cltbld', 10:22:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:22:34 INFO - 'LOGNAME': 'cltbld', 10:22:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:34 INFO - 'MOZ_NO_REMOTE': '1', 10:22:34 INFO - 'NO_EM_RESTART': '1', 10:22:34 INFO - 'PAGER': '/bin/cat', 10:22:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:22:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:34 INFO - 'PWD': '/builds/slave/test', 10:22:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:22:34 INFO - 'SHELL': '/bin/bash', 10:22:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NNgosTQjhj/Listeners', 10:22:34 INFO - 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', 10:22:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:22:34 INFO - 'USER': 'cltbld', 10:22:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:22:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:22:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:34 INFO - 'XPC_FLAGS': '0x0', 10:22:34 INFO - 'XPC_SERVICE_NAME': '0', 10:22:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:22:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:22:34 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-5qPQBg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:22:34 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-XRLZYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:22:34 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-GUDacc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:22:34 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-dLo6VL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:22:34 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-jYsze9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:22:34 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-EfNTc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:22:34 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-gPhoxE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:22:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:22:35 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-zDqs1c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:22:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:22:35 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-30rcQp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:22:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:22:35 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-ZFLQjQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:22:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:22:35 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-SplaXY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:22:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:22:35 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-zO0SNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:22:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:22:35 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-OAlU2v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:22:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:22:35 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-6IlKky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:22:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:22:35 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-kEnm55-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:22:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:22:35 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-wHO6BY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:22:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:22:35 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-pnws1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:22:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:22:36 INFO - Running setup.py install for manifestparser 10:22:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:22:36 INFO - Running setup.py install for mozcrash 10:22:36 INFO - Running setup.py install for mozdebug 10:22:36 INFO - Running setup.py install for mozdevice 10:22:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:22:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:22:36 INFO - Running setup.py install for mozfile 10:22:36 INFO - Running setup.py install for mozhttpd 10:22:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:22:36 INFO - Running setup.py install for mozinfo 10:22:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:22:37 INFO - Running setup.py install for mozInstall 10:22:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:22:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:22:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:22:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:22:37 INFO - Running setup.py install for mozleak 10:22:37 INFO - Running setup.py install for mozlog 10:22:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:22:37 INFO - Running setup.py install for moznetwork 10:22:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:22:37 INFO - Running setup.py install for mozprocess 10:22:37 INFO - Running setup.py install for mozprofile 10:22:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:22:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:22:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:22:38 INFO - Running setup.py install for mozrunner 10:22:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:22:38 INFO - Running setup.py install for mozscreenshot 10:22:38 INFO - Running setup.py install for moztest 10:22:38 INFO - Running setup.py install for mozversion 10:22:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:22:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:22:38 INFO - Cleaning up... 10:22:38 INFO - Return code: 0 10:22:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:22:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:22:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:22:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:38 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 0x10c521a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5b6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa1146e200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c485d30>, '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 0x10c1eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c47db20>, '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.Gk7XUNOyyq/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.NNgosTQjhj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/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 10:22:38 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 10:22:38 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 10:22:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gk7XUNOyyq/Render', 10:22:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:22:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:22:38 INFO - 'HOME': '/Users/cltbld', 10:22:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:22:38 INFO - 'LOGNAME': 'cltbld', 10:22:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:38 INFO - 'MOZ_NO_REMOTE': '1', 10:22:38 INFO - 'NO_EM_RESTART': '1', 10:22:38 INFO - 'PAGER': '/bin/cat', 10:22:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:22:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:38 INFO - 'PWD': '/builds/slave/test', 10:22:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:22:38 INFO - 'SHELL': '/bin/bash', 10:22:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NNgosTQjhj/Listeners', 10:22:38 INFO - 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', 10:22:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:22:38 INFO - 'USER': 'cltbld', 10:22:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:22:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:22:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:38 INFO - 'XPC_FLAGS': '0x0', 10:22:38 INFO - 'XPC_SERVICE_NAME': '0', 10:22:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:22:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:22:38 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-rPBUYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:22:39 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)) 10:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:22:39 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-qaHOTp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:22:39 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)) 10:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:22:39 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-vnSEh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:22:39 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)) 10:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:22:39 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-jNi4k3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:22:39 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)) 10:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:22:39 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-ZX0uz4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:22:39 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)) 10:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:22:39 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-mskC2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:22:39 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)) 10:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:22:39 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-XSPlzB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:22:39 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)) 10:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:22:39 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-Fic5nO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:22:39 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)) 10:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:22:39 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-i3oF0E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:22:39 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)) 10:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:22:39 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-7QHjyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:22:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:22:39 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-dRpCtC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:22:40 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)) 10:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:22:40 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-DXtW7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:22:40 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)) 10:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:22:40 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-MwQm7E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:22:40 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)) 10:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:22:40 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-7kCQVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:22:40 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)) 10:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:22:40 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-wSXr4e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:22:40 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)) 10:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:22:40 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-NXcrJz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:22:40 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)) 10:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:22:40 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-gdTcUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:22:40 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)) 10:22:40 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)) 10:22:40 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)) 10:22:40 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)) 10:22:40 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)) 10:22:40 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)) 10:22:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:22:40 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 10:22:40 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 10:22:40 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 10:22:40 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 10:22:40 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 10:22:40 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 10:22:43 INFO - Downloading blessings-1.6.tar.gz 10:22:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:22:43 INFO - Installing collected packages: blessings 10:22:43 INFO - Running setup.py install for blessings 10:22:43 INFO - Successfully installed blessings 10:22:43 INFO - Cleaning up... 10:22:43 INFO - Return code: 0 10:22:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:22:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:22:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:22:44 INFO - Reading from file tmpfile_stdout 10:22:44 INFO - Current package versions: 10:22:44 INFO - blessings == 1.6 10:22:44 INFO - blobuploader == 1.2.4 10:22:44 INFO - docopt == 0.6.1 10:22:44 INFO - functools32 == 3.2.3-2 10:22:44 INFO - jsonschema == 2.5.1 10:22:44 INFO - manifestparser == 1.1 10:22:44 INFO - mozInstall == 1.12 10:22:44 INFO - mozcrash == 0.17 10:22:44 INFO - mozdebug == 0.1 10:22:44 INFO - mozdevice == 0.48 10:22:44 INFO - mozfile == 1.2 10:22:44 INFO - mozhttpd == 0.7 10:22:44 INFO - mozinfo == 0.9 10:22:44 INFO - mozleak == 0.1 10:22:44 INFO - mozlog == 3.2 10:22:44 INFO - moznetwork == 0.27 10:22:44 INFO - mozprocess == 0.23 10:22:44 INFO - mozprofile == 0.28 10:22:44 INFO - mozrunner == 6.12 10:22:44 INFO - mozscreenshot == 0.1 10:22:44 INFO - mozsystemmonitor == 0.3 10:22:44 INFO - moztest == 0.7 10:22:44 INFO - mozversion == 1.4 10:22:44 INFO - psutil == 3.1.1 10:22:44 INFO - requests == 1.2.3 10:22:44 INFO - wsgiref == 0.1.2 10:22:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:22:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:22:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:22:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:44 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 0x10c521a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5b6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa1146e200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c485d30>, '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 0x10c1eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c47db20>, '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.Gk7XUNOyyq/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.NNgosTQjhj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/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 10:22:44 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 10:22:44 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 10:22:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gk7XUNOyyq/Render', 10:22:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:22:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:22:44 INFO - 'HOME': '/Users/cltbld', 10:22:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:22:44 INFO - 'LOGNAME': 'cltbld', 10:22:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:44 INFO - 'MOZ_NO_REMOTE': '1', 10:22:44 INFO - 'NO_EM_RESTART': '1', 10:22:44 INFO - 'PAGER': '/bin/cat', 10:22:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:22:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:44 INFO - 'PWD': '/builds/slave/test', 10:22:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:22:44 INFO - 'SHELL': '/bin/bash', 10:22:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NNgosTQjhj/Listeners', 10:22:44 INFO - 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', 10:22:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:22:44 INFO - 'USER': 'cltbld', 10:22:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:22:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:22:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:44 INFO - 'XPC_FLAGS': '0x0', 10:22:44 INFO - 'XPC_SERVICE_NAME': '0', 10:22:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:22:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:44 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)) 10:22:44 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)) 10:22:44 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)) 10:22:44 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)) 10:22:44 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)) 10:22:44 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)) 10:22:44 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)) 10:22:44 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)) 10:22:44 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)) 10:22:44 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)) 10:22:44 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)) 10:22:44 INFO - Cleaning up... 10:22:44 INFO - Return code: 0 10:22:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:22:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:22:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:22:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:44 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 0x10c521a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5b6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa1146e200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c485d30>, '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 0x10c1eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c47db20>, '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.Gk7XUNOyyq/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.NNgosTQjhj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/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 10:22:44 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 10:22:44 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 10:22:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gk7XUNOyyq/Render', 10:22:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:22:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:22:44 INFO - 'HOME': '/Users/cltbld', 10:22:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:22:44 INFO - 'LOGNAME': 'cltbld', 10:22:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:44 INFO - 'MOZ_NO_REMOTE': '1', 10:22:44 INFO - 'NO_EM_RESTART': '1', 10:22:44 INFO - 'PAGER': '/bin/cat', 10:22:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:22:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:44 INFO - 'PWD': '/builds/slave/test', 10:22:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:22:44 INFO - 'SHELL': '/bin/bash', 10:22:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NNgosTQjhj/Listeners', 10:22:44 INFO - 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', 10:22:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:22:44 INFO - 'USER': 'cltbld', 10:22:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:22:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:22:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:44 INFO - 'XPC_FLAGS': '0x0', 10:22:44 INFO - 'XPC_SERVICE_NAME': '0', 10:22:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:22:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:22:45 INFO - Cleaning up... 10:22:45 INFO - Return code: 0 10:22:45 INFO - Running post-action listener: _resource_record_post_action 10:22:45 INFO - Running post-action listener: _start_resource_monitoring 10:22:45 INFO - Starting resource monitoring. 10:22:45 INFO - ##### 10:22:45 INFO - ##### Finished create-virtualenv step (success) 10:22:45 INFO - ##### 10:22:45 INFO - ##### 10:22:45 INFO - ##### Running install step. 10:22:45 INFO - ##### 10:22:45 INFO - Running pre-action listener: _resource_record_pre_action 10:22:45 INFO - Running main action method: install 10:22:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:22:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:22:45 INFO - Reading from file tmpfile_stdout 10:22:45 INFO - Detecting whether we're running mozinstall >=1.0... 10:22:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:22:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:22:45 INFO - Reading from file tmpfile_stdout 10:22:45 INFO - Output received: 10:22:45 INFO - Usage: mozinstall [options] installer 10:22:45 INFO - Options: 10:22:45 INFO - -h, --help show this help message and exit 10:22:45 INFO - -d DEST, --destination=DEST 10:22:45 INFO - Directory to install application into. [default: 10:22:45 INFO - "/builds/slave/test"] 10:22:45 INFO - --app=APP Application being installed. [default: firefox] 10:22:45 INFO - mkdir: /builds/slave/test/build/application 10:22:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:22:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:23:20 INFO - Reading from file tmpfile_stdout 10:23:20 INFO - Output received: 10:23:20 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:23:20 INFO - Running post-action listener: _resource_record_post_action 10:23:20 INFO - ##### 10:23:20 INFO - ##### Finished install step (success) 10:23:20 INFO - ##### 10:23:20 INFO - ##### 10:23:20 INFO - ##### Running run-tests step. 10:23:20 INFO - ##### 10:23:20 INFO - Running pre-action listener: _resource_record_pre_action 10:23:20 INFO - Running main action method: run_tests 10:23:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:23:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:23:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:23:20 INFO - 2016-07-18 10:23:20.038 screenresolution[1643:7718] starting screenresolution argv=screenresolution get 10:23:20 INFO - 2016-07-18 10:23:20.041 screenresolution[1643:7718] Display 0: 1600x1200x32@60 10:23:20 INFO - 2016-07-18 10:23:20.047 screenresolution[1644:7720] starting screenresolution argv=screenresolution list 10:23:20 INFO - Available Modes on Display 0 10:23:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:23:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:23:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:23:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:23:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:23:20 INFO - Intel Iris: 10:23:20 INFO - Chipset Model: Intel Iris 10:23:20 INFO - Type: GPU 10:23:20 INFO - Bus: Built-In 10:23:20 INFO - VRAM (Dynamic, Max): 1536 MB 10:23:20 INFO - Vendor: Intel (0x8086) 10:23:20 INFO - Device ID: 0x0a2e 10:23:20 INFO - Revision ID: 0x0009 10:23:20 INFO - Displays: 10:23:20 INFO - Display: 10:23:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:23:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:23:20 INFO - Display Serial Number: 1600x1200 60 10:23:20 INFO - Main Display: Yes 10:23:20 INFO - Mirror: Off 10:23:20 INFO - Online: Yes 10:23:20 INFO - Rotation: Supported 10:23:20 INFO - Return code: 0 10:23:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:23:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:23:20 INFO - Python 2.7.3 10:23:20 INFO - Return code: 0 10:23:20 INFO - grabbing minidump binary from tooltool 10:23:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:23:20 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 0x10c485d30>, '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 0x10c1eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c47db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:23:20 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 10:23:20 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 10:23:20 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 10:23:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:23:20 INFO - Return code: 0 10:23:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:23:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:23:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:23:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:23:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:23:20 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/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0045', '--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 10:23:20 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/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0045 --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 10:23:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gk7XUNOyyq/Render', 10:23:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:23:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:23:20 INFO - 'HOME': '/Users/cltbld', 10:23:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:23:20 INFO - 'LOGNAME': 'cltbld', 10:23:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:23:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:23:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:20 INFO - 'MOZ_NO_REMOTE': '1', 10:23:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:23:20 INFO - 'NO_EM_RESTART': '1', 10:23:20 INFO - 'PAGER': '/bin/cat', 10:23:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:23:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:20 INFO - 'PWD': '/builds/slave/test', 10:23:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:23:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:23:20 INFO - 'SHELL': '/bin/bash', 10:23:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NNgosTQjhj/Listeners', 10:23:20 INFO - 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', 10:23:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:23:20 INFO - 'USER': 'cltbld', 10:23:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:23:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:23:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:20 INFO - 'XPC_FLAGS': '0x0', 10:23:20 INFO - 'XPC_SERVICE_NAME': '0', 10:23:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:23:20 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/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0045', '--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 10:23:20 INFO - mozversion application_buildid: 20160718080853 10:23:20 INFO - mozversion application_changeset: cde56ead650fd302be1d440507485b9abf7c163a 10:23:20 INFO - mozversion application_display_name: Nightly 10:23:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:23:20 INFO - mozversion application_name: Firefox 10:23:20 INFO - mozversion application_remotingname: firefox 10:23:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:23:20 INFO - mozversion application_vendor: Mozilla 10:23:20 INFO - mozversion application_version: 50.0a1 10:23:20 INFO - mozversion platform_buildid: 20160718080853 10:23:20 INFO - mozversion platform_changeset: cde56ead650fd302be1d440507485b9abf7c163a 10:23:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:23:20 INFO - mozversion platform_version: 50.0a1 10:23:20 INFO - using testdate: 1468862600 10:23:20 INFO - actual date: 1468862600 10:23:20 INFO - starting webserver on 'localhost:49277' 10:23:20 INFO - SUITE-START | Running 2 tests 10:23:20 INFO - TEST-START | dromaeo_css 10:23:20 INFO - operating with platform_type : mac_ 10:23:20 INFO - Initialising browser for dromaeo_css test... 10:23:20 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/tmp6tUzm5/profile http://localhost:49277/getInfo.html) 10:23:23 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 10:23:23 INFO - PROCESS | 1654 | colorDepth:24 10:23:23 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 10:23:23 INFO - PROCESS | 1654 | __metrics 10:23:23 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" 10:23:23 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:23:23 INFO - TEST-INFO | 1654: exit 0 10:23:23 INFO - Browser initialized. 10:23:23 INFO - Running cycle 1/1 for dromaeo_css test... 10:23:23 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/tmp6tUzm5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:23:35 INFO - PROCESS | 1656 | RSS: Main: 245022720 10:23:35 INFO - PROCESS | 1656 | 10:24:27 INFO - PROCESS | 1656 | 1468862667597 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] 10:26:38 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 10:26:39 INFO - PROCESS | 1656 | RSS: Main: 376946688 10:26:39 INFO - PROCESS | 1656 | 10:29:42 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 10:29:43 INFO - PROCESS | 1656 | RSS: Main: 382140416 10:29:43 INFO - PROCESS | 1656 | 10:32:45 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 10:32:46 INFO - PROCESS | 1656 | RSS: Main: 374403072 10:32:46 INFO - PROCESS | 1656 | 10:35:48 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 10:35:49 INFO - PROCESS | 1656 | RSS: Main: 375619584 10:35:49 INFO - PROCESS | 1656 | 10:38:52 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 10:38:53 INFO - PROCESS | 1656 | RSS: Main: 344842240 10:38:53 INFO - PROCESS | 1656 | 10:41:56 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 10:41:56 INFO - PROCESS | 1656 | RSS: Main: 393646080 10:41:56 INFO - PROCESS | 1656 | 10:41:56 INFO - PROCESS | 1656 | __start_tp_report 10:41:56 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 10:41:56 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 10:41:56 INFO - PROCESS | 1656 | |i|pagename|runs| 10:41:56 INFO - PROCESS | 1656 | |0;dojo.html;2991.6858729833366;3371.814550199738;3384.593848738151;3386.966975072605;3435.848990588153;5602.60781745278;5682.431756824316;5683.2895888014;5789.0448076067505;5792.247007888974;5693.686847223401;5730.4269573042675;5835.35811060783;5839.970800146004;5842.006858833998;5181.974090129563;5199.14214154664;5262.4474430184855;5267.078261304276;5319.468053194688;5337.412884582693;5346.358436987567;5355.009323275187;5375.462453754632;5393.919091213635;5129.666571672829;5165.586753059746;5220.008198442284;5222.973885130588;5273.2617433559335;4298.46269216348;5222.138347172712;5334.253204551366;5344.599155063347;5362.329708786402;5199.272101905698;5203.713795741232;5254.290670759437;5272.578193744491;5291.920621190685;5789.507891829199;5883.941160588382;5890.676012819294;5890.734916928705;5898.64608123514;5994.520438364921;6018.067199584071;6018.127371531147;6068.514518838482;6148.616561273678;6106.328303886569;6110.942468562003;6142.96928515359;6145.231846019247;6164.075388691687;5647.068233741469;5683.8010669626365;5705.229793977843;5708.628939118944;5714.685692286964;6242.406971337716;6270.059491076375;6314.431701146918;6337.017762246859;6340.841478963062;5206.635535512553;6201.317855035943;6212.223472065992;6227.501799856001;6230.376962303824;6145.617236121926;6232;6279.152314437492;6299.055141728777;6310.7791227306825;6140.0134868502855;6192.690365481798;6259.186305780219;6272.1219029335925;6314.273858506239;6252.5935814171935;6313.463355614732;6328.367163283727;6348.650824204713;6355.491560675227;5038.219076043146;5042.571381432619;5045.84862454127;5054.721990290495;5063.468335824759;4423.026934074498;4462.977685111554;4478.619317357996;4522.276435770261;4541.84103556374;5679.290088738908;5714.314282286152;5715.799947001919;5720.570957178228;5729;6203.348648391854;6248.250209974741;6269;6272.435480806749;6278.215223097113;5630.07103827872;5695.971520142456;5720.656760594295;5724.112762521816;5744.138379243147;5453.23654688344;5465.808696695596;5472.50747432725;5482.424345443671;5485.259489968883;5212.791488340424;6202.627842329474;6267;6282.026285925689;6317.810465686037;6255.437010669061;6300.59046161998;6302.779402720883;6320.336364681642;6323.146375239284;5509.173623956439;6332.873342533216;6356.2054743157105;6386.393292637192;6436.195475565554;6112.2970858351855;6133.579501509301;6141.1484926909025;6199.256089269316;6218.844528886633;6310.7475700971445;6317.084022816758;6322.778702745381;6323.841903952438;6331.0819931110655;5080.7205603692155;6159.4744659428825;6178.9382106180165;6183.227096612924;6188.597741146811;5995.550333724901;6005.789797357246;6073.665948372882;6106.339326077157;6117;5945.078512830518;6008.909866351921;6044.15381846533;6084.148219249397;6094.055421409688;6112.083187521907;6127.724252408563;6148.846278843064;6149.41580549847;6155.107509411021;5866.6480011199465;5908.763649453974;5928.644281343133;5967.522598192048;5989.760409583568 10:41:56 INFO - PROCESS | 1656 | |1;ext.html;469.47887844492607;484.78184816832425;533.84518489638;551.2585572405117;558.1739026241161;30575.082747517583;42932;43823.78088109555;43825.34261986073;43884.90287742808;44612.3308150378;44811;44933.87665308369;45007.549924500745;45036;8900.910990890105;9905.603775848975;10071.5467803949;10080.69757907264;10082.04220599042;8910.242629376511;12096.274223546616;12266.877331226662;12306.953908917752;12535.43590538428;12214.877851221463;12287.69280767979;12296;12297.569585064524;12312.938435307811;9021.594028268744;9151.908480915172;9174.036726143726;12271.938640306786;12360.876391236063;9132.497712625804;11901.702457438547;12158.939205304005;12164.14850960433;12370.443330050079;42529;42875.785621072006;42956;43028.354574681405;43037.92405189895;3551.9699287206936;3611.656892595199;3612.349777040132;3646.380115380365;3675.44868269759;27035;32524.837375813207;33103.83448082768;33779.49330760016;33877.830610847035;3245.6916592923635;3407.4207384744655;3424.143964008998;3444.362792883325;3448.689617934373;499986;500077;500089;500319.99999999633;502108;64567.35432645614;64635;65014.34985650177;65056.349436505974;65137.674311628136;67336.98994515087;67957.3204267951;68147;68312.3168768316;68363;16864.662706746043;17071.487855364358;17110.230039648246;17124.972501649692;17141.828581714024;11887.28676279426;12069;13108.279044652445;13172.934135329453;13208.33958302081;18269.725954110698;18274.903505789694;18283.26866925308;18293.7255941161;18327;6409.7180563887405;6435.324290949477;6452.451541619015;6474.805755827333;6504.707288172043;946.2997381937316;948.2414068744977;960.2845879819579;1062.5749700120014;1085.0288991352852;31344.529832052536;31489.37021259562;31603;32217.194570135467;32534.511982320284;1102.2284400919389;1109.4896347679996;1143.2854465958776;1146.8394424780538;1238.9256644601353;16942.40701575439;17506.737398939025;17698;17709.911450442665;17771.378001770136;541274;543202;545546;546806;548820;63438.682806585675;63495.36504634987;63619.68190159019;63713;63753.72492550123;1279.5457612547598;1406.1282005156868;1425.5937057938438;1437.037185085974;1458.4895286649587;31629.84185079152;31844.36311273808;31937;32655.673443265263;32723.672763273014;53920.46079539155;54502.18246726382;54538.18192727033;54701.45298547123;54707;568532;569877.6024479404;572041;573043;573047;26063;27311.180664580093;27339.179824604496;27341.86329068342;27357.72642273552;49265.75367123142;49877;49898.00203995973;49935.00129997452;50978.23532647087;40866;41207.58792412037;41795.58382897687;41936;42291.731248062606;40593;41044.179116418105;41171.38242926299;41186.1762764749;41837.95405114896;39935.60064399435;40377.596224037385;41138;41269.793651032756;43312;27476.17571472859;32585.69657213685;33588.49617255792;35066.474002890485;36288.63711362852;25121.120760773287;25902.48195036051;26327.73672263253;26528.734712652626;26674.866625666746 10:41:56 INFO - PROCESS | 1656 | |2;jquery.html;359.36392585124315;375.75951391109686;388.13058748403586;390.68159450048216;413.7021344631864;13554.728905421885;16133.515994520154;20887.582248355026;21205.257815976445;21266;21025.789742102574;21437.57124857502;21807;22175.334739957787;22195.44511387212;5230.790768369261;5841.561882858781;6090.360512146227;6103.572749907508;6111.511079113682;6024.48791852693;6079.176247125866;6117.418845209698;6117.5717699761035;6119.694015299239;5883.087996401166;5901.262342207224;5993.490553302974;5996;6006.369331220225;5693.77224911003;5896.97051514744;5988.31134419543;5995.910061349062;5998.940010599882;5142.717150556718;5170.922436163423;5174.741262936838;5175.068487672217;5188.221766734982;26811.463770724476;26825.865870670717;26998.460030799273;27207.86396068007;27219.455610887868;2093.497560585464;2282.075759317479;2338.4504641409253;2684.8657567121763;2775.1813215101392;10047.799044019079;10121.746956326077;10129.848052279222;10522.789544209072;10579.682609521727;1663.259849367032;1878.7745470543623;1887.9622407551772;1980.0792631426457;2041.4386043837915;82308;90885.54557227172;105148;121107;129968.7003129966;11257.71855703614;11437.714057148638;11586.826197607043;11716.589919352953;11843.170978031456;12888.968882489378;13454.663633409047;13654.93172534131;14691.971561990771;14725.96918215736;6887.414569761526;8412.284955778827;8542.487450752851;8591.613377398033;8689.6524139035;7388.630568471663;7443.665035073326;8344.123866993878;8628.741137765877;8723.171298726611;3903.2388684206308;4578.931316030262;4588.2888152336445;4634.304854271819;4643.814247430065;1910.9713354299697;1917.8657493975568;1920.19351872214;1930.054273406049;1941.223510595773;892.1078921078921;975.2782940624029;994.4530508220449;1003.6738060130486;1019.2814066083394;21930.78069219319;22280.331590052323;22324.888375558345;22331.66502502464;22335.09135901211;826.7023871406288;912.8128733609594;933.9252859771202;941.7457286532598;990.2375171118199;10016.747906511686;10826.783464330827;11360.772784544262;11560.76878462426;11564.986212120382;540293;541117;555249;578975;583923;81571.5921420389;83715.5814220925;136211;137233;139798.3010084943;1025.0825511167614;1027.8817935937313;1027.912627426677;1038.9324693894873;1083.5936523803573;19658.41024769259;19993.300234491722;20028;20029.89985050124;20169.798302016792;36556.81721591375;44138.11723765571;46285.38001169943;46488.53511464842;61096.16711498662;86024.5698771502;86064;86405;86869;88180.55909720411;7905.249001344863;8082.959585202037;8172.242967761959;8263.256306932404;8389.286910612665;7254.919561553082;10947.45262736844;12536.874631253571;12948.935255323662;13071.673208169492;11031.779364412507;11102.444877756241;11199.60801371948;11205.327680339205;11286.20996530235;10961;10967.396793176453;10992.340459572451;11049.779004420026;11140.220184587;11271.887281127083;11310.60412885545;11367.943160284145;11435.828462572901;11473.483693233991;7973.521588704696;8013.519188848688;8274.503529788231;8280.213379728915;8297.95851020741;10139.89860101419;11207.159463040141;11247.044001259985;11266.549338026387;11448.370339631067 10:41:56 INFO - PROCESS | 1656 | |3;mootools.html;1364.9522266720667;1519.4681861348529;1572.15889499118;1728.8962662240267;1883.256113835036;7702.46082774206;8646.610902509387;8882.289416846654;8893.866592001124;9006.729798106067;6833.282505336942;8770.780730481742;8831.425957312771;8845.938487381523;8847.955760221206;2086.113401804235;2160.5570857974158;2264.6036943534896;2296.5636529059507;2297.161536039342;2296.207808306132;2382.9642555361684;2385.355953892448;2386.3795413192606;2388.641703744435;2131.9467013324634;2169.817449490032;2332.8017118544944;2368.549975504657;2369.0050178924807;2074.2325339624285;2252.5044490212126;2327.150590034642;2345.777151170636;2384.76152384761;2352.4118970257437;2364.609839376501;2366.2782851230368;2366.337425520857;2366.5858474766846;8625.827483450295;8679.783005424852;8683.392162548624;8684.392092553524;8691.304695624334;5133.332666753336;5275.498827611371;5285.894282114336;5299.019681358961;5303.920441193385;3375.780574262665;3444.1389652586854;3476.66971637694;3486.2504561519395;3492.842822073013;1082.6373164989739;1088.2871719024051;1091.2197778588347;1107.9279846809932;1108.5478023788862;11334.829977550344;11528.59649912249;11551.595694150748;11559.53761849525;11573.652790416301;4465.1962646723905;4562.88592785183;4732.2428411454;4739.857804265877;4772.809087636525;10050.246231532665;10069.949650251849;10078.697639070839;10081.445520496443;10099.798004039878;5243.030039442715;5298.735063246822;5308.256844041837;5321.441248668912;5328.866778330495;4096.017154429606;4342.0664557119735;4428.247197976288;4479.350494178391;4863.051704917507;3486.6861982421196;3719.702423806089;3838.7696738195796;3841.5966323536186;3853.672437842759;1264.943077561512;1274.8342715447118;1306.053111494159;1309.5547513845338;1312.7702652035855;940.5626383731598;955.1690029674228;956.818204541135;960.7550074730899;985.8613301636622;5922.555808314308;6033.426824451758;6049.879002419927;6129.570930034944;6248;938.4369378373058;951.0109486134481;969.2972594868804;970.1898914406408;1008.9949550252348;3227.932146529972;3994.8601798936897;4021.9597804021587;4081.3469844824685;4124.628783409507;9829.754256143653;9932.10611044995;10031.448270345201;10037.749056273506;10186.745331366776;9902.455364954996;9985.650502232533;10015.69952901414;10018.198544116452;10025.598976041023;8206.22040906113;8339.916600834036;8362.372822038493;8363.289120424832;8373.665053397917;5710.143478478261;5964.552658550713;5994.460498555151;5996.820095397145;6051.183090282755;9685.854712179464;9986.001399859955;10032.247581431568;10037.297389182688;10111.140553053074;8568.57157142153;9212.677556285498;9912.15746661542;10312.27814053009;10341.327813692085;7554.169041405331;7599.620018999139;7698.422618303762;7721.845563088819;7732.8840067397905;8233.83532329362;8395.454295470774;8399.790005249917;8404.873926891223;8430.325573954191;7508.474406791473;7576.166621671722;7647.655855486405;7660.463767536219;7805.297523222937;7491.775246742389;7528.473006889465;7563.054618172729;7662.310392064741;7686.07767067933;6936.410405115622;7164.068671072727;7167.175774785758;7167.713291468492;7194.496385252982;4978.2034874419915;5089.160288552495;5109.156989096758;5109.616778741683;5237.607179461479;5039.571636410799;5116.565091967224;5166.354205724285;5188.558972487381;5294.205869119663 10:41:56 INFO - PROCESS | 1656 | |4;prototype.html;303.69326979750446;308.6820574807948;314.81897908702496;319.8272932616387;320.3289109315982;4544.341070544769;4902.852914412564;5214.582833373326;5248.714065054062;5319.308489896308;3770.359038963376;4143.4613500245005;4353.804078816453;5008.624353173508;5151.76817043233;3291.8847840325593;3785.5835858055593;3915.3735402335633;3937.310970580151;4045.5752146024683;3491.982540087296;3682.3555877721424;3840.542053086027;3932.0956180078597;4055.2497787909333;3376.9025066853246;3842.559040359865;3867.284552357823;4011.899702507446;4017.4978127734034;3853.479780229661;3992.461017762594;3994.4407782910125;4016.83511781583;4076.5719599441927;2711.606817011542;3333.9833300833584;3513.5959364673135;3532.452469867175;3547.3614749345106;4344.630706389929;5072.072612407299;5092.955944031464;5121.897562048775;5177.4822517747925;4215.831366745327;4548.795304211319;4595.2188128018315;4630.768461576941;4648.651351148644;5554.833354999357;5562.471565201299;5572.498475137217;5575.832725018216;5614.484089295908;3842.654161125512;3844;3847;3854.672352850011;3865.1640470776438;5449.5640348772;5724.255846739898;5801.375614827855;5803.332616749086;5857.15071314657;3404.6425125361975;3849.615038496128;3856.093817952779;3867.245887052054;3878.166194268246;3771.453139294787;3924.8626298080003;3926.960730392717;3927.9214415711526;3949.328614135604;4306.707987603769;4386.407834942306;4572.4055872736335;4581.725096494221;4607.442869207622;5444.727763611803;5463.945360546423;5480.3971563128025;5503.3395992480355;5570.805021824298;1023.7082431506984;1052.7999680060764;1294.2169987157822;1317.1372750848209;1321.6035189443128;677.8440958579554;741.7848823841133;850.2602735620051;858.3690987124459;894.890335983376;3984.960150398517;4970.732463221928;5044.697318160849;5079.415867175248;5114.616403769732;4475.843345482957;4665.230237010923;5489.313835770528;5549.556035517149;5650.208970744099;4669.789859456333;4743.225213250218;5043.773030213649;5088.669236499616;5100.362454693163;2615.777658898977;2632.3550730071256;2676.745709157627;2905.7239562241975;2909.6217491726366;4940.641323636029;4999.325091112726;5718.656880587095;5762.654240745569;5764.971175144097;5397.487238712316;5525.590974301608;5571.791332878182;5649.802256920988;5743.597948143673;5635;5641;5690.6301090429815;5699.316082070178;5709.971450142806;4806.9665022019035;5523.7238138093735;5525.585581081516;5571.804986825442;5599.776008959596;5556.694381808878;5595.496405323541;5612.466815652507;5622.606417550732;5808.883822323445;5424.484673955968;5802.593818432838;5807.303123625192;5860.736266867916;5873;3418.3505134024454;3741.981290093532;3797.417755925997;3821.7706937583835;3885.611438856092;4774.689645173053;5541.251930989426;5642.210090587239;5715.571332150189;5763.106718458645;4915.901681966412;5197.9727906117405;5483.643563168382;5560.02699527573;5607.439256074523;4198.580141985777;5045.899082018265;5137.691738495702;5298.019866324742;5331.3868905076415;5224.52979231871;5398.703071331114;5496.560275177896;5508.421615730291;5589.80435684749;4887.926681099715;5369.946300536944;5449.291592093003;5515.503604675599;5525.861853453695;5374.522006448102;5382.596305277041;5388.245645609696;5416.0772925610845;5481.999070339377 10:41:56 INFO - PROCESS | 1656 | |5;yui.html;737.7122922060394;758.4425447296236;785.8978332816733;788.1921030943282;821.2261515127326;15057;16203;16210.270537825796;16214.83785162148;16220.594485137877;14727.852721472782;14896;14906.254687265648;14927.552173434788;14950.700985980275;693.9757108501203;715.7029832619456;858.2490320969167;859.1751918158544;886.0165216609558;868.7437206024238;878.6573236437795;878.9165029322204;881.0352663833124;881.2993670032307;927.4296307770707;927.6243121535755;928.5589345061076;928.6239073175341;930.5626355612851;727.421699748701;890.9064548222408;918.7519369770191;920.5765347939956;922.064104933493;685.5612408058846;687.6046273392769;689.1798759476208;689.8654762321349;691.3155975584186;14636.41454341825;14681.559553213421;14693.118412895261;14701;14725.190114543695;13797.724045519033;13947.302634868216;13977.301134943213;13991.510297139654;14200.644983875383;10576.629817956411;12622.873771262262;12773.808392874114;12790.296533690644;12803.295818729966;4487.775611219426;4501.369808226851;4501.7974191161475;4518.819247230107;4728.858134255944;107286;109133;110448.34327485094;111039.88960110376;111270.44364778206;10762.3542587445;10873.728156795985;10907.509162087725;11089.944550277358;11174.832377514344;21556;21688.457788555414;21722.674159887614;21725;21888.343349699535;7970.721024764105;7982.521048737094;7991.400644951652;8157.306628936488;8167.836643267101;4433.556644335606;4488.12481566098;4496.168208881368;4520.276755719069;4599.08018396322;3335.1328654549507;3489.8953031409096;3491.5810102787827;3493.8427770750377;3497.89506314811;649.8440374310131;657.8585604095142;662.063180599456;663.3432901427649;667.7062092679206;586.4985437450918;609.8444896551351;639.657783086056;639.9104125422444;641.4099028893429;8513.702020429255;8755.474671519602;8766.18358932949;8851.203391694778;8968.05835387288;616.1035693066577;626.8558231606756;633.3824521091899;635.1996484429652;639.4500729372829;3965.920681586352;4341.696081274344;4352;4417.425734654475;4447.3773671685985;152006.47993520144;154987;155029.224853875;155348;160834;124679;128298;129550;131378.34310828577;131605.34197328953;92745;113975.86024139653;114611;116978.8302116968;117366;9647.758806029906;9963.103320701171;9997.850032249376;10115.544800483849;10124.493775311057;44224;47927.76036119797;53093.67265818385;57541;65303.34696652972;74841;82109.17890821016;86454.97407590956;88684.22631547462;97934;11583.478743456733;13141.211527308393;13386.330683465983;13607.455701771818;13658.931705341409;15048.764515629193;15190.46833360827;15281.541553753405;15331.38674453054;15368.961819497103;14056.226907519775;14428.783568246694;14444.927775361057;14474.855251447772;14738.778918316448;11577.6011783615;11927.701807454883;11992.980596649382;12163.817542737042;14468.927655361656;11568.305901645928;11789.941050294694;11811.621724269142;11900.285982841056;12406.255624662188;11103.777924441305;11407.657770266906;11539.884601154217;11652;11864.82202766942;11132.88867111351;11138.721531961442;11171.776564468502;11344.716382090513;11483.25358851672 10:41:56 INFO - PROCESS | 1656 | __end_tp_report 10:41:56 INFO - PROCESS | 1656 | __start_cc_report 10:41:56 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,1297 10:41:56 INFO - PROCESS | 1656 | __end_cc_report 10:41:56 INFO - PROCESS | 1656 | __startTimestamp1468863716276__endTimestamp 10:41:56 INFO - PROCESS | 1656 | 10:41:56 INFO - PROCESS | 1656 | ------- Summary: start ------- 10:41:56 INFO - PROCESS | 1656 | Number of tests: 6 10:41:56 INFO - PROCESS | 1656 | 10:41:56 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5748.28 Median:5945.08 stddev:643.28 (10.8%) stddev-sans-first:607.82 10:41:56 INFO - PROCESS | 1656 | Values: 2991.7 3371.8 3384.6 3387.0 3435.8 5602.6 5682.4 5683.3 5789.0 5792.2 5693.7 5730.4 5835.4 5840.0 5842.0 5182.0 5199.1 5262.4 5267.1 5319.5 5337.4 5346.4 5355.0 5375.5 5393.9 5129.7 5165.6 5220.0 5223.0 5273.3 4298.5 5222.1 5334.3 5344.6 5362.3 5199.3 5203.7 5254.3 5272.6 5291.9 5789.5 5883.9 5890.7 5890.7 5898.6 5994.5 6018.1 6018.1 6068.5 6148.6 6106.3 6110.9 6143.0 6145.2 6164.1 5647.1 5683.8 5705.2 5708.6 5714.7 6242.4 6270.1 6314.4 6337.0 6340.8 5206.6 6201.3 6212.2 6227.5 6230.4 6145.6 6232.0 6279.2 6299.1 6310.8 6140.0 6192.7 6259.2 6272.1 6314.3 6252.6 6313.5 6328.4 6348.7 6355.5 5038.2 5042.6 5045.8 5054.7 5063.5 4423.0 4463.0 4478.6 4522.3 4541.8 5679.3 5714.3 5715.8 5720.6 5729.0 6203.3 6248.3 6269.0 6272.4 6278.2 5630.1 5696.0 5720.7 5724.1 5744.1 5453.2 5465.8 5472.5 5482.4 5485.3 5212.8 6202.6 6267.0 6282.0 6317.8 6255.4 6300.6 6302.8 6320.3 6323.1 5509.2 6332.9 6356.2 6386.4 6436.2 6112.3 6133.6 6141.1 6199.3 6218.8 6310.7 6317.1 6322.8 6323.8 6331.1 5080.7 6159.5 6178.9 6183.2 6188.6 5995.6 6005.8 6073.7 6106.3 6117.0 5945.1 6008.9 6044.2 6084.1 6094.1 6112.1 6127.7 6148.8 6149.4 6155.1 5866.6 5908.8 5928.6 5967.5 5989.8 10:41:56 INFO - PROCESS | 1656 | 10:41:56 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:69214.15 Median:21805.52 stddev:143582.95 (658.5%) stddev-sans-first:143892.96 10:41:56 INFO - PROCESS | 1656 | Values: 469.5 484.8 533.8 551.3 558.2 30575.1 42932.0 43823.8 43825.3 43884.9 44612.3 44811.0 44933.9 45007.5 45036.0 8900.9 9905.6 10071.5 10080.7 10082.0 8910.2 12096.3 12266.9 12307.0 12535.4 12214.9 12287.7 12296.0 12297.6 12312.9 9021.6 9151.9 9174.0 12271.9 12360.9 9132.5 11901.7 12158.9 12164.1 12370.4 42529.0 42875.8 42956.0 43028.4 43037.9 3552.0 3611.7 3612.3 3646.4 3675.4 27035.0 32524.8 33103.8 33779.5 33877.8 3245.7 3407.4 3424.1 3444.4 3448.7 499986.0 500077.0 500089.0 500320.0 502108.0 64567.4 64635.0 65014.3 65056.3 65137.7 67337.0 67957.3 68147.0 68312.3 68363.0 16864.7 17071.5 17110.2 17125.0 17141.8 11887.3 12069.0 13108.3 13172.9 13208.3 18269.7 18274.9 18283.3 18293.7 18327.0 6409.7 6435.3 6452.5 6474.8 6504.7 946.3 948.2 960.3 1062.6 1085.0 31344.5 31489.4 31603.0 32217.2 32534.5 1102.2 1109.5 1143.3 1146.8 1238.9 16942.4 17506.7 17698.0 17709.9 17771.4 541274.0 543202.0 545546.0 546806.0 548820.0 63438.7 63495.4 63619.7 63713.0 63753.7 1279.5 1406.1 1425.6 1437.0 1458.5 31629.8 31844.4 31937.0 32655.7 32723.7 53920.5 54502.2 54538.2 54701.5 54707.0 568532.0 569877.6 572041.0 573043.0 573047.0 26063.0 27311.2 27339.2 27341.9 27357.7 49265.8 49877.0 49898.0 49935.0 50978.2 40866.0 41207.6 41795.6 41936.0 42291.7 40593.0 41044.2 41171.4 41186.2 41838.0 39935.6 40377.6 41138.0 41269.8 43312.0 27476.2 32585.7 33588.5 35066.5 36288.6 25121.1 25902.5 26327.7 26528.7 26674.9 10:41:56 INFO - PROCESS | 1656 | 10:41:56 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:33421.46 Median:26818.66 stddev:93529.93 (348.7%) stddev-sans-first:93759.36 10:41:56 INFO - PROCESS | 1656 | Values: 359.4 375.8 388.1 390.7 413.7 13554.7 16133.5 20887.6 21205.3 21266.0 21025.8 21437.6 21807.0 22175.3 22195.4 5230.8 5841.6 6090.4 6103.6 6111.5 6024.5 6079.2 6117.4 6117.6 6119.7 5883.1 5901.3 5993.5 5996.0 6006.4 5693.8 5897.0 5988.3 5995.9 5998.9 5142.7 5170.9 5174.7 5175.1 5188.2 26811.5 26825.9 26998.5 27207.9 27219.5 2093.5 2282.1 2338.5 2684.9 2775.2 10047.8 10121.7 10129.8 10522.8 10579.7 1663.3 1878.8 1888.0 1980.1 2041.4 82308.0 90885.5 105148.0 121107.0 129968.7 11257.7 11437.7 11586.8 11716.6 11843.2 12889.0 13454.7 13654.9 14692.0 14726.0 6887.4 8412.3 8542.5 8591.6 8689.7 7388.6 7443.7 8344.1 8628.7 8723.2 3903.2 4578.9 4588.3 4634.3 4643.8 1911.0 1917.9 1920.2 1930.1 1941.2 892.1 975.3 994.5 1003.7 1019.3 21930.8 22280.3 22324.9 22331.7 22335.1 826.7 912.8 933.9 941.7 990.2 10016.7 10826.8 11360.8 11560.8 11565.0 540293.0 541117.0 555249.0 578975.0 583923.0 81571.6 83715.6 136211.0 137233.0 139798.3 1025.1 1027.9 1027.9 1038.9 1083.6 19658.4 19993.3 20028.0 20029.9 20169.8 36556.8 44138.1 46285.4 46488.5 61096.2 86024.6 86064.0 86405.0 86869.0 88180.6 7905.2 8083.0 8172.2 8263.3 8389.3 7254.9 10947.5 12536.9 12948.9 13071.7 11031.8 11102.4 11199.6 11205.3 11286.2 10961.0 10967.4 10992.3 11049.8 11140.2 11271.9 11310.6 11367.9 11435.8 11473.5 7973.5 8013.5 8274.5 8280.2 8298.0 10139.9 11207.2 11247.0 11266.5 11448.4 10:41:56 INFO - PROCESS | 1656 | 10:41:56 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5670.23 Median:5109.39 stddev:3142.69 (61.5%) stddev-sans-first:3134.85 10:41:56 INFO - PROCESS | 1656 | Values: 1365.0 1519.5 1572.2 1728.9 1883.3 7702.5 8646.6 8882.3 8893.9 9006.7 6833.3 8770.8 8831.4 8845.9 8848.0 2086.1 2160.6 2264.6 2296.6 2297.2 2296.2 2383.0 2385.4 2386.4 2388.6 2131.9 2169.8 2332.8 2368.5 2369.0 2074.2 2252.5 2327.2 2345.8 2384.8 2352.4 2364.6 2366.3 2366.3 2366.6 8625.8 8679.8 8683.4 8684.4 8691.3 5133.3 5275.5 5285.9 5299.0 5303.9 3375.8 3444.1 3476.7 3486.3 3492.8 1082.6 1088.3 1091.2 1107.9 1108.5 11334.8 11528.6 11551.6 11559.5 11573.7 4465.2 4562.9 4732.2 4739.9 4772.8 10050.2 10069.9 10078.7 10081.4 10099.8 5243.0 5298.7 5308.3 5321.4 5328.9 4096.0 4342.1 4428.2 4479.4 4863.1 3486.7 3719.7 3838.8 3841.6 3853.7 1264.9 1274.8 1306.1 1309.6 1312.8 940.6 955.2 956.8 960.8 985.9 5922.6 6033.4 6049.9 6129.6 6248.0 938.4 951.0 969.3 970.2 1009.0 3227.9 3994.9 4022.0 4081.3 4124.6 9829.8 9932.1 10031.4 10037.7 10186.7 9902.5 9985.7 10015.7 10018.2 10025.6 8206.2 8339.9 8362.4 8363.3 8373.7 5710.1 5964.6 5994.5 5996.8 6051.2 9685.9 9986.0 10032.2 10037.3 10111.1 8568.6 9212.7 9912.2 10312.3 10341.3 7554.2 7599.6 7698.4 7721.8 7732.9 8233.8 8395.5 8399.8 8404.9 8430.3 7508.5 7576.2 7647.7 7660.5 7805.3 7491.8 7528.5 7563.1 7662.3 7686.1 6936.4 7164.1 7167.2 7167.7 7194.5 4978.2 5089.2 5109.2 5109.6 5237.6 5039.6 5116.6 5166.4 5188.6 5294.2 10:41:56 INFO - PROCESS | 1656 | 10:41:56 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4439.77 Median:5044.24 stddev:1383.02 (27.4%) stddev-sans-first:1351.61 10:41:56 INFO - PROCESS | 1656 | Values: 303.7 308.7 314.8 319.8 320.3 4544.3 4902.9 5214.6 5248.7 5319.3 3770.4 4143.5 4353.8 5008.6 5151.8 3291.9 3785.6 3915.4 3937.3 4045.6 3492.0 3682.4 3840.5 3932.1 4055.2 3376.9 3842.6 3867.3 4011.9 4017.5 3853.5 3992.5 3994.4 4016.8 4076.6 2711.6 3334.0 3513.6 3532.5 3547.4 4344.6 5072.1 5093.0 5121.9 5177.5 4215.8 4548.8 4595.2 4630.8 4648.7 5554.8 5562.5 5572.5 5575.8 5614.5 3842.7 3844.0 3847.0 3854.7 3865.2 5449.6 5724.3 5801.4 5803.3 5857.2 3404.6 3849.6 3856.1 3867.2 3878.2 3771.5 3924.9 3927.0 3927.9 3949.3 4306.7 4386.4 4572.4 4581.7 4607.4 5444.7 5463.9 5480.4 5503.3 5570.8 1023.7 1052.8 1294.2 1317.1 1321.6 677.8 741.8 850.3 858.4 894.9 3985.0 4970.7 5044.7 5079.4 5114.6 4475.8 4665.2 5489.3 5549.6 5650.2 4669.8 4743.2 5043.8 5088.7 5100.4 2615.8 2632.4 2676.7 2905.7 2909.6 4940.6 4999.3 5718.7 5762.7 5765.0 5397.5 5525.6 5571.8 5649.8 5743.6 5635.0 5641.0 5690.6 5699.3 5710.0 4807.0 5523.7 5525.6 5571.8 5599.8 5556.7 5595.5 5612.5 5622.6 5808.9 5424.5 5802.6 5807.3 5860.7 5873.0 3418.4 3742.0 3797.4 3821.8 3885.6 4774.7 5541.3 5642.2 5715.6 5763.1 4915.9 5198.0 5483.6 5560.0 5607.4 4198.6 5045.9 5137.7 5298.0 5331.4 5224.5 5398.7 5496.6 5508.4 5589.8 4887.9 5369.9 5449.3 5515.5 5525.9 5374.5 5382.6 5388.2 5416.1 5482.0 10:41:56 INFO - PROCESS | 1656 | 10:41:56 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:24727.24 Median:12611.74 stddev:39867.47 (316.1%) stddev-sans-first:39938.62 10:41:56 INFO - PROCESS | 1656 | Values: 737.7 758.4 785.9 788.2 821.2 15057.0 16203.0 16210.3 16214.8 16220.6 14727.9 14896.0 14906.3 14927.6 14950.7 694.0 715.7 858.2 859.2 886.0 868.7 878.7 878.9 881.0 881.3 927.4 927.6 928.6 928.6 930.6 727.4 890.9 918.8 920.6 922.1 685.6 687.6 689.2 689.9 691.3 14636.4 14681.6 14693.1 14701.0 14725.2 13797.7 13947.3 13977.3 13991.5 14200.6 10576.6 12622.9 12773.8 12790.3 12803.3 4487.8 4501.4 4501.8 4518.8 4728.9 107286.0 109133.0 110448.3 111039.9 111270.4 10762.4 10873.7 10907.5 11089.9 11174.8 21556.0 21688.5 21722.7 21725.0 21888.3 7970.7 7982.5 7991.4 8157.3 8167.8 4433.6 4488.1 4496.2 4520.3 4599.1 3335.1 3489.9 3491.6 3493.8 3497.9 649.8 657.9 662.1 663.3 667.7 586.5 609.8 639.7 639.9 641.4 8513.7 8755.5 8766.2 8851.2 8968.1 616.1 626.9 633.4 635.2 639.5 3965.9 4341.7 4352.0 4417.4 4447.4 152006.5 154987.0 155029.2 155348.0 160834.0 124679.0 128298.0 129550.0 131378.3 131605.3 92745.0 113975.9 114611.0 116978.8 117366.0 9647.8 9963.1 9997.9 10115.5 10124.5 44224.0 47927.8 53093.7 57541.0 65303.3 74841.0 82109.2 86455.0 88684.2 97934.0 11583.5 13141.2 13386.3 13607.5 13658.9 15048.8 15190.5 15281.5 15331.4 15369.0 14056.2 14428.8 14444.9 14474.9 14738.8 11577.6 11927.7 11993.0 12163.8 14468.9 11568.3 11789.9 11811.6 11900.3 12406.3 11103.8 11407.7 11539.9 11652.0 11864.8 11132.9 11138.7 11171.8 11344.7 11483.3 10:41:56 INFO - PROCESS | 1656 | -------- Summary: end -------- 10:41:56 INFO - PROCESS | 1656 | 10:41:56 INFO - TEST-INFO | 1656: exit 0 10:41:56 INFO - TEST-OK | dromaeo_css | took 1116287ms 10:41:56 INFO - TEST-START | kraken 10:41:56 INFO - operating with platform_type : mac_ 10:41:56 INFO - Initialising browser for kraken test... 10:41:56 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/tmpmnLxis/profile http://localhost:49277/getInfo.html) 10:41:59 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 10:41:59 INFO - PROCESS | 1664 | colorDepth:24 10:41:59 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 10:41:59 INFO - PROCESS | 1664 | __metrics 10:41:59 INFO - PROCESS | 1664 | 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" 10:41:59 INFO - PROCESS | 1664 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:42:00 INFO - TEST-INFO | 1664: exit 0 10:42:00 INFO - Browser initialized. 10:42:00 INFO - Running cycle 1/1 for kraken test... 10:42:00 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/tmpmnLxis/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:42:11 INFO - PROCESS | 1665 | RSS: Main: 245321728 10:42:11 INFO - PROCESS | 1665 | 10:42:14 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 10:42:15 INFO - PROCESS | 1665 | RSS: Main: 294547456 10:42:15 INFO - PROCESS | 1665 | 10:42:17 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 10:42:18 INFO - PROCESS | 1665 | RSS: Main: 305627136 10:42:18 INFO - PROCESS | 1665 | 10:42:22 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 10:42:22 INFO - PROCESS | 1665 | RSS: Main: 392400896 10:42:22 INFO - PROCESS | 1665 | 10:42:24 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 10:42:25 INFO - PROCESS | 1665 | RSS: Main: 292732928 10:42:25 INFO - PROCESS | 1665 | 10:42:27 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:42:28 INFO - PROCESS | 1665 | RSS: Main: 296493056 10:42:28 INFO - PROCESS | 1665 | 10:42:30 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 10:42:31 INFO - PROCESS | 1665 | RSS: Main: 332849152 10:42:31 INFO - PROCESS | 1665 | 10:42:34 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 10:42:34 INFO - PROCESS | 1665 | RSS: Main: 335114240 10:42:34 INFO - PROCESS | 1665 | 10:42:37 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 10:42:38 INFO - PROCESS | 1665 | RSS: Main: 335151104 10:42:38 INFO - PROCESS | 1665 | 10:42:39 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:42:40 INFO - PROCESS | 1665 | RSS: Main: 312397824 10:42:40 INFO - PROCESS | 1665 | 10:42:42 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:42:43 INFO - PROCESS | 1665 | RSS: Main: 318570496 10:42:43 INFO - PROCESS | 1665 | 10:42:45 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:42:46 INFO - PROCESS | 1665 | RSS: Main: 321789952 10:42:46 INFO - PROCESS | 1665 | 10:42:48 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:42:49 INFO - PROCESS | 1665 | RSS: Main: 325439488 10:42:49 INFO - PROCESS | 1665 | 10:42:52 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:42:53 INFO - PROCESS | 1665 | RSS: Main: 328097792 10:42:53 INFO - PROCESS | 1665 | 10:42:54 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 10:42:55 INFO - PROCESS | 1665 | RSS: Main: 371384320 10:42:55 INFO - PROCESS | 1665 | 10:42:55 INFO - PROCESS | 1665 | __start_tp_report 10:42:55 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 10:42:55 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 10:42:55 INFO - PROCESS | 1665 | |i|pagename|runs| 10:42:55 INFO - PROCESS | 1665 | |0;ai-astar;101;113;112;112;110;112;111;111;111;111 10:42:55 INFO - PROCESS | 1665 | |1;audio-beat-detection;145;109;107;160;106;107;130;104;141;108 10:42:55 INFO - PROCESS | 1665 | |2;audio-dft;184;183;185;181;184;182;180;182;186;178 10:42:55 INFO - PROCESS | 1665 | |3;audio-fft;82;75;57;90;59;59;66;59;82;62 10:42:55 INFO - PROCESS | 1665 | |4;audio-oscillator;84;91;92;72;75;68;96;70;66;101 10:42:55 INFO - PROCESS | 1665 | |5;imaging-gaussian-blur;88;83;86;85;87;87;89;85;89;83 10:42:55 INFO - PROCESS | 1665 | |6;imaging-darkroom;93;94;94;92;93;93;94;93;93;93 10:42:55 INFO - PROCESS | 1665 | |7;imaging-desaturate;84;85;85;84;84;81;84;83;85;83 10:42:55 INFO - PROCESS | 1665 | |8;json-parse-financial;53;52;52;52;54;53;53;52;52;53 10:42:55 INFO - PROCESS | 1665 | |9;json-stringify-tinderbox;66;76;78;76;73;73;71;71;71;75 10:42:55 INFO - PROCESS | 1665 | |10;stanford-crypto-aes;85;85;85;92;102;99;98;85;83;88 10:42:55 INFO - PROCESS | 1665 | |11;stanford-crypto-ccm;137;136;142;148;147;143;136;136;235;149 10:42:55 INFO - PROCESS | 1665 | |12;stanford-crypto-pbkdf2;162;163;165;165;163;165;164;164;165;162 10:42:55 INFO - PROCESS | 1665 | |13;stanford-crypto-sha256-iterative;67;67;66;63;58;75;90;65;57;81 10:42:55 INFO - PROCESS | 1665 | __end_tp_report 10:42:55 INFO - PROCESS | 1665 | __start_cc_report 10:42:55 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,2031 10:42:55 INFO - PROCESS | 1665 | __end_cc_report 10:42:55 INFO - PROCESS | 1665 | __startTimestamp1468863775043__endTimestamp 10:42:55 INFO - PROCESS | 1665 | 10:42:55 INFO - PROCESS | 1665 | ------- Summary: start ------- 10:42:55 INFO - PROCESS | 1665 | Number of tests: 14 10:42:55 INFO - PROCESS | 1665 | 10:42:55 INFO - PROCESS | 1665 | [#0] ai-astar Cycles:10 Average:110.40 Median:111.50 stddev:3.41 (3.1%) stddev-sans-first:0.88 10:42:55 INFO - PROCESS | 1665 | Values: 101.0 113.0 112.0 112.0 110.0 112.0 111.0 111.0 111.0 111.0 10:42:55 INFO - PROCESS | 1665 | 10:42:55 INFO - PROCESS | 1665 | [#1] audio-beat-detection Cycles:10 Average:121.70 Median:119.50 stddev:20.53 (17.2%) stddev-sans-first:19.97 10:42:55 INFO - PROCESS | 1665 | Values: 145.0 109.0 107.0 160.0 106.0 107.0 130.0 104.0 141.0 108.0 10:42:55 INFO - PROCESS | 1665 | 10:42:55 INFO - PROCESS | 1665 | [#2] audio-dft Cycles:10 Average:182.50 Median:183.50 stddev:2.42 (1.3%) stddev-sans-first:2.50 10:42:55 INFO - PROCESS | 1665 | Values: 184.0 183.0 185.0 181.0 184.0 182.0 180.0 182.0 186.0 178.0 10:42:55 INFO - PROCESS | 1665 | 10:42:55 INFO - PROCESS | 1665 | [#3] audio-fft Cycles:10 Average:69.10 Median:70.50 stddev:12.10 (17.2%) stddev-sans-first:11.90 10:42:55 INFO - PROCESS | 1665 | Values: 82.0 75.0 57.0 90.0 59.0 59.0 66.0 59.0 82.0 62.0 10:42:55 INFO - PROCESS | 1665 | 10:42:55 INFO - PROCESS | 1665 | [#4] audio-oscillator Cycles:10 Average:81.50 Median:79.50 stddev:12.84 (16.2%) stddev-sans-first:13.59 10:42:55 INFO - PROCESS | 1665 | Values: 84.0 91.0 92.0 72.0 75.0 68.0 96.0 70.0 66.0 101.0 10:42:55 INFO - PROCESS | 1665 | 10:42:55 INFO - PROCESS | 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:86.20 Median:87.00 stddev:2.20 (2.5%) stddev-sans-first:2.24 10:42:55 INFO - PROCESS | 1665 | Values: 88.0 83.0 86.0 85.0 87.0 87.0 89.0 85.0 89.0 83.0 10:42:55 INFO - PROCESS | 1665 | 10:42:55 INFO - PROCESS | 1665 | [#6] imaging-darkroom Cycles:10 Average:93.20 Median:93.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 10:42:55 INFO - PROCESS | 1665 | Values: 93.0 94.0 94.0 92.0 93.0 93.0 94.0 93.0 93.0 93.0 10:42:55 INFO - PROCESS | 1665 | 10:42:55 INFO - PROCESS | 1665 | [#7] imaging-desaturate Cycles:10 Average:83.80 Median:84.00 stddev:1.23 (1.5%) stddev-sans-first:1.30 10:42:55 INFO - PROCESS | 1665 | Values: 84.0 85.0 85.0 84.0 84.0 81.0 84.0 83.0 85.0 83.0 10:42:55 INFO - PROCESS | 1665 | 10:42:55 INFO - PROCESS | 1665 | [#8] json-parse-financial Cycles:10 Average:52.60 Median:53.00 stddev:0.70 (1.3%) stddev-sans-first:0.73 10:42:55 INFO - PROCESS | 1665 | Values: 53.0 52.0 52.0 52.0 54.0 53.0 53.0 52.0 52.0 53.0 10:42:55 INFO - PROCESS | 1665 | 10:42:55 INFO - PROCESS | 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:73.00 Median:74.00 stddev:3.46 (4.7%) stddev-sans-first:2.59 10:42:55 INFO - PROCESS | 1665 | Values: 66.0 76.0 78.0 76.0 73.0 73.0 71.0 71.0 71.0 75.0 10:42:55 INFO - PROCESS | 1665 | 10:42:55 INFO - PROCESS | 1665 | [#10] stanford-crypto-aes Cycles:10 Average:90.20 Median:86.50 stddev:7.04 (8.1%) stddev-sans-first:7.21 10:42:55 INFO - PROCESS | 1665 | Values: 85.0 85.0 85.0 92.0 102.0 99.0 98.0 85.0 83.0 88.0 10:42:55 INFO - PROCESS | 1665 | 10:42:55 INFO - PROCESS | 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:150.90 Median:145.00 stddev:30.00 (20.7%) stddev-sans-first:31.40 10:42:55 INFO - PROCESS | 1665 | Values: 137.0 136.0 142.0 148.0 147.0 143.0 136.0 136.0 235.0 149.0 10:42:55 INFO - PROCESS | 1665 | 10:42:55 INFO - PROCESS | 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.80 Median:164.50 stddev:1.23 (0.7%) stddev-sans-first:1.12 10:42:55 INFO - PROCESS | 1665 | Values: 162.0 163.0 165.0 165.0 163.0 165.0 164.0 164.0 165.0 162.0 10:42:55 INFO - PROCESS | 1665 | 10:42:55 INFO - PROCESS | 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:67.00 stddev:10.30 (15.4%) stddev-sans-first:10.90 10:42:55 INFO - PROCESS | 1665 | Values: 67.0 67.0 66.0 63.0 58.0 75.0 90.0 65.0 57.0 81.0 10:42:55 INFO - PROCESS | 1665 | -------- Summary: end -------- 10:42:55 INFO - PROCESS | 1665 | 10:42:55 INFO - TEST-INFO | 1665: exit 0 10:42:55 INFO - TEST-OK | kraken | took 59019ms 10:42:55 INFO - SUITE-END | took 1175s 10:42:56 INFO - Completed test suite (00:19:35) 10:42:56 INFO - javascript benchmark 10:42:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2991.6858729833366, 3371.814550199738, 3384.593848738151, 3386.966975072605, 3435.848990588153, 5602.60781745278, 5682.431756824316, 5683.2895888014, 5789.0448076067505, 5792.247007888974, 5693.686847223401, 5730.4269573042675, 5835.35811060783, 5839.970800146004, 5842.006858833998, 5181.974090129563, 5199.14214154664, 5262.4474430184855, 5267.078261304276, 5319.468053194688, 5337.412884582693, 5346.358436987567, 5355.009323275187, 5375.462453754632, 5393.919091213635, 5129.666571672829, 5165.586753059746, 5220.008198442284, 5222.973885130588, 5273.2617433559335, 4298.46269216348, 5222.138347172712, 5334.253204551366, 5344.599155063347, 5362.329708786402, 5199.272101905698, 5203.713795741232, 5254.290670759437, 5272.578193744491, 5291.920621190685, 5789.507891829199, 5883.941160588382, 5890.676012819294, 5890.734916928705, 5898.64608123514, 5994.520438364921, 6018.067199584071, 6018.127371531147, 6068.514518838482, 6148.616561273678, 6106.328303886569, 6110.942468562003, 6142.96928515359, 6145.231846019247, 6164.075388691687, 5647.068233741469, 5683.8010669626365, 5705.229793977843, 5708.628939118944, 5714.685692286964, 6242.406971337716, 6270.059491076375, 6314.431701146918, 6337.017762246859, 6340.841478963062, 5206.635535512553, 6201.317855035943, 6212.223472065992, 6227.501799856001, 6230.376962303824, 6145.617236121926, 6232.0, 6279.152314437492, 6299.055141728777, 6310.7791227306825, 6140.0134868502855, 6192.690365481798, 6259.186305780219, 6272.1219029335925, 6314.273858506239, 6252.5935814171935, 6313.463355614732, 6328.367163283727, 6348.650824204713, 6355.491560675227, 5038.219076043146, 5042.571381432619, 5045.84862454127, 5054.721990290495, 5063.468335824759, 4423.026934074498, 4462.977685111554, 4478.619317357996, 4522.276435770261, 4541.84103556374, 5679.290088738908, 5714.314282286152, 5715.799947001919, 5720.570957178228, 5729.0, 6203.348648391854, 6248.250209974741, 6269.0, 6272.435480806749, 6278.215223097113, 5630.07103827872, 5695.971520142456, 5720.656760594295, 5724.112762521816, 5744.138379243147, 5453.23654688344, 5465.808696695596, 5472.50747432725, 5482.424345443671, 5485.259489968883, 5212.791488340424, 6202.627842329474, 6267.0, 6282.026285925689, 6317.810465686037, 6255.437010669061, 6300.59046161998, 6302.779402720883, 6320.336364681642, 6323.146375239284, 5509.173623956439, 6332.873342533216, 6356.2054743157105, 6386.393292637192, 6436.195475565554, 6112.2970858351855, 6133.579501509301, 6141.1484926909025, 6199.256089269316, 6218.844528886633, 6310.7475700971445, 6317.084022816758, 6322.778702745381, 6323.841903952438, 6331.0819931110655, 5080.7205603692155, 6159.4744659428825, 6178.9382106180165, 6183.227096612924, 6188.597741146811, 5995.550333724901, 6005.789797357246, 6073.665948372882, 6106.339326077157, 6117.0, 5945.078512830518, 6008.909866351921, 6044.15381846533, 6084.148219249397, 6094.055421409688, 6112.083187521907, 6127.724252408563, 6148.846278843064, 6149.41580549847, 6155.107509411021, 5866.6480011199465, 5908.763649453974, 5928.644281343133, 5967.522598192048, 5989.760409583568], "value": 5707.8097864009815, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [469.47887844492607, 484.78184816832425, 533.84518489638, 551.2585572405117, 558.1739026241161, 30575.082747517583, 42932.0, 43823.78088109555, 43825.34261986073, 43884.90287742808, 44612.3308150378, 44811.0, 44933.87665308369, 45007.549924500745, 45036.0, 8900.910990890105, 9905.603775848975, 10071.5467803949, 10080.69757907264, 10082.04220599042, 8910.242629376511, 12096.274223546616, 12266.877331226662, 12306.953908917752, 12535.43590538428, 12214.877851221463, 12287.69280767979, 12296.0, 12297.569585064524, 12312.938435307811, 9021.594028268744, 9151.908480915172, 9174.036726143726, 12271.938640306786, 12360.876391236063, 9132.497712625804, 11901.702457438547, 12158.939205304005, 12164.14850960433, 12370.443330050079, 42529.0, 42875.785621072006, 42956.0, 43028.354574681405, 43037.92405189895, 3551.9699287206936, 3611.656892595199, 3612.349777040132, 3646.380115380365, 3675.44868269759, 27035.0, 32524.837375813207, 33103.83448082768, 33779.49330760016, 33877.830610847035, 3245.6916592923635, 3407.4207384744655, 3424.143964008998, 3444.362792883325, 3448.689617934373, 499986.0, 500077.0, 500089.0, 500319.99999999633, 502108.0, 64567.35432645614, 64635.0, 65014.34985650177, 65056.349436505974, 65137.674311628136, 67336.98994515087, 67957.3204267951, 68147.0, 68312.3168768316, 68363.0, 16864.662706746043, 17071.487855364358, 17110.230039648246, 17124.972501649692, 17141.828581714024, 11887.28676279426, 12069.0, 13108.279044652445, 13172.934135329453, 13208.33958302081, 18269.725954110698, 18274.903505789694, 18283.26866925308, 18293.7255941161, 18327.0, 6409.7180563887405, 6435.324290949477, 6452.451541619015, 6474.805755827333, 6504.707288172043, 946.2997381937316, 948.2414068744977, 960.2845879819579, 1062.5749700120014, 1085.0288991352852, 31344.529832052536, 31489.37021259562, 31603.0, 32217.194570135467, 32534.511982320284, 1102.2284400919389, 1109.4896347679996, 1143.2854465958776, 1146.8394424780538, 1238.9256644601353, 16942.40701575439, 17506.737398939025, 17698.0, 17709.911450442665, 17771.378001770136, 541274.0, 543202.0, 545546.0, 546806.0, 548820.0, 63438.682806585675, 63495.36504634987, 63619.68190159019, 63713.0, 63753.72492550123, 1279.5457612547598, 1406.1282005156868, 1425.5937057938438, 1437.037185085974, 1458.4895286649587, 31629.84185079152, 31844.36311273808, 31937.0, 32655.673443265263, 32723.672763273014, 53920.46079539155, 54502.18246726382, 54538.18192727033, 54701.45298547123, 54707.0, 568532.0, 569877.6024479404, 572041.0, 573043.0, 573047.0, 26063.0, 27311.180664580093, 27339.179824604496, 27341.86329068342, 27357.72642273552, 49265.75367123142, 49877.0, 49898.00203995973, 49935.00129997452, 50978.23532647087, 40866.0, 41207.58792412037, 41795.58382897687, 41936.0, 42291.731248062606, 40593.0, 41044.179116418105, 41171.38242926299, 41186.1762764749, 41837.95405114896, 39935.60064399435, 40377.596224037385, 41138.0, 41269.793651032756, 43312.0, 27476.17571472859, 32585.69657213685, 33588.49617255792, 35066.474002890485, 36288.63711362852, 25121.120760773287, 25902.48195036051, 26327.73672263253, 26528.734712652626, 26674.866625666746], "value": 20996.059941489013, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.36392585124315, 375.75951391109686, 388.13058748403586, 390.68159450048216, 413.7021344631864, 13554.728905421885, 16133.515994520154, 20887.582248355026, 21205.257815976445, 21266.0, 21025.789742102574, 21437.57124857502, 21807.0, 22175.334739957787, 22195.44511387212, 5230.790768369261, 5841.561882858781, 6090.360512146227, 6103.572749907508, 6111.511079113682, 6024.48791852693, 6079.176247125866, 6117.418845209698, 6117.5717699761035, 6119.694015299239, 5883.087996401166, 5901.262342207224, 5993.490553302974, 5996.0, 6006.369331220225, 5693.77224911003, 5896.97051514744, 5988.31134419543, 5995.910061349062, 5998.940010599882, 5142.717150556718, 5170.922436163423, 5174.741262936838, 5175.068487672217, 5188.221766734982, 26811.463770724476, 26825.865870670717, 26998.460030799273, 27207.86396068007, 27219.455610887868, 2093.497560585464, 2282.075759317479, 2338.4504641409253, 2684.8657567121763, 2775.1813215101392, 10047.799044019079, 10121.746956326077, 10129.848052279222, 10522.789544209072, 10579.682609521727, 1663.259849367032, 1878.7745470543623, 1887.9622407551772, 1980.0792631426457, 2041.4386043837915, 82308.0, 90885.54557227172, 105148.0, 121107.0, 129968.7003129966, 11257.71855703614, 11437.714057148638, 11586.826197607043, 11716.589919352953, 11843.170978031456, 12888.968882489378, 13454.663633409047, 13654.93172534131, 14691.971561990771, 14725.96918215736, 6887.414569761526, 8412.284955778827, 8542.487450752851, 8591.613377398033, 8689.6524139035, 7388.630568471663, 7443.665035073326, 8344.123866993878, 8628.741137765877, 8723.171298726611, 3903.2388684206308, 4578.931316030262, 4588.2888152336445, 4634.304854271819, 4643.814247430065, 1910.9713354299697, 1917.8657493975568, 1920.19351872214, 1930.054273406049, 1941.223510595773, 892.1078921078921, 975.2782940624029, 994.4530508220449, 1003.6738060130486, 1019.2814066083394, 21930.78069219319, 22280.331590052323, 22324.888375558345, 22331.66502502464, 22335.09135901211, 826.7023871406288, 912.8128733609594, 933.9252859771202, 941.7457286532598, 990.2375171118199, 10016.747906511686, 10826.783464330827, 11360.772784544262, 11560.76878462426, 11564.986212120382, 540293.0, 541117.0, 555249.0, 578975.0, 583923.0, 81571.5921420389, 83715.5814220925, 136211.0, 137233.0, 139798.3010084943, 1025.0825511167614, 1027.8817935937313, 1027.912627426677, 1038.9324693894873, 1083.5936523803573, 19658.41024769259, 19993.300234491722, 20028.0, 20029.89985050124, 20169.798302016792, 36556.81721591375, 44138.11723765571, 46285.38001169943, 46488.53511464842, 61096.16711498662, 86024.5698771502, 86064.0, 86405.0, 86869.0, 88180.55909720411, 7905.249001344863, 8082.959585202037, 8172.242967761959, 8263.256306932404, 8389.286910612665, 7254.919561553082, 10947.45262736844, 12536.874631253571, 12948.935255323662, 13071.673208169492, 11031.779364412507, 11102.444877756241, 11199.60801371948, 11205.327680339205, 11286.20996530235, 10961.0, 10967.396793176453, 10992.340459572451, 11049.779004420026, 11140.220184587, 11271.887281127083, 11310.60412885545, 11367.943160284145, 11435.828462572901, 11473.483693233991, 7973.521588704696, 8013.519188848688, 8274.503529788231, 8280.213379728915, 8297.95851020741, 10139.89860101419, 11207.159463040141, 11247.044001259985, 11266.549338026387, 11448.370339631067], "value": 9536.472975895967, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1364.9522266720667, 1519.4681861348529, 1572.15889499118, 1728.8962662240267, 1883.256113835036, 7702.46082774206, 8646.610902509387, 8882.289416846654, 8893.866592001124, 9006.729798106067, 6833.282505336942, 8770.780730481742, 8831.425957312771, 8845.938487381523, 8847.955760221206, 2086.113401804235, 2160.5570857974158, 2264.6036943534896, 2296.5636529059507, 2297.161536039342, 2296.207808306132, 2382.9642555361684, 2385.355953892448, 2386.3795413192606, 2388.641703744435, 2131.9467013324634, 2169.817449490032, 2332.8017118544944, 2368.549975504657, 2369.0050178924807, 2074.2325339624285, 2252.5044490212126, 2327.150590034642, 2345.777151170636, 2384.76152384761, 2352.4118970257437, 2364.609839376501, 2366.2782851230368, 2366.337425520857, 2366.5858474766846, 8625.827483450295, 8679.783005424852, 8683.392162548624, 8684.392092553524, 8691.304695624334, 5133.332666753336, 5275.498827611371, 5285.894282114336, 5299.019681358961, 5303.920441193385, 3375.780574262665, 3444.1389652586854, 3476.66971637694, 3486.2504561519395, 3492.842822073013, 1082.6373164989739, 1088.2871719024051, 1091.2197778588347, 1107.9279846809932, 1108.5478023788862, 11334.829977550344, 11528.59649912249, 11551.595694150748, 11559.53761849525, 11573.652790416301, 4465.1962646723905, 4562.88592785183, 4732.2428411454, 4739.857804265877, 4772.809087636525, 10050.246231532665, 10069.949650251849, 10078.697639070839, 10081.445520496443, 10099.798004039878, 5243.030039442715, 5298.735063246822, 5308.256844041837, 5321.441248668912, 5328.866778330495, 4096.017154429606, 4342.0664557119735, 4428.247197976288, 4479.350494178391, 4863.051704917507, 3486.6861982421196, 3719.702423806089, 3838.7696738195796, 3841.5966323536186, 3853.672437842759, 1264.943077561512, 1274.8342715447118, 1306.053111494159, 1309.5547513845338, 1312.7702652035855, 940.5626383731598, 955.1690029674228, 956.818204541135, 960.7550074730899, 985.8613301636622, 5922.555808314308, 6033.426824451758, 6049.879002419927, 6129.570930034944, 6248.0, 938.4369378373058, 951.0109486134481, 969.2972594868804, 970.1898914406408, 1008.9949550252348, 3227.932146529972, 3994.8601798936897, 4021.9597804021587, 4081.3469844824685, 4124.628783409507, 9829.754256143653, 9932.10611044995, 10031.448270345201, 10037.749056273506, 10186.745331366776, 9902.455364954996, 9985.650502232533, 10015.69952901414, 10018.198544116452, 10025.598976041023, 8206.22040906113, 8339.916600834036, 8362.372822038493, 8363.289120424832, 8373.665053397917, 5710.143478478261, 5964.552658550713, 5994.460498555151, 5996.820095397145, 6051.183090282755, 9685.854712179464, 9986.001399859955, 10032.247581431568, 10037.297389182688, 10111.140553053074, 8568.57157142153, 9212.677556285498, 9912.15746661542, 10312.27814053009, 10341.327813692085, 7554.169041405331, 7599.620018999139, 7698.422618303762, 7721.845563088819, 7732.8840067397905, 8233.83532329362, 8395.454295470774, 8399.790005249917, 8404.873926891223, 8430.325573954191, 7508.474406791473, 7576.166621671722, 7647.655855486405, 7660.463767536219, 7805.297523222937, 7491.775246742389, 7528.473006889465, 7563.054618172729, 7662.310392064741, 7686.07767067933, 6936.410405115622, 7164.068671072727, 7167.175774785758, 7167.713291468492, 7194.496385252982, 4978.2034874419915, 5089.160288552495, 5109.156989096758, 5109.616778741683, 5237.607179461479, 5039.571636410799, 5116.565091967224, 5166.354205724285, 5188.558972487381, 5294.205869119663], "value": 4573.773503277844, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.69326979750446, 308.6820574807948, 314.81897908702496, 319.8272932616387, 320.3289109315982, 4544.341070544769, 4902.852914412564, 5214.582833373326, 5248.714065054062, 5319.308489896308, 3770.359038963376, 4143.4613500245005, 4353.804078816453, 5008.624353173508, 5151.76817043233, 3291.8847840325593, 3785.5835858055593, 3915.3735402335633, 3937.310970580151, 4045.5752146024683, 3491.982540087296, 3682.3555877721424, 3840.542053086027, 3932.0956180078597, 4055.2497787909333, 3376.9025066853246, 3842.559040359865, 3867.284552357823, 4011.899702507446, 4017.4978127734034, 3853.479780229661, 3992.461017762594, 3994.4407782910125, 4016.83511781583, 4076.5719599441927, 2711.606817011542, 3333.9833300833584, 3513.5959364673135, 3532.452469867175, 3547.3614749345106, 4344.630706389929, 5072.072612407299, 5092.955944031464, 5121.897562048775, 5177.4822517747925, 4215.831366745327, 4548.795304211319, 4595.2188128018315, 4630.768461576941, 4648.651351148644, 5554.833354999357, 5562.471565201299, 5572.498475137217, 5575.832725018216, 5614.484089295908, 3842.654161125512, 3844.0, 3847.0, 3854.672352850011, 3865.1640470776438, 5449.5640348772, 5724.255846739898, 5801.375614827855, 5803.332616749086, 5857.15071314657, 3404.6425125361975, 3849.615038496128, 3856.093817952779, 3867.245887052054, 3878.166194268246, 3771.453139294787, 3924.8626298080003, 3926.960730392717, 3927.9214415711526, 3949.328614135604, 4306.707987603769, 4386.407834942306, 4572.4055872736335, 4581.725096494221, 4607.442869207622, 5444.727763611803, 5463.945360546423, 5480.3971563128025, 5503.3395992480355, 5570.805021824298, 1023.7082431506984, 1052.7999680060764, 1294.2169987157822, 1317.1372750848209, 1321.6035189443128, 677.8440958579554, 741.7848823841133, 850.2602735620051, 858.3690987124459, 894.890335983376, 3984.960150398517, 4970.732463221928, 5044.697318160849, 5079.415867175248, 5114.616403769732, 4475.843345482957, 4665.230237010923, 5489.313835770528, 5549.556035517149, 5650.208970744099, 4669.789859456333, 4743.225213250218, 5043.773030213649, 5088.669236499616, 5100.362454693163, 2615.777658898977, 2632.3550730071256, 2676.745709157627, 2905.7239562241975, 2909.6217491726366, 4940.641323636029, 4999.325091112726, 5718.656880587095, 5762.654240745569, 5764.971175144097, 5397.487238712316, 5525.590974301608, 5571.791332878182, 5649.802256920988, 5743.597948143673, 5635.0, 5641.0, 5690.6301090429815, 5699.316082070178, 5709.971450142806, 4806.9665022019035, 5523.7238138093735, 5525.585581081516, 5571.804986825442, 5599.776008959596, 5556.694381808878, 5595.496405323541, 5612.466815652507, 5622.606417550732, 5808.883822323445, 5424.484673955968, 5802.593818432838, 5807.303123625192, 5860.736266867916, 5873.0, 3418.3505134024454, 3741.981290093532, 3797.417755925997, 3821.7706937583835, 3885.611438856092, 4774.689645173053, 5541.251930989426, 5642.210090587239, 5715.571332150189, 5763.106718458645, 4915.901681966412, 5197.9727906117405, 5483.643563168382, 5560.02699527573, 5607.439256074523, 4198.580141985777, 5045.899082018265, 5137.691738495702, 5298.019866324742, 5331.3868905076415, 5224.52979231871, 5398.703071331114, 5496.560275177896, 5508.421615730291, 5589.80435684749, 4887.926681099715, 5369.946300536944, 5449.291592093003, 5515.503604675599, 5525.861853453695, 5374.522006448102, 5382.596305277041, 5388.245645609696, 5416.0772925610845, 5481.999070339377], "value": 3993.616197321286, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [737.7122922060394, 758.4425447296236, 785.8978332816733, 788.1921030943282, 821.2261515127326, 15057.0, 16203.0, 16210.270537825796, 16214.83785162148, 16220.594485137877, 14727.852721472782, 14896.0, 14906.254687265648, 14927.552173434788, 14950.700985980275, 693.9757108501203, 715.7029832619456, 858.2490320969167, 859.1751918158544, 886.0165216609558, 868.7437206024238, 878.6573236437795, 878.9165029322204, 881.0352663833124, 881.2993670032307, 927.4296307770707, 927.6243121535755, 928.5589345061076, 928.6239073175341, 930.5626355612851, 727.421699748701, 890.9064548222408, 918.7519369770191, 920.5765347939956, 922.064104933493, 685.5612408058846, 687.6046273392769, 689.1798759476208, 689.8654762321349, 691.3155975584186, 14636.41454341825, 14681.559553213421, 14693.118412895261, 14701.0, 14725.190114543695, 13797.724045519033, 13947.302634868216, 13977.301134943213, 13991.510297139654, 14200.644983875383, 10576.629817956411, 12622.873771262262, 12773.808392874114, 12790.296533690644, 12803.295818729966, 4487.775611219426, 4501.369808226851, 4501.7974191161475, 4518.819247230107, 4728.858134255944, 107286.0, 109133.0, 110448.34327485094, 111039.88960110376, 111270.44364778206, 10762.3542587445, 10873.728156795985, 10907.509162087725, 11089.944550277358, 11174.832377514344, 21556.0, 21688.457788555414, 21722.674159887614, 21725.0, 21888.343349699535, 7970.721024764105, 7982.521048737094, 7991.400644951652, 8157.306628936488, 8167.836643267101, 4433.556644335606, 4488.12481566098, 4496.168208881368, 4520.276755719069, 4599.08018396322, 3335.1328654549507, 3489.8953031409096, 3491.5810102787827, 3493.8427770750377, 3497.89506314811, 649.8440374310131, 657.8585604095142, 662.063180599456, 663.3432901427649, 667.7062092679206, 586.4985437450918, 609.8444896551351, 639.657783086056, 639.9104125422444, 641.4099028893429, 8513.702020429255, 8755.474671519602, 8766.18358932949, 8851.203391694778, 8968.05835387288, 616.1035693066577, 626.8558231606756, 633.3824521091899, 635.1996484429652, 639.4500729372829, 3965.920681586352, 4341.696081274344, 4352.0, 4417.425734654475, 4447.3773671685985, 152006.47993520144, 154987.0, 155029.224853875, 155348.0, 160834.0, 124679.0, 128298.0, 129550.0, 131378.34310828577, 131605.34197328953, 92745.0, 113975.86024139653, 114611.0, 116978.8302116968, 117366.0, 9647.758806029906, 9963.103320701171, 9997.850032249376, 10115.544800483849, 10124.493775311057, 44224.0, 47927.76036119797, 53093.67265818385, 57541.0, 65303.34696652972, 74841.0, 82109.17890821016, 86454.97407590956, 88684.22631547462, 97934.0, 11583.478743456733, 13141.211527308393, 13386.330683465983, 13607.455701771818, 13658.931705341409, 15048.764515629193, 15190.46833360827, 15281.541553753405, 15331.38674453054, 15368.961819497103, 14056.226907519775, 14428.783568246694, 14444.927775361057, 14474.855251447772, 14738.778918316448, 11577.6011783615, 11927.701807454883, 11992.980596649382, 12163.817542737042, 14468.927655361656, 11568.305901645928, 11789.941050294694, 11811.621724269142, 11900.285982841056, 12406.255624662188, 11103.777924441305, 11407.657770266906, 11539.884601154217, 11652.0, 11864.82202766942, 11132.88867111351, 11138.721531961442, 11171.776564468502, 11344.716382090513, 11483.25358851672], "value": 7836.61236669389, "unit": "score"}], "name": "dromaeo_css", "value": 7395.523411429972, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 112.0, 112.0, 110.0, 112.0, 111.0, 111.0, 111.0, 111.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 109.0, 107.0, 160.0, 106.0, 107.0, 130.0, 104.0, 141.0, 108.0], "value": 121.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 185.0, 181.0, 184.0, 182.0, 180.0, 182.0, 186.0, 178.0], "value": 182.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 75.0, 57.0, 90.0, 59.0, 59.0, 66.0, 59.0, 82.0, 62.0], "value": 69.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 91.0, 92.0, 72.0, 75.0, 68.0, 96.0, 70.0, 66.0, 101.0], "value": 81.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 83.0, 86.0, 85.0, 87.0, 87.0, 89.0, 85.0, 89.0, 83.0], "value": 86.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 92.0, 93.0, 93.0, 94.0, 93.0, 93.0, 93.0], "value": 93.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 85.0, 84.0, 84.0, 81.0, 84.0, 83.0, 85.0, 83.0], "value": 83.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 52.0, 52.0, 54.0, 53.0, 53.0, 52.0, 52.0, 53.0], "value": 52.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 76.0, 78.0, 76.0, 73.0, 73.0, 71.0, 71.0, 71.0, 75.0], "value": 73.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 92.0, 102.0, 99.0, 98.0, 85.0, 83.0, 88.0], "value": 90.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 136.0, 142.0, 148.0, 147.0, 143.0, 136.0, 136.0, 235.0, 149.0], "value": 150.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 165.0, 165.0, 163.0, 165.0, 164.0, 164.0, 165.0, 162.0], "value": 163.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 63.0, 58.0, 75.0, 90.0, 65.0, 57.0, 81.0], "value": 68.9, "unit": "score"}], "name": "kraken", "value": 1427.8000000000002, "alertThreshold": 2.0}]} 10:42:56 INFO - Return code: 0 10:42:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:42:56 INFO - # TBPL SUCCESS # 10:42:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:42:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:42:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:42:56 INFO - 2016-07-18 10:42:56.284 screenresolution[1669:10064] starting screenresolution argv=screenresolution get 10:42:56 INFO - 2016-07-18 10:42:56.287 screenresolution[1669:10064] Display 0: 1600x1200x32@60 10:42:56 INFO - 2016-07-18 10:42:56.293 screenresolution[1670:10067] starting screenresolution argv=screenresolution list 10:42:56 INFO - Available Modes on Display 0 10:42:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:42:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:42:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:42:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:42:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:42:56 INFO - Intel Iris: 10:42:56 INFO - Chipset Model: Intel Iris 10:42:56 INFO - Type: GPU 10:42:56 INFO - Bus: Built-In 10:42:56 INFO - VRAM (Dynamic, Max): 1536 MB 10:42:56 INFO - Vendor: Intel (0x8086) 10:42:56 INFO - Device ID: 0x0a2e 10:42:56 INFO - Revision ID: 0x0009 10:42:56 INFO - Displays: 10:42:56 INFO - Display: 10:42:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:42:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:42:56 INFO - Display Serial Number: 1600x1200 60 10:42:56 INFO - Main Display: Yes 10:42:56 INFO - Mirror: Off 10:42:56 INFO - Online: Yes 10:42:56 INFO - Rotation: Supported 10:42:56 INFO - Return code: 0 10:42:56 INFO - Running post-action listener: _resource_record_post_action 10:42:56 INFO - ##### 10:42:56 INFO - ##### Finished run-tests step (success) 10:42:56 INFO - ##### 10:42:56 INFO - Running post-run listener: _resource_record_post_run 10:42:56 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 38110720; Write bytes: 707073536; Read time: 376; Write time: 2017 10:42:56 INFO - TinderboxPrint: CPU usage
53.2% 10:42:56 INFO - TinderboxPrint: I/O read bytes / time
38,110,720 / 376 10:42:56 INFO - TinderboxPrint: I/O write bytes / time
707,073,536 / 2,017 10:42:56 INFO - TinderboxPrint: CPU idle
2,267.8 (46.8%) 10:42:56 INFO - TinderboxPrint: CPU system
961.9 (19.8%) 10:42:56 INFO - TinderboxPrint: CPU user
1,616.1 (33.4%) 10:42:56 INFO - TinderboxPrint: Swap in / out
445,702,144 / 0 10:42:56 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 392049152; Write bytes: 380904960; Read time: 29371; Write time: 744 10:42:56 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 31870464; Write bytes: 296796160; Read time: 297; Write time: 1218 10:42:56 INFO - Running post-run listener: _upload_blobber_files 10:42:56 INFO - Blob upload gear active. 10:42:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:42:56 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 10:42:56 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'] 10:42:56 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 10:42:57 INFO - (blobuploader) - INFO - Open directory for files ... 10:42:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:42:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:42:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:42:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:42:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:42:58 INFO - (blobuploader) - INFO - Done attempting. 10:42:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:42:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:42:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:42:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:42:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:42:59 INFO - (blobuploader) - INFO - Done attempting. 10:42:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:42:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:42:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:43:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:43:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:43:01 INFO - (blobuploader) - INFO - Done attempting. 10:43:01 INFO - (blobuploader) - INFO - Iteration through files over. 10:43:01 INFO - Return code: 0 10:43:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:43:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:43:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c43b6c506a073c17edf0ee47aa4589caa0013ef72b5c12ce280a862f70c1888598386d764af4f8e20b707445a2675ca31d1ba6b98e7e8f425f8fb4ff09b84ca4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/34affeb32d59fce47dc2ed1c4bf47a6a3bb444543b567179bd42d120cac4238992fa19abd9dc3f52069e8b7f91b615ffce0d811f29ac371037ccf9af089a54fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98fc06aedf558297c4fed4e05949f6356d2675e9fc18a9f70c25b7d98c13698d1be9263b5031755c6585cc78b788094c1f8804d4d094ede82e8f9278c740a034"} 10:43:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:43:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:43:01 INFO - Contents: 10:43:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c43b6c506a073c17edf0ee47aa4589caa0013ef72b5c12ce280a862f70c1888598386d764af4f8e20b707445a2675ca31d1ba6b98e7e8f425f8fb4ff09b84ca4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/34affeb32d59fce47dc2ed1c4bf47a6a3bb444543b567179bd42d120cac4238992fa19abd9dc3f52069e8b7f91b615ffce0d811f29ac371037ccf9af089a54fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98fc06aedf558297c4fed4e05949f6356d2675e9fc18a9f70c25b7d98c13698d1be9263b5031755c6585cc78b788094c1f8804d4d094ede82e8f9278c740a034"} 10:43:01 INFO - Running post-run listener: copy_logs_to_upload_dir 10:43:01 INFO - Copying logs to upload dir... 10:43:01 INFO - mkdir: /builds/slave/test/build/upload/logs 10:43:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.951072 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-07-18 10:43:01.692085) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 10:43:01.695784) ========= 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.Gk7XUNOyyq/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.NNgosTQjhj/Listeners TMPDIR=/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/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/c43b6c506a073c17edf0ee47aa4589caa0013ef72b5c12ce280a862f70c1888598386d764af4f8e20b707445a2675ca31d1ba6b98e7e8f425f8fb4ff09b84ca4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/34affeb32d59fce47dc2ed1c4bf47a6a3bb444543b567179bd42d120cac4238992fa19abd9dc3f52069e8b7f91b615ffce0d811f29ac371037ccf9af089a54fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98fc06aedf558297c4fed4e05949f6356d2675e9fc18a9f70c25b7d98c13698d1be9263b5031755c6585cc78b788094c1f8804d4d094ede82e8f9278c740a034"} build_url:https://queue.taskcluster.net/v1/task/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011089 build_url: 'https://queue.taskcluster.net/v1/task/OtRDXNKpToK24M9FdFPIIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c43b6c506a073c17edf0ee47aa4589caa0013ef72b5c12ce280a862f70c1888598386d764af4f8e20b707445a2675ca31d1ba6b98e7e8f425f8fb4ff09b84ca4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/34affeb32d59fce47dc2ed1c4bf47a6a3bb444543b567179bd42d120cac4238992fa19abd9dc3f52069e8b7f91b615ffce0d811f29ac371037ccf9af089a54fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98fc06aedf558297c4fed4e05949f6356d2675e9fc18a9f70c25b7d98c13698d1be9263b5031755c6585cc78b788094c1f8804d4d094ede82e8f9278c740a034"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 10:43:01.729721) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 10:43:01.730042) ========= 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.Gk7XUNOyyq/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.NNgosTQjhj/Listeners TMPDIR=/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/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.005039 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 10:43:01.779259) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 10:43:01.779593) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 10:43:01.779935) ========= ========= Total master_lag: 2.88 =========