builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0075 starttime: 1492449924.57 results: success (0) buildid: 20170417082108 builduid: fcabfd3f11bc48d68b718b9892105b60 revision: befe7c6050b76a46f5d4bed42e28eca8fa6d31ca ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-17 10:25:24.572804) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-17 10:25:24.573297) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-17 10:25:24.573622) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-17 10:25:24.602405) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 10:25:24.602705) ========= 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.VFaDmm7zrH/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.mm6MfOc3cK/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-17 10:25:24-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 179M=0s 2017-04-17 10:25:24 (179 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.133756 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 10:25:24.761104) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 10:25:24.761467) ========= 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.VFaDmm7zrH/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.mm6MfOc3cK/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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.028327 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 10:25:24.815180) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-17 10:25:24.815879) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev befe7c6050b76a46f5d4bed42e28eca8fa6d31ca --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev befe7c6050b76a46f5d4bed42e28eca8fa6d31ca --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VFaDmm7zrH/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.mm6MfOc3cK/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-17 10:25:24,910 truncating revision to first 12 chars 2017-04-17 10:25:24,910 Setting DEBUG logging. 2017-04-17 10:25:24,910 attempt 1/10 2017-04-17 10:25:24,911 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/befe7c6050b7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-17 10:25:26,895 unpacking tar archive at: pine-befe7c6050b7/testing/mozharness/ program finished with exit code 0 elapsedTime=2.268209 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-17 10:25:27.109263) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-17 10:25:27.109624) ========= script_repo_revision: befe7c6050b76a46f5d4bed42e28eca8fa6d31ca ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-17 10:25:27.110027) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-17 10:25:27.110319) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-17 10:25:27.127068) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-17 10:25:27.127355) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VFaDmm7zrH/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.mm6MfOc3cK/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 10:25:27 INFO - MultiFileLogger online at 20170417 10:25:27 in /builds/slave/test 10:25:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 10:25:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:25:27 INFO - {'append_to_log': False, 10:25:27 INFO - 'base_work_dir': '/builds/slave/test', 10:25:27 INFO - 'blob_upload_branch': 'Pine', 10:25:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:25:27 INFO - 'branch': 'Pine', 10:25:27 INFO - 'buildbot_json_path': 'buildprops.json', 10:25:27 INFO - 'config_files': ('talos/mac_config.py',), 10:25:27 INFO - 'default_actions': ('clobber', 10:25:27 INFO - 'read-buildbot-config', 10:25:27 INFO - 'download-and-extract', 10:25:27 INFO - 'populate-webroot', 10:25:27 INFO - 'create-virtualenv', 10:25:27 INFO - 'install', 10:25:27 INFO - 'run-tests'), 10:25:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:25:27 INFO - 'download_minidump_stackwalk': True, 10:25:27 INFO - 'download_symbols': 'ondemand', 10:25:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:25:27 INFO - 'tooltool.py': '/tools/tooltool.py', 10:25:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:25:27 INFO - '/tools/misc-python/virtualenv.py')}, 10:25:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:25:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:25:27 INFO - 'gecko_profile': False, 10:25:27 INFO - 'gecko_profile_interval': 0, 10:25:27 INFO - 'installer_path': 'installer.exe', 10:25:27 INFO - 'log_level': 'info', 10:25:27 INFO - 'log_name': 'talos', 10:25:27 INFO - 'log_to_console': True, 10:25:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:25:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:25:27 INFO - 'opt_config_files': (), 10:25:27 INFO - 'pip_index': False, 10:25:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:25:27 INFO - 'cmd': ('bash', 10:25:27 INFO - '-c', 10:25:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:25:27 INFO - 'enabled': True, 10:25:27 INFO - 'halt_on_failure': False, 10:25:27 INFO - 'name': 'check_screen_resolution'},), 10:25:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:25:27 INFO - 'cmd': ('bash', 10:25:27 INFO - '-c', 10:25:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:25:27 INFO - 'enabled': True, 10:25:27 INFO - 'halt_on_failure': False, 10:25:27 INFO - 'name': 'check_screen_resolution'},), 10:25:27 INFO - 'run_cmd_checks_enabled': True, 10:25:27 INFO - 'suite': 'dromaeojs-e10s', 10:25:27 INFO - 'system_bits': '32', 10:25:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:25:27 INFO - 'title': 't-yosemite-r7-0075', 10:25:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:25:27 INFO - 'use_talos_json': True, 10:25:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:25:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:25:27 INFO - 'work_dir': 'build'} 10:25:27 INFO - [mozharness: 2017-04-17 17:25:27.331083Z] Running clobber step. 10:25:27 INFO - Running pre-action listener: _resource_record_pre_action 10:25:27 INFO - Running main action method: clobber 10:25:27 INFO - rmtree: /builds/slave/test/build 10:25:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:25:31 INFO - Running post-action listener: _resource_record_post_action 10:25:31 INFO - [mozharness: 2017-04-17 17:25:31.929122Z] Finished clobber step (success) 10:25:31 INFO - [mozharness: 2017-04-17 17:25:31.929316Z] Running read-buildbot-config step. 10:25:31 INFO - Running pre-action listener: _resource_record_pre_action 10:25:31 INFO - Running main action method: read_buildbot_config 10:25:31 INFO - Using buildbot properties: 10:25:31 INFO - { 10:25:31 INFO - "project": "", 10:25:31 INFO - "product": "firefox", 10:25:31 INFO - "script_repo_revision": "default", 10:25:31 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 10:25:31 INFO - "repository": "", 10:25:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 10:25:31 INFO - "buildid": "20170417082108", 10:25:31 INFO - "pgo_build": "False", 10:25:31 INFO - "basedir": "/builds/slave/test", 10:25:31 INFO - "buildnumber": 2, 10:25:31 INFO - "slavename": "t-yosemite-r7-0075", 10:25:31 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 10:25:31 INFO - "platform": "yosemite_r7", 10:25:31 INFO - "scheduler": "tests-pine-macosx64-talos", 10:25:31 INFO - "branch": "pine", 10:25:31 INFO - "repo_path": "projects/pine", 10:25:31 INFO - "revision": "befe7c6050b76a46f5d4bed42e28eca8fa6d31ca", 10:25:31 INFO - "stage_platform": "macosx64", 10:25:31 INFO - "builduid": "fcabfd3f11bc48d68b718b9892105b60", 10:25:31 INFO - "slavebuilddir": "test" 10:25:31 INFO - } 10:25:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/Qz7uu5y3RZq_OzCRpQSrmQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 10:25:31 INFO - Running post-action listener: _resource_record_post_action 10:25:31 INFO - [mozharness: 2017-04-17 17:25:31.935029Z] Finished read-buildbot-config step (success) 10:25:31 INFO - [mozharness: 2017-04-17 17:25:31.935271Z] Running download-and-extract step. 10:25:31 INFO - Running pre-action listener: _resource_record_pre_action 10:25:31 INFO - Running main action method: download_and_extract 10:25:31 INFO - mkdir: /builds/slave/test/build/tests 10:25:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:31 INFO - trying https://queue.taskcluster.net/v1/task/Qz7uu5y3RZq_OzCRpQSrmQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 10:25:31 INFO - Downloading https://queue.taskcluster.net/v1/task/Qz7uu5y3RZq_OzCRpQSrmQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:25:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Qz7uu5y3RZq_OzCRpQSrmQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 10:25:33 INFO - Downloaded 1325 bytes. 10:25:33 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:25:33 INFO - Using the following test package requirements: 10:25:33 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:25:33 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 10:25:33 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:25:33 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:25:33 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 10:25:33 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:25:33 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 10:25:33 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:25:33 INFO - u'jsshell-mac.zip'], 10:25:33 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:25:33 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 10:25:33 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:25:33 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:25:33 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 10:25:33 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:25:33 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 10:25:33 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:25:33 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 10:25:33 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:25:33 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 10:25:33 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:25:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Qz7uu5y3RZq_OzCRpQSrmQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:25:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Qz7uu5y3RZq_OzCRpQSrmQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:25:33 INFO - Fetch https://queue.taskcluster.net/v1/task/Qz7uu5y3RZq_OzCRpQSrmQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:25:34 INFO - Expected file size: 52660073 10:25:34 INFO - Obtained file size: 52660073 10:25:51 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:25:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Qz7uu5y3RZq_OzCRpQSrmQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:25:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Qz7uu5y3RZq_OzCRpQSrmQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:25:51 INFO - Fetch https://queue.taskcluster.net/v1/task/Qz7uu5y3RZq_OzCRpQSrmQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:25:52 INFO - Expected file size: 52660073 10:25:52 INFO - Obtained file size: 52660073 10:26:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Qz7uu5y3RZq_OzCRpQSrmQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 10:26:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Qz7uu5y3RZq_OzCRpQSrmQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:26:08 INFO - Fetch https://queue.taskcluster.net/v1/task/Qz7uu5y3RZq_OzCRpQSrmQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 10:26:09 INFO - Expected file size: 14408802 10:26:09 INFO - Obtained file size: 14408802 10:26:09 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')]} 10:26:09 INFO - trying https://queue.taskcluster.net/v1/task/Qz7uu5y3RZq_OzCRpQSrmQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:26:09 INFO - Downloading https://queue.taskcluster.net/v1/task/Qz7uu5y3RZq_OzCRpQSrmQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 10:26:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Qz7uu5y3RZq_OzCRpQSrmQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 10:26:11 INFO - Downloaded 71490717 bytes. 10:26:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Qz7uu5y3RZq_OzCRpQSrmQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:26:11 INFO - mkdir: /builds/slave/test/properties 10:26:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:26:11 INFO - Writing to file /builds/slave/test/properties/build_url 10:26:11 INFO - Contents: 10:26:11 INFO - build_url:https://queue.taskcluster.net/v1/task/Qz7uu5y3RZq_OzCRpQSrmQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:26:12 INFO - Running post-action listener: _resource_record_post_action 10:26:12 INFO - Running post-action listener: set_extra_try_arguments 10:26:12 INFO - [mozharness: 2017-04-17 17:26:12.642702Z] Finished download-and-extract step (success) 10:26:12 INFO - [mozharness: 2017-04-17 17:26:12.642818Z] Running populate-webroot step. 10:26:12 INFO - Running pre-action listener: _resource_record_pre_action 10:26:12 INFO - Running main action method: populate_webroot 10:26:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:26:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:26:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:26:12 INFO - '--tppagecycles', 10:26:12 INFO - '7'], 10:26:12 INFO - 'tests': ['tsvgm']}}, 10:26:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:26:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:26:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:26:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:26:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:26:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:26:12 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:26:12 INFO - 'talos_options': ['--disable-e10s'], 10:26:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:26:12 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:26:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:26:12 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:26:12 INFO - 'talos_options': ['--disable-e10s'], 10:26:12 INFO - 'tests': ['damp', 'tps']}, 10:26:12 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:26:12 INFO - 'tests': ['damp', 'tps']}, 10:26:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:26:12 INFO - 'tests': ['dromaeo_dom']}, 10:26:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:26:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:26:12 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 10:26:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 10:26:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:26:12 INFO - 'tests': ['a11yr', 10:26:12 INFO - 'ts_paint', 10:26:12 INFO - 'tpaint', 10:26:12 INFO - 'sessionrestore', 10:26:12 INFO - 'sessionrestore_no_auto_restore', 10:26:12 INFO - 'tabpaint']}, 10:26:12 INFO - 'other-e10s': {'tests': ['a11yr', 10:26:12 INFO - 'ts_paint', 10:26:12 INFO - 'tpaint', 10:26:12 INFO - 'sessionrestore', 10:26:12 INFO - 'sessionrestore_no_auto_restore', 10:26:12 INFO - 'tabpaint']}, 10:26:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:26:12 INFO - 'ts_paint', 10:26:12 INFO - 'tpaint', 10:26:12 INFO - 'sessionrestore', 10:26:12 INFO - 'sessionrestore_no_auto_restore', 10:26:12 INFO - 'tabpaint']}, 10:26:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:26:12 INFO - 'ts_paint', 10:26:12 INFO - 'tpaint', 10:26:12 INFO - 'sessionrestore', 10:26:12 INFO - 'sessionrestore_no_auto_restore', 10:26:12 INFO - 'tabpaint']}, 10:26:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:26:12 INFO - 'tests': ['a11yr', 10:26:12 INFO - 'ts_paint', 10:26:12 INFO - 'tpaint', 10:26:12 INFO - 'sessionrestore', 10:26:12 INFO - 'sessionrestore_no_auto_restore', 10:26:12 INFO - 'tabpaint']}, 10:26:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:26:12 INFO - 'tests': ['a11yr', 10:26:12 INFO - 'ts_paint', 10:26:12 INFO - 'tpaint', 10:26:12 INFO - 'sessionrestore', 10:26:12 INFO - 'sessionrestore_no_auto_restore', 10:26:12 INFO - 'tabpaint']}, 10:26:12 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 10:26:12 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 10:26:12 INFO - 'bloom_basic_ref']}, 10:26:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:26:12 INFO - 'tests': ['tsvgx', 10:26:12 INFO - 'tsvgr_opacity', 10:26:12 INFO - 'tart', 10:26:12 INFO - 'tscrollx', 10:26:12 INFO - 'cart', 10:26:12 INFO - 'tsvg_static']}, 10:26:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:26:12 INFO - 'tsvgr_opacity', 10:26:12 INFO - 'tart', 10:26:12 INFO - 'tscrollx', 10:26:12 INFO - 'cart', 10:26:12 INFO - 'tsvg_static']}, 10:26:12 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:26:12 INFO - 'talos_options': ['--disable-e10s'], 10:26:12 INFO - 'tests': ['tp5o']}, 10:26:12 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:26:12 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:26:12 INFO - 'talos_options': ['--disable-e10s', 10:26:12 INFO - '--xperf_path', 10:26:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:26:12 INFO - 'tests': ['tp5n']}, 10:26:12 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:26:12 INFO - 'talos_options': ['--xperf_path', 10:26:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:26:12 INFO - 'tests': ['tp5n']}}, 10:26:12 INFO - 'talos.zip': {'path': '', 10:26:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:26:12 INFO - Running post-action listener: _resource_record_post_action 10:26:12 INFO - [mozharness: 2017-04-17 17:26:12.662146Z] Finished populate-webroot step (success) 10:26:12 INFO - [mozharness: 2017-04-17 17:26:12.662277Z] Running create-virtualenv step. 10:26:12 INFO - Running pre-action listener: _resource_record_pre_action 10:26:12 INFO - Running main action method: create_virtualenv 10:26:12 INFO - Creating virtualenv /builds/slave/test/build/venv 10:26:12 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:26:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:26:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:26:12 INFO - Using real prefix '/tools/python27' 10:26:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:26:13 INFO - Installing distribute.............................................................................................................................................................................................done. 10:26:16 INFO - Installing pip.................done. 10:26:16 INFO - Return code: 0 10:26:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:26:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:26:16 INFO - Reading from file tmpfile_stdout 10:26:16 INFO - Output received: 10:26:16 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 10:26:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:26:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:26:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:26:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:16 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 0x107722b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107725768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffefbd91120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107500d30>, '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 0x107426db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074f5be8>, '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.VFaDmm7zrH/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.mm6MfOc3cK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:26:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:26:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:26:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VFaDmm7zrH/Render', 10:26:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:16 INFO - 'HOME': '/Users/cltbld', 10:26:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:16 INFO - 'LOGNAME': 'cltbld', 10:26:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:16 INFO - 'MOZ_NO_REMOTE': '1', 10:26:16 INFO - 'NO_EM_RESTART': '1', 10:26:16 INFO - 'PAGER': '/bin/cat', 10:26:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:16 INFO - 'PWD': '/builds/slave/test', 10:26:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:16 INFO - 'SHELL': '/bin/bash', 10:26:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mm6MfOc3cK/Listeners', 10:26:16 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 10:26:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:16 INFO - 'USER': 'cltbld', 10:26:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:16 INFO - 'XPC_FLAGS': '0x0', 10:26:16 INFO - 'XPC_SERVICE_NAME': '0', 10:26:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:16 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:26:16 INFO - Cleaning up... 10:26:16 INFO - Return code: 0 10:26:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:26:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:26:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:26:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:16 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 0x107722b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107725768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffefbd91120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107500d30>, '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 0x107426db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074f5be8>, '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.VFaDmm7zrH/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.mm6MfOc3cK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:26:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:26:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:26:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VFaDmm7zrH/Render', 10:26:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:16 INFO - 'HOME': '/Users/cltbld', 10:26:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:16 INFO - 'LOGNAME': 'cltbld', 10:26:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:16 INFO - 'MOZ_NO_REMOTE': '1', 10:26:16 INFO - 'NO_EM_RESTART': '1', 10:26:16 INFO - 'PAGER': '/bin/cat', 10:26:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:16 INFO - 'PWD': '/builds/slave/test', 10:26:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:16 INFO - 'SHELL': '/bin/bash', 10:26:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mm6MfOc3cK/Listeners', 10:26:16 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 10:26:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:16 INFO - 'USER': 'cltbld', 10:26:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:16 INFO - 'XPC_FLAGS': '0x0', 10:26:16 INFO - 'XPC_SERVICE_NAME': '0', 10:26:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:17 INFO - Downloading/unpacking psutil>=3.1.1 10:26:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:26:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:26:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:26:20 INFO - Installing collected packages: psutil 10:26:20 INFO - Running setup.py install for psutil 10:26:20 INFO - building 'psutil._psutil_osx' extension 10:26:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 10:26: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 10:26: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 10:26: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 10:26:21 INFO - building 'psutil._psutil_posix' extension 10:26: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 10:26:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:26:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:26:21 INFO - ^ 10:26:21 INFO - 1 warning generated. 10:26:21 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 10:26:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:26:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:26:21 INFO - Successfully installed psutil 10:26:21 INFO - Cleaning up... 10:26:21 INFO - Return code: 0 10:26:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:26:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:26:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26: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')]} 10:26:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26: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 0x107722b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107725768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffefbd91120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107500d30>, '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 0x107426db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074f5be8>, '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.VFaDmm7zrH/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.mm6MfOc3cK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:26:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:26:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:26:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VFaDmm7zrH/Render', 10:26:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:22 INFO - 'HOME': '/Users/cltbld', 10:26:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:22 INFO - 'LOGNAME': 'cltbld', 10:26:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:22 INFO - 'MOZ_NO_REMOTE': '1', 10:26:22 INFO - 'NO_EM_RESTART': '1', 10:26:22 INFO - 'PAGER': '/bin/cat', 10:26:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:22 INFO - 'PWD': '/builds/slave/test', 10:26:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:22 INFO - 'SHELL': '/bin/bash', 10:26:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mm6MfOc3cK/Listeners', 10:26:22 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 10:26:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:22 INFO - 'USER': 'cltbld', 10:26:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:22 INFO - 'XPC_FLAGS': '0x0', 10:26:22 INFO - 'XPC_SERVICE_NAME': '0', 10:26:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:26: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 10:26: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 10:26: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 10:26: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 10:26: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 10:26: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 10:26:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:26:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:26:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:26:25 INFO - Installing collected packages: mozsystemmonitor 10:26:25 INFO - Running setup.py install for mozsystemmonitor 10:26:25 INFO - Successfully installed mozsystemmonitor 10:26:25 INFO - Cleaning up... 10:26:26 INFO - Return code: 0 10:26:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:26:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:26:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:26:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107722b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107725768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffefbd91120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107500d30>, '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 0x107426db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074f5be8>, '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.VFaDmm7zrH/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.mm6MfOc3cK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:26:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:26:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:26:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VFaDmm7zrH/Render', 10:26:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:26 INFO - 'HOME': '/Users/cltbld', 10:26:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:26 INFO - 'LOGNAME': 'cltbld', 10:26:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:26 INFO - 'MOZ_NO_REMOTE': '1', 10:26:26 INFO - 'NO_EM_RESTART': '1', 10:26:26 INFO - 'PAGER': '/bin/cat', 10:26:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:26 INFO - 'PWD': '/builds/slave/test', 10:26:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:26 INFO - 'SHELL': '/bin/bash', 10:26:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mm6MfOc3cK/Listeners', 10:26:26 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 10:26:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:26 INFO - 'USER': 'cltbld', 10:26:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:26 INFO - 'XPC_FLAGS': '0x0', 10:26:26 INFO - 'XPC_SERVICE_NAME': '0', 10:26:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:26 INFO - Downloading/unpacking jsonschema==2.5.1 10:26:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:26:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:26:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:29 INFO - Downloading functools32-3.2.3-2.tar.gz 10:26:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:26:29 INFO - warning: no files found matching '*.txt' 10:26:29 INFO - no previously-included directories found matching 'build' 10:26:29 INFO - no previously-included directories found matching 'dist' 10:26:29 INFO - no previously-included directories found matching '.git*' 10:26:29 INFO - Installing collected packages: jsonschema, functools32 10:26:29 INFO - Running setup.py install for functools32 10:26:29 INFO - warning: no files found matching '*.txt' 10:26:29 INFO - no previously-included directories found matching 'build' 10:26:29 INFO - no previously-included directories found matching 'dist' 10:26:29 INFO - no previously-included directories found matching '.git*' 10:26:29 INFO - Successfully installed jsonschema functools32 10:26:29 INFO - Cleaning up... 10:26:29 INFO - Return code: 0 10:26:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:26: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')]} 10:26:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26: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')]} 10:26:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26: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', '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 0x107722b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107725768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffefbd91120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107500d30>, '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 0x107426db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074f5be8>, '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.VFaDmm7zrH/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.mm6MfOc3cK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:26: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:26: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 functools32==3.2.3-2 10:26:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VFaDmm7zrH/Render', 10:26:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:29 INFO - 'HOME': '/Users/cltbld', 10:26:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:29 INFO - 'LOGNAME': 'cltbld', 10:26:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:29 INFO - 'MOZ_NO_REMOTE': '1', 10:26:29 INFO - 'NO_EM_RESTART': '1', 10:26:29 INFO - 'PAGER': '/bin/cat', 10:26:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:29 INFO - 'PWD': '/builds/slave/test', 10:26:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:29 INFO - 'SHELL': '/bin/bash', 10:26:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mm6MfOc3cK/Listeners', 10:26:29 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 10:26:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:29 INFO - 'USER': 'cltbld', 10:26:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:29 INFO - 'XPC_FLAGS': '0x0', 10:26:29 INFO - 'XPC_SERVICE_NAME': '0', 10:26:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:26:30 INFO - Cleaning up... 10:26:30 INFO - Return code: 0 10:26:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:26:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:26:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:26:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:30 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 0x107722b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107725768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffefbd91120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107500d30>, '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 0x107426db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074f5be8>, '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.VFaDmm7zrH/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.mm6MfOc3cK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:26:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:26:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:26:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VFaDmm7zrH/Render', 10:26:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:30 INFO - 'HOME': '/Users/cltbld', 10:26:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:30 INFO - 'LOGNAME': 'cltbld', 10:26:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:30 INFO - 'MOZ_NO_REMOTE': '1', 10:26:30 INFO - 'NO_EM_RESTART': '1', 10:26:30 INFO - 'PAGER': '/bin/cat', 10:26:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:30 INFO - 'PWD': '/builds/slave/test', 10:26:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:30 INFO - 'SHELL': '/bin/bash', 10:26:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mm6MfOc3cK/Listeners', 10:26:30 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 10:26:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:30 INFO - 'USER': 'cltbld', 10:26:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:30 INFO - 'XPC_FLAGS': '0x0', 10:26:30 INFO - 'XPC_SERVICE_NAME': '0', 10:26:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:30 INFO - Downloading/unpacking blobuploader==1.2.4 10:26:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:32 INFO - Downloading blobuploader-1.2.4.tar.gz 10:26:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:26:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:26:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:26:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:26:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:34 INFO - Downloading docopt-0.6.1.tar.gz 10:26:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:26:34 INFO - Installing collected packages: blobuploader, requests, docopt 10:26:34 INFO - Running setup.py install for blobuploader 10:26:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:26:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:26:34 INFO - Running setup.py install for requests 10:26:34 INFO - Running setup.py install for docopt 10:26:35 INFO - Successfully installed blobuploader requests docopt 10:26:35 INFO - Cleaning up... 10:26:35 INFO - Return code: 0 10:26:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:26:35 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')]} 10:26:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:35 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')]} 10:26:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:35 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 0x107722b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107725768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffefbd91120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107500d30>, '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 0x107426db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074f5be8>, '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.VFaDmm7zrH/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.mm6MfOc3cK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:26:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:26:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:26:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VFaDmm7zrH/Render', 10:26:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:35 INFO - 'HOME': '/Users/cltbld', 10:26:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:35 INFO - 'LOGNAME': 'cltbld', 10:26:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:35 INFO - 'MOZ_NO_REMOTE': '1', 10:26:35 INFO - 'NO_EM_RESTART': '1', 10:26:35 INFO - 'PAGER': '/bin/cat', 10:26:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:35 INFO - 'PWD': '/builds/slave/test', 10:26:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:35 INFO - 'SHELL': '/bin/bash', 10:26:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mm6MfOc3cK/Listeners', 10:26:35 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 10:26:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:35 INFO - 'USER': 'cltbld', 10:26:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:35 INFO - 'XPC_FLAGS': '0x0', 10:26:35 INFO - 'XPC_SERVICE_NAME': '0', 10:26:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:26:35 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-SRcQp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:26:35 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-ELAxFL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:26:35 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-LMWmZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:26:35 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-qW3Vk6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:26:35 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-MleddA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:26:35 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-nudTOW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:26:35 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-89zzN_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:26:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:26:36 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-yfAg19-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:26:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:26:36 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-Cdwvm8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:26:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:26:36 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-ZSiusK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:26:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:26:36 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-rKWskU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:26:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:26:36 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-22R0GP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:26:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:26:36 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-xgC1dY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:26:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:26:36 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-xMfkez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:26:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:26:36 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-JDnGG4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:26:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:26:36 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-fhXZu1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:26:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:26:37 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-OabLXs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:26:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:26:37 INFO - Running setup.py install for manifestparser 10:26:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:26:37 INFO - Running setup.py install for mozcrash 10:26:37 INFO - Running setup.py install for mozdebug 10:26:37 INFO - Running setup.py install for mozdevice 10:26:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:26:37 INFO - Running setup.py install for mozfile 10:26:37 INFO - Running setup.py install for mozhttpd 10:26:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:26:37 INFO - Running setup.py install for mozinfo 10:26:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:26:38 INFO - Running setup.py install for mozInstall 10:26:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:26:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:26:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:26:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:26:38 INFO - Running setup.py install for mozleak 10:26:38 INFO - Running setup.py install for mozlog 10:26:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:26:38 INFO - Running setup.py install for moznetwork 10:26:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:26:38 INFO - Running setup.py install for mozprocess 10:26:38 INFO - Running setup.py install for mozprofile 10:26:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:26:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:26:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:26:39 INFO - Running setup.py install for mozrunner 10:26:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:26:39 INFO - Running setup.py install for mozscreenshot 10:26:39 INFO - Running setup.py install for moztest 10:26:39 INFO - Running setup.py install for mozversion 10:26:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:26:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:26:39 INFO - Cleaning up... 10:26:39 INFO - Return code: 0 10:26:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:26:39 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')]} 10:26:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:39 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')]} 10:26:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:39 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 0x107722b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107725768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffefbd91120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107500d30>, '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 0x107426db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074f5be8>, '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.VFaDmm7zrH/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.mm6MfOc3cK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:26:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:26:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:26:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VFaDmm7zrH/Render', 10:26:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:39 INFO - 'HOME': '/Users/cltbld', 10:26:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:39 INFO - 'LOGNAME': 'cltbld', 10:26:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:39 INFO - 'MOZ_NO_REMOTE': '1', 10:26:39 INFO - 'NO_EM_RESTART': '1', 10:26:39 INFO - 'PAGER': '/bin/cat', 10:26:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:39 INFO - 'PWD': '/builds/slave/test', 10:26:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:39 INFO - 'SHELL': '/bin/bash', 10:26:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mm6MfOc3cK/Listeners', 10:26:39 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 10:26:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:39 INFO - 'USER': 'cltbld', 10:26:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:39 INFO - 'XPC_FLAGS': '0x0', 10:26:39 INFO - 'XPC_SERVICE_NAME': '0', 10:26:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:26:39 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-rowDrb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:26:40 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:26:40 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-m4JxWG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:26:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:26:40 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-Ef7Cvu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:26:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:26:40 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-gZNT2x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:26:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:26:40 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-WFR2Tv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:26:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:26:40 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-xynUB2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:26:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:26:40 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-Pn2xIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:26:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:26:40 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-fQbMNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:26:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:26:40 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-usWyjH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:26:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:26:40 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-RvX46w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:26:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:26:40 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-c1hhqU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:26:41 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-IoymQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:26:41 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-08VSaK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:26:41 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-NivP00-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:26:41 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-X5raJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:26:41 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-grRx0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:26:41 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-VIFchU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:26:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:26:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:44 INFO - Downloading blessings-1.6.tar.gz 10:26:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:26:44 INFO - Installing collected packages: blessings 10:26:44 INFO - Running setup.py install for blessings 10:26:44 INFO - Successfully installed blessings 10:26:44 INFO - Cleaning up... 10:26:44 INFO - Return code: 0 10:26:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:26:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:26:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:26:45 INFO - Reading from file tmpfile_stdout 10:26:45 INFO - Current package versions: 10:26:45 INFO - blessings == 1.6 10:26:45 INFO - blobuploader == 1.2.4 10:26:45 INFO - docopt == 0.6.1 10:26:45 INFO - functools32 == 3.2.3-2 10:26:45 INFO - jsonschema == 2.5.1 10:26:45 INFO - manifestparser == 1.1 10:26:45 INFO - mozInstall == 1.12 10:26:45 INFO - mozcrash == 1.0 10:26:45 INFO - mozdebug == 0.1 10:26:45 INFO - mozdevice == 0.50 10:26:45 INFO - mozfile == 1.2 10:26:45 INFO - mozhttpd == 0.7 10:26:45 INFO - mozinfo == 0.9 10:26:45 INFO - mozleak == 0.1 10:26:45 INFO - mozlog == 3.4 10:26:45 INFO - moznetwork == 0.27 10:26:45 INFO - mozprocess == 0.25 10:26:45 INFO - mozprofile == 0.28 10:26:45 INFO - mozrunner == 6.13 10:26:45 INFO - mozscreenshot == 0.1 10:26:45 INFO - mozsystemmonitor == 0.3 10:26:45 INFO - moztest == 0.8 10:26:45 INFO - mozversion == 1.4 10:26:45 INFO - psutil == 3.1.1 10:26:45 INFO - requests == 1.2.3 10:26:45 INFO - wsgiref == 0.1.2 10:26:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:26:45 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')]} 10:26:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:45 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')]} 10:26:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:45 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 0x107722b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107725768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffefbd91120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107500d30>, '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 0x107426db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074f5be8>, '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.VFaDmm7zrH/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.mm6MfOc3cK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:26:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:26:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:26:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VFaDmm7zrH/Render', 10:26:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:45 INFO - 'HOME': '/Users/cltbld', 10:26:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:45 INFO - 'LOGNAME': 'cltbld', 10:26:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:45 INFO - 'MOZ_NO_REMOTE': '1', 10:26:45 INFO - 'NO_EM_RESTART': '1', 10:26:45 INFO - 'PAGER': '/bin/cat', 10:26:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:45 INFO - 'PWD': '/builds/slave/test', 10:26:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:45 INFO - 'SHELL': '/bin/bash', 10:26:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mm6MfOc3cK/Listeners', 10:26:45 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 10:26:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:45 INFO - 'USER': 'cltbld', 10:26:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:45 INFO - 'XPC_FLAGS': '0x0', 10:26:45 INFO - 'XPC_SERVICE_NAME': '0', 10:26:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:26:45 INFO - Cleaning up... 10:26:45 INFO - Return code: 0 10:26:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:26:45 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')]} 10:26:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:45 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')]} 10:26:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:45 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 0x107722b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107725768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffefbd91120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107500d30>, '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 0x107426db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074f5be8>, '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.VFaDmm7zrH/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.mm6MfOc3cK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:26:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:26:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:26:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VFaDmm7zrH/Render', 10:26:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:45 INFO - 'HOME': '/Users/cltbld', 10:26:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:45 INFO - 'LOGNAME': 'cltbld', 10:26:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:45 INFO - 'MOZ_NO_REMOTE': '1', 10:26:45 INFO - 'NO_EM_RESTART': '1', 10:26:45 INFO - 'PAGER': '/bin/cat', 10:26:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:45 INFO - 'PWD': '/builds/slave/test', 10:26:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:45 INFO - 'SHELL': '/bin/bash', 10:26:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mm6MfOc3cK/Listeners', 10:26:45 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 10:26:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:45 INFO - 'USER': 'cltbld', 10:26:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:45 INFO - 'XPC_FLAGS': '0x0', 10:26:45 INFO - 'XPC_SERVICE_NAME': '0', 10:26:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:26:45 INFO - Cleaning up... 10:26:45 INFO - Return code: 0 10:26:45 INFO - Running post-action listener: _resource_record_post_action 10:26:45 INFO - Running post-action listener: _start_resource_monitoring 10:26:45 INFO - Starting resource monitoring. 10:26:45 INFO - [mozharness: 2017-04-17 17:26:45.601906Z] Finished create-virtualenv step (success) 10:26:45 INFO - [mozharness: 2017-04-17 17:26:45.602860Z] Running install step. 10:26:45 INFO - Running pre-action listener: _resource_record_pre_action 10:26:45 INFO - Running main action method: install 10:26:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:26:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:26:45 INFO - Reading from file tmpfile_stdout 10:26:45 INFO - Detecting whether we're running mozinstall >=1.0... 10:26:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:26:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:26:45 INFO - Reading from file tmpfile_stdout 10:26:45 INFO - Output received: 10:26:45 INFO - Usage: mozinstall [options] installer 10:26:45 INFO - Options: 10:26:45 INFO - -h, --help show this help message and exit 10:26:45 INFO - -d DEST, --destination=DEST 10:26:45 INFO - Directory to install application into. [default: 10:26:45 INFO - "/builds/slave/test"] 10:26:45 INFO - --app=APP Application being installed. [default: firefox] 10:26:45 INFO - mkdir: /builds/slave/test/build/application 10:26:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:26:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:27:05 INFO - Reading from file tmpfile_stdout 10:27:05 INFO - Output received: 10:27:05 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:27:05 INFO - Running post-action listener: _resource_record_post_action 10:27:05 INFO - [mozharness: 2017-04-17 17:27:05.867980Z] Finished install step (success) 10:27:05 INFO - [mozharness: 2017-04-17 17:27:05.868137Z] Running run-tests step. 10:27:05 INFO - Running pre-action listener: _resource_record_pre_action 10:27:05 INFO - Running main action method: run_tests 10:27:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:27:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:27:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:27:05 INFO - 2017-04-17 10:27:05.882 screenresolution[1618:7515] starting screenresolution argv=screenresolution get 10:27:05 INFO - 2017-04-17 10:27:05.885 screenresolution[1618:7515] Display 0: 1600x1200x32@60 10:27:05 INFO - 2017-04-17 10:27:05.891 screenresolution[1619:7517] starting screenresolution argv=screenresolution list 10:27:05 INFO - Available Modes on Display 0 10:27:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:27:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:27:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:27:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:27:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:27:06 INFO - Intel Iris: 10:27:06 INFO - Chipset Model: Intel Iris 10:27:06 INFO - Type: GPU 10:27:06 INFO - Bus: Built-In 10:27:06 INFO - VRAM (Dynamic, Max): 1536 MB 10:27:06 INFO - Vendor: Intel (0x8086) 10:27:06 INFO - Device ID: 0x0a2e 10:27:06 INFO - Revision ID: 0x0009 10:27:06 INFO - Displays: 10:27:06 INFO - Display: 10:27:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:27:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:27:06 INFO - Display Serial Number: 1600x1200 60 10:27:06 INFO - Main Display: Yes 10:27:06 INFO - Mirror: Off 10:27:06 INFO - Online: Yes 10:27:06 INFO - Rotation: Supported 10:27:06 INFO - Return code: 0 10:27:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:27:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:27:06 INFO - Python 2.7.3 10:27:06 INFO - Return code: 0 10:27:06 INFO - grabbing minidump binary from tooltool 10:27:06 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')]} 10:27:06 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 0x107500d30>, '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 0x107426db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074f5be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:27:06 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:27:06 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 10:27:06 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:27:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:27:06 INFO - Return code: 0 10:27:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:27:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:27:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:27:06 INFO - ENV: RUST_BACKTRACE is now 1 10:27:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:27:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:27:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Qz7uu5y3RZq_OzCRpQSrmQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0075', '--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 10:27:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Qz7uu5y3RZq_OzCRpQSrmQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0075 --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 10:27:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VFaDmm7zrH/Render', 10:27:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:27:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:27:06 INFO - 'HOME': '/Users/cltbld', 10:27:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:27:06 INFO - 'LOGNAME': 'cltbld', 10:27:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:27:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:27:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:27:06 INFO - 'MOZ_NO_REMOTE': '1', 10:27:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:27:06 INFO - 'NO_EM_RESTART': '1', 10:27:06 INFO - 'PAGER': '/bin/cat', 10:27:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:27:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:27:06 INFO - 'PWD': '/builds/slave/test', 10:27:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:27:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:27:06 INFO - 'RUST_BACKTRACE': '1', 10:27:06 INFO - 'SHELL': '/bin/bash', 10:27:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mm6MfOc3cK/Listeners', 10:27:06 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 10:27:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:27:06 INFO - 'USER': 'cltbld', 10:27:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:27:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:27:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:27:06 INFO - 'XPC_FLAGS': '0x0', 10:27:06 INFO - 'XPC_SERVICE_NAME': '0', 10:27:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:27:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Qz7uu5y3RZq_OzCRpQSrmQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0075', '--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 10:27:06 INFO - mozversion application_buildid: 20170417082108 10:27:06 INFO - mozversion application_changeset: befe7c6050b76a46f5d4bed42e28eca8fa6d31ca 10:27:06 INFO - mozversion application_display_name: Nightly 10:27:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:27:06 INFO - mozversion application_name: Firefox 10:27:06 INFO - mozversion application_remotingname: firefox 10:27:06 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 10:27:06 INFO - mozversion application_vendor: Mozilla 10:27:06 INFO - mozversion application_version: 55.0a1 10:27:06 INFO - mozversion platform_buildid: 20170417082108 10:27:06 INFO - mozversion platform_changeset: befe7c6050b76a46f5d4bed42e28eca8fa6d31ca 10:27:06 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 10:27:06 INFO - mozversion platform_version: 55.0a1 10:27:06 INFO - using testdate: 1492450026 10:27:06 INFO - actual date: 1492450026 10:27:06 INFO - starting webserver on 'localhost:49240' 10:27:06 INFO - SUITE-START | Running 2 tests 10:27:06 INFO - TEST-START | dromaeo_css 10:27:06 INFO - operating with platform_type : mac_ 10:27:06 INFO - Initialising browser for dromaeo_css test... 10:27:06 INFO - TEST-INFO | started process 1629 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/tmpmmYAIn/profile http://localhost:49240/getInfo.html) 10:27:09 INFO - PID 1629 | 2017-04-17 10:27:09.683 plugin-container[1632:7712] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c47, name = 'com.apple.tsm.portname' 10:27:09 INFO - PID 1629 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:27:09 INFO - PID 1629 | 2017-04-17 10:27:09.686 plugin-container[1632:7712] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 10:27:09 INFO - PID 1629 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:27:09 INFO - PID 1629 | 2017-04-17 10:27:09.686 plugin-container[1632:7712] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:27:09 INFO - PID 1629 | __metrics Screen width/height:1600/1200 10:27:09 INFO - PID 1629 | colorDepth:24 10:27:09 INFO - PID 1629 | Browser inner width/height: 1024/689 10:27:09 INFO - PID 1629 | __metrics 10:27:09 ERROR - PID 1629 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3374: TypeError: this.tabs is undefined 10:27:10 INFO - TEST-INFO | 1629: exit 0 10:27:10 INFO - Browser initialized. 10:27:10 INFO - Running cycle 1/1 for dromaeo_css test... 10:27:10 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/tmpmmYAIn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:27:13 INFO - PID 1633 | 2017-04-17 10:27:13.520 plugin-container[1634:7847] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 10:27:13 INFO - PID 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:27:13 INFO - PID 1633 | 2017-04-17 10:27:13.520 plugin-container[1634:7847] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d1f, name = 'com.apple.CFPasteboardClient' 10:27:13 INFO - PID 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:27:13 INFO - PID 1633 | 2017-04-17 10:27:13.520 plugin-container[1634:7847] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:27:23 INFO - PID 1633 | RSS: Main: 236683264 10:27:23 INFO - PID 1633 | 10:30:27 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 10:30:29 INFO - PID 1633 | RSS: Main: 254099456 10:30:29 INFO - PID 1633 | 10:32:11 INFO - PID 1633 | ************************* 10:32:11 INFO - PID 1633 | A coding exception was thrown and uncaught in a Task. 10:32:11 INFO - PID 1633 | 10:32:11 ERROR - PID 1633 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:32:11 INFO - PID 1633 | Full stack: 10:32:11 INFO - PID 1633 | ************************* 10:32:11 INFO - PID 1633 | ************************* 10:32:11 INFO - PID 1633 | A coding exception was thrown and uncaught in a Task. 10:32:11 INFO - PID 1633 | 10:32:11 ERROR - PID 1633 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:32:11 INFO - PID 1633 | Full stack: 10:32:11 INFO - PID 1633 | ************************* 10:32:11 INFO - PID 1633 | ************************* 10:32:11 INFO - PID 1633 | A coding exception was thrown and uncaught in a Task. 10:32:11 INFO - PID 1633 | 10:32:11 ERROR - PID 1633 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:32:11 INFO - PID 1633 | Full stack: 10:32:11 INFO - PID 1633 | ************************* 10:32:11 INFO - PID 1633 | ************************* 10:32:11 INFO - PID 1633 | A coding exception was thrown and uncaught in a Task. 10:32:11 INFO - PID 1633 | 10:32:11 ERROR - PID 1633 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:32:11 INFO - PID 1633 | Full stack: 10:32:11 INFO - PID 1633 | ************************* 10:33:31 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 10:33:31 INFO - PID 1633 | RSS: Main: 260825088 10:33:31 INFO - PID 1633 | 10:36:33 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 10:36:34 INFO - PID 1633 | RSS: Main: 269586432 10:36:34 INFO - PID 1633 | 10:39:35 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 10:39:36 INFO - PID 1633 | RSS: Main: 262017024 10:39:36 INFO - PID 1633 | 10:42:39 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 10:42:40 INFO - PID 1633 | RSS: Main: 271450112 10:42:40 INFO - PID 1633 | 10:45:41 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 10:45:41 INFO - PID 1633 | RSS: Main: 266207232 10:45:41 INFO - PID 1633 | 10:45:41 INFO - PID 1633 | __start_tp_report 10:45:41 INFO - PID 1633 | _x_x_mozilla_page_load 10:45:41 INFO - PID 1633 | _x_x_mozilla_page_load_details 10:45:41 INFO - PID 1633 | |i|pagename|runs| 10:45:41 INFO - PID 1633 | |0;dojo.html;3565.4117070683337;3597.8920632381028;3600.6939410150135;3624.9818750906243;3628.6552777486136;4814.4944780798;6076.118962750398;6170.907436388457;6176.536759743015;6243.344448832875;6142.375998161084;6152.323244443108;6172.660503672296;6235.438810507054;6247.125402443661;5221.399539053001;5411.945880541203;5429.0227759003365;5431.2396264522995;5446.509814116738;4742.668013239075;5449.128139497681;5507.449255074481;5527.474889885454;5561.416051314614;4468.4637843458995;5404.378496472917;5461.453854614547;5479;5545.722713864311;4518.5481451854885;5508.807191748309;5513.531349835269;5544.362398324163;5577.247071645316;5444.6188766786345;5448.400675925645;5469.425710300441;5480.945190548084;5492.505674489315;4810.735409552473;5367.275417818622;5502.642328248651;6097;6250.406211409909;6495.253045899687;6571.474282057472;6599.406053455212;6704.93295067048;6722.563033402813;5430.456954304577;6774.051632771416;6823.795286141423;6829.214640316378;6845.247022827485;6329.892268852932;6341.746330146835;6343.904841427382;6355.841103972438;6372.330905254974;6571;6789.694463749143;6848.7260509579055;6903.75836845708;6944.4097251734165;6289.96855015722;6437.25971513282;6456.644884531396;6564.245111812107;6630.9668451657435;6684.63234522106;6721;6793.864122717518;6809.897851532231;6815.2843951385385;6482.416582507597;6701.631410272482;6738.427233685192;6739.157605299338;6752.020956961312;6387.808365749035;6520.510961677893;6532.869342613121;6611.900821487681;6741.797746067626;5372.516473517402;5376.301080859464;5381.67709937405;5408;5436.510714035756;4763.333133361332;4767.308740232646;4777.8327758528285;4778.976105119452;4807.78364973577;4827.806887724451;5898.498627616615;6289.842753931189;6330.272018717815;6439.935600643934;6787.287334829871;6794.694238759268;6823.624700641413;6836.555623884432;6848.14398200225;6375.011873159668;6391.88142075135;6433.774817881352;6439.967800161063;6468.579542329828;5802.82591522255;5861.413858614104;5953.880922381528;5981.940180598225;5986.9401305986385;5696.686682232517;6673.999999999903;6791.558548694319;6798.660066996532;6809.965950170217;6839.62382068991;6842.281560436281;6846.863062738817;6865.0388945546665;6867;5605.495505404533;6875.415589674733;6908.965455172692;6925.792226233019;6944.722211111499;6556.377144171296;6645.335466453316;6661.100751398693;6711.127553418024;6738.427233684995;6784.321567843176;6786.423154031962;6808.557443766139;6817.5909445433435;6825.180978282638;6271.216097987752;6321.115043893767;6459.515536334699;6522.641254731036;6603.174603174603;5793.304803423616;6615.801525954229;6635.834104147242;6638.800835974928;6644.867102658017;6345.682715864281;6425.907595708647;6464.09502669636;6470.967645161744;6477.838054048687;5225.5819534437915;5304.681719096867;6347.904781428375;6568.408843204134;6723.327667233238;6333.556651034568;6385.393387628168;6411.422971932362;6447.806565803034;6464.676766161579 10:45:41 INFO - PID 1633 | |1;ext.html;735.5035351137984;813.8697808350663;836.3936146293937;1283.7689215941132;1292.1213574769165;37751.867443976655;38846.8057659712;38862.22275554487;38885.611143888586;38939.41590876139;39517.85062568432;39528.80235598825;39771.80114099433;39828;39859.402108968316;8011.118776934518;8172.836543269131;8561.14388561142;8812.823743525125;8848.823023539526;6884.827879303032;7330.8900366494545;8776.868346974801;8782.868256976151;8844.867326990068;8087.353011759045;8845.292376609856;8849.469031858109;8857.114288571156;8877.600507977157;8766.430182038179;8836;8860.22795440908;8862.822743545094;8866.290696744245;8610.397272190949;8638.827223455495;8642.265407440374;8675.522846243453;8694;38347.424788628196;38433.23133537314;38509.61490385088;38543.42184867229;38562.03594910121;3648.1973965727498;3687.3547129252543;3691.224842782992;3691.7231207659265;3695.6858667013335;31161.376772464424;31446.89935852257;31525.84237078823;31561.36877262465;31588.894388696513;3183.4428974929297;3216.790908590934;3219.919502012445;3226.935461290761;3228.6932741389764;258502;266617;294832;307447;309117;48984;49272;49396.259056114184;49407.25889111666;49488.51534453972;50639.746801265755;50781;50943.74528127335;50986;50987;14556.344964476624;14643.34104965279;14665.706685866224;14666.706665866623;14692.26538673062;12020;12026.879731202576;12035.458404371824;12057.758844823229;12064.21582597146;13507.581703921143;13779.448822047007;13808.757211850982;13814.309284535733;13871.722565548633;5448.482394172547;6031.185789918305;6045.549068223595;6075.270967483842;6096.542759293071;907.6460180529599;942.2756355868744;992.2805965674828;997.5660587644253;1043.1654676258963;22228.777712222993;22688.09247630109;24181;24406.51186976286;24572.754272457045;941.9529023548795;1092.7650555130526;1107.8892110789018;1125.2741981421937;1148.3913525831322;12512.749745005049;12522.248665080124;12526.060545459128;12550.309732964592;12552.81170782439;338404;338925;339575;340536;340577;54783.45216547863;54831.725841370535;55101.72449137809;55111;55136.17295740567;1507.1936513964977;1523.9847601524064;1573.1033311012613;1576.9605759855865;1578.9763153552706;23798.405039874142;23871.880640596686;23927.16254931069;24076.277711668678;24119.51760964736;39036.609633903296;39248.60751392449;39337.606623933396;39377;39429;292626;292738;292972;293442;293624;34137.82931085329;35175.77890653138;35675.075741668916;35772.4634130483;35850.28299434049;45821.85445363892;46747;46768.53231467778;46813.53186468092;47021.529784701714;28139.155825325273;29798.25504362408;30820.075397738103;31885.6811431892;31893.68106318907;31092.533611995386;31250.531242031844;31851;31961.200969975936;32101.197470063435;36852.81573592115;37206;37311.626883730816;37504.87485375334;37562.436563451025;24246.272611821674;26132.693365332038;29535.55696664595;32301;32431.189220269684;22204.97898480491;22392.32823015312;22757.43106422353;24738.010479580615;24971.75028249694 10:45:41 INFO - PID 1633 | |2;jquery.html;426.55939523788703;426.6053900142368;429.47174974781007;440.76198852619575;453.2295098332833;19760.838265877446;21297.787022129774;21935.780642193575;22107.77892221077;22177.55644887101;21358.466038349048;21509.386795990285;22138.88930555345;22382.440438988993;23531.647025294635;6136.23297087864;6274.278457977345;6295.961166407537;6344.397282258178;6348.650824204667;5874.5594080443925;6574.539782215247;7033.331833475811;7037.929620703779;7046.85906281874;6781.76263830766;6880.759173428931;6960.79117626472;6975.755848545302;7010.1587809462935;6722.462203023746;6873.278305777896;6894.13823272091;6923.511445039316;6953.860922781567;6723.125993620847;6852.554583952027;6858.245592984767;6863.794086177422;6869.175698916162;28750.856245718845;30021.949231776784;30212.24469388261;30307;30657.38685226283;2265.8527195732227;2317.617593097137;2319.0375993962484;2319.7796209360085;2329.208069256444;17235.31058757648;18389.632207355913;18474.63050738978;18516.629667406713;18688.439346819618;1862.6926557118059;2283.212291759357;2323.8954247058755;2324.7094113235844;2352.482453860148;166600.16699916543;167987;169424;169954;172631;19461.053894610708;20370.28703995353;24457;24645;25319.113831016195;41735.16529669389;42091.15817683629;42242;42262.57737422586;42276.78861605672;9914.206863450907;11091.44542772858;11488.023518000897;11532.827007594893;11703.356315402734;7499.700011999568;8933.46399216049;8976.551172441352;9024.593893274818;9144.817103658022;11707.004904583035;11810.940945295219;11824.881751182551;11829.349385783864;11916;3929.2337994090876;4523.117991991596;4620.838270660511;4630.814767409333;4652.651051171176;927.2026057590467;927.5408672707053;938.4791440750449;959.5490119643756;961.8412961861354;20511.38465846027;21032.78967210339;21134.365969020648;21134.837583932724;21224.04491797873;980.2892902645667;996.1930836022844;997.6531682228933;1012.5494155101056;1018.1854516386861;10502.90609054608;13312.933435332761;14027.85972140286;14208.431662733581;14405.85594144066;608506.6597336145;611532;616315;621424;628284;83843;127601;129937;139546;147713;1183.993605435373;1210.0440651885087;1244.402686710366;1248.9313087780079;1250.7248405350808;18508.889466632045;20011.799882000993;21426.357209283746;22672.886635566716;22748.886255568617;38539.807300964436;58614;59268.110978334495;59318.40681593129;59357.10964335625;76215;76256;77502;79411.60294198492;79424;6588.901166482411;7475.4019678426685;8732.388732788644;9162.267018638626;9211.217046551119;13521.391537381038;14263.64495372938;14271.357788899317;14436.711265774837;14702.191379474232;11810.051341528551;11999.40002999864;12002.879971200176;12035.097367697634;12079.456424461096;9067;11298.265612735146;11485.655430337103;11578.321143434323;11717.472713727731;11488.48301826403;11678.474468649101;11691.532338706356;11752.177347585928;11933.52265909354;8693.739187824376;8785.692500762443;8821.2060914518;9093.4998575079;9125.315601329794;11866.406679666155;11890.821637675615;12027.037836972846;12076.879231207575;12096.516139354679 10:45:41 INFO - PID 1633 | |3;mootools.html;1249.6376050945225;1296.7147227609926;1328.6811165320314;1338.0967846703472;1359.1709057474939;11365.488553015113;11673.357965311905;11886.108541859352;11894.881051189486;12009.159358844885;11757.235779674314;11949.58176463824;11953.820692689616;11954.701132471693;12031.398430078483;2518.7355327690602;2544.7200807911113;2558;2558.7825034871967;2560.7311232320617;2207.293666026872;2461.4707837814867;2504.4490212153296;2529.7976161907095;2546.31249562618;2506.373406648338;2508.7114981777054;2519.3323769201174;2539.6825396825398;2545.1346542175656;2437.9024839006506;2497.463045445229;2509.5357358888664;2528.456381877905;2535.2140836340795;2437.427204606916;2513.2711513661015;2535.949281014388;2538;2547.554178018858;12027;12096.81854772179;12167.817482737766;12169.391530423443;12180.81728774069;6605.669716514155;6810.216825065131;6826.965865170692;6835.93164068358;6863.142107236596;4074.8981275468054;4368.104538569617;4399.076193999264;4456.08650226706;4464.352668863029;1249.9875001249961;1253.5675192058725;1262.4129779652415;1296.0085534566065;1302.51806831472;15168.469103581321;15667.059976401339;16755;16829.242684079123;16862.915685421616;5595.468430499096;5679.716014199274;5911.113333000085;5913.733881975321;5937.6734279614175;12352.320622365856;12537.43581538833;12538;12577.748445031048;12581.496740130293;6632.535722499475;6662.86674266512;6682.766103186365;6685.6322902240845;6686.83282917931;6017.398260174035;6147.293061297919;6148.9692551536955;6212.347703491158;6234;4421.690481666253;4453.3320001999955;4494.865154045384;4539.455265368111;4558.430196225472;1113.3375641493299;1190.1014733875868;1244.8443944506937;1329.6609364611961;1345.2399394342165;774.4462709162975;782.471831513726;790.660016193031;792.6908505682788;795.2802713544252;5452.945470545244;6127.846803829851;6717.16035495563;7046.260142685047;7113.82215444618;907.2016625369632;946.7822400847706;952.2096659772373;962.8301613539583;965.652365148546;4178.561251068655;4308.526062133163;4379.321205213197;4477.597016268552;4514.300283456069;11711.88737069977;15140.394384224508;15162.772558411632;15171.620709482351;15205.923970380078;14697.26513674312;15102.924485377722;15142;15201.771973420407;15231.771523427158;8585.18440748128;8742.475451472932;8776.91223087761;8795.604197811113;8802.91197088021;5237;7211.3870321023305;7334.596597186993;7405.629718513945;7458.813529661802;10943.45282735844;11183.105351572018;12102.45538950767;12760.74478510443;12809.359532023547;13496.932515337361;13941.651458713613;14091.859081409055;14137.43450261978;14182.078164919247;9536.284778641768;9549.856752148584;9755.122039016522;9825.55784989663;9887.802243955224;11153.163512736415;11191.888081119085;11267.830982535432;11273.323600583992;11280.887191127984;9054.456732596065;9287.582058807235;9313.487758173365;9313.767155820888;9379.484128373006;8905.599248033983;8973.7756556084;9017.458952462874;9122;9147.63409463614;8273.46222495536;8295.626696798538;8351.749447516584;8380.455270407649;8386.287165590993;6059.969700151471;6093.2078829751845;6144.41628045335;6180.47465965398;6238.126662267377;6066.666333351708;6206.193194884636;6243.844888695606;6279.591826531261;6316.147320111726 10:45:41 INFO - PID 1633 | |4;prototype.html;385.25837762307566;399.9700770515922;405.60453557781165;412.30320757919094;412.93599492078727;5633.887322253552;5836.970815145918;6205.782797602084;6221.720022598983;6246.750129994801;4927.5072492750705;5904.675242861641;6057.242844644419;6060.878782424349;6143.416375444337;3694.408894576868;4432.11357728454;4470.173017991666;4479.566538707615;4497.763115143345;3764.3788774852255;4232.222466564051;4264.8507302244425;4453.332000199964;4468.8212471501265;3947.2894878921784;4164.666826653875;4421.248387774086;4467.977660111695;4499.662525310615;3659.9634003660153;3865.840247925625;3972.8410863565423;4466.955330446686;4480.238359478896;3043.3304672971913;3847.365184744514;3851.306764782338;3858.144972720195;3884.3685652026215;4760.428748550162;5113.565346945476;5817.069268916953;5856;5902.586818922677;4731.432228132611;5015.523525265094;5245.554127899133;5339.129131379757;5339.893202135935;5129.12804823181;5263.3947096083675;5647.322321321427;6265.906011409833;6524.956007038899;4160.687948403882;4358.583460375359;4379.707986144095;4391.846285380028;4399.38408622793;6587.473002159816;6663.433608143314;6756.695948682321;6856.211535673362;6951.270116637731;4016.758994460342;4090.181963607231;4396.868093957122;4407.272799987966;4415.580519850609;4447.844325448594;4498.955010449854;4499.032707967803;4518.774061296922;4524.4842977602875;4499.7975091120325;4954.950450495449;5202.609804264695;5274.841754747364;5315.468453154731;6504.219493660791;6506.512011599149;6529.575577587441;6538.509611779135;6577.309382514863;1519.5517322389921;1541.6762479879126;1542.6683263098487;1543.1127101916218;1546.95359139226;970.2432102959561;1013.5540362240738;1017.2726500551993;1023.4473384372355;1027.9743006424749;4931.876703082452;5469.823987842633;6043.942310095716;6243.469305109116;6275.592086514362;5580.944190558123;6475.352464753487;6721.126253587003;6729.192496900403;6767.39093481589;5401.081816091274;5860.589758716934;6074.696265186812;6262.6868656566985;6278.277998030194;3489.7557170998293;3551.2897420516;3555.5555555555557;3569.910752231215;3570.3754791569772;5456.563474921997;6656.60060396368;6843;6940.009494540659;7010.614416207056;5629.746661400165;5697.945880012211;6072.969635151796;6869.793906182823;6936.4797640177185;6265.436110750145;6621.569597976116;6917.688704008332;6989.860202796017;6997.615131167835;5466.863328416822;6636.502262330248;6725.663716814041;6761.22245941723;6797.4901882358035;5655.208270842003;6304.306526282014;6639.502037347122;6653.767118150841;6676;5771.5671324649975;6661.433778128913;6765.526413151032;6817.795466136024;6924.896126558207;4406.650945281545;4414.448193975753;4443.355713421601;4454;4460.598546130864;5011.874703132305;5458.908218356424;5718.942810572007;6442.516811239082;6720.361565651256;6464.450521705708;6487.318831522811;6494.220693516808;6497.155369801895;6523.933751136958;4687.32033855096;5691.544676425793;6138.508919286357;6140.846478838065;6471.805845824632;5511.614187006871;5740.737037851666;6346.587471814318;6356.618602883842;6396.10454536356;6398.968005160131;6475.384838440341;6524.67376631176;6526.869462610626;6531.444827189742;5689.971550142222;5955.612885162451;6378.394052564999;6390.936090639034;6394.392532709385 10:45:42 INFO - PID 1633 | |5;yui.html;694.5693669924646;825.1047613339534;833.5748768128257;848.4612271207782;862.9180227878353;16329.183540822956;16830.747538786927;16855.41006064788;18145.092745362745;18848;17633.206505707243;18058.187381567826;18202.453926382197;18278.543036424064;18292.176852041655;988.8071825993934;1004.4174378860243;1019.0471908765328;1019.7195771162916;1019.9235057370689;1008.1682611845221;1008.6268080810111;1008.8234558952191;1009.9293049486539;1010.3331801011298;1074.962376316829;1074.968030690538;1075.5805235957944;1076.7415820203173;1076.9299995500267;1059.4437920091975;1062.6068354708807;1066.328213225663;1070.5931745936534;1074.457399013501;757.4054367321626;772.3396495995955;777.8055486128468;789.4197764642983;789.9842003159962;16541.25564349607;16774.412895548718;16784.916075419667;16867.578310542212;16882.240299186567;17134.742978855327;17239.965602063916;17496.125193740263;17570.472885813448;17635.382761603283;13265.66835829112;14092.647683807885;14208.857911420857;14386.640333991629;14411.92794036023;2945.9263518412;3328.900132996014;3590.425531914881;3601.2797440512004;3604.1350075981836;79430;102129;125556;125774;126609;13620.455181792815;13885.791713124312;13903.235322057182;13945.093568917988;13948.16311021342;19484.415467535997;24752.5049499009;25418.85615147323;25836.25374320713;25843;7897.486663366863;8010.359171266285;8022.919770802334;8101.594920253964;8135.227153420416;3709.9443508347395;3950.6839452843706;4047.5142982841658;4068.5524592294823;4083.7549747015273;4373.278409062469;4394.582514661102;4462.821487140543;4517.661175411791;4524.819007239674;791.6121100660635;793.7221972309645;798.7643645124703;801.2708435323827;805.9959700201462;652.9281779004306;665.2748504130521;691.2085661917178;692.4910191009604;695.4505940307201;10251.333663311862;10703.464826758787;11781.175317727848;11987.280763154064;12000;664.551427786252;666.9199696036507;667.9165104361955;672.1195234243157;689.4897775646086;5464.453554644504;5536.197251398525;5573.470520300565;5587.664740115606;5715.085586306171;96769.87146478104;97651.75143954008;98512.50743746235;99392.01215975784;103462.96537034684;85999.57000215084;86742.4583295837;87465.1253487457;89447.55276223578;89454;80860.59569702114;81818;81933.59033204796;82443.5877820619;83000.67997280041;11537.307761534335;11852.585159519376;12132.818007730068;12420.503179872703;12433;41007.58992410038;41606.1678766429;41833.37249941314;41891.162176755686;41902;54491.45508544864;55093.44906550883;55132.72433637806;55426.72286638541;55630.72184639213;11536.884631153916;15132.394704211709;15248.237588120328;15433.691326173639;15453.845461545241;17997.910010449865;18007.00961447133;18022;18243.361482348053;18363.449096527125;16450.83549164493;16474.58813529671;16522;16526.421575244807;16766.91616541958;16255.51233462998;16468.505944821674;16472.588185295463;16608.66782664316;16616.50150495487;14969.326380312694;15048.097114173184;15119.773203402177;15228.010179338307;15232.466863659718;11772.941135294268;11856.585019524275;12009.939950300193;12113.454894529592;12170.817437738619;12526.624201273977;12856.678583035498;12881.227126372447;12940.805887911878;13106.148100373444 10:45:42 INFO - PID 1633 | __end_tp_report 10:45:42 INFO - PID 1633 | __start_cc_report 10:45:42 INFO - PID 1633 | _x_x_mozilla_cycle_collect,418 10:45:42 INFO - PID 1633 | __end_cc_report 10:45:42 INFO - PID 1633 | __startTimestamp1492451141985__endTimestamp 10:45:42 INFO - PID 1633 | 10:45:42 INFO - PID 1633 | ------- Summary: start ------- 10:45:42 INFO - PID 1633 | Number of tests: 6 10:45:42 INFO - PID 1633 | 10:45:42 INFO - PID 1633 | [#0] dojo.html Cycles:165 Average:6106.34 Median:6385.39 stddev:769.65 (12.1%) stddev-sans-first:745.75 10:45:42 INFO - PID 1633 | Values: 3565.4 3597.9 3600.7 3625.0 3628.7 4814.5 6076.1 6170.9 6176.5 6243.3 6142.4 6152.3 6172.7 6235.4 6247.1 5221.4 5411.9 5429.0 5431.2 5446.5 4742.7 5449.1 5507.4 5527.5 5561.4 4468.5 5404.4 5461.5 5479.0 5545.7 4518.5 5508.8 5513.5 5544.4 5577.2 5444.6 5448.4 5469.4 5480.9 5492.5 4810.7 5367.3 5502.6 6097.0 6250.4 6495.3 6571.5 6599.4 6704.9 6722.6 5430.5 6774.1 6823.8 6829.2 6845.2 6329.9 6341.7 6343.9 6355.8 6372.3 6571.0 6789.7 6848.7 6903.8 6944.4 6290.0 6437.3 6456.6 6564.2 6631.0 6684.6 6721.0 6793.9 6809.9 6815.3 6482.4 6701.6 6738.4 6739.2 6752.0 6387.8 6520.5 6532.9 6611.9 6741.8 5372.5 5376.3 5381.7 5408.0 5436.5 4763.3 4767.3 4777.8 4779.0 4807.8 4827.8 5898.5 6289.8 6330.3 6439.9 6787.3 6794.7 6823.6 6836.6 6848.1 6375.0 6391.9 6433.8 6440.0 6468.6 5802.8 5861.4 5953.9 5981.9 5986.9 5696.7 6674.0 6791.6 6798.7 6810.0 6839.6 6842.3 6846.9 6865.0 6867.0 5605.5 6875.4 6909.0 6925.8 6944.7 6556.4 6645.3 6661.1 6711.1 6738.4 6784.3 6786.4 6808.6 6817.6 6825.2 6271.2 6321.1 6459.5 6522.6 6603.2 5793.3 6615.8 6635.8 6638.8 6644.9 6345.7 6425.9 6464.1 6471.0 6477.8 5225.6 5304.7 6347.9 6568.4 6723.3 6333.6 6385.4 6411.4 6447.8 6464.7 10:45:42 INFO - PID 1633 | 10:45:42 INFO - PID 1633 | [#1] ext.html Cycles:180 Average:46035.33 Median:35811.37 stddev:80754.19 (225.5%) stddev-sans-first:80909.10 10:45:42 INFO - PID 1633 | Values: 735.5 813.9 836.4 1283.8 1292.1 37751.9 38846.8 38862.2 38885.6 38939.4 39517.9 39528.8 39771.8 39828.0 39859.4 8011.1 8172.8 8561.1 8812.8 8848.8 6884.8 7330.9 8776.9 8782.9 8844.9 8087.4 8845.3 8849.5 8857.1 8877.6 8766.4 8836.0 8860.2 8862.8 8866.3 8610.4 8638.8 8642.3 8675.5 8694.0 38347.4 38433.2 38509.6 38543.4 38562.0 3648.2 3687.4 3691.2 3691.7 3695.7 31161.4 31446.9 31525.8 31561.4 31588.9 3183.4 3216.8 3219.9 3226.9 3228.7 258502.0 266617.0 294832.0 307447.0 309117.0 48984.0 49272.0 49396.3 49407.3 49488.5 50639.7 50781.0 50943.7 50986.0 50987.0 14556.3 14643.3 14665.7 14666.7 14692.3 12020.0 12026.9 12035.5 12057.8 12064.2 13507.6 13779.4 13808.8 13814.3 13871.7 5448.5 6031.2 6045.5 6075.3 6096.5 907.6 942.3 992.3 997.6 1043.2 22228.8 22688.1 24181.0 24406.5 24572.8 942.0 1092.8 1107.9 1125.3 1148.4 12512.7 12522.2 12526.1 12550.3 12552.8 338404.0 338925.0 339575.0 340536.0 340577.0 54783.5 54831.7 55101.7 55111.0 55136.2 1507.2 1524.0 1573.1 1577.0 1579.0 23798.4 23871.9 23927.2 24076.3 24119.5 39036.6 39248.6 39337.6 39377.0 39429.0 292626.0 292738.0 292972.0 293442.0 293624.0 34137.8 35175.8 35675.1 35772.5 35850.3 45821.9 46747.0 46768.5 46813.5 47021.5 28139.2 29798.3 30820.1 31885.7 31893.7 31092.5 31250.5 31851.0 31961.2 32101.2 36852.8 37206.0 37311.6 37504.9 37562.4 24246.3 26132.7 29535.6 32301.0 32431.2 22205.0 22392.3 22757.4 24738.0 24971.8 10:45:42 INFO - PID 1633 | 10:45:42 INFO - PID 1633 | [#2] jquery.html Cycles:180 Average:39258.00 Median:23994.32 stddev:103929.62 (433.1%) stddev-sans-first:104180.27 10:45:42 INFO - PID 1633 | Values: 426.6 426.6 429.5 440.8 453.2 19760.8 21297.8 21935.8 22107.8 22177.6 21358.5 21509.4 22138.9 22382.4 23531.6 6136.2 6274.3 6296.0 6344.4 6348.7 5874.6 6574.5 7033.3 7037.9 7046.9 6781.8 6880.8 6960.8 6975.8 7010.2 6722.5 6873.3 6894.1 6923.5 6953.9 6723.1 6852.6 6858.2 6863.8 6869.2 28750.9 30021.9 30212.2 30307.0 30657.4 2265.9 2317.6 2319.0 2319.8 2329.2 17235.3 18389.6 18474.6 18516.6 18688.4 1862.7 2283.2 2323.9 2324.7 2352.5 166600.2 167987.0 169424.0 169954.0 172631.0 19461.1 20370.3 24457.0 24645.0 25319.1 41735.2 42091.2 42242.0 42262.6 42276.8 9914.2 11091.4 11488.0 11532.8 11703.4 7499.7 8933.5 8976.6 9024.6 9144.8 11707.0 11810.9 11824.9 11829.3 11916.0 3929.2 4523.1 4620.8 4630.8 4652.7 927.2 927.5 938.5 959.5 961.8 20511.4 21032.8 21134.4 21134.8 21224.0 980.3 996.2 997.7 1012.5 1018.2 10502.9 13312.9 14027.9 14208.4 14405.9 608506.7 611532.0 616315.0 621424.0 628284.0 83843.0 127601.0 129937.0 139546.0 147713.0 1184.0 1210.0 1244.4 1248.9 1250.7 18508.9 20011.8 21426.4 22672.9 22748.9 38539.8 58614.0 59268.1 59318.4 59357.1 76215.0 76256.0 77502.0 79411.6 79424.0 6588.9 7475.4 8732.4 9162.3 9211.2 13521.4 14263.6 14271.4 14436.7 14702.2 11810.1 11999.4 12002.9 12035.1 12079.5 9067.0 11298.3 11485.7 11578.3 11717.5 11488.5 11678.5 11691.5 11752.2 11933.5 8693.7 8785.7 8821.2 9093.5 9125.3 11866.4 11890.8 12027.0 12076.9 12096.5 10:45:42 INFO - PID 1633 | 10:45:42 INFO - PID 1633 | [#3] mootools.html Cycles:180 Average:7192.59 Median:4126.73 stddev:4499.40 (109.0%) stddev-sans-first:4489.85 10:45:42 INFO - PID 1633 | Values: 1249.6 1296.7 1328.7 1338.1 1359.2 11365.5 11673.4 11886.1 11894.9 12009.2 11757.2 11949.6 11953.8 11954.7 12031.4 2518.7 2544.7 2558.0 2558.8 2560.7 2207.3 2461.5 2504.4 2529.8 2546.3 2506.4 2508.7 2519.3 2539.7 2545.1 2437.9 2497.5 2509.5 2528.5 2535.2 2437.4 2513.3 2535.9 2538.0 2547.6 12027.0 12096.8 12167.8 12169.4 12180.8 6605.7 6810.2 6827.0 6835.9 6863.1 4074.9 4368.1 4399.1 4456.1 4464.4 1250.0 1253.6 1262.4 1296.0 1302.5 15168.5 15667.1 16755.0 16829.2 16862.9 5595.5 5679.7 5911.1 5913.7 5937.7 12352.3 12537.4 12538.0 12577.7 12581.5 6632.5 6662.9 6682.8 6685.6 6686.8 6017.4 6147.3 6149.0 6212.3 6234.0 4421.7 4453.3 4494.9 4539.5 4558.4 1113.3 1190.1 1244.8 1329.7 1345.2 774.4 782.5 790.7 792.7 795.3 5452.9 6127.8 6717.2 7046.3 7113.8 907.2 946.8 952.2 962.8 965.7 4178.6 4308.5 4379.3 4477.6 4514.3 11711.9 15140.4 15162.8 15171.6 15205.9 14697.3 15102.9 15142.0 15201.8 15231.8 8585.2 8742.5 8776.9 8795.6 8802.9 5237.0 7211.4 7334.6 7405.6 7458.8 10943.5 11183.1 12102.5 12760.7 12809.4 13496.9 13941.7 14091.9 14137.4 14182.1 9536.3 9549.9 9755.1 9825.6 9887.8 11153.2 11191.9 11267.8 11273.3 11280.9 9054.5 9287.6 9313.5 9313.8 9379.5 8905.6 8973.8 9017.5 9122.0 9147.6 8273.5 8295.6 8351.7 8380.5 8386.3 6060.0 6093.2 6144.4 6180.5 6238.1 6066.7 6206.2 6243.8 6279.6 6316.1 10:45:42 INFO - PID 1633 | 10:45:42 INFO - PID 1633 | [#4] prototype.html Cycles:180 Average:5150.73 Median:5640.60 stddev:1621.38 (28.7%) stddev-sans-first:1585.99 10:45:42 INFO - PID 1633 | Values: 385.3 400.0 405.6 412.3 412.9 5633.9 5837.0 6205.8 6221.7 6246.8 4927.5 5904.7 6057.2 6060.9 6143.4 3694.4 4432.1 4470.2 4479.6 4497.8 3764.4 4232.2 4264.9 4453.3 4468.8 3947.3 4164.7 4421.2 4468.0 4499.7 3660.0 3865.8 3972.8 4467.0 4480.2 3043.3 3847.4 3851.3 3858.1 3884.4 4760.4 5113.6 5817.1 5856.0 5902.6 4731.4 5015.5 5245.6 5339.1 5339.9 5129.1 5263.4 5647.3 6265.9 6525.0 4160.7 4358.6 4379.7 4391.8 4399.4 6587.5 6663.4 6756.7 6856.2 6951.3 4016.8 4090.2 4396.9 4407.3 4415.6 4447.8 4499.0 4499.0 4518.8 4524.5 4499.8 4955.0 5202.6 5274.8 5315.5 6504.2 6506.5 6529.6 6538.5 6577.3 1519.6 1541.7 1542.7 1543.1 1547.0 970.2 1013.6 1017.3 1023.4 1028.0 4931.9 5469.8 6043.9 6243.5 6275.6 5580.9 6475.4 6721.1 6729.2 6767.4 5401.1 5860.6 6074.7 6262.7 6278.3 3489.8 3551.3 3555.6 3569.9 3570.4 5456.6 6656.6 6843.0 6940.0 7010.6 5629.7 5697.9 6073.0 6869.8 6936.5 6265.4 6621.6 6917.7 6989.9 6997.6 5466.9 6636.5 6725.7 6761.2 6797.5 5655.2 6304.3 6639.5 6653.8 6676.0 5771.6 6661.4 6765.5 6817.8 6924.9 4406.7 4414.4 4443.4 4454.0 4460.6 5011.9 5458.9 5718.9 6442.5 6720.4 6464.5 6487.3 6494.2 6497.2 6523.9 4687.3 5691.5 6138.5 6140.8 6471.8 5511.6 5740.7 6346.6 6356.6 6396.1 6399.0 6475.4 6524.7 6526.9 6531.4 5690.0 5955.6 6378.4 6390.9 6394.4 10:45:42 INFO - PID 1633 | 10:45:42 INFO - PID 1633 | [#5] yui.html Cycles:180 Average:21167.59 Median:18040.09 stddev:28971.24 (160.6%) stddev-sans-first:29011.73 10:45:42 INFO - PID 1633 | Values: 694.6 825.1 833.6 848.5 862.9 16329.2 16830.7 16855.4 18145.1 18848.0 17633.2 18058.2 18202.5 18278.5 18292.2 988.8 1004.4 1019.0 1019.7 1019.9 1008.2 1008.6 1008.8 1009.9 1010.3 1075.0 1075.0 1075.6 1076.7 1076.9 1059.4 1062.6 1066.3 1070.6 1074.5 757.4 772.3 777.8 789.4 790.0 16541.3 16774.4 16784.9 16867.6 16882.2 17134.7 17240.0 17496.1 17570.5 17635.4 13265.7 14092.6 14208.9 14386.6 14411.9 2945.9 3328.9 3590.4 3601.3 3604.1 79430.0 102129.0 125556.0 125774.0 126609.0 13620.5 13885.8 13903.2 13945.1 13948.2 19484.4 24752.5 25418.9 25836.3 25843.0 7897.5 8010.4 8022.9 8101.6 8135.2 3709.9 3950.7 4047.5 4068.6 4083.8 4373.3 4394.6 4462.8 4517.7 4524.8 791.6 793.7 798.8 801.3 806.0 652.9 665.3 691.2 692.5 695.5 10251.3 10703.5 11781.2 11987.3 12000.0 664.6 666.9 667.9 672.1 689.5 5464.5 5536.2 5573.5 5587.7 5715.1 96769.9 97651.8 98512.5 99392.0 103463.0 85999.6 86742.5 87465.1 89447.6 89454.0 80860.6 81818.0 81933.6 82443.6 83000.7 11537.3 11852.6 12132.8 12420.5 12433.0 41007.6 41606.2 41833.4 41891.2 41902.0 54491.5 55093.4 55132.7 55426.7 55630.7 11536.9 15132.4 15248.2 15433.7 15453.8 17997.9 18007.0 18022.0 18243.4 18363.4 16450.8 16474.6 16522.0 16526.4 16766.9 16255.5 16468.5 16472.6 16608.7 16616.5 14969.3 15048.1 15119.8 15228.0 15232.5 11772.9 11856.6 12009.9 12113.5 12170.8 12526.6 12856.7 12881.2 12940.8 13106.1 10:45:42 INFO - PID 1633 | -------- Summary: end -------- 10:45:42 INFO - PID 1633 | 10:45:42 INFO - TEST-INFO | 1633: exit 0 10:45:42 INFO - TEST-OK | dromaeo_css | took 1116209ms 10:45:42 INFO - TEST-START | kraken 10:45:42 INFO - operating with platform_type : mac_ 10:45:42 INFO - Initialising browser for kraken test... 10:45:42 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/tmpwxKJob/profile http://localhost:49240/getInfo.html) 10:45:45 INFO - PID 1646 | 2017-04-17 10:45:45.349 plugin-container[1647:10485] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893b, name = 'com.apple.tsm.portname' 10:45:45 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:45:45 INFO - PID 1646 | 2017-04-17 10:45:45.352 plugin-container[1647:10485] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 10:45:45 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:45:45 INFO - PID 1646 | 2017-04-17 10:45:45.352 plugin-container[1647:10485] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:45:45 INFO - PID 1646 | __metrics Screen width/height:1600/1200 10:45:45 INFO - PID 1646 | colorDepth:24 10:45:45 INFO - PID 1646 | Browser inner width/height: 1024/689 10:45:45 INFO - PID 1646 | __metrics 10:45:45 ERROR - PID 1646 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3374: TypeError: this.tabs is undefined 10:45:46 INFO - TEST-INFO | 1646: exit 0 10:45:46 INFO - Browser initialized. 10:45:46 INFO - Running cycle 1/1 for kraken test... 10:45:46 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/tmpwxKJob/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:45:49 INFO - PID 1648 | 2017-04-17 10:45:49.121 plugin-container[1649:10616] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.tsm.portname' 10:45:49 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:45:49 INFO - PID 1648 | 2017-04-17 10:45:49.122 plugin-container[1649:10616] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461f, name = 'com.apple.CFPasteboardClient' 10:45:49 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:45:49 INFO - PID 1648 | 2017-04-17 10:45:49.122 plugin-container[1649:10616] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:45:58 INFO - PID 1648 | RSS: Main: 252456960 10:45:58 INFO - PID 1648 | 10:46:01 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 10:46:02 INFO - PID 1648 | RSS: Main: 260304896 10:46:02 INFO - PID 1648 | 10:46:05 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 10:46:05 INFO - PID 1648 | RSS: Main: 267124736 10:46:05 INFO - PID 1648 | 10:46:09 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 10:46:09 INFO - PID 1648 | RSS: Main: 263286784 10:46:09 INFO - PID 1648 | 10:46:11 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 10:46:12 INFO - PID 1648 | RSS: Main: 264540160 10:46:12 INFO - PID 1648 | 10:46:14 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:46:14 INFO - PID 1648 | RSS: Main: 266104832 10:46:14 INFO - PID 1648 | 10:46:17 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 10:46:18 INFO - PID 1648 | RSS: Main: 271491072 10:46:18 INFO - PID 1648 | 10:46:21 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 10:46:22 INFO - PID 1648 | RSS: Main: 270004224 10:46:22 INFO - PID 1648 | 10:46:25 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 10:46:26 INFO - PID 1648 | RSS: Main: 270749696 10:46:26 INFO - PID 1648 | 10:46:27 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:46:28 INFO - PID 1648 | RSS: Main: 270917632 10:46:28 INFO - PID 1648 | 10:46:30 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:46:30 INFO - PID 1648 | RSS: Main: 271040512 10:46:30 INFO - PID 1648 | 10:46:32 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:46:33 INFO - PID 1648 | RSS: Main: 270606336 10:46:33 INFO - PID 1648 | 10:46:36 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:46:37 INFO - PID 1648 | RSS: Main: 270880768 10:46:37 INFO - PID 1648 | 10:46:40 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:46:40 INFO - PID 1648 | RSS: Main: 270864384 10:46:40 INFO - PID 1648 | 10:46:42 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 10:46:42 INFO - PID 1648 | RSS: Main: 272588800 10:46:42 INFO - PID 1648 | 10:46:42 INFO - PID 1648 | __start_tp_report 10:46:42 INFO - PID 1648 | _x_x_mozilla_page_load 10:46:42 INFO - PID 1648 | _x_x_mozilla_page_load_details 10:46:42 INFO - PID 1648 | |i|pagename|runs| 10:46:42 INFO - PID 1648 | |0;ai-astar;104;116;115;114;115;114;115;114;116;114 10:46:42 INFO - PID 1648 | |1;audio-beat-detection;152;148;102;190;140;111;113;114;140;114 10:46:42 INFO - PID 1648 | |2;audio-dft;194;201;199;208;209;239;197;200;197;197 10:46:42 INFO - PID 1648 | |3;audio-fft;80;64;91;106;69;61;59;60;78;60 10:46:42 INFO - PID 1648 | |4;audio-oscillator;89;76;85;72;72;71;92;71;91;114 10:46:42 INFO - PID 1648 | |5;imaging-gaussian-blur;88;89;89;87;88;89;89;88;87;87 10:46:42 INFO - PID 1648 | |6;imaging-darkroom;100;154;155;107;154;107;165;96;113;157 10:46:42 INFO - PID 1648 | |7;imaging-desaturate;91;95;92;90;92;93;112;140;92;92 10:46:42 INFO - PID 1648 | |8;json-parse-financial;53;52;51;55;52;53;53;53;52;51 10:46:42 INFO - PID 1648 | |9;json-stringify-tinderbox;64;63;65;57;60;64;58;57;57;56 10:46:42 INFO - PID 1648 | |10;stanford-crypto-aes;84;83;82;83;82;84;84;86;83;82 10:46:42 INFO - PID 1648 | |11;stanford-crypto-ccm;141;139;141;175;148;155;130;148;203;139 10:46:42 INFO - PID 1648 | |12;stanford-crypto-pbkdf2;182;186;166;181;227;233;199;185;187;185 10:46:42 INFO - PID 1648 | |13;stanford-crypto-sha256-iterative;70;70;69;70;69;70;70;65;70;99 10:46:42 INFO - PID 1648 | __end_tp_report 10:46:42 INFO - PID 1648 | __start_cc_report 10:46:42 INFO - PID 1648 | _x_x_mozilla_cycle_collect,1004 10:46:42 INFO - PID 1648 | __end_cc_report 10:46:42 INFO - PID 1648 | __startTimestamp1492451202990__endTimestamp 10:46:42 INFO - PID 1648 | 10:46:42 INFO - PID 1648 | ------- Summary: start ------- 10:46:42 INFO - PID 1648 | Number of tests: 14 10:46:42 INFO - PID 1648 | 10:46:42 INFO - PID 1648 | [#0] ai-astar Cycles:10 Average:113.70 Median:115.00 stddev:3.50 (3.0%) stddev-sans-first:0.83 10:46:42 INFO - PID 1648 | Values: 104.0 116.0 115.0 114.0 115.0 114.0 115.0 114.0 116.0 114.0 10:46:42 INFO - PID 1648 | 10:46:42 INFO - PID 1648 | [#1] audio-beat-detection Cycles:10 Average:132.40 Median:140.00 stddev:26.87 (19.2%) stddev-sans-first:27.54 10:46:42 INFO - PID 1648 | Values: 152.0 148.0 102.0 190.0 140.0 111.0 113.0 114.0 140.0 114.0 10:46:42 INFO - PID 1648 | 10:46:42 INFO - PID 1648 | [#2] audio-dft Cycles:10 Average:204.10 Median:200.50 stddev:13.18 (6.6%) stddev-sans-first:13.46 10:46:42 INFO - PID 1648 | Values: 194.0 201.0 199.0 208.0 209.0 239.0 197.0 200.0 197.0 197.0 10:46:42 INFO - PID 1648 | 10:46:42 INFO - PID 1648 | [#3] audio-fft Cycles:10 Average:72.80 Median:66.50 stddev:15.85 (23.8%) stddev-sans-first:16.60 10:46:43 INFO - PID 1648 | Values: 80.0 64.0 91.0 106.0 69.0 61.0 59.0 60.0 78.0 60.0 10:46:43 INFO - PID 1648 | 10:46:43 INFO - PID 1648 | [#4] audio-oscillator Cycles:10 Average:83.30 Median:80.50 stddev:13.84 (17.2%) stddev-sans-first:14.53 10:46:43 INFO - PID 1648 | Values: 89.0 76.0 85.0 72.0 72.0 71.0 92.0 71.0 91.0 114.0 10:46:43 INFO - PID 1648 | 10:46:43 INFO - PID 1648 | [#5] imaging-gaussian-blur Cycles:10 Average:88.10 Median:88.50 stddev:0.88 (1.0%) stddev-sans-first:0.93 10:46:43 INFO - PID 1648 | Values: 88.0 89.0 89.0 87.0 88.0 89.0 89.0 88.0 87.0 87.0 10:46:43 INFO - PID 1648 | 10:46:43 INFO - PID 1648 | [#6] imaging-darkroom Cycles:10 Average:130.80 Median:154.50 stddev:28.14 (18.2%) stddev-sans-first:27.55 10:46:43 INFO - PID 1648 | Values: 100.0 154.0 155.0 107.0 154.0 107.0 165.0 96.0 113.0 157.0 10:46:43 INFO - PID 1648 | 10:46:43 INFO - PID 1648 | [#7] imaging-desaturate Cycles:10 Average:98.90 Median:92.00 stddev:15.79 (17.2%) stddev-sans-first:16.48 10:46:43 INFO - PID 1648 | Values: 91.0 95.0 92.0 90.0 92.0 93.0 112.0 140.0 92.0 92.0 10:46:43 INFO - PID 1648 | 10:46:43 INFO - PID 1648 | [#8] json-parse-financial Cycles:10 Average:52.50 Median:53.00 stddev:1.18 (2.2%) stddev-sans-first:1.24 10:46:43 INFO - PID 1648 | Values: 53.0 52.0 51.0 55.0 52.0 53.0 53.0 53.0 52.0 51.0 10:46:43 INFO - PID 1648 | 10:46:43 INFO - PID 1648 | [#9] json-stringify-tinderbox Cycles:10 Average:60.10 Median:61.50 stddev:3.54 (5.8%) stddev-sans-first:3.46 10:46:43 INFO - PID 1648 | Values: 64.0 63.0 65.0 57.0 60.0 64.0 58.0 57.0 57.0 56.0 10:46:43 INFO - PID 1648 | 10:46:43 INFO - PID 1648 | [#10] stanford-crypto-aes Cycles:10 Average:83.30 Median:83.50 stddev:1.25 (1.5%) stddev-sans-first:1.30 10:46:43 INFO - PID 1648 | Values: 84.0 83.0 82.0 83.0 82.0 84.0 84.0 86.0 83.0 82.0 10:46:43 INFO - PID 1648 | 10:46:43 INFO - PID 1648 | [#11] stanford-crypto-ccm Cycles:10 Average:151.90 Median:148.00 stddev:21.69 (14.7%) stddev-sans-first:22.65 10:46:43 INFO - PID 1648 | Values: 141.0 139.0 141.0 175.0 148.0 155.0 130.0 148.0 203.0 139.0 10:46:43 INFO - PID 1648 | 10:46:43 INFO - PID 1648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.10 Median:186.50 stddev:21.08 (11.3%) stddev-sans-first:21.97 10:46:43 INFO - PID 1648 | Values: 182.0 186.0 166.0 181.0 227.0 233.0 199.0 185.0 187.0 185.0 10:46:43 INFO - PID 1648 | 10:46:43 INFO - PID 1648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.20 Median:70.00 stddev:9.54 (13.6%) stddev-sans-first:10.09 10:46:43 INFO - PID 1648 | Values: 70.0 70.0 69.0 70.0 69.0 70.0 70.0 65.0 70.0 99.0 10:46:43 INFO - PID 1648 | -------- Summary: end -------- 10:46:43 INFO - PID 1648 | 10:46:44 INFO - TEST-INFO | 1648: exit 0 10:46:44 INFO - TEST-OK | kraken | took 61418ms 10:46:44 INFO - SUITE-END | took 1177s 10:46:44 INFO - Completed test suite (00:19:37) 10:46:44 INFO - javascript benchmark 10:46:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8022.572562771561, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3565.4117070683337, 3597.8920632381028, 3600.6939410150135, 3624.9818750906243, 3628.6552777486136, 4814.4944780798, 6076.118962750398, 6170.907436388457, 6176.536759743015, 6243.344448832875, 6142.375998161084, 6152.323244443108, 6172.660503672296, 6235.438810507054, 6247.125402443661, 5221.399539053001, 5411.945880541203, 5429.0227759003365, 5431.2396264522995, 5446.509814116738, 4742.668013239075, 5449.128139497681, 5507.449255074481, 5527.474889885454, 5561.416051314614, 4468.4637843458995, 5404.378496472917, 5461.453854614547, 5479.0, 5545.722713864311, 4518.5481451854885, 5508.807191748309, 5513.531349835269, 5544.362398324163, 5577.247071645316, 5444.6188766786345, 5448.400675925645, 5469.425710300441, 5480.945190548084, 5492.505674489315, 4810.735409552473, 5367.275417818622, 5502.642328248651, 6097.0, 6250.406211409909, 6495.253045899687, 6571.474282057472, 6599.406053455212, 6704.93295067048, 6722.563033402813, 5430.456954304577, 6774.051632771416, 6823.795286141423, 6829.214640316378, 6845.247022827485, 6329.892268852932, 6341.746330146835, 6343.904841427382, 6355.841103972438, 6372.330905254974, 6571.0, 6789.694463749143, 6848.7260509579055, 6903.75836845708, 6944.4097251734165, 6289.96855015722, 6437.25971513282, 6456.644884531396, 6564.245111812107, 6630.9668451657435, 6684.63234522106, 6721.0, 6793.864122717518, 6809.897851532231, 6815.2843951385385, 6482.416582507597, 6701.631410272482, 6738.427233685192, 6739.157605299338, 6752.020956961312, 6387.808365749035, 6520.510961677893, 6532.869342613121, 6611.900821487681, 6741.797746067626, 5372.516473517402, 5376.301080859464, 5381.67709937405, 5408.0, 5436.510714035756, 4763.333133361332, 4767.308740232646, 4777.8327758528285, 4778.976105119452, 4807.78364973577, 4827.806887724451, 5898.498627616615, 6289.842753931189, 6330.272018717815, 6439.935600643934, 6787.287334829871, 6794.694238759268, 6823.624700641413, 6836.555623884432, 6848.14398200225, 6375.011873159668, 6391.88142075135, 6433.774817881352, 6439.967800161063, 6468.579542329828, 5802.82591522255, 5861.413858614104, 5953.880922381528, 5981.940180598225, 5986.9401305986385, 5696.686682232517, 6673.999999999903, 6791.558548694319, 6798.660066996532, 6809.965950170217, 6839.62382068991, 6842.281560436281, 6846.863062738817, 6865.0388945546665, 6867.0, 5605.495505404533, 6875.415589674733, 6908.965455172692, 6925.792226233019, 6944.722211111499, 6556.377144171296, 6645.335466453316, 6661.100751398693, 6711.127553418024, 6738.427233684995, 6784.321567843176, 6786.423154031962, 6808.557443766139, 6817.5909445433435, 6825.180978282638, 6271.216097987752, 6321.115043893767, 6459.515536334699, 6522.641254731036, 6603.174603174603, 5793.304803423616, 6615.801525954229, 6635.834104147242, 6638.800835974928, 6644.867102658017, 6345.682715864281, 6425.907595708647, 6464.09502669636, 6470.967645161744, 6477.838054048687, 5225.5819534437915, 5304.681719096867, 6347.904781428375, 6568.408843204134, 6723.327667233238, 6333.556651034568, 6385.393387628168, 6411.422971932362, 6447.806565803034, 6464.676766161579], "value": 6058.128295873956, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [735.5035351137984, 813.8697808350663, 836.3936146293937, 1283.7689215941132, 1292.1213574769165, 37751.867443976655, 38846.8057659712, 38862.22275554487, 38885.611143888586, 38939.41590876139, 39517.85062568432, 39528.80235598825, 39771.80114099433, 39828.0, 39859.402108968316, 8011.118776934518, 8172.836543269131, 8561.14388561142, 8812.823743525125, 8848.823023539526, 6884.827879303032, 7330.8900366494545, 8776.868346974801, 8782.868256976151, 8844.867326990068, 8087.353011759045, 8845.292376609856, 8849.469031858109, 8857.114288571156, 8877.600507977157, 8766.430182038179, 8836.0, 8860.22795440908, 8862.822743545094, 8866.290696744245, 8610.397272190949, 8638.827223455495, 8642.265407440374, 8675.522846243453, 8694.0, 38347.424788628196, 38433.23133537314, 38509.61490385088, 38543.42184867229, 38562.03594910121, 3648.1973965727498, 3687.3547129252543, 3691.224842782992, 3691.7231207659265, 3695.6858667013335, 31161.376772464424, 31446.89935852257, 31525.84237078823, 31561.36877262465, 31588.894388696513, 3183.4428974929297, 3216.790908590934, 3219.919502012445, 3226.935461290761, 3228.6932741389764, 258502.0, 266617.0, 294832.0, 307447.0, 309117.0, 48984.0, 49272.0, 49396.259056114184, 49407.25889111666, 49488.51534453972, 50639.746801265755, 50781.0, 50943.74528127335, 50986.0, 50987.0, 14556.344964476624, 14643.34104965279, 14665.706685866224, 14666.706665866623, 14692.26538673062, 12020.0, 12026.879731202576, 12035.458404371824, 12057.758844823229, 12064.21582597146, 13507.581703921143, 13779.448822047007, 13808.757211850982, 13814.309284535733, 13871.722565548633, 5448.482394172547, 6031.185789918305, 6045.549068223595, 6075.270967483842, 6096.542759293071, 907.6460180529599, 942.2756355868744, 992.2805965674828, 997.5660587644253, 1043.1654676258963, 22228.777712222993, 22688.09247630109, 24181.0, 24406.51186976286, 24572.754272457045, 941.9529023548795, 1092.7650555130526, 1107.8892110789018, 1125.2741981421937, 1148.3913525831322, 12512.749745005049, 12522.248665080124, 12526.060545459128, 12550.309732964592, 12552.81170782439, 338404.0, 338925.0, 339575.0, 340536.0, 340577.0, 54783.45216547863, 54831.725841370535, 55101.72449137809, 55111.0, 55136.17295740567, 1507.1936513964977, 1523.9847601524064, 1573.1033311012613, 1576.9605759855865, 1578.9763153552706, 23798.405039874142, 23871.880640596686, 23927.16254931069, 24076.277711668678, 24119.51760964736, 39036.609633903296, 39248.60751392449, 39337.606623933396, 39377.0, 39429.0, 292626.0, 292738.0, 292972.0, 293442.0, 293624.0, 34137.82931085329, 35175.77890653138, 35675.075741668916, 35772.4634130483, 35850.28299434049, 45821.85445363892, 46747.0, 46768.53231467778, 46813.53186468092, 47021.529784701714, 28139.155825325273, 29798.25504362408, 30820.075397738103, 31885.6811431892, 31893.68106318907, 31092.533611995386, 31250.531242031844, 31851.0, 31961.200969975936, 32101.197470063435, 36852.81573592115, 37206.0, 37311.626883730816, 37504.87485375334, 37562.436563451025, 24246.272611821674, 26132.693365332038, 29535.55696664595, 32301.0, 32431.189220269684, 22204.97898480491, 22392.32823015312, 22757.43106422353, 24738.010479580615, 24971.75028249694], "value": 17806.490035196824, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.55939523788703, 426.6053900142368, 429.47174974781007, 440.76198852619575, 453.2295098332833, 19760.838265877446, 21297.787022129774, 21935.780642193575, 22107.77892221077, 22177.55644887101, 21358.466038349048, 21509.386795990285, 22138.88930555345, 22382.440438988993, 23531.647025294635, 6136.23297087864, 6274.278457977345, 6295.961166407537, 6344.397282258178, 6348.650824204667, 5874.5594080443925, 6574.539782215247, 7033.331833475811, 7037.929620703779, 7046.85906281874, 6781.76263830766, 6880.759173428931, 6960.79117626472, 6975.755848545302, 7010.1587809462935, 6722.462203023746, 6873.278305777896, 6894.13823272091, 6923.511445039316, 6953.860922781567, 6723.125993620847, 6852.554583952027, 6858.245592984767, 6863.794086177422, 6869.175698916162, 28750.856245718845, 30021.949231776784, 30212.24469388261, 30307.0, 30657.38685226283, 2265.8527195732227, 2317.617593097137, 2319.0375993962484, 2319.7796209360085, 2329.208069256444, 17235.31058757648, 18389.632207355913, 18474.63050738978, 18516.629667406713, 18688.439346819618, 1862.6926557118059, 2283.212291759357, 2323.8954247058755, 2324.7094113235844, 2352.482453860148, 166600.16699916543, 167987.0, 169424.0, 169954.0, 172631.0, 19461.053894610708, 20370.28703995353, 24457.0, 24645.0, 25319.113831016195, 41735.16529669389, 42091.15817683629, 42242.0, 42262.57737422586, 42276.78861605672, 9914.206863450907, 11091.44542772858, 11488.023518000897, 11532.827007594893, 11703.356315402734, 7499.700011999568, 8933.46399216049, 8976.551172441352, 9024.593893274818, 9144.817103658022, 11707.004904583035, 11810.940945295219, 11824.881751182551, 11829.349385783864, 11916.0, 3929.2337994090876, 4523.117991991596, 4620.838270660511, 4630.814767409333, 4652.651051171176, 927.2026057590467, 927.5408672707053, 938.4791440750449, 959.5490119643756, 961.8412961861354, 20511.38465846027, 21032.78967210339, 21134.365969020648, 21134.837583932724, 21224.04491797873, 980.2892902645667, 996.1930836022844, 997.6531682228933, 1012.5494155101056, 1018.1854516386861, 10502.90609054608, 13312.933435332761, 14027.85972140286, 14208.431662733581, 14405.85594144066, 608506.6597336145, 611532.0, 616315.0, 621424.0, 628284.0, 83843.0, 127601.0, 129937.0, 139546.0, 147713.0, 1183.993605435373, 1210.0440651885087, 1244.402686710366, 1248.9313087780079, 1250.7248405350808, 18508.889466632045, 20011.799882000993, 21426.357209283746, 22672.886635566716, 22748.886255568617, 38539.807300964436, 58614.0, 59268.110978334495, 59318.40681593129, 59357.10964335625, 76215.0, 76256.0, 77502.0, 79411.60294198492, 79424.0, 6588.901166482411, 7475.4019678426685, 8732.388732788644, 9162.267018638626, 9211.217046551119, 13521.391537381038, 14263.64495372938, 14271.357788899317, 14436.711265774837, 14702.191379474232, 11810.051341528551, 11999.40002999864, 12002.879971200176, 12035.097367697634, 12079.456424461096, 9067.0, 11298.265612735146, 11485.655430337103, 11578.321143434323, 11717.472713727731, 11488.48301826403, 11678.474468649101, 11691.532338706356, 11752.177347585928, 11933.52265909354, 8693.739187824376, 8785.692500762443, 8821.2060914518, 9093.4998575079, 9125.315601329794, 11866.406679666155, 11890.821637675615, 12027.037836972846, 12076.879231207575, 12096.516139354679], "value": 11711.89497776183, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1249.6376050945225, 1296.7147227609926, 1328.6811165320314, 1338.0967846703472, 1359.1709057474939, 11365.488553015113, 11673.357965311905, 11886.108541859352, 11894.881051189486, 12009.159358844885, 11757.235779674314, 11949.58176463824, 11953.820692689616, 11954.701132471693, 12031.398430078483, 2518.7355327690602, 2544.7200807911113, 2558.0, 2558.7825034871967, 2560.7311232320617, 2207.293666026872, 2461.4707837814867, 2504.4490212153296, 2529.7976161907095, 2546.31249562618, 2506.373406648338, 2508.7114981777054, 2519.3323769201174, 2539.6825396825398, 2545.1346542175656, 2437.9024839006506, 2497.463045445229, 2509.5357358888664, 2528.456381877905, 2535.2140836340795, 2437.427204606916, 2513.2711513661015, 2535.949281014388, 2538.0, 2547.554178018858, 12027.0, 12096.81854772179, 12167.817482737766, 12169.391530423443, 12180.81728774069, 6605.669716514155, 6810.216825065131, 6826.965865170692, 6835.93164068358, 6863.142107236596, 4074.8981275468054, 4368.104538569617, 4399.076193999264, 4456.08650226706, 4464.352668863029, 1249.9875001249961, 1253.5675192058725, 1262.4129779652415, 1296.0085534566065, 1302.51806831472, 15168.469103581321, 15667.059976401339, 16755.0, 16829.242684079123, 16862.915685421616, 5595.468430499096, 5679.716014199274, 5911.113333000085, 5913.733881975321, 5937.6734279614175, 12352.320622365856, 12537.43581538833, 12538.0, 12577.748445031048, 12581.496740130293, 6632.535722499475, 6662.86674266512, 6682.766103186365, 6685.6322902240845, 6686.83282917931, 6017.398260174035, 6147.293061297919, 6148.9692551536955, 6212.347703491158, 6234.0, 4421.690481666253, 4453.3320001999955, 4494.865154045384, 4539.455265368111, 4558.430196225472, 1113.3375641493299, 1190.1014733875868, 1244.8443944506937, 1329.6609364611961, 1345.2399394342165, 774.4462709162975, 782.471831513726, 790.660016193031, 792.6908505682788, 795.2802713544252, 5452.945470545244, 6127.846803829851, 6717.16035495563, 7046.260142685047, 7113.82215444618, 907.2016625369632, 946.7822400847706, 952.2096659772373, 962.8301613539583, 965.652365148546, 4178.561251068655, 4308.526062133163, 4379.321205213197, 4477.597016268552, 4514.300283456069, 11711.88737069977, 15140.394384224508, 15162.772558411632, 15171.620709482351, 15205.923970380078, 14697.26513674312, 15102.924485377722, 15142.0, 15201.771973420407, 15231.771523427158, 8585.18440748128, 8742.475451472932, 8776.91223087761, 8795.604197811113, 8802.91197088021, 5237.0, 7211.3870321023305, 7334.596597186993, 7405.629718513945, 7458.813529661802, 10943.45282735844, 11183.105351572018, 12102.45538950767, 12760.74478510443, 12809.359532023547, 13496.932515337361, 13941.651458713613, 14091.859081409055, 14137.43450261978, 14182.078164919247, 9536.284778641768, 9549.856752148584, 9755.122039016522, 9825.55784989663, 9887.802243955224, 11153.163512736415, 11191.888081119085, 11267.830982535432, 11273.323600583992, 11280.887191127984, 9054.456732596065, 9287.582058807235, 9313.487758173365, 9313.767155820888, 9379.484128373006, 8905.599248033983, 8973.7756556084, 9017.458952462874, 9122.0, 9147.63409463614, 8273.46222495536, 8295.626696798538, 8351.749447516584, 8380.455270407649, 8386.287165590993, 6059.969700151471, 6093.2078829751845, 6144.41628045335, 6180.47465965398, 6238.126662267377, 6066.666333351708, 6206.193194884636, 6243.844888695606, 6279.591826531261, 6316.147320111726], "value": 5442.781231608286, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.25837762307566, 399.9700770515922, 405.60453557781165, 412.30320757919094, 412.93599492078727, 5633.887322253552, 5836.970815145918, 6205.782797602084, 6221.720022598983, 6246.750129994801, 4927.5072492750705, 5904.675242861641, 6057.242844644419, 6060.878782424349, 6143.416375444337, 3694.408894576868, 4432.11357728454, 4470.173017991666, 4479.566538707615, 4497.763115143345, 3764.3788774852255, 4232.222466564051, 4264.8507302244425, 4453.332000199964, 4468.8212471501265, 3947.2894878921784, 4164.666826653875, 4421.248387774086, 4467.977660111695, 4499.662525310615, 3659.9634003660153, 3865.840247925625, 3972.8410863565423, 4466.955330446686, 4480.238359478896, 3043.3304672971913, 3847.365184744514, 3851.306764782338, 3858.144972720195, 3884.3685652026215, 4760.428748550162, 5113.565346945476, 5817.069268916953, 5856.0, 5902.586818922677, 4731.432228132611, 5015.523525265094, 5245.554127899133, 5339.129131379757, 5339.893202135935, 5129.12804823181, 5263.3947096083675, 5647.322321321427, 6265.906011409833, 6524.956007038899, 4160.687948403882, 4358.583460375359, 4379.707986144095, 4391.846285380028, 4399.38408622793, 6587.473002159816, 6663.433608143314, 6756.695948682321, 6856.211535673362, 6951.270116637731, 4016.758994460342, 4090.181963607231, 4396.868093957122, 4407.272799987966, 4415.580519850609, 4447.844325448594, 4498.955010449854, 4499.032707967803, 4518.774061296922, 4524.4842977602875, 4499.7975091120325, 4954.950450495449, 5202.609804264695, 5274.841754747364, 5315.468453154731, 6504.219493660791, 6506.512011599149, 6529.575577587441, 6538.509611779135, 6577.309382514863, 1519.5517322389921, 1541.6762479879126, 1542.6683263098487, 1543.1127101916218, 1546.95359139226, 970.2432102959561, 1013.5540362240738, 1017.2726500551993, 1023.4473384372355, 1027.9743006424749, 4931.876703082452, 5469.823987842633, 6043.942310095716, 6243.469305109116, 6275.592086514362, 5580.944190558123, 6475.352464753487, 6721.126253587003, 6729.192496900403, 6767.39093481589, 5401.081816091274, 5860.589758716934, 6074.696265186812, 6262.6868656566985, 6278.277998030194, 3489.7557170998293, 3551.2897420516, 3555.5555555555557, 3569.910752231215, 3570.3754791569772, 5456.563474921997, 6656.60060396368, 6843.0, 6940.009494540659, 7010.614416207056, 5629.746661400165, 5697.945880012211, 6072.969635151796, 6869.793906182823, 6936.4797640177185, 6265.436110750145, 6621.569597976116, 6917.688704008332, 6989.860202796017, 6997.615131167835, 5466.863328416822, 6636.502262330248, 6725.663716814041, 6761.22245941723, 6797.4901882358035, 5655.208270842003, 6304.306526282014, 6639.502037347122, 6653.767118150841, 6676.0, 5771.5671324649975, 6661.433778128913, 6765.526413151032, 6817.795466136024, 6924.896126558207, 4406.650945281545, 4414.448193975753, 4443.355713421601, 4454.0, 4460.598546130864, 5011.874703132305, 5458.908218356424, 5718.942810572007, 6442.516811239082, 6720.361565651256, 6464.450521705708, 6487.318831522811, 6494.220693516808, 6497.155369801895, 6523.933751136958, 4687.32033855096, 5691.544676425793, 6138.508919286357, 6140.846478838065, 6471.805845824632, 5511.614187006871, 5740.737037851666, 6346.587471814318, 6356.618602883842, 6396.10454536356, 6398.968005160131, 6475.384838440341, 6524.67376631176, 6526.869462610626, 6531.444827189742, 5689.971550142222, 5955.612885162451, 6378.394052564999, 6390.936090639034, 6394.392532709385], "value": 4657.833909283849, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.5693669924646, 825.1047613339534, 833.5748768128257, 848.4612271207782, 862.9180227878353, 16329.183540822956, 16830.747538786927, 16855.41006064788, 18145.092745362745, 18848.0, 17633.206505707243, 18058.187381567826, 18202.453926382197, 18278.543036424064, 18292.176852041655, 988.8071825993934, 1004.4174378860243, 1019.0471908765328, 1019.7195771162916, 1019.9235057370689, 1008.1682611845221, 1008.6268080810111, 1008.8234558952191, 1009.9293049486539, 1010.3331801011298, 1074.962376316829, 1074.968030690538, 1075.5805235957944, 1076.7415820203173, 1076.9299995500267, 1059.4437920091975, 1062.6068354708807, 1066.328213225663, 1070.5931745936534, 1074.457399013501, 757.4054367321626, 772.3396495995955, 777.8055486128468, 789.4197764642983, 789.9842003159962, 16541.25564349607, 16774.412895548718, 16784.916075419667, 16867.578310542212, 16882.240299186567, 17134.742978855327, 17239.965602063916, 17496.125193740263, 17570.472885813448, 17635.382761603283, 13265.66835829112, 14092.647683807885, 14208.857911420857, 14386.640333991629, 14411.92794036023, 2945.9263518412, 3328.900132996014, 3590.425531914881, 3601.2797440512004, 3604.1350075981836, 79430.0, 102129.0, 125556.0, 125774.0, 126609.0, 13620.455181792815, 13885.791713124312, 13903.235322057182, 13945.093568917988, 13948.16311021342, 19484.415467535997, 24752.5049499009, 25418.85615147323, 25836.25374320713, 25843.0, 7897.486663366863, 8010.359171266285, 8022.919770802334, 8101.594920253964, 8135.227153420416, 3709.9443508347395, 3950.6839452843706, 4047.5142982841658, 4068.5524592294823, 4083.7549747015273, 4373.278409062469, 4394.582514661102, 4462.821487140543, 4517.661175411791, 4524.819007239674, 791.6121100660635, 793.7221972309645, 798.7643645124703, 801.2708435323827, 805.9959700201462, 652.9281779004306, 665.2748504130521, 691.2085661917178, 692.4910191009604, 695.4505940307201, 10251.333663311862, 10703.464826758787, 11781.175317727848, 11987.280763154064, 12000.0, 664.551427786252, 666.9199696036507, 667.9165104361955, 672.1195234243157, 689.4897775646086, 5464.453554644504, 5536.197251398525, 5573.470520300565, 5587.664740115606, 5715.085586306171, 96769.87146478104, 97651.75143954008, 98512.50743746235, 99392.01215975784, 103462.96537034684, 85999.57000215084, 86742.4583295837, 87465.1253487457, 89447.55276223578, 89454.0, 80860.59569702114, 81818.0, 81933.59033204796, 82443.5877820619, 83000.67997280041, 11537.307761534335, 11852.585159519376, 12132.818007730068, 12420.503179872703, 12433.0, 41007.58992410038, 41606.1678766429, 41833.37249941314, 41891.162176755686, 41902.0, 54491.45508544864, 55093.44906550883, 55132.72433637806, 55426.72286638541, 55630.72184639213, 11536.884631153916, 15132.394704211709, 15248.237588120328, 15433.691326173639, 15453.845461545241, 17997.910010449865, 18007.00961447133, 18022.0, 18243.361482348053, 18363.449096527125, 16450.83549164493, 16474.58813529671, 16522.0, 16526.421575244807, 16766.91616541958, 16255.51233462998, 16468.505944821674, 16472.588185295463, 16608.66782664316, 16616.50150495487, 14969.326380312694, 15048.097114173184, 15119.773203402177, 15228.010179338307, 15232.466863659718, 11772.941135294268, 11856.585019524275, 12009.939950300193, 12113.454894529592, 12170.817437738619, 12526.624201273977, 12856.678583035498, 12881.227126372447, 12940.805887911878, 13106.148100373444], "value": 8323.376379229943, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1537.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 116.0, 115.0, 114.0, 115.0, 114.0, 115.0, 114.0, 116.0, 114.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 148.0, 102.0, 190.0, 140.0, 111.0, 113.0, 114.0, 140.0, 114.0], "value": 132.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 201.0, 199.0, 208.0, 209.0, 239.0, 197.0, 200.0, 197.0, 197.0], "value": 204.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 64.0, 91.0, 106.0, 69.0, 61.0, 59.0, 60.0, 78.0, 60.0], "value": 72.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 76.0, 85.0, 72.0, 72.0, 71.0, 92.0, 71.0, 91.0, 114.0], "value": 83.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 89.0, 87.0, 88.0, 89.0, 89.0, 88.0, 87.0, 87.0], "value": 88.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 154.0, 155.0, 107.0, 154.0, 107.0, 165.0, 96.0, 113.0, 157.0], "value": 130.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 95.0, 92.0, 90.0, 92.0, 93.0, 112.0, 140.0, 92.0, 92.0], "value": 98.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 51.0, 55.0, 52.0, 53.0, 53.0, 53.0, 52.0, 51.0], "value": 52.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 65.0, 57.0, 60.0, 64.0, 58.0, 57.0, 57.0, 56.0], "value": 60.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 82.0, 83.0, 82.0, 84.0, 84.0, 86.0, 83.0, 82.0], "value": 83.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 139.0, 141.0, 175.0, 148.0, 155.0, 130.0, 148.0, 203.0, 139.0], "value": 151.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 186.0, 166.0, 181.0, 227.0, 233.0, 199.0, 185.0, 187.0, 185.0], "value": 193.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 69.0, 70.0, 69.0, 70.0, 70.0, 65.0, 70.0, 99.0], "value": 72.2, "unit": "score"}]}]} 10:46:44 INFO - Return code: 0 10:46:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:46:44 ERROR - # TBPL SUCCESS # 10:46:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:46:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:46:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:46:44 INFO - 2017-04-17 10:46:44.268 screenresolution[1654:11009] starting screenresolution argv=screenresolution get 10:46:44 INFO - 2017-04-17 10:46:44.270 screenresolution[1654:11009] Display 0: 1600x1200x32@60 10:46:44 INFO - 2017-04-17 10:46:44.275 screenresolution[1655:11012] starting screenresolution argv=screenresolution list 10:46:44 INFO - Available Modes on Display 0 10:46:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:46:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:46:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:46:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:46:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:46:44 INFO - Intel Iris: 10:46:44 INFO - Chipset Model: Intel Iris 10:46:44 INFO - Type: GPU 10:46:44 INFO - Bus: Built-In 10:46:44 INFO - VRAM (Dynamic, Max): 1536 MB 10:46:44 INFO - Vendor: Intel (0x8086) 10:46:44 INFO - Device ID: 0x0a2e 10:46:44 INFO - Revision ID: 0x0009 10:46:44 INFO - Displays: 10:46:44 INFO - Display: 10:46:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:46:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:46:44 INFO - Display Serial Number: 1600x1200 60 10:46:44 INFO - Main Display: Yes 10:46:44 INFO - Mirror: Off 10:46:44 INFO - Online: Yes 10:46:44 INFO - Rotation: Supported 10:46:44 INFO - Return code: 0 10:46:44 INFO - Running post-action listener: _resource_record_post_action 10:46:44 INFO - [mozharness: 2017-04-17 17:46:44.524961Z] Finished run-tests step (success) 10:46:44 INFO - Running post-run listener: _resource_record_post_run 10:46:44 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 43629568; Write bytes: 519944192; Read time: 451; Write time: 1663 10:46:44 INFO - TinderboxPrint: CPU usage
53.9% 10:46:44 INFO - TinderboxPrint: I/O read bytes / time
43,629,568 / 451 10:46:44 INFO - TinderboxPrint: I/O write bytes / time
519,944,192 / 1,663 10:46:44 INFO - TinderboxPrint: CPU idle
2,207.6 (46.1%) 10:46:44 INFO - TinderboxPrint: CPU system
958.4 (20.0%) 10:46:44 INFO - TinderboxPrint: CPU user
1,627.6 (34.0%) 10:46:44 INFO - TinderboxPrint: Swap in / out
262,979,584 / 0 10:46:44 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 206029312; Write bytes: 206002176; Read time: 15886; Write time: 431 10:46:44 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 38718464; Write bytes: 306737152; Read time: 416; Write time: 1206 10:46:45 INFO - Running post-run listener: _upload_blobber_files 10:46:45 INFO - Blob upload gear active. 10:46:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:46:45 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:46:45 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:46:45 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:46:45 INFO - (blobuploader) - INFO - Open directory for files ... 10:46:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:46:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:46:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:46:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:46:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:46:47 INFO - (blobuploader) - INFO - Done attempting. 10:46:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:46:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:46:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:46:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:46:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:46:48 INFO - (blobuploader) - INFO - Done attempting. 10:46:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:46:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:46:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:46:49 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:46:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:46:49 INFO - (blobuploader) - INFO - Done attempting. 10:46:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:46:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:46:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:46:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:46:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:46:51 INFO - (blobuploader) - INFO - Done attempting. 10:46:51 INFO - (blobuploader) - INFO - Iteration through files over. 10:46:51 INFO - Return code: 0 10:46:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:46:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:46:51 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/44fe2f3d04ae8674261ad3e950399c39d3681964c982ff8a238c890cfcb97c92a465672b41aef59dfdbad4c2c7afd99dc81feaf288e675cf352acf8db13d9982", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ca7b4500eaccc7261338af3e155159f2a8476205fa7be126e914349060e142ccea5d9d75c73896222065c9791009cb79d7eded1cfe284d666a56ea1158b693c3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/430ab68bddbfc14b8ce537138f5c38a8b0102f2526e2a185f22150a5051ede3c1fe0863d88589b9c1037c5e0311a71c9b89e89d4aecca9672f35e27d7c1f0867", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/0dd78455e9450bbb4abde167de32369238d8859d6a0ca606e4b19e06b683d48853bffdfa6bee2cca9550006381b921ddf76ac99198eb5f8d07b2ad95ac1f1bef"} 10:46:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:46:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:46:51 INFO - Contents: 10:46:51 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/44fe2f3d04ae8674261ad3e950399c39d3681964c982ff8a238c890cfcb97c92a465672b41aef59dfdbad4c2c7afd99dc81feaf288e675cf352acf8db13d9982", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ca7b4500eaccc7261338af3e155159f2a8476205fa7be126e914349060e142ccea5d9d75c73896222065c9791009cb79d7eded1cfe284d666a56ea1158b693c3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/430ab68bddbfc14b8ce537138f5c38a8b0102f2526e2a185f22150a5051ede3c1fe0863d88589b9c1037c5e0311a71c9b89e89d4aecca9672f35e27d7c1f0867", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/0dd78455e9450bbb4abde167de32369238d8859d6a0ca606e4b19e06b683d48853bffdfa6bee2cca9550006381b921ddf76ac99198eb5f8d07b2ad95ac1f1bef"} 10:46:51 INFO - Running post-run listener: copy_logs_to_upload_dir 10:46:51 INFO - Copying logs to upload dir... 10:46:51 INFO - mkdir: /builds/slave/test/build/upload/logs 10:46:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.000321 ========= master_lag: 0.19 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-17 10:46:51.316937) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-17 10:46:51.321548) ========= 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.VFaDmm7zrH/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.mm6MfOc3cK/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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/Pine/sha512/44fe2f3d04ae8674261ad3e950399c39d3681964c982ff8a238c890cfcb97c92a465672b41aef59dfdbad4c2c7afd99dc81feaf288e675cf352acf8db13d9982", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ca7b4500eaccc7261338af3e155159f2a8476205fa7be126e914349060e142ccea5d9d75c73896222065c9791009cb79d7eded1cfe284d666a56ea1158b693c3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/430ab68bddbfc14b8ce537138f5c38a8b0102f2526e2a185f22150a5051ede3c1fe0863d88589b9c1037c5e0311a71c9b89e89d4aecca9672f35e27d7c1f0867", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/0dd78455e9450bbb4abde167de32369238d8859d6a0ca606e4b19e06b683d48853bffdfa6bee2cca9550006381b921ddf76ac99198eb5f8d07b2ad95ac1f1bef"} build_url:https://queue.taskcluster.net/v1/task/Qz7uu5y3RZq_OzCRpQSrmQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011256 build_url: 'https://queue.taskcluster.net/v1/task/Qz7uu5y3RZq_OzCRpQSrmQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/44fe2f3d04ae8674261ad3e950399c39d3681964c982ff8a238c890cfcb97c92a465672b41aef59dfdbad4c2c7afd99dc81feaf288e675cf352acf8db13d9982", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ca7b4500eaccc7261338af3e155159f2a8476205fa7be126e914349060e142ccea5d9d75c73896222065c9791009cb79d7eded1cfe284d666a56ea1158b693c3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/430ab68bddbfc14b8ce537138f5c38a8b0102f2526e2a185f22150a5051ede3c1fe0863d88589b9c1037c5e0311a71c9b89e89d4aecca9672f35e27d7c1f0867", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/0dd78455e9450bbb4abde167de32369238d8859d6a0ca606e4b19e06b683d48853bffdfa6bee2cca9550006381b921ddf76ac99198eb5f8d07b2ad95ac1f1bef"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-17 10:46:51.369180) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 10:46:51.369537) ========= 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.VFaDmm7zrH/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.mm6MfOc3cK/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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.005108 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 10:46:51.417930) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-17 10:46:51.418249) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-17 10:46:51.418575) ========= ========= Total master_lag: 0.34 =========