builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0020 starttime: 1470559916.14 results: success (0) buildid: 20160806233352 builduid: c7eac4ee2f484858b7db618471e1fcf7 revision: 679159ab1be9a9e38075c77783cde2f58dbbb20a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-07 01:51:56.142323) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-07 01:51:56.142719) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-07 01:51:56.142986) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-07 01:51:56.181401) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 01:51:56.181685) ========= 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.9xxY3tWd8H/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.qfX53FGuS3/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-07 01:51:56-- 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% 152M=0s 2016-08-07 01:51:56 (152 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.596623 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 01:51:56.805468) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 01:51:56.805775) ========= 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.9xxY3tWd8H/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.qfX53FGuS3/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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.020713 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 01:51:56.856183) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-07 01:51:56.856709) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 679159ab1be9a9e38075c77783cde2f58dbbb20a --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 679159ab1be9a9e38075c77783cde2f58dbbb20a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9xxY3tWd8H/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.qfX53FGuS3/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-07 01:51:56,931 truncating revision to first 12 chars 2016-08-07 01:51:56,931 Setting DEBUG logging. 2016-08-07 01:51:56,931 attempt 1/10 2016-08-07 01:51:56,931 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/679159ab1be9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-07 01:51:57,629 unpacking tar archive at: mozilla-central-679159ab1be9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.036431 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-07 01:51:57.925657) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-07 01:51:57.926298) ========= script_repo_revision: 679159ab1be9a9e38075c77783cde2f58dbbb20a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-07 01:51:57.927176) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-07 01:51:57.927775) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-07 01:51:57.980793) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-08-07 01:51:57.981468) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.9xxY3tWd8H/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.qfX53FGuS3/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 01:51:58 INFO - MultiFileLogger online at 20160807 01:51:58 in /builds/slave/test 01:51:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 01:51:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:51:58 INFO - {'append_to_log': False, 01:51:58 INFO - 'base_work_dir': '/builds/slave/test', 01:51:58 INFO - 'blob_upload_branch': 'Firefox', 01:51:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:51:58 INFO - 'branch': 'Firefox', 01:51:58 INFO - 'buildbot_json_path': 'buildprops.json', 01:51:58 INFO - 'config_files': ('talos/mac_config.py',), 01:51:58 INFO - 'default_actions': ('clobber', 01:51:58 INFO - 'read-buildbot-config', 01:51:58 INFO - 'download-and-extract', 01:51:58 INFO - 'populate-webroot', 01:51:58 INFO - 'create-virtualenv', 01:51:58 INFO - 'install', 01:51:58 INFO - 'run-tests'), 01:51:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:51:58 INFO - 'download_minidump_stackwalk': True, 01:51:58 INFO - 'download_symbols': 'ondemand', 01:51:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:51:58 INFO - 'tooltool.py': '/tools/tooltool.py', 01:51:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:51:58 INFO - '/tools/misc-python/virtualenv.py')}, 01:51:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:51:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:51:58 INFO - 'installer_path': 'installer.exe', 01:51:58 INFO - 'log_level': 'info', 01:51:58 INFO - 'log_name': 'talos', 01:51:58 INFO - 'log_to_console': True, 01:51:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:51:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:51:58 INFO - 'opt_config_files': (), 01:51:58 INFO - 'pip_index': False, 01:51:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:51:58 INFO - 'cmd': ('bash', 01:51:58 INFO - '-c', 01:51:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:51:58 INFO - 'enabled': True, 01:51:58 INFO - 'halt_on_failure': False, 01:51:58 INFO - 'name': 'check_screen_resolution'},), 01:51:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:51:58 INFO - 'cmd': ('bash', 01:51:58 INFO - '-c', 01:51:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:51:58 INFO - 'enabled': True, 01:51:58 INFO - 'halt_on_failure': False, 01:51:58 INFO - 'name': 'check_screen_resolution'},), 01:51:58 INFO - 'run_cmd_checks_enabled': True, 01:51:58 INFO - 'sps_profile': False, 01:51:58 INFO - 'sps_profile_interval': 0, 01:51:58 INFO - 'suite': 'dromaeojs-e10s', 01:51:58 INFO - 'system_bits': '32', 01:51:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:51:58 INFO - 'title': 't-yosemite-r7-0020', 01:51:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:51:58 INFO - 'use_talos_json': True, 01:51:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:51:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:51:58 INFO - 'work_dir': 'build'} 01:51:58 INFO - [mozharness: 2016-08-07 08:51:58.193072Z] Running clobber step. 01:51:58 INFO - Running pre-action listener: _resource_record_pre_action 01:51:58 INFO - Running main action method: clobber 01:51:58 INFO - rmtree: /builds/slave/test/build 01:51:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:51:59 INFO - Running post-action listener: _resource_record_post_action 01:51:59 INFO - [mozharness: 2016-08-07 08:51:59.147523Z] Finished clobber step (success) 01:51:59 INFO - [mozharness: 2016-08-07 08:51:59.147641Z] Running read-buildbot-config step. 01:51:59 INFO - Running pre-action listener: _resource_record_pre_action 01:51:59 INFO - Running main action method: read_buildbot_config 01:51:59 INFO - Using buildbot properties: 01:51:59 INFO - { 01:51:59 INFO - "project": "", 01:51:59 INFO - "product": "firefox", 01:51:59 INFO - "script_repo_revision": "production", 01:51:59 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 01:51:59 INFO - "repository": "", 01:51:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 01:51:59 INFO - "buildid": "20160806233352", 01:51:59 INFO - "pgo_build": "False", 01:51:59 INFO - "basedir": "/builds/slave/test", 01:51:59 INFO - "buildnumber": 18, 01:51:59 INFO - "slavename": "t-yosemite-r7-0020", 01:51:59 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 01:51:59 INFO - "platform": "yosemite_r7", 01:51:59 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 01:51:59 INFO - "branch": "mozilla-central", 01:51:59 INFO - "repo_path": "mozilla-central", 01:51:59 INFO - "revision": "679159ab1be9a9e38075c77783cde2f58dbbb20a", 01:51:59 INFO - "stage_platform": "macosx64", 01:51:59 INFO - "builduid": "c7eac4ee2f484858b7db618471e1fcf7", 01:51:59 INFO - "slavebuilddir": "test" 01:51:59 INFO - } 01:51:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/AQ_vNVkuSNqcfAHjzizILg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 01:51:59 INFO - Running post-action listener: _resource_record_post_action 01:51:59 INFO - [mozharness: 2016-08-07 08:51:59.150916Z] Finished read-buildbot-config step (success) 01:51:59 INFO - [mozharness: 2016-08-07 08:51:59.151038Z] Running download-and-extract step. 01:51:59 INFO - Running pre-action listener: _resource_record_pre_action 01:51:59 INFO - Running main action method: download_and_extract 01:51:59 INFO - mkdir: /builds/slave/test/build/tests 01:51:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:51:59 INFO - trying https://queue.taskcluster.net/v1/task/AQ_vNVkuSNqcfAHjzizILg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 01:51:59 INFO - Downloading https://queue.taskcluster.net/v1/task/AQ_vNVkuSNqcfAHjzizILg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 01:51:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AQ_vNVkuSNqcfAHjzizILg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 01:52:00 INFO - Downloaded 1199 bytes. 01:52:00 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 01:52:00 INFO - Using the following test package requirements: 01:52:00 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 01:52:00 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:52:00 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 01:52:00 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:52:00 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 01:52:00 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:52:00 INFO - u'jsshell-mac.zip'], 01:52:00 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:52:00 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 01:52:00 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 01:52:00 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:52:00 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 01:52:00 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:52:00 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 01:52:00 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:52:00 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 01:52:00 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:52:00 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 01:52:00 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 01:52:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:00 INFO - trying https://queue.taskcluster.net/v1/task/AQ_vNVkuSNqcfAHjzizILg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 01:52:00 INFO - Downloading https://queue.taskcluster.net/v1/task/AQ_vNVkuSNqcfAHjzizILg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 01:52:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AQ_vNVkuSNqcfAHjzizILg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 01:52:01 INFO - Downloaded 16321167 bytes. 01:52:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:52:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 01:52:04 INFO - Return code: 0 01:52:04 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 01:52:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:04 INFO - trying https://queue.taskcluster.net/v1/task/AQ_vNVkuSNqcfAHjzizILg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 01:52:04 INFO - Downloading https://queue.taskcluster.net/v1/task/AQ_vNVkuSNqcfAHjzizILg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 01:52:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AQ_vNVkuSNqcfAHjzizILg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 01:52:05 INFO - Downloaded 16321167 bytes. 01:52:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:52:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 01:52:08 INFO - Return code: 0 01:52:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:08 INFO - trying https://queue.taskcluster.net/v1/task/AQ_vNVkuSNqcfAHjzizILg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 01:52:08 INFO - Downloading https://queue.taskcluster.net/v1/task/AQ_vNVkuSNqcfAHjzizILg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 01:52:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AQ_vNVkuSNqcfAHjzizILg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 01:52:09 INFO - Downloaded 14413723 bytes. 01:52:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:52:09 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 01:52:10 INFO - Return code: 0 01:52:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:10 INFO - trying https://queue.taskcluster.net/v1/task/AQ_vNVkuSNqcfAHjzizILg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 01:52:10 INFO - Downloading https://queue.taskcluster.net/v1/task/AQ_vNVkuSNqcfAHjzizILg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 01:52:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AQ_vNVkuSNqcfAHjzizILg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 01:52:13 INFO - Downloaded 111806990 bytes. 01:52:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AQ_vNVkuSNqcfAHjzizILg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 01:52:13 INFO - mkdir: /builds/slave/test/properties 01:52:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:52:13 INFO - Writing to file /builds/slave/test/properties/build_url 01:52:13 INFO - Contents: 01:52:13 INFO - build_url:https://queue.taskcluster.net/v1/task/AQ_vNVkuSNqcfAHjzizILg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 01:52:14 INFO - Running post-action listener: _resource_record_post_action 01:52:14 INFO - Running post-action listener: set_extra_try_arguments 01:52:14 INFO - [mozharness: 2016-08-07 08:52:14.225905Z] Finished download-and-extract step (success) 01:52:14 INFO - [mozharness: 2016-08-07 08:52:14.226033Z] Running populate-webroot step. 01:52:14 INFO - Running pre-action listener: _resource_record_pre_action 01:52:14 INFO - Running main action method: populate_webroot 01:52:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:52:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:52:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:52:14 INFO - '--tppagecycles', 01:52:14 INFO - '7'], 01:52:14 INFO - 'tests': ['tsvgm']}}, 01:52:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:52:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:52:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:52:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:52:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:52:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:52:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:52:14 INFO - 'talos_options': ['--disable-e10s'], 01:52:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:52:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:52:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:52:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:52:14 INFO - 'talos_options': ['--disable-e10s'], 01:52:14 INFO - 'tests': ['damp', 'tps']}, 01:52:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:52:14 INFO - 'tests': ['damp', 'tps']}, 01:52:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:52:14 INFO - 'tests': ['dromaeo_dom']}, 01:52:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:52:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:52:14 INFO - 'tests': ['basic_compositor_video']}, 01:52:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:52:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:52:14 INFO - 'tests': ['a11yr', 01:52:14 INFO - 'ts_paint', 01:52:14 INFO - 'tpaint', 01:52:14 INFO - 'sessionrestore', 01:52:14 INFO - 'sessionrestore_no_auto_restore', 01:52:14 INFO - 'tabpaint']}, 01:52:14 INFO - 'other-e10s': {'tests': ['a11yr', 01:52:14 INFO - 'ts_paint', 01:52:14 INFO - 'tpaint', 01:52:14 INFO - 'sessionrestore', 01:52:14 INFO - 'sessionrestore_no_auto_restore', 01:52:14 INFO - 'tabpaint']}, 01:52:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:52:14 INFO - 'ts_paint', 01:52:14 INFO - 'tpaint', 01:52:14 INFO - 'sessionrestore', 01:52:14 INFO - 'sessionrestore_no_auto_restore', 01:52:14 INFO - 'tabpaint']}, 01:52:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:52:14 INFO - 'ts_paint', 01:52:14 INFO - 'tpaint', 01:52:14 INFO - 'sessionrestore', 01:52:14 INFO - 'sessionrestore_no_auto_restore', 01:52:14 INFO - 'tabpaint']}, 01:52:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:52:14 INFO - 'tests': ['a11yr', 01:52:14 INFO - 'ts_paint', 01:52:14 INFO - 'tpaint', 01:52:14 INFO - 'sessionrestore', 01:52:14 INFO - 'sessionrestore_no_auto_restore', 01:52:14 INFO - 'tabpaint']}, 01:52:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:52:14 INFO - 'tests': ['a11yr', 01:52:14 INFO - 'ts_paint', 01:52:14 INFO - 'tpaint', 01:52:14 INFO - 'sessionrestore', 01:52:14 INFO - 'sessionrestore_no_auto_restore', 01:52:14 INFO - 'tabpaint']}, 01:52:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:52:14 INFO - 'tests': ['tsvgx', 01:52:14 INFO - 'tsvgr_opacity', 01:52:14 INFO - 'tart', 01:52:14 INFO - 'tscrollx', 01:52:14 INFO - 'cart']}, 01:52:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:52:14 INFO - 'tsvgr_opacity', 01:52:14 INFO - 'tart', 01:52:14 INFO - 'tscrollx', 01:52:14 INFO - 'cart']}, 01:52:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:52:14 INFO - 'talos_options': ['--disable-e10s'], 01:52:14 INFO - 'tests': ['tp5o']}, 01:52:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:52:14 INFO - 'tests': ['tp5o']}, 01:52:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:52:14 INFO - 'talos_options': ['--disable-e10s', 01:52:14 INFO - '--xperf_path', 01:52:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:52:14 INFO - 'tests': ['tp5n']}, 01:52:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:52:14 INFO - 'talos_options': ['--xperf_path', 01:52:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:52:14 INFO - 'tests': ['tp5n']}}, 01:52:14 INFO - 'talos.zip': {'path': '', 01:52:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:52:14 INFO - Running post-action listener: _resource_record_post_action 01:52:14 INFO - [mozharness: 2016-08-07 08:52:14.244989Z] Finished populate-webroot step (success) 01:52:14 INFO - [mozharness: 2016-08-07 08:52:14.245120Z] Running create-virtualenv step. 01:52:14 INFO - Running pre-action listener: _resource_record_pre_action 01:52:14 INFO - Running main action method: create_virtualenv 01:52:14 INFO - Creating virtualenv /builds/slave/test/build/venv 01:52: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 01:52:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:52:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:52:14 INFO - Using real prefix '/tools/python27' 01:52:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:52:15 INFO - Installing distribute.............................................................................................................................................................................................done. 01:52:17 INFO - Installing pip.................done. 01:52:17 INFO - Return code: 0 01:52:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:52:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109f36a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fcb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5b14971d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e9ad30>, '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 0x109bffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e92b20>, '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.9xxY3tWd8H/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.qfX53FGuS3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 01:52:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 01:52:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:52:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9xxY3tWd8H/Render', 01:52:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:52:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:52:17 INFO - 'HOME': '/Users/cltbld', 01:52:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:52:17 INFO - 'LOGNAME': 'cltbld', 01:52:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:17 INFO - 'MOZ_NO_REMOTE': '1', 01:52:17 INFO - 'NO_EM_RESTART': '1', 01:52:17 INFO - 'PAGER': '/bin/cat', 01:52:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:52:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:17 INFO - 'PWD': '/builds/slave/test', 01:52:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:52:17 INFO - 'SHELL': '/bin/bash', 01:52:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qfX53FGuS3/Listeners', 01:52:17 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 01:52:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:52:17 INFO - 'USER': 'cltbld', 01:52:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:52:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:52:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:17 INFO - 'XPC_FLAGS': '0x0', 01:52:17 INFO - 'XPC_SERVICE_NAME': '0', 01:52:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:52:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52: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 01:52:18 INFO - Cleaning up... 01:52:18 INFO - Return code: 0 01:52:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:52:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52: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 0x109f36a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fcb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5b14971d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e9ad30>, '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 0x109bffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e92b20>, '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.9xxY3tWd8H/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.qfX53FGuS3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 01:52: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 01:52: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 01:52:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9xxY3tWd8H/Render', 01:52:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:52:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:52:18 INFO - 'HOME': '/Users/cltbld', 01:52:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:52:18 INFO - 'LOGNAME': 'cltbld', 01:52:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:18 INFO - 'MOZ_NO_REMOTE': '1', 01:52:18 INFO - 'NO_EM_RESTART': '1', 01:52:18 INFO - 'PAGER': '/bin/cat', 01:52:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:52:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:18 INFO - 'PWD': '/builds/slave/test', 01:52:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:52:18 INFO - 'SHELL': '/bin/bash', 01:52:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qfX53FGuS3/Listeners', 01:52:18 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 01:52:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:52:18 INFO - 'USER': 'cltbld', 01:52:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:52:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:52:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:18 INFO - 'XPC_FLAGS': '0x0', 01:52:18 INFO - 'XPC_SERVICE_NAME': '0', 01:52:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:52:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:18 INFO - Downloading/unpacking psutil>=3.1.1 01:52: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 01:52: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 01:52: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 01:52: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 01:52: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 01:52: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 01:52:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:52:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:52:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:52:21 INFO - Installing collected packages: psutil 01:52:21 INFO - Running setup.py install for psutil 01:52:21 INFO - building 'psutil._psutil_osx' extension 01:52: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 01:52: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_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 01:52: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/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 01:52:21 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 01:52:21 INFO - building 'psutil._psutil_posix' extension 01:52:21 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 01:52:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:52:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:52:22 INFO - ^ 01:52:22 INFO - 1 warning generated. 01:52: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 01:52:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:52:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:52:22 INFO - Successfully installed psutil 01:52:22 INFO - Cleaning up... 01:52:22 INFO - Return code: 0 01:52:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:52: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52: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 0x109f36a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fcb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5b14971d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e9ad30>, '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 0x109bffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e92b20>, '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.9xxY3tWd8H/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.qfX53FGuS3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 01:52: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 01:52: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 01:52:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9xxY3tWd8H/Render', 01:52:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:52:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:52:22 INFO - 'HOME': '/Users/cltbld', 01:52:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:52:22 INFO - 'LOGNAME': 'cltbld', 01:52:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:22 INFO - 'MOZ_NO_REMOTE': '1', 01:52:22 INFO - 'NO_EM_RESTART': '1', 01:52:22 INFO - 'PAGER': '/bin/cat', 01:52:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:52:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:22 INFO - 'PWD': '/builds/slave/test', 01:52:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:52:22 INFO - 'SHELL': '/bin/bash', 01:52:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qfX53FGuS3/Listeners', 01:52:22 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 01:52:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:52:22 INFO - 'USER': 'cltbld', 01:52:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:52:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:52:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:22 INFO - 'XPC_FLAGS': '0x0', 01:52:22 INFO - 'XPC_SERVICE_NAME': '0', 01:52:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:52:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:52:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:52:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:52:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:52:25 INFO - Installing collected packages: mozsystemmonitor 01:52:25 INFO - Running setup.py install for mozsystemmonitor 01:52:25 INFO - Successfully installed mozsystemmonitor 01:52:25 INFO - Cleaning up... 01:52:25 INFO - Return code: 0 01:52:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:52:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52: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 0x109f36a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fcb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5b14971d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e9ad30>, '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 0x109bffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e92b20>, '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.9xxY3tWd8H/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.qfX53FGuS3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 01:52: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 01:52: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 01:52:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9xxY3tWd8H/Render', 01:52:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:52:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:52:25 INFO - 'HOME': '/Users/cltbld', 01:52:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:52:25 INFO - 'LOGNAME': 'cltbld', 01:52:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:25 INFO - 'MOZ_NO_REMOTE': '1', 01:52:25 INFO - 'NO_EM_RESTART': '1', 01:52:25 INFO - 'PAGER': '/bin/cat', 01:52:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:52:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:25 INFO - 'PWD': '/builds/slave/test', 01:52:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:52:25 INFO - 'SHELL': '/bin/bash', 01:52:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qfX53FGuS3/Listeners', 01:52:25 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 01:52:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:52:25 INFO - 'USER': 'cltbld', 01:52:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:52:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:52:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:25 INFO - 'XPC_FLAGS': '0x0', 01:52:25 INFO - 'XPC_SERVICE_NAME': '0', 01:52:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:52:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:25 INFO - Downloading/unpacking jsonschema==2.5.1 01:52:25 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 01:52:25 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 01:52:25 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 01:52:25 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 01:52:25 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 01:52:25 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 01:52:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:52:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:52: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 01:52: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 01:52: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 01:52: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 01:52: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 01:52: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 01:52:28 INFO - Downloading functools32-3.2.3-2.tar.gz 01:52:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:52:28 INFO - warning: no files found matching '*.txt' 01:52:28 INFO - no previously-included directories found matching 'build' 01:52:28 INFO - no previously-included directories found matching 'dist' 01:52:28 INFO - no previously-included directories found matching '.git*' 01:52:28 INFO - Installing collected packages: jsonschema, functools32 01:52:28 INFO - Running setup.py install for functools32 01:52:28 INFO - warning: no files found matching '*.txt' 01:52:28 INFO - no previously-included directories found matching 'build' 01:52:28 INFO - no previously-included directories found matching 'dist' 01:52:28 INFO - no previously-included directories found matching '.git*' 01:52:28 INFO - Successfully installed jsonschema functools32 01:52:28 INFO - Cleaning up... 01:52:28 INFO - Return code: 0 01:52:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:52:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109f36a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fcb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5b14971d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e9ad30>, '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 0x109bffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e92b20>, '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.9xxY3tWd8H/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.qfX53FGuS3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 01:52:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 01:52:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 01:52:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9xxY3tWd8H/Render', 01:52:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:52:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:52:28 INFO - 'HOME': '/Users/cltbld', 01:52:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:52:28 INFO - 'LOGNAME': 'cltbld', 01:52:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:28 INFO - 'MOZ_NO_REMOTE': '1', 01:52:28 INFO - 'NO_EM_RESTART': '1', 01:52:28 INFO - 'PAGER': '/bin/cat', 01:52:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:52:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:28 INFO - 'PWD': '/builds/slave/test', 01:52:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:52:28 INFO - 'SHELL': '/bin/bash', 01:52:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qfX53FGuS3/Listeners', 01:52:28 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 01:52:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:52:28 INFO - 'USER': 'cltbld', 01:52:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:52:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:52:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:28 INFO - 'XPC_FLAGS': '0x0', 01:52:28 INFO - 'XPC_SERVICE_NAME': '0', 01:52:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:52:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:52:29 INFO - Cleaning up... 01:52:29 INFO - Return code: 0 01:52:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:52: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52: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 0x109f36a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fcb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5b14971d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e9ad30>, '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 0x109bffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e92b20>, '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.9xxY3tWd8H/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.qfX53FGuS3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 01:52: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 01:52: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 01:52:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9xxY3tWd8H/Render', 01:52:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:52:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:52:29 INFO - 'HOME': '/Users/cltbld', 01:52:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:52:29 INFO - 'LOGNAME': 'cltbld', 01:52:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:29 INFO - 'MOZ_NO_REMOTE': '1', 01:52:29 INFO - 'NO_EM_RESTART': '1', 01:52:29 INFO - 'PAGER': '/bin/cat', 01:52:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:52:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:29 INFO - 'PWD': '/builds/slave/test', 01:52:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:52:29 INFO - 'SHELL': '/bin/bash', 01:52:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qfX53FGuS3/Listeners', 01:52:29 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 01:52:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:52:29 INFO - 'USER': 'cltbld', 01:52:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:52:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:52:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:29 INFO - 'XPC_FLAGS': '0x0', 01:52:29 INFO - 'XPC_SERVICE_NAME': '0', 01:52:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:52:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:29 INFO - Downloading/unpacking blobuploader==1.2.4 01:52: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 01:52: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 01:52: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 01:52: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 01:52: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 01:52: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 01:52:31 INFO - Downloading blobuploader-1.2.4.tar.gz 01:52:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:52:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:52:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:52:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:52: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 01:52: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 01:52: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 01:52: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 01:52: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 01:52: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 01:52:32 INFO - Downloading docopt-0.6.1.tar.gz 01:52:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:52:32 INFO - Installing collected packages: blobuploader, requests, docopt 01:52:32 INFO - Running setup.py install for blobuploader 01:52:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:52:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:52:33 INFO - Running setup.py install for requests 01:52:33 INFO - Running setup.py install for docopt 01:52:33 INFO - Successfully installed blobuploader requests docopt 01:52:33 INFO - Cleaning up... 01:52:33 INFO - Return code: 0 01:52:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:52:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:33 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 0x109f36a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fcb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5b14971d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e9ad30>, '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 0x109bffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e92b20>, '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.9xxY3tWd8H/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.qfX53FGuS3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 01:52:33 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 01:52:33 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 01:52:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9xxY3tWd8H/Render', 01:52:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:52:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:52:33 INFO - 'HOME': '/Users/cltbld', 01:52:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:52:33 INFO - 'LOGNAME': 'cltbld', 01:52:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:33 INFO - 'MOZ_NO_REMOTE': '1', 01:52:33 INFO - 'NO_EM_RESTART': '1', 01:52:33 INFO - 'PAGER': '/bin/cat', 01:52:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:52:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:33 INFO - 'PWD': '/builds/slave/test', 01:52:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:52:33 INFO - 'SHELL': '/bin/bash', 01:52:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qfX53FGuS3/Listeners', 01:52:33 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 01:52:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:52:33 INFO - 'USER': 'cltbld', 01:52:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:52:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:52:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:33 INFO - 'XPC_FLAGS': '0x0', 01:52:33 INFO - 'XPC_SERVICE_NAME': '0', 01:52:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:52:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:52:34 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-mS1A7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:52:34 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-_Z62AL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:52:34 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-DFKP7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:52:34 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-1Xvt_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:52:34 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-HTuzpn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:52:34 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-0iUcDU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:52:34 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-qqsfWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:52:34 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-_dZeUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:52:34 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-fSwl7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:52:35 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-CKYihb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:52:35 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-dFAG9N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:52:35 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-dke_v7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:52:35 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-NHIk5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:52:35 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-K2ZIhe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:52:35 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-wAUSnE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:52:35 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-rU68se-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:52:35 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-8wdj6p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:52:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:52:35 INFO - Running setup.py install for manifestparser 01:52:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:52:36 INFO - Running setup.py install for mozcrash 01:52:36 INFO - Running setup.py install for mozdebug 01:52:36 INFO - Running setup.py install for mozdevice 01:52:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:52:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:52:36 INFO - Running setup.py install for mozfile 01:52:36 INFO - Running setup.py install for mozhttpd 01:52:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:52:36 INFO - Running setup.py install for mozinfo 01:52:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:52:36 INFO - Running setup.py install for mozInstall 01:52:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:52:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:52:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:52:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:52:37 INFO - Running setup.py install for mozleak 01:52:37 INFO - Running setup.py install for mozlog 01:52:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:52:37 INFO - Running setup.py install for moznetwork 01:52:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:52:37 INFO - Running setup.py install for mozprocess 01:52:37 INFO - Running setup.py install for mozprofile 01:52:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:52:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:52:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:52:37 INFO - Running setup.py install for mozrunner 01:52:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:52:38 INFO - Running setup.py install for mozscreenshot 01:52:38 INFO - Running setup.py install for moztest 01:52:38 INFO - Running setup.py install for mozversion 01:52:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:52:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:52:38 INFO - Cleaning up... 01:52:38 INFO - Return code: 0 01:52:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:52: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52: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 0x109f36a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fcb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5b14971d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e9ad30>, '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 0x109bffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e92b20>, '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.9xxY3tWd8H/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.qfX53FGuS3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 01:52: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 01:52: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 01:52:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9xxY3tWd8H/Render', 01:52:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:52:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:52:38 INFO - 'HOME': '/Users/cltbld', 01:52:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:52:38 INFO - 'LOGNAME': 'cltbld', 01:52:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:38 INFO - 'MOZ_NO_REMOTE': '1', 01:52:38 INFO - 'NO_EM_RESTART': '1', 01:52:38 INFO - 'PAGER': '/bin/cat', 01:52:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:52:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:38 INFO - 'PWD': '/builds/slave/test', 01:52:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:52:38 INFO - 'SHELL': '/bin/bash', 01:52:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qfX53FGuS3/Listeners', 01:52:38 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 01:52:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:52:38 INFO - 'USER': 'cltbld', 01:52:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:52:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:52:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:38 INFO - 'XPC_FLAGS': '0x0', 01:52:38 INFO - 'XPC_SERVICE_NAME': '0', 01:52:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:52:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:52:38 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-c0B4uX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:52:38 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)) 01:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:52:38 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-A6qvsR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:52:38 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)) 01:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:52:38 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-7Fhfed-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:52: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)) 01:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:52:39 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-KyGwmI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:52: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)) 01:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:52:39 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-0DrG4c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:52: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)) 01:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:52:39 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-Fe2i_I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:52: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)) 01:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:52:39 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-YG7PkT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:52: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)) 01:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:52:39 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-_LniBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:52: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)) 01:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:52:39 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-VMDkJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:52: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)) 01:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:52:39 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-_Ctvjf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:52:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:52:39 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-cyslu3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:52:39 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)) 01:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:52:39 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-PkNk1O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:52: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)) 01:52:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:52:40 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-q5zttb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:52: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)) 01:52:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:52:40 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-K7cYw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:52: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)) 01:52:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:52:40 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-7tfPuF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:52: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)) 01:52:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:52:40 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-oBvQB0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:52: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)) 01:52:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:52:40 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-pJ7A6b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:52: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)) 01:52: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)) 01:52: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)) 01:52: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)) 01:52: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)) 01:52: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)) 01:52:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:52: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 01:52: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 01:52: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 01:52: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 01:52: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 01:52: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 01:52:42 INFO - Downloading blessings-1.6.tar.gz 01:52:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:52:43 INFO - Installing collected packages: blessings 01:52:43 INFO - Running setup.py install for blessings 01:52:43 INFO - Successfully installed blessings 01:52:43 INFO - Cleaning up... 01:52:43 INFO - Return code: 0 01:52:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:52:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:52:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:52:43 INFO - Reading from file tmpfile_stdout 01:52:43 INFO - Current package versions: 01:52:43 INFO - blessings == 1.6 01:52:43 INFO - blobuploader == 1.2.4 01:52:43 INFO - docopt == 0.6.1 01:52:43 INFO - functools32 == 3.2.3-2 01:52:43 INFO - jsonschema == 2.5.1 01:52:43 INFO - manifestparser == 1.1 01:52:43 INFO - mozInstall == 1.12 01:52:43 INFO - mozcrash == 0.17 01:52:43 INFO - mozdebug == 0.1 01:52:43 INFO - mozdevice == 0.48 01:52:43 INFO - mozfile == 1.2 01:52:43 INFO - mozhttpd == 0.7 01:52:43 INFO - mozinfo == 0.9 01:52:43 INFO - mozleak == 0.1 01:52:43 INFO - mozlog == 3.3 01:52:43 INFO - moznetwork == 0.27 01:52:43 INFO - mozprocess == 0.23 01:52:43 INFO - mozprofile == 0.28 01:52:43 INFO - mozrunner == 6.12 01:52:43 INFO - mozscreenshot == 0.1 01:52:43 INFO - mozsystemmonitor == 0.3 01:52:43 INFO - moztest == 0.7 01:52:43 INFO - mozversion == 1.4 01:52:43 INFO - psutil == 3.1.1 01:52:43 INFO - requests == 1.2.3 01:52:43 INFO - wsgiref == 0.1.2 01:52:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:52:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:43 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 0x109f36a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fcb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5b14971d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e9ad30>, '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 0x109bffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e92b20>, '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.9xxY3tWd8H/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.qfX53FGuS3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 01:52:43 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 01:52:43 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 01:52:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9xxY3tWd8H/Render', 01:52:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:52:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:52:43 INFO - 'HOME': '/Users/cltbld', 01:52:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:52:43 INFO - 'LOGNAME': 'cltbld', 01:52:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:43 INFO - 'MOZ_NO_REMOTE': '1', 01:52:43 INFO - 'NO_EM_RESTART': '1', 01:52:43 INFO - 'PAGER': '/bin/cat', 01:52:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:52:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:43 INFO - 'PWD': '/builds/slave/test', 01:52:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:52:43 INFO - 'SHELL': '/bin/bash', 01:52:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qfX53FGuS3/Listeners', 01:52:43 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 01:52:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:52:43 INFO - 'USER': 'cltbld', 01:52:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:52:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:52:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:43 INFO - 'XPC_FLAGS': '0x0', 01:52:43 INFO - 'XPC_SERVICE_NAME': '0', 01:52:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:52:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:43 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)) 01:52:43 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)) 01:52:43 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)) 01:52:43 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)) 01:52:43 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)) 01:52:43 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)) 01:52:43 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)) 01:52:43 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)) 01:52:43 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)) 01:52:43 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)) 01:52:43 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)) 01:52:43 INFO - Cleaning up... 01:52:43 INFO - Return code: 0 01:52:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:52:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:43 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 0x109f36a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fcb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5b14971d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e9ad30>, '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 0x109bffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e92b20>, '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.9xxY3tWd8H/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.qfX53FGuS3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 01:52:43 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 01:52:43 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 01:52:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9xxY3tWd8H/Render', 01:52:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:52:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:52:43 INFO - 'HOME': '/Users/cltbld', 01:52:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:52:43 INFO - 'LOGNAME': 'cltbld', 01:52:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:43 INFO - 'MOZ_NO_REMOTE': '1', 01:52:43 INFO - 'NO_EM_RESTART': '1', 01:52:43 INFO - 'PAGER': '/bin/cat', 01:52:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:52:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:43 INFO - 'PWD': '/builds/slave/test', 01:52:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:52:43 INFO - 'SHELL': '/bin/bash', 01:52:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qfX53FGuS3/Listeners', 01:52:43 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 01:52:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:52:43 INFO - 'USER': 'cltbld', 01:52:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:52:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:52:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:43 INFO - 'XPC_FLAGS': '0x0', 01:52:43 INFO - 'XPC_SERVICE_NAME': '0', 01:52:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:52:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:52:44 INFO - Cleaning up... 01:52:44 INFO - Return code: 0 01:52:44 INFO - Running post-action listener: _resource_record_post_action 01:52:44 INFO - Running post-action listener: _start_resource_monitoring 01:52:44 INFO - Starting resource monitoring. 01:52:44 INFO - [mozharness: 2016-08-07 08:52:44.161902Z] Finished create-virtualenv step (success) 01:52:44 INFO - [mozharness: 2016-08-07 08:52:44.162855Z] Running install step. 01:52:44 INFO - Running pre-action listener: _resource_record_pre_action 01:52:44 INFO - Running main action method: install 01:52:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:52:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:52:44 INFO - Reading from file tmpfile_stdout 01:52:44 INFO - Detecting whether we're running mozinstall >=1.0... 01:52:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:52:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:52:44 INFO - Reading from file tmpfile_stdout 01:52:44 INFO - Output received: 01:52:44 INFO - Usage: mozinstall [options] installer 01:52:44 INFO - Options: 01:52:44 INFO - -h, --help show this help message and exit 01:52:44 INFO - -d DEST, --destination=DEST 01:52:44 INFO - Directory to install application into. [default: 01:52:44 INFO - "/builds/slave/test"] 01:52:44 INFO - --app=APP Application being installed. [default: firefox] 01:52:44 INFO - mkdir: /builds/slave/test/build/application 01:52:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:52:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 01:53:18 INFO - Reading from file tmpfile_stdout 01:53:18 INFO - Output received: 01:53:18 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 01:53:18 INFO - Running post-action listener: _resource_record_post_action 01:53:18 INFO - [mozharness: 2016-08-07 08:53:18.708577Z] Finished install step (success) 01:53:18 INFO - [mozharness: 2016-08-07 08:53:18.708687Z] Running run-tests step. 01:53:18 INFO - Running pre-action listener: _resource_record_pre_action 01:53:18 INFO - Running main action method: run_tests 01:53:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:53:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:53:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:53:18 INFO - 2016-08-07 01:53:18.722 screenresolution[2033:21829] starting screenresolution argv=screenresolution get 01:53:18 INFO - 2016-08-07 01:53:18.725 screenresolution[2033:21829] Display 0: 1600x1200x32@60 01:53:18 INFO - 2016-08-07 01:53:18.731 screenresolution[2034:21836] starting screenresolution argv=screenresolution list 01:53:18 INFO - Available Modes on Display 0 01:53:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:53:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:53:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:53:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:53:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:53:18 INFO - Intel Iris: 01:53:18 INFO - Chipset Model: Intel Iris 01:53:18 INFO - Type: GPU 01:53:18 INFO - Bus: Built-In 01:53:18 INFO - VRAM (Dynamic, Max): 1536 MB 01:53:18 INFO - Vendor: Intel (0x8086) 01:53:18 INFO - Device ID: 0x0a2e 01:53:18 INFO - Revision ID: 0x0009 01:53:18 INFO - Displays: 01:53:18 INFO - Display: 01:53:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:53:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:53:18 INFO - Display Serial Number: 1600x1200 60 01:53:18 INFO - Main Display: Yes 01:53:18 INFO - Mirror: Off 01:53:18 INFO - Online: Yes 01:53:18 INFO - Rotation: Supported 01:53:18 INFO - Return code: 0 01:53:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:53:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:53:18 INFO - Python 2.7.3 01:53:18 INFO - Return code: 0 01:53:18 INFO - grabbing minidump binary from tooltool 01:53:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:53:18 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 0x109e9ad30>, '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 0x109bffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e92b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:53:18 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 01:53:18 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 01:53:18 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 01:53:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:53:19 INFO - Return code: 0 01:53:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:53:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:53:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:53:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:53:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:53:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AQ_vNVkuSNqcfAHjzizILg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0020', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 01:53:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AQ_vNVkuSNqcfAHjzizILg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0020 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 01:53:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9xxY3tWd8H/Render', 01:53:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:53:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:53:19 INFO - 'HOME': '/Users/cltbld', 01:53:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:53:19 INFO - 'LOGNAME': 'cltbld', 01:53:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:53:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:53:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:53:19 INFO - 'MOZ_NO_REMOTE': '1', 01:53:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:53:19 INFO - 'NO_EM_RESTART': '1', 01:53:19 INFO - 'PAGER': '/bin/cat', 01:53:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:53:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:53:19 INFO - 'PWD': '/builds/slave/test', 01:53:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:53:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:53:19 INFO - 'SHELL': '/bin/bash', 01:53:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qfX53FGuS3/Listeners', 01:53:19 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 01:53:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:53:19 INFO - 'USER': 'cltbld', 01:53:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:53:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:53:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:53:19 INFO - 'XPC_FLAGS': '0x0', 01:53:19 INFO - 'XPC_SERVICE_NAME': '0', 01:53:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:53:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AQ_vNVkuSNqcfAHjzizILg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0020', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 01:53:19 INFO - mozversion application_buildid: 20160806233352 01:53:19 INFO - mozversion application_changeset: 679159ab1be9a9e38075c77783cde2f58dbbb20a 01:53:19 INFO - mozversion application_display_name: Nightly 01:53:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:53:19 INFO - mozversion application_name: Firefox 01:53:19 INFO - mozversion application_remotingname: firefox 01:53:19 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 01:53:19 INFO - mozversion application_vendor: Mozilla 01:53:19 INFO - mozversion application_version: 51.0a1 01:53:19 INFO - mozversion platform_buildid: 20160806233352 01:53:19 INFO - mozversion platform_changeset: 679159ab1be9a9e38075c77783cde2f58dbbb20a 01:53:19 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 01:53:19 INFO - mozversion platform_version: 51.0a1 01:53:19 INFO - using testdate: 1470559999 01:53:19 INFO - actual date: 1470559999 01:53:19 INFO - starting webserver on 'localhost:51313' 01:53:19 INFO - SUITE-START | Running 2 tests 01:53:19 INFO - TEST-START | dromaeo_css 01:53:19 INFO - operating with platform_type : mac_ 01:53:19 INFO - Initialising browser for dromaeo_css test... 01:53:19 INFO - TEST-INFO | started process 2044 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/tmpH4hd_5/profile http://localhost:51313/getInfo.html) 01:53:22 INFO - PROCESS | 2044 | __metrics Screen width/height:1600/1200 01:53:22 INFO - PROCESS | 2044 | colorDepth:24 01:53:22 INFO - PROCESS | 2044 | Browser inner width/height: 1024/689 01:53:22 INFO - PROCESS | 2044 | __metrics 01:53:22 ERROR - PROCESS | 2044 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 01:53:22 INFO - TEST-INFO | 2044: exit 0 01:53:22 INFO - Browser initialized. 01:53:22 INFO - Running cycle 1/1 for dromaeo_css test... 01:53:22 INFO - TEST-INFO | started process 2047 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/tmpH4hd_5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:53:35 INFO - PROCESS | 2047 | RSS: Main: 254586880 01:53:35 INFO - PROCESS | 2047 | 01:54:24 INFO - PROCESS | 2047 | 1470560064629 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] 01:56:36 INFO - PROCESS | 2047 | Cycle 1(1): loaded http://localhost:51313/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51313/tests/dromaeo/cssquery-ext.html) 01:56:38 INFO - PROCESS | 2047 | RSS: Main: 267603968 01:56:38 INFO - PROCESS | 2047 | 01:59:40 INFO - PROCESS | 2047 | Cycle 1(1): loaded http://localhost:51313/tests/dromaeo/cssquery-ext.html (next: http://localhost:51313/tests/dromaeo/cssquery-jquery.html) 01:59:41 INFO - PROCESS | 2047 | RSS: Main: 279920640 01:59:41 INFO - PROCESS | 2047 | 02:02:43 INFO - PROCESS | 2047 | Cycle 1(1): loaded http://localhost:51313/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51313/tests/dromaeo/cssquery-mootools.html) 02:02:43 INFO - PROCESS | 2047 | RSS: Main: 287211520 02:02:43 INFO - PROCESS | 2047 | 02:05:45 INFO - PROCESS | 2047 | Cycle 1(1): loaded http://localhost:51313/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51313/tests/dromaeo/cssquery-prototype.html) 02:05:46 INFO - PROCESS | 2047 | RSS: Main: 286633984 02:05:46 INFO - PROCESS | 2047 | 02:08:48 INFO - PROCESS | 2047 | Cycle 1(1): loaded http://localhost:51313/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51313/tests/dromaeo/cssquery-yui.html) 02:08:49 INFO - PROCESS | 2047 | RSS: Main: 291815424 02:08:49 INFO - PROCESS | 2047 | 02:11:52 INFO - PROCESS | 2047 | Cycle 1(1): loaded http://localhost:51313/tests/dromaeo/cssquery-yui.html (next: http://localhost:51313/tests/dromaeo/cssquery-dojo.html) 02:11:52 INFO - PROCESS | 2047 | RSS: Main: 291688448 02:11:52 INFO - PROCESS | 2047 | 02:11:52 INFO - PROCESS | 2047 | __start_tp_report 02:11:52 INFO - PROCESS | 2047 | _x_x_mozilla_page_load 02:11:52 INFO - PROCESS | 2047 | _x_x_mozilla_page_load_details 02:11:52 INFO - PROCESS | 2047 | |i|pagename|runs| 02:11:52 INFO - PROCESS | 2047 | |0;dojo.html;2942.8822847086117;2956.8521573921307;3338.64944180861;3423.229773301009;3498.9125271868206;4621.584057434834;5657.9434205657935;5732.312122545301;5780.971095144524;5849.912251316228;5675.772969081242;5753.194552762617;5795.82612521624;5799.826005219839;5809.506191973687;5167.147420675584;5264.473552644743;5279.841604751864;5349.411564727877;5351.812686555972;5318.414974352838;5346.144616861303;5359.16932875403;5376.220448035033;5401.351837779472;5152.690838549699;5210.322658054429;5274.525292723635;5286.074936886068;5372;4178.540360560366;5297.47025297471;5316.255724198615;5329.440408757064;5352.2506849041165;5118.283440318359;5221.503957124067;5221.72067843377;5233.21501774733;5302.469753024705;5555.694436805974;5832.88334233313;5832.9708351457975;5833.445822646841;5941.049432090888;6217.2539295284405;6253.937460625427;6258.006216299377;6298.874022519524;6308.526860485436;6199.752009919598;6279.026750853625;6308.211473565804;6312.305646378894;6325.114483972247;5825;5846.356900740915;5889.293284805808;5907.113932910098;5970.641761494324;6324.272708638566;6369.745210191633;6383.234011918599;6386.776462823779;6397.712102955378;6144.201253837062;6146.200993870768;6153.784617538454;6219.471344935731;6220.284667263322;6194.721237544321;6214.471769899519;6252.6873656317;6288.119663247149;6309.589876658002;6237.1891654084675;6272.18461599989;6277.340879207709;6282.4974002079725;6301.527385446018;5016.924746128811;6300.810975670737;6304.873902521924;6315.715792789336;6317.368263173737;4911.45973942866;4952.5542701157065;4994.425641051325;4996.575291100225;5047.268146118866;4040.939385909214;4420.270655341897;4433.069055498317;4452.131834292282;4460.8661740147845;5649;5890.676012819336;5896.64620122794;5905.822825315247;5916.349201587822;6173.81478555644;6183.443490085914;6207.751689932443;6236.314005459396;6269.811905642838;5854.619449735754;5861.120831875252;5870.178175055496;5871.530277577784;5881.05903055509;5444.972775136099;5461.426550212171;5471.5896307777075;5477.890442191214;5495.752691128909;5164.7417629119145;5419.701916394636;6381.23425188971;6502.707378168088;6505.154329937267;5298;6297.96851015742;6298.905516417166;6301.653409062362;6315.747370105144;6292.653904035322;6361.650109243851;6508.967455162694;6514.771982980573;6530.608163510205;5716;6177.073438984188;6220.968895155495;6251.156093927261;6307.873842523216;5827.650340979555;5945.702714864153;6211.565190436626;6255.280642726145;6269.4984401246875;4926.876828079184;6097.634141951497;6195.1636529068;6224.439800417984;6235.501159907287;6101.005087479836;6142.907856382247;6143.815685529441;6147.96926015385;6165.075238714228;4931.4328852181025;5975.312839023568;6019.127226552213;6027.095935609694;6081;6051.909221361771;6117.522930757332;6162.969185154046;6171.876562468636;6188.102725104744;6081.148639190428;6124.809316330491;6126.914660831389;6129.49582850979;6140.412224875342 02:11:52 INFO - PROCESS | 2047 | |1;ext.html;492.83982705620673;524.1325606121867;542.0135353656344;545.7216819422096;557.5957430862628;37844.621553784455;40490.79754601227;40604.18791624166;40720.38919416211;40766;41629.791851040776;42152.946176345606;42172.945676358104;42237.36643950343;42337.7883110584;9548.80902381952;9553.426794392324;9619.326647134685;9638.566264518113;9872.851907221397;9567.904320956806;9701.805963880719;9720.319577629534;9726.56230469627;9766.658166964158;9582.66460673873;9711.854322185172;9740;9741.366811157288;9767.316287859818;9466;9576.377535460173;9690.75773105678;9700.514974251259;9728.270379721549;9481.146696797252;9611;9617.23062155026;9635.181009614122;9696.95151524238;39540;39825.80087099546;40001.79999100015;40032.79983600092;40203.994900127436;2942.396808654221;3105.487594546897;3361.344537815127;3366.360391525602;3400.6429324920773;31768;32041.83979080113;32079.83960080208;32320.030399088064;32993;2899.9275018124504;3205.423023855705;3212.3093534889877;3241.546183534307;3358.983205083983;492965;503400;505392;506192;507164;57461;59837.11058781252;60167.487438442295;60248;60364;35048;35235.64764352375;35625.46561801575;36546.26907461889;62764.05853912195;16618.83381166197;16715.582110447336;16807.91596042012;16818;16820.15899205035;12497.687557810945;12635.494580216688;12735;12765.978721702242;12787.488500459878;19252.4224273272;19374.612507750047;19402.029898505018;19441.833489990364;19502.317418890554;7785.922140778633;7918.6436610352675;7936.127026027132;7951.284384405431;7997.840043199103;864.8443280209497;949.8052899155656;972.5526257921294;980.9950950245346;1007.2646967713464;30117.69882301149;30512.237194070327;30851.382972340427;31428.37143257122;31435.84282078575;1008.5915204342253;1052.3002203534718;1065.0574241796066;1106.6016234155697;1132.8640563132476;15387.512948488666;15983.200839957955;16037.358505659877;16122.838771612134;16202.513924582046;663158;668181;669905.300946994;670742;671457;59439.40560594425;59857.802843942874;60668.08997865036;60754.39245607576;60852.69573652192;1323.219300612643;1345.6501309659363;1403.7226124227109;1408.3380811018806;1409.1122592766696;29490.557641634965;30071.398572028873;30380.93666721654;30576;30580.388392232475;51483;51578;51870.74064629653;52001.7399912998;52008.73995629998;496604;497236;497275;497588;497910;22193.334199974026;26675.599866001638;26781.464370712867;27142.592861106703;27203.72796272012;46977.29534056924;47746.76126619345;48798.512014879394;49090.75454622704;49202.507974921224;35926.820365898006;39746.40380394354;39943;40644.98387540335;41442.792786035876;38247.42628860514;40038;40087;41205.175896481305;41261.58738412698;39212.80393598014;39352;40074.79962600168;40255.59744402636;41086.794566026976;31102;34775.47836782501;34900.65099348974;35447.64552354546;35638.109047274025;19433.902830485757;23692.881535592212;25960;26509.60235596506;26834.597481038192 02:11:52 INFO - PROCESS | 2047 | |2;jquery.html;335.39126485428943;367.98344074516626;377.23421454447464;391.84130427177;396.90275882408815;18786.436406907796;20310.492237694045;21222.832744199062;21268.680969785466;21522;12043.698907527316;12278;16531.752023719622;20562.280320188795;21294;5504.119340905446;5602.579806514507;5671.461211184931;5675.51758100562;5676.687782171979;5452.318460192476;5557.72211389431;5575.776968921258;5578;5582.106862901936;5440.292761940962;5488.259085023531;5497.835064948038;5599.552035837123;5604.859878503029;5413.918791218135;5424.864378390533;5470.9452905471035;5484.149956756709;5518.503334699856;5184.974075129638;5273.419923808378;5336.1195402758885;5358.223057656618;5389.973050134763;24842.627360589606;25219.24342269735;25681;25720.871395643087;25807.35481612956;2119.501917049492;2135.95728085439;2152.2251989283845;2180.7819218078225;2365.053978408633;10223.335483193569;10355.948220258926;10442.425666588335;10514.474276286155;10589.470526473722;1734.9479515614553;1858.4517567317673;1874.8968806715625;1875.8201121316968;1885.7737071551364;131814;135890.999999999;136088;136314;138365;11483.598074067368;11602.303861768321;11708.946194842505;11932.284062956076;11941;14294.428222870969;14645.707085858223;14651.780223296659;14657.853421465863;14694.926525367304;8436.493810371398;8511.74464766058;8573.699920502753;8649.437786543855;8745.956270218609;8466.661333546712;8499.915000849913;8606.526641034678;8752.8687069694;8783.077776833341;4476.306172543261;4513.119941611354;4603.102395032936;4617.769111544477;4730.172219861579;1711.9471525012027;1920.5582715975404;1969.3304276546044;1995.9501012474805;1997.4007797660643;747.5813544415145;875.3478658399406;897.2328658996464;950.1733491861986;998.5656239535825;19219;21162.894185529283;21279.574408511744;21479.892600536896;21557.02993365271;931.5761328595473;934.6915517879083;950.4154944708954;1017.8625885505362;1058.9258751887294;10655.627053053106;11286.774264514828;11297.71755706114;11309.71725706864;11373.147013973985;588396;612277;620451;634464;635484;131544;133515;136558;136949;137592;960.2174228004154;998.6454808543008;1014.9238807089498;1018.7656838927088;1019.37308555238;16282;18379;18970.52573685669;19265.614687705885;19321.323753668556;43596;58923;59420.40579594322;60020;61885;83167;84235.89410264793;85072;86625;87648.12351876654;8003.83992320162;8049;8077.5961201940845;8211.794705132419;8276.337892968668;10902;11287.85530749155;12658.493660253487;12948.417321220379;13092.083554151119;10831.025207731342;10842.241043126905;11193.048590869867;11206.943965280447;11233.157513186381;9814;10211.7447063824;10960.890391095987;11261.49323280438;11397.259178153394;10987.505562249558;11375.374354410587;11414.08687304997;11434.970852623304;11498.712532186762;7104.715811367694;7352.264773522605;7520.736774212876;7962.761117166494;8008.399370047153;7112.608806515691;8541.743747687578;11276.60531881413;11606.651800446;11806.940965295118 02:11:52 INFO - PROCESS | 2047 | |3;mootools.html;1511.848815118488;1581.193591268453;1726.9136543172838;1801.3425099838546;1825.3520000399858;7947.284744373005;8337.83324333513;8520.446170998881;8855.291576673875;8884.11158884112;8605.526696031715;8635.95682021589;8723.302135829133;8741.16958888906;8756.03683594804;2347.80043696286;2393.509330587234;2397.568437681217;2398.388410955204;2403.399150212447;2358.7995020423286;2384.5707772600927;2407.711074671042;2407.9518409631796;2418.51629674065;2339.578875802355;2386.2364043506086;2397.4006498375406;2407.6749638798715;2410.6384042393606;2361.8819059046978;2369.786719195272;2378.001239479423;2381.5356005578833;2387.6299173628117;2327.964055995083;2330.102910379499;2332.9066837326486;2339.5671800716923;2347.7065366829147;8792.560371981439;8846.42498237619;8856.202941735275;8859.335549833788;8863.734087977307;5164.6642968207325;5371.59713021521;5393.946060539383;5413.02565538203;5417.133258678593;3415.487676848468;3496;3631.0377749896033;3679.7056235501095;3704.5739739929713;1083.8049151152788;1084.8481212630238;1088.395940253159;1090.574675876409;1094.9945250273777;11350.886491135148;11526.538938442452;11655.892690194503;11683.941580292045;11691.47388367517;3806.2006978534264;3984.940225896614;4552.362669226311;4752.3584316116885;4798.184308667535;9491.193248573949;9797.363171393836;9878.259130565237;9900;9907.306488545733;5268.394134674561;5312.990531798946;5316.813911513156;5321.627486076015;5337.653052551572;4392.297232442794;4726.480087190406;4876.317315575822;4896.755162241873;4901.240307752304;3432.7940323580665;3442.0362298556443;3852.4028775539837;4039.2325458162854;4039.9596004040172;1285.762134005212;1291.8837304642627;1297.2864924291603;1304.3217526885967;1305.8258000429369;737.848741008092;851;891.4250308551078;911.2709832134266;937.6202637931649;5430.782768689287;5478.506934375925;5670.716464176775;5707.286589176353;5783.421657834183;757.7007082202499;912.981740365189;939.3603972836352;944.9433034017981;981.5828272983953;3628.818559072036;3762.078290818767;3765.8681946131755;3785.9432108518395;3796.468494410785;10286.845697314546;10734.087602553871;10739.892601074045;10741.570337186426;10765.13878889687;10497.685069447776;10651.254412191227;10712.196585256137;10714;10761.677149685522;7924.286814186751;7946.165652606509;7967.2431119043595;8251.669933202606;8251.793705157419;5442.292501974718;5544.528715059265;5556.138798486241;5636.239107720569;5642.802501912578;9724.076212759777;9925.35485193489;9950.45272510019;10072.84890726625;10091.798164036825;9905.752356191153;10121.481777733105;10294.742631434274;10294.794104117726;10310;6540.051692504465;6888.862222755617;7038.612876291853;7065.505414620927;7152.785416437515;8382.83234335322;8436.873446898424;8508.74473765788;8528.829423411373;8536.829263414822;7304.233055529306;7460.216677249028;7614.048243969504;7666.310032097138;7723.266289702469;7280.67236974348;7378.077740282465;7403.333699967029;7496.512726672749;7524.5861477619255;6968.059311993019;7000.719971201299;7072.858542829012;7087.61018144007;7094.858102838018;4477.283634618446;4822.132016237111;4855.150348688895;4874.390701162355;4920.532549407801;4490.3713480113465;4574.5425457453985;4623.375844261116;4736.9052618946735;5052.59579233653 02:11:52 INFO - PROCESS | 2047 | |4;prototype.html;296.48559748835766;296.68106785205896;305.93728285701434;312.8998720409469;314.86460821846623;5393.703346315951;5478.301726464799;5607.4112218217115;5641.661500309979;5653.915191272129;5491.835244942648;5562.833115006546;5568.1647752837;5569.0229968361255;5577.414371490996;4274.679399045076;4293;4298.613124818766;4303.763293018898;4315.82736690532;3529.1530032792202;3791.279656865187;4235.475228917602;4253.2769429197115;4325.653947684178;3859.9421008684894;3888.6889048876023;4220.704550681454;4220.957790422087;4225.323948168294;3524.665156810112;3697.963020369789;3996.86010989614;4075.816588253536;4252.064545799919;3676.1361080146144;3680.4663323817895;3688.963110368889;3703.666669999686;3715.8327875245677;5189.455107213726;5293.417724050351;5413.783448662049;5417.458254174551;5484.2596249506205;3797.905052373685;4077.3883917412327;4715.31627913954;4837.564619184291;4859.635527335428;5197.750272225042;5201.609879259071;5637.492625663669;5672.68800215988;5966.2840459144745;3656.981715091434;4022.235775202702;4022.3162062449455;4025.75845449274;4040.333344998072;6286.308506064329;6292.5595208335435;6299.3936546180885;6303.936960630427;6305.77037477692;3930.5283365996265;4067.8169482373364;4093.529244136903;4095.5699651536756;4117.135401565704;3393.7285017198565;3709.350863598859;3906.980465097656;4101.425800387948;4141.834326626961;4868.829590964299;4872.4640289568115;4885.509919474594;4894.779734911866;4900.191468407673;5849.13827024476;5981.401859814071;5998.070299103646;5998.610090344114;5999.400059993965;1365.7405093032291;1394.5816255123625;1414.1939094715913;1422.3954819201797;1427.100926416351;750.951188172767;765.9234076592408;779.1118125337007;840.7351684219451;848.9769827358019;4253.8723838284905;4317.7478531226225;4394.264265615064;4504.144212599595;4546.977265113654;4676.251799712098;4887.020756593585;5069.645124841226;5107.8723031923755;5154.92267615986;4250.364773840239;4352.521222665504;4575.679702420865;4620.283856002325;4653.883652908705;2258.5934531784437;2544.3511904464613;2678.9552074690887;2682.25069303965;2695.2857492764433;4286.871393858127;4793.041391721669;5251.579873610102;5292.761825717852;5320.946790532123;4189.099343641132;4511.684182107287;4876.975615121902;5076.923846142311;5158.97420512895;5716.57125715573;6400.263969643457;6413.6793160341795;6420.133282006869;6445.45213656835;6186.969065154825;6204.937950620436;6209.9817559292505;6236.968815155895;6271.310155882758;5765.769369225184;5966.880662386815;5988.341282459013;6051.394860513913;6099.8825792590715;5566.805161819317;6199.404345479701;6251.343608920998;6347.714352854225;6379.904301435575;3825.6365645263654;3912.3544615138185;3975.2447035063246;4030.9519524923144;4030.972102113942;6004.309504406874;6042.851858146938;6093.786717464867;6101.481374083075;6118.286879673698;4898.8775280618265;4939.085939295833;5880.617759845596;5898.203742494708;5922.230110085661;4666.739980205393;4679.298105284234;5500.009998200362;5870.295564532113;5877.911831322448;4608.009278005245;4622.6070783982395;5707.429257074259;5739.110437882135;5786.479216870502;4860.371775455262;5628.549716022627;5866.970665146818;5874.236349274567;5883.940620782691;4851.757412129309;4890.510948905223;5789.363170051375;5864.618799778001;5871.412858714094 02:11:52 INFO - PROCESS | 2047 | |5;yui.html;695.5339922252091;712.159651611099;719.2879049740754;741.7737590035038;776.2977027521484;15486.845131548695;15935.681286374294;16291.266892989814;16320.591985200375;16337.509874703748;13996.300184990761;14773.778393324083;14788.6302842429;14819.55541333759;14925.77611335828;680.5372346804171;847.6609356257485;849.9405041647103;876.9079246679103;876.9736907892774;861;877.4954401219325;879.3712495565669;880.3837313880277;881.8721285413611;896.7040876510736;927.7680579855037;928.6656803550717;928.7538802217417;933.6592143867507;912.2337236721153;921.1525396635112;925.9722208333761;926.5737760630118;936.7704912296463;628.6605233174081;647.9384458476437;666.0575285970347;675.9695813688396;690.5062880040745;13836.723265534632;14487.78268325976;15347.923260383739;15400;15419.22903854814;11798.46906889192;12221.633350999484;12907;15026.549203523802;15270.236488175547;10365.896341036569;11676.007539359165;11831.881681183164;11901;12188.81716774249;4542.38677778499;4572.748498832563;4575.450945886481;4626.560476754703;4857.222844344923;114306;116073;116271;117270.41364793207;118490.40754796258;10988.780224395467;11074.058705010004;11098.223124381217;11197.16021298406;11245.325280483197;21282.78717212808;21283;21317.36047918565;21318.57362852734;21347.14611415557;7749.535027898344;7888.40836937232;7916;8147.9185208147155;8165;4415.779211039435;4476.9104617908115;4484.282514797616;4544.091181763661;4631.8842028948875;3451.9654803452145;3571.1250743567994;3577.624349443323;3586.4261718124976;3919.47087143232;692.8302565871394;700.0479348086598;707.6673963237373;718.511412239682;718.529363267061;594.3966873623276;656.2551504042946;666.2904007232282;693.6809067828755;711.8825393809964;9167.587458564381;9640.276979226446;9722.659706910224;9919.950400247953;10058.59765609382;547.7097138516513;608.0423333250149;645.6836150286326;694;704.2077662629541;4433.113377324548;4434.357018232403;4692.765361731968;4701.553352431513;4799;96031;96483;97133.0286697138;163453;163999;82173;127394;140905.7275417964;143576.28211858874;148675;71013.64493177501;72025.6398718003;101344.99999999853;107979.46010270056;114631.42684286692;8980.86528702083;9012.684556040507;9040.290196898071;9041.457512549268;9135.451872887648;52290.738546307024;60262.49343766441;66564;66593.33406665872;67102.94764628276;94520;96049.5197524008;97225;98729.50635246778;100327;11068;11981;12340.580833204296;12510.687232819253;12558.246505209716;10963;11457.885421145682;13621;13631.86368136306;13681;12494.250344979331;12755.234685918875;13163.868361316265;13168.209907405586;13177.143485673401;12173.634790956285;12635.3682315882;12670.809937850756;12676.936615316865;13076.346182691017;12393.302203848885;12449.813252801396;12499.875001250235;12905.677358065748;13046.608601741962;9600.32797704154;10470.743510778755;10554.261201715806;10719.303245289031;10852.45737713127;7309.890351644836;8266.1009183449;9917.603295868084;10035.749106272402;10959.342439453658 02:11:52 INFO - PROCESS | 2047 | __end_tp_report 02:11:52 INFO - PROCESS | 2047 | __start_cc_report 02:11:52 INFO - PROCESS | 2047 | _x_x_mozilla_cycle_collect,416 02:11:52 INFO - PROCESS | 2047 | __end_cc_report 02:11:52 INFO - PROCESS | 2047 | __startTimestamp1470561112423__endTimestamp 02:11:52 INFO - PROCESS | 2047 | 02:11:52 INFO - PROCESS | 2047 | ------- Summary: start ------- 02:11:52 INFO - PROCESS | 2047 | Number of tests: 6 02:11:52 INFO - PROCESS | 2047 | 02:11:52 INFO - PROCESS | 2047 | [#0] dojo.html Cycles:165 Average:5750.68 Median:5945.70 stddev:689.42 (11.6%) stddev-sans-first:655.40 02:11:52 INFO - PROCESS | 2047 | Values: 2942.9 2956.9 3338.6 3423.2 3498.9 4621.6 5657.9 5732.3 5781.0 5849.9 5675.8 5753.2 5795.8 5799.8 5809.5 5167.1 5264.5 5279.8 5349.4 5351.8 5318.4 5346.1 5359.2 5376.2 5401.4 5152.7 5210.3 5274.5 5286.1 5372.0 4178.5 5297.5 5316.3 5329.4 5352.3 5118.3 5221.5 5221.7 5233.2 5302.5 5555.7 5832.9 5833.0 5833.4 5941.0 6217.3 6253.9 6258.0 6298.9 6308.5 6199.8 6279.0 6308.2 6312.3 6325.1 5825.0 5846.4 5889.3 5907.1 5970.6 6324.3 6369.7 6383.2 6386.8 6397.7 6144.2 6146.2 6153.8 6219.5 6220.3 6194.7 6214.5 6252.7 6288.1 6309.6 6237.2 6272.2 6277.3 6282.5 6301.5 5016.9 6300.8 6304.9 6315.7 6317.4 4911.5 4952.6 4994.4 4996.6 5047.3 4040.9 4420.3 4433.1 4452.1 4460.9 5649.0 5890.7 5896.6 5905.8 5916.3 6173.8 6183.4 6207.8 6236.3 6269.8 5854.6 5861.1 5870.2 5871.5 5881.1 5445.0 5461.4 5471.6 5477.9 5495.8 5164.7 5419.7 6381.2 6502.7 6505.2 5298.0 6298.0 6298.9 6301.7 6315.7 6292.7 6361.7 6509.0 6514.8 6530.6 5716.0 6177.1 6221.0 6251.2 6307.9 5827.7 5945.7 6211.6 6255.3 6269.5 4926.9 6097.6 6195.2 6224.4 6235.5 6101.0 6142.9 6143.8 6148.0 6165.1 4931.4 5975.3 6019.1 6027.1 6081.0 6051.9 6117.5 6163.0 6171.9 6188.1 6081.1 6124.8 6126.9 6129.5 6140.4 02:11:52 INFO - PROCESS | 2047 | 02:11:52 INFO - PROCESS | 2047 | [#1] ext.html Cycles:180 Average:68930.95 Median:40373.20 stddev:150164.44 (371.9%) stddev-sans-first:150497.77 02:11:52 INFO - PROCESS | 2047 | Values: 492.8 524.1 542.0 545.7 557.6 37844.6 40490.8 40604.2 40720.4 40766.0 41629.8 42152.9 42172.9 42237.4 42337.8 9548.8 9553.4 9619.3 9638.6 9872.9 9567.9 9701.8 9720.3 9726.6 9766.7 9582.7 9711.9 9740.0 9741.4 9767.3 9466.0 9576.4 9690.8 9700.5 9728.3 9481.1 9611.0 9617.2 9635.2 9697.0 39540.0 39825.8 40001.8 40032.8 40204.0 2942.4 3105.5 3361.3 3366.4 3400.6 31768.0 32041.8 32079.8 32320.0 32993.0 2899.9 3205.4 3212.3 3241.5 3359.0 492965.0 503400.0 505392.0 506192.0 507164.0 57461.0 59837.1 60167.5 60248.0 60364.0 35048.0 35235.6 35625.5 36546.3 62764.1 16618.8 16715.6 16807.9 16818.0 16820.2 12497.7 12635.5 12735.0 12766.0 12787.5 19252.4 19374.6 19402.0 19441.8 19502.3 7785.9 7918.6 7936.1 7951.3 7997.8 864.8 949.8 972.6 981.0 1007.3 30117.7 30512.2 30851.4 31428.4 31435.8 1008.6 1052.3 1065.1 1106.6 1132.9 15387.5 15983.2 16037.4 16122.8 16202.5 663158.0 668181.0 669905.3 670742.0 671457.0 59439.4 59857.8 60668.1 60754.4 60852.7 1323.2 1345.7 1403.7 1408.3 1409.1 29490.6 30071.4 30380.9 30576.0 30580.4 51483.0 51578.0 51870.7 52001.7 52008.7 496604.0 497236.0 497275.0 497588.0 497910.0 22193.3 26675.6 26781.5 27142.6 27203.7 46977.3 47746.8 48798.5 49090.8 49202.5 35926.8 39746.4 39943.0 40645.0 41442.8 38247.4 40038.0 40087.0 41205.2 41261.6 39212.8 39352.0 40074.8 40255.6 41086.8 31102.0 34775.5 34900.7 35447.6 35638.1 19433.9 23692.9 25960.0 26509.6 26834.6 02:11:52 INFO - PROCESS | 2047 | 02:11:52 INFO - PROCESS | 2047 | [#2] jquery.html Cycles:180 Average:36409.88 Median:2272.92 stddev:103814.87 (4567.5%) stddev-sans-first:104070.76 02:11:52 INFO - PROCESS | 2047 | Values: 335.4 368.0 377.2 391.8 396.9 18786.4 20310.5 21222.8 21268.7 21522.0 12043.7 12278.0 16531.8 20562.3 21294.0 5504.1 5602.6 5671.5 5675.5 5676.7 5452.3 5557.7 5575.8 5578.0 5582.1 5440.3 5488.3 5497.8 5599.6 5604.9 5413.9 5424.9 5470.9 5484.1 5518.5 5185.0 5273.4 5336.1 5358.2 5390.0 24842.6 25219.2 25681.0 25720.9 25807.4 2119.5 2136.0 2152.2 2180.8 2365.1 10223.3 10355.9 10442.4 10514.5 10589.5 1734.9 1858.5 1874.9 1875.8 1885.8 131814.0 135891.0 136088.0 136314.0 138365.0 11483.6 11602.3 11708.9 11932.3 11941.0 14294.4 14645.7 14651.8 14657.9 14694.9 8436.5 8511.7 8573.7 8649.4 8746.0 8466.7 8499.9 8606.5 8752.9 8783.1 4476.3 4513.1 4603.1 4617.8 4730.2 1711.9 1920.6 1969.3 1996.0 1997.4 747.6 875.3 897.2 950.2 998.6 19219.0 21162.9 21279.6 21479.9 21557.0 931.6 934.7 950.4 1017.9 1058.9 10655.6 11286.8 11297.7 11309.7 11373.1 588396.0 612277.0 620451.0 634464.0 635484.0 131544.0 133515.0 136558.0 136949.0 137592.0 960.2 998.6 1014.9 1018.8 1019.4 16282.0 18379.0 18970.5 19265.6 19321.3 43596.0 58923.0 59420.4 60020.0 61885.0 83167.0 84235.9 85072.0 86625.0 87648.1 8003.8 8049.0 8077.6 8211.8 8276.3 10902.0 11287.9 12658.5 12948.4 13092.1 10831.0 10842.2 11193.0 11206.9 11233.2 9814.0 10211.7 10960.9 11261.5 11397.3 10987.5 11375.4 11414.1 11435.0 11498.7 7104.7 7352.3 7520.7 7962.8 8008.4 7112.6 8541.7 11276.6 11606.7 11806.9 02:11:52 INFO - PROCESS | 2047 | 02:11:52 INFO - PROCESS | 2047 | [#3] mootools.html Cycles:180 Average:5657.52 Median:4875.35 stddev:3190.33 (65.4%) stddev-sans-first:3184.07 02:11:52 INFO - PROCESS | 2047 | Values: 1511.8 1581.2 1726.9 1801.3 1825.4 7947.3 8337.8 8520.4 8855.3 8884.1 8605.5 8636.0 8723.3 8741.2 8756.0 2347.8 2393.5 2397.6 2398.4 2403.4 2358.8 2384.6 2407.7 2408.0 2418.5 2339.6 2386.2 2397.4 2407.7 2410.6 2361.9 2369.8 2378.0 2381.5 2387.6 2328.0 2330.1 2332.9 2339.6 2347.7 8792.6 8846.4 8856.2 8859.3 8863.7 5164.7 5371.6 5393.9 5413.0 5417.1 3415.5 3496.0 3631.0 3679.7 3704.6 1083.8 1084.8 1088.4 1090.6 1095.0 11350.9 11526.5 11655.9 11683.9 11691.5 3806.2 3984.9 4552.4 4752.4 4798.2 9491.2 9797.4 9878.3 9900.0 9907.3 5268.4 5313.0 5316.8 5321.6 5337.7 4392.3 4726.5 4876.3 4896.8 4901.2 3432.8 3442.0 3852.4 4039.2 4040.0 1285.8 1291.9 1297.3 1304.3 1305.8 737.8 851.0 891.4 911.3 937.6 5430.8 5478.5 5670.7 5707.3 5783.4 757.7 913.0 939.4 944.9 981.6 3628.8 3762.1 3765.9 3785.9 3796.5 10286.8 10734.1 10739.9 10741.6 10765.1 10497.7 10651.3 10712.2 10714.0 10761.7 7924.3 7946.2 7967.2 8251.7 8251.8 5442.3 5544.5 5556.1 5636.2 5642.8 9724.1 9925.4 9950.5 10072.8 10091.8 9905.8 10121.5 10294.7 10294.8 10310.0 6540.1 6888.9 7038.6 7065.5 7152.8 8382.8 8436.9 8508.7 8528.8 8536.8 7304.2 7460.2 7614.0 7666.3 7723.3 7280.7 7378.1 7403.3 7496.5 7524.6 6968.1 7000.7 7072.9 7087.6 7094.9 4477.3 4822.1 4855.2 4874.4 4920.5 4490.4 4574.5 4623.4 4736.9 5052.6 02:11:52 INFO - PROCESS | 2047 | 02:11:52 INFO - PROCESS | 2047 | [#4] prototype.html Cycles:180 Average:4612.09 Median:4888.77 stddev:1469.34 (30.1%) stddev-sans-first:1437.32 02:11:52 INFO - PROCESS | 2047 | Values: 296.5 296.7 305.9 312.9 314.9 5393.7 5478.3 5607.4 5641.7 5653.9 5491.8 5562.8 5568.2 5569.0 5577.4 4274.7 4293.0 4298.6 4303.8 4315.8 3529.2 3791.3 4235.5 4253.3 4325.7 3859.9 3888.7 4220.7 4221.0 4225.3 3524.7 3698.0 3996.9 4075.8 4252.1 3676.1 3680.5 3689.0 3703.7 3715.8 5189.5 5293.4 5413.8 5417.5 5484.3 3797.9 4077.4 4715.3 4837.6 4859.6 5197.8 5201.6 5637.5 5672.7 5966.3 3657.0 4022.2 4022.3 4025.8 4040.3 6286.3 6292.6 6299.4 6303.9 6305.8 3930.5 4067.8 4093.5 4095.6 4117.1 3393.7 3709.4 3907.0 4101.4 4141.8 4868.8 4872.5 4885.5 4894.8 4900.2 5849.1 5981.4 5998.1 5998.6 5999.4 1365.7 1394.6 1414.2 1422.4 1427.1 751.0 765.9 779.1 840.7 849.0 4253.9 4317.7 4394.3 4504.1 4547.0 4676.3 4887.0 5069.6 5107.9 5154.9 4250.4 4352.5 4575.7 4620.3 4653.9 2258.6 2544.4 2679.0 2682.3 2695.3 4286.9 4793.0 5251.6 5292.8 5320.9 4189.1 4511.7 4877.0 5076.9 5159.0 5716.6 6400.3 6413.7 6420.1 6445.5 6187.0 6204.9 6210.0 6237.0 6271.3 5765.8 5966.9 5988.3 6051.4 6099.9 5566.8 6199.4 6251.3 6347.7 6379.9 3825.6 3912.4 3975.2 4031.0 4031.0 6004.3 6042.9 6093.8 6101.5 6118.3 4898.9 4939.1 5880.6 5898.2 5922.2 4666.7 4679.3 5500.0 5870.3 5877.9 4608.0 4622.6 5707.4 5739.1 5786.5 4860.4 5628.5 5867.0 5874.2 5883.9 4851.8 4890.5 5789.4 5864.6 5871.4 02:11:52 INFO - PROCESS | 2047 | 02:11:52 INFO - PROCESS | 2047 | [#5] yui.html Cycles:180 Average:23852.41 Median:4480.60 stddev:38080.22 (849.9%) stddev-sans-first:38147.35 02:11:52 INFO - PROCESS | 2047 | Values: 695.5 712.2 719.3 741.8 776.3 15486.8 15935.7 16291.3 16320.6 16337.5 13996.3 14773.8 14788.6 14819.6 14925.8 680.5 847.7 849.9 876.9 877.0 861.0 877.5 879.4 880.4 881.9 896.7 927.8 928.7 928.8 933.7 912.2 921.2 926.0 926.6 936.8 628.7 647.9 666.1 676.0 690.5 13836.7 14487.8 15347.9 15400.0 15419.2 11798.5 12221.6 12907.0 15026.5 15270.2 10365.9 11676.0 11831.9 11901.0 12188.8 4542.4 4572.7 4575.5 4626.6 4857.2 114306.0 116073.0 116271.0 117270.4 118490.4 10988.8 11074.1 11098.2 11197.2 11245.3 21282.8 21283.0 21317.4 21318.6 21347.1 7749.5 7888.4 7916.0 8147.9 8165.0 4415.8 4476.9 4484.3 4544.1 4631.9 3452.0 3571.1 3577.6 3586.4 3919.5 692.8 700.0 707.7 718.5 718.5 594.4 656.3 666.3 693.7 711.9 9167.6 9640.3 9722.7 9920.0 10058.6 547.7 608.0 645.7 694.0 704.2 4433.1 4434.4 4692.8 4701.6 4799.0 96031.0 96483.0 97133.0 163453.0 163999.0 82173.0 127394.0 140905.7 143576.3 148675.0 71013.6 72025.6 101345.0 107979.5 114631.4 8980.9 9012.7 9040.3 9041.5 9135.5 52290.7 60262.5 66564.0 66593.3 67102.9 94520.0 96049.5 97225.0 98729.5 100327.0 11068.0 11981.0 12340.6 12510.7 12558.2 10963.0 11457.9 13621.0 13631.9 13681.0 12494.3 12755.2 13163.9 13168.2 13177.1 12173.6 12635.4 12670.8 12676.9 13076.3 12393.3 12449.8 12499.9 12905.7 13046.6 9600.3 10470.7 10554.3 10719.3 10852.5 7309.9 8266.1 9917.6 10035.7 10959.3 02:11:52 INFO - PROCESS | 2047 | -------- Summary: end -------- 02:11:52 INFO - PROCESS | 2047 | 02:11:53 INFO - TEST-INFO | 2047: exit 0 02:11:53 INFO - TEST-OK | dromaeo_css | took 1113714ms 02:11:53 INFO - TEST-START | kraken 02:11:53 INFO - operating with platform_type : mac_ 02:11:53 INFO - Initialising browser for kraken test... 02:11:53 INFO - TEST-INFO | started process 2051 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/tmpOT_O3e/profile http://localhost:51313/getInfo.html) 02:11:55 INFO - PROCESS | 2051 | __metrics Screen width/height:1600/1200 02:11:55 INFO - PROCESS | 2051 | colorDepth:24 02:11:55 INFO - PROCESS | 2051 | Browser inner width/height: 1024/689 02:11:55 INFO - PROCESS | 2051 | __metrics 02:11:55 ERROR - PROCESS | 2051 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 02:11:56 INFO - TEST-INFO | 2051: exit 0 02:11:56 INFO - Browser initialized. 02:11:56 INFO - Running cycle 1/1 for kraken test... 02:11:56 INFO - TEST-INFO | started process 2053 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/tmpOT_O3e/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:12:08 INFO - PROCESS | 2053 | RSS: Main: 254865408 02:12:08 INFO - PROCESS | 2053 | 02:12:11 INFO - PROCESS | 2053 | Cycle 1(1): loaded http://localhost:51313/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51313/tests/kraken/driver.html?testName=audio-beat-detection) 02:12:12 INFO - PROCESS | 2053 | RSS: Main: 267649024 02:12:12 INFO - PROCESS | 2053 | 02:12:14 INFO - PROCESS | 2053 | Cycle 1(1): loaded http://localhost:51313/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51313/tests/kraken/driver.html?testName=audio-dft) 02:12:15 INFO - PROCESS | 2053 | RSS: Main: 276402176 02:12:15 INFO - PROCESS | 2053 | 02:12:18 INFO - PROCESS | 2053 | Cycle 1(1): loaded http://localhost:51313/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51313/tests/kraken/driver.html?testName=audio-fft) 02:12:19 INFO - PROCESS | 2053 | RSS: Main: 279056384 02:12:19 INFO - PROCESS | 2053 | 02:12:21 INFO - PROCESS | 2053 | Cycle 1(1): loaded http://localhost:51313/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51313/tests/kraken/driver.html?testName=audio-oscillator) 02:12:22 INFO - PROCESS | 2053 | RSS: Main: 280522752 02:12:22 INFO - PROCESS | 2053 | 02:12:24 INFO - PROCESS | 2053 | Cycle 1(1): loaded http://localhost:51313/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51313/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:12:24 INFO - PROCESS | 2053 | RSS: Main: 280866816 02:12:24 INFO - PROCESS | 2053 | 02:12:27 INFO - PROCESS | 2053 | Cycle 1(1): loaded http://localhost:51313/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51313/tests/kraken/driver.html?testName=imaging-darkroom) 02:12:28 INFO - PROCESS | 2053 | RSS: Main: 280969216 02:12:28 INFO - PROCESS | 2053 | 02:12:31 INFO - PROCESS | 2053 | Cycle 1(1): loaded http://localhost:51313/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51313/tests/kraken/driver.html?testName=imaging-desaturate) 02:12:32 INFO - PROCESS | 2053 | RSS: Main: 280109056 02:12:32 INFO - PROCESS | 2053 | 02:12:34 INFO - PROCESS | 2053 | Cycle 1(1): loaded http://localhost:51313/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51313/tests/kraken/driver.html?testName=json-parse-financial) 02:12:35 INFO - PROCESS | 2053 | RSS: Main: 282509312 02:12:35 INFO - PROCESS | 2053 | 02:12:37 INFO - PROCESS | 2053 | Cycle 1(1): loaded http://localhost:51313/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51313/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:12:37 INFO - PROCESS | 2053 | RSS: Main: 282304512 02:12:37 INFO - PROCESS | 2053 | 02:12:39 INFO - PROCESS | 2053 | Cycle 1(1): loaded http://localhost:51313/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51313/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:12:40 INFO - PROCESS | 2053 | RSS: Main: 282796032 02:12:40 INFO - PROCESS | 2053 | 02:12:42 INFO - PROCESS | 2053 | Cycle 1(1): loaded http://localhost:51313/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51313/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:12:43 INFO - PROCESS | 2053 | RSS: Main: 282722304 02:12:43 INFO - PROCESS | 2053 | 02:12:46 INFO - PROCESS | 2053 | Cycle 1(1): loaded http://localhost:51313/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51313/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:12:46 INFO - PROCESS | 2053 | RSS: Main: 281853952 02:12:46 INFO - PROCESS | 2053 | 02:12:49 INFO - PROCESS | 2053 | Cycle 1(1): loaded http://localhost:51313/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51313/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:12:50 INFO - PROCESS | 2053 | RSS: Main: 282894336 02:12:50 INFO - PROCESS | 2053 | 02:12:52 INFO - PROCESS | 2053 | Cycle 1(1): loaded http://localhost:51313/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51313/tests/kraken/driver.html?testName=ai-astar) 02:12:52 INFO - PROCESS | 2053 | RSS: Main: 283938816 02:12:52 INFO - PROCESS | 2053 | 02:12:52 INFO - PROCESS | 2053 | __start_tp_report 02:12:52 INFO - PROCESS | 2053 | _x_x_mozilla_page_load 02:12:52 INFO - PROCESS | 2053 | _x_x_mozilla_page_load_details 02:12:52 INFO - PROCESS | 2053 | |i|pagename|runs| 02:12:52 INFO - PROCESS | 2053 | |0;ai-astar;106;116;107;118;116;114;114;118;116;116 02:12:52 INFO - PROCESS | 2053 | |1;audio-beat-detection;146;109;131;158;109;131;128;108;158;112 02:12:52 INFO - PROCESS | 2053 | |2;audio-dft;201;204;208;236;251;196;198;199;202;203 02:12:52 INFO - PROCESS | 2053 | |3;audio-fft;68;83;76;144;59;59;59;63;133;60 02:12:52 INFO - PROCESS | 2053 | |4;audio-oscillator;71;76;139;102;109;92;119;70;72;119 02:12:52 INFO - PROCESS | 2053 | |5;imaging-gaussian-blur;94;87;123;86;89;89;91;75;77;95 02:12:52 INFO - PROCESS | 2053 | |6;imaging-darkroom;114;147;116;97;96;99;97;93;145;96 02:12:52 INFO - PROCESS | 2053 | |7;imaging-desaturate;89;99;98;93;94;90;90;94;92;135 02:12:52 INFO - PROCESS | 2053 | |8;json-parse-financial;55;56;56;55;56;56;60;70;73;56 02:12:52 INFO - PROCESS | 2053 | |9;json-stringify-tinderbox;69;71;92;67;74;108;105;94;74;71 02:12:52 INFO - PROCESS | 2053 | |10;stanford-crypto-aes;102;103;102;84;82;90;88;95;104;82 02:12:52 INFO - PROCESS | 2053 | |11;stanford-crypto-ccm;143;161;154;150;157;144;139;136;135;141 02:12:52 INFO - PROCESS | 2053 | |12;stanford-crypto-pbkdf2;166;173;167;171;169;167;169;172;227;210 02:12:52 INFO - PROCESS | 2053 | |13;stanford-crypto-sha256-iterative;70;67;70;66;72;65;65;67;68;67 02:12:52 INFO - PROCESS | 2053 | __end_tp_report 02:12:52 INFO - PROCESS | 2053 | __start_cc_report 02:12:52 INFO - PROCESS | 2053 | _x_x_mozilla_cycle_collect,1057 02:12:52 INFO - PROCESS | 2053 | __end_cc_report 02:12:52 INFO - PROCESS | 2053 | __startTimestamp1470561172517__endTimestamp 02:12:52 INFO - PROCESS | 2053 | 02:12:52 INFO - PROCESS | 2053 | ------- Summary: start ------- 02:12:52 INFO - PROCESS | 2053 | Number of tests: 14 02:12:52 INFO - PROCESS | 2053 | 02:12:52 INFO - PROCESS | 2053 | [#0] ai-astar Cycles:10 Average:114.10 Median:116.00 stddev:4.23 (3.6%) stddev-sans-first:3.32 02:12:52 INFO - PROCESS | 2053 | Values: 106.0 116.0 107.0 118.0 116.0 114.0 114.0 118.0 116.0 116.0 02:12:52 INFO - PROCESS | 2053 | 02:12:52 INFO - PROCESS | 2053 | [#1] audio-beat-detection Cycles:10 Average:129.00 Median:131.00 stddev:19.75 (15.1%) stddev-sans-first:19.97 02:12:52 INFO - PROCESS | 2053 | Values: 146.0 109.0 131.0 158.0 109.0 131.0 128.0 108.0 158.0 112.0 02:12:52 INFO - PROCESS | 2053 | 02:12:52 INFO - PROCESS | 2053 | [#2] audio-dft Cycles:10 Average:209.80 Median:203.50 stddev:18.41 (9.0%) stddev-sans-first:19.25 02:12:52 INFO - PROCESS | 2053 | Values: 201.0 204.0 208.0 236.0 251.0 196.0 198.0 199.0 202.0 203.0 02:12:52 INFO - PROCESS | 2053 | 02:12:52 INFO - PROCESS | 2053 | [#3] audio-fft Cycles:10 Average:80.40 Median:61.50 stddev:31.77 (51.7%) stddev-sans-first:33.38 02:12:52 INFO - PROCESS | 2053 | Values: 68.0 83.0 76.0 144.0 59.0 59.0 59.0 63.0 133.0 60.0 02:12:52 INFO - PROCESS | 2053 | 02:12:52 INFO - PROCESS | 2053 | [#4] audio-oscillator Cycles:10 Average:96.90 Median:70.50 stddev:24.49 (34.7%) stddev-sans-first:24.11 02:12:52 INFO - PROCESS | 2053 | Values: 71.0 76.0 139.0 102.0 109.0 92.0 119.0 70.0 72.0 119.0 02:12:52 INFO - PROCESS | 2053 | 02:12:52 INFO - PROCESS | 2053 | [#5] imaging-gaussian-blur Cycles:10 Average:90.60 Median:89.00 stddev:13.12 (14.7%) stddev-sans-first:13.85 02:12:52 INFO - PROCESS | 2053 | Values: 94.0 87.0 123.0 86.0 89.0 89.0 91.0 75.0 77.0 95.0 02:12:52 INFO - PROCESS | 2053 | 02:12:52 INFO - PROCESS | 2053 | [#6] imaging-darkroom Cycles:10 Average:110.00 Median:96.00 stddev:20.51 (21.4%) stddev-sans-first:21.70 02:12:52 INFO - PROCESS | 2053 | Values: 114.0 147.0 116.0 97.0 96.0 99.0 97.0 93.0 145.0 96.0 02:12:52 INFO - PROCESS | 2053 | 02:12:52 INFO - PROCESS | 2053 | [#7] imaging-desaturate Cycles:10 Average:97.40 Median:93.50 stddev:13.62 (14.6%) stddev-sans-first:14.10 02:12:52 INFO - PROCESS | 2053 | Values: 89.0 99.0 98.0 93.0 94.0 90.0 90.0 94.0 92.0 135.0 02:12:52 INFO - PROCESS | 2053 | 02:12:52 INFO - PROCESS | 2053 | [#8] json-parse-financial Cycles:10 Average:59.30 Median:56.00 stddev:6.62 (11.8%) stddev-sans-first:6.83 02:12:52 INFO - PROCESS | 2053 | Values: 55.0 56.0 56.0 55.0 56.0 56.0 60.0 70.0 73.0 56.0 02:12:52 INFO - PROCESS | 2053 | 02:12:52 INFO - PROCESS | 2053 | [#9] json-stringify-tinderbox Cycles:10 Average:82.50 Median:72.50 stddev:15.67 (21.6%) stddev-sans-first:15.84 02:12:52 INFO - PROCESS | 2053 | Values: 69.0 71.0 92.0 67.0 74.0 108.0 105.0 94.0 74.0 71.0 02:12:52 INFO - PROCESS | 2053 | 02:12:52 INFO - PROCESS | 2053 | [#10] stanford-crypto-aes Cycles:10 Average:93.20 Median:83.00 stddev:9.09 (11.0%) stddev-sans-first:9.07 02:12:52 INFO - PROCESS | 2053 | Values: 102.0 103.0 102.0 84.0 82.0 90.0 88.0 95.0 104.0 82.0 02:12:52 INFO - PROCESS | 2053 | 02:12:52 INFO - PROCESS | 2053 | [#11] stanford-crypto-ccm Cycles:10 Average:146.00 Median:147.00 stddev:9.03 (6.1%) stddev-sans-first:9.51 02:12:52 INFO - PROCESS | 2053 | Values: 143.0 161.0 154.0 150.0 157.0 144.0 139.0 136.0 135.0 141.0 02:12:52 INFO - PROCESS | 2053 | 02:12:52 INFO - PROCESS | 2053 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.10 Median:171.50 stddev:21.27 (12.4%) stddev-sans-first:22.02 02:12:52 INFO - PROCESS | 2053 | Values: 166.0 173.0 167.0 171.0 169.0 167.0 169.0 172.0 227.0 210.0 02:12:52 INFO - PROCESS | 2053 | 02:12:52 INFO - PROCESS | 2053 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:67.50 stddev:2.31 (3.4%) stddev-sans-first:2.30 02:12:52 INFO - PROCESS | 2053 | Values: 70.0 67.0 70.0 66.0 72.0 65.0 65.0 67.0 68.0 67.0 02:12:52 INFO - PROCESS | 2053 | -------- Summary: end -------- 02:12:52 INFO - PROCESS | 2053 | 02:12:53 INFO - TEST-INFO | 2053: exit 0 02:12:53 INFO - TEST-OK | kraken | took 60463ms 02:12:53 INFO - SUITE-END | took 1174s 02:12:53 INFO - Completed test suite (00:19:34) 02:12:53 INFO - javascript benchmark 02:12:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7346.108356813105, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2942.8822847086117, 2956.8521573921307, 3338.64944180861, 3423.229773301009, 3498.9125271868206, 4621.584057434834, 5657.9434205657935, 5732.312122545301, 5780.971095144524, 5849.912251316228, 5675.772969081242, 5753.194552762617, 5795.82612521624, 5799.826005219839, 5809.506191973687, 5167.147420675584, 5264.473552644743, 5279.841604751864, 5349.411564727877, 5351.812686555972, 5318.414974352838, 5346.144616861303, 5359.16932875403, 5376.220448035033, 5401.351837779472, 5152.690838549699, 5210.322658054429, 5274.525292723635, 5286.074936886068, 5372.0, 4178.540360560366, 5297.47025297471, 5316.255724198615, 5329.440408757064, 5352.2506849041165, 5118.283440318359, 5221.503957124067, 5221.72067843377, 5233.21501774733, 5302.469753024705, 5555.694436805974, 5832.88334233313, 5832.9708351457975, 5833.445822646841, 5941.049432090888, 6217.2539295284405, 6253.937460625427, 6258.006216299377, 6298.874022519524, 6308.526860485436, 6199.752009919598, 6279.026750853625, 6308.211473565804, 6312.305646378894, 6325.114483972247, 5825.0, 5846.356900740915, 5889.293284805808, 5907.113932910098, 5970.641761494324, 6324.272708638566, 6369.745210191633, 6383.234011918599, 6386.776462823779, 6397.712102955378, 6144.201253837062, 6146.200993870768, 6153.784617538454, 6219.471344935731, 6220.284667263322, 6194.721237544321, 6214.471769899519, 6252.6873656317, 6288.119663247149, 6309.589876658002, 6237.1891654084675, 6272.18461599989, 6277.340879207709, 6282.4974002079725, 6301.527385446018, 5016.924746128811, 6300.810975670737, 6304.873902521924, 6315.715792789336, 6317.368263173737, 4911.45973942866, 4952.5542701157065, 4994.425641051325, 4996.575291100225, 5047.268146118866, 4040.939385909214, 4420.270655341897, 4433.069055498317, 4452.131834292282, 4460.8661740147845, 5649.0, 5890.676012819336, 5896.64620122794, 5905.822825315247, 5916.349201587822, 6173.81478555644, 6183.443490085914, 6207.751689932443, 6236.314005459396, 6269.811905642838, 5854.619449735754, 5861.120831875252, 5870.178175055496, 5871.530277577784, 5881.05903055509, 5444.972775136099, 5461.426550212171, 5471.5896307777075, 5477.890442191214, 5495.752691128909, 5164.7417629119145, 5419.701916394636, 6381.23425188971, 6502.707378168088, 6505.154329937267, 5298.0, 6297.96851015742, 6298.905516417166, 6301.653409062362, 6315.747370105144, 6292.653904035322, 6361.650109243851, 6508.967455162694, 6514.771982980573, 6530.608163510205, 5716.0, 6177.073438984188, 6220.968895155495, 6251.156093927261, 6307.873842523216, 5827.650340979555, 5945.702714864153, 6211.565190436626, 6255.280642726145, 6269.4984401246875, 4926.876828079184, 6097.634141951497, 6195.1636529068, 6224.439800417984, 6235.501159907287, 6101.005087479836, 6142.907856382247, 6143.815685529441, 6147.96926015385, 6165.075238714228, 4931.4328852181025, 5975.312839023568, 6019.127226552213, 6027.095935609694, 6081.0, 6051.909221361771, 6117.522930757332, 6162.969185154046, 6171.876562468636, 6188.102725104744, 6081.148639190428, 6124.809316330491, 6126.914660831389, 6129.49582850979, 6140.412224875342], "value": 5706.397127157702, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [492.83982705620673, 524.1325606121867, 542.0135353656344, 545.7216819422096, 557.5957430862628, 37844.621553784455, 40490.79754601227, 40604.18791624166, 40720.38919416211, 40766.0, 41629.791851040776, 42152.946176345606, 42172.945676358104, 42237.36643950343, 42337.7883110584, 9548.80902381952, 9553.426794392324, 9619.326647134685, 9638.566264518113, 9872.851907221397, 9567.904320956806, 9701.805963880719, 9720.319577629534, 9726.56230469627, 9766.658166964158, 9582.66460673873, 9711.854322185172, 9740.0, 9741.366811157288, 9767.316287859818, 9466.0, 9576.377535460173, 9690.75773105678, 9700.514974251259, 9728.270379721549, 9481.146696797252, 9611.0, 9617.23062155026, 9635.181009614122, 9696.95151524238, 39540.0, 39825.80087099546, 40001.79999100015, 40032.79983600092, 40203.994900127436, 2942.396808654221, 3105.487594546897, 3361.344537815127, 3366.360391525602, 3400.6429324920773, 31768.0, 32041.83979080113, 32079.83960080208, 32320.030399088064, 32993.0, 2899.9275018124504, 3205.423023855705, 3212.3093534889877, 3241.546183534307, 3358.983205083983, 492965.0, 503400.0, 505392.0, 506192.0, 507164.0, 57461.0, 59837.11058781252, 60167.487438442295, 60248.0, 60364.0, 35048.0, 35235.64764352375, 35625.46561801575, 36546.26907461889, 62764.05853912195, 16618.83381166197, 16715.582110447336, 16807.91596042012, 16818.0, 16820.15899205035, 12497.687557810945, 12635.494580216688, 12735.0, 12765.978721702242, 12787.488500459878, 19252.4224273272, 19374.612507750047, 19402.029898505018, 19441.833489990364, 19502.317418890554, 7785.922140778633, 7918.6436610352675, 7936.127026027132, 7951.284384405431, 7997.840043199103, 864.8443280209497, 949.8052899155656, 972.5526257921294, 980.9950950245346, 1007.2646967713464, 30117.69882301149, 30512.237194070327, 30851.382972340427, 31428.37143257122, 31435.84282078575, 1008.5915204342253, 1052.3002203534718, 1065.0574241796066, 1106.6016234155697, 1132.8640563132476, 15387.512948488666, 15983.200839957955, 16037.358505659877, 16122.838771612134, 16202.513924582046, 663158.0, 668181.0, 669905.300946994, 670742.0, 671457.0, 59439.40560594425, 59857.802843942874, 60668.08997865036, 60754.39245607576, 60852.69573652192, 1323.219300612643, 1345.6501309659363, 1403.7226124227109, 1408.3380811018806, 1409.1122592766696, 29490.557641634965, 30071.398572028873, 30380.93666721654, 30576.0, 30580.388392232475, 51483.0, 51578.0, 51870.74064629653, 52001.7399912998, 52008.73995629998, 496604.0, 497236.0, 497275.0, 497588.0, 497910.0, 22193.334199974026, 26675.599866001638, 26781.464370712867, 27142.592861106703, 27203.72796272012, 46977.29534056924, 47746.76126619345, 48798.512014879394, 49090.75454622704, 49202.507974921224, 35926.820365898006, 39746.40380394354, 39943.0, 40644.98387540335, 41442.792786035876, 38247.42628860514, 40038.0, 40087.0, 41205.175896481305, 41261.58738412698, 39212.80393598014, 39352.0, 40074.79962600168, 40255.59744402636, 41086.794566026976, 31102.0, 34775.47836782501, 34900.65099348974, 35447.64552354546, 35638.109047274025, 19433.902830485757, 23692.881535592212, 25960.0, 26509.60235596506, 26834.597481038192], "value": 19918.28627470842, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.39126485428943, 367.98344074516626, 377.23421454447464, 391.84130427177, 396.90275882408815, 18786.436406907796, 20310.492237694045, 21222.832744199062, 21268.680969785466, 21522.0, 12043.698907527316, 12278.0, 16531.752023719622, 20562.280320188795, 21294.0, 5504.119340905446, 5602.579806514507, 5671.461211184931, 5675.51758100562, 5676.687782171979, 5452.318460192476, 5557.72211389431, 5575.776968921258, 5578.0, 5582.106862901936, 5440.292761940962, 5488.259085023531, 5497.835064948038, 5599.552035837123, 5604.859878503029, 5413.918791218135, 5424.864378390533, 5470.9452905471035, 5484.149956756709, 5518.503334699856, 5184.974075129638, 5273.419923808378, 5336.1195402758885, 5358.223057656618, 5389.973050134763, 24842.627360589606, 25219.24342269735, 25681.0, 25720.871395643087, 25807.35481612956, 2119.501917049492, 2135.95728085439, 2152.2251989283845, 2180.7819218078225, 2365.053978408633, 10223.335483193569, 10355.948220258926, 10442.425666588335, 10514.474276286155, 10589.470526473722, 1734.9479515614553, 1858.4517567317673, 1874.8968806715625, 1875.8201121316968, 1885.7737071551364, 131814.0, 135890.999999999, 136088.0, 136314.0, 138365.0, 11483.598074067368, 11602.303861768321, 11708.946194842505, 11932.284062956076, 11941.0, 14294.428222870969, 14645.707085858223, 14651.780223296659, 14657.853421465863, 14694.926525367304, 8436.493810371398, 8511.74464766058, 8573.699920502753, 8649.437786543855, 8745.956270218609, 8466.661333546712, 8499.915000849913, 8606.526641034678, 8752.8687069694, 8783.077776833341, 4476.306172543261, 4513.119941611354, 4603.102395032936, 4617.769111544477, 4730.172219861579, 1711.9471525012027, 1920.5582715975404, 1969.3304276546044, 1995.9501012474805, 1997.4007797660643, 747.5813544415145, 875.3478658399406, 897.2328658996464, 950.1733491861986, 998.5656239535825, 19219.0, 21162.894185529283, 21279.574408511744, 21479.892600536896, 21557.02993365271, 931.5761328595473, 934.6915517879083, 950.4154944708954, 1017.8625885505362, 1058.9258751887294, 10655.627053053106, 11286.774264514828, 11297.71755706114, 11309.71725706864, 11373.147013973985, 588396.0, 612277.0, 620451.0, 634464.0, 635484.0, 131544.0, 133515.0, 136558.0, 136949.0, 137592.0, 960.2174228004154, 998.6454808543008, 1014.9238807089498, 1018.7656838927088, 1019.37308555238, 16282.0, 18379.0, 18970.52573685669, 19265.614687705885, 19321.323753668556, 43596.0, 58923.0, 59420.40579594322, 60020.0, 61885.0, 83167.0, 84235.89410264793, 85072.0, 86625.0, 87648.12351876654, 8003.83992320162, 8049.0, 8077.5961201940845, 8211.794705132419, 8276.337892968668, 10902.0, 11287.85530749155, 12658.493660253487, 12948.417321220379, 13092.083554151119, 10831.025207731342, 10842.241043126905, 11193.048590869867, 11206.943965280447, 11233.157513186381, 9814.0, 10211.7447063824, 10960.890391095987, 11261.49323280438, 11397.259178153394, 10987.505562249558, 11375.374354410587, 11414.08687304997, 11434.970852623304, 11498.712532186762, 7104.715811367694, 7352.264773522605, 7520.736774212876, 7962.761117166494, 8008.399370047153, 7112.608806515691, 8541.743747687578, 11276.60531881413, 11606.651800446, 11806.940965295118], "value": 9508.29644327096, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1511.848815118488, 1581.193591268453, 1726.9136543172838, 1801.3425099838546, 1825.3520000399858, 7947.284744373005, 8337.83324333513, 8520.446170998881, 8855.291576673875, 8884.11158884112, 8605.526696031715, 8635.95682021589, 8723.302135829133, 8741.16958888906, 8756.03683594804, 2347.80043696286, 2393.509330587234, 2397.568437681217, 2398.388410955204, 2403.399150212447, 2358.7995020423286, 2384.5707772600927, 2407.711074671042, 2407.9518409631796, 2418.51629674065, 2339.578875802355, 2386.2364043506086, 2397.4006498375406, 2407.6749638798715, 2410.6384042393606, 2361.8819059046978, 2369.786719195272, 2378.001239479423, 2381.5356005578833, 2387.6299173628117, 2327.964055995083, 2330.102910379499, 2332.9066837326486, 2339.5671800716923, 2347.7065366829147, 8792.560371981439, 8846.42498237619, 8856.202941735275, 8859.335549833788, 8863.734087977307, 5164.6642968207325, 5371.59713021521, 5393.946060539383, 5413.02565538203, 5417.133258678593, 3415.487676848468, 3496.0, 3631.0377749896033, 3679.7056235501095, 3704.5739739929713, 1083.8049151152788, 1084.8481212630238, 1088.395940253159, 1090.574675876409, 1094.9945250273777, 11350.886491135148, 11526.538938442452, 11655.892690194503, 11683.941580292045, 11691.47388367517, 3806.2006978534264, 3984.940225896614, 4552.362669226311, 4752.3584316116885, 4798.184308667535, 9491.193248573949, 9797.363171393836, 9878.259130565237, 9900.0, 9907.306488545733, 5268.394134674561, 5312.990531798946, 5316.813911513156, 5321.627486076015, 5337.653052551572, 4392.297232442794, 4726.480087190406, 4876.317315575822, 4896.755162241873, 4901.240307752304, 3432.7940323580665, 3442.0362298556443, 3852.4028775539837, 4039.2325458162854, 4039.9596004040172, 1285.762134005212, 1291.8837304642627, 1297.2864924291603, 1304.3217526885967, 1305.8258000429369, 737.848741008092, 851.0, 891.4250308551078, 911.2709832134266, 937.6202637931649, 5430.782768689287, 5478.506934375925, 5670.716464176775, 5707.286589176353, 5783.421657834183, 757.7007082202499, 912.981740365189, 939.3603972836352, 944.9433034017981, 981.5828272983953, 3628.818559072036, 3762.078290818767, 3765.8681946131755, 3785.9432108518395, 3796.468494410785, 10286.845697314546, 10734.087602553871, 10739.892601074045, 10741.570337186426, 10765.13878889687, 10497.685069447776, 10651.254412191227, 10712.196585256137, 10714.0, 10761.677149685522, 7924.286814186751, 7946.165652606509, 7967.2431119043595, 8251.669933202606, 8251.793705157419, 5442.292501974718, 5544.528715059265, 5556.138798486241, 5636.239107720569, 5642.802501912578, 9724.076212759777, 9925.35485193489, 9950.45272510019, 10072.84890726625, 10091.798164036825, 9905.752356191153, 10121.481777733105, 10294.742631434274, 10294.794104117726, 10310.0, 6540.051692504465, 6888.862222755617, 7038.612876291853, 7065.505414620927, 7152.785416437515, 8382.83234335322, 8436.873446898424, 8508.74473765788, 8528.829423411373, 8536.829263414822, 7304.233055529306, 7460.216677249028, 7614.048243969504, 7666.310032097138, 7723.266289702469, 7280.67236974348, 7378.077740282465, 7403.333699967029, 7496.512726672749, 7524.5861477619255, 6968.059311993019, 7000.719971201299, 7072.858542829012, 7087.61018144007, 7094.858102838018, 4477.283634618446, 4822.132016237111, 4855.150348688895, 4874.390701162355, 4920.532549407801, 4490.3713480113465, 4574.5425457453985, 4623.375844261116, 4736.9052618946735, 5052.59579233653], "value": 4547.857679685617, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.48559748835766, 296.68106785205896, 305.93728285701434, 312.8998720409469, 314.86460821846623, 5393.703346315951, 5478.301726464799, 5607.4112218217115, 5641.661500309979, 5653.915191272129, 5491.835244942648, 5562.833115006546, 5568.1647752837, 5569.0229968361255, 5577.414371490996, 4274.679399045076, 4293.0, 4298.613124818766, 4303.763293018898, 4315.82736690532, 3529.1530032792202, 3791.279656865187, 4235.475228917602, 4253.2769429197115, 4325.653947684178, 3859.9421008684894, 3888.6889048876023, 4220.704550681454, 4220.957790422087, 4225.323948168294, 3524.665156810112, 3697.963020369789, 3996.86010989614, 4075.816588253536, 4252.064545799919, 3676.1361080146144, 3680.4663323817895, 3688.963110368889, 3703.666669999686, 3715.8327875245677, 5189.455107213726, 5293.417724050351, 5413.783448662049, 5417.458254174551, 5484.2596249506205, 3797.905052373685, 4077.3883917412327, 4715.31627913954, 4837.564619184291, 4859.635527335428, 5197.750272225042, 5201.609879259071, 5637.492625663669, 5672.68800215988, 5966.2840459144745, 3656.981715091434, 4022.235775202702, 4022.3162062449455, 4025.75845449274, 4040.333344998072, 6286.308506064329, 6292.5595208335435, 6299.3936546180885, 6303.936960630427, 6305.77037477692, 3930.5283365996265, 4067.8169482373364, 4093.529244136903, 4095.5699651536756, 4117.135401565704, 3393.7285017198565, 3709.350863598859, 3906.980465097656, 4101.425800387948, 4141.834326626961, 4868.829590964299, 4872.4640289568115, 4885.509919474594, 4894.779734911866, 4900.191468407673, 5849.13827024476, 5981.401859814071, 5998.070299103646, 5998.610090344114, 5999.400059993965, 1365.7405093032291, 1394.5816255123625, 1414.1939094715913, 1422.3954819201797, 1427.100926416351, 750.951188172767, 765.9234076592408, 779.1118125337007, 840.7351684219451, 848.9769827358019, 4253.8723838284905, 4317.7478531226225, 4394.264265615064, 4504.144212599595, 4546.977265113654, 4676.251799712098, 4887.020756593585, 5069.645124841226, 5107.8723031923755, 5154.92267615986, 4250.364773840239, 4352.521222665504, 4575.679702420865, 4620.283856002325, 4653.883652908705, 2258.5934531784437, 2544.3511904464613, 2678.9552074690887, 2682.25069303965, 2695.2857492764433, 4286.871393858127, 4793.041391721669, 5251.579873610102, 5292.761825717852, 5320.946790532123, 4189.099343641132, 4511.684182107287, 4876.975615121902, 5076.923846142311, 5158.97420512895, 5716.57125715573, 6400.263969643457, 6413.6793160341795, 6420.133282006869, 6445.45213656835, 6186.969065154825, 6204.937950620436, 6209.9817559292505, 6236.968815155895, 6271.310155882758, 5765.769369225184, 5966.880662386815, 5988.341282459013, 6051.394860513913, 6099.8825792590715, 5566.805161819317, 6199.404345479701, 6251.343608920998, 6347.714352854225, 6379.904301435575, 3825.6365645263654, 3912.3544615138185, 3975.2447035063246, 4030.9519524923144, 4030.972102113942, 6004.309504406874, 6042.851858146938, 6093.786717464867, 6101.481374083075, 6118.286879673698, 4898.8775280618265, 4939.085939295833, 5880.617759845596, 5898.203742494708, 5922.230110085661, 4666.739980205393, 4679.298105284234, 5500.009998200362, 5870.295564532113, 5877.911831322448, 4608.009278005245, 4622.6070783982395, 5707.429257074259, 5739.110437882135, 5786.479216870502, 4860.371775455262, 5628.549716022627, 5866.970665146818, 5874.236349274567, 5883.940620782691, 4851.757412129309, 4890.510948905223, 5789.363170051375, 5864.618799778001, 5871.412858714094], "value": 4137.734399558536, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [695.5339922252091, 712.159651611099, 719.2879049740754, 741.7737590035038, 776.2977027521484, 15486.845131548695, 15935.681286374294, 16291.266892989814, 16320.591985200375, 16337.509874703748, 13996.300184990761, 14773.778393324083, 14788.6302842429, 14819.55541333759, 14925.77611335828, 680.5372346804171, 847.6609356257485, 849.9405041647103, 876.9079246679103, 876.9736907892774, 861.0, 877.4954401219325, 879.3712495565669, 880.3837313880277, 881.8721285413611, 896.7040876510736, 927.7680579855037, 928.6656803550717, 928.7538802217417, 933.6592143867507, 912.2337236721153, 921.1525396635112, 925.9722208333761, 926.5737760630118, 936.7704912296463, 628.6605233174081, 647.9384458476437, 666.0575285970347, 675.9695813688396, 690.5062880040745, 13836.723265534632, 14487.78268325976, 15347.923260383739, 15400.0, 15419.22903854814, 11798.46906889192, 12221.633350999484, 12907.0, 15026.549203523802, 15270.236488175547, 10365.896341036569, 11676.007539359165, 11831.881681183164, 11901.0, 12188.81716774249, 4542.38677778499, 4572.748498832563, 4575.450945886481, 4626.560476754703, 4857.222844344923, 114306.0, 116073.0, 116271.0, 117270.41364793207, 118490.40754796258, 10988.780224395467, 11074.058705010004, 11098.223124381217, 11197.16021298406, 11245.325280483197, 21282.78717212808, 21283.0, 21317.36047918565, 21318.57362852734, 21347.14611415557, 7749.535027898344, 7888.40836937232, 7916.0, 8147.9185208147155, 8165.0, 4415.779211039435, 4476.9104617908115, 4484.282514797616, 4544.091181763661, 4631.8842028948875, 3451.9654803452145, 3571.1250743567994, 3577.624349443323, 3586.4261718124976, 3919.47087143232, 692.8302565871394, 700.0479348086598, 707.6673963237373, 718.511412239682, 718.529363267061, 594.3966873623276, 656.2551504042946, 666.2904007232282, 693.6809067828755, 711.8825393809964, 9167.587458564381, 9640.276979226446, 9722.659706910224, 9919.950400247953, 10058.59765609382, 547.7097138516513, 608.0423333250149, 645.6836150286326, 694.0, 704.2077662629541, 4433.113377324548, 4434.357018232403, 4692.765361731968, 4701.553352431513, 4799.0, 96031.0, 96483.0, 97133.0286697138, 163453.0, 163999.0, 82173.0, 127394.0, 140905.7275417964, 143576.28211858874, 148675.0, 71013.64493177501, 72025.6398718003, 101344.99999999853, 107979.46010270056, 114631.42684286692, 8980.86528702083, 9012.684556040507, 9040.290196898071, 9041.457512549268, 9135.451872887648, 52290.738546307024, 60262.49343766441, 66564.0, 66593.33406665872, 67102.94764628276, 94520.0, 96049.5197524008, 97225.0, 98729.50635246778, 100327.0, 11068.0, 11981.0, 12340.580833204296, 12510.687232819253, 12558.246505209716, 10963.0, 11457.885421145682, 13621.0, 13631.86368136306, 13681.0, 12494.250344979331, 12755.234685918875, 13163.868361316265, 13168.209907405586, 13177.143485673401, 12173.634790956285, 12635.3682315882, 12670.809937850756, 12676.936615316865, 13076.346182691017, 12393.302203848885, 12449.813252801396, 12499.875001250235, 12905.677358065748, 13046.608601741962, 9600.32797704154, 10470.743510778755, 10554.261201715806, 10719.303245289031, 10852.45737713127, 7309.890351644836, 8266.1009183449, 9917.603295868084, 10035.749106272402, 10959.342439453658], "value": 7727.024886133119, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1556.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 116.0, 107.0, 118.0, 116.0, 114.0, 114.0, 118.0, 116.0, 116.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 109.0, 131.0, 158.0, 109.0, 131.0, 128.0, 108.0, 158.0, 112.0], "value": 129.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 204.0, 208.0, 236.0, 251.0, 196.0, 198.0, 199.0, 202.0, 203.0], "value": 209.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 83.0, 76.0, 144.0, 59.0, 59.0, 59.0, 63.0, 133.0, 60.0], "value": 80.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 76.0, 139.0, 102.0, 109.0, 92.0, 119.0, 70.0, 72.0, 119.0], "value": 96.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 87.0, 123.0, 86.0, 89.0, 89.0, 91.0, 75.0, 77.0, 95.0], "value": 90.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 147.0, 116.0, 97.0, 96.0, 99.0, 97.0, 93.0, 145.0, 96.0], "value": 110.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 99.0, 98.0, 93.0, 94.0, 90.0, 90.0, 94.0, 92.0, 135.0], "value": 97.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 56.0, 55.0, 56.0, 56.0, 60.0, 70.0, 73.0, 56.0], "value": 59.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 92.0, 67.0, 74.0, 108.0, 105.0, 94.0, 74.0, 71.0], "value": 82.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 103.0, 102.0, 84.0, 82.0, 90.0, 88.0, 95.0, 104.0, 82.0], "value": 93.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 161.0, 154.0, 150.0, 157.0, 144.0, 139.0, 136.0, 135.0, 141.0], "value": 146.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 173.0, 167.0, 171.0, 169.0, 167.0, 169.0, 172.0, 227.0, 210.0], "value": 179.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 70.0, 66.0, 72.0, 65.0, 65.0, 67.0, 68.0, 67.0], "value": 67.7, "unit": "score"}]}]} 02:12:53 INFO - Return code: 0 02:12:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:12:53 ERROR - # TBPL SUCCESS # 02:12:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:12:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:12:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:12:53 INFO - 2016-08-07 02:12:53.844 screenresolution[2058:24457] starting screenresolution argv=screenresolution get 02:12:53 INFO - 2016-08-07 02:12:53.847 screenresolution[2058:24457] Display 0: 1600x1200x32@60 02:12:53 INFO - 2016-08-07 02:12:53.854 screenresolution[2059:24460] starting screenresolution argv=screenresolution list 02:12:53 INFO - Available Modes on Display 0 02:12:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:12:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:12:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:12:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:12:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:12:54 INFO - Intel Iris: 02:12:54 INFO - Chipset Model: Intel Iris 02:12:54 INFO - Type: GPU 02:12:54 INFO - Bus: Built-In 02:12:54 INFO - VRAM (Dynamic, Max): 1536 MB 02:12:54 INFO - Vendor: Intel (0x8086) 02:12:54 INFO - Device ID: 0x0a2e 02:12:54 INFO - Revision ID: 0x0009 02:12:54 INFO - Displays: 02:12:54 INFO - Display: 02:12:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:12:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:12:54 INFO - Display Serial Number: 1600x1200 60 02:12:54 INFO - Main Display: Yes 02:12:54 INFO - Mirror: Off 02:12:54 INFO - Online: Yes 02:12:54 INFO - Rotation: Supported 02:12:54 INFO - Return code: 0 02:12:54 INFO - Running post-action listener: _resource_record_post_action 02:12:54 INFO - [mozharness: 2016-08-07 09:12:54.093463Z] Finished run-tests step (success) 02:12:54 INFO - Running post-run listener: _resource_record_post_run 02:12:54 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 634880; Write bytes: 707069952; Read time: 5; Write time: 1829 02:12:54 INFO - TinderboxPrint: CPU usage
54.4% 02:12:54 INFO - TinderboxPrint: I/O read bytes / time
634,880 / 5 02:12:54 INFO - TinderboxPrint: I/O write bytes / time
707,069,952 / 1,829 02:12:54 INFO - TinderboxPrint: CPU idle
2,207.8 (45.6%) 02:12:54 INFO - TinderboxPrint: CPU system
975.1 (20.2%) 02:12:54 INFO - TinderboxPrint: CPU user
1,654.7 (34.2%) 02:12:54 INFO - TinderboxPrint: Swap in / out
406,786,048 / 0 02:12:54 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 398094848; Write bytes: 390596096; Read time: 29590; Write time: 688 02:12:54 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 634880; Write bytes: 296649216; Read time: 5; Write time: 1098 02:12:54 INFO - Running post-run listener: _upload_blobber_files 02:12:54 INFO - Blob upload gear active. 02:12:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:12:54 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 02:12:54 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'] 02:12:54 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 02:12:54 INFO - (blobuploader) - INFO - Open directory for files ... 02:12:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:12:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:12:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:12:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:12:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:12:56 INFO - (blobuploader) - INFO - Done attempting. 02:12:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:12:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:12:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:12:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:12:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:12:57 INFO - (blobuploader) - INFO - Done attempting. 02:12:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:12:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:12:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:12:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:12:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:12:59 INFO - (blobuploader) - INFO - Done attempting. 02:12:59 INFO - (blobuploader) - INFO - Iteration through files over. 02:12:59 INFO - Return code: 0 02:12:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:12:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:12:59 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39843e9f4ed668bb68d775ef99ff5454128c94e09c0f5a949931bee422a27f8abdf3d8cce838281183b75da567f00bbade1231e2ebb230384fda53ab63c6b5e8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c57636a50ba7eca4321cba021129fdf0c5ab379634610f7111a7ff41363078c3ee1bdb4ae455f4d3d342c42f617ceacaa831dd88d855e1891a86ad108b9831e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e75fc84621afda178f059210f406e1e51f9000cdf94bdff9cf4ca68b737bf956895f27d07ad0cd0e3ac5d30f7d834ade1a3f538b501dc50eb8f968667d66b5c"} 02:12:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:12:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:12:59 INFO - Contents: 02:12:59 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39843e9f4ed668bb68d775ef99ff5454128c94e09c0f5a949931bee422a27f8abdf3d8cce838281183b75da567f00bbade1231e2ebb230384fda53ab63c6b5e8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c57636a50ba7eca4321cba021129fdf0c5ab379634610f7111a7ff41363078c3ee1bdb4ae455f4d3d342c42f617ceacaa831dd88d855e1891a86ad108b9831e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e75fc84621afda178f059210f406e1e51f9000cdf94bdff9cf4ca68b737bf956895f27d07ad0cd0e3ac5d30f7d834ade1a3f538b501dc50eb8f968667d66b5c"} 02:12:59 INFO - Running post-run listener: copy_logs_to_upload_dir 02:12:59 INFO - Copying logs to upload dir... 02:12:59 INFO - mkdir: /builds/slave/test/build/upload/logs 02:12:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.314291 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-08-07 02:12:59.344402) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-07 02:12:59.347762) ========= 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.9xxY3tWd8H/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.qfX53FGuS3/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39843e9f4ed668bb68d775ef99ff5454128c94e09c0f5a949931bee422a27f8abdf3d8cce838281183b75da567f00bbade1231e2ebb230384fda53ab63c6b5e8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c57636a50ba7eca4321cba021129fdf0c5ab379634610f7111a7ff41363078c3ee1bdb4ae455f4d3d342c42f617ceacaa831dd88d855e1891a86ad108b9831e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e75fc84621afda178f059210f406e1e51f9000cdf94bdff9cf4ca68b737bf956895f27d07ad0cd0e3ac5d30f7d834ade1a3f538b501dc50eb8f968667d66b5c"} build_url:https://queue.taskcluster.net/v1/task/AQ_vNVkuSNqcfAHjzizILg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011336 build_url: 'https://queue.taskcluster.net/v1/task/AQ_vNVkuSNqcfAHjzizILg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39843e9f4ed668bb68d775ef99ff5454128c94e09c0f5a949931bee422a27f8abdf3d8cce838281183b75da567f00bbade1231e2ebb230384fda53ab63c6b5e8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c57636a50ba7eca4321cba021129fdf0c5ab379634610f7111a7ff41363078c3ee1bdb4ae455f4d3d342c42f617ceacaa831dd88d855e1891a86ad108b9831e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e75fc84621afda178f059210f406e1e51f9000cdf94bdff9cf4ca68b737bf956895f27d07ad0cd0e3ac5d30f7d834ade1a3f538b501dc50eb8f968667d66b5c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-07 02:12:59.381222) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 02:12:59.381595) ========= 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.9xxY3tWd8H/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.qfX53FGuS3/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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.004478 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 02:12:59.430778) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-07 02:12:59.431119) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-07 02:12:59.431541) ========= ========= Total master_lag: 0.20 =========