builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0135 starttime: 1472920787.78 results: success (0) buildid: 20160903071749 builduid: 63f212c225e14be9917090e32f177c6e revision: bcab45259b4fe5787d0d6fea16067e763f2a11ea ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-03 09:39:47.778876) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-03 09:39:47.779319) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-03 09:39:47.779595) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-03 09:39:47.803252) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 09:39:47.803525) ========= 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.waQQu08FRQ/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.UQbqCRhUPc/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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-09-03 09:39:47-- 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% 8.24M=0.001s 2016-09-03 09:39:48 (8.24 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.586212 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 09:39:48.425227) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 09:39:48.425646) ========= 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.waQQu08FRQ/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.UQbqCRhUPc/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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.021940 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 09:39:48.477807) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-03 09:39:48.481279) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev bcab45259b4fe5787d0d6fea16067e763f2a11ea --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev bcab45259b4fe5787d0d6fea16067e763f2a11ea --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.waQQu08FRQ/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.UQbqCRhUPc/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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-09-03 09:39:48,559 truncating revision to first 12 chars 2016-09-03 09:39:48,559 Setting DEBUG logging. 2016-09-03 09:39:48,559 attempt 1/10 2016-09-03 09:39:48,559 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/bcab45259b4f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-03 09:39:49,618 unpacking tar archive at: fx-team-bcab45259b4f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.306199 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-03 09:39:49.810614) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-03 09:39:49.810937) ========= script_repo_revision: bcab45259b4fe5787d0d6fea16067e763f2a11ea ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-03 09:39:49.811441) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-03 09:39:49.811719) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-03 09:39:49.832763) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-03 09:39:49.833077) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.waQQu08FRQ/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.UQbqCRhUPc/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:39:50 INFO - MultiFileLogger online at 20160903 09:39:50 in /builds/slave/test 09:39:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:39:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:39:50 INFO - {'append_to_log': False, 09:39:50 INFO - 'base_work_dir': '/builds/slave/test', 09:39:50 INFO - 'blob_upload_branch': 'Fx-Team', 09:39:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:39:50 INFO - 'branch': 'Fx-Team', 09:39:50 INFO - 'buildbot_json_path': 'buildprops.json', 09:39:50 INFO - 'config_files': ('talos/mac_config.py',), 09:39:50 INFO - 'default_actions': ('clobber', 09:39:50 INFO - 'read-buildbot-config', 09:39:50 INFO - 'download-and-extract', 09:39:50 INFO - 'populate-webroot', 09:39:50 INFO - 'create-virtualenv', 09:39:50 INFO - 'install', 09:39:50 INFO - 'run-tests'), 09:39:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:39:50 INFO - 'download_minidump_stackwalk': True, 09:39:50 INFO - 'download_symbols': 'ondemand', 09:39:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:39:50 INFO - 'tooltool.py': '/tools/tooltool.py', 09:39:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:39:50 INFO - '/tools/misc-python/virtualenv.py')}, 09:39:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:39:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:39:50 INFO - 'installer_path': 'installer.exe', 09:39:50 INFO - 'log_level': 'info', 09:39:50 INFO - 'log_name': 'talos', 09:39:50 INFO - 'log_to_console': True, 09:39:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:39:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:39:50 INFO - 'opt_config_files': (), 09:39:50 INFO - 'pip_index': False, 09:39:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:39:50 INFO - 'cmd': ('bash', 09:39:50 INFO - '-c', 09:39:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:39:50 INFO - 'enabled': True, 09:39:50 INFO - 'halt_on_failure': False, 09:39:50 INFO - 'name': 'check_screen_resolution'},), 09:39:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:39:50 INFO - 'cmd': ('bash', 09:39:50 INFO - '-c', 09:39:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:39:50 INFO - 'enabled': True, 09:39:50 INFO - 'halt_on_failure': False, 09:39:50 INFO - 'name': 'check_screen_resolution'},), 09:39:50 INFO - 'run_cmd_checks_enabled': True, 09:39:50 INFO - 'sps_profile': False, 09:39:50 INFO - 'sps_profile_interval': 0, 09:39:50 INFO - 'suite': 'dromaeojs', 09:39:50 INFO - 'system_bits': '32', 09:39:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:39:50 INFO - 'title': 't-yosemite-r7-0135', 09:39:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:39:50 INFO - 'use_talos_json': True, 09:39:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:39:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:39:50 INFO - 'work_dir': 'build'} 09:39:50 INFO - [mozharness: 2016-09-03 16:39:50.032408Z] Running clobber step. 09:39:50 INFO - Running pre-action listener: _resource_record_pre_action 09:39:50 INFO - Running main action method: clobber 09:39:50 INFO - rmtree: /builds/slave/test/build 09:39:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:39:51 INFO - Running post-action listener: _resource_record_post_action 09:39:51 INFO - [mozharness: 2016-09-03 16:39:51.105759Z] Finished clobber step (success) 09:39:51 INFO - [mozharness: 2016-09-03 16:39:51.105915Z] Running read-buildbot-config step. 09:39:51 INFO - Running pre-action listener: _resource_record_pre_action 09:39:51 INFO - Running main action method: read_buildbot_config 09:39:51 INFO - Using buildbot properties: 09:39:51 INFO - { 09:39:51 INFO - "project": "", 09:39:51 INFO - "product": "firefox", 09:39:51 INFO - "script_repo_revision": "production", 09:39:51 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:39:51 INFO - "repository": "", 09:39:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:39:51 INFO - "buildid": "20160903071749", 09:39:51 INFO - "pgo_build": "False", 09:39:51 INFO - "basedir": "/builds/slave/test", 09:39:51 INFO - "buildnumber": 140, 09:39:51 INFO - "slavename": "t-yosemite-r7-0135", 09:39:51 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 09:39:51 INFO - "platform": "yosemite_r7", 09:39:51 INFO - "scheduler": "tests-fx-team-macosx64-talos", 09:39:51 INFO - "branch": "fx-team", 09:39:51 INFO - "repo_path": "integration/fx-team", 09:39:51 INFO - "revision": "bcab45259b4fe5787d0d6fea16067e763f2a11ea", 09:39:51 INFO - "stage_platform": "macosx64", 09:39:51 INFO - "builduid": "63f212c225e14be9917090e32f177c6e", 09:39:51 INFO - "slavebuilddir": "test" 09:39:51 INFO - } 09:39:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/PGFUous9SA6YVGtP76ma4A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 09:39:51 INFO - Running post-action listener: _resource_record_post_action 09:39:51 INFO - [mozharness: 2016-09-03 16:39:51.109149Z] Finished read-buildbot-config step (success) 09:39:51 INFO - [mozharness: 2016-09-03 16:39:51.109259Z] Running download-and-extract step. 09:39:51 INFO - Running pre-action listener: _resource_record_pre_action 09:39:51 INFO - Running main action method: download_and_extract 09:39:51 INFO - mkdir: /builds/slave/test/build/tests 09:39:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:51 INFO - trying https://queue.taskcluster.net/v1/task/PGFUous9SA6YVGtP76ma4A/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 09:39:51 INFO - Downloading https://queue.taskcluster.net/v1/task/PGFUous9SA6YVGtP76ma4A/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 09:39:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PGFUous9SA6YVGtP76ma4A/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 09:39:52 INFO - Downloaded 1199 bytes. 09:39:52 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 09:39:52 INFO - Using the following test package requirements: 09:39:52 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:39:52 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:39:52 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 09:39:52 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:39:52 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 09:39:52 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:39:52 INFO - u'jsshell-mac.zip'], 09:39:52 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:39:52 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 09:39:52 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:39:52 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:39:52 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 09:39:52 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:39:52 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 09:39:52 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:39:52 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 09:39:52 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:39:52 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 09:39:52 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:39:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:52 INFO - trying https://queue.taskcluster.net/v1/task/PGFUous9SA6YVGtP76ma4A/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:39:52 INFO - Downloading https://queue.taskcluster.net/v1/task/PGFUous9SA6YVGtP76ma4A/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:39:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PGFUous9SA6YVGtP76ma4A/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:39:53 INFO - Downloaded 16399977 bytes. 09:39:53 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 09:40:00 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:40:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:00 INFO - trying https://queue.taskcluster.net/v1/task/PGFUous9SA6YVGtP76ma4A/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:40:00 INFO - Downloading https://queue.taskcluster.net/v1/task/PGFUous9SA6YVGtP76ma4A/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:40:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PGFUous9SA6YVGtP76ma4A/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:40:01 INFO - Downloaded 16399977 bytes. 09:40:01 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 09:40:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:07 INFO - trying https://queue.taskcluster.net/v1/task/PGFUous9SA6YVGtP76ma4A/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 09:40:07 INFO - Downloading https://queue.taskcluster.net/v1/task/PGFUous9SA6YVGtP76ma4A/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 09:40:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PGFUous9SA6YVGtP76ma4A/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:40:08 INFO - Downloaded 14413998 bytes. 09:40:08 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 09:40: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')]} 09:40:09 INFO - trying https://queue.taskcluster.net/v1/task/PGFUous9SA6YVGtP76ma4A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:40:09 INFO - Downloading https://queue.taskcluster.net/v1/task/PGFUous9SA6YVGtP76ma4A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 09:40:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PGFUous9SA6YVGtP76ma4A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 09:40:12 INFO - Downloaded 111491114 bytes. 09:40:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PGFUous9SA6YVGtP76ma4A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:40:12 INFO - mkdir: /builds/slave/test/properties 09:40:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:40:12 INFO - Writing to file /builds/slave/test/properties/build_url 09:40:12 INFO - Contents: 09:40:12 INFO - build_url:https://queue.taskcluster.net/v1/task/PGFUous9SA6YVGtP76ma4A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:40:12 INFO - Running post-action listener: _resource_record_post_action 09:40:12 INFO - Running post-action listener: set_extra_try_arguments 09:40:12 INFO - [mozharness: 2016-09-03 16:40:12.705894Z] Finished download-and-extract step (success) 09:40:12 INFO - [mozharness: 2016-09-03 16:40:12.706033Z] Running populate-webroot step. 09:40:12 INFO - Running pre-action listener: _resource_record_pre_action 09:40:12 INFO - Running main action method: populate_webroot 09:40:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:40:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:40:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:40:12 INFO - '--tppagecycles', 09:40:12 INFO - '7'], 09:40:12 INFO - 'tests': ['tsvgm']}}, 09:40:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:40:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:40:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:40:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:40:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:40:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:40:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:40:12 INFO - 'talos_options': ['--disable-e10s'], 09:40:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:40:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:40:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:40:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:40:12 INFO - 'talos_options': ['--disable-e10s'], 09:40:12 INFO - 'tests': ['damp', 'tps']}, 09:40:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:40:12 INFO - 'tests': ['damp', 'tps']}, 09:40:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:40:12 INFO - 'tests': ['dromaeo_dom']}, 09:40:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:40:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:40:12 INFO - 'tests': ['basic_compositor_video']}, 09:40:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:40:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:40:12 INFO - 'tests': ['a11yr', 09:40:12 INFO - 'ts_paint', 09:40:12 INFO - 'tpaint', 09:40:12 INFO - 'sessionrestore', 09:40:12 INFO - 'sessionrestore_no_auto_restore', 09:40:12 INFO - 'tabpaint']}, 09:40:12 INFO - 'other-e10s': {'tests': ['a11yr', 09:40:12 INFO - 'ts_paint', 09:40:12 INFO - 'tpaint', 09:40:12 INFO - 'sessionrestore', 09:40:12 INFO - 'sessionrestore_no_auto_restore', 09:40:12 INFO - 'tabpaint']}, 09:40:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:40:12 INFO - 'ts_paint', 09:40:12 INFO - 'tpaint', 09:40:12 INFO - 'sessionrestore', 09:40:12 INFO - 'sessionrestore_no_auto_restore', 09:40:12 INFO - 'tabpaint']}, 09:40:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:40:12 INFO - 'ts_paint', 09:40:12 INFO - 'tpaint', 09:40:12 INFO - 'sessionrestore', 09:40:12 INFO - 'sessionrestore_no_auto_restore', 09:40:12 INFO - 'tabpaint']}, 09:40:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:40:12 INFO - 'tests': ['a11yr', 09:40:12 INFO - 'ts_paint', 09:40:12 INFO - 'tpaint', 09:40:12 INFO - 'sessionrestore', 09:40:12 INFO - 'sessionrestore_no_auto_restore', 09:40:12 INFO - 'tabpaint']}, 09:40:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:40:12 INFO - 'tests': ['a11yr', 09:40:12 INFO - 'ts_paint', 09:40:12 INFO - 'tpaint', 09:40:12 INFO - 'sessionrestore', 09:40:12 INFO - 'sessionrestore_no_auto_restore', 09:40:12 INFO - 'tabpaint']}, 09:40:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:40:12 INFO - 'tests': ['tsvgx', 09:40:12 INFO - 'tsvgr_opacity', 09:40:12 INFO - 'tart', 09:40:12 INFO - 'tscrollx', 09:40:12 INFO - 'cart']}, 09:40:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:40:12 INFO - 'tsvgr_opacity', 09:40:12 INFO - 'tart', 09:40:12 INFO - 'tscrollx', 09:40:12 INFO - 'cart']}, 09:40:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:40:12 INFO - 'talos_options': ['--disable-e10s'], 09:40:12 INFO - 'tests': ['tp5o']}, 09:40:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:40:12 INFO - 'tests': ['tp5o']}, 09:40:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:40:12 INFO - 'talos_options': ['--disable-e10s', 09:40:12 INFO - '--xperf_path', 09:40:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:40:12 INFO - 'tests': ['tp5n']}, 09:40:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:40:12 INFO - 'talos_options': ['--xperf_path', 09:40:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:40:12 INFO - 'tests': ['tp5n']}}, 09:40:12 INFO - 'talos.zip': {'path': '', 09:40:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:40:12 INFO - Running post-action listener: _resource_record_post_action 09:40:12 INFO - [mozharness: 2016-09-03 16:40:12.725379Z] Finished populate-webroot step (success) 09:40:12 INFO - [mozharness: 2016-09-03 16:40:12.725516Z] Running create-virtualenv step. 09:40:12 INFO - Running pre-action listener: _resource_record_pre_action 09:40:12 INFO - Running main action method: create_virtualenv 09:40:12 INFO - Creating virtualenv /builds/slave/test/build/venv 09:40:12 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:40:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:40:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:40:12 INFO - Using real prefix '/tools/python27' 09:40:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:40:13 INFO - Installing distribute.............................................................................................................................................................................................done. 09:40:16 INFO - Installing pip.................done. 09:40:16 INFO - Return code: 0 09:40:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:40:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1088baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10894f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa800c6a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10881ed30>, '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 0x108583db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108816b20>, '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.waQQu08FRQ/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.UQbqCRhUPc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:40:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:40:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:40:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.waQQu08FRQ/Render', 09:40:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:16 INFO - 'HOME': '/Users/cltbld', 09:40:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:16 INFO - 'LOGNAME': 'cltbld', 09:40:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:16 INFO - 'MOZ_NO_REMOTE': '1', 09:40:16 INFO - 'NO_EM_RESTART': '1', 09:40:16 INFO - 'PAGER': '/bin/cat', 09:40:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:16 INFO - 'PWD': '/builds/slave/test', 09:40:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:16 INFO - 'SHELL': '/bin/bash', 09:40:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UQbqCRhUPc/Listeners', 09:40:16 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 09:40:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:16 INFO - 'USER': 'cltbld', 09:40:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:16 INFO - 'XPC_FLAGS': '0x0', 09:40:16 INFO - 'XPC_SERVICE_NAME': '0', 09:40:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:40:16 INFO - Cleaning up... 09:40:16 INFO - Return code: 0 09:40:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:40:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1088baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10894f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa800c6a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10881ed30>, '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 0x108583db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108816b20>, '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.waQQu08FRQ/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.UQbqCRhUPc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:40:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:40:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:40:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.waQQu08FRQ/Render', 09:40:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:16 INFO - 'HOME': '/Users/cltbld', 09:40:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:16 INFO - 'LOGNAME': 'cltbld', 09:40:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:16 INFO - 'MOZ_NO_REMOTE': '1', 09:40:16 INFO - 'NO_EM_RESTART': '1', 09:40:16 INFO - 'PAGER': '/bin/cat', 09:40:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:16 INFO - 'PWD': '/builds/slave/test', 09:40:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:16 INFO - 'SHELL': '/bin/bash', 09:40:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UQbqCRhUPc/Listeners', 09:40:16 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 09:40:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:16 INFO - 'USER': 'cltbld', 09:40:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:16 INFO - 'XPC_FLAGS': '0x0', 09:40:16 INFO - 'XPC_SERVICE_NAME': '0', 09:40:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:16 INFO - Downloading/unpacking psutil>=3.1.1 09:40:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:40:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:40:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:40:20 INFO - Installing collected packages: psutil 09:40:20 INFO - Running setup.py install for psutil 09:40:20 INFO - building 'psutil._psutil_osx' extension 09:40:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:40:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:40:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:40:20 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:40:20 INFO - building 'psutil._psutil_posix' extension 09:40:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:40:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:40:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:40:21 INFO - ^ 09:40:21 INFO - 1 warning generated. 09:40:21 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:40:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:40:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:40:21 INFO - Successfully installed psutil 09:40:21 INFO - Cleaning up... 09:40:21 INFO - Return code: 0 09:40:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:40:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:21 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 0x1088baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10894f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa800c6a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10881ed30>, '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 0x108583db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108816b20>, '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.waQQu08FRQ/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.UQbqCRhUPc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:40:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:40:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:40:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.waQQu08FRQ/Render', 09:40:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:21 INFO - 'HOME': '/Users/cltbld', 09:40:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:21 INFO - 'LOGNAME': 'cltbld', 09:40:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:21 INFO - 'MOZ_NO_REMOTE': '1', 09:40:21 INFO - 'NO_EM_RESTART': '1', 09:40:21 INFO - 'PAGER': '/bin/cat', 09:40:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:21 INFO - 'PWD': '/builds/slave/test', 09:40:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:21 INFO - 'SHELL': '/bin/bash', 09:40:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UQbqCRhUPc/Listeners', 09:40:21 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 09:40:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:21 INFO - 'USER': 'cltbld', 09:40:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:21 INFO - 'XPC_FLAGS': '0x0', 09:40:21 INFO - 'XPC_SERVICE_NAME': '0', 09:40:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:40:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:40:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:40:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:40:24 INFO - Installing collected packages: mozsystemmonitor 09:40:24 INFO - Running setup.py install for mozsystemmonitor 09:40:24 INFO - Successfully installed mozsystemmonitor 09:40:24 INFO - Cleaning up... 09:40:24 INFO - Return code: 0 09:40:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:40:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:24 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 0x1088baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10894f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa800c6a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10881ed30>, '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 0x108583db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108816b20>, '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.waQQu08FRQ/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.UQbqCRhUPc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:40:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:40:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:40:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.waQQu08FRQ/Render', 09:40:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:24 INFO - 'HOME': '/Users/cltbld', 09:40:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:24 INFO - 'LOGNAME': 'cltbld', 09:40:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:24 INFO - 'MOZ_NO_REMOTE': '1', 09:40:24 INFO - 'NO_EM_RESTART': '1', 09:40:24 INFO - 'PAGER': '/bin/cat', 09:40:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:24 INFO - 'PWD': '/builds/slave/test', 09:40:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:24 INFO - 'SHELL': '/bin/bash', 09:40:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UQbqCRhUPc/Listeners', 09:40:24 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 09:40:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:24 INFO - 'USER': 'cltbld', 09:40:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:24 INFO - 'XPC_FLAGS': '0x0', 09:40:24 INFO - 'XPC_SERVICE_NAME': '0', 09:40:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:24 INFO - Downloading/unpacking jsonschema==2.5.1 09:40:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:40:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:40:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:27 INFO - Downloading functools32-3.2.3-2.tar.gz 09:40:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:40:27 INFO - warning: no files found matching '*.txt' 09:40:27 INFO - no previously-included directories found matching 'build' 09:40:27 INFO - no previously-included directories found matching 'dist' 09:40:27 INFO - no previously-included directories found matching '.git*' 09:40:27 INFO - Installing collected packages: jsonschema, functools32 09:40:27 INFO - Running setup.py install for functools32 09:40:27 INFO - warning: no files found matching '*.txt' 09:40:27 INFO - no previously-included directories found matching 'build' 09:40:27 INFO - no previously-included directories found matching 'dist' 09:40:27 INFO - no previously-included directories found matching '.git*' 09:40:27 INFO - Successfully installed jsonschema functools32 09:40:27 INFO - Cleaning up... 09:40:27 INFO - Return code: 0 09:40:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:40:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:27 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 0x1088baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10894f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa800c6a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10881ed30>, '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 0x108583db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108816b20>, '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.waQQu08FRQ/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.UQbqCRhUPc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:40:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:40:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:40:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.waQQu08FRQ/Render', 09:40:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:27 INFO - 'HOME': '/Users/cltbld', 09:40:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:27 INFO - 'LOGNAME': 'cltbld', 09:40:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:27 INFO - 'MOZ_NO_REMOTE': '1', 09:40:27 INFO - 'NO_EM_RESTART': '1', 09:40:27 INFO - 'PAGER': '/bin/cat', 09:40:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:27 INFO - 'PWD': '/builds/slave/test', 09:40:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:27 INFO - 'SHELL': '/bin/bash', 09:40:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UQbqCRhUPc/Listeners', 09:40:27 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 09:40:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:27 INFO - 'USER': 'cltbld', 09:40:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:27 INFO - 'XPC_FLAGS': '0x0', 09:40:27 INFO - 'XPC_SERVICE_NAME': '0', 09:40:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:40:28 INFO - Cleaning up... 09:40:28 INFO - Return code: 0 09:40:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:40:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1088baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10894f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa800c6a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10881ed30>, '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 0x108583db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108816b20>, '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.waQQu08FRQ/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.UQbqCRhUPc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:40:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:40:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:40:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.waQQu08FRQ/Render', 09:40:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:28 INFO - 'HOME': '/Users/cltbld', 09:40:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:28 INFO - 'LOGNAME': 'cltbld', 09:40:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:28 INFO - 'MOZ_NO_REMOTE': '1', 09:40:28 INFO - 'NO_EM_RESTART': '1', 09:40:28 INFO - 'PAGER': '/bin/cat', 09:40:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:28 INFO - 'PWD': '/builds/slave/test', 09:40:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:28 INFO - 'SHELL': '/bin/bash', 09:40:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UQbqCRhUPc/Listeners', 09:40:28 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 09:40:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:28 INFO - 'USER': 'cltbld', 09:40:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:28 INFO - 'XPC_FLAGS': '0x0', 09:40:28 INFO - 'XPC_SERVICE_NAME': '0', 09:40:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:28 INFO - Downloading/unpacking blobuploader==1.2.4 09:40:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:30 INFO - Downloading blobuploader-1.2.4.tar.gz 09:40:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:40:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:40:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:40:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:40:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:31 INFO - Downloading docopt-0.6.1.tar.gz 09:40:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:40:31 INFO - Installing collected packages: blobuploader, requests, docopt 09:40:31 INFO - Running setup.py install for blobuploader 09:40:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:40:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:40:32 INFO - Running setup.py install for requests 09:40:32 INFO - Running setup.py install for docopt 09:40:32 INFO - Successfully installed blobuploader requests docopt 09:40:32 INFO - Cleaning up... 09:40:32 INFO - Return code: 0 09:40:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:40:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:32 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 0x1088baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10894f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa800c6a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10881ed30>, '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 0x108583db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108816b20>, '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.waQQu08FRQ/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.UQbqCRhUPc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:40:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:40:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:40:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.waQQu08FRQ/Render', 09:40:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:32 INFO - 'HOME': '/Users/cltbld', 09:40:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:32 INFO - 'LOGNAME': 'cltbld', 09:40:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:32 INFO - 'MOZ_NO_REMOTE': '1', 09:40:32 INFO - 'NO_EM_RESTART': '1', 09:40:32 INFO - 'PAGER': '/bin/cat', 09:40:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:32 INFO - 'PWD': '/builds/slave/test', 09:40:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:32 INFO - 'SHELL': '/bin/bash', 09:40:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UQbqCRhUPc/Listeners', 09:40:32 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 09:40:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:32 INFO - 'USER': 'cltbld', 09:40:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:32 INFO - 'XPC_FLAGS': '0x0', 09:40:32 INFO - 'XPC_SERVICE_NAME': '0', 09:40:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:40:33 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-rcvm2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:40:33 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-0lwJgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:40:33 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-8kcR9i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:40:33 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-stud3E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:40:33 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-nKHrb3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:40:33 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-NJB1sV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:40:33 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-CakFUM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:40:33 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-nUwQCW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:40:33 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-YzU8Xv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:40:34 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-V03K7G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:40:34 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-3JuY2a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:40:34 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-wdk_MK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:40:34 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-gLnzkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:40:34 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-trFpUR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:40:34 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-5XPTOL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:40:34 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-tkANR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:40:34 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-qBXLt7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:40:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:40:34 INFO - Running setup.py install for manifestparser 09:40:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:40:35 INFO - Running setup.py install for mozcrash 09:40:35 INFO - Running setup.py install for mozdebug 09:40:35 INFO - Running setup.py install for mozdevice 09:40:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:40:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:40:35 INFO - Running setup.py install for mozfile 09:40:35 INFO - Running setup.py install for mozhttpd 09:40:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:40:35 INFO - Running setup.py install for mozinfo 09:40:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:40:35 INFO - Running setup.py install for mozInstall 09:40:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:40:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:40:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:40:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:40:36 INFO - Running setup.py install for mozleak 09:40:36 INFO - Running setup.py install for mozlog 09:40:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:40:36 INFO - Running setup.py install for moznetwork 09:40:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:40:36 INFO - Running setup.py install for mozprocess 09:40:36 INFO - Running setup.py install for mozprofile 09:40:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:40:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:40:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:40:36 INFO - Running setup.py install for mozrunner 09:40:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:40:37 INFO - Running setup.py install for mozscreenshot 09:40:37 INFO - Running setup.py install for moztest 09:40:37 INFO - Running setup.py install for mozversion 09:40:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:40:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:40:37 INFO - Cleaning up... 09:40:37 INFO - Return code: 0 09:40:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:40:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:37 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 0x1088baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10894f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa800c6a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10881ed30>, '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 0x108583db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108816b20>, '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.waQQu08FRQ/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.UQbqCRhUPc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:40:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:40:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:40:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.waQQu08FRQ/Render', 09:40:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:37 INFO - 'HOME': '/Users/cltbld', 09:40:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:37 INFO - 'LOGNAME': 'cltbld', 09:40:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:37 INFO - 'MOZ_NO_REMOTE': '1', 09:40:37 INFO - 'NO_EM_RESTART': '1', 09:40:37 INFO - 'PAGER': '/bin/cat', 09:40:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:37 INFO - 'PWD': '/builds/slave/test', 09:40:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:37 INFO - 'SHELL': '/bin/bash', 09:40:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UQbqCRhUPc/Listeners', 09:40:37 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 09:40:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:37 INFO - 'USER': 'cltbld', 09:40:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:37 INFO - 'XPC_FLAGS': '0x0', 09:40:37 INFO - 'XPC_SERVICE_NAME': '0', 09:40:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:40:37 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-vYtb2D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:40:37 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:40:37 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-CbM6H8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:40:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:40:38 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-6XOliT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:40:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:40:38 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-wMV5mW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:40:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:40:38 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-IrNGuv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:40:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:40:38 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-qLgL2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:40:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:40:38 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-n47btJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:40:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:40:38 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-RWBExE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:40:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:40:38 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-9yFK31-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:40:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:40:38 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-XFDfH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:40:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:40:38 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-k2rxsO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:40:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:40:38 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-as5o_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:40:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:40:39 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-Toeyca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:40:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:40:39 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-UVPF1k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:40:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:40:39 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-ngg_L4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:40:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:40:39 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-8pcRJg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:40:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:40:39 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-1ru4x_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:40:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:40:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:40:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:40:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:40:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:40:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:40:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:40:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:42 INFO - Downloading blessings-1.6.tar.gz 09:40:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:40:42 INFO - Installing collected packages: blessings 09:40:42 INFO - Running setup.py install for blessings 09:40:42 INFO - Successfully installed blessings 09:40:42 INFO - Cleaning up... 09:40:42 INFO - Return code: 0 09:40:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:40:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:40:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:40:42 INFO - Reading from file tmpfile_stdout 09:40:42 INFO - Current package versions: 09:40:42 INFO - blessings == 1.6 09:40:42 INFO - blobuploader == 1.2.4 09:40:42 INFO - docopt == 0.6.1 09:40:42 INFO - functools32 == 3.2.3-2 09:40:42 INFO - jsonschema == 2.5.1 09:40:42 INFO - manifestparser == 1.1 09:40:42 INFO - mozInstall == 1.12 09:40:42 INFO - mozcrash == 0.17 09:40:42 INFO - mozdebug == 0.1 09:40:42 INFO - mozdevice == 0.48 09:40:42 INFO - mozfile == 1.2 09:40:42 INFO - mozhttpd == 0.7 09:40:42 INFO - mozinfo == 0.9 09:40:42 INFO - mozleak == 0.1 09:40:42 INFO - mozlog == 3.3 09:40:42 INFO - moznetwork == 0.27 09:40:42 INFO - mozprocess == 0.23 09:40:42 INFO - mozprofile == 0.28 09:40:42 INFO - mozrunner == 6.12 09:40:42 INFO - mozscreenshot == 0.1 09:40:42 INFO - mozsystemmonitor == 0.3 09:40:42 INFO - moztest == 0.7 09:40:42 INFO - mozversion == 1.4 09:40:42 INFO - psutil == 3.1.1 09:40:42 INFO - requests == 1.2.3 09:40:42 INFO - wsgiref == 0.1.2 09:40:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:40:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:42 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 0x1088baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10894f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa800c6a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10881ed30>, '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 0x108583db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108816b20>, '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.waQQu08FRQ/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.UQbqCRhUPc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:40:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:40:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:40:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.waQQu08FRQ/Render', 09:40:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:42 INFO - 'HOME': '/Users/cltbld', 09:40:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:42 INFO - 'LOGNAME': 'cltbld', 09:40:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:42 INFO - 'MOZ_NO_REMOTE': '1', 09:40:42 INFO - 'NO_EM_RESTART': '1', 09:40:42 INFO - 'PAGER': '/bin/cat', 09:40:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:42 INFO - 'PWD': '/builds/slave/test', 09:40:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:42 INFO - 'SHELL': '/bin/bash', 09:40:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UQbqCRhUPc/Listeners', 09:40:42 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 09:40:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:42 INFO - 'USER': 'cltbld', 09:40:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:42 INFO - 'XPC_FLAGS': '0x0', 09:40:42 INFO - 'XPC_SERVICE_NAME': '0', 09:40:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:40:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:40:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:40:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:40:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:40:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:40:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:40:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:40:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:40:43 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:40:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:40:43 INFO - Cleaning up... 09:40:43 INFO - Return code: 0 09:40:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:40:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1088baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10894f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa800c6a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10881ed30>, '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 0x108583db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108816b20>, '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.waQQu08FRQ/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.UQbqCRhUPc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:40:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:40:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:40:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.waQQu08FRQ/Render', 09:40:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:43 INFO - 'HOME': '/Users/cltbld', 09:40:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:43 INFO - 'LOGNAME': 'cltbld', 09:40:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:43 INFO - 'MOZ_NO_REMOTE': '1', 09:40:43 INFO - 'NO_EM_RESTART': '1', 09:40:43 INFO - 'PAGER': '/bin/cat', 09:40:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:43 INFO - 'PWD': '/builds/slave/test', 09:40:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:43 INFO - 'SHELL': '/bin/bash', 09:40:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UQbqCRhUPc/Listeners', 09:40:43 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 09:40:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:43 INFO - 'USER': 'cltbld', 09:40:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:43 INFO - 'XPC_FLAGS': '0x0', 09:40:43 INFO - 'XPC_SERVICE_NAME': '0', 09:40:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:40:43 INFO - Cleaning up... 09:40:43 INFO - Return code: 0 09:40:43 INFO - Running post-action listener: _resource_record_post_action 09:40:43 INFO - Running post-action listener: _start_resource_monitoring 09:40:43 INFO - Starting resource monitoring. 09:40:43 INFO - [mozharness: 2016-09-03 16:40:43.339545Z] Finished create-virtualenv step (success) 09:40:43 INFO - [mozharness: 2016-09-03 16:40:43.340612Z] Running install step. 09:40:43 INFO - Running pre-action listener: _resource_record_pre_action 09:40:43 INFO - Running main action method: install 09:40:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:40:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:40:43 INFO - Reading from file tmpfile_stdout 09:40:43 INFO - Detecting whether we're running mozinstall >=1.0... 09:40:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:40:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:40:43 INFO - Reading from file tmpfile_stdout 09:40:43 INFO - Output received: 09:40:43 INFO - Usage: mozinstall [options] installer 09:40:43 INFO - Options: 09:40:43 INFO - -h, --help show this help message and exit 09:40:43 INFO - -d DEST, --destination=DEST 09:40:43 INFO - Directory to install application into. [default: 09:40:43 INFO - "/builds/slave/test"] 09:40:43 INFO - --app=APP Application being installed. [default: firefox] 09:40:43 INFO - mkdir: /builds/slave/test/build/application 09:40:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:40:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:41:18 INFO - Reading from file tmpfile_stdout 09:41:18 INFO - Output received: 09:41:18 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:41:18 INFO - Running post-action listener: _resource_record_post_action 09:41:18 INFO - [mozharness: 2016-09-03 16:41:18.773066Z] Finished install step (success) 09:41:18 INFO - [mozharness: 2016-09-03 16:41:18.773206Z] Running run-tests step. 09:41:18 INFO - Running pre-action listener: _resource_record_pre_action 09:41:18 INFO - Running main action method: run_tests 09:41:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:41:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:41:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:41:18 INFO - 2016-09-03 09:41:18.788 screenresolution[2381:28821] starting screenresolution argv=screenresolution get 09:41:18 INFO - 2016-09-03 09:41:18.790 screenresolution[2381:28821] Display 0: 1600x1200x32@60 09:41:18 INFO - 2016-09-03 09:41:18.796 screenresolution[2382:28823] starting screenresolution argv=screenresolution list 09:41:18 INFO - Available Modes on Display 0 09:41:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:41:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:41:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:41:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:41:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:41:19 INFO - Intel Iris: 09:41:19 INFO - Chipset Model: Intel Iris 09:41:19 INFO - Type: GPU 09:41:19 INFO - Bus: Built-In 09:41:19 INFO - VRAM (Dynamic, Max): 1536 MB 09:41:19 INFO - Vendor: Intel (0x8086) 09:41:19 INFO - Device ID: 0x0a2e 09:41:19 INFO - Revision ID: 0x0009 09:41:19 INFO - Displays: 09:41:19 INFO - Display: 09:41:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:41:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:41:19 INFO - Display Serial Number: 1600x1200 60 09:41:19 INFO - Main Display: Yes 09:41:19 INFO - Mirror: Off 09:41:19 INFO - Online: Yes 09:41:19 INFO - Rotation: Supported 09:41:19 INFO - Return code: 0 09:41:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:41:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:41:19 INFO - Python 2.7.3 09:41:19 INFO - Return code: 0 09:41:19 INFO - grabbing minidump binary from tooltool 09:41:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:19 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 0x10881ed30>, '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 0x108583db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108816b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:41:19 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:41:19 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:41:19 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:41:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:41:19 INFO - Return code: 0 09:41:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:41:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:41:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:41:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:41:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:41:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PGFUous9SA6YVGtP76ma4A/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0135', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:41:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PGFUous9SA6YVGtP76ma4A/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0135 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:41:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.waQQu08FRQ/Render', 09:41:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:41:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:41:19 INFO - 'HOME': '/Users/cltbld', 09:41:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:41:19 INFO - 'LOGNAME': 'cltbld', 09:41:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:41:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:41:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:41:19 INFO - 'MOZ_NO_REMOTE': '1', 09:41:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:41:19 INFO - 'NO_EM_RESTART': '1', 09:41:19 INFO - 'PAGER': '/bin/cat', 09:41:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:41:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:41:19 INFO - 'PWD': '/builds/slave/test', 09:41:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:41:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:41:19 INFO - 'SHELL': '/bin/bash', 09:41:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UQbqCRhUPc/Listeners', 09:41:19 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 09:41:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:41:19 INFO - 'USER': 'cltbld', 09:41:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:41:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:41:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:41:19 INFO - 'XPC_FLAGS': '0x0', 09:41:19 INFO - 'XPC_SERVICE_NAME': '0', 09:41:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:41:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PGFUous9SA6YVGtP76ma4A/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0135', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:41:19 INFO - mozversion application_buildid: 20160903071749 09:41:19 INFO - mozversion application_changeset: bcab45259b4fe5787d0d6fea16067e763f2a11ea 09:41:19 INFO - mozversion application_display_name: Nightly 09:41:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:41:19 INFO - mozversion application_name: Firefox 09:41:19 INFO - mozversion application_remotingname: firefox 09:41:19 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:41:19 INFO - mozversion application_vendor: Mozilla 09:41:19 INFO - mozversion application_version: 51.0a1 09:41:19 INFO - mozversion platform_buildid: 20160903071749 09:41:19 INFO - mozversion platform_changeset: bcab45259b4fe5787d0d6fea16067e763f2a11ea 09:41:19 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:41:19 INFO - mozversion platform_version: 51.0a1 09:41:19 INFO - using testdate: 1472920879 09:41:19 INFO - actual date: 1472920879 09:41:19 INFO - starting webserver on 'localhost:49809' 09:41:19 INFO - SUITE-START | Running 2 tests 09:41:19 INFO - TEST-START | dromaeo_css 09:41:19 INFO - operating with platform_type : mac_ 09:41:19 INFO - Initialising browser for dromaeo_css test... 09:41:19 INFO - TEST-INFO | started process 2392 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/tmpUKULIu/profile http://localhost:49809/getInfo.html) 09:41:21 INFO - PROCESS | 2392 | __metrics Screen width/height:1600/1200 09:41:21 INFO - PROCESS | 2392 | colorDepth:24 09:41:21 INFO - PROCESS | 2392 | Browser inner width/height: 1024/689 09:41:21 INFO - PROCESS | 2392 | __metrics 09:41:21 INFO - PROCESS | 2392 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:41:21 INFO - PROCESS | 2392 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:41:22 INFO - TEST-INFO | 2392: exit 0 09:41:22 INFO - Browser initialized. 09:41:22 INFO - Running cycle 1/1 for dromaeo_css test... 09:41:22 INFO - TEST-INFO | started process 2394 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/tmpUKULIu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:41:34 INFO - PROCESS | 2394 | RSS: Main: 233709568 09:41:34 INFO - PROCESS | 2394 | 09:42:25 INFO - PROCESS | 2394 | 1472920945352 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:44:37 INFO - PROCESS | 2394 | Cycle 1(1): loaded http://localhost:49809/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49809/tests/dromaeo/cssquery-ext.html) 09:44:38 INFO - PROCESS | 2394 | RSS: Main: 398336000 09:44:38 INFO - PROCESS | 2394 | 09:47:41 INFO - PROCESS | 2394 | Cycle 1(1): loaded http://localhost:49809/tests/dromaeo/cssquery-ext.html (next: http://localhost:49809/tests/dromaeo/cssquery-jquery.html) 09:47:42 INFO - PROCESS | 2394 | RSS: Main: 372080640 09:47:42 INFO - PROCESS | 2394 | 09:50:44 INFO - PROCESS | 2394 | Cycle 1(1): loaded http://localhost:49809/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49809/tests/dromaeo/cssquery-mootools.html) 09:50:45 INFO - PROCESS | 2394 | RSS: Main: 363266048 09:50:45 INFO - PROCESS | 2394 | 09:53:47 INFO - PROCESS | 2394 | Cycle 1(1): loaded http://localhost:49809/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49809/tests/dromaeo/cssquery-prototype.html) 09:53:48 INFO - PROCESS | 2394 | RSS: Main: 337764352 09:53:48 INFO - PROCESS | 2394 | 09:56:51 INFO - PROCESS | 2394 | Cycle 1(1): loaded http://localhost:49809/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49809/tests/dromaeo/cssquery-yui.html) 09:56:52 INFO - PROCESS | 2394 | RSS: Main: 330108928 09:56:52 INFO - PROCESS | 2394 | 09:59:55 INFO - PROCESS | 2394 | Cycle 1(1): loaded http://localhost:49809/tests/dromaeo/cssquery-yui.html (next: http://localhost:49809/tests/dromaeo/cssquery-dojo.html) 09:59:56 INFO - PROCESS | 2394 | RSS: Main: 429232128 09:59:56 INFO - PROCESS | 2394 | 09:59:56 INFO - PROCESS | 2394 | __start_tp_report 09:59:56 INFO - PROCESS | 2394 | _x_x_mozilla_page_load 09:59:56 INFO - PROCESS | 2394 | _x_x_mozilla_page_load_details 09:59:56 INFO - PROCESS | 2394 | |i|pagename|runs| 09:59:56 INFO - PROCESS | 2394 | |0;dojo.html;2752.7384898434652;3239.967600323997;3276.8361581920913;3295.1597342677615;3312.90061298161;5486.506214440699;5561.054620714478;5626.071698169802;5628.634138780976;5631.239782629344;5573.4147914469;5606.018946684334;5612.887742245152;5620.269364982557;5633.80281690141;5109.69341839489;5148.665336753118;5153.922691159635;5211.218317252424;5219.42586315505;4798.352222449959;5233.450487698794;5244.973775131138;5245.947540524584;5272.525472707455;4643.349931009644;5181.248718935771;5185.144451165572;5233.3981592116825;5250.868728281805;5247.580193584504;5252.159654455288;5272.446393128705;5288;5333.546648534879;5099.4390617032095;5122.231665250205;5154;5211.869703257411;5242.68543887368;5680.6591604503865;5687.772489100431;5691.7723291068305;5742.770289188427;5810.883782324372;6091.786787462417;6093.482054025369;6118.847028824315;6124.081387791823;6126.663033533154;5994.520438364921;6079.483243924272;6080.270367555878;6090.695465226721;6105.694715264263;5800.709964501758;5803.912941305884;5823.796167134173;5850.736716847752;5851.297844258716;5257.8159764408065;6132.202813634288;6147.477464415485;6170.907436388457;6181.041938499539;5940.6435613863305;6040.728167232485;6074.4532992030045;6076.301225359052;6083.8479038023515;6044.063170208625;6085.084002341905;6126.326104128542;6131.499004853489;6137.018077107641;6094.329623741385;6107.144999700045;6117.449429551273;6118.479929205978;6149.41580549847;4796.352492413549;5189.195674670432;5993.880122397528;6080.391960803884;6090.482309003784;4191.476065491814;4839.612830973584;4842.927356089662;4854.68444551103;4855.36880205571;4374.03771170342;4376.168527979674;4408.162449134654;4414.757188354607;4415.823367065282;5645.181448689999;5696.316442026983;5719.084946408555;5726.455986681259;5733.111367738007;6153.784617538365;6190.783322583688;6215.34738852414;6220.471259942956;6220.626762394271;5511.448855114457;5640.746166422521;5654.236678048493;5663.858403539944;5668.489835914705;5235.136202526616;5333.439988801198;5382.757775900016;5393.1370980642905;5401;6193.876122477615;6212.658303793335;6218.844528886814;6225.159603453476;6231.937680623136;6185.865586753405;6208.751649933952;6220.973539365954;6245.968770156302;6249.6562689052535;6123.1911131038305;6145.877082458415;6157.168782214344;6210.161628180138;6247.562670613013;6097.817065488042;6107.295156111712;6113.144159817718;6125.448709616156;6141.1402403664415;5256.31667883188;6194.628322300677;6211.534134939807;6227.626342419469;6237.937620623736;5084.745762711924;5850.473457388855;5856.121581762599;5996.010658241341;6055.788047418319;4697.295405689174;6028.608140470856;6066.878662426639;6083.148359229623;6096.02463605821;5952.107183922446;5963.165156877953;5965.164876917148;5970.402959703995;5970.940290597038;5944;5967.552433567413;6026.819195424145;6067.605605635619;6094.3600921904335;5870.735816888165;5918.3489816121055;5960.195373624505;5969.940300597113;6028.90956635642 09:59:56 INFO - PROCESS | 2394 | |1;ext.html;453.46717606811995;471.42957022003355;474.46859517340584;512.5720023780143;518.5669965578742;30265.394692106143;40099.79950100249;41168.79415602922;41305.58694413055;43419.565804341946;42144.36783448251;42469.78765106178;42548.14903701924;42617.360739588854;42645.7867710661;8222.794430139249;8683.044865064838;9796.755081122994;9805.803883922317;9828.557714902847;8658.913410865905;9887.357321774098;11957.043436525057;12031.819522707166;12237.204581702206;11962.581309654164;11965.641030769048;11997.880021199808;12009.039276857831;12020.278783272988;11933.34366609836;12005.339706316148;12012;12013.27920324779;12044.638660840188;11733.296002239807;11805.822912656316;11808.586699465563;11817;11832.763344733057;39734.801325993474;40093.398599021035;40109.79945100256;40173.995650108984;40226;3322.551455553518;3432.5022871683714;3493.1616412061176;3579.552555930509;4031.8387264509383;30063.098107056823;30239.546406803915;30353.848230758926;30770.53844192339;31511.897083601965;2928.443595716807;3156.1116229308413;3229.160418291238;3239.951400728991;3255.609326880766;421296;423656;424925.9999999969;426182;426420;56998.86002280014;57307.40964789019;57343;57401.42598574045;57492;33783.66216337854;34373;34389.828050859585;35545;60214.39785602176;15901.284442200129;16045.358185672443;16068.598285042968;16081.356745730274;16089;11280.379579123062;11470.655880323604;12304;12414.379281035912;12601.369931503572;18702.438926832216;18710.625787484445;18740.625187496174;18775.15511801972;18832.34086806955;6485.70814313357;6705.396514313637;7129.322714342129;7324.718174319472;7700.921870938073;815.5759005317202;945.5130607737021;971.2424309038961;1039.1946241662772;1057.7937302226146;28870.711292887223;29306.7069329304;30078.54882176769;30220.697793022227;30590.235244119074;990.4898977026837;1012.8531362952331;1032.6695457453543;1075.0915476422567;1131.9660410187707;13424.597262082154;15394.846051539565;15397.99913005651;15488.14815185153;15800.762988555181;554601;637897.8105109445;651448;651564;653125;46675.76662116668;56398.718006409705;57006.7149664249;60907.39092609106;61029.77940441166;1280.7246442015198;1318.3210646517243;1398.5175114585452;1407.0502410872825;1436.3967133803828;26384.92629576789;32077;32173.35653286968;32459.837700811346;32758.181045474055;55534.16698749441;56034.439655602924;56192.438075618724;56201;56214.718926405105;547565;549596;551720;552860.1784955409;553867;41750.79124604358;42406.7277981661;42959.355609665254;43329.783351084305;43459.78270108629;46967;48107;48150;48171;48508.757456212494;37912.81043594764;38909.6109038906;39067.41398878962;39578.94946795322;40407.79796101001;38566.42150367803;39037.609623903394;39058.80470597629;39749.031371078614;40065.59934400619;34376.82811585926;35732.64267357293;36102.8194859024;38394.80802595969;38711;28240.29399265035;33358.83320583381;33439.83280083584;34460.48309275413;34963.65036349706;24934.750652493243;25121.120760773287;25607.871960640077;25852.965881365286;25951.870240648677 09:59:56 INFO - PROCESS | 2394 | |2;jquery.html;308.9907302780917;347.4649040477664;367.98528058877645;368.289201840448;399.94000899865017;15099;17833.732494012584;20812.063457144428;20945.16219351226;20997.895010524964;11833.349165795878;14722.969392142555;19322.613547729037;20132.597348053067;20283.898580507077;5422.240886275935;5647.548196144298;5650.265465489501;5652.123920792264;5654.575906806986;5218.921716174261;5482.424345443731;5509.283793106891;5532.446755324476;5535.308086489189;5535.363433205192;5538.695371754552;5541.695206763627;5544.556435485151;5551.9722401387935;5530.917036244459;5811.186433899257;6107;6192.597481163755;6201.348858369851;5284.497972692588;6016.75932962681;6022.187004754346;6030.969845150747;6048.153258543807;12822.679433014157;13464.259465729381;13474.865251347459;13511.391987360592;15875.682486350324;2128.159377046058;2247.3595025417676;2270.4437412833963;2355.093289083698;2588.8705564721877;9865.60537578496;9873.753156171082;9963.41317125834;10274.589016439408;10379.481025948673;1679.5717092141547;1778.97331540027;1872.447627949758;1900.8669393142486;2013;99866.50066749692;110454;117645;120456;120688;9216.493092879822;9752.073553012453;9887.555060022316;11365.147613928988;11654.242474239147;12082.637520874388;12233.755324893453;12271.263724176579;12286.447109880077;12295.4467048983;7860.803479912934;7964.920350796534;8030.156833532512;8116.797080072927;8138.134386166642;8183.099859015503;8209.753707388787;8244.628991695388;8402.747917562483;8422.873656895152;3816.0841398064263;3832.5017747692623;3842.3083844908183;3913.178232571135;3933.0364060805286;1860.9720854187199;1905.7427247321712;1914.8085191481018;1920.875143115721;1937.7674679038605;869.4435561240684;882.342654722236;888.8977767556685;911.9954400227956;976.6532880827347;20834.68747968782;21384.679229811565;21778.01998910053;21929.561408772053;21988.350873684536;786.6263524825662;900.7162743735702;909.1363204955319;953.50894289441;980.6028558433845;9547.778448028677;11059.557617695364;11216.943915280535;11358.318500889973;11452.656420307405;473431.6328418336;547687;552532;564965;565426;96614;125161;125639;131512;132458;928.5960607135918;947.8151760406793;979.4759803505091;979.5787811241092;981.3228872078244;19264.422067338;19271;19320.32378866789;19368.903155484695;19533.70699439538;51715.74142129265;52503;53656.46343536515;53774.65563360947;54035.91928161494;68469;69159.65420172867;76619;80309;80946.19053809387;6800.387965083166;7752.14726380109;7854.882176767239;7911.723089691834;8061.556614386031;8040.597970101355;12330.383480825743;12435.06736994711;12653.114282000171;12741.426635801596;10867.184961127788;10917.126629869432;11091.5563377464;11190.496427660937;11200.439978000904;10554.472276386303;10616.363018218932;10633.680989570325;10879.891201087888;11089.556417743523;11028;11047.392393418118;11106.61126860556;11117.777644446904;11170.162237832357;7635.465517413728;7887.329576986021;7916.881246781418;8018.518888866687;8236.135205803304;8080.232377924088;10093;10653.201009924442;11024.44877756125;11071.50178241965 09:59:56 INFO - PROCESS | 2394 | |3;mootools.html;1356.830396200475;1504.232841250962;1688.62006048639;1827.7806663200402;1907.484979055655;8031.076426210973;8305.91694083059;8511.872321915169;9092.090790920905;9095.90904090959;7719.614019299041;8735.956320218405;8745.650173993032;8865.86701199481;8878.112188781135;2380.3573035280465;2402.771736685012;2409.265174121892;2411.67442395277;2412.107520217522;2089.122568521217;2163.642998905179;2355.4700192456644;2397.5564520563667;2413.2036427978815;2321.1991862807304;2398.5922393193114;2399.3042017814814;2399.664047033417;2405.050005247926;2377.393764590036;2389.151851092863;2397.8681172535416;2405.542946840095;2406.03758496601;2349.8707571083582;2369.2536850891915;2400.543896659629;2402.159244264511;2414.155045733979;8796.428232164953;8908.287337013087;8912.777180570472;8914.955425222897;8923.375363724543;5254.632175747699;5416.891662166735;5418.431064738224;5419.160030195287;5421.457854214586;3504.702100321476;3634.509341238925;3654.104744337628;3694.8152592370275;3697.242065376591;1045.9372437653765;1093.9835902461468;1116.7319843237565;1122.1079242002627;1122.764219513903;11587.304761714324;11628.53485860573;11641.01051410631;11647.767044659144;11653.533858645644;4264.253755592821;4441.311596702516;4668.136394766979;4813.831515896927;4860.659753817199;9955;9956.551955162035;9960.153386962045;9964.451955142536;9983.550740216708;4700.835470758507;5400;5404.189371594292;5411.756470958816;5421.213923981;4807.6394270429855;4907.55831975124;4915.21356582945;4930.309756634074;4936.062148191832;3896.2207558488417;3936.8622098226424;3959.287328280937;3996.720229583901;4041.5854450941933;1098.2477003252638;1114.7547539541288;1249.4877100388978;1272.9045321600918;1274.1399555300136;836.3100442135141;848;878.4026861734083;886.7472770260445;939.4879790514186;5782.88434231313;5786.942130578725;6004.909926351108;6019.337872833985;6175.59858609189;902.2150728865929;909.0364213933252;911.9544022798833;922.1654402765374;937.1659223291276;3936.4488971544006;3989.3218152914073;4068.6338229559483;4086.182763447263;4096.508418989741;10612.151027917747;10881.510332035077;10933.18001149917;10975.176861735401;10988.835167472495;10919.017288444078;10930.836037459445;10934.015938565568;10941.398223097805;10996.395198264172;8126.268635822805;8230.382721295926;8230.42387032914;8249.092599814016;8261.793455163668;5048.444671086251;5569.860753481195;5721.456461636138;5849.795257165979;5939.287285525592;10072.546735396778;10298.845517317395;10304.175665946856;10306.742331441774;10325.74185645365;8311.83376332458;9875.40747555149;10098.444585547866;10189.84715229287;10389.948050260004;7494.887576686463;7651.4261430391825;7749.535027898344;7767;7787.377009839086;8216.876746848922;8337.583120844054;8419.915800842158;8443.746687599381;8455.450395724258;7510.624468776648;7550.848983020419;7568.243175682388;7598.544087354777;7621;7127.75052873147;7348.338649521569;7369.594672293076;7436.367908727602;7532.246775322424;6890.827729306607;6997.930020699728;7006;7030.894536581853;7033.929660703532;4883.194272945067;4963.975180124076;5008;5062.873428164178;5247.842564722911;5034.546890779848;5122.129238029618;5144.434112247724;5168.960263742821;5211.791528338824 09:59:56 INFO - PROCESS | 2394 | |4;prototype.html;299.61649089165866;300.3467458278246;307.60780005492995;312.8435782108946;315.54718978266203;4480.7983640736165;5229.398619158798;5279.392869819972;5332.280142180804;5373.623846330758;4199.979000104995;4969.676970996883;5142.177251639739;5171.58675274319;5319.510537049632;3846.557645870733;3966.8809935701975;3968.3452230382027;4003.359462486003;4029.7985100744845;3481.0949153220217;4031.2340655275552;4036.777977211252;4117.938230926524;4126.54607993122;3902.5316961964463;3989.7207195496326;4030.0932290234755;4038.1116154445976;4059.416827437302;3703.777773333609;4043.1913617276664;4060.614241647039;4062.1875624874997;4079;2970.658374286963;3096.643885953099;3500.8949731508096;3507.8772242971627;3510.9279380542025;4077.97961010196;5043.915558154978;5129.9483605860705;5161.66449180802;5165.199394093921;4148.688848336386;4193.496780386343;4507.729536227836;4566.817327306904;4624.791884365211;5380.91928621071;5490.615656904019;5530.668159910419;5543.695096769676;5573.665580065209;3842.2911847147975;3849.191669749356;3854.8990800014058;3868.516435445569;3882.7282090253693;5340.7716225268405;5370.925814837048;5619.775208991636;5765.855853603652;5821.476067153934;3889.86385476507;3894.299026175259;3897.928069780833;3901.49280593521;3905.2621583395685;3827.105582736563;3970.861019864291;4014.337634290368;4016.6987475939422;4040.939385909214;3826.6556009959236;4283.935740963888;4531.18438681034;4588.564086411852;4627;5024.874378140576;5214.895702085937;5520.889582208333;5527.44725527444;5590.776368945197;1333.4731732166615;1354.9729005419836;1356.6947436826833;1360.347033423962;1368.9383977721027;856.0113069404847;892.6206362295998;909.3316412436853;923.5705396990297;944.0842382888587;5161.303224064778;5177.6700484781995;5182.352205974254;5248.6588371755715;5255.973720131375;4473.696046536036;4940.258961155855;5045.571126454219;5725.879395729255;5747.3677895431465;4354.368616550582;4639.327297541838;4677.2226553909295;5188.948110518846;5196.610254230948;2788.2053614719707;2867.938862620844;2930.355321829194;2938.2507460597412;2938.8971386001813;4445.622122119591;4894.853154405302;5772.740226689809;5815.883682326415;5843.152742852262;5217.3999990000875;5218.582513398919;5895.675737834375;5955.851103722355;5960;6020.729067191899;6042.975603172898;6051.152838602599;6068.757249709962;6080.513558915188;5463.617546771688;5694.544436444991;5718.79984200551;5718.942810571841;5793.335065869579;5413.7834486620095;5722.340521248958;5738.799142030009;5784.2769653793275;5796.232448908075;5534.972325138348;5761.769529218785;5962.493188079062;6006.699665016644;6100.420460056288;3559.323728491578;3631.276542593055;3803.23935212953;3847.3267178243136;3861.8841434757;5661.490465858093;5677.006523858391;5686.82018716235;5710.600257982068;5727.6849773262875;5349.234405100006;5553.056879853684;5574.358948720949;5586.776528938797;5588.944110559005;5018.598512118949;5064.012232782528;5204.58363330925;5237.847673511821;5504.9449505504435;4636.026434448669;5234.0221431712935;5394.703291319015;5432.728363581884;5452.591055670761;5372.516473517246;5550.280362622367;5559.471850174227;5584.329880414214;5594.552435805207;4613.630909527163;5410.242566040679;5454.318210223722;5459.890802184013;5476.342838859362 09:59:56 INFO - PROCESS | 2394 | |5;yui.html;703.5989485992984;713.051641317048;760.52467207995;802.4382931947638;808.1958451340914;15345.232738363078;15943;15950.840491595081;15962.680746385066;15973;14126.57620271391;14195;14217.360218790154;14249.430022799075;14331.570052898403;680.7923583307089;696.6412297666706;845.9238668519816;847.3433089355768;864.5029108262775;846.4371193156544;863.0420233540765;864.0409145848104;864.9740507784745;865.1521508921261;912.6531917871233;912.8448163812168;913.2830727878617;914.8490499067645;914.8947870994854;714.5534041224234;882.1795729971125;905.814308066848;906.7869050773063;918.3020904112856;593.5429719116256;684.948628852838;685.3009929871553;685.5989246290926;685.9725610975555;14691.191984440851;14719.484818031318;14740.631484212874;14757.55727328171;14790.482333118396;13702;13939.303034848217;14104.672728999703;14155.221462819542;14337.42650293987;12576.559820406334;12698.238105713686;12914.741705165938;13048.28234447105;13432.529861454803;4535.750533720644;4574.977125114353;4682.719036857799;4761.28606694181;4782.593479554207;110587;111753;111963.99999999919;112178;113666;11026.44867756609;11049.392283424328;11123;11400.885991140149;11532.59635912739;22089.116435342727;22331.441713956956;22333.55332893333;22359.776402236093;22366.440838979157;7875.684972601146;7900.881486777703;7920.5247685139075;7944.682212711542;8144.959275203704;3735.6637902588895;4401.691881568259;4413.451939266643;4476.910461790746;4582.991741816795;3379.459286514146;3490.9527141857343;3494.371013217645;3498.2478767065204;3498.965010349915;541.7664677462554;559.8355420724882;637.4995628431751;643.1092936283281;643.5270076493773;557.5618080082508;594.9464548190597;635.870024192861;640.3532432243468;653.1998302079915;8622.741317760478;8745.431546949556;8913.910860891308;8947.865782013276;9107.681231156877;535.9330083739533;575.4159528079003;584.3893131677407;625.7121724006917;633.4362417448475;4331.696781225284;4409.603135717801;4448.332750087448;4453.576910193527;4513.1650644630845;65694;97964.51017745008;98269;98448.53878653092;152007;129560;131339.34330328286;133969.33015334862;134122;136457;110670.44664776625;113424.43287783508;114639.42680286379;116659;116840.41579792046;7242.891356629761;8524.403291769517;8529;8571.357148213785;8802.779930501789;57648.71175644095;64855.67572162109;65570.67214663896;66248.3437914056;66992;78096.60951695206;96217.03782962081;99328;100193;104525;11609.419529023346;11674.591389301333;11790.174687771774;11945;12083.879161208275;12190.329531875832;12224.388780561114;12321.691957701129;12362.597573826391;12370.319632420304;10042.447665378475;11793.469293881968;11908.345041022827;11992.820107698217;12219.26684398939;11788.174827762317;11797.52809887595;11864.406779661154;12103.273803571814;12447.066470014604;11177.329360238411;11216.495257713259;11281.943590281997;11290.266132701348;11490.94254528722;9649.710508684751;9788.510574471391;9904.851427228454;10077.798444031225;10164.136048435967;8541.615627296911;9876.061774131445;9905.158061564847;9921.751956201153;10000.499975001367 09:59:56 INFO - PROCESS | 2394 | __end_tp_report 09:59:56 INFO - PROCESS | 2394 | __start_cc_report 09:59:56 INFO - PROCESS | 2394 | _x_x_mozilla_cycle_collect,1462 09:59:56 INFO - PROCESS | 2394 | __end_cc_report 09:59:56 INFO - PROCESS | 2394 | __startTimestamp1472921996125__endTimestamp 09:59:56 INFO - PROCESS | 2394 | 09:59:56 INFO - PROCESS | 2394 | ------- Summary: start ------- 09:59:56 INFO - PROCESS | 2394 | Number of tests: 6 09:59:56 INFO - PROCESS | 2394 | 09:59:56 INFO - PROCESS | 2394 | [#0] dojo.html Cycles:165 Average:5639.30 Median:5856.12 stddev:655.51 (11.2%) stddev-sans-first:617.17 09:59:56 INFO - PROCESS | 2394 | Values: 2752.7 3240.0 3276.8 3295.2 3312.9 5486.5 5561.1 5626.1 5628.6 5631.2 5573.4 5606.0 5612.9 5620.3 5633.8 5109.7 5148.7 5153.9 5211.2 5219.4 4798.4 5233.5 5245.0 5245.9 5272.5 4643.3 5181.2 5185.1 5233.4 5250.9 5247.6 5252.2 5272.4 5288.0 5333.5 5099.4 5122.2 5154.0 5211.9 5242.7 5680.7 5687.8 5691.8 5742.8 5810.9 6091.8 6093.5 6118.8 6124.1 6126.7 5994.5 6079.5 6080.3 6090.7 6105.7 5800.7 5803.9 5823.8 5850.7 5851.3 5257.8 6132.2 6147.5 6170.9 6181.0 5940.6 6040.7 6074.5 6076.3 6083.8 6044.1 6085.1 6126.3 6131.5 6137.0 6094.3 6107.1 6117.4 6118.5 6149.4 4796.4 5189.2 5993.9 6080.4 6090.5 4191.5 4839.6 4842.9 4854.7 4855.4 4374.0 4376.2 4408.2 4414.8 4415.8 5645.2 5696.3 5719.1 5726.5 5733.1 6153.8 6190.8 6215.3 6220.5 6220.6 5511.4 5640.7 5654.2 5663.9 5668.5 5235.1 5333.4 5382.8 5393.1 5401.0 6193.9 6212.7 6218.8 6225.2 6231.9 6185.9 6208.8 6221.0 6246.0 6249.7 6123.2 6145.9 6157.2 6210.2 6247.6 6097.8 6107.3 6113.1 6125.4 6141.1 5256.3 6194.6 6211.5 6227.6 6237.9 5084.7 5850.5 5856.1 5996.0 6055.8 4697.3 6028.6 6066.9 6083.1 6096.0 5952.1 5963.2 5965.2 5970.4 5970.9 5944.0 5967.6 6026.8 6067.6 6094.4 5870.7 5918.3 5960.2 5969.9 6028.9 09:59:56 INFO - PROCESS | 2394 | 09:59:56 INFO - PROCESS | 2394 | [#1] ext.html Cycles:180 Average:67444.54 Median:37007.81 stddev:144668.97 (390.9%) stddev-sans-first:144987.37 09:59:56 INFO - PROCESS | 2394 | Values: 453.5 471.4 474.5 512.6 518.6 30265.4 40099.8 41168.8 41305.6 43419.6 42144.4 42469.8 42548.1 42617.4 42645.8 8222.8 8683.0 9796.8 9805.8 9828.6 8658.9 9887.4 11957.0 12031.8 12237.2 11962.6 11965.6 11997.9 12009.0 12020.3 11933.3 12005.3 12012.0 12013.3 12044.6 11733.3 11805.8 11808.6 11817.0 11832.8 39734.8 40093.4 40109.8 40174.0 40226.0 3322.6 3432.5 3493.2 3579.6 4031.8 30063.1 30239.5 30353.8 30770.5 31511.9 2928.4 3156.1 3229.2 3240.0 3255.6 421296.0 423656.0 424926.0 426182.0 426420.0 56998.9 57307.4 57343.0 57401.4 57492.0 33783.7 34373.0 34389.8 35545.0 60214.4 15901.3 16045.4 16068.6 16081.4 16089.0 11280.4 11470.7 12304.0 12414.4 12601.4 18702.4 18710.6 18740.6 18775.2 18832.3 6485.7 6705.4 7129.3 7324.7 7700.9 815.6 945.5 971.2 1039.2 1057.8 28870.7 29306.7 30078.5 30220.7 30590.2 990.5 1012.9 1032.7 1075.1 1132.0 13424.6 15394.8 15398.0 15488.1 15800.8 554601.0 637897.8 651448.0 651564.0 653125.0 46675.8 56398.7 57006.7 60907.4 61029.8 1280.7 1318.3 1398.5 1407.1 1436.4 26384.9 32077.0 32173.4 32459.8 32758.2 55534.2 56034.4 56192.4 56201.0 56214.7 547565.0 549596.0 551720.0 552860.2 553867.0 41750.8 42406.7 42959.4 43329.8 43459.8 46967.0 48107.0 48150.0 48171.0 48508.8 37912.8 38909.6 39067.4 39578.9 40407.8 38566.4 39037.6 39058.8 39749.0 40065.6 34376.8 35732.6 36102.8 38394.8 38711.0 28240.3 33358.8 33439.8 34460.5 34963.7 24934.8 25121.1 25607.9 25853.0 25951.9 09:59:56 INFO - PROCESS | 2394 | 09:59:56 INFO - PROCESS | 2394 | [#2] jquery.html Cycles:180 Average:32447.19 Median:3877.74 stddev:90873.61 (2343.5%) stddev-sans-first:91096.49 09:59:56 INFO - PROCESS | 2394 | Values: 309.0 347.5 368.0 368.3 399.9 15099.0 17833.7 20812.1 20945.2 20997.9 11833.3 14723.0 19322.6 20132.6 20283.9 5422.2 5647.5 5650.3 5652.1 5654.6 5218.9 5482.4 5509.3 5532.4 5535.3 5535.4 5538.7 5541.7 5544.6 5552.0 5530.9 5811.2 6107.0 6192.6 6201.3 5284.5 6016.8 6022.2 6031.0 6048.2 12822.7 13464.3 13474.9 13511.4 15875.7 2128.2 2247.4 2270.4 2355.1 2588.9 9865.6 9873.8 9963.4 10274.6 10379.5 1679.6 1779.0 1872.4 1900.9 2013.0 99866.5 110454.0 117645.0 120456.0 120688.0 9216.5 9752.1 9887.6 11365.1 11654.2 12082.6 12233.8 12271.3 12286.4 12295.4 7860.8 7964.9 8030.2 8116.8 8138.1 8183.1 8209.8 8244.6 8402.7 8422.9 3816.1 3832.5 3842.3 3913.2 3933.0 1861.0 1905.7 1914.8 1920.9 1937.8 869.4 882.3 888.9 912.0 976.7 20834.7 21384.7 21778.0 21929.6 21988.4 786.6 900.7 909.1 953.5 980.6 9547.8 11059.6 11216.9 11358.3 11452.7 473431.6 547687.0 552532.0 564965.0 565426.0 96614.0 125161.0 125639.0 131512.0 132458.0 928.6 947.8 979.5 979.6 981.3 19264.4 19271.0 19320.3 19368.9 19533.7 51715.7 52503.0 53656.5 53774.7 54035.9 68469.0 69159.7 76619.0 80309.0 80946.2 6800.4 7752.1 7854.9 7911.7 8061.6 8040.6 12330.4 12435.1 12653.1 12741.4 10867.2 10917.1 11091.6 11190.5 11200.4 10554.5 10616.4 10633.7 10879.9 11089.6 11028.0 11047.4 11106.6 11117.8 11170.2 7635.5 7887.3 7916.9 8018.5 8236.1 8080.2 10093.0 10653.2 11024.4 11071.5 09:59:56 INFO - PROCESS | 2394 | 09:59:56 INFO - PROCESS | 2394 | [#3] mootools.html Cycles:180 Average:5747.43 Median:4985.99 stddev:3219.28 (64.6%) stddev-sans-first:3211.40 09:59:56 INFO - PROCESS | 2394 | Values: 1356.8 1504.2 1688.6 1827.8 1907.5 8031.1 8305.9 8511.9 9092.1 9095.9 7719.6 8736.0 8745.7 8865.9 8878.1 2380.4 2402.8 2409.3 2411.7 2412.1 2089.1 2163.6 2355.5 2397.6 2413.2 2321.2 2398.6 2399.3 2399.7 2405.1 2377.4 2389.2 2397.9 2405.5 2406.0 2349.9 2369.3 2400.5 2402.2 2414.2 8796.4 8908.3 8912.8 8915.0 8923.4 5254.6 5416.9 5418.4 5419.2 5421.5 3504.7 3634.5 3654.1 3694.8 3697.2 1045.9 1094.0 1116.7 1122.1 1122.8 11587.3 11628.5 11641.0 11647.8 11653.5 4264.3 4441.3 4668.1 4813.8 4860.7 9955.0 9956.6 9960.2 9964.5 9983.6 4700.8 5400.0 5404.2 5411.8 5421.2 4807.6 4907.6 4915.2 4930.3 4936.1 3896.2 3936.9 3959.3 3996.7 4041.6 1098.2 1114.8 1249.5 1272.9 1274.1 836.3 848.0 878.4 886.7 939.5 5782.9 5786.9 6004.9 6019.3 6175.6 902.2 909.0 912.0 922.2 937.2 3936.4 3989.3 4068.6 4086.2 4096.5 10612.2 10881.5 10933.2 10975.2 10988.8 10919.0 10930.8 10934.0 10941.4 10996.4 8126.3 8230.4 8230.4 8249.1 8261.8 5048.4 5569.9 5721.5 5849.8 5939.3 10072.5 10298.8 10304.2 10306.7 10325.7 8311.8 9875.4 10098.4 10189.8 10389.9 7494.9 7651.4 7749.5 7767.0 7787.4 8216.9 8337.6 8419.9 8443.7 8455.5 7510.6 7550.8 7568.2 7598.5 7621.0 7127.8 7348.3 7369.6 7436.4 7532.2 6890.8 6997.9 7006.0 7030.9 7033.9 4883.2 4964.0 5008.0 5062.9 5247.8 5034.5 5122.1 5144.4 5169.0 5211.8 09:59:56 INFO - PROCESS | 2394 | 09:59:56 INFO - PROCESS | 2394 | [#4] prototype.html Cycles:180 Average:4511.10 Median:5161.48 stddev:1380.55 (26.7%) stddev-sans-first:1347.74 09:59:56 INFO - PROCESS | 2394 | Values: 299.6 300.3 307.6 312.8 315.5 4480.8 5229.4 5279.4 5332.3 5373.6 4200.0 4969.7 5142.2 5171.6 5319.5 3846.6 3966.9 3968.3 4003.4 4029.8 3481.1 4031.2 4036.8 4117.9 4126.5 3902.5 3989.7 4030.1 4038.1 4059.4 3703.8 4043.2 4060.6 4062.2 4079.0 2970.7 3096.6 3500.9 3507.9 3510.9 4078.0 5043.9 5129.9 5161.7 5165.2 4148.7 4193.5 4507.7 4566.8 4624.8 5380.9 5490.6 5530.7 5543.7 5573.7 3842.3 3849.2 3854.9 3868.5 3882.7 5340.8 5370.9 5619.8 5765.9 5821.5 3889.9 3894.3 3897.9 3901.5 3905.3 3827.1 3970.9 4014.3 4016.7 4040.9 3826.7 4283.9 4531.2 4588.6 4627.0 5024.9 5214.9 5520.9 5527.4 5590.8 1333.5 1355.0 1356.7 1360.3 1368.9 856.0 892.6 909.3 923.6 944.1 5161.3 5177.7 5182.4 5248.7 5256.0 4473.7 4940.3 5045.6 5725.9 5747.4 4354.4 4639.3 4677.2 5188.9 5196.6 2788.2 2867.9 2930.4 2938.3 2938.9 4445.6 4894.9 5772.7 5815.9 5843.2 5217.4 5218.6 5895.7 5955.9 5960.0 6020.7 6043.0 6051.2 6068.8 6080.5 5463.6 5694.5 5718.8 5718.9 5793.3 5413.8 5722.3 5738.8 5784.3 5796.2 5535.0 5761.8 5962.5 6006.7 6100.4 3559.3 3631.3 3803.2 3847.3 3861.9 5661.5 5677.0 5686.8 5710.6 5727.7 5349.2 5553.1 5574.4 5586.8 5588.9 5018.6 5064.0 5204.6 5237.8 5504.9 4636.0 5234.0 5394.7 5432.7 5452.6 5372.5 5550.3 5559.5 5584.3 5594.6 4613.6 5410.2 5454.3 5459.9 5476.3 09:59:56 INFO - PROCESS | 2394 | 09:59:56 INFO - PROCESS | 2394 | [#5] yui.html Cycles:180 Average:23737.79 Median:4430.89 stddev:37586.73 (848.3%) stddev-sans-first:37652.38 09:59:56 INFO - PROCESS | 2394 | Values: 703.6 713.1 760.5 802.4 808.2 15345.2 15943.0 15950.8 15962.7 15973.0 14126.6 14195.0 14217.4 14249.4 14331.6 680.8 696.6 845.9 847.3 864.5 846.4 863.0 864.0 865.0 865.2 912.7 912.8 913.3 914.8 914.9 714.6 882.2 905.8 906.8 918.3 593.5 684.9 685.3 685.6 686.0 14691.2 14719.5 14740.6 14757.6 14790.5 13702.0 13939.3 14104.7 14155.2 14337.4 12576.6 12698.2 12914.7 13048.3 13432.5 4535.8 4575.0 4682.7 4761.3 4782.6 110587.0 111753.0 111964.0 112178.0 113666.0 11026.4 11049.4 11123.0 11400.9 11532.6 22089.1 22331.4 22333.6 22359.8 22366.4 7875.7 7900.9 7920.5 7944.7 8145.0 3735.7 4401.7 4413.5 4476.9 4583.0 3379.5 3491.0 3494.4 3498.2 3499.0 541.8 559.8 637.5 643.1 643.5 557.6 594.9 635.9 640.4 653.2 8622.7 8745.4 8913.9 8947.9 9107.7 535.9 575.4 584.4 625.7 633.4 4331.7 4409.6 4448.3 4453.6 4513.2 65694.0 97964.5 98269.0 98448.5 152007.0 129560.0 131339.3 133969.3 134122.0 136457.0 110670.4 113424.4 114639.4 116659.0 116840.4 7242.9 8524.4 8529.0 8571.4 8802.8 57648.7 64855.7 65570.7 66248.3 66992.0 78096.6 96217.0 99328.0 100193.0 104525.0 11609.4 11674.6 11790.2 11945.0 12083.9 12190.3 12224.4 12321.7 12362.6 12370.3 10042.4 11793.5 11908.3 11992.8 12219.3 11788.2 11797.5 11864.4 12103.3 12447.1 11177.3 11216.5 11281.9 11290.3 11490.9 9649.7 9788.5 9904.9 10077.8 10164.1 8541.6 9876.1 9905.2 9921.8 10000.5 09:59:56 INFO - PROCESS | 2394 | -------- Summary: end -------- 09:59:56 INFO - PROCESS | 2394 | 09:59:56 INFO - TEST-INFO | 2394: exit 0 09:59:56 INFO - TEST-OK | dromaeo_css | took 1117397ms 09:59:56 INFO - TEST-START | kraken 09:59:56 INFO - operating with platform_type : mac_ 09:59:56 INFO - Initialising browser for kraken test... 09:59:56 INFO - TEST-INFO | started process 2397 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/tmpIwZbH3/profile http://localhost:49809/getInfo.html) 09:59:58 INFO - PROCESS | 2397 | __metrics Screen width/height:1600/1200 09:59:58 INFO - PROCESS | 2397 | colorDepth:24 09:59:58 INFO - PROCESS | 2397 | Browser inner width/height: 1024/689 09:59:58 INFO - PROCESS | 2397 | __metrics 09:59:59 INFO - PROCESS | 2397 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:59:59 INFO - PROCESS | 2397 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:59:59 INFO - TEST-INFO | 2397: exit 0 09:59:59 INFO - Browser initialized. 09:59:59 INFO - Running cycle 1/1 for kraken test... 09:59:59 INFO - TEST-INFO | started process 2398 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/tmpIwZbH3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:00:11 INFO - PROCESS | 2398 | RSS: Main: 234049536 10:00:11 INFO - PROCESS | 2398 | 10:00:14 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:49809/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49809/tests/kraken/driver.html?testName=audio-beat-detection) 10:00:15 INFO - PROCESS | 2398 | RSS: Main: 292696064 10:00:15 INFO - PROCESS | 2398 | 10:00:17 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:49809/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49809/tests/kraken/driver.html?testName=audio-dft) 10:00:18 INFO - PROCESS | 2398 | RSS: Main: 289771520 10:00:18 INFO - PROCESS | 2398 | 10:00:21 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:49809/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49809/tests/kraken/driver.html?testName=audio-fft) 10:00:22 INFO - PROCESS | 2398 | RSS: Main: 381067264 10:00:22 INFO - PROCESS | 2398 | 10:00:24 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:49809/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49809/tests/kraken/driver.html?testName=audio-oscillator) 10:00:25 INFO - PROCESS | 2398 | RSS: Main: 283164672 10:00:25 INFO - PROCESS | 2398 | 10:00:27 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:49809/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49809/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:00:27 INFO - PROCESS | 2398 | RSS: Main: 286867456 10:00:27 INFO - PROCESS | 2398 | 10:00:30 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:49809/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49809/tests/kraken/driver.html?testName=imaging-darkroom) 10:00:31 INFO - PROCESS | 2398 | RSS: Main: 324980736 10:00:31 INFO - PROCESS | 2398 | 10:00:34 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:49809/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49809/tests/kraken/driver.html?testName=imaging-desaturate) 10:00:35 INFO - PROCESS | 2398 | RSS: Main: 327020544 10:00:35 INFO - PROCESS | 2398 | 10:00:37 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:49809/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49809/tests/kraken/driver.html?testName=json-parse-financial) 10:00:38 INFO - PROCESS | 2398 | RSS: Main: 324038656 10:00:38 INFO - PROCESS | 2398 | 10:00:39 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:49809/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49809/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:00:40 INFO - PROCESS | 2398 | RSS: Main: 298422272 10:00:40 INFO - PROCESS | 2398 | 10:00:42 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:49809/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49809/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:00:43 INFO - PROCESS | 2398 | RSS: Main: 303067136 10:00:43 INFO - PROCESS | 2398 | 10:00:45 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:49809/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49809/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:00:46 INFO - PROCESS | 2398 | RSS: Main: 301027328 10:00:46 INFO - PROCESS | 2398 | 10:00:49 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:49809/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49809/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:00:49 INFO - PROCESS | 2398 | RSS: Main: 319102976 10:00:49 INFO - PROCESS | 2398 | 10:00:52 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:49809/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49809/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:00:53 INFO - PROCESS | 2398 | RSS: Main: 315359232 10:00:53 INFO - PROCESS | 2398 | 10:00:55 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:49809/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49809/tests/kraken/driver.html?testName=ai-astar) 10:00:55 INFO - PROCESS | 2398 | RSS: Main: 359034880 10:00:55 INFO - PROCESS | 2398 | 10:00:55 INFO - PROCESS | 2398 | __start_tp_report 10:00:55 INFO - PROCESS | 2398 | _x_x_mozilla_page_load 10:00:55 INFO - PROCESS | 2398 | _x_x_mozilla_page_load_details 10:00:55 INFO - PROCESS | 2398 | |i|pagename|runs| 10:00:55 INFO - PROCESS | 2398 | |0;ai-astar;114;113;117;114;113;113;112;113;113;124 10:00:55 INFO - PROCESS | 2398 | |1;audio-beat-detection;146;116;129;165;109;132;128;111;159;108 10:00:55 INFO - PROCESS | 2398 | |2;audio-dft;187;187;185;184;187;187;184;182;185;183 10:00:55 INFO - PROCESS | 2398 | |3;audio-fft;74;77;79;158;58;57;58;67;149;80 10:00:55 INFO - PROCESS | 2398 | |4;audio-oscillator;83;85;136;69;70;76;123;68;70;117 10:00:55 INFO - PROCESS | 2398 | |5;imaging-gaussian-blur;91;85;126;124;92;88;92;88;91;88 10:00:55 INFO - PROCESS | 2398 | |6;imaging-darkroom;96;95;96;97;96;96;96;96;104;141 10:00:55 INFO - PROCESS | 2398 | |7;imaging-desaturate;85;84;85;84;89;86;88;84;84;84 10:00:55 INFO - PROCESS | 2398 | |8;json-parse-financial;55;55;55;55;55;57;55;55;56;55 10:00:55 INFO - PROCESS | 2398 | |9;json-stringify-tinderbox;66;64;69;109;103;93;74;70;69;70 10:00:55 INFO - PROCESS | 2398 | |10;stanford-crypto-aes;91;102;101;77;84;77;94;102;101;87 10:00:55 INFO - PROCESS | 2398 | |11;stanford-crypto-ccm;145;150;137;139;140;141;140;139;182;153 10:00:55 INFO - PROCESS | 2398 | |12;stanford-crypto-pbkdf2;167;164;170;167;169;171;196;222;186;171 10:00:55 INFO - PROCESS | 2398 | |13;stanford-crypto-sha256-iterative;67;67;67;65;71;83;82;82;77;66 10:00:55 INFO - PROCESS | 2398 | __end_tp_report 10:00:55 INFO - PROCESS | 2398 | __start_cc_report 10:00:55 INFO - PROCESS | 2398 | _x_x_mozilla_cycle_collect,2030 10:00:55 INFO - PROCESS | 2398 | __end_cc_report 10:00:55 INFO - PROCESS | 2398 | __startTimestamp1472922055411__endTimestamp 10:00:55 INFO - PROCESS | 2398 | 10:00:55 INFO - PROCESS | 2398 | ------- Summary: start ------- 10:00:55 INFO - PROCESS | 2398 | Number of tests: 14 10:00:55 INFO - PROCESS | 2398 | 10:00:55 INFO - PROCESS | 2398 | [#0] ai-astar Cycles:10 Average:114.60 Median:113.50 stddev:3.57 (3.1%) stddev-sans-first:3.77 10:00:55 INFO - PROCESS | 2398 | Values: 114.0 113.0 117.0 114.0 113.0 113.0 112.0 113.0 113.0 124.0 10:00:55 INFO - PROCESS | 2398 | 10:00:55 INFO - PROCESS | 2398 | [#1] audio-beat-detection Cycles:10 Average:130.30 Median:130.50 stddev:20.58 (15.8%) stddev-sans-first:21.03 10:00:55 INFO - PROCESS | 2398 | Values: 146.0 116.0 129.0 165.0 109.0 132.0 128.0 111.0 159.0 108.0 10:00:55 INFO - PROCESS | 2398 | 10:00:55 INFO - PROCESS | 2398 | [#2] audio-dft Cycles:10 Average:185.10 Median:186.00 stddev:1.85 (1.0%) stddev-sans-first:1.83 10:00:55 INFO - PROCESS | 2398 | Values: 187.0 187.0 185.0 184.0 187.0 187.0 184.0 182.0 185.0 183.0 10:00:55 INFO - PROCESS | 2398 | 10:00:55 INFO - PROCESS | 2398 | [#3] audio-fft Cycles:10 Average:85.70 Median:70.50 stddev:36.87 (52.3%) stddev-sans-first:38.86 10:00:55 INFO - PROCESS | 2398 | Values: 74.0 77.0 79.0 158.0 58.0 57.0 58.0 67.0 149.0 80.0 10:00:55 INFO - PROCESS | 2398 | 10:00:55 INFO - PROCESS | 2398 | [#4] audio-oscillator Cycles:10 Average:89.70 Median:70.00 stddev:25.66 (36.7%) stddev-sans-first:27.11 10:00:55 INFO - PROCESS | 2398 | Values: 83.0 85.0 136.0 69.0 70.0 76.0 123.0 68.0 70.0 117.0 10:00:55 INFO - PROCESS | 2398 | 10:00:55 INFO - PROCESS | 2398 | [#5] imaging-gaussian-blur Cycles:10 Average:96.50 Median:89.50 stddev:15.19 (17.0%) stddev-sans-first:15.98 10:00:55 INFO - PROCESS | 2398 | Values: 91.0 85.0 126.0 124.0 92.0 88.0 92.0 88.0 91.0 88.0 10:00:55 INFO - PROCESS | 2398 | 10:00:55 INFO - PROCESS | 2398 | [#6] imaging-darkroom Cycles:10 Average:101.30 Median:96.00 stddev:14.18 (14.8%) stddev-sans-first:14.91 10:00:55 INFO - PROCESS | 2398 | Values: 96.0 95.0 96.0 97.0 96.0 96.0 96.0 96.0 104.0 141.0 10:00:55 INFO - PROCESS | 2398 | 10:00:55 INFO - PROCESS | 2398 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:85.00 stddev:1.83 (2.2%) stddev-sans-first:1.94 10:00:55 INFO - PROCESS | 2398 | Values: 85.0 84.0 85.0 84.0 89.0 86.0 88.0 84.0 84.0 84.0 10:00:55 INFO - PROCESS | 2398 | 10:00:55 INFO - PROCESS | 2398 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:55.00 stddev:0.67 (1.2%) stddev-sans-first:0.71 10:00:55 INFO - PROCESS | 2398 | Values: 55.0 55.0 55.0 55.0 55.0 57.0 55.0 55.0 56.0 55.0 10:00:55 INFO - PROCESS | 2398 | 10:00:55 INFO - PROCESS | 2398 | [#9] json-stringify-tinderbox Cycles:10 Average:78.70 Median:69.50 stddev:16.51 (23.7%) stddev-sans-first:16.86 10:00:55 INFO - PROCESS | 2398 | Values: 66.0 64.0 69.0 109.0 103.0 93.0 74.0 70.0 69.0 70.0 10:00:55 INFO - PROCESS | 2398 | 10:00:55 INFO - PROCESS | 2398 | [#10] stanford-crypto-aes Cycles:10 Average:91.60 Median:80.50 stddev:10.02 (12.5%) stddev-sans-first:10.63 10:00:55 INFO - PROCESS | 2398 | Values: 91.0 102.0 101.0 77.0 84.0 77.0 94.0 102.0 101.0 87.0 10:00:55 INFO - PROCESS | 2398 | 10:00:55 INFO - PROCESS | 2398 | [#11] stanford-crypto-ccm Cycles:10 Average:146.60 Median:143.00 stddev:13.48 (9.4%) stddev-sans-first:14.28 10:00:55 INFO - PROCESS | 2398 | Values: 145.0 150.0 137.0 139.0 140.0 141.0 140.0 139.0 182.0 153.0 10:00:55 INFO - PROCESS | 2398 | 10:00:55 INFO - PROCESS | 2398 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.30 Median:171.00 stddev:18.27 (10.7%) stddev-sans-first:18.92 10:00:55 INFO - PROCESS | 2398 | Values: 167.0 164.0 170.0 167.0 169.0 171.0 196.0 222.0 186.0 171.0 10:00:55 INFO - PROCESS | 2398 | 10:00:55 INFO - PROCESS | 2398 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.70 Median:74.00 stddev:7.47 (10.1%) stddev-sans-first:7.63 10:00:55 INFO - PROCESS | 2398 | Values: 67.0 67.0 67.0 65.0 71.0 83.0 82.0 82.0 77.0 66.0 10:00:55 INFO - PROCESS | 2398 | -------- Summary: end -------- 10:00:55 INFO - PROCESS | 2398 | 10:00:56 INFO - TEST-INFO | 2398: exit 0 10:00:56 INFO - TEST-OK | kraken | took 59485ms 10:00:56 INFO - SUITE-END | took 1176s 10:00:56 INFO - Completed test suite (00:19:37) 10:00:56 INFO - javascript benchmark 10:00:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2752.7384898434652, 3239.967600323997, 3276.8361581920913, 3295.1597342677615, 3312.90061298161, 5486.506214440699, 5561.054620714478, 5626.071698169802, 5628.634138780976, 5631.239782629344, 5573.4147914469, 5606.018946684334, 5612.887742245152, 5620.269364982557, 5633.80281690141, 5109.69341839489, 5148.665336753118, 5153.922691159635, 5211.218317252424, 5219.42586315505, 4798.352222449959, 5233.450487698794, 5244.973775131138, 5245.947540524584, 5272.525472707455, 4643.349931009644, 5181.248718935771, 5185.144451165572, 5233.3981592116825, 5250.868728281805, 5247.580193584504, 5252.159654455288, 5272.446393128705, 5288.0, 5333.546648534879, 5099.4390617032095, 5122.231665250205, 5154.0, 5211.869703257411, 5242.68543887368, 5680.6591604503865, 5687.772489100431, 5691.7723291068305, 5742.770289188427, 5810.883782324372, 6091.786787462417, 6093.482054025369, 6118.847028824315, 6124.081387791823, 6126.663033533154, 5994.520438364921, 6079.483243924272, 6080.270367555878, 6090.695465226721, 6105.694715264263, 5800.709964501758, 5803.912941305884, 5823.796167134173, 5850.736716847752, 5851.297844258716, 5257.8159764408065, 6132.202813634288, 6147.477464415485, 6170.907436388457, 6181.041938499539, 5940.6435613863305, 6040.728167232485, 6074.4532992030045, 6076.301225359052, 6083.8479038023515, 6044.063170208625, 6085.084002341905, 6126.326104128542, 6131.499004853489, 6137.018077107641, 6094.329623741385, 6107.144999700045, 6117.449429551273, 6118.479929205978, 6149.41580549847, 4796.352492413549, 5189.195674670432, 5993.880122397528, 6080.391960803884, 6090.482309003784, 4191.476065491814, 4839.612830973584, 4842.927356089662, 4854.68444551103, 4855.36880205571, 4374.03771170342, 4376.168527979674, 4408.162449134654, 4414.757188354607, 4415.823367065282, 5645.181448689999, 5696.316442026983, 5719.084946408555, 5726.455986681259, 5733.111367738007, 6153.784617538365, 6190.783322583688, 6215.34738852414, 6220.471259942956, 6220.626762394271, 5511.448855114457, 5640.746166422521, 5654.236678048493, 5663.858403539944, 5668.489835914705, 5235.136202526616, 5333.439988801198, 5382.757775900016, 5393.1370980642905, 5401.0, 6193.876122477615, 6212.658303793335, 6218.844528886814, 6225.159603453476, 6231.937680623136, 6185.865586753405, 6208.751649933952, 6220.973539365954, 6245.968770156302, 6249.6562689052535, 6123.1911131038305, 6145.877082458415, 6157.168782214344, 6210.161628180138, 6247.562670613013, 6097.817065488042, 6107.295156111712, 6113.144159817718, 6125.448709616156, 6141.1402403664415, 5256.31667883188, 6194.628322300677, 6211.534134939807, 6227.626342419469, 6237.937620623736, 5084.745762711924, 5850.473457388855, 5856.121581762599, 5996.010658241341, 6055.788047418319, 4697.295405689174, 6028.608140470856, 6066.878662426639, 6083.148359229623, 6096.02463605821, 5952.107183922446, 5963.165156877953, 5965.164876917148, 5970.402959703995, 5970.940290597038, 5944.0, 5967.552433567413, 6026.819195424145, 6067.605605635619, 6094.3600921904335, 5870.735816888165, 5918.3489816121055, 5960.195373624505, 5969.940300597113, 6028.90956635642], "value": 5596.978396703438, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [453.46717606811995, 471.42957022003355, 474.46859517340584, 512.5720023780143, 518.5669965578742, 30265.394692106143, 40099.79950100249, 41168.79415602922, 41305.58694413055, 43419.565804341946, 42144.36783448251, 42469.78765106178, 42548.14903701924, 42617.360739588854, 42645.7867710661, 8222.794430139249, 8683.044865064838, 9796.755081122994, 9805.803883922317, 9828.557714902847, 8658.913410865905, 9887.357321774098, 11957.043436525057, 12031.819522707166, 12237.204581702206, 11962.581309654164, 11965.641030769048, 11997.880021199808, 12009.039276857831, 12020.278783272988, 11933.34366609836, 12005.339706316148, 12012.0, 12013.27920324779, 12044.638660840188, 11733.296002239807, 11805.822912656316, 11808.586699465563, 11817.0, 11832.763344733057, 39734.801325993474, 40093.398599021035, 40109.79945100256, 40173.995650108984, 40226.0, 3322.551455553518, 3432.5022871683714, 3493.1616412061176, 3579.552555930509, 4031.8387264509383, 30063.098107056823, 30239.546406803915, 30353.848230758926, 30770.53844192339, 31511.897083601965, 2928.443595716807, 3156.1116229308413, 3229.160418291238, 3239.951400728991, 3255.609326880766, 421296.0, 423656.0, 424925.9999999969, 426182.0, 426420.0, 56998.86002280014, 57307.40964789019, 57343.0, 57401.42598574045, 57492.0, 33783.66216337854, 34373.0, 34389.828050859585, 35545.0, 60214.39785602176, 15901.284442200129, 16045.358185672443, 16068.598285042968, 16081.356745730274, 16089.0, 11280.379579123062, 11470.655880323604, 12304.0, 12414.379281035912, 12601.369931503572, 18702.438926832216, 18710.625787484445, 18740.625187496174, 18775.15511801972, 18832.34086806955, 6485.70814313357, 6705.396514313637, 7129.322714342129, 7324.718174319472, 7700.921870938073, 815.5759005317202, 945.5130607737021, 971.2424309038961, 1039.1946241662772, 1057.7937302226146, 28870.711292887223, 29306.7069329304, 30078.54882176769, 30220.697793022227, 30590.235244119074, 990.4898977026837, 1012.8531362952331, 1032.6695457453543, 1075.0915476422567, 1131.9660410187707, 13424.597262082154, 15394.846051539565, 15397.99913005651, 15488.14815185153, 15800.762988555181, 554601.0, 637897.8105109445, 651448.0, 651564.0, 653125.0, 46675.76662116668, 56398.718006409705, 57006.7149664249, 60907.39092609106, 61029.77940441166, 1280.7246442015198, 1318.3210646517243, 1398.5175114585452, 1407.0502410872825, 1436.3967133803828, 26384.92629576789, 32077.0, 32173.35653286968, 32459.837700811346, 32758.181045474055, 55534.16698749441, 56034.439655602924, 56192.438075618724, 56201.0, 56214.718926405105, 547565.0, 549596.0, 551720.0, 552860.1784955409, 553867.0, 41750.79124604358, 42406.7277981661, 42959.355609665254, 43329.783351084305, 43459.78270108629, 46967.0, 48107.0, 48150.0, 48171.0, 48508.757456212494, 37912.81043594764, 38909.6109038906, 39067.41398878962, 39578.94946795322, 40407.79796101001, 38566.42150367803, 39037.609623903394, 39058.80470597629, 39749.031371078614, 40065.59934400619, 34376.82811585926, 35732.64267357293, 36102.8194859024, 38394.80802595969, 38711.0, 28240.29399265035, 33358.83320583381, 33439.83280083584, 34460.48309275413, 34963.65036349706, 24934.750652493243, 25121.120760773287, 25607.871960640077, 25852.965881365286, 25951.870240648677], "value": 20205.382743876562, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.9907302780917, 347.4649040477664, 367.98528058877645, 368.289201840448, 399.94000899865017, 15099.0, 17833.732494012584, 20812.063457144428, 20945.16219351226, 20997.895010524964, 11833.349165795878, 14722.969392142555, 19322.613547729037, 20132.597348053067, 20283.898580507077, 5422.240886275935, 5647.548196144298, 5650.265465489501, 5652.123920792264, 5654.575906806986, 5218.921716174261, 5482.424345443731, 5509.283793106891, 5532.446755324476, 5535.308086489189, 5535.363433205192, 5538.695371754552, 5541.695206763627, 5544.556435485151, 5551.9722401387935, 5530.917036244459, 5811.186433899257, 6107.0, 6192.597481163755, 6201.348858369851, 5284.497972692588, 6016.75932962681, 6022.187004754346, 6030.969845150747, 6048.153258543807, 12822.679433014157, 13464.259465729381, 13474.865251347459, 13511.391987360592, 15875.682486350324, 2128.159377046058, 2247.3595025417676, 2270.4437412833963, 2355.093289083698, 2588.8705564721877, 9865.60537578496, 9873.753156171082, 9963.41317125834, 10274.589016439408, 10379.481025948673, 1679.5717092141547, 1778.97331540027, 1872.447627949758, 1900.8669393142486, 2013.0, 99866.50066749692, 110454.0, 117645.0, 120456.0, 120688.0, 9216.493092879822, 9752.073553012453, 9887.555060022316, 11365.147613928988, 11654.242474239147, 12082.637520874388, 12233.755324893453, 12271.263724176579, 12286.447109880077, 12295.4467048983, 7860.803479912934, 7964.920350796534, 8030.156833532512, 8116.797080072927, 8138.134386166642, 8183.099859015503, 8209.753707388787, 8244.628991695388, 8402.747917562483, 8422.873656895152, 3816.0841398064263, 3832.5017747692623, 3842.3083844908183, 3913.178232571135, 3933.0364060805286, 1860.9720854187199, 1905.7427247321712, 1914.8085191481018, 1920.875143115721, 1937.7674679038605, 869.4435561240684, 882.342654722236, 888.8977767556685, 911.9954400227956, 976.6532880827347, 20834.68747968782, 21384.679229811565, 21778.01998910053, 21929.561408772053, 21988.350873684536, 786.6263524825662, 900.7162743735702, 909.1363204955319, 953.50894289441, 980.6028558433845, 9547.778448028677, 11059.557617695364, 11216.943915280535, 11358.318500889973, 11452.656420307405, 473431.6328418336, 547687.0, 552532.0, 564965.0, 565426.0, 96614.0, 125161.0, 125639.0, 131512.0, 132458.0, 928.5960607135918, 947.8151760406793, 979.4759803505091, 979.5787811241092, 981.3228872078244, 19264.422067338, 19271.0, 19320.32378866789, 19368.903155484695, 19533.70699439538, 51715.74142129265, 52503.0, 53656.46343536515, 53774.65563360947, 54035.91928161494, 68469.0, 69159.65420172867, 76619.0, 80309.0, 80946.19053809387, 6800.387965083166, 7752.14726380109, 7854.882176767239, 7911.723089691834, 8061.556614386031, 8040.597970101355, 12330.383480825743, 12435.06736994711, 12653.114282000171, 12741.426635801596, 10867.184961127788, 10917.126629869432, 11091.5563377464, 11190.496427660937, 11200.439978000904, 10554.472276386303, 10616.363018218932, 10633.680989570325, 10879.891201087888, 11089.556417743523, 11028.0, 11047.392393418118, 11106.61126860556, 11117.777644446904, 11170.162237832357, 7635.465517413728, 7887.329576986021, 7916.881246781418, 8018.518888866687, 8236.135205803304, 8080.232377924088, 10093.0, 10653.201009924442, 11024.44877756125, 11071.50178241965], "value": 9047.559873059601, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1356.830396200475, 1504.232841250962, 1688.62006048639, 1827.7806663200402, 1907.484979055655, 8031.076426210973, 8305.91694083059, 8511.872321915169, 9092.090790920905, 9095.90904090959, 7719.614019299041, 8735.956320218405, 8745.650173993032, 8865.86701199481, 8878.112188781135, 2380.3573035280465, 2402.771736685012, 2409.265174121892, 2411.67442395277, 2412.107520217522, 2089.122568521217, 2163.642998905179, 2355.4700192456644, 2397.5564520563667, 2413.2036427978815, 2321.1991862807304, 2398.5922393193114, 2399.3042017814814, 2399.664047033417, 2405.050005247926, 2377.393764590036, 2389.151851092863, 2397.8681172535416, 2405.542946840095, 2406.03758496601, 2349.8707571083582, 2369.2536850891915, 2400.543896659629, 2402.159244264511, 2414.155045733979, 8796.428232164953, 8908.287337013087, 8912.777180570472, 8914.955425222897, 8923.375363724543, 5254.632175747699, 5416.891662166735, 5418.431064738224, 5419.160030195287, 5421.457854214586, 3504.702100321476, 3634.509341238925, 3654.104744337628, 3694.8152592370275, 3697.242065376591, 1045.9372437653765, 1093.9835902461468, 1116.7319843237565, 1122.1079242002627, 1122.764219513903, 11587.304761714324, 11628.53485860573, 11641.01051410631, 11647.767044659144, 11653.533858645644, 4264.253755592821, 4441.311596702516, 4668.136394766979, 4813.831515896927, 4860.659753817199, 9955.0, 9956.551955162035, 9960.153386962045, 9964.451955142536, 9983.550740216708, 4700.835470758507, 5400.0, 5404.189371594292, 5411.756470958816, 5421.213923981, 4807.6394270429855, 4907.55831975124, 4915.21356582945, 4930.309756634074, 4936.062148191832, 3896.2207558488417, 3936.8622098226424, 3959.287328280937, 3996.720229583901, 4041.5854450941933, 1098.2477003252638, 1114.7547539541288, 1249.4877100388978, 1272.9045321600918, 1274.1399555300136, 836.3100442135141, 848.0, 878.4026861734083, 886.7472770260445, 939.4879790514186, 5782.88434231313, 5786.942130578725, 6004.909926351108, 6019.337872833985, 6175.59858609189, 902.2150728865929, 909.0364213933252, 911.9544022798833, 922.1654402765374, 937.1659223291276, 3936.4488971544006, 3989.3218152914073, 4068.6338229559483, 4086.182763447263, 4096.508418989741, 10612.151027917747, 10881.510332035077, 10933.18001149917, 10975.176861735401, 10988.835167472495, 10919.017288444078, 10930.836037459445, 10934.015938565568, 10941.398223097805, 10996.395198264172, 8126.268635822805, 8230.382721295926, 8230.42387032914, 8249.092599814016, 8261.793455163668, 5048.444671086251, 5569.860753481195, 5721.456461636138, 5849.795257165979, 5939.287285525592, 10072.546735396778, 10298.845517317395, 10304.175665946856, 10306.742331441774, 10325.74185645365, 8311.83376332458, 9875.40747555149, 10098.444585547866, 10189.84715229287, 10389.948050260004, 7494.887576686463, 7651.4261430391825, 7749.535027898344, 7767.0, 7787.377009839086, 8216.876746848922, 8337.583120844054, 8419.915800842158, 8443.746687599381, 8455.450395724258, 7510.624468776648, 7550.848983020419, 7568.243175682388, 7598.544087354777, 7621.0, 7127.75052873147, 7348.338649521569, 7369.594672293076, 7436.367908727602, 7532.246775322424, 6890.827729306607, 6997.930020699728, 7006.0, 7030.894536581853, 7033.929660703532, 4883.194272945067, 4963.975180124076, 5008.0, 5062.873428164178, 5247.842564722911, 5034.546890779848, 5122.129238029618, 5144.434112247724, 5168.960263742821, 5211.791528338824], "value": 4613.210408030815, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.61649089165866, 300.3467458278246, 307.60780005492995, 312.8435782108946, 315.54718978266203, 4480.7983640736165, 5229.398619158798, 5279.392869819972, 5332.280142180804, 5373.623846330758, 4199.979000104995, 4969.676970996883, 5142.177251639739, 5171.58675274319, 5319.510537049632, 3846.557645870733, 3966.8809935701975, 3968.3452230382027, 4003.359462486003, 4029.7985100744845, 3481.0949153220217, 4031.2340655275552, 4036.777977211252, 4117.938230926524, 4126.54607993122, 3902.5316961964463, 3989.7207195496326, 4030.0932290234755, 4038.1116154445976, 4059.416827437302, 3703.777773333609, 4043.1913617276664, 4060.614241647039, 4062.1875624874997, 4079.0, 2970.658374286963, 3096.643885953099, 3500.8949731508096, 3507.8772242971627, 3510.9279380542025, 4077.97961010196, 5043.915558154978, 5129.9483605860705, 5161.66449180802, 5165.199394093921, 4148.688848336386, 4193.496780386343, 4507.729536227836, 4566.817327306904, 4624.791884365211, 5380.91928621071, 5490.615656904019, 5530.668159910419, 5543.695096769676, 5573.665580065209, 3842.2911847147975, 3849.191669749356, 3854.8990800014058, 3868.516435445569, 3882.7282090253693, 5340.7716225268405, 5370.925814837048, 5619.775208991636, 5765.855853603652, 5821.476067153934, 3889.86385476507, 3894.299026175259, 3897.928069780833, 3901.49280593521, 3905.2621583395685, 3827.105582736563, 3970.861019864291, 4014.337634290368, 4016.6987475939422, 4040.939385909214, 3826.6556009959236, 4283.935740963888, 4531.18438681034, 4588.564086411852, 4627.0, 5024.874378140576, 5214.895702085937, 5520.889582208333, 5527.44725527444, 5590.776368945197, 1333.4731732166615, 1354.9729005419836, 1356.6947436826833, 1360.347033423962, 1368.9383977721027, 856.0113069404847, 892.6206362295998, 909.3316412436853, 923.5705396990297, 944.0842382888587, 5161.303224064778, 5177.6700484781995, 5182.352205974254, 5248.6588371755715, 5255.973720131375, 4473.696046536036, 4940.258961155855, 5045.571126454219, 5725.879395729255, 5747.3677895431465, 4354.368616550582, 4639.327297541838, 4677.2226553909295, 5188.948110518846, 5196.610254230948, 2788.2053614719707, 2867.938862620844, 2930.355321829194, 2938.2507460597412, 2938.8971386001813, 4445.622122119591, 4894.853154405302, 5772.740226689809, 5815.883682326415, 5843.152742852262, 5217.3999990000875, 5218.582513398919, 5895.675737834375, 5955.851103722355, 5960.0, 6020.729067191899, 6042.975603172898, 6051.152838602599, 6068.757249709962, 6080.513558915188, 5463.617546771688, 5694.544436444991, 5718.79984200551, 5718.942810571841, 5793.335065869579, 5413.7834486620095, 5722.340521248958, 5738.799142030009, 5784.2769653793275, 5796.232448908075, 5534.972325138348, 5761.769529218785, 5962.493188079062, 6006.699665016644, 6100.420460056288, 3559.323728491578, 3631.276542593055, 3803.23935212953, 3847.3267178243136, 3861.8841434757, 5661.490465858093, 5677.006523858391, 5686.82018716235, 5710.600257982068, 5727.6849773262875, 5349.234405100006, 5553.056879853684, 5574.358948720949, 5586.776528938797, 5588.944110559005, 5018.598512118949, 5064.012232782528, 5204.58363330925, 5237.847673511821, 5504.9449505504435, 4636.026434448669, 5234.0221431712935, 5394.703291319015, 5432.728363581884, 5452.591055670761, 5372.516473517246, 5550.280362622367, 5559.471850174227, 5584.329880414214, 5594.552435805207, 4613.630909527163, 5410.242566040679, 5454.318210223722, 5459.890802184013, 5476.342838859362], "value": 4074.433729825004, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.5989485992984, 713.051641317048, 760.52467207995, 802.4382931947638, 808.1958451340914, 15345.232738363078, 15943.0, 15950.840491595081, 15962.680746385066, 15973.0, 14126.57620271391, 14195.0, 14217.360218790154, 14249.430022799075, 14331.570052898403, 680.7923583307089, 696.6412297666706, 845.9238668519816, 847.3433089355768, 864.5029108262775, 846.4371193156544, 863.0420233540765, 864.0409145848104, 864.9740507784745, 865.1521508921261, 912.6531917871233, 912.8448163812168, 913.2830727878617, 914.8490499067645, 914.8947870994854, 714.5534041224234, 882.1795729971125, 905.814308066848, 906.7869050773063, 918.3020904112856, 593.5429719116256, 684.948628852838, 685.3009929871553, 685.5989246290926, 685.9725610975555, 14691.191984440851, 14719.484818031318, 14740.631484212874, 14757.55727328171, 14790.482333118396, 13702.0, 13939.303034848217, 14104.672728999703, 14155.221462819542, 14337.42650293987, 12576.559820406334, 12698.238105713686, 12914.741705165938, 13048.28234447105, 13432.529861454803, 4535.750533720644, 4574.977125114353, 4682.719036857799, 4761.28606694181, 4782.593479554207, 110587.0, 111753.0, 111963.99999999919, 112178.0, 113666.0, 11026.44867756609, 11049.392283424328, 11123.0, 11400.885991140149, 11532.59635912739, 22089.116435342727, 22331.441713956956, 22333.55332893333, 22359.776402236093, 22366.440838979157, 7875.684972601146, 7900.881486777703, 7920.5247685139075, 7944.682212711542, 8144.959275203704, 3735.6637902588895, 4401.691881568259, 4413.451939266643, 4476.910461790746, 4582.991741816795, 3379.459286514146, 3490.9527141857343, 3494.371013217645, 3498.2478767065204, 3498.965010349915, 541.7664677462554, 559.8355420724882, 637.4995628431751, 643.1092936283281, 643.5270076493773, 557.5618080082508, 594.9464548190597, 635.870024192861, 640.3532432243468, 653.1998302079915, 8622.741317760478, 8745.431546949556, 8913.910860891308, 8947.865782013276, 9107.681231156877, 535.9330083739533, 575.4159528079003, 584.3893131677407, 625.7121724006917, 633.4362417448475, 4331.696781225284, 4409.603135717801, 4448.332750087448, 4453.576910193527, 4513.1650644630845, 65694.0, 97964.51017745008, 98269.0, 98448.53878653092, 152007.0, 129560.0, 131339.34330328286, 133969.33015334862, 134122.0, 136457.0, 110670.44664776625, 113424.43287783508, 114639.42680286379, 116659.0, 116840.41579792046, 7242.891356629761, 8524.403291769517, 8529.0, 8571.357148213785, 8802.779930501789, 57648.71175644095, 64855.67572162109, 65570.67214663896, 66248.3437914056, 66992.0, 78096.60951695206, 96217.03782962081, 99328.0, 100193.0, 104525.0, 11609.419529023346, 11674.591389301333, 11790.174687771774, 11945.0, 12083.879161208275, 12190.329531875832, 12224.388780561114, 12321.691957701129, 12362.597573826391, 12370.319632420304, 10042.447665378475, 11793.469293881968, 11908.345041022827, 11992.820107698217, 12219.26684398939, 11788.174827762317, 11797.52809887595, 11864.406779661154, 12103.273803571814, 12447.066470014604, 11177.329360238411, 11216.495257713259, 11281.943590281997, 11290.266132701348, 11490.94254528722, 9649.710508684751, 9788.510574471391, 9904.851427228454, 10077.798444031225, 10164.136048435967, 8541.615627296911, 9876.061774131445, 9905.158061564847, 9921.751956201153, 10000.499975001367], "value": 7542.84398546153, "unit": "score"}], "name": "dromaeo_css", "value": 7248.819560370987, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 117.0, 114.0, 113.0, 113.0, 112.0, 113.0, 113.0, 124.0], "value": 114.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 116.0, 129.0, 165.0, 109.0, 132.0, 128.0, 111.0, 159.0, 108.0], "value": 130.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 187.0, 185.0, 184.0, 187.0, 187.0, 184.0, 182.0, 185.0, 183.0], "value": 185.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 79.0, 158.0, 58.0, 57.0, 58.0, 67.0, 149.0, 80.0], "value": 85.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 136.0, 69.0, 70.0, 76.0, 123.0, 68.0, 70.0, 117.0], "value": 89.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 85.0, 126.0, 124.0, 92.0, 88.0, 92.0, 88.0, 91.0, 88.0], "value": 96.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 97.0, 96.0, 96.0, 96.0, 96.0, 104.0, 141.0], "value": 101.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 85.0, 84.0, 89.0, 86.0, 88.0, 84.0, 84.0, 84.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 55.0, 55.0, 57.0, 55.0, 55.0, 56.0, 55.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 69.0, 109.0, 103.0, 93.0, 74.0, 70.0, 69.0, 70.0], "value": 78.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 102.0, 101.0, 77.0, 84.0, 77.0, 94.0, 102.0, 101.0, 87.0], "value": 91.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 150.0, 137.0, 139.0, 140.0, 141.0, 140.0, 139.0, 182.0, 153.0], "value": 146.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 164.0, 170.0, 167.0, 169.0, 171.0, 196.0, 222.0, 186.0, 171.0], "value": 178.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 65.0, 71.0, 83.0, 82.0, 82.0, 77.0, 66.0], "value": 72.7, "unit": "score"}], "name": "kraken", "value": 1511.6999999999998, "alertThreshold": 2.0}]} 10:00:56 INFO - Return code: 0 10:00:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:00:56 INFO - # TBPL SUCCESS # 10:00:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:00:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:00:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:00:56 INFO - 2016-09-03 10:00:56.719 screenresolution[2402:31026] starting screenresolution argv=screenresolution get 10:00:56 INFO - 2016-09-03 10:00:56.722 screenresolution[2402:31026] Display 0: 1600x1200x32@60 10:00:56 INFO - 2016-09-03 10:00:56.729 screenresolution[2403:31029] starting screenresolution argv=screenresolution list 10:00:56 INFO - Available Modes on Display 0 10:00:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:00:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:00:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:00:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:00:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:00:56 INFO - Intel Iris: 10:00:56 INFO - Chipset Model: Intel Iris 10:00:56 INFO - Type: GPU 10:00:56 INFO - Bus: Built-In 10:00:56 INFO - VRAM (Dynamic, Max): 1536 MB 10:00:56 INFO - Vendor: Intel (0x8086) 10:00:56 INFO - Device ID: 0x0a2e 10:00:56 INFO - Revision ID: 0x0009 10:00:56 INFO - Displays: 10:00:56 INFO - Display: 10:00:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:00:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:00:56 INFO - Display Serial Number: 1600x1200 60 10:00:56 INFO - Main Display: Yes 10:00:56 INFO - Mirror: Off 10:00:56 INFO - Online: Yes 10:00:56 INFO - Rotation: Supported 10:00:56 INFO - Return code: 0 10:00:56 INFO - Running post-action listener: _resource_record_post_action 10:00:56 INFO - [mozharness: 2016-09-03 17:00:56.964096Z] Finished run-tests step (success) 10:00:56 INFO - Running post-run listener: _resource_record_post_run 10:00:57 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 200704; Write bytes: 705360384; Read time: 1; Write time: 2584 10:00:57 INFO - TinderboxPrint: CPU usage
53.8% 10:00:57 INFO - TinderboxPrint: I/O read bytes / time
200,704 / 1 10:00:57 INFO - TinderboxPrint: I/O write bytes / time
705,360,384 / 2,584 10:00:57 INFO - TinderboxPrint: CPU idle
2,240.8 (46.2%) 10:00:57 INFO - TinderboxPrint: CPU system
976.8 (20.1%) 10:00:57 INFO - TinderboxPrint: CPU user
1,636.2 (33.7%) 10:00:57 INFO - TinderboxPrint: Swap in / out
404,992,000 / 0 10:00:57 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 397937152; Write bytes: 392570368; Read time: 30469; Write time: 662 10:00:57 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 200704; Write bytes: 296074240; Read time: 1; Write time: 1881 10:00:57 INFO - Running post-run listener: _upload_blobber_files 10:00:57 INFO - Blob upload gear active. 10:00:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:00:57 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:00:57 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:00:57 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:00:57 INFO - (blobuploader) - INFO - Open directory for files ... 10:00:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:00:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:00:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:00:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:00:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:00:58 INFO - (blobuploader) - INFO - Done attempting. 10:00:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:00:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:00:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:00:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:00:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:00:59 INFO - (blobuploader) - INFO - Done attempting. 10:00:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:00:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:00:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:01:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:01:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:01:01 INFO - (blobuploader) - INFO - Done attempting. 10:01:01 INFO - (blobuploader) - INFO - Iteration through files over. 10:01:01 INFO - Return code: 0 10:01:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:01:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:01:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9472d841e4c8c3bc48b0824f741e4b59d4028cef6bf8a2d7c3e94b46a51f6bc769789c86dbca0d3d54cb7494965516ff0350fa1f4813ab6bb8c1a513c0fc168d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e60ba0206943cbcfdd4cb181cca3def6b64fe1083e2af8e8e7e0203cbc592f6d72d0d8b0a4e30165d34cd1d9844890288dd430820be5eabadd2e1dcf67d07e2f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/15bd14baf23353e45f3437bb14a237574fde0c86b7a16a1e70b4b3145f5904faf011589eedaba2bb0daed13be72b4ff725fe518737fecc6c413d7fdfb097ca7f"} 10:01:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:01:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:01:01 INFO - Contents: 10:01:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9472d841e4c8c3bc48b0824f741e4b59d4028cef6bf8a2d7c3e94b46a51f6bc769789c86dbca0d3d54cb7494965516ff0350fa1f4813ab6bb8c1a513c0fc168d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e60ba0206943cbcfdd4cb181cca3def6b64fe1083e2af8e8e7e0203cbc592f6d72d0d8b0a4e30165d34cd1d9844890288dd430820be5eabadd2e1dcf67d07e2f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/15bd14baf23353e45f3437bb14a237574fde0c86b7a16a1e70b4b3145f5904faf011589eedaba2bb0daed13be72b4ff725fe518737fecc6c413d7fdfb097ca7f"} 10:01:01 INFO - Running post-run listener: copy_logs_to_upload_dir 10:01:01 INFO - Copying logs to upload dir... 10:01:01 INFO - mkdir: /builds/slave/test/build/upload/logs 10:01:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.928662 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-03 10:01:01.803548) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-03 10:01:01.809554) ========= 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.waQQu08FRQ/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.UQbqCRhUPc/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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/Fx-Team/sha512/9472d841e4c8c3bc48b0824f741e4b59d4028cef6bf8a2d7c3e94b46a51f6bc769789c86dbca0d3d54cb7494965516ff0350fa1f4813ab6bb8c1a513c0fc168d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e60ba0206943cbcfdd4cb181cca3def6b64fe1083e2af8e8e7e0203cbc592f6d72d0d8b0a4e30165d34cd1d9844890288dd430820be5eabadd2e1dcf67d07e2f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/15bd14baf23353e45f3437bb14a237574fde0c86b7a16a1e70b4b3145f5904faf011589eedaba2bb0daed13be72b4ff725fe518737fecc6c413d7fdfb097ca7f"} build_url:https://queue.taskcluster.net/v1/task/PGFUous9SA6YVGtP76ma4A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011500 build_url: 'https://queue.taskcluster.net/v1/task/PGFUous9SA6YVGtP76ma4A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9472d841e4c8c3bc48b0824f741e4b59d4028cef6bf8a2d7c3e94b46a51f6bc769789c86dbca0d3d54cb7494965516ff0350fa1f4813ab6bb8c1a513c0fc168d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e60ba0206943cbcfdd4cb181cca3def6b64fe1083e2af8e8e7e0203cbc592f6d72d0d8b0a4e30165d34cd1d9844890288dd430820be5eabadd2e1dcf67d07e2f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/15bd14baf23353e45f3437bb14a237574fde0c86b7a16a1e70b4b3145f5904faf011589eedaba2bb0daed13be72b4ff725fe518737fecc6c413d7fdfb097ca7f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-03 10:01:01.844531) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 10:01:01.844929) ========= 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.waQQu08FRQ/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.UQbqCRhUPc/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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.005265 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 10:01:01.894670) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-03 10:01:01.895129) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-03 10:01:01.895625) ========= ========= Total master_lag: 0.20 =========