builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0074 starttime: 1471515673.81 results: success (0) buildid: 20160818004215 builduid: 5be35c9eca3a468fa445f4fbd00272d0 revision: 8bb342dfaa0e8d44f6eefc35aa8e040c1e0a8b3c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 03:21:13.812809) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 03:21:13.813260) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 03:21:13.813550) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 03:21:13.907698) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 03:21:13.908139) ========= 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.ttY3fXo9Wp/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.4kM9fFCOO6/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-18 03:21:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.91M=0.001s 2016-08-18 03:21:14 (7.91 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.128281 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 03:21:14.059163) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 03:21:14.059490) ========= 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.ttY3fXo9Wp/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.4kM9fFCOO6/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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.020746 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 03:21:14.108417) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-18 03:21:14.108797) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 8bb342dfaa0e8d44f6eefc35aa8e040c1e0a8b3c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 8bb342dfaa0e8d44f6eefc35aa8e040c1e0a8b3c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ttY3fXo9Wp/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.4kM9fFCOO6/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-18 03:21:14,183 truncating revision to first 12 chars 2016-08-18 03:21:14,183 Setting DEBUG logging. 2016-08-18 03:21:14,184 attempt 1/10 2016-08-18 03:21:14,184 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/8bb342dfaa0e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-18 03:21:15,460 unpacking tar archive at: autoland-8bb342dfaa0e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.546366 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-18 03:21:15.677455) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 03:21:15.677776) ========= script_repo_revision: 8bb342dfaa0e8d44f6eefc35aa8e040c1e0a8b3c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 03:21:15.678160) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 03:21:15.678422) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 03:21:15.701938) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-08-18 03:21:15.702314) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ttY3fXo9Wp/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.4kM9fFCOO6/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 03:21:15 INFO - MultiFileLogger online at 20160818 03:21:15 in /builds/slave/test 03:21:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 03:21:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:21:15 INFO - {'append_to_log': False, 03:21:15 INFO - 'base_work_dir': '/builds/slave/test', 03:21:15 INFO - 'blob_upload_branch': 'Autoland', 03:21:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:21:15 INFO - 'branch': 'Autoland', 03:21:15 INFO - 'buildbot_json_path': 'buildprops.json', 03:21:15 INFO - 'config_files': ('talos/mac_config.py',), 03:21:15 INFO - 'default_actions': ('clobber', 03:21:15 INFO - 'read-buildbot-config', 03:21:15 INFO - 'download-and-extract', 03:21:15 INFO - 'populate-webroot', 03:21:15 INFO - 'create-virtualenv', 03:21:15 INFO - 'install', 03:21:15 INFO - 'run-tests'), 03:21:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:21:15 INFO - 'download_minidump_stackwalk': True, 03:21:15 INFO - 'download_symbols': 'ondemand', 03:21:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:21:15 INFO - 'tooltool.py': '/tools/tooltool.py', 03:21:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:21:15 INFO - '/tools/misc-python/virtualenv.py')}, 03:21:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:21:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:21:15 INFO - 'installer_path': 'installer.exe', 03:21:15 INFO - 'log_level': 'info', 03:21:15 INFO - 'log_name': 'talos', 03:21:15 INFO - 'log_to_console': True, 03:21:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:21:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:21:15 INFO - 'opt_config_files': (), 03:21:15 INFO - 'pip_index': False, 03:21:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:21:15 INFO - 'cmd': ('bash', 03:21:15 INFO - '-c', 03:21:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:21:15 INFO - 'enabled': True, 03:21:15 INFO - 'halt_on_failure': False, 03:21:15 INFO - 'name': 'check_screen_resolution'},), 03:21:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:21:15 INFO - 'cmd': ('bash', 03:21:15 INFO - '-c', 03:21:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:21:15 INFO - 'enabled': True, 03:21:15 INFO - 'halt_on_failure': False, 03:21:15 INFO - 'name': 'check_screen_resolution'},), 03:21:15 INFO - 'run_cmd_checks_enabled': True, 03:21:15 INFO - 'sps_profile': False, 03:21:15 INFO - 'sps_profile_interval': 0, 03:21:15 INFO - 'suite': 'dromaeojs', 03:21:15 INFO - 'system_bits': '32', 03:21:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:21:15 INFO - 'title': 't-yosemite-r7-0074', 03:21:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:21:15 INFO - 'use_talos_json': True, 03:21:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:21:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:21:15 INFO - 'work_dir': 'build'} 03:21:15 INFO - [mozharness: 2016-08-18 10:21:15.895148Z] Running clobber step. 03:21:15 INFO - Running pre-action listener: _resource_record_pre_action 03:21:15 INFO - Running main action method: clobber 03:21:15 INFO - rmtree: /builds/slave/test/build 03:21:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:21:17 INFO - Running post-action listener: _resource_record_post_action 03:21:17 INFO - [mozharness: 2016-08-18 10:21:17.292586Z] Finished clobber step (success) 03:21:17 INFO - [mozharness: 2016-08-18 10:21:17.292754Z] Running read-buildbot-config step. 03:21:17 INFO - Running pre-action listener: _resource_record_pre_action 03:21:17 INFO - Running main action method: read_buildbot_config 03:21:17 INFO - Using buildbot properties: 03:21:17 INFO - { 03:21:17 INFO - "project": "", 03:21:17 INFO - "product": "firefox", 03:21:17 INFO - "script_repo_revision": "production", 03:21:17 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 03:21:17 INFO - "repository": "", 03:21:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 03:21:17 INFO - "buildid": "20160818004215", 03:21:17 INFO - "pgo_build": "False", 03:21:17 INFO - "basedir": "/builds/slave/test", 03:21:17 INFO - "buildnumber": 155, 03:21:17 INFO - "slavename": "t-yosemite-r7-0074", 03:21:17 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 03:21:17 INFO - "platform": "yosemite_r7", 03:21:17 INFO - "scheduler": "tests-autoland-macosx64-talos-7-3600", 03:21:17 INFO - "branch": "autoland", 03:21:17 INFO - "repo_path": "integration/autoland", 03:21:17 INFO - "revision": "8bb342dfaa0e8d44f6eefc35aa8e040c1e0a8b3c", 03:21:17 INFO - "stage_platform": "macosx64", 03:21:17 INFO - "builduid": "5be35c9eca3a468fa445f4fbd00272d0", 03:21:17 INFO - "slavebuilddir": "test" 03:21:17 INFO - } 03:21:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/KZEBFf6SRRSUtFP-5y3ysw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 03:21:17 INFO - Running post-action listener: _resource_record_post_action 03:21:17 INFO - [mozharness: 2016-08-18 10:21:17.296077Z] Finished read-buildbot-config step (success) 03:21:17 INFO - [mozharness: 2016-08-18 10:21:17.296217Z] Running download-and-extract step. 03:21:17 INFO - Running pre-action listener: _resource_record_pre_action 03:21:17 INFO - Running main action method: download_and_extract 03:21:17 INFO - mkdir: /builds/slave/test/build/tests 03:21:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:17 INFO - trying https://queue.taskcluster.net/v1/task/KZEBFf6SRRSUtFP-5y3ysw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 03:21:17 INFO - Downloading https://queue.taskcluster.net/v1/task/KZEBFf6SRRSUtFP-5y3ysw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 03:21:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KZEBFf6SRRSUtFP-5y3ysw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 03:21:18 INFO - Downloaded 1199 bytes. 03:21:18 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 03:21:18 INFO - Using the following test package requirements: 03:21:18 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 03:21:18 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:21:18 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 03:21:18 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:21:18 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 03:21:18 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:21:18 INFO - u'jsshell-mac.zip'], 03:21:18 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:21:18 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 03:21:18 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 03:21:18 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:21:18 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 03:21:18 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:21:18 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 03:21:18 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:21:18 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 03:21:18 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:21:18 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 03:21:18 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:21:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:18 INFO - trying https://queue.taskcluster.net/v1/task/KZEBFf6SRRSUtFP-5y3ysw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 03:21:18 INFO - Downloading https://queue.taskcluster.net/v1/task/KZEBFf6SRRSUtFP-5y3ysw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 03:21:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KZEBFf6SRRSUtFP-5y3ysw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:21:19 INFO - Downloaded 16339524 bytes. 03:21:19 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 03:21:26 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 03:21: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')]} 03:21:26 INFO - trying https://queue.taskcluster.net/v1/task/KZEBFf6SRRSUtFP-5y3ysw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 03:21:26 INFO - Downloading https://queue.taskcluster.net/v1/task/KZEBFf6SRRSUtFP-5y3ysw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 03:21:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KZEBFf6SRRSUtFP-5y3ysw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:21:27 INFO - Downloaded 16339524 bytes. 03:21:27 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 03:21:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:34 INFO - trying https://queue.taskcluster.net/v1/task/KZEBFf6SRRSUtFP-5y3ysw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 03:21:34 INFO - Downloading https://queue.taskcluster.net/v1/task/KZEBFf6SRRSUtFP-5y3ysw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 03:21:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KZEBFf6SRRSUtFP-5y3ysw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:21:35 INFO - Downloaded 14413981 bytes. 03:21:35 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 03:21:36 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')]} 03:21:36 INFO - trying https://queue.taskcluster.net/v1/task/KZEBFf6SRRSUtFP-5y3ysw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 03:21:36 INFO - Downloading https://queue.taskcluster.net/v1/task/KZEBFf6SRRSUtFP-5y3ysw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 03:21:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KZEBFf6SRRSUtFP-5y3ysw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 03:21:38 INFO - Downloaded 111265036 bytes. 03:21:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KZEBFf6SRRSUtFP-5y3ysw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 03:21:38 INFO - mkdir: /builds/slave/test/properties 03:21:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:21:38 INFO - Writing to file /builds/slave/test/properties/build_url 03:21:38 INFO - Contents: 03:21:38 INFO - build_url:https://queue.taskcluster.net/v1/task/KZEBFf6SRRSUtFP-5y3ysw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 03:21:39 INFO - Running post-action listener: _resource_record_post_action 03:21:39 INFO - Running post-action listener: set_extra_try_arguments 03:21:39 INFO - [mozharness: 2016-08-18 10:21:39.547896Z] Finished download-and-extract step (success) 03:21:39 INFO - [mozharness: 2016-08-18 10:21:39.548035Z] Running populate-webroot step. 03:21:39 INFO - Running pre-action listener: _resource_record_pre_action 03:21:39 INFO - Running main action method: populate_webroot 03:21:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:21:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:21:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:21:39 INFO - '--tppagecycles', 03:21:39 INFO - '7'], 03:21:39 INFO - 'tests': ['tsvgm']}}, 03:21:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:21:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:21:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:21:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:21:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:21:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:21:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:39 INFO - 'talos_options': ['--disable-e10s'], 03:21:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:21:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:21:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:39 INFO - 'talos_options': ['--disable-e10s'], 03:21:39 INFO - 'tests': ['damp', 'tps']}, 03:21:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:39 INFO - 'tests': ['damp', 'tps']}, 03:21:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:21:39 INFO - 'tests': ['dromaeo_dom']}, 03:21:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:21:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:21:39 INFO - 'tests': ['basic_compositor_video']}, 03:21:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:21:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:21:39 INFO - 'tests': ['a11yr', 03:21:39 INFO - 'ts_paint', 03:21:39 INFO - 'tpaint', 03:21:39 INFO - 'sessionrestore', 03:21:39 INFO - 'sessionrestore_no_auto_restore', 03:21:39 INFO - 'tabpaint']}, 03:21:39 INFO - 'other-e10s': {'tests': ['a11yr', 03:21:39 INFO - 'ts_paint', 03:21:39 INFO - 'tpaint', 03:21:39 INFO - 'sessionrestore', 03:21:39 INFO - 'sessionrestore_no_auto_restore', 03:21:39 INFO - 'tabpaint']}, 03:21:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:21:39 INFO - 'ts_paint', 03:21:39 INFO - 'tpaint', 03:21:39 INFO - 'sessionrestore', 03:21:39 INFO - 'sessionrestore_no_auto_restore', 03:21:39 INFO - 'tabpaint']}, 03:21:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:21:39 INFO - 'ts_paint', 03:21:39 INFO - 'tpaint', 03:21:39 INFO - 'sessionrestore', 03:21:39 INFO - 'sessionrestore_no_auto_restore', 03:21:39 INFO - 'tabpaint']}, 03:21:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:21:39 INFO - 'tests': ['a11yr', 03:21:39 INFO - 'ts_paint', 03:21:39 INFO - 'tpaint', 03:21:39 INFO - 'sessionrestore', 03:21:39 INFO - 'sessionrestore_no_auto_restore', 03:21:39 INFO - 'tabpaint']}, 03:21:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:21:39 INFO - 'tests': ['a11yr', 03:21:39 INFO - 'ts_paint', 03:21:39 INFO - 'tpaint', 03:21:39 INFO - 'sessionrestore', 03:21:39 INFO - 'sessionrestore_no_auto_restore', 03:21:39 INFO - 'tabpaint']}, 03:21:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:21:39 INFO - 'tests': ['tsvgx', 03:21:39 INFO - 'tsvgr_opacity', 03:21:39 INFO - 'tart', 03:21:39 INFO - 'tscrollx', 03:21:39 INFO - 'cart']}, 03:21:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:21:39 INFO - 'tsvgr_opacity', 03:21:39 INFO - 'tart', 03:21:39 INFO - 'tscrollx', 03:21:39 INFO - 'cart']}, 03:21:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:39 INFO - 'talos_options': ['--disable-e10s'], 03:21:39 INFO - 'tests': ['tp5o']}, 03:21:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:39 INFO - 'tests': ['tp5o']}, 03:21:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:39 INFO - 'talos_options': ['--disable-e10s', 03:21:39 INFO - '--xperf_path', 03:21:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:21:39 INFO - 'tests': ['tp5n']}, 03:21:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:39 INFO - 'talos_options': ['--xperf_path', 03:21:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:21:39 INFO - 'tests': ['tp5n']}}, 03:21:39 INFO - 'talos.zip': {'path': '', 03:21:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:21:39 INFO - Running post-action listener: _resource_record_post_action 03:21:39 INFO - [mozharness: 2016-08-18 10:21:39.566707Z] Finished populate-webroot step (success) 03:21:39 INFO - [mozharness: 2016-08-18 10:21:39.566817Z] Running create-virtualenv step. 03:21:39 INFO - Running pre-action listener: _resource_record_pre_action 03:21:39 INFO - Running main action method: create_virtualenv 03:21:39 INFO - Creating virtualenv /builds/slave/test/build/venv 03:21:39 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 03:21:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:21:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:21:39 INFO - Using real prefix '/tools/python27' 03:21:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:21:40 INFO - Installing distribute.............................................................................................................................................................................................done. 03:21:43 INFO - Installing pip.................done. 03:21:43 INFO - Return code: 0 03:21:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:21:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104a72a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b07618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe420579cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049d6d30>, '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 0x10473bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049ceb20>, '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.ttY3fXo9Wp/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.4kM9fFCOO6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 03:21:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:21:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:21:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ttY3fXo9Wp/Render', 03:21:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:43 INFO - 'HOME': '/Users/cltbld', 03:21:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:43 INFO - 'LOGNAME': 'cltbld', 03:21:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:43 INFO - 'MOZ_NO_REMOTE': '1', 03:21:43 INFO - 'NO_EM_RESTART': '1', 03:21:43 INFO - 'PAGER': '/bin/cat', 03:21:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:43 INFO - 'PWD': '/builds/slave/test', 03:21:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:43 INFO - 'SHELL': '/bin/bash', 03:21:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4kM9fFCOO6/Listeners', 03:21:43 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 03:21:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:43 INFO - 'USER': 'cltbld', 03:21:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:43 INFO - 'XPC_FLAGS': '0x0', 03:21:43 INFO - 'XPC_SERVICE_NAME': '0', 03:21:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:43 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 03:21:43 INFO - Cleaning up... 03:21:43 INFO - Return code: 0 03:21:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:21:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104a72a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b07618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe420579cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049d6d30>, '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 0x10473bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049ceb20>, '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.ttY3fXo9Wp/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.4kM9fFCOO6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 03:21:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:21:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:21:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ttY3fXo9Wp/Render', 03:21:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:43 INFO - 'HOME': '/Users/cltbld', 03:21:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:43 INFO - 'LOGNAME': 'cltbld', 03:21:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:43 INFO - 'MOZ_NO_REMOTE': '1', 03:21:43 INFO - 'NO_EM_RESTART': '1', 03:21:43 INFO - 'PAGER': '/bin/cat', 03:21:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:43 INFO - 'PWD': '/builds/slave/test', 03:21:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:43 INFO - 'SHELL': '/bin/bash', 03:21:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4kM9fFCOO6/Listeners', 03:21:43 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 03:21:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:43 INFO - 'USER': 'cltbld', 03:21:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:43 INFO - 'XPC_FLAGS': '0x0', 03:21:43 INFO - 'XPC_SERVICE_NAME': '0', 03:21:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:43 INFO - Downloading/unpacking psutil>=3.1.1 03:21:43 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 03:21:43 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 03:21:43 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 03:21:43 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 03:21:43 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 03:21:43 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 03:21:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:21:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:21:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:21:46 INFO - Installing collected packages: psutil 03:21:46 INFO - Running setup.py install for psutil 03:21:47 INFO - building 'psutil._psutil_osx' extension 03:21:47 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 03:21:47 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 03:21:47 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 03:21:47 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 03:21:47 INFO - building 'psutil._psutil_posix' extension 03:21:47 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 03:21:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:21:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:21:47 INFO - ^ 03:21:47 INFO - 1 warning generated. 03:21:47 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 03:21:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:21:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:21:47 INFO - Successfully installed psutil 03:21:47 INFO - Cleaning up... 03:21:47 INFO - Return code: 0 03:21:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:21:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:47 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 0x104a72a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b07618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe420579cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049d6d30>, '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 0x10473bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049ceb20>, '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.ttY3fXo9Wp/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.4kM9fFCOO6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 03:21:47 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 03:21:47 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 03:21:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ttY3fXo9Wp/Render', 03:21:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:47 INFO - 'HOME': '/Users/cltbld', 03:21:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:47 INFO - 'LOGNAME': 'cltbld', 03:21:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:47 INFO - 'MOZ_NO_REMOTE': '1', 03:21:47 INFO - 'NO_EM_RESTART': '1', 03:21:47 INFO - 'PAGER': '/bin/cat', 03:21:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:47 INFO - 'PWD': '/builds/slave/test', 03:21:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:47 INFO - 'SHELL': '/bin/bash', 03:21:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4kM9fFCOO6/Listeners', 03:21:47 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 03:21:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:47 INFO - 'USER': 'cltbld', 03:21:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:47 INFO - 'XPC_FLAGS': '0x0', 03:21:47 INFO - 'XPC_SERVICE_NAME': '0', 03:21:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:21:48 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 03:21:48 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 03:21:48 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 03:21:48 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 03:21:48 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 03:21:48 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 03:21:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:21:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:21:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:21:50 INFO - Installing collected packages: mozsystemmonitor 03:21:50 INFO - Running setup.py install for mozsystemmonitor 03:21:50 INFO - Successfully installed mozsystemmonitor 03:21:50 INFO - Cleaning up... 03:21:50 INFO - Return code: 0 03:21:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:21:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104a72a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b07618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe420579cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049d6d30>, '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 0x10473bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049ceb20>, '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.ttY3fXo9Wp/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.4kM9fFCOO6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 03:21:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:21:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:21:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ttY3fXo9Wp/Render', 03:21:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:50 INFO - 'HOME': '/Users/cltbld', 03:21:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:50 INFO - 'LOGNAME': 'cltbld', 03:21:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:50 INFO - 'MOZ_NO_REMOTE': '1', 03:21:50 INFO - 'NO_EM_RESTART': '1', 03:21:50 INFO - 'PAGER': '/bin/cat', 03:21:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:50 INFO - 'PWD': '/builds/slave/test', 03:21:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:50 INFO - 'SHELL': '/bin/bash', 03:21:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4kM9fFCOO6/Listeners', 03:21:50 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 03:21:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:50 INFO - 'USER': 'cltbld', 03:21:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:50 INFO - 'XPC_FLAGS': '0x0', 03:21:50 INFO - 'XPC_SERVICE_NAME': '0', 03:21:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:51 INFO - Downloading/unpacking jsonschema==2.5.1 03:21:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:21:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:21:53 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 03:21:53 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 03:21:53 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 03:21:53 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 03:21:53 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 03:21:53 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 03:21:53 INFO - Downloading functools32-3.2.3-2.tar.gz 03:21:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:21:54 INFO - warning: no files found matching '*.txt' 03:21:54 INFO - no previously-included directories found matching 'build' 03:21:54 INFO - no previously-included directories found matching 'dist' 03:21:54 INFO - no previously-included directories found matching '.git*' 03:21:54 INFO - Installing collected packages: jsonschema, functools32 03:21:54 INFO - Running setup.py install for functools32 03:21:54 INFO - warning: no files found matching '*.txt' 03:21:54 INFO - no previously-included directories found matching 'build' 03:21:54 INFO - no previously-included directories found matching 'dist' 03:21:54 INFO - no previously-included directories found matching '.git*' 03:21:54 INFO - Successfully installed jsonschema functools32 03:21:54 INFO - Cleaning up... 03:21:54 INFO - Return code: 0 03:21:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:21:54 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')]} 03:21:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:54 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')]} 03:21:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:54 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 0x104a72a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b07618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe420579cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049d6d30>, '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 0x10473bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049ceb20>, '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.ttY3fXo9Wp/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.4kM9fFCOO6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 03:21:54 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 03:21:54 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 03:21:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ttY3fXo9Wp/Render', 03:21:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:54 INFO - 'HOME': '/Users/cltbld', 03:21:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:54 INFO - 'LOGNAME': 'cltbld', 03:21:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:54 INFO - 'MOZ_NO_REMOTE': '1', 03:21:54 INFO - 'NO_EM_RESTART': '1', 03:21:54 INFO - 'PAGER': '/bin/cat', 03:21:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:54 INFO - 'PWD': '/builds/slave/test', 03:21:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:54 INFO - 'SHELL': '/bin/bash', 03:21:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4kM9fFCOO6/Listeners', 03:21:54 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 03:21:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:54 INFO - 'USER': 'cltbld', 03:21:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:54 INFO - 'XPC_FLAGS': '0x0', 03:21:54 INFO - 'XPC_SERVICE_NAME': '0', 03:21:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:21:54 INFO - Cleaning up... 03:21:54 INFO - Return code: 0 03:21:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:21:54 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')]} 03:21:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:54 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')]} 03:21:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:54 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 0x104a72a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b07618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe420579cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049d6d30>, '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 0x10473bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049ceb20>, '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.ttY3fXo9Wp/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.4kM9fFCOO6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 03:21:54 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 03:21:54 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 03:21:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ttY3fXo9Wp/Render', 03:21:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:54 INFO - 'HOME': '/Users/cltbld', 03:21:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:54 INFO - 'LOGNAME': 'cltbld', 03:21:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:54 INFO - 'MOZ_NO_REMOTE': '1', 03:21:54 INFO - 'NO_EM_RESTART': '1', 03:21:54 INFO - 'PAGER': '/bin/cat', 03:21:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:54 INFO - 'PWD': '/builds/slave/test', 03:21:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:54 INFO - 'SHELL': '/bin/bash', 03:21:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4kM9fFCOO6/Listeners', 03:21:54 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 03:21:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:54 INFO - 'USER': 'cltbld', 03:21:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:54 INFO - 'XPC_FLAGS': '0x0', 03:21:54 INFO - 'XPC_SERVICE_NAME': '0', 03:21:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:54 INFO - Downloading/unpacking blobuploader==1.2.4 03:21:54 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 03:21:54 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 03:21:54 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 03:21:54 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 03:21:54 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 03:21:54 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 03:21:57 INFO - Downloading blobuploader-1.2.4.tar.gz 03:21:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:21:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:21:57 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 03:21:57 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 03:21:57 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 03:21:57 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 03:21:57 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 03:21:57 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 03:21:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:21:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:21:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:58 INFO - Downloading docopt-0.6.1.tar.gz 03:21:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:21:58 INFO - Installing collected packages: blobuploader, requests, docopt 03:21:58 INFO - Running setup.py install for blobuploader 03:21:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:21:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:21:58 INFO - Running setup.py install for requests 03:21:59 INFO - Running setup.py install for docopt 03:21:59 INFO - Successfully installed blobuploader requests docopt 03:21:59 INFO - Cleaning up... 03:21:59 INFO - Return code: 0 03:21:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:21:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:59 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 0x104a72a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b07618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe420579cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049d6d30>, '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 0x10473bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049ceb20>, '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.ttY3fXo9Wp/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.4kM9fFCOO6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 03:21:59 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 03:21:59 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 03:21:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ttY3fXo9Wp/Render', 03:21:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:59 INFO - 'HOME': '/Users/cltbld', 03:21:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:59 INFO - 'LOGNAME': 'cltbld', 03:21:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:59 INFO - 'MOZ_NO_REMOTE': '1', 03:21:59 INFO - 'NO_EM_RESTART': '1', 03:21:59 INFO - 'PAGER': '/bin/cat', 03:21:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:59 INFO - 'PWD': '/builds/slave/test', 03:21:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:59 INFO - 'SHELL': '/bin/bash', 03:21:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4kM9fFCOO6/Listeners', 03:21:59 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 03:21:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:59 INFO - 'USER': 'cltbld', 03:21:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:59 INFO - 'XPC_FLAGS': '0x0', 03:21:59 INFO - 'XPC_SERVICE_NAME': '0', 03:21:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:21:59 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-121L1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:21:59 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-pxs9UP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:21:59 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-PViyT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:22:00 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-rVLnwj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:22:00 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-GjHB98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:22:00 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-Ox2eC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:22:00 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-II48U2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:22:00 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-gVFM4v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:22:00 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-4xvK2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:22:00 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-Qm2OdI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:22:00 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-C1I1dm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:22:00 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-EaNra3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:22:00 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-tEHkyR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:22:01 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-3yL0rG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:01 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-ZBfSn4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:22:01 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-FMGrUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:22:01 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-mwe6e4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:22:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:22:01 INFO - Running setup.py install for manifestparser 03:22:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:22:01 INFO - Running setup.py install for mozcrash 03:22:01 INFO - Running setup.py install for mozdebug 03:22:01 INFO - Running setup.py install for mozdevice 03:22:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:22:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:22:02 INFO - Running setup.py install for mozfile 03:22:02 INFO - Running setup.py install for mozhttpd 03:22:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:22:02 INFO - Running setup.py install for mozinfo 03:22:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:22:02 INFO - Running setup.py install for mozInstall 03:22:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:22:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:22:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:22:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:22:02 INFO - Running setup.py install for mozleak 03:22:02 INFO - Running setup.py install for mozlog 03:22:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:22:02 INFO - Running setup.py install for moznetwork 03:22:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:22:03 INFO - Running setup.py install for mozprocess 03:22:03 INFO - Running setup.py install for mozprofile 03:22:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:22:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:22:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:22:03 INFO - Running setup.py install for mozrunner 03:22:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:22:03 INFO - Running setup.py install for mozscreenshot 03:22:03 INFO - Running setup.py install for moztest 03:22:03 INFO - Running setup.py install for mozversion 03:22:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:22:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:22:03 INFO - Cleaning up... 03:22:04 INFO - Return code: 0 03:22:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:22:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:04 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 0x104a72a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b07618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe420579cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049d6d30>, '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 0x10473bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049ceb20>, '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.ttY3fXo9Wp/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.4kM9fFCOO6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 03:22:04 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 03:22:04 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 03:22:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ttY3fXo9Wp/Render', 03:22:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:04 INFO - 'HOME': '/Users/cltbld', 03:22:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:04 INFO - 'LOGNAME': 'cltbld', 03:22:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:04 INFO - 'MOZ_NO_REMOTE': '1', 03:22:04 INFO - 'NO_EM_RESTART': '1', 03:22:04 INFO - 'PAGER': '/bin/cat', 03:22:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:04 INFO - 'PWD': '/builds/slave/test', 03:22:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:04 INFO - 'SHELL': '/bin/bash', 03:22:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4kM9fFCOO6/Listeners', 03:22:04 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 03:22:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:04 INFO - 'USER': 'cltbld', 03:22:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:04 INFO - 'XPC_FLAGS': '0x0', 03:22:04 INFO - 'XPC_SERVICE_NAME': '0', 03:22:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:22:04 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-NdCe6A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:22:04 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)) 03:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:22:04 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-40r_1k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:22:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:22:04 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-iiSa4q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:22:04 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)) 03:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:22:04 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-za2xTJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:22:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:22:04 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-SFinb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:22:04 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)) 03:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:22:04 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-NDtSTz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:22:04 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)) 03:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:22:04 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-D6d_Vl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:22:05 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)) 03:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:22:05 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-NyBznU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:22:05 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)) 03:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:22:05 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-AcDkRF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:22:05 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)) 03:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:22:05 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-BOxoRp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:22:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:22:05 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-LxLWAv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:22:05 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)) 03:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:22:05 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-nQ9SWI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:22:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:22:05 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-Hoko4A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:22:05 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)) 03:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:22:05 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-5DwQqg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:22:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:05 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-36bmmr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:05 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)) 03:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:22:05 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-jvZoMD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:22:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:22:05 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-y7qEZ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:22:06 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)) 03:22:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:22:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:22:06 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)) 03:22:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:22:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:22:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:22:06 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 03:22:06 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 03:22:06 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 03:22:06 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 03:22:06 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 03:22:06 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 03:22:08 INFO - Downloading blessings-1.6.tar.gz 03:22:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:22:08 INFO - Installing collected packages: blessings 03:22:08 INFO - Running setup.py install for blessings 03:22:08 INFO - Successfully installed blessings 03:22:08 INFO - Cleaning up... 03:22:08 INFO - Return code: 0 03:22:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:22:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:22:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:22:09 INFO - Reading from file tmpfile_stdout 03:22:09 INFO - Current package versions: 03:22:09 INFO - blessings == 1.6 03:22:09 INFO - blobuploader == 1.2.4 03:22:09 INFO - docopt == 0.6.1 03:22:09 INFO - functools32 == 3.2.3-2 03:22:09 INFO - jsonschema == 2.5.1 03:22:09 INFO - manifestparser == 1.1 03:22:09 INFO - mozInstall == 1.12 03:22:09 INFO - mozcrash == 0.17 03:22:09 INFO - mozdebug == 0.1 03:22:09 INFO - mozdevice == 0.48 03:22:09 INFO - mozfile == 1.2 03:22:09 INFO - mozhttpd == 0.7 03:22:09 INFO - mozinfo == 0.9 03:22:09 INFO - mozleak == 0.1 03:22:09 INFO - mozlog == 3.3 03:22:09 INFO - moznetwork == 0.27 03:22:09 INFO - mozprocess == 0.23 03:22:09 INFO - mozprofile == 0.28 03:22:09 INFO - mozrunner == 6.12 03:22:09 INFO - mozscreenshot == 0.1 03:22:09 INFO - mozsystemmonitor == 0.3 03:22:09 INFO - moztest == 0.7 03:22:09 INFO - mozversion == 1.4 03:22:09 INFO - psutil == 3.1.1 03:22:09 INFO - requests == 1.2.3 03:22:09 INFO - wsgiref == 0.1.2 03:22:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:22: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')]} 03:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22: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')]} 03:22:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:09 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 0x104a72a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b07618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe420579cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049d6d30>, '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 0x10473bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049ceb20>, '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.ttY3fXo9Wp/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.4kM9fFCOO6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 03:22:09 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 03:22:09 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 03:22:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ttY3fXo9Wp/Render', 03:22:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:09 INFO - 'HOME': '/Users/cltbld', 03:22:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:09 INFO - 'LOGNAME': 'cltbld', 03:22:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:09 INFO - 'MOZ_NO_REMOTE': '1', 03:22:09 INFO - 'NO_EM_RESTART': '1', 03:22:09 INFO - 'PAGER': '/bin/cat', 03:22:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:09 INFO - 'PWD': '/builds/slave/test', 03:22:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:09 INFO - 'SHELL': '/bin/bash', 03:22:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4kM9fFCOO6/Listeners', 03:22:09 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 03:22:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:09 INFO - 'USER': 'cltbld', 03:22:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:09 INFO - 'XPC_FLAGS': '0x0', 03:22:09 INFO - 'XPC_SERVICE_NAME': '0', 03:22:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:09 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)) 03:22:09 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)) 03:22:09 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)) 03:22:09 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)) 03:22:09 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)) 03:22:09 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)) 03:22:09 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)) 03:22:09 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)) 03:22:09 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)) 03:22:09 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)) 03:22:09 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)) 03:22:09 INFO - Cleaning up... 03:22:09 INFO - Return code: 0 03:22:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:22: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')]} 03:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22: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')]} 03:22:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:09 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 0x104a72a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b07618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe420579cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049d6d30>, '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 0x10473bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049ceb20>, '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.ttY3fXo9Wp/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.4kM9fFCOO6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 03:22:09 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 03:22:09 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 03:22:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ttY3fXo9Wp/Render', 03:22:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:09 INFO - 'HOME': '/Users/cltbld', 03:22:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:09 INFO - 'LOGNAME': 'cltbld', 03:22:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:09 INFO - 'MOZ_NO_REMOTE': '1', 03:22:09 INFO - 'NO_EM_RESTART': '1', 03:22:09 INFO - 'PAGER': '/bin/cat', 03:22:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:09 INFO - 'PWD': '/builds/slave/test', 03:22:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:09 INFO - 'SHELL': '/bin/bash', 03:22:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4kM9fFCOO6/Listeners', 03:22:09 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 03:22:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:09 INFO - 'USER': 'cltbld', 03:22:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:09 INFO - 'XPC_FLAGS': '0x0', 03:22:09 INFO - 'XPC_SERVICE_NAME': '0', 03:22:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:22:09 INFO - Cleaning up... 03:22:09 INFO - Return code: 0 03:22:09 INFO - Running post-action listener: _resource_record_post_action 03:22:09 INFO - Running post-action listener: _start_resource_monitoring 03:22:09 INFO - Starting resource monitoring. 03:22:09 INFO - [mozharness: 2016-08-18 10:22:09.700505Z] Finished create-virtualenv step (success) 03:22:09 INFO - [mozharness: 2016-08-18 10:22:09.701516Z] Running install step. 03:22:09 INFO - Running pre-action listener: _resource_record_pre_action 03:22:09 INFO - Running main action method: install 03:22:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:22:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:22:09 INFO - Reading from file tmpfile_stdout 03:22:09 INFO - Detecting whether we're running mozinstall >=1.0... 03:22:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:22:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:22:10 INFO - Reading from file tmpfile_stdout 03:22:10 INFO - Output received: 03:22:10 INFO - Usage: mozinstall [options] installer 03:22:10 INFO - Options: 03:22:10 INFO - -h, --help show this help message and exit 03:22:10 INFO - -d DEST, --destination=DEST 03:22:10 INFO - Directory to install application into. [default: 03:22:10 INFO - "/builds/slave/test"] 03:22:10 INFO - --app=APP Application being installed. [default: firefox] 03:22:10 INFO - mkdir: /builds/slave/test/build/application 03:22:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:22:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 03:22:44 INFO - Reading from file tmpfile_stdout 03:22:44 INFO - Output received: 03:22:44 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:22:44 INFO - Running post-action listener: _resource_record_post_action 03:22:44 INFO - [mozharness: 2016-08-18 10:22:44.145432Z] Finished install step (success) 03:22:44 INFO - [mozharness: 2016-08-18 10:22:44.145594Z] Running run-tests step. 03:22:44 INFO - Running pre-action listener: _resource_record_pre_action 03:22:44 INFO - Running main action method: run_tests 03:22:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:22:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:22:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:22:44 INFO - 2016-08-18 03:22:44.160 screenresolution[1903:19508] starting screenresolution argv=screenresolution get 03:22:44 INFO - 2016-08-18 03:22:44.163 screenresolution[1903:19508] Display 0: 1600x1200x32@60 03:22:44 INFO - 2016-08-18 03:22:44.169 screenresolution[1904:19511] starting screenresolution argv=screenresolution list 03:22:44 INFO - Available Modes on Display 0 03:22:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:22:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:22:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:22:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:22:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:22:44 INFO - Intel Iris: 03:22:44 INFO - Chipset Model: Intel Iris 03:22:44 INFO - Type: GPU 03:22:44 INFO - Bus: Built-In 03:22:44 INFO - VRAM (Dynamic, Max): 1536 MB 03:22:44 INFO - Vendor: Intel (0x8086) 03:22:44 INFO - Device ID: 0x0a2e 03:22:44 INFO - Revision ID: 0x0009 03:22:44 INFO - Displays: 03:22:44 INFO - Display: 03:22:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:22:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:22:44 INFO - Display Serial Number: 1600x1200 60 03:22:44 INFO - Main Display: Yes 03:22:44 INFO - Mirror: Off 03:22:44 INFO - Online: Yes 03:22:44 INFO - Rotation: Supported 03:22:44 INFO - Return code: 0 03:22:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:22:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:22:44 INFO - Python 2.7.3 03:22:44 INFO - Return code: 0 03:22:44 INFO - grabbing minidump binary from tooltool 03:22:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:44 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 0x1049d6d30>, '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 0x10473bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049ceb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:22:44 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 03:22:44 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 03:22:44 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 03:22:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:22:44 INFO - Return code: 0 03:22:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:22:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:22:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:22:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:22:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:22:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KZEBFf6SRRSUtFP-5y3ysw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0074', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:22:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KZEBFf6SRRSUtFP-5y3ysw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0074 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:22:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ttY3fXo9Wp/Render', 03:22:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:44 INFO - 'HOME': '/Users/cltbld', 03:22:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:44 INFO - 'LOGNAME': 'cltbld', 03:22:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:22:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:22:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:44 INFO - 'MOZ_NO_REMOTE': '1', 03:22:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:22:44 INFO - 'NO_EM_RESTART': '1', 03:22:44 INFO - 'PAGER': '/bin/cat', 03:22:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:44 INFO - 'PWD': '/builds/slave/test', 03:22:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:22:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:44 INFO - 'SHELL': '/bin/bash', 03:22:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4kM9fFCOO6/Listeners', 03:22:44 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 03:22:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:44 INFO - 'USER': 'cltbld', 03:22:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:44 INFO - 'XPC_FLAGS': '0x0', 03:22:44 INFO - 'XPC_SERVICE_NAME': '0', 03:22:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:44 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KZEBFf6SRRSUtFP-5y3ysw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0074', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:22:44 INFO - mozversion application_buildid: 20160818004215 03:22:44 INFO - mozversion application_changeset: 8bb342dfaa0e8d44f6eefc35aa8e040c1e0a8b3c 03:22:44 INFO - mozversion application_display_name: Nightly 03:22:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:22:44 INFO - mozversion application_name: Firefox 03:22:44 INFO - mozversion application_remotingname: firefox 03:22:44 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 03:22:44 INFO - mozversion application_vendor: Mozilla 03:22:44 INFO - mozversion application_version: 51.0a1 03:22:44 INFO - mozversion platform_buildid: 20160818004215 03:22:44 INFO - mozversion platform_changeset: 8bb342dfaa0e8d44f6eefc35aa8e040c1e0a8b3c 03:22:44 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 03:22:44 INFO - mozversion platform_version: 51.0a1 03:22:44 INFO - using testdate: 1471515764 03:22:44 INFO - actual date: 1471515764 03:22:44 INFO - starting webserver on 'localhost:55746' 03:22:44 INFO - SUITE-START | Running 2 tests 03:22:44 INFO - TEST-START | dromaeo_css 03:22:44 INFO - operating with platform_type : mac_ 03:22:44 INFO - Initialising browser for dromaeo_css test... 03:22:44 INFO - TEST-INFO | started process 1914 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmpVKqPgs/profile http://localhost:55746/getInfo.html) 03:22:47 INFO - PROCESS | 1914 | __metrics Screen width/height:1600/1200 03:22:47 INFO - PROCESS | 1914 | colorDepth:24 03:22:47 INFO - PROCESS | 1914 | Browser inner width/height: 1024/689 03:22:47 INFO - PROCESS | 1914 | __metrics 03:22:47 INFO - PROCESS | 1914 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:22:47 INFO - PROCESS | 1914 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:22:47 INFO - TEST-INFO | 1914: exit 0 03:22:47 INFO - Browser initialized. 03:22:47 INFO - Running cycle 1/1 for dromaeo_css test... 03:22:47 INFO - TEST-INFO | started process 1916 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmpVKqPgs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:22:59 INFO - PROCESS | 1916 | RSS: Main: 228139008 03:22:59 INFO - PROCESS | 1916 | 03:23:51 INFO - PROCESS | 1916 | 1471515831350 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:26:03 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:55746/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55746/tests/dromaeo/cssquery-ext.html) 03:26:04 INFO - PROCESS | 1916 | RSS: Main: 362987520 03:26:04 INFO - PROCESS | 1916 | 03:29:07 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:55746/tests/dromaeo/cssquery-ext.html (next: http://localhost:55746/tests/dromaeo/cssquery-jquery.html) 03:29:08 INFO - PROCESS | 1916 | RSS: Main: 372731904 03:29:08 INFO - PROCESS | 1916 | 03:32:10 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:55746/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55746/tests/dromaeo/cssquery-mootools.html) 03:32:11 INFO - PROCESS | 1916 | RSS: Main: 343240704 03:32:11 INFO - PROCESS | 1916 | 03:35:13 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:55746/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55746/tests/dromaeo/cssquery-prototype.html) 03:35:14 INFO - PROCESS | 1916 | RSS: Main: 345821184 03:35:14 INFO - PROCESS | 1916 | 03:38:17 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:55746/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55746/tests/dromaeo/cssquery-yui.html) 03:38:17 INFO - PROCESS | 1916 | RSS: Main: 328716288 03:38:17 INFO - PROCESS | 1916 | 03:41:21 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:55746/tests/dromaeo/cssquery-yui.html (next: http://localhost:55746/tests/dromaeo/cssquery-dojo.html) 03:41:21 INFO - PROCESS | 1916 | RSS: Main: 454283264 03:41:21 INFO - PROCESS | 1916 | 03:41:21 INFO - PROCESS | 1916 | __start_tp_report 03:41:21 INFO - PROCESS | 1916 | _x_x_mozilla_page_load 03:41:21 INFO - PROCESS | 1916 | _x_x_mozilla_page_load_details 03:41:21 INFO - PROCESS | 1916 | |i|pagename|runs| 03:41:21 INFO - PROCESS | 1916 | |0;dojo.html;3309.4704847224443;3333.6333003369623;3347.263602007559;3367;3372.7133193678537;5492.368377636573;5603.663780173188;5625.662460252388;5641.548676105912;5699.259096317483;5583.720813959306;5638.0415329394;5640.407757185498;5657.094864821629;5657.915131273035;5110.105731496982;5151.896962060757;5166.328377310944;5183.792648294083;5233.450487698794;5193.065248255312;5211.218317252405;5216.035033518811;5220.686758794465;5224.7387630618505;5178.14560597501;5205.453427390126;5207.244949482323;5207.687538747668;5228.843134705965;5271.947280527184;5296.443873393277;5305.257263983045;5330.173823057432;5364.222187782789;5067.34124563808;5078.365204349457;5105.463926287724;5208.062548741225;5215.530602245816;5588.636738612018;5653;5664.0371136906415;5666.688332141731;5675.262215911946;6214.974529202676;6238.251409830804;6241.3446588108045;6247.937520624827;6266.060090986343;6086.634801911855;6114.847128821771;6183.752649893999;6273.529485288576;6275.309715931244;5738.569607279471;5766.077427611562;5781.82654520365;5788.21859049023;5815.273090863642;6286.465650419674;6326.082718005955;6343.746250150035;6345.587536810092;6349.777757778455;5893.145493903446;5971.492423144082;6127.050307202391;6129.356417576179;6136.821428392946;6106.077105207941;6164.722587483574;6172.290186628506;6241.375862413723;6242.063690446378;6116.143739876421;6140.508759299335;6177.073438984188;6178.042403427476;6180.134781130645;6017.669028203491;6139.723712432951;6202.72087756052;6212.4408803207925;6214.658193799384;4234.957650423518;4857.587105096036;4870.196417591058;4898.755062246873;4930.5069493050405;4387.078713470143;4390;4393.121375724804;4399.428074350314;4436.223660859337;4853.684510506876;5573.303337082864;5659.066254068115;5683.175939488834;5750.25246717924;6169.321374648785;6171.352008039181;6214.316425193225;6246.219222597176;6358.237011558643;5697.145428185722;5710.428957104256;5710.5146062585145;5728.427157284238;5746.827595172152;5402.027635025733;5417.59368047398;5419.539339156216;5419.566434685215;5426.593005524523;6217.129601855744;6217.222847144107;6267.717952692049;6277.246730392382;6339.683015849281;6321.30465648769;6321.620702757849;6332.936670633235;6351.777687780906;6355.682215889279;6259.780907668392;6312;6312.495000400048;6316.84207894788;6391.520635952229;5944.851378715567;6112.564273424362;6112.694365281629;6114.785982490592;6148.4773794227995;6243.157173781481;6266.060090986389;6273.059041143865;6278.963970944743;6297.433231009231;6063;6082.635041897501;6098.75604975796;6105.9084113737445;6106.023036314168;4962;5781.733320673806;5977.701114944322;6001.099835024781;6055.1522786809;5952.0476723724;5952.255968003999;5953.28560572734;5977;5998.970005149947;6062.42406971337;6065.363136870565;6086.2087928569;6098.237720284965;6098.847528811815;5744;5825.184474173534;5835.67903765297;5888.882222355443;5914.290285165807 03:41:21 INFO - PROCESS | 1916 | |1;ext.html;480.5866954419199;509.87762936895143;518.2847670215102;520.3001962360626;549.0364410459644;30882.227944301405;31234.750609975603;32532.67467325326;32559.837200813963;32583.67416325836;42470.15059698804;43608.3458748119;43747.56252437475;45690.77154614231;45792;9797.461139637335;9947.20422366209;9953.751156221117;9971.601135954554;9975.750606234866;9881;9977.15194208493;9978.700638980843;9987.800243995152;10025.147862431702;9930.05664461875;9973.90026099737;9978.750531236741;9979.750506237366;10003.299769016136;9857.654982075628;9951.203903687687;9957.153641940371;9969.152622027137;9974.351667141613;9738.464384458852;9833.115019648194;9843.015698430172;9846.90153098467;9883.950580247125;41089;41402.1719565607;41412;41423.7928810354;41498;3361.7142542883885;3444.190615205425;3568.7145028397927;3641.963580364189;3910.5502867170353;29499.96750113736;31231.687683123106;32303.192420189447;32410.513842292385;32448;3319.3029463812627;3339.96660033399;3352.1619595101224;3422.606400263952;3462.4633181856852;484764;485024;485791;486434;493090.53454732854;59597.40402596005;59843;59973;59974.70012649909;60051.09923351153;62983;63012.684936575024;63013.68493157505;63188.25799614077;63390;16146.838531614534;16245.944013639075;16452.177391130397;16471;16481.093539855185;12722.300273485047;12777.488900443877;12784.872151278554;12810.359482026048;12814.103012789015;13585.796213056812;15020.173890435912;16594.83405165957;16616.504514593744;16733.832661673227;5311.920321195185;6747.527673062838;6895.862082758316;6980.301969802979;7005.474589405815;868.4572142410993;898.9101089890959;1033.3696445168441;1049.8057859296055;1150.396042077899;28872.123311985073;30342.089737307917;30561.69438305633;31052.534211986836;31130.84434577858;1050.7373156710823;1097.5664612478026;1134.5235001299475;1142.3831131189231;1234.7098431868503;15260.542183734506;15740.68518629621;15752.369905203663;15792.36830526789;16160.949538280209;648135;655881;656586;658328;658714;59081.40918590845;59151.408485915454;59152.40847591555;59182.11226831601;59216.40783592195;1216.0575553945587;1334.7797613393682;1360.8094866718668;1376.5526203983545;1393.986060139406;30716.078517644506;31600.841995789873;31841.840790795897;32287.838560807046;32363.676363236067;50796.74601626968;51359.74320128375;51361.74319128381;51410.97178056493;51481.48518514767;439341;439512;440037.5996240125;440381;441008;25309.62035569431;26001.34996625024;26091;26798.464030719668;37408;46447.83880403017;46581.76709116568;46951.53048469472;46994;47878;38466.2306753869;38892.80553597327;38954;39811.80094099511;40210.39684404678;37931;38304;38341;39697.80151099226;40459.59540404558;37238.8138059308;37374;38096;38183;38237;28341.724622392456;30816.691833082277;33352.83323583366;33376;33715.494267586495;23778.40553986164;25251.242462726146;25283.747162528874;25614.975400983752;25640.61539076878 03:41:21 INFO - PROCESS | 1916 | |2;jquery.html;301.6998086903531;370.3112211287004;375.89287053189844;383.39806503789055;402.71809733186774;15434.459793907201;17028.829711702896;20268;20960.895195524;20980.37058888232;19016.429507114775;19348.226070957146;20932.267370641992;21228.257011004618;21338.35984920451;5872;5881.235439392873;6089.330173680892;6129.877402451948;6130.2643682758135;5925.674087925162;6082.604630699013;6092.025275955848;6092.29938557065;6094.512439004869;4181.623653871151;6436.1311222985;6707.396334329909;6749.7300107995625;6825.044493770876;6453.386928241809;6666.100076489661;6736.797896063126;6745.527813053088;6755.885278928971;5811.012127938261;5814.5057670098095;5817.970910145465;5821.563382746311;5822.214001109881;26708.866455667598;26973;27044.59433108505;27082.864585677144;27299.181024569294;2231.5871563760757;2460.225029115823;2516.8867400967;2593.974060259392;2752.2431331383827;15287.006344587677;15626.45307414235;15645.904786664938;15880.444184453605;16000.919995400065;1724.2068648421741;1863.5154859736567;1909.8472122230328;1928.9421317360502;2001.449601359623;97166.51416742941;115554.11114722115;118761.03097422548;120089;120550;9810.509474526245;10894.019538241597;11411.25826821254;11493.655190344303;11679.99999999983;14386.208758518382;14418.711625767426;14422.134671919719;14427.42290308376;14475.855241447662;7460.776176714708;8315.29320007794;8611.354148438892;8762.824743505094;8774.736757897146;8546.401751877309;8578.356623253281;8597.5701214939;8744.431611945203;8783.989841168219;4458.152950939311;4532.592066713945;4540.77296135192;4606.424196975378;4652.325412815123;1787.6067265201896;1865.1793210987123;1914.4161030885714;1936.845052395805;1949.7952714965008;725.6516871901517;786.6460092958192;824.674253669797;877.8770972063916;883.9248663863643;19328.71006934869;20603.4849128773;21306.36080917575;21451.89274053651;21501.588996263203;931.0642803981955;972.2027937091517;976.1653786012998;1011.1809434358194;1058.0583280880023;9136.223421009156;10361.740956475998;11208.327500350006;11237.775244495064;11239.21325507223;507625;510465;510561;591298;596539;101459;134787;140933;142445;148189;874.4316194473643;936.5551363102472;938.8920274168559;940.9414408790248;942.072059021863;19512.804871951666;19778.802211978273;20005.59988800245;20042.498937526678;20095.095720692312;42790.2977464047;56770;57488;57711.98008069865;57969.71015144897;61993.070103947575;78710;80588;81704.77442838243;82009;5944.5838791284195;6479.287278399278;6941.757038503628;7658.961705191438;8329.83340333202;11756.58851940178;12465.314407707301;12727.490900363882;12903.41934612926;12997;10701.357918524913;10973.561057557608;11013.724656883322;11088.611898583833;11286.887131128911;10835.295705779099;10888.40113793749;11152.498137583665;11155.776884462428;11208.10335173168;10933.671989840317;11324.980751732384;11397.430128493708;11425.542978280775;11471.705658868093;7175.174854891754;7224.494285399972;7314.341709246193;7390.96304518474;7467.402607791255;10526.631567895087;10962.56149754001;11144.498497567467;11167.553297868319;11185.217034807485 03:41:21 INFO - PROCESS | 1916 | |3;mootools.html;1354.2551596621856;1365.460643045997;1526.8549487798662;1673.3557580331576;1868.2620364955842;7756;8547.700830470934;8781.297496200305;8792.252658524018;8867.91132088679;8613.612387442565;8695;8712.738617841474;8716.302695784338;8725.171108744675;2341.344423561401;2371.715394152704;2375.0143690368477;2380.5000949800565;2403.4472071423584;2330.382448651109;2358.2571489980687;2378.335165384229;2384.415818124562;2384.7257565380028;2345.26124270855;2362.196853069956;2370.0756704885107;2373.8012303786563;2373.9881300593474;2322.744498105207;2335.252719129879;2344.929652110431;2347.272345572878;2359.752226016261;2018.253246298864;2157.4067131538795;2314.618088015475;2353.9529209415887;2359.740428552858;8624.568771561397;8704.912950870474;8789.296856251485;8797.516136612483;8809.163129502687;5173.120569503193;5376.4085950545405;5378.408375078739;5379.784808607651;5393.460653934614;3548.2193917338145;3571.285742851414;3631.909202269938;3648.3068217038676;3658.3414985302634;1087.0434018064052;1100.4387762241236;1101.6474728087014;1114.6321713834416;1120.6694025262568;11472.94263528677;11534.250273732181;11586.76826463466;11597.710057248552;11651.30092194471;3955.3473676843573;4353.455818022747;4483.641308695296;4593.19619066662;4640.071985602893;9055.95472022649;9063.864042039375;9065.546722663841;9076.137766912132;9084.81830363389;5191.610629202825;5198.714070726146;5199.1161502544655;5199.610029247821;5200.453952335026;4245.1934132514725;4308;4393.428854248991;4557.45310562728;4884.438289596742;3777.206786574795;3788.052986753312;3879.3599056155417;3888.4556162137324;3902.551206611219;1175.0951767139254;1181.3915833345834;1183.8401815754935;1197.1500234833193;1198.2810313811817;728.8797348437448;863.9287283768082;926.7219834049893;928.874601928731;932.4265576670299;5784;5844.649321040752;5890.587658863924;6037.879242415127;6161.4061614062;439.9802008909607;441.45480331790856;455.2192989023774;458.53458739379556;459.34084588615445;3885.203533275672;4006.3790112532606;4124.52567954689;4127.690423218271;4151.252774500619;8701.695440659547;10566.207534434794;10608.522616482276;10625.256232063835;10664.040236378609;10187.796244075076;10308.175345972304;10511.369317840954;10546.894531054744;10555.577776888838;7463.589502577302;7744.961275193588;8060.677572897136;8113.432059755761;8119.9188008120345;5839.510536508027;5886.705664716833;5928.436798504135;5931.288245410406;6019.367966363644;9363.719088427359;9692.127708505986;9728.513574321398;9836;9926.950365248127;9300.813983720423;9885.950570247103;10056.949715251378;10102.24233182529;10150.340227885164;6583.34166583357;6877.38103570661;7023.33278338557;7072.893906591508;7141.107361579802;8295.875561866456;8323.95838020806;8327.50034997902;8387.790305242417;8390.077091519806;7482.812929676802;7539.246075392636;7558.962205188939;7572.167061623327;7637.847243055219;7173.928260717326;7326.816829579303;7335.77992660221;7408.740694075682;7600.1639819618695;6731.293214212437;6933.306669333027;7067.469939754436;7072.222055573986;7077.7168913242895;4939.061578300111;5007.974960125176;5033.597312214941;5089.312942752681;5248.291480650063;4978.178600530872;5099.413567439792;5115.283860259492;5189.169732842727;5265.631405801557 03:41:21 INFO - PROCESS | 1916 | |4;prototype.html;263.83510306058713;298.5983851719438;306.4927544913169;314.16432290108315;317.9062176657886;4820.469748327681;5298.57568929944;5348.46515348465;5413.729313534327;5423.864403389913;4989.850304490862;5056.570191533724;5173;5354.812581559646;5357.866053348668;4092.3656833190753;4107.178564287147;4113.773742444165;4130.524989626187;4132.214879172948;4103.5280942691525;4112.773797441139;4128.401381799637;4142.937855932163;4162.958370416302;3924.725269231155;4030.758154510724;4068.596334264674;4081.7755023473696;4082.856800095978;4039.151778126597;4056.0873803394297;4057.2088442753675;4070.2266569351877;4081.8775436736946;3368.6631336866362;3461.8961431156854;3527.876524321662;3563.982180089109;3569.375359312136;3464.549608550872;5116.437191908887;5189.896202075975;5222.660527065728;5251.684898906078;4556.951901062741;4594.517575654575;4612.515685852971;4613.1696294666945;4627.282771170474;5614.242077319591;5616.887662246732;5634.91508141986;5638.462691788671;5653.2299173405145;3011.804232724866;3638.490611314418;3809.7999130273874;3853.5183102112237;3853.7109716771074;4985.376827896513;5510.559155267569;5602.691851948141;5885.293764748215;5902.291724993029;3856.2646808936092;3874.721341957147;3880.3403421418425;3886.6502014818802;3888.5528164260904;3338.2989572190113;3347.4726581430864;3930.9213815723524;3952.782596957195;3969.6228858258996;4024.376221685585;4261.296886013773;4354.259775838115;4447.599716025573;4647.372604698322;5334.199870019451;5646.548276137899;5652.943470565324;5673.446948872941;5683.943160568341;1159.8608167019843;1274.184521905981;1316.5194703932996;1337.7190789934223;1346.7575836349552;749.456643933155;848.245061894914;871.834351473218;872.6858331000834;874.7419511244199;3737.2712321097124;4050.326784322333;4143.97928010364;4182.686298527562;4440.977795111004;4153.6677065834665;4529.547045295444;4816.8795780105775;4837.612990960714;4855.587275081658;3512.877049303262;3984.373830656654;4346.913061738747;4361.672874534423;4375.381108989694;2127.6702061211745;2193.5064610462837;2453.4915071448;2535.782824244373;2551.413174969767;4242.978785106055;4452.1763473757455;4580.190824624255;4646.83736069236;4667.192791424838;4203.390508376268;4261.403403523509;4515.661325400608;4959.553640172329;5015.648904576645;4188.099558594856;4404.933925991113;5009.117933601488;5037.697738135724;5053;4578.5650363215445;4592.678512504092;4800.991791723777;4826.634948875097;4826.8069277228515;4443.466783985942;4450.399196108613;4463.933041004322;4709.576138147583;4761.809527618856;4204.369344598213;4417.955820441754;4587.087199628193;4717.174494463524;5059.291699162194;3139.340738444952;3322.9501557477142;3357.4795906634513;3366.1584603849037;3386.9179047068037;4155.231282212801;4728.17256980034;4792.95207047939;4878.804847806048;4906.681065730716;4090.3455447128317;4309.698321117492;4323.654107671316;4647.953520464887;4662;4385.605295523418;4614.584687378152;4658.790354434126;4690.601298889633;4696.497120921273;4462.66120163943;4537.546245375568;4569.497355290849;4599.908001839877;5220.504052115043;4201.046513371175;5329.893402132013;5546.724253421655;5589.245451863946;5613.803516876889;4665.836695715634;5072.365954255718;5498.86564658141;5516.510542153676;5536.75084621201 03:41:21 INFO - PROCESS | 1916 | |5;yui.html;727.6216367488905;752.8306131120498;761.5202422473842;810.6352141536311;843.2284459719357;15962.521124366258;16756.162191890417;16760.580985475368;16762;16767.077810720435;14502.202378869159;15053.397864085451;15433.536993890173;15437;15449.768253476206;852.3607294529103;858.6050416808275;870.4820631724114;874.3660845886714;875.4397185801092;678.7997540725498;706.2443185791205;868.15355028847;870.9564521773917;871.255076909243;884.4958373726978;920.8710780490703;921.4655499810094;922.4373132389237;934.0612684252317;909.5543183839937;909.9990010987893;911.6854685133685;912.95891684874;914.6570036236411;518.8521271437622;652.9836754081139;677.8102131403215;681.6148875885109;684.3122661724934;13296.534621288305;15529.378824846992;15604.453844115515;15624.453144140014;15624.76562851558;14270.858331333573;14493.202873841934;14514;14520;14940.32768525408;13138.080334376598;13303.135296205715;13450.192988420726;13536.323183840768;13843.515476958359;4517.457905051382;4582.450718967266;4665.976670116662;4726.858194254178;4820.373351464323;118795.4060229702;118934;119113;119501.8049819508;119513;11186.216964812385;11578;11645.243059201124;11645.359505227125;11975.161738678384;22717.88641056784;22999.770002300098;23117.88441057784;23176;23615.409614759425;7928.207179282025;7995.800104997305;8017.919820801834;8122.065962414398;8274.089850116483;3828.3874580067054;4390.692651514363;4428.82284708608;4512.954870451319;4554.726716397026;3535.4343305071066;3655.1593133579427;3655.1958569114754;3656.2504686539196;3658.835352409148;639.1946147853645;651.0592194279221;667.152716050613;668.5019660352971;669.0065253099144;502.28424495094345;627.7677259414046;631.36232405271;634.9871954232991;636.5098873867097;8049.476784009021;8851.291896648252;8958.835351404407;10149.391036537683;10405.47972601367;532.7283085626359;597.7847974729094;627.0030651264511;645.106527459472;665.8002599220215;3953.6244056814558;4442.311441726538;4748.529492469097;4872.6589138760655;4876;145475.5452445462;145962.2701886505;147150;150820.245898772;152483;131279.49764018855;131543.34228328796;131928.84924959886;132659;135471;112349;115619.17237034778;117467.8253217457;117864.41067794777;117903.82096178929;9124.726258212264;9251.629934802533;9319.953400232955;9344.252459803336;9447.763805904908;59366.40633593781;62017.68991155015;63188.68405657943;63691.89853927739;63740;85305.57347213224;86655;89820;93107;99716.50141749244;13112.503730229933;13563.321833908069;13607.79588306196;13768.311584420939;13963.720725585634;15159.924200378928;15342.693146137239;15439.536813895602;15493.380264789283;15912.761308580612;14139.64650883695;14433.422663093359;14568.198749068903;14693.92653036728;14712.852871471148;11592.362420066976;13083.672908176992;13742.725145497234;14086.295685215904;14730;13600.183988960694;13872;13972.790408143683;14424.350904209126;14436.061655992327;10249.74375640615;11031;11139.273412620732;11309.264897781617;11606.24559403636;10679.946600266949;10841.945790270998;10947.945260273647;11003.17476189305;11096.334219946506 03:41:21 INFO - PROCESS | 1916 | __end_tp_report 03:41:21 INFO - PROCESS | 1916 | __start_cc_report 03:41:21 INFO - PROCESS | 1916 | _x_x_mozilla_cycle_collect,1265 03:41:21 INFO - PROCESS | 1916 | __end_cc_report 03:41:21 INFO - PROCESS | 1916 | __startTimestamp1471516881888__endTimestamp 03:41:21 INFO - PROCESS | 1916 | 03:41:21 INFO - PROCESS | 1916 | ------- Summary: start ------- 03:41:21 INFO - PROCESS | 1916 | Number of tests: 6 03:41:21 INFO - PROCESS | 1916 | 03:41:21 INFO - PROCESS | 1916 | [#0] dojo.html Cycles:165 Average:5709.55 Median:5914.29 stddev:649.86 (11.0%) stddev-sans-first:623.98 03:41:21 INFO - PROCESS | 1916 | Values: 3309.5 3333.6 3347.3 3367.0 3372.7 5492.4 5603.7 5625.7 5641.5 5699.3 5583.7 5638.0 5640.4 5657.1 5657.9 5110.1 5151.9 5166.3 5183.8 5233.5 5193.1 5211.2 5216.0 5220.7 5224.7 5178.1 5205.5 5207.2 5207.7 5228.8 5271.9 5296.4 5305.3 5330.2 5364.2 5067.3 5078.4 5105.5 5208.1 5215.5 5588.6 5653.0 5664.0 5666.7 5675.3 6215.0 6238.3 6241.3 6247.9 6266.1 6086.6 6114.8 6183.8 6273.5 6275.3 5738.6 5766.1 5781.8 5788.2 5815.3 6286.5 6326.1 6343.7 6345.6 6349.8 5893.1 5971.5 6127.1 6129.4 6136.8 6106.1 6164.7 6172.3 6241.4 6242.1 6116.1 6140.5 6177.1 6178.0 6180.1 6017.7 6139.7 6202.7 6212.4 6214.7 4235.0 4857.6 4870.2 4898.8 4930.5 4387.1 4390.0 4393.1 4399.4 4436.2 4853.7 5573.3 5659.1 5683.2 5750.3 6169.3 6171.4 6214.3 6246.2 6358.2 5697.1 5710.4 5710.5 5728.4 5746.8 5402.0 5417.6 5419.5 5419.6 5426.6 6217.1 6217.2 6267.7 6277.2 6339.7 6321.3 6321.6 6332.9 6351.8 6355.7 6259.8 6312.0 6312.5 6316.8 6391.5 5944.9 6112.6 6112.7 6114.8 6148.5 6243.2 6266.1 6273.1 6279.0 6297.4 6063.0 6082.6 6098.8 6105.9 6106.0 4962.0 5781.7 5977.7 6001.1 6055.2 5952.0 5952.3 5953.3 5977.0 5999.0 6062.4 6065.4 6086.2 6098.2 6098.8 5744.0 5825.2 5835.7 5888.9 5914.3 03:41:21 INFO - PROCESS | 1916 | 03:41:21 INFO - PROCESS | 1916 | [#1] ext.html Cycles:180 Average:66764.09 Median:21804.18 stddev:143003.14 (655.9%) stddev-sans-first:143317.70 03:41:21 INFO - PROCESS | 1916 | Values: 480.6 509.9 518.3 520.3 549.0 30882.2 31234.8 32532.7 32559.8 32583.7 42470.2 43608.3 43747.6 45690.8 45792.0 9797.5 9947.2 9953.8 9971.6 9975.8 9881.0 9977.2 9978.7 9987.8 10025.1 9930.1 9973.9 9978.8 9979.8 10003.3 9857.7 9951.2 9957.2 9969.2 9974.4 9738.5 9833.1 9843.0 9846.9 9884.0 41089.0 41402.2 41412.0 41423.8 41498.0 3361.7 3444.2 3568.7 3642.0 3910.6 29500.0 31231.7 32303.2 32410.5 32448.0 3319.3 3340.0 3352.2 3422.6 3462.5 484764.0 485024.0 485791.0 486434.0 493090.5 59597.4 59843.0 59973.0 59974.7 60051.1 62983.0 63012.7 63013.7 63188.3 63390.0 16146.8 16245.9 16452.2 16471.0 16481.1 12722.3 12777.5 12784.9 12810.4 12814.1 13585.8 15020.2 16594.8 16616.5 16733.8 5311.9 6747.5 6895.9 6980.3 7005.5 868.5 898.9 1033.4 1049.8 1150.4 28872.1 30342.1 30561.7 31052.5 31130.8 1050.7 1097.6 1134.5 1142.4 1234.7 15260.5 15740.7 15752.4 15792.4 16160.9 648135.0 655881.0 656586.0 658328.0 658714.0 59081.4 59151.4 59152.4 59182.1 59216.4 1216.1 1334.8 1360.8 1376.6 1394.0 30716.1 31600.8 31841.8 32287.8 32363.7 50796.7 51359.7 51361.7 51411.0 51481.5 439341.0 439512.0 440037.6 440381.0 441008.0 25309.6 26001.3 26091.0 26798.5 37408.0 46447.8 46581.8 46951.5 46994.0 47878.0 38466.2 38892.8 38954.0 39811.8 40210.4 37931.0 38304.0 38341.0 39697.8 40459.6 37238.8 37374.0 38096.0 38183.0 38237.0 28341.7 30816.7 33352.8 33376.0 33715.5 23778.4 25251.2 25283.7 25615.0 25640.6 03:41:21 INFO - PROCESS | 1916 | 03:41:21 INFO - PROCESS | 1916 | [#2] jquery.html Cycles:180 Average:33743.02 Median:27063.73 stddev:91592.98 (338.4%) stddev-sans-first:91815.50 03:41:21 INFO - PROCESS | 1916 | Values: 301.7 370.3 375.9 383.4 402.7 15434.5 17028.8 20268.0 20960.9 20980.4 19016.4 19348.2 20932.3 21228.3 21338.4 5872.0 5881.2 6089.3 6129.9 6130.3 5925.7 6082.6 6092.0 6092.3 6094.5 4181.6 6436.1 6707.4 6749.7 6825.0 6453.4 6666.1 6736.8 6745.5 6755.9 5811.0 5814.5 5818.0 5821.6 5822.2 26708.9 26973.0 27044.6 27082.9 27299.2 2231.6 2460.2 2516.9 2594.0 2752.2 15287.0 15626.5 15645.9 15880.4 16000.9 1724.2 1863.5 1909.8 1928.9 2001.4 97166.5 115554.1 118761.0 120089.0 120550.0 9810.5 10894.0 11411.3 11493.7 11680.0 14386.2 14418.7 14422.1 14427.4 14475.9 7460.8 8315.3 8611.4 8762.8 8774.7 8546.4 8578.4 8597.6 8744.4 8784.0 4458.2 4532.6 4540.8 4606.4 4652.3 1787.6 1865.2 1914.4 1936.8 1949.8 725.7 786.6 824.7 877.9 883.9 19328.7 20603.5 21306.4 21451.9 21501.6 931.1 972.2 976.2 1011.2 1058.1 9136.2 10361.7 11208.3 11237.8 11239.2 507625.0 510465.0 510561.0 591298.0 596539.0 101459.0 134787.0 140933.0 142445.0 148189.0 874.4 936.6 938.9 940.9 942.1 19512.8 19778.8 20005.6 20042.5 20095.1 42790.3 56770.0 57488.0 57712.0 57969.7 61993.1 78710.0 80588.0 81704.8 82009.0 5944.6 6479.3 6941.8 7659.0 8329.8 11756.6 12465.3 12727.5 12903.4 12997.0 10701.4 10973.6 11013.7 11088.6 11286.9 10835.3 10888.4 11152.5 11155.8 11208.1 10933.7 11325.0 11397.4 11425.5 11471.7 7175.2 7224.5 7314.3 7391.0 7467.4 10526.6 10962.6 11144.5 11167.6 11185.2 03:41:21 INFO - PROCESS | 1916 | 03:41:21 INFO - PROCESS | 1916 | [#3] mootools.html Cycles:180 Average:5606.67 Median:5061.46 stddev:3134.79 (61.9%) stddev-sans-first:3127.30 03:41:21 INFO - PROCESS | 1916 | Values: 1354.3 1365.5 1526.9 1673.4 1868.3 7756.0 8547.7 8781.3 8792.3 8867.9 8613.6 8695.0 8712.7 8716.3 8725.2 2341.3 2371.7 2375.0 2380.5 2403.4 2330.4 2358.3 2378.3 2384.4 2384.7 2345.3 2362.2 2370.1 2373.8 2374.0 2322.7 2335.3 2344.9 2347.3 2359.8 2018.3 2157.4 2314.6 2354.0 2359.7 8624.6 8704.9 8789.3 8797.5 8809.2 5173.1 5376.4 5378.4 5379.8 5393.5 3548.2 3571.3 3631.9 3648.3 3658.3 1087.0 1100.4 1101.6 1114.6 1120.7 11472.9 11534.3 11586.8 11597.7 11651.3 3955.3 4353.5 4483.6 4593.2 4640.1 9056.0 9063.9 9065.5 9076.1 9084.8 5191.6 5198.7 5199.1 5199.6 5200.5 4245.2 4308.0 4393.4 4557.5 4884.4 3777.2 3788.1 3879.4 3888.5 3902.6 1175.1 1181.4 1183.8 1197.2 1198.3 728.9 863.9 926.7 928.9 932.4 5784.0 5844.6 5890.6 6037.9 6161.4 440.0 441.5 455.2 458.5 459.3 3885.2 4006.4 4124.5 4127.7 4151.3 8701.7 10566.2 10608.5 10625.3 10664.0 10187.8 10308.2 10511.4 10546.9 10555.6 7463.6 7745.0 8060.7 8113.4 8119.9 5839.5 5886.7 5928.4 5931.3 6019.4 9363.7 9692.1 9728.5 9836.0 9927.0 9300.8 9886.0 10056.9 10102.2 10150.3 6583.3 6877.4 7023.3 7072.9 7141.1 8295.9 8324.0 8327.5 8387.8 8390.1 7482.8 7539.2 7559.0 7572.2 7637.8 7173.9 7326.8 7335.8 7408.7 7600.2 6731.3 6933.3 7067.5 7072.2 7077.7 4939.1 5008.0 5033.6 5089.3 5248.3 4978.2 5099.4 5115.3 5189.2 5265.6 03:41:21 INFO - PROCESS | 1916 | 03:41:21 INFO - PROCESS | 1916 | [#4] prototype.html Cycles:180 Average:4159.22 Median:4451.29 stddev:1242.90 (27.9%) stddev-sans-first:1211.51 03:41:21 INFO - PROCESS | 1916 | Values: 263.8 298.6 306.5 314.2 317.9 4820.5 5298.6 5348.5 5413.7 5423.9 4989.9 5056.6 5173.0 5354.8 5357.9 4092.4 4107.2 4113.8 4130.5 4132.2 4103.5 4112.8 4128.4 4142.9 4163.0 3924.7 4030.8 4068.6 4081.8 4082.9 4039.2 4056.1 4057.2 4070.2 4081.9 3368.7 3461.9 3527.9 3564.0 3569.4 3464.5 5116.4 5189.9 5222.7 5251.7 4557.0 4594.5 4612.5 4613.2 4627.3 5614.2 5616.9 5634.9 5638.5 5653.2 3011.8 3638.5 3809.8 3853.5 3853.7 4985.4 5510.6 5602.7 5885.3 5902.3 3856.3 3874.7 3880.3 3886.7 3888.6 3338.3 3347.5 3930.9 3952.8 3969.6 4024.4 4261.3 4354.3 4447.6 4647.4 5334.2 5646.5 5652.9 5673.4 5683.9 1159.9 1274.2 1316.5 1337.7 1346.8 749.5 848.2 871.8 872.7 874.7 3737.3 4050.3 4144.0 4182.7 4441.0 4153.7 4529.5 4816.9 4837.6 4855.6 3512.9 3984.4 4346.9 4361.7 4375.4 2127.7 2193.5 2453.5 2535.8 2551.4 4243.0 4452.2 4580.2 4646.8 4667.2 4203.4 4261.4 4515.7 4959.6 5015.6 4188.1 4404.9 5009.1 5037.7 5053.0 4578.6 4592.7 4801.0 4826.6 4826.8 4443.5 4450.4 4463.9 4709.6 4761.8 4204.4 4418.0 4587.1 4717.2 5059.3 3139.3 3323.0 3357.5 3366.2 3386.9 4155.2 4728.2 4793.0 4878.8 4906.7 4090.3 4309.7 4323.7 4648.0 4662.0 4385.6 4614.6 4658.8 4690.6 4696.5 4462.7 4537.5 4569.5 4599.9 5220.5 4201.0 5329.9 5546.7 5589.2 5613.8 4665.8 5072.4 5498.9 5516.5 5536.8 03:41:21 INFO - PROCESS | 1916 | 03:41:21 INFO - PROCESS | 1916 | [#5] yui.html Cycles:180 Average:25640.73 Median:23395.70 stddev:40670.54 (173.8%) stddev-sans-first:40741.61 03:41:21 INFO - PROCESS | 1916 | Values: 727.6 752.8 761.5 810.6 843.2 15962.5 16756.2 16760.6 16762.0 16767.1 14502.2 15053.4 15433.5 15437.0 15449.8 852.4 858.6 870.5 874.4 875.4 678.8 706.2 868.2 871.0 871.3 884.5 920.9 921.5 922.4 934.1 909.6 910.0 911.7 913.0 914.7 518.9 653.0 677.8 681.6 684.3 13296.5 15529.4 15604.5 15624.5 15624.8 14270.9 14493.2 14514.0 14520.0 14940.3 13138.1 13303.1 13450.2 13536.3 13843.5 4517.5 4582.5 4666.0 4726.9 4820.4 118795.4 118934.0 119113.0 119501.8 119513.0 11186.2 11578.0 11645.2 11645.4 11975.2 22717.9 22999.8 23117.9 23176.0 23615.4 7928.2 7995.8 8017.9 8122.1 8274.1 3828.4 4390.7 4428.8 4513.0 4554.7 3535.4 3655.2 3655.2 3656.3 3658.8 639.2 651.1 667.2 668.5 669.0 502.3 627.8 631.4 635.0 636.5 8049.5 8851.3 8958.8 10149.4 10405.5 532.7 597.8 627.0 645.1 665.8 3953.6 4442.3 4748.5 4872.7 4876.0 145475.5 145962.3 147150.0 150820.2 152483.0 131279.5 131543.3 131928.8 132659.0 135471.0 112349.0 115619.2 117467.8 117864.4 117903.8 9124.7 9251.6 9320.0 9344.3 9447.8 59366.4 62017.7 63188.7 63691.9 63740.0 85305.6 86655.0 89820.0 93107.0 99716.5 13112.5 13563.3 13607.8 13768.3 13963.7 15159.9 15342.7 15439.5 15493.4 15912.8 14139.6 14433.4 14568.2 14693.9 14712.9 11592.4 13083.7 13742.7 14086.3 14730.0 13600.2 13872.0 13972.8 14424.4 14436.1 10249.7 11031.0 11139.3 11309.3 11606.2 10679.9 10841.9 10947.9 11003.2 11096.3 03:41:21 INFO - PROCESS | 1916 | -------- Summary: end -------- 03:41:21 INFO - PROCESS | 1916 | 03:41:22 INFO - TEST-INFO | 1916: exit 0 03:41:22 INFO - TEST-OK | dromaeo_css | took 1117757ms 03:41:22 INFO - TEST-START | kraken 03:41:22 INFO - operating with platform_type : mac_ 03:41:22 INFO - Initialising browser for kraken test... 03:41:22 INFO - TEST-INFO | started process 1942 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmpTuRT8M/profile http://localhost:55746/getInfo.html) 03:41:24 INFO - PROCESS | 1942 | __metrics Screen width/height:1600/1200 03:41:24 INFO - PROCESS | 1942 | colorDepth:24 03:41:24 INFO - PROCESS | 1942 | Browser inner width/height: 1024/689 03:41:24 INFO - PROCESS | 1942 | __metrics 03:41:24 INFO - PROCESS | 1942 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:41:24 INFO - PROCESS | 1942 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:41:25 INFO - TEST-INFO | 1942: exit 0 03:41:25 INFO - Browser initialized. 03:41:25 INFO - Running cycle 1/1 for kraken test... 03:41:25 INFO - TEST-INFO | started process 1943 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmpTuRT8M/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:41:37 INFO - PROCESS | 1943 | RSS: Main: 228311040 03:41:37 INFO - PROCESS | 1943 | 03:41:40 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:55746/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55746/tests/kraken/driver.html?testName=audio-beat-detection) 03:41:41 INFO - PROCESS | 1943 | RSS: Main: 285253632 03:41:41 INFO - PROCESS | 1943 | 03:41:43 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:55746/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55746/tests/kraken/driver.html?testName=audio-dft) 03:41:44 INFO - PROCESS | 1943 | RSS: Main: 293158912 03:41:44 INFO - PROCESS | 1943 | 03:41:48 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:55746/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55746/tests/kraken/driver.html?testName=audio-fft) 03:41:48 INFO - PROCESS | 1943 | RSS: Main: 385110016 03:41:48 INFO - PROCESS | 1943 | 03:41:50 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:55746/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55746/tests/kraken/driver.html?testName=audio-oscillator) 03:41:51 INFO - PROCESS | 1943 | RSS: Main: 287682560 03:41:51 INFO - PROCESS | 1943 | 03:41:53 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:55746/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55746/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:41:54 INFO - PROCESS | 1943 | RSS: Main: 293056512 03:41:54 INFO - PROCESS | 1943 | 03:41:56 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:55746/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55746/tests/kraken/driver.html?testName=imaging-darkroom) 03:41:57 INFO - PROCESS | 1943 | RSS: Main: 328249344 03:41:57 INFO - PROCESS | 1943 | 03:42:00 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:55746/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55746/tests/kraken/driver.html?testName=imaging-desaturate) 03:42:00 INFO - PROCESS | 1943 | RSS: Main: 331182080 03:42:00 INFO - PROCESS | 1943 | 03:42:03 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:55746/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55746/tests/kraken/driver.html?testName=json-parse-financial) 03:42:04 INFO - PROCESS | 1943 | RSS: Main: 330829824 03:42:04 INFO - PROCESS | 1943 | 03:42:05 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:55746/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55746/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:42:06 INFO - PROCESS | 1943 | RSS: Main: 301031424 03:42:06 INFO - PROCESS | 1943 | 03:42:08 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:55746/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55746/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:42:09 INFO - PROCESS | 1943 | RSS: Main: 306376704 03:42:09 INFO - PROCESS | 1943 | 03:42:11 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:55746/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55746/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:42:11 INFO - PROCESS | 1943 | RSS: Main: 300892160 03:42:11 INFO - PROCESS | 1943 | 03:42:14 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:55746/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55746/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:42:15 INFO - PROCESS | 1943 | RSS: Main: 312324096 03:42:15 INFO - PROCESS | 1943 | 03:42:18 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:55746/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55746/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:42:19 INFO - PROCESS | 1943 | RSS: Main: 314400768 03:42:19 INFO - PROCESS | 1943 | 03:42:20 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:55746/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55746/tests/kraken/driver.html?testName=ai-astar) 03:42:20 INFO - PROCESS | 1943 | RSS: Main: 357937152 03:42:20 INFO - PROCESS | 1943 | 03:42:20 INFO - PROCESS | 1943 | __start_tp_report 03:42:20 INFO - PROCESS | 1943 | _x_x_mozilla_page_load 03:42:20 INFO - PROCESS | 1943 | _x_x_mozilla_page_load_details 03:42:20 INFO - PROCESS | 1943 | |i|pagename|runs| 03:42:21 INFO - PROCESS | 1943 | |0;ai-astar;100;111;112;113;108;111;113;113;112;112 03:42:21 INFO - PROCESS | 1943 | |1;audio-beat-detection;144;114;108;159;123;116;128;103;141;107 03:42:21 INFO - PROCESS | 1943 | |2;audio-dft;182;185;186;183;183;183;181;185;251;215 03:42:21 INFO - PROCESS | 1943 | |3;audio-fft;75;57;61;85;63;80;84;63;85;56 03:42:21 INFO - PROCESS | 1943 | |4;audio-oscillator;85;69;107;71;70;69;95;70;67;102 03:42:21 INFO - PROCESS | 1943 | |5;imaging-gaussian-blur;88;85;88;85;88;85;88;84;88;84 03:42:21 INFO - PROCESS | 1943 | |6;imaging-darkroom;92;92;92;94;93;92;93;94;98;93 03:42:21 INFO - PROCESS | 1943 | |7;imaging-desaturate;84;87;84;81;84;84;84;84;85;85 03:42:21 INFO - PROCESS | 1943 | |8;json-parse-financial;54;53;55;51;52;55;52;52;54;52 03:42:21 INFO - PROCESS | 1943 | |9;json-stringify-tinderbox;62;63;70;78;70;69;68;69;69;69 03:42:21 INFO - PROCESS | 1943 | |10;stanford-crypto-aes;84;85;86;83;85;85;85;83;89;86 03:42:21 INFO - PROCESS | 1943 | |11;stanford-crypto-ccm;140;135;141;135;157;147;152;138;208;139 03:42:21 INFO - PROCESS | 1943 | |12;stanford-crypto-pbkdf2;167;165;164;166;166;165;173;229;148;209 03:42:21 INFO - PROCESS | 1943 | |13;stanford-crypto-sha256-iterative;67;67;69;67;66;66;67;68;66;67 03:42:21 INFO - PROCESS | 1943 | __end_tp_report 03:42:21 INFO - PROCESS | 1943 | __start_cc_report 03:42:21 INFO - PROCESS | 1943 | _x_x_mozilla_cycle_collect,2009 03:42:21 INFO - PROCESS | 1943 | __end_cc_report 03:42:21 INFO - PROCESS | 1943 | __startTimestamp1471516940993__endTimestamp 03:42:21 INFO - PROCESS | 1943 | 03:42:21 INFO - PROCESS | 1943 | ------- Summary: start ------- 03:42:21 INFO - PROCESS | 1943 | Number of tests: 14 03:42:21 INFO - PROCESS | 1943 | 03:42:21 INFO - PROCESS | 1943 | [#0] ai-astar Cycles:10 Average:110.50 Median:112.00 stddev:3.98 (3.6%) stddev-sans-first:1.58 03:42:21 INFO - PROCESS | 1943 | Values: 100.0 111.0 112.0 113.0 108.0 111.0 113.0 113.0 112.0 112.0 03:42:21 INFO - PROCESS | 1943 | 03:42:21 INFO - PROCESS | 1943 | [#1] audio-beat-detection Cycles:10 Average:124.30 Median:125.50 stddev:18.50 (14.7%) stddev-sans-first:18.20 03:42:21 INFO - PROCESS | 1943 | Values: 144.0 114.0 108.0 159.0 123.0 116.0 128.0 103.0 141.0 107.0 03:42:21 INFO - PROCESS | 1943 | 03:42:21 INFO - PROCESS | 1943 | [#2] audio-dft Cycles:10 Average:193.40 Median:185.00 stddev:22.58 (12.2%) stddev-sans-first:23.57 03:42:21 INFO - PROCESS | 1943 | Values: 182.0 185.0 186.0 183.0 183.0 183.0 181.0 185.0 251.0 215.0 03:42:21 INFO - PROCESS | 1943 | 03:42:21 INFO - PROCESS | 1943 | [#3] audio-fft Cycles:10 Average:70.90 Median:77.50 stddev:12.05 (15.5%) stddev-sans-first:12.69 03:42:21 INFO - PROCESS | 1943 | Values: 75.0 57.0 61.0 85.0 63.0 80.0 84.0 63.0 85.0 56.0 03:42:21 INFO - PROCESS | 1943 | 03:42:21 INFO - PROCESS | 1943 | [#4] audio-oscillator Cycles:10 Average:80.50 Median:70.00 stddev:15.47 (22.1%) stddev-sans-first:16.32 03:42:21 INFO - PROCESS | 1943 | Values: 85.0 69.0 107.0 71.0 70.0 69.0 95.0 70.0 67.0 102.0 03:42:21 INFO - PROCESS | 1943 | 03:42:21 INFO - PROCESS | 1943 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:88.00 stddev:1.83 (2.1%) stddev-sans-first:1.83 03:42:21 INFO - PROCESS | 1943 | Values: 88.0 85.0 88.0 85.0 88.0 85.0 88.0 84.0 88.0 84.0 03:42:21 INFO - PROCESS | 1943 | 03:42:21 INFO - PROCESS | 1943 | [#6] imaging-darkroom Cycles:10 Average:93.30 Median:93.00 stddev:1.83 (2.0%) stddev-sans-first:1.88 03:42:21 INFO - PROCESS | 1943 | Values: 92.0 92.0 92.0 94.0 93.0 92.0 93.0 94.0 98.0 93.0 03:42:21 INFO - PROCESS | 1943 | 03:42:21 INFO - PROCESS | 1943 | [#7] imaging-desaturate Cycles:10 Average:84.20 Median:84.00 stddev:1.48 (1.8%) stddev-sans-first:1.56 03:42:21 INFO - PROCESS | 1943 | Values: 84.0 87.0 84.0 81.0 84.0 84.0 84.0 84.0 85.0 85.0 03:42:21 INFO - PROCESS | 1943 | 03:42:21 INFO - PROCESS | 1943 | [#8] json-parse-financial Cycles:10 Average:53.00 Median:53.50 stddev:1.41 (2.6%) stddev-sans-first:1.45 03:42:21 INFO - PROCESS | 1943 | Values: 54.0 53.0 55.0 51.0 52.0 55.0 52.0 52.0 54.0 52.0 03:42:21 INFO - PROCESS | 1943 | 03:42:21 INFO - PROCESS | 1943 | [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:69.00 stddev:4.32 (6.3%) stddev-sans-first:3.84 03:42:21 INFO - PROCESS | 1943 | Values: 62.0 63.0 70.0 78.0 70.0 69.0 68.0 69.0 69.0 69.0 03:42:21 INFO - PROCESS | 1943 | 03:42:21 INFO - PROCESS | 1943 | [#10] stanford-crypto-aes Cycles:10 Average:85.10 Median:85.00 stddev:1.73 (2.0%) stddev-sans-first:1.79 03:42:21 INFO - PROCESS | 1943 | Values: 84.0 85.0 86.0 83.0 85.0 85.0 85.0 83.0 89.0 86.0 03:42:21 INFO - PROCESS | 1943 | 03:42:21 INFO - PROCESS | 1943 | [#11] stanford-crypto-ccm Cycles:10 Average:149.20 Median:144.00 stddev:21.90 (15.2%) stddev-sans-first:22.97 03:42:21 INFO - PROCESS | 1943 | Values: 140.0 135.0 141.0 135.0 157.0 147.0 152.0 138.0 208.0 139.0 03:42:21 INFO - PROCESS | 1943 | 03:42:21 INFO - PROCESS | 1943 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.20 Median:166.50 stddev:24.38 (14.6%) stddev-sans-first:25.68 03:42:21 INFO - PROCESS | 1943 | Values: 167.0 165.0 164.0 166.0 166.0 165.0 173.0 229.0 148.0 209.0 03:42:21 INFO - PROCESS | 1943 | 03:42:21 INFO - PROCESS | 1943 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:0.94 (1.4%) stddev-sans-first:1.00 03:42:21 INFO - PROCESS | 1943 | Values: 67.0 67.0 69.0 67.0 66.0 66.0 67.0 68.0 66.0 67.0 03:42:21 INFO - PROCESS | 1943 | -------- Summary: end -------- 03:42:21 INFO - PROCESS | 1943 | 03:42:21 INFO - TEST-INFO | 1943: exit 0 03:42:21 INFO - TEST-OK | kraken | took 59377ms 03:42:21 INFO - SUITE-END | took 1177s 03:42:22 INFO - Completed test suite (00:19:37) 03:42:22 INFO - javascript benchmark 03:42:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3309.4704847224443, 3333.6333003369623, 3347.263602007559, 3367.0, 3372.7133193678537, 5492.368377636573, 5603.663780173188, 5625.662460252388, 5641.548676105912, 5699.259096317483, 5583.720813959306, 5638.0415329394, 5640.407757185498, 5657.094864821629, 5657.915131273035, 5110.105731496982, 5151.896962060757, 5166.328377310944, 5183.792648294083, 5233.450487698794, 5193.065248255312, 5211.218317252405, 5216.035033518811, 5220.686758794465, 5224.7387630618505, 5178.14560597501, 5205.453427390126, 5207.244949482323, 5207.687538747668, 5228.843134705965, 5271.947280527184, 5296.443873393277, 5305.257263983045, 5330.173823057432, 5364.222187782789, 5067.34124563808, 5078.365204349457, 5105.463926287724, 5208.062548741225, 5215.530602245816, 5588.636738612018, 5653.0, 5664.0371136906415, 5666.688332141731, 5675.262215911946, 6214.974529202676, 6238.251409830804, 6241.3446588108045, 6247.937520624827, 6266.060090986343, 6086.634801911855, 6114.847128821771, 6183.752649893999, 6273.529485288576, 6275.309715931244, 5738.569607279471, 5766.077427611562, 5781.82654520365, 5788.21859049023, 5815.273090863642, 6286.465650419674, 6326.082718005955, 6343.746250150035, 6345.587536810092, 6349.777757778455, 5893.145493903446, 5971.492423144082, 6127.050307202391, 6129.356417576179, 6136.821428392946, 6106.077105207941, 6164.722587483574, 6172.290186628506, 6241.375862413723, 6242.063690446378, 6116.143739876421, 6140.508759299335, 6177.073438984188, 6178.042403427476, 6180.134781130645, 6017.669028203491, 6139.723712432951, 6202.72087756052, 6212.4408803207925, 6214.658193799384, 4234.957650423518, 4857.587105096036, 4870.196417591058, 4898.755062246873, 4930.5069493050405, 4387.078713470143, 4390.0, 4393.121375724804, 4399.428074350314, 4436.223660859337, 4853.684510506876, 5573.303337082864, 5659.066254068115, 5683.175939488834, 5750.25246717924, 6169.321374648785, 6171.352008039181, 6214.316425193225, 6246.219222597176, 6358.237011558643, 5697.145428185722, 5710.428957104256, 5710.5146062585145, 5728.427157284238, 5746.827595172152, 5402.027635025733, 5417.59368047398, 5419.539339156216, 5419.566434685215, 5426.593005524523, 6217.129601855744, 6217.222847144107, 6267.717952692049, 6277.246730392382, 6339.683015849281, 6321.30465648769, 6321.620702757849, 6332.936670633235, 6351.777687780906, 6355.682215889279, 6259.780907668392, 6312.0, 6312.495000400048, 6316.84207894788, 6391.520635952229, 5944.851378715567, 6112.564273424362, 6112.694365281629, 6114.785982490592, 6148.4773794227995, 6243.157173781481, 6266.060090986389, 6273.059041143865, 6278.963970944743, 6297.433231009231, 6063.0, 6082.635041897501, 6098.75604975796, 6105.9084113737445, 6106.023036314168, 4962.0, 5781.733320673806, 5977.701114944322, 6001.099835024781, 6055.1522786809, 5952.0476723724, 5952.255968003999, 5953.28560572734, 5977.0, 5998.970005149947, 6062.42406971337, 6065.363136870565, 6086.2087928569, 6098.237720284965, 6098.847528811815, 5744.0, 5825.184474173534, 5835.67903765297, 5888.882222355443, 5914.290285165807], "value": 5667.216424460005, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [480.5866954419199, 509.87762936895143, 518.2847670215102, 520.3001962360626, 549.0364410459644, 30882.227944301405, 31234.750609975603, 32532.67467325326, 32559.837200813963, 32583.67416325836, 42470.15059698804, 43608.3458748119, 43747.56252437475, 45690.77154614231, 45792.0, 9797.461139637335, 9947.20422366209, 9953.751156221117, 9971.601135954554, 9975.750606234866, 9881.0, 9977.15194208493, 9978.700638980843, 9987.800243995152, 10025.147862431702, 9930.05664461875, 9973.90026099737, 9978.750531236741, 9979.750506237366, 10003.299769016136, 9857.654982075628, 9951.203903687687, 9957.153641940371, 9969.152622027137, 9974.351667141613, 9738.464384458852, 9833.115019648194, 9843.015698430172, 9846.90153098467, 9883.950580247125, 41089.0, 41402.1719565607, 41412.0, 41423.7928810354, 41498.0, 3361.7142542883885, 3444.190615205425, 3568.7145028397927, 3641.963580364189, 3910.5502867170353, 29499.96750113736, 31231.687683123106, 32303.192420189447, 32410.513842292385, 32448.0, 3319.3029463812627, 3339.96660033399, 3352.1619595101224, 3422.606400263952, 3462.4633181856852, 484764.0, 485024.0, 485791.0, 486434.0, 493090.53454732854, 59597.40402596005, 59843.0, 59973.0, 59974.70012649909, 60051.09923351153, 62983.0, 63012.684936575024, 63013.68493157505, 63188.25799614077, 63390.0, 16146.838531614534, 16245.944013639075, 16452.177391130397, 16471.0, 16481.093539855185, 12722.300273485047, 12777.488900443877, 12784.872151278554, 12810.359482026048, 12814.103012789015, 13585.796213056812, 15020.173890435912, 16594.83405165957, 16616.504514593744, 16733.832661673227, 5311.920321195185, 6747.527673062838, 6895.862082758316, 6980.301969802979, 7005.474589405815, 868.4572142410993, 898.9101089890959, 1033.3696445168441, 1049.8057859296055, 1150.396042077899, 28872.123311985073, 30342.089737307917, 30561.69438305633, 31052.534211986836, 31130.84434577858, 1050.7373156710823, 1097.5664612478026, 1134.5235001299475, 1142.3831131189231, 1234.7098431868503, 15260.542183734506, 15740.68518629621, 15752.369905203663, 15792.36830526789, 16160.949538280209, 648135.0, 655881.0, 656586.0, 658328.0, 658714.0, 59081.40918590845, 59151.408485915454, 59152.40847591555, 59182.11226831601, 59216.40783592195, 1216.0575553945587, 1334.7797613393682, 1360.8094866718668, 1376.5526203983545, 1393.986060139406, 30716.078517644506, 31600.841995789873, 31841.840790795897, 32287.838560807046, 32363.676363236067, 50796.74601626968, 51359.74320128375, 51361.74319128381, 51410.97178056493, 51481.48518514767, 439341.0, 439512.0, 440037.5996240125, 440381.0, 441008.0, 25309.62035569431, 26001.34996625024, 26091.0, 26798.464030719668, 37408.0, 46447.83880403017, 46581.76709116568, 46951.53048469472, 46994.0, 47878.0, 38466.2306753869, 38892.80553597327, 38954.0, 39811.80094099511, 40210.39684404678, 37931.0, 38304.0, 38341.0, 39697.80151099226, 40459.59540404558, 37238.8138059308, 37374.0, 38096.0, 38183.0, 38237.0, 28341.724622392456, 30816.691833082277, 33352.83323583366, 33376.0, 33715.494267586495, 23778.40553986164, 25251.242462726146, 25283.747162528874, 25614.975400983752, 25640.61539076878], "value": 19880.032116954502, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.6998086903531, 370.3112211287004, 375.89287053189844, 383.39806503789055, 402.71809733186774, 15434.459793907201, 17028.829711702896, 20268.0, 20960.895195524, 20980.37058888232, 19016.429507114775, 19348.226070957146, 20932.267370641992, 21228.257011004618, 21338.35984920451, 5872.0, 5881.235439392873, 6089.330173680892, 6129.877402451948, 6130.2643682758135, 5925.674087925162, 6082.604630699013, 6092.025275955848, 6092.29938557065, 6094.512439004869, 4181.623653871151, 6436.1311222985, 6707.396334329909, 6749.7300107995625, 6825.044493770876, 6453.386928241809, 6666.100076489661, 6736.797896063126, 6745.527813053088, 6755.885278928971, 5811.012127938261, 5814.5057670098095, 5817.970910145465, 5821.563382746311, 5822.214001109881, 26708.866455667598, 26973.0, 27044.59433108505, 27082.864585677144, 27299.181024569294, 2231.5871563760757, 2460.225029115823, 2516.8867400967, 2593.974060259392, 2752.2431331383827, 15287.006344587677, 15626.45307414235, 15645.904786664938, 15880.444184453605, 16000.919995400065, 1724.2068648421741, 1863.5154859736567, 1909.8472122230328, 1928.9421317360502, 2001.449601359623, 97166.51416742941, 115554.11114722115, 118761.03097422548, 120089.0, 120550.0, 9810.509474526245, 10894.019538241597, 11411.25826821254, 11493.655190344303, 11679.99999999983, 14386.208758518382, 14418.711625767426, 14422.134671919719, 14427.42290308376, 14475.855241447662, 7460.776176714708, 8315.29320007794, 8611.354148438892, 8762.824743505094, 8774.736757897146, 8546.401751877309, 8578.356623253281, 8597.5701214939, 8744.431611945203, 8783.989841168219, 4458.152950939311, 4532.592066713945, 4540.77296135192, 4606.424196975378, 4652.325412815123, 1787.6067265201896, 1865.1793210987123, 1914.4161030885714, 1936.845052395805, 1949.7952714965008, 725.6516871901517, 786.6460092958192, 824.674253669797, 877.8770972063916, 883.9248663863643, 19328.71006934869, 20603.4849128773, 21306.36080917575, 21451.89274053651, 21501.588996263203, 931.0642803981955, 972.2027937091517, 976.1653786012998, 1011.1809434358194, 1058.0583280880023, 9136.223421009156, 10361.740956475998, 11208.327500350006, 11237.775244495064, 11239.21325507223, 507625.0, 510465.0, 510561.0, 591298.0, 596539.0, 101459.0, 134787.0, 140933.0, 142445.0, 148189.0, 874.4316194473643, 936.5551363102472, 938.8920274168559, 940.9414408790248, 942.072059021863, 19512.804871951666, 19778.802211978273, 20005.59988800245, 20042.498937526678, 20095.095720692312, 42790.2977464047, 56770.0, 57488.0, 57711.98008069865, 57969.71015144897, 61993.070103947575, 78710.0, 80588.0, 81704.77442838243, 82009.0, 5944.5838791284195, 6479.287278399278, 6941.757038503628, 7658.961705191438, 8329.83340333202, 11756.58851940178, 12465.314407707301, 12727.490900363882, 12903.41934612926, 12997.0, 10701.357918524913, 10973.561057557608, 11013.724656883322, 11088.611898583833, 11286.887131128911, 10835.295705779099, 10888.40113793749, 11152.498137583665, 11155.776884462428, 11208.10335173168, 10933.671989840317, 11324.980751732384, 11397.430128493708, 11425.542978280775, 11471.705658868093, 7175.174854891754, 7224.494285399972, 7314.341709246193, 7390.96304518474, 7467.402607791255, 10526.631567895087, 10962.56149754001, 11144.498497567467, 11167.553297868319, 11185.217034807485], "value": 9655.86049437274, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1354.2551596621856, 1365.460643045997, 1526.8549487798662, 1673.3557580331576, 1868.2620364955842, 7756.0, 8547.700830470934, 8781.297496200305, 8792.252658524018, 8867.91132088679, 8613.612387442565, 8695.0, 8712.738617841474, 8716.302695784338, 8725.171108744675, 2341.344423561401, 2371.715394152704, 2375.0143690368477, 2380.5000949800565, 2403.4472071423584, 2330.382448651109, 2358.2571489980687, 2378.335165384229, 2384.415818124562, 2384.7257565380028, 2345.26124270855, 2362.196853069956, 2370.0756704885107, 2373.8012303786563, 2373.9881300593474, 2322.744498105207, 2335.252719129879, 2344.929652110431, 2347.272345572878, 2359.752226016261, 2018.253246298864, 2157.4067131538795, 2314.618088015475, 2353.9529209415887, 2359.740428552858, 8624.568771561397, 8704.912950870474, 8789.296856251485, 8797.516136612483, 8809.163129502687, 5173.120569503193, 5376.4085950545405, 5378.408375078739, 5379.784808607651, 5393.460653934614, 3548.2193917338145, 3571.285742851414, 3631.909202269938, 3648.3068217038676, 3658.3414985302634, 1087.0434018064052, 1100.4387762241236, 1101.6474728087014, 1114.6321713834416, 1120.6694025262568, 11472.94263528677, 11534.250273732181, 11586.76826463466, 11597.710057248552, 11651.30092194471, 3955.3473676843573, 4353.455818022747, 4483.641308695296, 4593.19619066662, 4640.071985602893, 9055.95472022649, 9063.864042039375, 9065.546722663841, 9076.137766912132, 9084.81830363389, 5191.610629202825, 5198.714070726146, 5199.1161502544655, 5199.610029247821, 5200.453952335026, 4245.1934132514725, 4308.0, 4393.428854248991, 4557.45310562728, 4884.438289596742, 3777.206786574795, 3788.052986753312, 3879.3599056155417, 3888.4556162137324, 3902.551206611219, 1175.0951767139254, 1181.3915833345834, 1183.8401815754935, 1197.1500234833193, 1198.2810313811817, 728.8797348437448, 863.9287283768082, 926.7219834049893, 928.874601928731, 932.4265576670299, 5784.0, 5844.649321040752, 5890.587658863924, 6037.879242415127, 6161.4061614062, 439.9802008909607, 441.45480331790856, 455.2192989023774, 458.53458739379556, 459.34084588615445, 3885.203533275672, 4006.3790112532606, 4124.52567954689, 4127.690423218271, 4151.252774500619, 8701.695440659547, 10566.207534434794, 10608.522616482276, 10625.256232063835, 10664.040236378609, 10187.796244075076, 10308.175345972304, 10511.369317840954, 10546.894531054744, 10555.577776888838, 7463.589502577302, 7744.961275193588, 8060.677572897136, 8113.432059755761, 8119.9188008120345, 5839.510536508027, 5886.705664716833, 5928.436798504135, 5931.288245410406, 6019.367966363644, 9363.719088427359, 9692.127708505986, 9728.513574321398, 9836.0, 9926.950365248127, 9300.813983720423, 9885.950570247103, 10056.949715251378, 10102.24233182529, 10150.340227885164, 6583.34166583357, 6877.38103570661, 7023.33278338557, 7072.893906591508, 7141.107361579802, 8295.875561866456, 8323.95838020806, 8327.50034997902, 8387.790305242417, 8390.077091519806, 7482.812929676802, 7539.246075392636, 7558.962205188939, 7572.167061623327, 7637.847243055219, 7173.928260717326, 7326.816829579303, 7335.77992660221, 7408.740694075682, 7600.1639819618695, 6731.293214212437, 6933.306669333027, 7067.469939754436, 7072.222055573986, 7077.7168913242895, 4939.061578300111, 5007.974960125176, 5033.597312214941, 5089.312942752681, 5248.291480650063, 4978.178600530872, 5099.413567439792, 5115.283860259492, 5189.169732842727, 5265.631405801557], "value": 4437.574255134777, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [263.83510306058713, 298.5983851719438, 306.4927544913169, 314.16432290108315, 317.9062176657886, 4820.469748327681, 5298.57568929944, 5348.46515348465, 5413.729313534327, 5423.864403389913, 4989.850304490862, 5056.570191533724, 5173.0, 5354.812581559646, 5357.866053348668, 4092.3656833190753, 4107.178564287147, 4113.773742444165, 4130.524989626187, 4132.214879172948, 4103.5280942691525, 4112.773797441139, 4128.401381799637, 4142.937855932163, 4162.958370416302, 3924.725269231155, 4030.758154510724, 4068.596334264674, 4081.7755023473696, 4082.856800095978, 4039.151778126597, 4056.0873803394297, 4057.2088442753675, 4070.2266569351877, 4081.8775436736946, 3368.6631336866362, 3461.8961431156854, 3527.876524321662, 3563.982180089109, 3569.375359312136, 3464.549608550872, 5116.437191908887, 5189.896202075975, 5222.660527065728, 5251.684898906078, 4556.951901062741, 4594.517575654575, 4612.515685852971, 4613.1696294666945, 4627.282771170474, 5614.242077319591, 5616.887662246732, 5634.91508141986, 5638.462691788671, 5653.2299173405145, 3011.804232724866, 3638.490611314418, 3809.7999130273874, 3853.5183102112237, 3853.7109716771074, 4985.376827896513, 5510.559155267569, 5602.691851948141, 5885.293764748215, 5902.291724993029, 3856.2646808936092, 3874.721341957147, 3880.3403421418425, 3886.6502014818802, 3888.5528164260904, 3338.2989572190113, 3347.4726581430864, 3930.9213815723524, 3952.782596957195, 3969.6228858258996, 4024.376221685585, 4261.296886013773, 4354.259775838115, 4447.599716025573, 4647.372604698322, 5334.199870019451, 5646.548276137899, 5652.943470565324, 5673.446948872941, 5683.943160568341, 1159.8608167019843, 1274.184521905981, 1316.5194703932996, 1337.7190789934223, 1346.7575836349552, 749.456643933155, 848.245061894914, 871.834351473218, 872.6858331000834, 874.7419511244199, 3737.2712321097124, 4050.326784322333, 4143.97928010364, 4182.686298527562, 4440.977795111004, 4153.6677065834665, 4529.547045295444, 4816.8795780105775, 4837.612990960714, 4855.587275081658, 3512.877049303262, 3984.373830656654, 4346.913061738747, 4361.672874534423, 4375.381108989694, 2127.6702061211745, 2193.5064610462837, 2453.4915071448, 2535.782824244373, 2551.413174969767, 4242.978785106055, 4452.1763473757455, 4580.190824624255, 4646.83736069236, 4667.192791424838, 4203.390508376268, 4261.403403523509, 4515.661325400608, 4959.553640172329, 5015.648904576645, 4188.099558594856, 4404.933925991113, 5009.117933601488, 5037.697738135724, 5053.0, 4578.5650363215445, 4592.678512504092, 4800.991791723777, 4826.634948875097, 4826.8069277228515, 4443.466783985942, 4450.399196108613, 4463.933041004322, 4709.576138147583, 4761.809527618856, 4204.369344598213, 4417.955820441754, 4587.087199628193, 4717.174494463524, 5059.291699162194, 3139.340738444952, 3322.9501557477142, 3357.4795906634513, 3366.1584603849037, 3386.9179047068037, 4155.231282212801, 4728.17256980034, 4792.95207047939, 4878.804847806048, 4906.681065730716, 4090.3455447128317, 4309.698321117492, 4323.654107671316, 4647.953520464887, 4662.0, 4385.605295523418, 4614.584687378152, 4658.790354434126, 4690.601298889633, 4696.497120921273, 4462.66120163943, 4537.546245375568, 4569.497355290849, 4599.908001839877, 5220.504052115043, 4201.046513371175, 5329.893402132013, 5546.724253421655, 5589.245451863946, 5613.803516876889, 4665.836695715634, 5072.365954255718, 5498.86564658141, 5516.510542153676, 5536.75084621201], "value": 3772.6381350986194, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [727.6216367488905, 752.8306131120498, 761.5202422473842, 810.6352141536311, 843.2284459719357, 15962.521124366258, 16756.162191890417, 16760.580985475368, 16762.0, 16767.077810720435, 14502.202378869159, 15053.397864085451, 15433.536993890173, 15437.0, 15449.768253476206, 852.3607294529103, 858.6050416808275, 870.4820631724114, 874.3660845886714, 875.4397185801092, 678.7997540725498, 706.2443185791205, 868.15355028847, 870.9564521773917, 871.255076909243, 884.4958373726978, 920.8710780490703, 921.4655499810094, 922.4373132389237, 934.0612684252317, 909.5543183839937, 909.9990010987893, 911.6854685133685, 912.95891684874, 914.6570036236411, 518.8521271437622, 652.9836754081139, 677.8102131403215, 681.6148875885109, 684.3122661724934, 13296.534621288305, 15529.378824846992, 15604.453844115515, 15624.453144140014, 15624.76562851558, 14270.858331333573, 14493.202873841934, 14514.0, 14520.0, 14940.32768525408, 13138.080334376598, 13303.135296205715, 13450.192988420726, 13536.323183840768, 13843.515476958359, 4517.457905051382, 4582.450718967266, 4665.976670116662, 4726.858194254178, 4820.373351464323, 118795.4060229702, 118934.0, 119113.0, 119501.8049819508, 119513.0, 11186.216964812385, 11578.0, 11645.243059201124, 11645.359505227125, 11975.161738678384, 22717.88641056784, 22999.770002300098, 23117.88441057784, 23176.0, 23615.409614759425, 7928.207179282025, 7995.800104997305, 8017.919820801834, 8122.065962414398, 8274.089850116483, 3828.3874580067054, 4390.692651514363, 4428.82284708608, 4512.954870451319, 4554.726716397026, 3535.4343305071066, 3655.1593133579427, 3655.1958569114754, 3656.2504686539196, 3658.835352409148, 639.1946147853645, 651.0592194279221, 667.152716050613, 668.5019660352971, 669.0065253099144, 502.28424495094345, 627.7677259414046, 631.36232405271, 634.9871954232991, 636.5098873867097, 8049.476784009021, 8851.291896648252, 8958.835351404407, 10149.391036537683, 10405.47972601367, 532.7283085626359, 597.7847974729094, 627.0030651264511, 645.106527459472, 665.8002599220215, 3953.6244056814558, 4442.311441726538, 4748.529492469097, 4872.6589138760655, 4876.0, 145475.5452445462, 145962.2701886505, 147150.0, 150820.245898772, 152483.0, 131279.49764018855, 131543.34228328796, 131928.84924959886, 132659.0, 135471.0, 112349.0, 115619.17237034778, 117467.8253217457, 117864.41067794777, 117903.82096178929, 9124.726258212264, 9251.629934802533, 9319.953400232955, 9344.252459803336, 9447.763805904908, 59366.40633593781, 62017.68991155015, 63188.68405657943, 63691.89853927739, 63740.0, 85305.57347213224, 86655.0, 89820.0, 93107.0, 99716.50141749244, 13112.503730229933, 13563.321833908069, 13607.79588306196, 13768.311584420939, 13963.720725585634, 15159.924200378928, 15342.693146137239, 15439.536813895602, 15493.380264789283, 15912.761308580612, 14139.64650883695, 14433.422663093359, 14568.198749068903, 14693.92653036728, 14712.852871471148, 11592.362420066976, 13083.672908176992, 13742.725145497234, 14086.295685215904, 14730.0, 13600.183988960694, 13872.0, 13972.790408143683, 14424.350904209126, 14436.061655992327, 10249.74375640615, 11031.0, 11139.273412620732, 11309.264897781617, 11606.24559403636, 10679.946600266949, 10841.945790270998, 10947.945260273647, 11003.17476189305, 11096.334219946506], "value": 8025.4212079293975, "unit": "score"}], "name": "dromaeo_css", "value": 7257.961609263347, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 111.0, 112.0, 113.0, 108.0, 111.0, 113.0, 113.0, 112.0, 112.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 114.0, 108.0, 159.0, 123.0, 116.0, 128.0, 103.0, 141.0, 107.0], "value": 124.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 185.0, 186.0, 183.0, 183.0, 183.0, 181.0, 185.0, 251.0, 215.0], "value": 193.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 57.0, 61.0, 85.0, 63.0, 80.0, 84.0, 63.0, 85.0, 56.0], "value": 70.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 69.0, 107.0, 71.0, 70.0, 69.0, 95.0, 70.0, 67.0, 102.0], "value": 80.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 85.0, 88.0, 85.0, 88.0, 84.0, 88.0, 84.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 92.0, 94.0, 93.0, 92.0, 93.0, 94.0, 98.0, 93.0], "value": 93.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 84.0, 81.0, 84.0, 84.0, 84.0, 84.0, 85.0, 85.0], "value": 84.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 55.0, 51.0, 52.0, 55.0, 52.0, 52.0, 54.0, 52.0], "value": 53.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 70.0, 78.0, 70.0, 69.0, 68.0, 69.0, 69.0, 69.0], "value": 68.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 86.0, 83.0, 85.0, 85.0, 85.0, 83.0, 89.0, 86.0], "value": 85.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 135.0, 141.0, 135.0, 157.0, 147.0, 152.0, 138.0, 208.0, 139.0], "value": 149.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 165.0, 164.0, 166.0, 166.0, 165.0, 173.0, 229.0, 148.0, 209.0], "value": 175.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 69.0, 67.0, 66.0, 66.0, 67.0, 68.0, 66.0, 67.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1441.6000000000001, "alertThreshold": 2.0}]} 03:42:22 INFO - Return code: 0 03:42:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:42:22 INFO - # TBPL SUCCESS # 03:42:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:42:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:42:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:42:22 INFO - 2016-08-18 03:42:22.314 screenresolution[1947:21878] starting screenresolution argv=screenresolution get 03:42:22 INFO - 2016-08-18 03:42:22.317 screenresolution[1947:21878] Display 0: 1600x1200x32@60 03:42:22 INFO - 2016-08-18 03:42:22.323 screenresolution[1948:21881] starting screenresolution argv=screenresolution list 03:42:22 INFO - Available Modes on Display 0 03:42:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:42:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:42:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:42:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:42:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:42:22 INFO - Intel Iris: 03:42:22 INFO - Chipset Model: Intel Iris 03:42:22 INFO - Type: GPU 03:42:22 INFO - Bus: Built-In 03:42:22 INFO - VRAM (Dynamic, Max): 1536 MB 03:42:22 INFO - Vendor: Intel (0x8086) 03:42:22 INFO - Device ID: 0x0a2e 03:42:22 INFO - Revision ID: 0x0009 03:42:22 INFO - Displays: 03:42:22 INFO - Display: 03:42:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:42:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:42:22 INFO - Display Serial Number: 1600x1200 60 03:42:22 INFO - Main Display: Yes 03:42:22 INFO - Mirror: Off 03:42:22 INFO - Online: Yes 03:42:22 INFO - Rotation: Supported 03:42:22 INFO - Return code: 0 03:42:22 INFO - Running post-action listener: _resource_record_post_action 03:42:22 INFO - [mozharness: 2016-08-18 10:42:22.559532Z] Finished run-tests step (success) 03:42:22 INFO - Running post-run listener: _resource_record_post_run 03:42:22 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 380928; Write bytes: 711317504; Read time: 7; Write time: 2004 03:42:22 INFO - TinderboxPrint: CPU usage
53.8% 03:42:22 INFO - TinderboxPrint: I/O read bytes / time
380,928 / 7 03:42:22 INFO - TinderboxPrint: I/O write bytes / time
711,317,504 / 2,004 03:42:22 INFO - TinderboxPrint: CPU idle
2,241.2 (46.2%) 03:42:22 INFO - TinderboxPrint: CPU system
972.5 (20.1%) 03:42:22 INFO - TinderboxPrint: CPU user
1,636.0 (33.7%) 03:42:22 INFO - TinderboxPrint: Swap in / out
405,192,704 / 0 03:42:22 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 398660096; Write bytes: 395260928; Read time: 29853; Write time: 796 03:42:22 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 372736; Write bytes: 300151808; Read time: 7; Write time: 1165 03:42:23 INFO - Running post-run listener: _upload_blobber_files 03:42:23 INFO - Blob upload gear active. 03:42:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:42:23 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 03:42:23 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:42:23 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:42:23 INFO - (blobuploader) - INFO - Open directory for files ... 03:42:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:42:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:42:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:24 INFO - (blobuploader) - INFO - Done attempting. 03:42:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:42:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:42:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:25 INFO - (blobuploader) - INFO - Done attempting. 03:42:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:42:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:42:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:27 INFO - (blobuploader) - INFO - Done attempting. 03:42:27 INFO - (blobuploader) - INFO - Iteration through files over. 03:42:27 INFO - Return code: 0 03:42:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:42:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:42:27 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1bcc06f66427d4014b57ed74f44c0b5ab91d4ac5d3096c3dedee12bf5ff55f5803fd0e3f49974067a3731cd0c7025a7c99435dd14fcc092c6eaa2feacc6e66da", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/aab1293a01e76262de33ada84bac6874f70b46f05546731c7b9df15394998700759af77fe1279617320184916030f0432c3c79f2720da874389401a5c4cc0dea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/10e64595485b366d8cfbdba8e50db0a8989bb5c410c70353dbfc3ef93577d8aae40ffdb9da85cc3fa0ae76ef88c003348104b9708521449e4ec6dbd39c6816c9"} 03:42:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:42:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:42:27 INFO - Contents: 03:42:27 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1bcc06f66427d4014b57ed74f44c0b5ab91d4ac5d3096c3dedee12bf5ff55f5803fd0e3f49974067a3731cd0c7025a7c99435dd14fcc092c6eaa2feacc6e66da", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/aab1293a01e76262de33ada84bac6874f70b46f05546731c7b9df15394998700759af77fe1279617320184916030f0432c3c79f2720da874389401a5c4cc0dea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/10e64595485b366d8cfbdba8e50db0a8989bb5c410c70353dbfc3ef93577d8aae40ffdb9da85cc3fa0ae76ef88c003348104b9708521449e4ec6dbd39c6816c9"} 03:42:27 INFO - Running post-run listener: copy_logs_to_upload_dir 03:42:27 INFO - Copying logs to upload dir... 03:42:27 INFO - mkdir: /builds/slave/test/build/upload/logs 03:42:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.614692 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-08-18 03:42:27.358206) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 03:42:27.361940) ========= 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.ttY3fXo9Wp/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.4kM9fFCOO6/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1bcc06f66427d4014b57ed74f44c0b5ab91d4ac5d3096c3dedee12bf5ff55f5803fd0e3f49974067a3731cd0c7025a7c99435dd14fcc092c6eaa2feacc6e66da", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/aab1293a01e76262de33ada84bac6874f70b46f05546731c7b9df15394998700759af77fe1279617320184916030f0432c3c79f2720da874389401a5c4cc0dea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/10e64595485b366d8cfbdba8e50db0a8989bb5c410c70353dbfc3ef93577d8aae40ffdb9da85cc3fa0ae76ef88c003348104b9708521449e4ec6dbd39c6816c9"} build_url:https://queue.taskcluster.net/v1/task/KZEBFf6SRRSUtFP-5y3ysw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011163 build_url: 'https://queue.taskcluster.net/v1/task/KZEBFf6SRRSUtFP-5y3ysw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1bcc06f66427d4014b57ed74f44c0b5ab91d4ac5d3096c3dedee12bf5ff55f5803fd0e3f49974067a3731cd0c7025a7c99435dd14fcc092c6eaa2feacc6e66da", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/aab1293a01e76262de33ada84bac6874f70b46f05546731c7b9df15394998700759af77fe1279617320184916030f0432c3c79f2720da874389401a5c4cc0dea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/10e64595485b366d8cfbdba8e50db0a8989bb5c410c70353dbfc3ef93577d8aae40ffdb9da85cc3fa0ae76ef88c003348104b9708521449e4ec6dbd39c6816c9"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 03:42:27.400282) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 03:42:27.401005) ========= 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.ttY3fXo9Wp/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.4kM9fFCOO6/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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.005385 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 03:42:27.451510) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 03:42:27.451849) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 03:42:27.452208) ========= ========= Total master_lag: 0.19 =========