builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0212 starttime: 1482162279.93 results: success (0) buildid: 20161219054038 builduid: cf2f127856cc4439a825f7154ef248c9 revision: 315219009f706d386fb26430e72eaa6e947bce1d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-19 07:44:39.934122) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-19 07:44:39.934567) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-19 07:44:39.934836) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-19 07:44:39.961057) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 07:44:39.961408) ========= 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.xf42QG4GRr/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.w6zUBv3xza/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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-12-19 07:44:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 56.9M=0s 2016-12-19 07:44:40 (56.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.115328 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 07:44:40.101776) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 07:44:40.102283) ========= 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.xf42QG4GRr/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.w6zUBv3xza/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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.026089 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 07:44:40.152875) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-19 07:44:40.153474) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 315219009f706d386fb26430e72eaa6e947bce1d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 315219009f706d386fb26430e72eaa6e947bce1d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xf42QG4GRr/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.w6zUBv3xza/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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-12-19 07:44:40,252 truncating revision to first 12 chars 2016-12-19 07:44:40,253 Setting DEBUG logging. 2016-12-19 07:44:40,253 attempt 1/10 2016-12-19 07:44:40,253 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/315219009f70?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-19 07:44:42,053 unpacking tar archive at: mozilla-aurora-315219009f70/testing/mozharness/ program finished with exit code 0 elapsedTime=2.081079 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-19 07:44:42.261644) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-19 07:44:42.261961) ========= script_repo_revision: 315219009f706d386fb26430e72eaa6e947bce1d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-19 07:44:42.262332) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-19 07:44:42.262607) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-19 07:44:42.282412) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-12-19 07:44:42.282710) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xf42QG4GRr/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.w6zUBv3xza/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 07:44:42 INFO - MultiFileLogger online at 20161219 07:44:42 in /builds/slave/test 07:44:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 07:44:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:44:42 INFO - {'append_to_log': False, 07:44:42 INFO - 'base_work_dir': '/builds/slave/test', 07:44:42 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:44:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:44:42 INFO - 'branch': 'Mozilla-Aurora', 07:44:42 INFO - 'buildbot_json_path': 'buildprops.json', 07:44:42 INFO - 'config_files': ('talos/mac_config.py',), 07:44:42 INFO - 'default_actions': ('clobber', 07:44:42 INFO - 'read-buildbot-config', 07:44:42 INFO - 'download-and-extract', 07:44:42 INFO - 'populate-webroot', 07:44:42 INFO - 'create-virtualenv', 07:44:42 INFO - 'install', 07:44:42 INFO - 'run-tests'), 07:44:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:44:42 INFO - 'download_minidump_stackwalk': True, 07:44:42 INFO - 'download_symbols': 'ondemand', 07:44:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:44:42 INFO - 'tooltool.py': '/tools/tooltool.py', 07:44:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:44:42 INFO - '/tools/misc-python/virtualenv.py')}, 07:44:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:44:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:44:42 INFO - 'installer_path': 'installer.exe', 07:44:42 INFO - 'log_level': 'info', 07:44:42 INFO - 'log_name': 'talos', 07:44:42 INFO - 'log_to_console': True, 07:44:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:44:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:44:42 INFO - 'opt_config_files': (), 07:44:42 INFO - 'pip_index': False, 07:44:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:44:42 INFO - 'cmd': ('bash', 07:44:42 INFO - '-c', 07:44:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:44:42 INFO - 'enabled': True, 07:44:42 INFO - 'halt_on_failure': False, 07:44:42 INFO - 'name': 'check_screen_resolution'},), 07:44:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:44:42 INFO - 'cmd': ('bash', 07:44:42 INFO - '-c', 07:44:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:44:42 INFO - 'enabled': True, 07:44:42 INFO - 'halt_on_failure': False, 07:44:42 INFO - 'name': 'check_screen_resolution'},), 07:44:42 INFO - 'run_cmd_checks_enabled': True, 07:44:42 INFO - 'sps_profile': False, 07:44:42 INFO - 'sps_profile_interval': 0, 07:44:42 INFO - 'suite': 'dromaeojs', 07:44:42 INFO - 'system_bits': '32', 07:44:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:44:42 INFO - 'title': 't-yosemite-r7-0212', 07:44:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:44:42 INFO - 'use_talos_json': True, 07:44:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:44:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:44:42 INFO - 'work_dir': 'build'} 07:44:42 INFO - [mozharness: 2016-12-19 15:44:42.488353Z] Running clobber step. 07:44:42 INFO - Running pre-action listener: _resource_record_pre_action 07:44:42 INFO - Running main action method: clobber 07:44:42 INFO - rmtree: /builds/slave/test/build 07:44:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:44:44 INFO - Running post-action listener: _resource_record_post_action 07:44:44 INFO - [mozharness: 2016-12-19 15:44:44.892866Z] Finished clobber step (success) 07:44:44 INFO - [mozharness: 2016-12-19 15:44:44.893034Z] Running read-buildbot-config step. 07:44:44 INFO - Running pre-action listener: _resource_record_pre_action 07:44:44 INFO - Running main action method: read_buildbot_config 07:44:44 INFO - Using buildbot properties: 07:44:44 INFO - { 07:44:44 INFO - "project": "", 07:44:44 INFO - "product": "firefox", 07:44:44 INFO - "script_repo_revision": "production", 07:44:44 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 07:44:44 INFO - "repository": "", 07:44:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 07:44:44 INFO - "buildid": "20161219054038", 07:44:44 INFO - "pgo_build": "False", 07:44:44 INFO - "basedir": "/builds/slave/test", 07:44:44 INFO - "buildnumber": 9, 07:44:44 INFO - "slavename": "t-yosemite-r7-0212", 07:44:44 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 07:44:44 INFO - "platform": "yosemite_r7", 07:44:44 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 07:44:44 INFO - "branch": "mozilla-aurora", 07:44:44 INFO - "repo_path": "releases/mozilla-aurora", 07:44:44 INFO - "revision": "315219009f706d386fb26430e72eaa6e947bce1d", 07:44:44 INFO - "stage_platform": "macosx64", 07:44:44 INFO - "builduid": "cf2f127856cc4439a825f7154ef248c9", 07:44:44 INFO - "slavebuilddir": "test" 07:44:44 INFO - } 07:44:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/FwRh5zyWRTuYXpJJ2MjuGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 07:44:44 INFO - Running post-action listener: _resource_record_post_action 07:44:44 INFO - [mozharness: 2016-12-19 15:44:44.898625Z] Finished read-buildbot-config step (success) 07:44:44 INFO - [mozharness: 2016-12-19 15:44:44.898779Z] Running download-and-extract step. 07:44:44 INFO - Running pre-action listener: _resource_record_pre_action 07:44:44 INFO - Running main action method: download_and_extract 07:44:44 INFO - mkdir: /builds/slave/test/build/tests 07:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:44:44 INFO - trying https://queue.taskcluster.net/v1/task/FwRh5zyWRTuYXpJJ2MjuGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 07:44:44 INFO - Downloading https://queue.taskcluster.net/v1/task/FwRh5zyWRTuYXpJJ2MjuGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 07:44:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FwRh5zyWRTuYXpJJ2MjuGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 07:44:45 INFO - Downloaded 1199 bytes. 07:44:45 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 07:44:45 INFO - Using the following test package requirements: 07:44:45 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 07:44:45 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:44:45 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 07:44:45 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:44:45 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 07:44:45 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:44:45 INFO - u'jsshell-mac.zip'], 07:44:45 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:44:45 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 07:44:45 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 07:44:45 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:44:45 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 07:44:45 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:44:45 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 07:44:45 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:44:45 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 07:44:45 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:44:45 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 07:44:45 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 07:44:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FwRh5zyWRTuYXpJJ2MjuGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 07:44:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FwRh5zyWRTuYXpJJ2MjuGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:44:45 INFO - Fetch https://queue.taskcluster.net/v1/task/FwRh5zyWRTuYXpJJ2MjuGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 07:44:46 INFO - Http code: 200 07:44:46 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 07:44:46 INFO - x-amz-cf-id: Z2Ld6DWYfz6g6--wTaaeg_Rk1VwSaFbxiqKdF8_r3fJ8TtX-JbK91w== 07:44:46 INFO - x-amz-version-id: 9W1OnHZk2sqC77oOW84j6s2V3ttU9Xgd 07:44:46 INFO - Expected file size: 16267328 07:44:46 INFO - Obtained file size: 16267328 07:44:53 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 07:44:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FwRh5zyWRTuYXpJJ2MjuGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 07:44:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FwRh5zyWRTuYXpJJ2MjuGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:44:53 INFO - Fetch https://queue.taskcluster.net/v1/task/FwRh5zyWRTuYXpJJ2MjuGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 07:44:53 INFO - Http code: 200 07:44:53 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 07:44:53 INFO - x-amz-cf-id: XQO_fZl0tiaZ32DVT0bQxpW6Sx1IAmlbBGbPSQvuxt45Hi1CqT3liw== 07:44:53 INFO - x-amz-version-id: 9W1OnHZk2sqC77oOW84j6s2V3ttU9Xgd 07:44:54 INFO - Expected file size: 16267328 07:44:54 INFO - Obtained file size: 16267328 07:45:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FwRh5zyWRTuYXpJJ2MjuGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 07:45:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FwRh5zyWRTuYXpJJ2MjuGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 07:45:00 INFO - Fetch https://queue.taskcluster.net/v1/task/FwRh5zyWRTuYXpJJ2MjuGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 07:45:01 INFO - Http code: 200 07:45:01 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront) 07:45:01 INFO - x-amz-cf-id: FI0D6kSeMB8BV29NcJ1uswuz37AvzBcX-NW4OOBMHHjYexb_LE3I7A== 07:45:01 INFO - x-amz-version-id: KFulLQmdJjtJeuQX5SnnRRZEYL97b7kY 07:45:01 INFO - Expected file size: 14409038 07:45:01 INFO - Obtained file size: 14409038 07:45:01 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')]} 07:45:01 INFO - trying https://queue.taskcluster.net/v1/task/FwRh5zyWRTuYXpJJ2MjuGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:45:01 INFO - Downloading https://queue.taskcluster.net/v1/task/FwRh5zyWRTuYXpJJ2MjuGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 07:45:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FwRh5zyWRTuYXpJJ2MjuGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 07:45:04 INFO - Downloaded 109681032 bytes. 07:45:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FwRh5zyWRTuYXpJJ2MjuGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:45:04 INFO - mkdir: /builds/slave/test/properties 07:45:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:45:04 INFO - Writing to file /builds/slave/test/properties/build_url 07:45:04 INFO - Contents: 07:45:04 INFO - build_url:https://queue.taskcluster.net/v1/task/FwRh5zyWRTuYXpJJ2MjuGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:45:05 INFO - Running post-action listener: _resource_record_post_action 07:45:05 INFO - Running post-action listener: set_extra_try_arguments 07:45:05 INFO - [mozharness: 2016-12-19 15:45:05.485942Z] Finished download-and-extract step (success) 07:45:05 INFO - [mozharness: 2016-12-19 15:45:05.486068Z] Running populate-webroot step. 07:45:05 INFO - Running pre-action listener: _resource_record_pre_action 07:45:05 INFO - Running main action method: populate_webroot 07:45:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:45:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:45:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:45:05 INFO - '--tppagecycles', 07:45:05 INFO - '7'], 07:45:05 INFO - 'tests': ['tsvgm']}}, 07:45:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:45:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:45:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:45:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:45:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:45:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:45:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:45:05 INFO - 'talos_options': ['--disable-e10s'], 07:45:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:45:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:45:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:45:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:45:05 INFO - 'talos_options': ['--disable-e10s'], 07:45:05 INFO - 'tests': ['damp', 'tps']}, 07:45:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:45:05 INFO - 'tests': ['damp', 'tps']}, 07:45:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:45:05 INFO - 'tests': ['dromaeo_dom']}, 07:45:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:45:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:45:05 INFO - 'tests': ['basic_compositor_video']}, 07:45:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:45:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:45:05 INFO - 'tests': ['a11yr', 07:45:05 INFO - 'ts_paint', 07:45:05 INFO - 'tpaint', 07:45:05 INFO - 'sessionrestore', 07:45:05 INFO - 'sessionrestore_no_auto_restore', 07:45:05 INFO - 'tabpaint']}, 07:45:05 INFO - 'other-e10s': {'tests': ['a11yr', 07:45:05 INFO - 'ts_paint', 07:45:05 INFO - 'tpaint', 07:45:05 INFO - 'sessionrestore', 07:45:05 INFO - 'sessionrestore_no_auto_restore', 07:45:05 INFO - 'tabpaint']}, 07:45:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:45:05 INFO - 'ts_paint', 07:45:05 INFO - 'tpaint', 07:45:05 INFO - 'sessionrestore', 07:45:05 INFO - 'sessionrestore_no_auto_restore', 07:45:05 INFO - 'tabpaint']}, 07:45:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:45:05 INFO - 'ts_paint', 07:45:05 INFO - 'tpaint', 07:45:05 INFO - 'sessionrestore', 07:45:05 INFO - 'sessionrestore_no_auto_restore', 07:45:05 INFO - 'tabpaint']}, 07:45:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:45:05 INFO - 'tests': ['a11yr', 07:45:05 INFO - 'ts_paint', 07:45:05 INFO - 'tpaint', 07:45:05 INFO - 'sessionrestore', 07:45:05 INFO - 'sessionrestore_no_auto_restore', 07:45:05 INFO - 'tabpaint']}, 07:45:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:45:05 INFO - 'tests': ['a11yr', 07:45:05 INFO - 'ts_paint', 07:45:05 INFO - 'tpaint', 07:45:05 INFO - 'sessionrestore', 07:45:05 INFO - 'sessionrestore_no_auto_restore', 07:45:05 INFO - 'tabpaint']}, 07:45:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:45:05 INFO - 'tests': ['tsvgx', 07:45:05 INFO - 'tsvgr_opacity', 07:45:05 INFO - 'tart', 07:45:05 INFO - 'tscrollx', 07:45:05 INFO - 'cart']}, 07:45:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:45:05 INFO - 'tsvgr_opacity', 07:45:05 INFO - 'tart', 07:45:05 INFO - 'tscrollx', 07:45:05 INFO - 'cart']}, 07:45:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:45:05 INFO - 'talos_options': ['--disable-e10s'], 07:45:05 INFO - 'tests': ['tp5o']}, 07:45:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:45:05 INFO - 'tests': ['tp5o']}, 07:45:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:45:05 INFO - 'talos_options': ['--disable-e10s', 07:45:05 INFO - '--xperf_path', 07:45:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:45:05 INFO - 'tests': ['tp5n']}, 07:45:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:45:05 INFO - 'talos_options': ['--xperf_path', 07:45:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:45:05 INFO - 'tests': ['tp5n']}}, 07:45:05 INFO - 'talos.zip': {'path': '', 07:45:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:45:05 INFO - Running post-action listener: _resource_record_post_action 07:45:05 INFO - [mozharness: 2016-12-19 15:45:05.504390Z] Finished populate-webroot step (success) 07:45:05 INFO - [mozharness: 2016-12-19 15:45:05.504501Z] Running create-virtualenv step. 07:45:05 INFO - Running pre-action listener: _resource_record_pre_action 07:45:05 INFO - Running main action method: create_virtualenv 07:45:05 INFO - Creating virtualenv /builds/slave/test/build/venv 07:45:05 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 07:45:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:45:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:45:05 INFO - Using real prefix '/tools/python27' 07:45:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:45:06 INFO - Installing distribute.............................................................................................................................................................................................done. 07:45:09 INFO - Installing pip.................done. 07:45:09 INFO - Return code: 0 07:45:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:45:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:45:09 INFO - Reading from file tmpfile_stdout 07:45:09 INFO - Output received: 07:45:09 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 07:45:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:45: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')]} 07:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45: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')]} 07:45:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107b10490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a86570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd67bd8d6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107924d30>, '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 0x10778adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10791cb20>, '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.xf42QG4GRr/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.w6zUBv3xza/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 07:45:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:45:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:45:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xf42QG4GRr/Render', 07:45:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:09 INFO - 'HOME': '/Users/cltbld', 07:45:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:09 INFO - 'LOGNAME': 'cltbld', 07:45:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:09 INFO - 'MOZ_NO_REMOTE': '1', 07:45:09 INFO - 'NO_EM_RESTART': '1', 07:45:09 INFO - 'PAGER': '/bin/cat', 07:45:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:09 INFO - 'PWD': '/builds/slave/test', 07:45:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:09 INFO - 'SHELL': '/bin/bash', 07:45:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w6zUBv3xza/Listeners', 07:45:09 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 07:45:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:09 INFO - 'USER': 'cltbld', 07:45:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:09 INFO - 'XPC_FLAGS': '0x0', 07:45:09 INFO - 'XPC_SERVICE_NAME': '0', 07:45:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:09 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 07:45:09 INFO - Cleaning up... 07:45:09 INFO - Return code: 0 07:45:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:45: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')]} 07:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45: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')]} 07:45:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107b10490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a86570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd67bd8d6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107924d30>, '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 0x10778adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10791cb20>, '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.xf42QG4GRr/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.w6zUBv3xza/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 07:45:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:45:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:45:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xf42QG4GRr/Render', 07:45:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:09 INFO - 'HOME': '/Users/cltbld', 07:45:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:09 INFO - 'LOGNAME': 'cltbld', 07:45:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:09 INFO - 'MOZ_NO_REMOTE': '1', 07:45:09 INFO - 'NO_EM_RESTART': '1', 07:45:09 INFO - 'PAGER': '/bin/cat', 07:45:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:09 INFO - 'PWD': '/builds/slave/test', 07:45:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:09 INFO - 'SHELL': '/bin/bash', 07:45:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w6zUBv3xza/Listeners', 07:45:09 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 07:45:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:09 INFO - 'USER': 'cltbld', 07:45:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:09 INFO - 'XPC_FLAGS': '0x0', 07:45:09 INFO - 'XPC_SERVICE_NAME': '0', 07:45:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:09 INFO - Downloading/unpacking psutil>=3.1.1 07:45:09 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 07:45:09 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 07:45:09 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 07:45:09 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 07:45:09 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 07:45:09 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 07:45:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:45:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:45:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:45:13 INFO - Installing collected packages: psutil 07:45:13 INFO - Running setup.py install for psutil 07:45:13 INFO - building 'psutil._psutil_osx' extension 07:45:13 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 07:45:14 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 07:45:14 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 07:45:14 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 07:45:14 INFO - building 'psutil._psutil_posix' extension 07:45:14 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 07:45:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:45:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:45:14 INFO - ^ 07:45:14 INFO - 1 warning generated. 07:45:14 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 07:45:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:45:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:45:14 INFO - Successfully installed psutil 07:45:14 INFO - Cleaning up... 07:45:14 INFO - Return code: 0 07:45:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:45:14 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')]} 07:45:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45:14 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')]} 07:45:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45:14 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 0x107b10490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a86570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd67bd8d6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107924d30>, '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 0x10778adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10791cb20>, '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.xf42QG4GRr/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.w6zUBv3xza/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 07:45:14 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 07:45:14 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 07:45:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xf42QG4GRr/Render', 07:45:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:14 INFO - 'HOME': '/Users/cltbld', 07:45:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:14 INFO - 'LOGNAME': 'cltbld', 07:45:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:14 INFO - 'MOZ_NO_REMOTE': '1', 07:45:14 INFO - 'NO_EM_RESTART': '1', 07:45:14 INFO - 'PAGER': '/bin/cat', 07:45:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:14 INFO - 'PWD': '/builds/slave/test', 07:45:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:14 INFO - 'SHELL': '/bin/bash', 07:45:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w6zUBv3xza/Listeners', 07:45:14 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 07:45:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:14 INFO - 'USER': 'cltbld', 07:45:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:14 INFO - 'XPC_FLAGS': '0x0', 07:45:14 INFO - 'XPC_SERVICE_NAME': '0', 07:45:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:45:14 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 07:45:14 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 07:45:14 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 07:45:14 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 07:45:14 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 07:45:14 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 07:45:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:45:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:45:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:45:17 INFO - Installing collected packages: mozsystemmonitor 07:45:17 INFO - Running setup.py install for mozsystemmonitor 07:45:17 INFO - Successfully installed mozsystemmonitor 07:45:17 INFO - Cleaning up... 07:45:17 INFO - Return code: 0 07:45:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:45:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107b10490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a86570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd67bd8d6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107924d30>, '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 0x10778adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10791cb20>, '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.xf42QG4GRr/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.w6zUBv3xza/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 07:45:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:45:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:45:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xf42QG4GRr/Render', 07:45:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:17 INFO - 'HOME': '/Users/cltbld', 07:45:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:17 INFO - 'LOGNAME': 'cltbld', 07:45:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:17 INFO - 'MOZ_NO_REMOTE': '1', 07:45:17 INFO - 'NO_EM_RESTART': '1', 07:45:17 INFO - 'PAGER': '/bin/cat', 07:45:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:17 INFO - 'PWD': '/builds/slave/test', 07:45:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:17 INFO - 'SHELL': '/bin/bash', 07:45:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w6zUBv3xza/Listeners', 07:45:17 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 07:45:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:17 INFO - 'USER': 'cltbld', 07:45:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:17 INFO - 'XPC_FLAGS': '0x0', 07:45:17 INFO - 'XPC_SERVICE_NAME': '0', 07:45:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:18 INFO - Downloading/unpacking jsonschema==2.5.1 07:45:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:45:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:45:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:45:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:45:20 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 07:45:20 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 07:45:20 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 07:45:20 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 07:45:20 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 07:45:20 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 07:45:21 INFO - Downloading functools32-3.2.3-2.tar.gz 07:45:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:45:21 INFO - warning: no files found matching '*.txt' 07:45:21 INFO - no previously-included directories found matching 'build' 07:45:21 INFO - no previously-included directories found matching 'dist' 07:45:21 INFO - no previously-included directories found matching '.git*' 07:45:21 INFO - Installing collected packages: jsonschema, functools32 07:45:21 INFO - Running setup.py install for functools32 07:45:21 INFO - warning: no files found matching '*.txt' 07:45:21 INFO - no previously-included directories found matching 'build' 07:45:21 INFO - no previously-included directories found matching 'dist' 07:45:21 INFO - no previously-included directories found matching '.git*' 07:45:21 INFO - Successfully installed jsonschema functools32 07:45:21 INFO - Cleaning up... 07:45:21 INFO - Return code: 0 07:45:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:45: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')]} 07:45:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45: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')]} 07:45:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45: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', '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 0x107b10490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a86570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd67bd8d6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107924d30>, '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 0x10778adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10791cb20>, '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.xf42QG4GRr/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.w6zUBv3xza/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 07:45: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:45: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 functools32==3.2.3-2 07:45:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xf42QG4GRr/Render', 07:45:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:21 INFO - 'HOME': '/Users/cltbld', 07:45:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:21 INFO - 'LOGNAME': 'cltbld', 07:45:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:21 INFO - 'MOZ_NO_REMOTE': '1', 07:45:21 INFO - 'NO_EM_RESTART': '1', 07:45:21 INFO - 'PAGER': '/bin/cat', 07:45:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:21 INFO - 'PWD': '/builds/slave/test', 07:45:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:21 INFO - 'SHELL': '/bin/bash', 07:45:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w6zUBv3xza/Listeners', 07:45:21 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 07:45:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:21 INFO - 'USER': 'cltbld', 07:45:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:21 INFO - 'XPC_FLAGS': '0x0', 07:45:21 INFO - 'XPC_SERVICE_NAME': '0', 07:45:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:45:21 INFO - Cleaning up... 07:45:21 INFO - Return code: 0 07:45:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:45: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')]} 07:45:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45: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')]} 07:45:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45: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', '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 0x107b10490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a86570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd67bd8d6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107924d30>, '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 0x10778adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10791cb20>, '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.xf42QG4GRr/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.w6zUBv3xza/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 07:45: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:45: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 blobuploader==1.2.4 07:45:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xf42QG4GRr/Render', 07:45:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:21 INFO - 'HOME': '/Users/cltbld', 07:45:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:21 INFO - 'LOGNAME': 'cltbld', 07:45:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:21 INFO - 'MOZ_NO_REMOTE': '1', 07:45:21 INFO - 'NO_EM_RESTART': '1', 07:45:21 INFO - 'PAGER': '/bin/cat', 07:45:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:21 INFO - 'PWD': '/builds/slave/test', 07:45:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:21 INFO - 'SHELL': '/bin/bash', 07:45:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w6zUBv3xza/Listeners', 07:45:21 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 07:45:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:21 INFO - 'USER': 'cltbld', 07:45:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:21 INFO - 'XPC_FLAGS': '0x0', 07:45:21 INFO - 'XPC_SERVICE_NAME': '0', 07:45:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:22 INFO - Downloading/unpacking blobuploader==1.2.4 07:45:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:45:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:45:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:24 INFO - Downloading blobuploader-1.2.4.tar.gz 07:45:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:45:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:45: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 07:45: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 07:45: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 07:45: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 07:45: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 07:45: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 07:45:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:45:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:45:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:45:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:45:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:25 INFO - Downloading docopt-0.6.1.tar.gz 07:45:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:45:25 INFO - Installing collected packages: blobuploader, requests, docopt 07:45:25 INFO - Running setup.py install for blobuploader 07:45:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:45:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:45:26 INFO - Running setup.py install for requests 07:45:26 INFO - Running setup.py install for docopt 07:45:26 INFO - Successfully installed blobuploader requests docopt 07:45:26 INFO - Cleaning up... 07:45:26 INFO - Return code: 0 07:45:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:45:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45:26 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 0x107b10490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a86570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd67bd8d6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107924d30>, '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 0x10778adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10791cb20>, '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.xf42QG4GRr/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.w6zUBv3xza/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 07:45:26 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 07:45:26 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 07:45:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xf42QG4GRr/Render', 07:45:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:26 INFO - 'HOME': '/Users/cltbld', 07:45:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:26 INFO - 'LOGNAME': 'cltbld', 07:45:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:26 INFO - 'MOZ_NO_REMOTE': '1', 07:45:26 INFO - 'NO_EM_RESTART': '1', 07:45:26 INFO - 'PAGER': '/bin/cat', 07:45:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:26 INFO - 'PWD': '/builds/slave/test', 07:45:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:26 INFO - 'SHELL': '/bin/bash', 07:45:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w6zUBv3xza/Listeners', 07:45:26 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 07:45:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:26 INFO - 'USER': 'cltbld', 07:45:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:26 INFO - 'XPC_FLAGS': '0x0', 07:45:26 INFO - 'XPC_SERVICE_NAME': '0', 07:45:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:45:27 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-Q23up0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:45:27 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-Wd6e92-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:45:27 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-7Xs1NE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:45:27 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-nj7n36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:45:27 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-VDYN3t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:45:27 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-kynoEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:45:27 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-EzowVv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:45:27 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-46ctjP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:45:27 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-1bTybd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:45:28 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-3c8ZHd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:45:28 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-R5ZdPq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:45:28 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-lySCod-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:45:28 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-AI1Z2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:45:28 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-4CodBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:45:28 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-7ESLrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:45:28 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-fcnf3D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:45:28 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-t8dC_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:45:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:45:28 INFO - Running setup.py install for manifestparser 07:45:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:45:28 INFO - Running setup.py install for mozcrash 07:45:29 INFO - Running setup.py install for mozdebug 07:45:29 INFO - Running setup.py install for mozdevice 07:45:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:45:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:45:29 INFO - Running setup.py install for mozfile 07:45:29 INFO - Running setup.py install for mozhttpd 07:45:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:45:29 INFO - Running setup.py install for mozinfo 07:45:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:45:29 INFO - Running setup.py install for mozInstall 07:45:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:45:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:45:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:45:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:45:29 INFO - Running setup.py install for mozleak 07:45:30 INFO - Running setup.py install for mozlog 07:45:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:45:30 INFO - Running setup.py install for moznetwork 07:45:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:45:30 INFO - Running setup.py install for mozprocess 07:45:30 INFO - Running setup.py install for mozprofile 07:45:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:45:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:45:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:45:30 INFO - Running setup.py install for mozrunner 07:45:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:45:30 INFO - Running setup.py install for mozscreenshot 07:45:31 INFO - Running setup.py install for moztest 07:45:31 INFO - Running setup.py install for mozversion 07:45:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:45:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:45:31 INFO - Cleaning up... 07:45:31 INFO - Return code: 0 07:45:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:45:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45:31 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 0x107b10490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a86570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd67bd8d6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107924d30>, '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 0x10778adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10791cb20>, '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.xf42QG4GRr/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.w6zUBv3xza/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 07:45:31 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 07:45:31 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 07:45:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xf42QG4GRr/Render', 07:45:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:31 INFO - 'HOME': '/Users/cltbld', 07:45:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:31 INFO - 'LOGNAME': 'cltbld', 07:45:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:31 INFO - 'MOZ_NO_REMOTE': '1', 07:45:31 INFO - 'NO_EM_RESTART': '1', 07:45:31 INFO - 'PAGER': '/bin/cat', 07:45:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:31 INFO - 'PWD': '/builds/slave/test', 07:45:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:31 INFO - 'SHELL': '/bin/bash', 07:45:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w6zUBv3xza/Listeners', 07:45:31 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 07:45:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:31 INFO - 'USER': 'cltbld', 07:45:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:31 INFO - 'XPC_FLAGS': '0x0', 07:45:31 INFO - 'XPC_SERVICE_NAME': '0', 07:45:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:45:31 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-zbqLJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:45:31 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)) 07:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:45:31 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-B4O9u8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:45:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:45:31 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-fvJ4PA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:45:31 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)) 07:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:45:31 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-3Ku68L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:45:32 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)) 07:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:45:32 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-JyiLda-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:45:32 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)) 07:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:45:32 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-VHBDqc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:45:32 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)) 07:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:45:32 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-_w_jVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:45:32 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)) 07:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:45:32 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-yI3gEL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:45:32 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)) 07:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:45:32 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-jLL7rO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:45:32 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)) 07:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:45:32 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-d5AMMW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:45:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:45:32 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-45QwSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:45:32 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)) 07:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:45:32 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-6ZtKV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:45:32 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)) 07:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:45:32 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-bXLTSc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:45:33 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)) 07:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:45:33 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-_IwRK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:45:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:45:33 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-cON7u1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:45:33 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)) 07:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:45:33 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-7r3Nk1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:45:33 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)) 07:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:45:33 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-Q5J2zA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:45:33 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)) 07:45:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:45:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:45:33 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)) 07:45:33 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)) 07:45:33 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)) 07:45:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:45:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:45:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:45:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:36 INFO - Downloading blessings-1.6.tar.gz 07:45:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:45:36 INFO - Installing collected packages: blessings 07:45:36 INFO - Running setup.py install for blessings 07:45:36 INFO - Successfully installed blessings 07:45:36 INFO - Cleaning up... 07:45:36 INFO - Return code: 0 07:45:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:45:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:45:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:45:37 INFO - Reading from file tmpfile_stdout 07:45:37 INFO - Current package versions: 07:45:37 INFO - blessings == 1.6 07:45:37 INFO - blobuploader == 1.2.4 07:45:37 INFO - docopt == 0.6.1 07:45:37 INFO - functools32 == 3.2.3-2 07:45:37 INFO - jsonschema == 2.5.1 07:45:37 INFO - manifestparser == 1.1 07:45:37 INFO - mozInstall == 1.12 07:45:37 INFO - mozcrash == 1.0 07:45:37 INFO - mozdebug == 0.1 07:45:37 INFO - mozdevice == 0.48 07:45:37 INFO - mozfile == 1.2 07:45:37 INFO - mozhttpd == 0.7 07:45:37 INFO - mozinfo == 0.9 07:45:37 INFO - mozleak == 0.1 07:45:37 INFO - mozlog == 3.4 07:45:37 INFO - moznetwork == 0.27 07:45:37 INFO - mozprocess == 0.23 07:45:37 INFO - mozprofile == 0.28 07:45:37 INFO - mozrunner == 6.13 07:45:37 INFO - mozscreenshot == 0.1 07:45:37 INFO - mozsystemmonitor == 0.3 07:45:37 INFO - moztest == 0.7 07:45:37 INFO - mozversion == 1.4 07:45:37 INFO - psutil == 3.1.1 07:45:37 INFO - requests == 1.2.3 07:45:37 INFO - wsgiref == 0.1.2 07:45:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:45: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')]} 07:45:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45: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')]} 07:45:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45:37 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 0x107b10490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a86570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd67bd8d6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107924d30>, '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 0x10778adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10791cb20>, '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.xf42QG4GRr/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.w6zUBv3xza/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 07:45:37 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 07:45:37 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 07:45:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xf42QG4GRr/Render', 07:45:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:37 INFO - 'HOME': '/Users/cltbld', 07:45:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:37 INFO - 'LOGNAME': 'cltbld', 07:45:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:37 INFO - 'MOZ_NO_REMOTE': '1', 07:45:37 INFO - 'NO_EM_RESTART': '1', 07:45:37 INFO - 'PAGER': '/bin/cat', 07:45:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:37 INFO - 'PWD': '/builds/slave/test', 07:45:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:37 INFO - 'SHELL': '/bin/bash', 07:45:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w6zUBv3xza/Listeners', 07:45:37 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 07:45:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:37 INFO - 'USER': 'cltbld', 07:45:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:37 INFO - 'XPC_FLAGS': '0x0', 07:45:37 INFO - 'XPC_SERVICE_NAME': '0', 07:45:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:37 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)) 07:45:37 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)) 07:45:37 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)) 07:45:37 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)) 07:45:37 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)) 07:45:37 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)) 07:45:37 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)) 07:45:37 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)) 07:45:37 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)) 07:45:37 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)) 07:45:37 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)) 07:45:37 INFO - Cleaning up... 07:45:37 INFO - Return code: 0 07:45:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:45: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')]} 07:45:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45: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')]} 07:45:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45:37 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 0x107b10490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a86570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd67bd8d6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107924d30>, '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 0x10778adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10791cb20>, '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.xf42QG4GRr/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.w6zUBv3xza/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 07:45:37 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 07:45:37 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 07:45:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xf42QG4GRr/Render', 07:45:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:37 INFO - 'HOME': '/Users/cltbld', 07:45:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:37 INFO - 'LOGNAME': 'cltbld', 07:45:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:37 INFO - 'MOZ_NO_REMOTE': '1', 07:45:37 INFO - 'NO_EM_RESTART': '1', 07:45:37 INFO - 'PAGER': '/bin/cat', 07:45:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:37 INFO - 'PWD': '/builds/slave/test', 07:45:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:37 INFO - 'SHELL': '/bin/bash', 07:45:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w6zUBv3xza/Listeners', 07:45:37 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 07:45:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:37 INFO - 'USER': 'cltbld', 07:45:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:37 INFO - 'XPC_FLAGS': '0x0', 07:45:37 INFO - 'XPC_SERVICE_NAME': '0', 07:45:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:45:37 INFO - Cleaning up... 07:45:37 INFO - Return code: 0 07:45:37 INFO - Running post-action listener: _resource_record_post_action 07:45:37 INFO - Running post-action listener: _start_resource_monitoring 07:45:37 INFO - Starting resource monitoring. 07:45:37 INFO - [mozharness: 2016-12-19 15:45:37.992114Z] Finished create-virtualenv step (success) 07:45:37 INFO - [mozharness: 2016-12-19 15:45:37.993106Z] Running install step. 07:45:37 INFO - Running pre-action listener: _resource_record_pre_action 07:45:37 INFO - Running main action method: install 07:45:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:45:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:45:38 INFO - Reading from file tmpfile_stdout 07:45:38 INFO - Detecting whether we're running mozinstall >=1.0... 07:45:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:45:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:45:38 INFO - Reading from file tmpfile_stdout 07:45:38 INFO - Output received: 07:45:38 INFO - Usage: mozinstall [options] installer 07:45:38 INFO - Options: 07:45:38 INFO - -h, --help show this help message and exit 07:45:38 INFO - -d DEST, --destination=DEST 07:45:38 INFO - Directory to install application into. [default: 07:45:38 INFO - "/builds/slave/test"] 07:45:38 INFO - --app=APP Application being installed. [default: firefox] 07:45:38 INFO - mkdir: /builds/slave/test/build/application 07:45:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:45:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 07:46:09 INFO - Reading from file tmpfile_stdout 07:46:09 INFO - Output received: 07:46:09 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:46:09 INFO - Running post-action listener: _resource_record_post_action 07:46:09 INFO - [mozharness: 2016-12-19 15:46:09.380176Z] Finished install step (success) 07:46:09 INFO - [mozharness: 2016-12-19 15:46:09.380299Z] Running run-tests step. 07:46:09 INFO - Running pre-action listener: _resource_record_pre_action 07:46:09 INFO - Running main action method: run_tests 07:46:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:46:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:46:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:46:09 INFO - 2016-12-19 07:46:09.392 screenresolution[1622:7647] starting screenresolution argv=screenresolution get 07:46:09 INFO - 2016-12-19 07:46:09.395 screenresolution[1622:7647] Display 0: 1600x1200x32@60 07:46:09 INFO - 2016-12-19 07:46:09.400 screenresolution[1623:7650] starting screenresolution argv=screenresolution list 07:46:09 INFO - Available Modes on Display 0 07:46:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:46:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:46:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:46:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:46:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:46:09 INFO - Intel Iris: 07:46:09 INFO - Chipset Model: Intel Iris 07:46:09 INFO - Type: GPU 07:46:09 INFO - Bus: Built-In 07:46:09 INFO - VRAM (Dynamic, Max): 1536 MB 07:46:09 INFO - Vendor: Intel (0x8086) 07:46:09 INFO - Device ID: 0x0a2e 07:46:09 INFO - Revision ID: 0x0009 07:46:09 INFO - Displays: 07:46:09 INFO - Display: 07:46:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:46:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:46:09 INFO - Display Serial Number: 1600x1200 60 07:46:09 INFO - Main Display: Yes 07:46:09 INFO - Mirror: Off 07:46:09 INFO - Online: Yes 07:46:09 INFO - Rotation: Supported 07:46:09 INFO - Return code: 0 07:46:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:46:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:46:09 INFO - Python 2.7.3 07:46:09 INFO - Return code: 0 07:46:09 INFO - grabbing minidump binary from tooltool 07:46: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')]} 07:46:09 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 0x107924d30>, '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 0x10778adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10791cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:46:09 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 07:46:09 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 07:46:09 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 07:46:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:46:09 INFO - Return code: 0 07:46:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:46:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:46:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:46:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:46:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:46:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FwRh5zyWRTuYXpJJ2MjuGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0212', '--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 07:46:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FwRh5zyWRTuYXpJJ2MjuGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0212 --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 07:46:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xf42QG4GRr/Render', 07:46:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:46:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:46:09 INFO - 'HOME': '/Users/cltbld', 07:46:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:46:09 INFO - 'LOGNAME': 'cltbld', 07:46:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:46:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:46:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:09 INFO - 'MOZ_NO_REMOTE': '1', 07:46:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:46:09 INFO - 'NO_EM_RESTART': '1', 07:46:09 INFO - 'PAGER': '/bin/cat', 07:46:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:46:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:46:09 INFO - 'PWD': '/builds/slave/test', 07:46:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:46:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:46:09 INFO - 'SHELL': '/bin/bash', 07:46:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w6zUBv3xza/Listeners', 07:46:09 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 07:46:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:46:09 INFO - 'USER': 'cltbld', 07:46:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:46:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:46:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:09 INFO - 'XPC_FLAGS': '0x0', 07:46:09 INFO - 'XPC_SERVICE_NAME': '0', 07:46:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:46:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FwRh5zyWRTuYXpJJ2MjuGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0212', '--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 07:46:09 INFO - mozversion application_buildid: 20161219054038 07:46:09 INFO - mozversion application_changeset: 315219009f706d386fb26430e72eaa6e947bce1d 07:46:09 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:46:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:46:09 INFO - mozversion application_name: Firefox 07:46:09 INFO - mozversion application_remotingname: firefox-dev 07:46:09 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:46:09 INFO - mozversion application_vendor: Mozilla 07:46:09 INFO - mozversion application_version: 52.0a2 07:46:09 INFO - mozversion platform_buildid: 20161219054038 07:46:09 INFO - mozversion platform_changeset: 315219009f706d386fb26430e72eaa6e947bce1d 07:46:09 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:46:09 INFO - mozversion platform_version: 52.0a2 07:46:09 INFO - using testdate: 1482162369 07:46:09 INFO - actual date: 1482162369 07:46:09 INFO - starting webserver on 'localhost:49247' 07:46:09 INFO - SUITE-START | Running 2 tests 07:46:09 INFO - TEST-START | dromaeo_css 07:46:09 INFO - operating with platform_type : mac_ 07:46:09 INFO - Initialising browser for dromaeo_css test... 07:46:09 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/tmp5LMafm/profile http://localhost:49247/getInfo.html) 07:46:12 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 07:46:12 INFO - PROCESS | 1633 | colorDepth:24 07:46:12 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 07:46:12 INFO - PROCESS | 1633 | __metrics 07:46:12 INFO - PROCESS | 1633 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:46:12 INFO - PROCESS | 1633 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:46:13 INFO - TEST-INFO | 1633: exit 0 07:46:13 INFO - Browser initialized. 07:46:13 INFO - Running cycle 1/1 for dromaeo_css test... 07:46:13 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/tmp5LMafm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:46:25 INFO - PROCESS | 1635 | RSS: Main: 222576640 07:46:25 INFO - PROCESS | 1635 | 07:47:17 INFO - PROCESS | 1635 | 1482162437303 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] 07:49:29 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49247/tests/dromaeo/cssquery-ext.html) 07:49:32 INFO - PROCESS | 1635 | RSS: Main: 457822208 07:49:32 INFO - PROCESS | 1635 | 07:52:35 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-ext.html (next: http://localhost:49247/tests/dromaeo/cssquery-jquery.html) 07:52:35 INFO - PROCESS | 1635 | RSS: Main: 348004352 07:52:35 INFO - PROCESS | 1635 | 07:55:38 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49247/tests/dromaeo/cssquery-mootools.html) 07:55:38 INFO - PROCESS | 1635 | RSS: Main: 319778816 07:55:38 INFO - PROCESS | 1635 | 07:58:41 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49247/tests/dromaeo/cssquery-prototype.html) 07:58:41 INFO - PROCESS | 1635 | RSS: Main: 321359872 07:58:41 INFO - PROCESS | 1635 | 08:01:45 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49247/tests/dromaeo/cssquery-yui.html) 08:01:45 INFO - PROCESS | 1635 | RSS: Main: 308191232 08:01:45 INFO - PROCESS | 1635 | 08:04:48 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-yui.html (next: http://localhost:49247/tests/dromaeo/cssquery-dojo.html) 08:04:49 INFO - PROCESS | 1635 | RSS: Main: 391954432 08:04:49 INFO - PROCESS | 1635 | 08:04:49 INFO - PROCESS | 1635 | __start_tp_report 08:04:49 INFO - PROCESS | 1635 | _x_x_mozilla_page_load 08:04:49 INFO - PROCESS | 1635 | _x_x_mozilla_page_load_details 08:04:49 INFO - PROCESS | 1635 | |i|pagename|runs| 08:04:49 INFO - PROCESS | 1635 | |0;dojo.html;3268.4116858965385;3274.8362581870915;3289.194147433878;3314.486254630533;3320.086976081579;5555.055640541108;5649.096144616862;5655.123455864343;5655.236543066685;5674.148877668352;5504.889902201963;5511.53151982082;5547.944520554794;5583.748731307091;5605.7197140143035;5115.411727651322;5123.846284611468;5145.562627176694;5154.329937108189;5174.870628234286;5078.542931136197;5094.974525127369;5112.335396398463;5216.478352164791;5219.765110570014;5101.591872650179;5150.407703114133;5154.38147422308;5167.302414174094;5184.766685499161;5178.818741344054;5215.191645294985;5216.1393370094265;5221;5273.947260527422;4423.739234318204;4881.267809828519;5153.123968925254;5182.144946083891;5185.870353241162;5484.780608775644;5508.14623733322;5538.252335934638;5541.141123125882;5571.777128914839;6061.575689701723;6095.329513753483;6176.505879529672;6185.350538193515;6201.007838745779;6237;6238.9376106238815;6243.56295059346;6245.125682404467;6259.530535209832;5617.691026993513;5628.240187574625;5633.352164501135;5658.236138121301;5666.093425052054;6313.652749098844;6319.684015799192;6329.177206963157;6332.33510481402;6333.429991300805;6066.575339726265;6070.7875224366935;6081.148639190517;6083.66539840313;6085.57400981927;6004.369541198112;6066.75732970685;6079.939200608026;6123.632582045091;6202.782902598387;6055.182550355646;6129.91263652097;6145.446909778141;6172.9074063889075;6173.9073913891325;6058.305572342586;6166.1983942087245;6166.753329866755;6181.7527298908435;6203.976343903206;4786.664933454695;4806.158922188673;4832;4862.149123903374;4873.95126048735;4349.195398851222;4375.5405682403525;4381.518033016429;4383.07955329378;4452.955470445254;5705.800296989585;5709.029464990961;5729.541636669056;5736.483716465538;5743.913841292384;6223.937760622426;6230.345813689497;6231.719572619243;6238.937610623836;6242.250929888442;5536.612437129363;5604.3835178130375;5622.775088996476;5668.489835914787;5700.743466544015;5286.81496147633;5350.357957045102;5357.598180136428;5358.651687640368;5388.865278367994;6303.3066362700065;6304.148939893055;6308.526860485482;6330.39861213184;6338.302786693553;4676.298555216745;6071.241094863142;6086.6348019119;6110.741024289911;6137.7851775187655;6272.560920735687;6300.369963003663;6309.053641953744;6320.304766475776;6321.209848768904;6058.606190597597;6086.969565152146;6123.540734444845;6141.385861414002;6142;4909.4845041270155;5870.119482077723;5893.734781934909;6180.196574445294;6232.096346029891;5723.771049157987;5758.683272420057;5767.423257674199;5767.9134812977;5772.278465191851;5732.140178973021;5753.367129615655;5764.596478246483;5766.048601980626;5767.192593037062;5553.278073850373;5645.717714114196;5687.203791469115;5699.943000569941;5704.714764261853;5803.7388317524965;5839.9780038492245;6012.549058820518;6026;6065.696715164312;5927.525797936241;5928.962431574543;5929.644221346733;5950.583459157818;5965.403459654 08:04:49 INFO - PROCESS | 1635 | |1;ext.html;431.9589788609451;462.88213963278685;478.8491625138081;511.88226707857194;532.374460009489;28944.710552894463;34593.48109778355;38479.80760096199;39619.60380396195;39710;40607.39088913669;41075.56235531757;41173.588264117425;41181.99999999993;41259.38110928331;9298.72103836886;9424.387414818015;9447.858282125773;9453.763655908588;9463.668771592995;8238.752837414857;8609.870851937227;8612.612432440525;8708.825823483527;8716.564171791386;8517.276031537296;11147.777044459106;11466.770664586704;11565.248258863146;11680.532778688885;11196.720081997975;11283.266587671775;11299.661010169708;11305.152113591514;11310.886891131066;8143.877841832377;8533.359998000113;11214.158938079676;11287.266327688674;11312.94343528277;38581.22837543261;39129.804350978346;39235.019124522114;40538.59461405378;40561.59438405608;3251.3334766372836;3462.740294477924;3791.229245774711;3838.5393752749846;3839.155385815116;27829.721702783118;28177.43645127086;28275.434491310058;28281;29487.70512294871;3123.4846250368887;3176.824574907276;3220.5652236947944;3245.140037889961;3285.0965984354257;483923.90190245176;489070;493757;493836;496151.5192424051;58929.70535147383;59309;59432;59446;59499.405005950255;62708.686456567426;62711;62795.37204627895;62904.685476572326;62936.68531657404;15512.689746205013;16043.438479653156;16055.357785688442;16056.678866422606;16085.356585736674;12363.196392234657;12420.75158496825;12435.564755233523;12436.191647543063;12470.75058498825;14544;14982.400703971936;16160.515184544247;16277.023378597321;16332.591685207728;6445.6454894981225;6522.184726909137;6525.967370163214;6549.9345006550275;6597.901031484532;829.2950991657164;927.0682963621517;962.3071388600197;1022.0341777020793;1085.571199376242;28465.28836779097;28770.28074298159;28877.56683649747;29632.851835740683;29672.40655186884;1013.5337744637402;1033.4471057983942;1038.4963292803136;1051.0360782593013;1121.433675993618;14701.55895323142;14717.190554519604;14778.039427437398;14952.40190392394;15050.774238386433;580247;583619;585112;585465;586856;54094;54338.184927226124;54375.72812135914;54399;54530.90938181293;1203.3141109567637;1346.6364081697886;1364.1337750528485;1383.702503961653;1385.5635474825397;26121.477570448864;27379;28293.292667683472;28403.289917752223;30926.0722178335;54113;54828;54851;54950.45049549454;55154.621134471956;467417;472489;472952.6352368216;474085;474734;25372.238832835046;25624.743752562237;25852;25914.35214119662;25957.870210648827;36018.8199059003;37649.81175094107;38924.80537597294;41116;44926;37103.62896371002;37702;38057;38595.03512412212;39076;37366.626333737404;37398.252034959696;38138.42792358172;38589.80705096456;38640;36086.09784755402;36619.81690091533;37205.62794372022;37279.44080838844;37563;31889.521657175625;33224.50163247505;33719.32561348809;33817.985460436226;33846.3230735379;24208.031678732652;25168.370790729645;25180.49639007246;25332.366690832878;25716.742832571435 08:04:49 INFO - PROCESS | 1635 | |2;jquery.html;339.29765385651706;373.79254513744877;379.829076915388;387.96702280306175;399.4168513969604;19067.14197861098;19581.608367832636;20061.896595687267;20514.487137821543;20698.586028279424;11599.362035088066;12027.819582706266;12042.036637069035;12051.276923384603;13078.607641770739;4787.736674482902;5125.948740512594;5484.149956756689;5486.643368181076;5488.506034456898;3893.5717071122153;4108.979455102735;4110.239605672945;5356.196570514415;5747.827565173031;5198.9082292718385;6006;6076.5138788897;6105.603135796159;6109.969450152743;5855.912161317584;5973.283206015262;6037.698115094227;6054.757809687607;6055.6063855849225;5820.447057529528;5835.416458354173;5842.444967728102;5843;7026.1217347831525;27387.178384648494;28202.858985705145;28248.15255542337;28893;29036.564451533242;2342.7774361435636;2443.621238708003;2541.1233124571995;3078.184281165493;3314.685104915029;15923.363065477368;16643.41748038825;16700;17811.307925747034;18096.276148954024;1980.485073880787;1990.4327266728912;2012.7282816819688;2077.4598604362973;2086.447091520733;131195.6880431193;132103.0817801411;136347;137392.31303843416;142293;21115.788842111688;21692.891535542538;21757.67363489517;22119.004644791024;22446.775532244796;34821.651783482346;35229.47155792665;35529.802317791524;35568.644313556535;35577;8731.170538798804;9755.804883902283;9904.702858914245;10650.680479585624;10909.509072091616;7648.885266721004;8846.823063538823;8880.600372983232;8943.3739638226;9058.094190580889;9407.388519746331;9808.852867206997;9857.704268871945;9898.10917017472;9926.503674816231;2678.437528119078;3169.920751981219;3529.2235708144167;3609.2749973769637;3778.0932576182067;760.2245709376516;796.5220867479466;861.3324673378182;874.226309715906;896.2337201692459;19793;20443.59112817735;20524.794752052287;20529.38411847617;20942.895285523475;789.3409003482157;875.1467319363696;898.2544488075013;928.4150984879618;1010.0959641121302;10470.319429237228;10656.360618362924;10844.45777711127;10911;10938.507767150339;589999;596105;596637;606595;607044;123818.76181238252;128812;130018;130738.34630826785;132644;831.2850948184556;972.3631021680669;985.5023213277249;998.336106489191;1000.5147503460815;17694.203760830536;18075.90962045234;18122;18350.908245458686;18464.630707386044;51295.23057154071;52597.94804103973;52776.47223527716;53046;53621.73189134186;74995;75520.24479755132;76444;77045.61477192579;77618;7838.372930165687;7989.400794940287;8052.436329456882;8257.628406721591;8358.58207089631;7894.565798881116;11883;11894.405279736151;12477;12586.622401327975;9890;10217.591296348062;10623.575056997634;10765.623203187852;10815.134789217002;10589.4705264738;10599.205059620404;10727.678169654922;10749.247552671239;10972.01251887334;10510.632127875488;10715;11077.058450031514;11202.327860328407;11275.32348059119;8005.199480051948;8027.518348899085;8090.7168249111;8283.798849166958;8321.791955201168;10630.946845265724;10648.520816563117;10924.126069914546;10964.725881852699;11028.779424411627 08:04:49 INFO - PROCESS | 1635 | |3;mootools.html;1462.707458508298;1482.5404124721333;1559.7660350947363;1649.5051484554638;1843.2166329310048;8010.599470026505;8413.747587572383;8421.78945526362;8429.91570084299;8483.703070392537;6161.353057928909;7721.420893432987;7969.203079692028;8232.711855085074;8254.298384637297;2293.0827668932434;2321.0019691532634;2324.6861673674093;2325.964945599209;2334.684817549626;2331.7901388875;2337.275444612167;2384.618461046233;2387.9641805372935;2391.940201494968;2344.1443872986406;2345.8827058647084;2358.422186564294;2364.680768096302;2366.1008816649737;2306.688597039395;2316.837821352506;2334.3113781434517;2344.7655234476415;2355.6702061711376;2319.1419174905222;2338;2343.2970108967243;2346.495503466746;2351.4239011442137;8269.710560130367;8437.95781021091;8450.915490845075;8456.36577256702;8460.78848028798;4925.827596034122;5014.172661510883;5165.173572228463;5167.715775632339;5183.844484665466;3102.0073576455543;3131.561581378609;3411.4200585900458;3471.895843124685;3503.7021853142514;1011.8735158105237;1048.6539441984128;1073.264813602677;1086.103964229506;1087.0542627913765;10842.457877106113;10942.288751231225;10945.124390048857;10959.890401095887;10962.890371096266;4283.250431174532;4494.73031618104;4574.153781550357;4690.742009189459;4697.577218050392;9400.48297343639;9403.153716165578;9427.717168484956;9430.575624096795;9431.292653051049;4995.425526064549;4998.700077995332;5008.449070602231;5009.273655319958;5027.924581131286;4218.0720241546815;4395.934060989088;4437.711548749321;4559.52125026874;4727.976360118178;3273.7708360415018;3750;3787.8295476703615;3848.5574158971517;3900.278448487039;1262.2237324045645;1267.9746405071846;1269.7587458382877;1282.2755143343982;1285.6914340558199;756.4780301591884;841.3731769831514;892.7723430525305;894.2086253665553;912.1061359867367;5576.275084239023;5579.525740312038;5662.603617746719;5797.4782269595935;5948.226730525091;778.7507997440878;939.3565407695751;951.7573018880141;968.8498282766183;1019.8674172357546;3457.22212502185;3661.5240018797385;3709.629037096323;3814.4659747635355;3947.842086316515;9814.65648702292;10259.897401026043;10299.74250643725;10307.94846025765;10317.432541210155;10161.644342447978;10171.7457063574;10173.135283500986;10181.847272290921;10182.949085254675;6796.184457864989;7410.073740782403;7550.942867998485;7769.92230077692;7816.609169541386;5689.6586204827845;5730.5129064030025;5738.5696072795545;5843.883122337615;5864.882702345843;9285.303602229995;9302.488363140092;9398.248140148908;9446.716598502057;9516.904830951602;9209.907900920905;9584.90415095868;9618.615255389706;9644.75888102803;9710.368826026286;6607.240167380813;6834.726610935507;6841.828954276183;7139.107611548557;7315.487915846053;7663.616819158909;7797.7660670179985;7910.2089791020435;7978.042634883851;7983.121856595886;7040.0847889774;7177.497575169688;7178.389836863925;7207.747728829466;7293.927060729325;6787.0498130262795;6827.726890924506;6989.7553585624255;7004.898530440949;7041.964790176016;6560.409563139149;6605.834854128685;6670.433013193933;6687.264400915993;6704.296048914794;4810.11012962603;4825.276208568743;4853.296272040605;4879.609631229564;4919.360483137312;4766.475687674422;4782.473927867863;4849.515048495122;4951.480094590016;4960.131976903956 08:04:49 INFO - PROCESS | 1635 | |4;prototype.html;297.1471875716693;304.5948887978988;306.06649718359006;314.86303457995774;314.94016136933993;4463.9553604463945;4589.472210695771;5039.488153553937;5082.161443361845;5086.160783470732;4763.618910487161;4773.260144677572;4904.190808516591;4915.090708218982;5021;3623.2934577757346;3903.024243939022;3915.7454765440298;3923.058465968176;3924.038610540422;2979.597754303163;3370.96629033709;3514.5255390522207;3901.6293452122004;3910.9413358799643;3029.8033925404015;3663.725220608465;3664.835082421284;3873.593272706353;3918.804059797013;3486.267883744417;3691.741578089535;3872.9225415491533;3903.1413089120347;3904.5509766376945;2864.269611249139;2889.8844046238123;3395.52462655226;3422.435298175798;3432.3696244284015;3882.9223415531815;4652.464966528859;4933.654644174909;4944.653874228806;4955.430125535573;4156.565984735262;4416.227160246976;4453.43927241834;4458.398116254297;4463.754493502856;4234.894127646833;5061.620378471629;5306.548943339821;5323.547498462596;5329.520343169094;3053.6946305369506;3765.2093060457337;3774.5093137892172;3794.6015668354976;3821.8280177392085;5041.605237512189;5470.863228419322;5590.832275031756;5647.519960803336;5648.265725455664;3320.966790332114;3503.2993401319836;3680.852765889388;3711.6266981217123;3725.8137093145233;3745.7377983541437;3749.250149970017;3749.8687545935763;3756.7933763642714;3759.661630453272;4165.937510937339;4354.978225108917;4358.191350589534;4365.890852728708;4370.147821174904;5278.199696218717;5366.570674346043;5375.4624537546715;5386.434424385461;5399.892002159935;1126.5381193710539;1183.6685727996003;1199.4962115911337;1215.1918973882268;1223.9351763965408;709.5529816215854;752.8531936272374;784.2001158817974;795.8527672380628;829.307528213936;4322.027543802619;4515.593596576324;4601.907961840744;4735.573798358096;4735.905281894343;4732.95267047332;4761.523847615211;5005.774740136702;5117.466853907265;5151.583314588518;4094.7306335036233;4355.607995280377;4491.956159947216;4723.362346083235;4726.550977657117;2439.463138222908;2543.351445381416;2724.2644485989067;2729.8362098274165;2734.179746076169;5207.843764687065;5356.1697936819855;5369;5373.387983604981;5403.270558474555;4454.26283749338;4679.742614156186;4892.804287828518;4898.730569818694;5323.057084164252;4729.621630269639;5297.337832770903;5336.199570064521;5339.7063161526485;5359.866003349869;4230;4804.807807687654;5123.410807757155;5157.853709237287;5246.947530524799;4584.472785629695;4822.831200907952;4888.095702295087;5098.949010509996;5127.717975511383;4361.978190109029;4732.73969931657;4848.587870030946;4945.629077819106;5318.4681531848055;3157.0371036833985;3438.570178727659;3482.2687235679778;3493.528373669522;3505.7370697197302;4757.26262429324;4881.829135980223;5007.649464537448;5079.866456088378;5130.333056702605;4951.77717002743;4958.900821983612;4961.181405068123;4970.155073637418;5050.368703912011;4618.912262647514;4958.669425370071;4958.826441074545;4968;4994.051130285234;3831;4095.1776459475245;4712.387389639325;4848.3454733611925;5352.839414817562;4521;5381.704006279692;5393;5423.728813559227;5462.688954650935;4446.244138496399;5251.422343542131;5296.443873393392;5320.414754376938;5363.839084827461 08:04:49 INFO - PROCESS | 1635 | |5;yui.html;605.6063558686853;643.3759253524083;734.067733977848;752.7515919746485;777.9533228006317;13988.930055349721;14128.505502307422;14472.565823025327;14576.125432474058;14632.195229262386;12730;12937.611871643865;13011.34943252836;13019.73960520789;13123.606291811237;749.1197842535026;820.7578764264526;846.272205902923;846.5513277962659;849.8810166576684;666.257123307511;690.5235387582577;690.533889624504;819.848328059308;847.9703210387638;902.165496915354;905.072300891588;905.2395987370608;908.4957848394172;909.4543274035559;837.8868852704928;842.1830824100613;893.718478679214;895.632790555869;896.4800415758845;517.707495265174;528.2287859724806;529.076761051963;531.1236459841293;535.237286866214;13218.272994985367;13657.521986730517;13722.039457237997;13944.930275348659;13984.160950343012;13289.269090200036;13534.323283835769;13646.795298070538;13757.243351615758;13777.380017899219;11010.669679909615;11252.66242012741;11295.20933534653;11331.830022549668;11335.659930202108;3504.912377190565;3573.2674801665858;3585.0858031202138;3751.7748935063983;3766.0584853786554;81927.77108343379;88044.67932981011;88419.67370489448;88544;88664.55667721685;10100.141487973458;10266.384016959006;10421.791564168674;10767.246292759588;10978.835317470244;18816.52958676044;19724.90137549332;20338.084786184656;20409.285675001305;20487.282945097148;7575.848483030419;7615.923840761632;7724.729634462767;7781.649825757854;7828.765137045898;4091.6317531422314;4127.855525056669;4222.852200173041;4270.0605866709275;4284.614384705392;3295.2256219788333;3412.334594753999;3412.4540073588105;3416.4533674611944;3419.9487007694906;588.9352171261157;595.553334998751;596.9880602388015;609.9237595300588;616.9907451388233;560.9467100625434;572.9512991395778;646.6508085633706;655.2301046270613;659.4197106546209;8119.9188008120345;8743.825123497621;9028.138096188412;9029.63881444748;9155.633774649074;612.9478994285536;645.9696783630103;658.4467763748962;664.3821246241042;670.8960111182774;4298.46269216348;4452.8218871245435;4462.352958820953;4497.145542346943;4563.771811409416;67741.98387024198;96565;99973;100481.49759251157;103705.48147259216;61025;70749;83152.58423707842;89703.55148224217;95108.52445737727;59680.40319596922;77476;78297.21702783013;83093;83382.58308708417;9280.164785169367;9657.517124143906;9690.85463718059;9700.369475984038;9720.562574684014;31655.683443165275;49983.75008124936;50888;52792.73603631957;52998.73500632472;65206.347936520026;66969.99545006732;68999.3100068993;72767.63616181885;76451.61774191093;11480.655580332603;11634.767304653691;11946;11996.700082498008;12189.146759726735;13735.126745990641;13939.930300348433;13991.23048232357;14034.017618766587;14947.85052149508;12003.219790713576;12253.69365765863;12532.686682833002;12828.422720977765;13071.281079540717;10177.389356638625;10382.948085259526;12855.971522278009;12943.158694684815;13018.479260829461;12510.812337815121;12657.303848288433;12747.681307967376;12823.807642885551;13354.265515396746;10709.105720132771;10775.730606734895;11285.37930413835;11374.488148033193;11380.986119288129;11012.559497620005;11123.27698699582;11346.262492937933;11466.426678666201;11497.770044599229 08:04:49 INFO - PROCESS | 1635 | __end_tp_report 08:04:49 INFO - PROCESS | 1635 | __start_cc_report 08:04:49 INFO - PROCESS | 1635 | _x_x_mozilla_cycle_collect,3006 08:04:49 INFO - PROCESS | 1635 | __end_cc_report 08:04:49 INFO - PROCESS | 1635 | __startTimestamp1482163489206__endTimestamp 08:04:49 INFO - PROCESS | 1635 | 08:04:49 INFO - PROCESS | 1635 | ------- Summary: start ------- 08:04:49 INFO - PROCESS | 1635 | Number of tests: 6 08:04:49 INFO - PROCESS | 1635 | 08:04:49 INFO - PROCESS | 1635 | [#0] dojo.html Cycles:165 Average:5637.51 Median:5736.48 stddev:644.77 (11.2%) stddev-sans-first:619.38 08:04:49 INFO - PROCESS | 1635 | Values: 3268.4 3274.8 3289.2 3314.5 3320.1 5555.1 5649.1 5655.1 5655.2 5674.1 5504.9 5511.5 5547.9 5583.7 5605.7 5115.4 5123.8 5145.6 5154.3 5174.9 5078.5 5095.0 5112.3 5216.5 5219.8 5101.6 5150.4 5154.4 5167.3 5184.8 5178.8 5215.2 5216.1 5221.0 5273.9 4423.7 4881.3 5153.1 5182.1 5185.9 5484.8 5508.1 5538.3 5541.1 5571.8 6061.6 6095.3 6176.5 6185.4 6201.0 6237.0 6238.9 6243.6 6245.1 6259.5 5617.7 5628.2 5633.4 5658.2 5666.1 6313.7 6319.7 6329.2 6332.3 6333.4 6066.6 6070.8 6081.1 6083.7 6085.6 6004.4 6066.8 6079.9 6123.6 6202.8 6055.2 6129.9 6145.4 6172.9 6173.9 6058.3 6166.2 6166.8 6181.8 6204.0 4786.7 4806.2 4832.0 4862.1 4874.0 4349.2 4375.5 4381.5 4383.1 4453.0 5705.8 5709.0 5729.5 5736.5 5743.9 6223.9 6230.3 6231.7 6238.9 6242.3 5536.6 5604.4 5622.8 5668.5 5700.7 5286.8 5350.4 5357.6 5358.7 5388.9 6303.3 6304.1 6308.5 6330.4 6338.3 4676.3 6071.2 6086.6 6110.7 6137.8 6272.6 6300.4 6309.1 6320.3 6321.2 6058.6 6087.0 6123.5 6141.4 6142.0 4909.5 5870.1 5893.7 6180.2 6232.1 5723.8 5758.7 5767.4 5767.9 5772.3 5732.1 5753.4 5764.6 5766.0 5767.2 5553.3 5645.7 5687.2 5699.9 5704.7 5803.7 5840.0 6012.5 6026.0 6065.7 5927.5 5929.0 5929.6 5950.6 5965.4 08:04:49 INFO - PROCESS | 1635 | 08:04:49 INFO - PROCESS | 1635 | [#1] ext.html Cycles:180 Average:65143.70 Median:37480.63 stddev:138155.70 (368.6%) stddev-sans-first:138457.83 08:04:49 INFO - PROCESS | 1635 | Values: 432.0 462.9 478.8 511.9 532.4 28944.7 34593.5 38479.8 39619.6 39710.0 40607.4 41075.6 41173.6 41182.0 41259.4 9298.7 9424.4 9447.9 9453.8 9463.7 8238.8 8609.9 8612.6 8708.8 8716.6 8517.3 11147.8 11466.8 11565.2 11680.5 11196.7 11283.3 11299.7 11305.2 11310.9 8143.9 8533.4 11214.2 11287.3 11312.9 38581.2 39129.8 39235.0 40538.6 40561.6 3251.3 3462.7 3791.2 3838.5 3839.2 27829.7 28177.4 28275.4 28281.0 29487.7 3123.5 3176.8 3220.6 3245.1 3285.1 483923.9 489070.0 493757.0 493836.0 496151.5 58929.7 59309.0 59432.0 59446.0 59499.4 62708.7 62711.0 62795.4 62904.7 62936.7 15512.7 16043.4 16055.4 16056.7 16085.4 12363.2 12420.8 12435.6 12436.2 12470.8 14544.0 14982.4 16160.5 16277.0 16332.6 6445.6 6522.2 6526.0 6549.9 6597.9 829.3 927.1 962.3 1022.0 1085.6 28465.3 28770.3 28877.6 29632.9 29672.4 1013.5 1033.4 1038.5 1051.0 1121.4 14701.6 14717.2 14778.0 14952.4 15050.8 580247.0 583619.0 585112.0 585465.0 586856.0 54094.0 54338.2 54375.7 54399.0 54530.9 1203.3 1346.6 1364.1 1383.7 1385.6 26121.5 27379.0 28293.3 28403.3 30926.1 54113.0 54828.0 54851.0 54950.5 55154.6 467417.0 472489.0 472952.6 474085.0 474734.0 25372.2 25624.7 25852.0 25914.4 25957.9 36018.8 37649.8 38924.8 41116.0 44926.0 37103.6 37702.0 38057.0 38595.0 39076.0 37366.6 37398.3 38138.4 38589.8 38640.0 36086.1 36619.8 37205.6 37279.4 37563.0 31889.5 33224.5 33719.3 33818.0 33846.3 24208.0 25168.4 25180.5 25332.4 25716.7 08:04:49 INFO - PROCESS | 1635 | 08:04:49 INFO - PROCESS | 1635 | [#2] jquery.html Cycles:180 Average:36541.82 Median:35025.56 stddev:100320.35 (286.4%) stddev-sans-first:100564.95 08:04:49 INFO - PROCESS | 1635 | Values: 339.3 373.8 379.8 388.0 399.4 19067.1 19581.6 20061.9 20514.5 20698.6 11599.4 12027.8 12042.0 12051.3 13078.6 4787.7 5125.9 5484.1 5486.6 5488.5 3893.6 4109.0 4110.2 5356.2 5747.8 5198.9 6006.0 6076.5 6105.6 6110.0 5855.9 5973.3 6037.7 6054.8 6055.6 5820.4 5835.4 5842.4 5843.0 7026.1 27387.2 28202.9 28248.2 28893.0 29036.6 2342.8 2443.6 2541.1 3078.2 3314.7 15923.4 16643.4 16700.0 17811.3 18096.3 1980.5 1990.4 2012.7 2077.5 2086.4 131195.7 132103.1 136347.0 137392.3 142293.0 21115.8 21692.9 21757.7 22119.0 22446.8 34821.7 35229.5 35529.8 35568.6 35577.0 8731.2 9755.8 9904.7 10650.7 10909.5 7648.9 8846.8 8880.6 8943.4 9058.1 9407.4 9808.9 9857.7 9898.1 9926.5 2678.4 3169.9 3529.2 3609.3 3778.1 760.2 796.5 861.3 874.2 896.2 19793.0 20443.6 20524.8 20529.4 20942.9 789.3 875.1 898.3 928.4 1010.1 10470.3 10656.4 10844.5 10911.0 10938.5 589999.0 596105.0 596637.0 606595.0 607044.0 123818.8 128812.0 130018.0 130738.3 132644.0 831.3 972.4 985.5 998.3 1000.5 17694.2 18075.9 18122.0 18350.9 18464.6 51295.2 52597.9 52776.5 53046.0 53621.7 74995.0 75520.2 76444.0 77045.6 77618.0 7838.4 7989.4 8052.4 8257.6 8358.6 7894.6 11883.0 11894.4 12477.0 12586.6 9890.0 10217.6 10623.6 10765.6 10815.1 10589.5 10599.2 10727.7 10749.2 10972.0 10510.6 10715.0 11077.1 11202.3 11275.3 8005.2 8027.5 8090.7 8283.8 8321.8 10630.9 10648.5 10924.1 10964.7 11028.8 08:04:49 INFO - PROCESS | 1635 | 08:04:49 INFO - PROCESS | 1635 | [#3] mootools.html Cycles:180 Average:5434.87 Median:4977.78 stddev:2989.34 (60.1%) stddev-sans-first:2982.82 08:04:49 INFO - PROCESS | 1635 | Values: 1462.7 1482.5 1559.8 1649.5 1843.2 8010.6 8413.7 8421.8 8429.9 8483.7 6161.4 7721.4 7969.2 8232.7 8254.3 2293.1 2321.0 2324.7 2326.0 2334.7 2331.8 2337.3 2384.6 2388.0 2391.9 2344.1 2345.9 2358.4 2364.7 2366.1 2306.7 2316.8 2334.3 2344.8 2355.7 2319.1 2338.0 2343.3 2346.5 2351.4 8269.7 8438.0 8450.9 8456.4 8460.8 4925.8 5014.2 5165.2 5167.7 5183.8 3102.0 3131.6 3411.4 3471.9 3503.7 1011.9 1048.7 1073.3 1086.1 1087.1 10842.5 10942.3 10945.1 10959.9 10962.9 4283.3 4494.7 4574.2 4690.7 4697.6 9400.5 9403.2 9427.7 9430.6 9431.3 4995.4 4998.7 5008.4 5009.3 5027.9 4218.1 4395.9 4437.7 4559.5 4728.0 3273.8 3750.0 3787.8 3848.6 3900.3 1262.2 1268.0 1269.8 1282.3 1285.7 756.5 841.4 892.8 894.2 912.1 5576.3 5579.5 5662.6 5797.5 5948.2 778.8 939.4 951.8 968.8 1019.9 3457.2 3661.5 3709.6 3814.5 3947.8 9814.7 10259.9 10299.7 10307.9 10317.4 10161.6 10171.7 10173.1 10181.8 10182.9 6796.2 7410.1 7550.9 7769.9 7816.6 5689.7 5730.5 5738.6 5843.9 5864.9 9285.3 9302.5 9398.2 9446.7 9516.9 9209.9 9584.9 9618.6 9644.8 9710.4 6607.2 6834.7 6841.8 7139.1 7315.5 7663.6 7797.8 7910.2 7978.0 7983.1 7040.1 7177.5 7178.4 7207.7 7293.9 6787.0 6827.7 6989.8 7004.9 7042.0 6560.4 6605.8 6670.4 6687.3 6704.3 4810.1 4825.3 4853.3 4879.6 4919.4 4766.5 4782.5 4849.5 4951.5 4960.1 08:04:49 INFO - PROCESS | 1635 | 08:04:49 INFO - PROCESS | 1635 | [#4] prototype.html Cycles:180 Average:4171.49 Median:4696.07 stddev:1268.86 (27.0%) stddev-sans-first:1238.65 08:04:49 INFO - PROCESS | 1635 | Values: 297.1 304.6 306.1 314.9 314.9 4464.0 4589.5 5039.5 5082.2 5086.2 4763.6 4773.3 4904.2 4915.1 5021.0 3623.3 3903.0 3915.7 3923.1 3924.0 2979.6 3371.0 3514.5 3901.6 3910.9 3029.8 3663.7 3664.8 3873.6 3918.8 3486.3 3691.7 3872.9 3903.1 3904.6 2864.3 2889.9 3395.5 3422.4 3432.4 3882.9 4652.5 4933.7 4944.7 4955.4 4156.6 4416.2 4453.4 4458.4 4463.8 4234.9 5061.6 5306.5 5323.5 5329.5 3053.7 3765.2 3774.5 3794.6 3821.8 5041.6 5470.9 5590.8 5647.5 5648.3 3321.0 3503.3 3680.9 3711.6 3725.8 3745.7 3749.3 3749.9 3756.8 3759.7 4165.9 4355.0 4358.2 4365.9 4370.1 5278.2 5366.6 5375.5 5386.4 5399.9 1126.5 1183.7 1199.5 1215.2 1223.9 709.6 752.9 784.2 795.9 829.3 4322.0 4515.6 4601.9 4735.6 4735.9 4733.0 4761.5 5005.8 5117.5 5151.6 4094.7 4355.6 4492.0 4723.4 4726.6 2439.5 2543.4 2724.3 2729.8 2734.2 5207.8 5356.2 5369.0 5373.4 5403.3 4454.3 4679.7 4892.8 4898.7 5323.1 4729.6 5297.3 5336.2 5339.7 5359.9 4230.0 4804.8 5123.4 5157.9 5246.9 4584.5 4822.8 4888.1 5098.9 5127.7 4362.0 4732.7 4848.6 4945.6 5318.5 3157.0 3438.6 3482.3 3493.5 3505.7 4757.3 4881.8 5007.6 5079.9 5130.3 4951.8 4958.9 4961.2 4970.2 5050.4 4618.9 4958.7 4958.8 4968.0 4994.1 3831.0 4095.2 4712.4 4848.3 5352.8 4521.0 5381.7 5393.0 5423.7 5462.7 4446.2 5251.4 5296.4 5320.4 5363.8 08:04:49 INFO - PROCESS | 1635 | 08:04:49 INFO - PROCESS | 1635 | [#5] yui.html Cycles:180 Average:19030.36 Median:4479.75 stddev:26932.87 (601.2%) stddev-sans-first:26972.89 08:04:49 INFO - PROCESS | 1635 | Values: 605.6 643.4 734.1 752.8 778.0 13988.9 14128.5 14472.6 14576.1 14632.2 12730.0 12937.6 13011.3 13019.7 13123.6 749.1 820.8 846.3 846.6 849.9 666.3 690.5 690.5 819.8 848.0 902.2 905.1 905.2 908.5 909.5 837.9 842.2 893.7 895.6 896.5 517.7 528.2 529.1 531.1 535.2 13218.3 13657.5 13722.0 13944.9 13984.2 13289.3 13534.3 13646.8 13757.2 13777.4 11010.7 11252.7 11295.2 11331.8 11335.7 3504.9 3573.3 3585.1 3751.8 3766.1 81927.8 88044.7 88419.7 88544.0 88664.6 10100.1 10266.4 10421.8 10767.2 10978.8 18816.5 19724.9 20338.1 20409.3 20487.3 7575.8 7615.9 7724.7 7781.6 7828.8 4091.6 4127.9 4222.9 4270.1 4284.6 3295.2 3412.3 3412.5 3416.5 3419.9 588.9 595.6 597.0 609.9 617.0 560.9 573.0 646.7 655.2 659.4 8119.9 8743.8 9028.1 9029.6 9155.6 612.9 646.0 658.4 664.4 670.9 4298.5 4452.8 4462.4 4497.1 4563.8 67742.0 96565.0 99973.0 100481.5 103705.5 61025.0 70749.0 83152.6 89703.6 95108.5 59680.4 77476.0 78297.2 83093.0 83382.6 9280.2 9657.5 9690.9 9700.4 9720.6 31655.7 49983.8 50888.0 52792.7 52998.7 65206.3 66970.0 68999.3 72767.6 76451.6 11480.7 11634.8 11946.0 11996.7 12189.1 13735.1 13939.9 13991.2 14034.0 14947.9 12003.2 12253.7 12532.7 12828.4 13071.3 10177.4 10382.9 12856.0 12943.2 13018.5 12510.8 12657.3 12747.7 12823.8 13354.3 10709.1 10775.7 11285.4 11374.5 11381.0 11012.6 11123.3 11346.3 11466.4 11497.8 08:04:49 INFO - PROCESS | 1635 | -------- Summary: end -------- 08:04:49 INFO - PROCESS | 1635 | 08:04:49 INFO - TEST-INFO | 1635: exit 0 08:04:49 INFO - TEST-OK | dromaeo_css | took 1119861ms 08:04:49 INFO - TEST-START | kraken 08:04:49 INFO - operating with platform_type : mac_ 08:04:49 INFO - Initialising browser for kraken test... 08:04:49 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/tmpotq32K/profile http://localhost:49247/getInfo.html) 08:04:52 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 08:04:52 INFO - PROCESS | 1644 | colorDepth:24 08:04:52 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 08:04:52 INFO - PROCESS | 1644 | __metrics 08:04:52 INFO - PROCESS | 1644 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:04:52 INFO - PROCESS | 1644 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:04:53 INFO - TEST-INFO | 1644: exit 0 08:04:53 INFO - Browser initialized. 08:04:53 INFO - Running cycle 1/1 for kraken test... 08:04:53 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/tmpotq32K/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:05:05 INFO - PROCESS | 1645 | RSS: Main: 218255360 08:05:05 INFO - PROCESS | 1645 | 08:05:08 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-beat-detection) 08:05:09 INFO - PROCESS | 1645 | RSS: Main: 268447744 08:05:09 INFO - PROCESS | 1645 | 08:05:11 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-dft) 08:05:12 INFO - PROCESS | 1645 | RSS: Main: 281509888 08:05:12 INFO - PROCESS | 1645 | 08:05:15 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-fft) 08:05:16 INFO - PROCESS | 1645 | RSS: Main: 378060800 08:05:16 INFO - PROCESS | 1645 | 08:05:18 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-oscillator) 08:05:18 INFO - PROCESS | 1645 | RSS: Main: 282972160 08:05:18 INFO - PROCESS | 1645 | 08:05:20 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:05:21 INFO - PROCESS | 1645 | RSS: Main: 284155904 08:05:21 INFO - PROCESS | 1645 | 08:05:24 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-darkroom) 08:05:24 INFO - PROCESS | 1645 | RSS: Main: 325234688 08:05:24 INFO - PROCESS | 1645 | 08:05:27 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-desaturate) 08:05:28 INFO - PROCESS | 1645 | RSS: Main: 323760128 08:05:28 INFO - PROCESS | 1645 | 08:05:31 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49247/tests/kraken/driver.html?testName=json-parse-financial) 08:05:31 INFO - PROCESS | 1645 | RSS: Main: 319995904 08:05:31 INFO - PROCESS | 1645 | 08:05:33 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49247/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:05:33 INFO - PROCESS | 1645 | RSS: Main: 300032000 08:05:33 INFO - PROCESS | 1645 | 08:05:35 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:05:36 INFO - PROCESS | 1645 | RSS: Main: 300867584 08:05:36 INFO - PROCESS | 1645 | 08:05:38 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:05:39 INFO - PROCESS | 1645 | RSS: Main: 304365568 08:05:39 INFO - PROCESS | 1645 | 08:05:41 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:05:42 INFO - PROCESS | 1645 | RSS: Main: 312139776 08:05:42 INFO - PROCESS | 1645 | 08:05:45 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:05:46 INFO - PROCESS | 1645 | RSS: Main: 312328192 08:05:46 INFO - PROCESS | 1645 | 08:05:47 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49247/tests/kraken/driver.html?testName=ai-astar) 08:05:48 INFO - PROCESS | 1645 | RSS: Main: 356343808 08:05:48 INFO - PROCESS | 1645 | 08:05:48 INFO - PROCESS | 1645 | __start_tp_report 08:05:48 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 08:05:48 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 08:05:48 INFO - PROCESS | 1645 | |i|pagename|runs| 08:05:48 INFO - PROCESS | 1645 | |0;ai-astar;103;113;113;113;113;114;113;113;114;118 08:05:48 INFO - PROCESS | 1645 | |1;audio-beat-detection;144;109;111;143;110;109;114;110;136;110 08:05:48 INFO - PROCESS | 1645 | |2;audio-dft;184;184;184;183;185;182;184;183;183;182 08:05:48 INFO - PROCESS | 1645 | |3;audio-fft;80;59;58;90;64;61;79;84;110;60 08:05:48 INFO - PROCESS | 1645 | |4;audio-oscillator;83;70;95;69;68;69;90;68;69;93 08:05:48 INFO - PROCESS | 1645 | |5;imaging-gaussian-blur;86;83;91;109;93;121;135;113;86;83 08:05:48 INFO - PROCESS | 1645 | |6;imaging-darkroom;92;91;92;93;91;92;101;93;92;91 08:05:48 INFO - PROCESS | 1645 | |7;imaging-desaturate;85;83;82;82;83;83;84;84;81;84 08:05:48 INFO - PROCESS | 1645 | |8;json-parse-financial;53;53;51;54;58;58;55;56;56;58 08:05:48 INFO - PROCESS | 1645 | |9;json-stringify-tinderbox;64;73;68;75;72;69;69;71;70;72 08:05:48 INFO - PROCESS | 1645 | |10;stanford-crypto-aes;86;84;84;80;90;73;86;106;100;79 08:05:48 INFO - PROCESS | 1645 | |11;stanford-crypto-ccm;140;137;138;137;138;142;137;145;179;147 08:05:48 INFO - PROCESS | 1645 | |12;stanford-crypto-pbkdf2;163;163;164;164;180;165;166;173;165;167 08:05:48 INFO - PROCESS | 1645 | |13;stanford-crypto-sha256-iterative;69;66;68;68;68;70;67;67;67;66 08:05:48 INFO - PROCESS | 1645 | __end_tp_report 08:05:48 INFO - PROCESS | 1645 | __start_cc_report 08:05:48 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,1996 08:05:48 INFO - PROCESS | 1645 | __end_cc_report 08:05:48 INFO - PROCESS | 1645 | __startTimestamp1482163548153__endTimestamp 08:05:48 INFO - PROCESS | 1645 | 08:05:48 INFO - PROCESS | 1645 | ------- Summary: start ------- 08:05:48 INFO - PROCESS | 1645 | Number of tests: 14 08:05:48 INFO - PROCESS | 1645 | 08:05:48 INFO - PROCESS | 1645 | [#0] ai-astar Cycles:10 Average:112.70 Median:113.00 stddev:3.74 (3.3%) stddev-sans-first:1.64 08:05:48 INFO - PROCESS | 1645 | Values: 103.0 113.0 113.0 113.0 113.0 114.0 113.0 113.0 114.0 118.0 08:05:48 INFO - PROCESS | 1645 | 08:05:48 INFO - PROCESS | 1645 | [#1] audio-beat-detection Cycles:10 Average:119.60 Median:112.50 stddev:14.98 (13.3%) stddev-sans-first:13.02 08:05:48 INFO - PROCESS | 1645 | Values: 144.0 109.0 111.0 143.0 110.0 109.0 114.0 110.0 136.0 110.0 08:05:48 INFO - PROCESS | 1645 | 08:05:48 INFO - PROCESS | 1645 | [#2] audio-dft Cycles:10 Average:183.40 Median:184.00 stddev:0.97 (0.5%) stddev-sans-first:1.00 08:05:48 INFO - PROCESS | 1645 | Values: 184.0 184.0 184.0 183.0 185.0 182.0 184.0 183.0 183.0 182.0 08:05:48 INFO - PROCESS | 1645 | 08:05:48 INFO - PROCESS | 1645 | [#3] audio-fft Cycles:10 Average:74.50 Median:71.50 stddev:17.18 (24.0%) stddev-sans-first:18.11 08:05:48 INFO - PROCESS | 1645 | Values: 80.0 59.0 58.0 90.0 64.0 61.0 79.0 84.0 110.0 60.0 08:05:48 INFO - PROCESS | 1645 | 08:05:48 INFO - PROCESS | 1645 | [#4] audio-oscillator Cycles:10 Average:77.40 Median:76.50 stddev:11.48 (15.0%) stddev-sans-first:12.00 08:05:48 INFO - PROCESS | 1645 | Values: 83.0 70.0 95.0 69.0 68.0 69.0 90.0 68.0 69.0 93.0 08:05:48 INFO - PROCESS | 1645 | 08:05:48 INFO - PROCESS | 1645 | [#5] imaging-gaussian-blur Cycles:10 Average:100.00 Median:84.50 stddev:18.31 (21.7%) stddev-sans-first:18.70 08:05:48 INFO - PROCESS | 1645 | Values: 86.0 83.0 91.0 109.0 93.0 121.0 135.0 113.0 86.0 83.0 08:05:48 INFO - PROCESS | 1645 | 08:05:48 INFO - PROCESS | 1645 | [#6] imaging-darkroom Cycles:10 Average:92.80 Median:92.00 stddev:2.97 (3.2%) stddev-sans-first:3.14 08:05:48 INFO - PROCESS | 1645 | Values: 92.0 91.0 92.0 93.0 91.0 92.0 101.0 93.0 92.0 91.0 08:05:48 INFO - PROCESS | 1645 | 08:05:48 INFO - PROCESS | 1645 | [#7] imaging-desaturate Cycles:10 Average:83.10 Median:83.50 stddev:1.20 (1.4%) stddev-sans-first:1.05 08:05:48 INFO - PROCESS | 1645 | Values: 85.0 83.0 82.0 82.0 83.0 83.0 84.0 84.0 81.0 84.0 08:05:48 INFO - PROCESS | 1645 | 08:05:48 INFO - PROCESS | 1645 | [#8] json-parse-financial Cycles:10 Average:55.20 Median:56.00 stddev:2.44 (4.4%) stddev-sans-first:2.46 08:05:48 INFO - PROCESS | 1645 | Values: 53.0 53.0 51.0 54.0 58.0 58.0 55.0 56.0 56.0 58.0 08:05:48 INFO - PROCESS | 1645 | 08:05:48 INFO - PROCESS | 1645 | [#9] json-stringify-tinderbox Cycles:10 Average:70.30 Median:71.50 stddev:3.06 (4.3%) stddev-sans-first:2.24 08:05:48 INFO - PROCESS | 1645 | Values: 64.0 73.0 68.0 75.0 72.0 69.0 69.0 71.0 70.0 72.0 08:05:48 INFO - PROCESS | 1645 | 08:05:48 INFO - PROCESS | 1645 | [#10] stanford-crypto-aes Cycles:10 Average:86.80 Median:84.00 stddev:9.82 (11.7%) stddev-sans-first:10.41 08:05:48 INFO - PROCESS | 1645 | Values: 86.0 84.0 84.0 80.0 90.0 73.0 86.0 106.0 100.0 79.0 08:05:48 INFO - PROCESS | 1645 | 08:05:48 INFO - PROCESS | 1645 | [#11] stanford-crypto-ccm Cycles:10 Average:144.00 Median:141.00 stddev:12.80 (9.1%) stddev-sans-first:13.49 08:05:48 INFO - PROCESS | 1645 | Values: 140.0 137.0 138.0 137.0 138.0 142.0 137.0 145.0 179.0 147.0 08:05:48 INFO - PROCESS | 1645 | 08:05:48 INFO - PROCESS | 1645 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.00 Median:165.50 stddev:5.42 (3.3%) stddev-sans-first:5.55 08:05:48 INFO - PROCESS | 1645 | Values: 163.0 163.0 164.0 164.0 180.0 165.0 166.0 173.0 165.0 167.0 08:05:48 INFO - PROCESS | 1645 | 08:05:48 INFO - PROCESS | 1645 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:68.00 stddev:1.26 (1.9%) stddev-sans-first:1.24 08:05:48 INFO - PROCESS | 1645 | Values: 69.0 66.0 68.0 68.0 68.0 70.0 67.0 67.0 67.0 66.0 08:05:48 INFO - PROCESS | 1645 | -------- Summary: end -------- 08:05:48 INFO - PROCESS | 1645 | 08:05:49 INFO - TEST-INFO | 1645: exit 0 08:05:49 INFO - TEST-OK | kraken | took 59359ms 08:05:49 INFO - SUITE-END | took 1179s 08:05:49 INFO - Completed test suite (00:19:39) 08:05:49 INFO - javascript benchmark 08:05:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3268.4116858965385, 3274.8362581870915, 3289.194147433878, 3314.486254630533, 3320.086976081579, 5555.055640541108, 5649.096144616862, 5655.123455864343, 5655.236543066685, 5674.148877668352, 5504.889902201963, 5511.53151982082, 5547.944520554794, 5583.748731307091, 5605.7197140143035, 5115.411727651322, 5123.846284611468, 5145.562627176694, 5154.329937108189, 5174.870628234286, 5078.542931136197, 5094.974525127369, 5112.335396398463, 5216.478352164791, 5219.765110570014, 5101.591872650179, 5150.407703114133, 5154.38147422308, 5167.302414174094, 5184.766685499161, 5178.818741344054, 5215.191645294985, 5216.1393370094265, 5221.0, 5273.947260527422, 4423.739234318204, 4881.267809828519, 5153.123968925254, 5182.144946083891, 5185.870353241162, 5484.780608775644, 5508.14623733322, 5538.252335934638, 5541.141123125882, 5571.777128914839, 6061.575689701723, 6095.329513753483, 6176.505879529672, 6185.350538193515, 6201.007838745779, 6237.0, 6238.9376106238815, 6243.56295059346, 6245.125682404467, 6259.530535209832, 5617.691026993513, 5628.240187574625, 5633.352164501135, 5658.236138121301, 5666.093425052054, 6313.652749098844, 6319.684015799192, 6329.177206963157, 6332.33510481402, 6333.429991300805, 6066.575339726265, 6070.7875224366935, 6081.148639190517, 6083.66539840313, 6085.57400981927, 6004.369541198112, 6066.75732970685, 6079.939200608026, 6123.632582045091, 6202.782902598387, 6055.182550355646, 6129.91263652097, 6145.446909778141, 6172.9074063889075, 6173.9073913891325, 6058.305572342586, 6166.1983942087245, 6166.753329866755, 6181.7527298908435, 6203.976343903206, 4786.664933454695, 4806.158922188673, 4832.0, 4862.149123903374, 4873.95126048735, 4349.195398851222, 4375.5405682403525, 4381.518033016429, 4383.07955329378, 4452.955470445254, 5705.800296989585, 5709.029464990961, 5729.541636669056, 5736.483716465538, 5743.913841292384, 6223.937760622426, 6230.345813689497, 6231.719572619243, 6238.937610623836, 6242.250929888442, 5536.612437129363, 5604.3835178130375, 5622.775088996476, 5668.489835914787, 5700.743466544015, 5286.81496147633, 5350.357957045102, 5357.598180136428, 5358.651687640368, 5388.865278367994, 6303.3066362700065, 6304.148939893055, 6308.526860485482, 6330.39861213184, 6338.302786693553, 4676.298555216745, 6071.241094863142, 6086.6348019119, 6110.741024289911, 6137.7851775187655, 6272.560920735687, 6300.369963003663, 6309.053641953744, 6320.304766475776, 6321.209848768904, 6058.606190597597, 6086.969565152146, 6123.540734444845, 6141.385861414002, 6142.0, 4909.4845041270155, 5870.119482077723, 5893.734781934909, 6180.196574445294, 6232.096346029891, 5723.771049157987, 5758.683272420057, 5767.423257674199, 5767.9134812977, 5772.278465191851, 5732.140178973021, 5753.367129615655, 5764.596478246483, 5766.048601980626, 5767.192593037062, 5553.278073850373, 5645.717714114196, 5687.203791469115, 5699.943000569941, 5704.714764261853, 5803.7388317524965, 5839.9780038492245, 6012.549058820518, 6026.0, 6065.696715164312, 5927.525797936241, 5928.962431574543, 5929.644221346733, 5950.583459157818, 5965.403459654], "value": 5596.273406584278, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [431.9589788609451, 462.88213963278685, 478.8491625138081, 511.88226707857194, 532.374460009489, 28944.710552894463, 34593.48109778355, 38479.80760096199, 39619.60380396195, 39710.0, 40607.39088913669, 41075.56235531757, 41173.588264117425, 41181.99999999993, 41259.38110928331, 9298.72103836886, 9424.387414818015, 9447.858282125773, 9453.763655908588, 9463.668771592995, 8238.752837414857, 8609.870851937227, 8612.612432440525, 8708.825823483527, 8716.564171791386, 8517.276031537296, 11147.777044459106, 11466.770664586704, 11565.248258863146, 11680.532778688885, 11196.720081997975, 11283.266587671775, 11299.661010169708, 11305.152113591514, 11310.886891131066, 8143.877841832377, 8533.359998000113, 11214.158938079676, 11287.266327688674, 11312.94343528277, 38581.22837543261, 39129.804350978346, 39235.019124522114, 40538.59461405378, 40561.59438405608, 3251.3334766372836, 3462.740294477924, 3791.229245774711, 3838.5393752749846, 3839.155385815116, 27829.721702783118, 28177.43645127086, 28275.434491310058, 28281.0, 29487.70512294871, 3123.4846250368887, 3176.824574907276, 3220.5652236947944, 3245.140037889961, 3285.0965984354257, 483923.90190245176, 489070.0, 493757.0, 493836.0, 496151.5192424051, 58929.70535147383, 59309.0, 59432.0, 59446.0, 59499.405005950255, 62708.686456567426, 62711.0, 62795.37204627895, 62904.685476572326, 62936.68531657404, 15512.689746205013, 16043.438479653156, 16055.357785688442, 16056.678866422606, 16085.356585736674, 12363.196392234657, 12420.75158496825, 12435.564755233523, 12436.191647543063, 12470.75058498825, 14544.0, 14982.400703971936, 16160.515184544247, 16277.023378597321, 16332.591685207728, 6445.6454894981225, 6522.184726909137, 6525.967370163214, 6549.9345006550275, 6597.901031484532, 829.2950991657164, 927.0682963621517, 962.3071388600197, 1022.0341777020793, 1085.571199376242, 28465.28836779097, 28770.28074298159, 28877.56683649747, 29632.851835740683, 29672.40655186884, 1013.5337744637402, 1033.4471057983942, 1038.4963292803136, 1051.0360782593013, 1121.433675993618, 14701.55895323142, 14717.190554519604, 14778.039427437398, 14952.40190392394, 15050.774238386433, 580247.0, 583619.0, 585112.0, 585465.0, 586856.0, 54094.0, 54338.184927226124, 54375.72812135914, 54399.0, 54530.90938181293, 1203.3141109567637, 1346.6364081697886, 1364.1337750528485, 1383.702503961653, 1385.5635474825397, 26121.477570448864, 27379.0, 28293.292667683472, 28403.289917752223, 30926.0722178335, 54113.0, 54828.0, 54851.0, 54950.45049549454, 55154.621134471956, 467417.0, 472489.0, 472952.6352368216, 474085.0, 474734.0, 25372.238832835046, 25624.743752562237, 25852.0, 25914.35214119662, 25957.870210648827, 36018.8199059003, 37649.81175094107, 38924.80537597294, 41116.0, 44926.0, 37103.62896371002, 37702.0, 38057.0, 38595.03512412212, 39076.0, 37366.626333737404, 37398.252034959696, 38138.42792358172, 38589.80705096456, 38640.0, 36086.09784755402, 36619.81690091533, 37205.62794372022, 37279.44080838844, 37563.0, 31889.521657175625, 33224.50163247505, 33719.32561348809, 33817.985460436226, 33846.3230735379, 24208.031678732652, 25168.370790729645, 25180.49639007246, 25332.366690832878, 25716.742832571435], "value": 19375.9841270985, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.29765385651706, 373.79254513744877, 379.829076915388, 387.96702280306175, 399.4168513969604, 19067.14197861098, 19581.608367832636, 20061.896595687267, 20514.487137821543, 20698.586028279424, 11599.362035088066, 12027.819582706266, 12042.036637069035, 12051.276923384603, 13078.607641770739, 4787.736674482902, 5125.948740512594, 5484.149956756689, 5486.643368181076, 5488.506034456898, 3893.5717071122153, 4108.979455102735, 4110.239605672945, 5356.196570514415, 5747.827565173031, 5198.9082292718385, 6006.0, 6076.5138788897, 6105.603135796159, 6109.969450152743, 5855.912161317584, 5973.283206015262, 6037.698115094227, 6054.757809687607, 6055.6063855849225, 5820.447057529528, 5835.416458354173, 5842.444967728102, 5843.0, 7026.1217347831525, 27387.178384648494, 28202.858985705145, 28248.15255542337, 28893.0, 29036.564451533242, 2342.7774361435636, 2443.621238708003, 2541.1233124571995, 3078.184281165493, 3314.685104915029, 15923.363065477368, 16643.41748038825, 16700.0, 17811.307925747034, 18096.276148954024, 1980.485073880787, 1990.4327266728912, 2012.7282816819688, 2077.4598604362973, 2086.447091520733, 131195.6880431193, 132103.0817801411, 136347.0, 137392.31303843416, 142293.0, 21115.788842111688, 21692.891535542538, 21757.67363489517, 22119.004644791024, 22446.775532244796, 34821.651783482346, 35229.47155792665, 35529.802317791524, 35568.644313556535, 35577.0, 8731.170538798804, 9755.804883902283, 9904.702858914245, 10650.680479585624, 10909.509072091616, 7648.885266721004, 8846.823063538823, 8880.600372983232, 8943.3739638226, 9058.094190580889, 9407.388519746331, 9808.852867206997, 9857.704268871945, 9898.10917017472, 9926.503674816231, 2678.437528119078, 3169.920751981219, 3529.2235708144167, 3609.2749973769637, 3778.0932576182067, 760.2245709376516, 796.5220867479466, 861.3324673378182, 874.226309715906, 896.2337201692459, 19793.0, 20443.59112817735, 20524.794752052287, 20529.38411847617, 20942.895285523475, 789.3409003482157, 875.1467319363696, 898.2544488075013, 928.4150984879618, 1010.0959641121302, 10470.319429237228, 10656.360618362924, 10844.45777711127, 10911.0, 10938.507767150339, 589999.0, 596105.0, 596637.0, 606595.0, 607044.0, 123818.76181238252, 128812.0, 130018.0, 130738.34630826785, 132644.0, 831.2850948184556, 972.3631021680669, 985.5023213277249, 998.336106489191, 1000.5147503460815, 17694.203760830536, 18075.90962045234, 18122.0, 18350.908245458686, 18464.630707386044, 51295.23057154071, 52597.94804103973, 52776.47223527716, 53046.0, 53621.73189134186, 74995.0, 75520.24479755132, 76444.0, 77045.61477192579, 77618.0, 7838.372930165687, 7989.400794940287, 8052.436329456882, 8257.628406721591, 8358.58207089631, 7894.565798881116, 11883.0, 11894.405279736151, 12477.0, 12586.622401327975, 9890.0, 10217.591296348062, 10623.575056997634, 10765.623203187852, 10815.134789217002, 10589.4705264738, 10599.205059620404, 10727.678169654922, 10749.247552671239, 10972.01251887334, 10510.632127875488, 10715.0, 11077.058450031514, 11202.327860328407, 11275.32348059119, 8005.199480051948, 8027.518348899085, 8090.7168249111, 8283.798849166958, 8321.791955201168, 10630.946845265724, 10648.520816563117, 10924.126069914546, 10964.725881852699, 11028.779424411627], "value": 10351.776404995298, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1462.707458508298, 1482.5404124721333, 1559.7660350947363, 1649.5051484554638, 1843.2166329310048, 8010.599470026505, 8413.747587572383, 8421.78945526362, 8429.91570084299, 8483.703070392537, 6161.353057928909, 7721.420893432987, 7969.203079692028, 8232.711855085074, 8254.298384637297, 2293.0827668932434, 2321.0019691532634, 2324.6861673674093, 2325.964945599209, 2334.684817549626, 2331.7901388875, 2337.275444612167, 2384.618461046233, 2387.9641805372935, 2391.940201494968, 2344.1443872986406, 2345.8827058647084, 2358.422186564294, 2364.680768096302, 2366.1008816649737, 2306.688597039395, 2316.837821352506, 2334.3113781434517, 2344.7655234476415, 2355.6702061711376, 2319.1419174905222, 2338.0, 2343.2970108967243, 2346.495503466746, 2351.4239011442137, 8269.710560130367, 8437.95781021091, 8450.915490845075, 8456.36577256702, 8460.78848028798, 4925.827596034122, 5014.172661510883, 5165.173572228463, 5167.715775632339, 5183.844484665466, 3102.0073576455543, 3131.561581378609, 3411.4200585900458, 3471.895843124685, 3503.7021853142514, 1011.8735158105237, 1048.6539441984128, 1073.264813602677, 1086.103964229506, 1087.0542627913765, 10842.457877106113, 10942.288751231225, 10945.124390048857, 10959.890401095887, 10962.890371096266, 4283.250431174532, 4494.73031618104, 4574.153781550357, 4690.742009189459, 4697.577218050392, 9400.48297343639, 9403.153716165578, 9427.717168484956, 9430.575624096795, 9431.292653051049, 4995.425526064549, 4998.700077995332, 5008.449070602231, 5009.273655319958, 5027.924581131286, 4218.0720241546815, 4395.934060989088, 4437.711548749321, 4559.52125026874, 4727.976360118178, 3273.7708360415018, 3750.0, 3787.8295476703615, 3848.5574158971517, 3900.278448487039, 1262.2237324045645, 1267.9746405071846, 1269.7587458382877, 1282.2755143343982, 1285.6914340558199, 756.4780301591884, 841.3731769831514, 892.7723430525305, 894.2086253665553, 912.1061359867367, 5576.275084239023, 5579.525740312038, 5662.603617746719, 5797.4782269595935, 5948.226730525091, 778.7507997440878, 939.3565407695751, 951.7573018880141, 968.8498282766183, 1019.8674172357546, 3457.22212502185, 3661.5240018797385, 3709.629037096323, 3814.4659747635355, 3947.842086316515, 9814.65648702292, 10259.897401026043, 10299.74250643725, 10307.94846025765, 10317.432541210155, 10161.644342447978, 10171.7457063574, 10173.135283500986, 10181.847272290921, 10182.949085254675, 6796.184457864989, 7410.073740782403, 7550.942867998485, 7769.92230077692, 7816.609169541386, 5689.6586204827845, 5730.5129064030025, 5738.5696072795545, 5843.883122337615, 5864.882702345843, 9285.303602229995, 9302.488363140092, 9398.248140148908, 9446.716598502057, 9516.904830951602, 9209.907900920905, 9584.90415095868, 9618.615255389706, 9644.75888102803, 9710.368826026286, 6607.240167380813, 6834.726610935507, 6841.828954276183, 7139.107611548557, 7315.487915846053, 7663.616819158909, 7797.7660670179985, 7910.2089791020435, 7978.042634883851, 7983.121856595886, 7040.0847889774, 7177.497575169688, 7178.389836863925, 7207.747728829466, 7293.927060729325, 6787.0498130262795, 6827.726890924506, 6989.7553585624255, 7004.898530440949, 7041.964790176016, 6560.409563139149, 6605.834854128685, 6670.433013193933, 6687.264400915993, 6704.296048914794, 4810.11012962603, 4825.276208568743, 4853.296272040605, 4879.609631229564, 4919.360483137312, 4766.475687674422, 4782.473927867863, 4849.515048495122, 4951.480094590016, 4960.131976903956], "value": 4409.798406181505, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.1471875716693, 304.5948887978988, 306.06649718359006, 314.86303457995774, 314.94016136933993, 4463.9553604463945, 4589.472210695771, 5039.488153553937, 5082.161443361845, 5086.160783470732, 4763.618910487161, 4773.260144677572, 4904.190808516591, 4915.090708218982, 5021.0, 3623.2934577757346, 3903.024243939022, 3915.7454765440298, 3923.058465968176, 3924.038610540422, 2979.597754303163, 3370.96629033709, 3514.5255390522207, 3901.6293452122004, 3910.9413358799643, 3029.8033925404015, 3663.725220608465, 3664.835082421284, 3873.593272706353, 3918.804059797013, 3486.267883744417, 3691.741578089535, 3872.9225415491533, 3903.1413089120347, 3904.5509766376945, 2864.269611249139, 2889.8844046238123, 3395.52462655226, 3422.435298175798, 3432.3696244284015, 3882.9223415531815, 4652.464966528859, 4933.654644174909, 4944.653874228806, 4955.430125535573, 4156.565984735262, 4416.227160246976, 4453.43927241834, 4458.398116254297, 4463.754493502856, 4234.894127646833, 5061.620378471629, 5306.548943339821, 5323.547498462596, 5329.520343169094, 3053.6946305369506, 3765.2093060457337, 3774.5093137892172, 3794.6015668354976, 3821.8280177392085, 5041.605237512189, 5470.863228419322, 5590.832275031756, 5647.519960803336, 5648.265725455664, 3320.966790332114, 3503.2993401319836, 3680.852765889388, 3711.6266981217123, 3725.8137093145233, 3745.7377983541437, 3749.250149970017, 3749.8687545935763, 3756.7933763642714, 3759.661630453272, 4165.937510937339, 4354.978225108917, 4358.191350589534, 4365.890852728708, 4370.147821174904, 5278.199696218717, 5366.570674346043, 5375.4624537546715, 5386.434424385461, 5399.892002159935, 1126.5381193710539, 1183.6685727996003, 1199.4962115911337, 1215.1918973882268, 1223.9351763965408, 709.5529816215854, 752.8531936272374, 784.2001158817974, 795.8527672380628, 829.307528213936, 4322.027543802619, 4515.593596576324, 4601.907961840744, 4735.573798358096, 4735.905281894343, 4732.95267047332, 4761.523847615211, 5005.774740136702, 5117.466853907265, 5151.583314588518, 4094.7306335036233, 4355.607995280377, 4491.956159947216, 4723.362346083235, 4726.550977657117, 2439.463138222908, 2543.351445381416, 2724.2644485989067, 2729.8362098274165, 2734.179746076169, 5207.843764687065, 5356.1697936819855, 5369.0, 5373.387983604981, 5403.270558474555, 4454.26283749338, 4679.742614156186, 4892.804287828518, 4898.730569818694, 5323.057084164252, 4729.621630269639, 5297.337832770903, 5336.199570064521, 5339.7063161526485, 5359.866003349869, 4230.0, 4804.807807687654, 5123.410807757155, 5157.853709237287, 5246.947530524799, 4584.472785629695, 4822.831200907952, 4888.095702295087, 5098.949010509996, 5127.717975511383, 4361.978190109029, 4732.73969931657, 4848.587870030946, 4945.629077819106, 5318.4681531848055, 3157.0371036833985, 3438.570178727659, 3482.2687235679778, 3493.528373669522, 3505.7370697197302, 4757.26262429324, 4881.829135980223, 5007.649464537448, 5079.866456088378, 5130.333056702605, 4951.77717002743, 4958.900821983612, 4961.181405068123, 4970.155073637418, 5050.368703912011, 4618.912262647514, 4958.669425370071, 4958.826441074545, 4968.0, 4994.051130285234, 3831.0, 4095.1776459475245, 4712.387389639325, 4848.3454733611925, 5352.839414817562, 4521.0, 5381.704006279692, 5393.0, 5423.728813559227, 5462.688954650935, 4446.244138496399, 5251.422343542131, 5296.443873393392, 5320.414754376938, 5363.839084827461], "value": 3769.4009913914338, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [605.6063558686853, 643.3759253524083, 734.067733977848, 752.7515919746485, 777.9533228006317, 13988.930055349721, 14128.505502307422, 14472.565823025327, 14576.125432474058, 14632.195229262386, 12730.0, 12937.611871643865, 13011.34943252836, 13019.73960520789, 13123.606291811237, 749.1197842535026, 820.7578764264526, 846.272205902923, 846.5513277962659, 849.8810166576684, 666.257123307511, 690.5235387582577, 690.533889624504, 819.848328059308, 847.9703210387638, 902.165496915354, 905.072300891588, 905.2395987370608, 908.4957848394172, 909.4543274035559, 837.8868852704928, 842.1830824100613, 893.718478679214, 895.632790555869, 896.4800415758845, 517.707495265174, 528.2287859724806, 529.076761051963, 531.1236459841293, 535.237286866214, 13218.272994985367, 13657.521986730517, 13722.039457237997, 13944.930275348659, 13984.160950343012, 13289.269090200036, 13534.323283835769, 13646.795298070538, 13757.243351615758, 13777.380017899219, 11010.669679909615, 11252.66242012741, 11295.20933534653, 11331.830022549668, 11335.659930202108, 3504.912377190565, 3573.2674801665858, 3585.0858031202138, 3751.7748935063983, 3766.0584853786554, 81927.77108343379, 88044.67932981011, 88419.67370489448, 88544.0, 88664.55667721685, 10100.141487973458, 10266.384016959006, 10421.791564168674, 10767.246292759588, 10978.835317470244, 18816.52958676044, 19724.90137549332, 20338.084786184656, 20409.285675001305, 20487.282945097148, 7575.848483030419, 7615.923840761632, 7724.729634462767, 7781.649825757854, 7828.765137045898, 4091.6317531422314, 4127.855525056669, 4222.852200173041, 4270.0605866709275, 4284.614384705392, 3295.2256219788333, 3412.334594753999, 3412.4540073588105, 3416.4533674611944, 3419.9487007694906, 588.9352171261157, 595.553334998751, 596.9880602388015, 609.9237595300588, 616.9907451388233, 560.9467100625434, 572.9512991395778, 646.6508085633706, 655.2301046270613, 659.4197106546209, 8119.9188008120345, 8743.825123497621, 9028.138096188412, 9029.63881444748, 9155.633774649074, 612.9478994285536, 645.9696783630103, 658.4467763748962, 664.3821246241042, 670.8960111182774, 4298.46269216348, 4452.8218871245435, 4462.352958820953, 4497.145542346943, 4563.771811409416, 67741.98387024198, 96565.0, 99973.0, 100481.49759251157, 103705.48147259216, 61025.0, 70749.0, 83152.58423707842, 89703.55148224217, 95108.52445737727, 59680.40319596922, 77476.0, 78297.21702783013, 83093.0, 83382.58308708417, 9280.164785169367, 9657.517124143906, 9690.85463718059, 9700.369475984038, 9720.562574684014, 31655.683443165275, 49983.75008124936, 50888.0, 52792.73603631957, 52998.73500632472, 65206.347936520026, 66969.99545006732, 68999.3100068993, 72767.63616181885, 76451.61774191093, 11480.655580332603, 11634.767304653691, 11946.0, 11996.700082498008, 12189.146759726735, 13735.126745990641, 13939.930300348433, 13991.23048232357, 14034.017618766587, 14947.85052149508, 12003.219790713576, 12253.69365765863, 12532.686682833002, 12828.422720977765, 13071.281079540717, 10177.389356638625, 10382.948085259526, 12855.971522278009, 12943.158694684815, 13018.479260829461, 12510.812337815121, 12657.303848288433, 12747.681307967376, 12823.807642885551, 13354.265515396746, 10709.105720132771, 10775.730606734895, 11285.37930413835, 11374.488148033193, 11380.986119288129, 11012.559497620005, 11123.27698699582, 11346.262492937933, 11466.426678666201, 11497.770044599229], "value": 7060.361998996165, "unit": "score"}], "name": "dromaeo_css", "value": 7133.312482682437, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 113.0, 113.0, 113.0, 114.0, 113.0, 113.0, 114.0, 118.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 109.0, 111.0, 143.0, 110.0, 109.0, 114.0, 110.0, 136.0, 110.0], "value": 119.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 184.0, 183.0, 185.0, 182.0, 184.0, 183.0, 183.0, 182.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 58.0, 90.0, 64.0, 61.0, 79.0, 84.0, 110.0, 60.0], "value": 74.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 70.0, 95.0, 69.0, 68.0, 69.0, 90.0, 68.0, 69.0, 93.0], "value": 77.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 91.0, 109.0, 93.0, 121.0, 135.0, 113.0, 86.0, 83.0], "value": 100.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 92.0, 93.0, 91.0, 92.0, 101.0, 93.0, 92.0, 91.0], "value": 92.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 82.0, 82.0, 83.0, 83.0, 84.0, 84.0, 81.0, 84.0], "value": 83.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 51.0, 54.0, 58.0, 58.0, 55.0, 56.0, 56.0, 58.0], "value": 55.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 73.0, 68.0, 75.0, 72.0, 69.0, 69.0, 71.0, 70.0, 72.0], "value": 70.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 84.0, 80.0, 90.0, 73.0, 86.0, 106.0, 100.0, 79.0], "value": 86.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 137.0, 138.0, 137.0, 138.0, 142.0, 137.0, 145.0, 179.0, 147.0], "value": 144.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 164.0, 164.0, 180.0, 165.0, 166.0, 173.0, 165.0, 167.0], "value": 167.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 68.0, 68.0, 68.0, 70.0, 67.0, 67.0, 67.0, 66.0], "value": 67.6, "unit": "score"}], "name": "kraken", "value": 1434.3999999999999, "alertThreshold": 2.0}]} 08:05:49 INFO - Return code: 0 08:05:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:05:49 INFO - # TBPL SUCCESS # 08:05:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:05:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:05:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:05:49 INFO - 2016-12-19 08:05:49.457 screenresolution[1649:10094] starting screenresolution argv=screenresolution get 08:05:49 INFO - 2016-12-19 08:05:49.460 screenresolution[1649:10094] Display 0: 1600x1200x32@60 08:05:49 INFO - 2016-12-19 08:05:49.466 screenresolution[1650:10097] starting screenresolution argv=screenresolution list 08:05:49 INFO - Available Modes on Display 0 08:05:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:05:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:05:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:05:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:05:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:05:49 INFO - Intel Iris: 08:05:49 INFO - Chipset Model: Intel Iris 08:05:49 INFO - Type: GPU 08:05:49 INFO - Bus: Built-In 08:05:49 INFO - VRAM (Dynamic, Max): 1536 MB 08:05:49 INFO - Vendor: Intel (0x8086) 08:05:49 INFO - Device ID: 0x0a2e 08:05:49 INFO - Revision ID: 0x0009 08:05:49 INFO - Displays: 08:05:49 INFO - Display: 08:05:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:05:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:05:49 INFO - Display Serial Number: 1600x1200 60 08:05:49 INFO - Main Display: Yes 08:05:49 INFO - Mirror: Off 08:05:49 INFO - Online: Yes 08:05:49 INFO - Rotation: Supported 08:05:49 INFO - Return code: 0 08:05:49 INFO - Running post-action listener: _resource_record_post_action 08:05:49 INFO - [mozharness: 2016-12-19 16:05:49.710735Z] Finished run-tests step (success) 08:05:49 INFO - Running post-run listener: _resource_record_post_run 08:05:49 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 37278720; Write bytes: 658501632; Read time: 237; Write time: 1878 08:05:49 INFO - TinderboxPrint: CPU usage
53.5% 08:05:49 INFO - TinderboxPrint: I/O read bytes / time
37,278,720 / 237 08:05:49 INFO - TinderboxPrint: I/O write bytes / time
658,501,632 / 1,878 08:05:49 INFO - TinderboxPrint: CPU idle
2,252.8 (46.5%) 08:05:49 INFO - TinderboxPrint: CPU system
966.6 (19.9%) 08:05:49 INFO - TinderboxPrint: CPU user
1,626.4 (33.6%) 08:05:49 INFO - TinderboxPrint: Swap in / out
386,326,528 / 0 08:05:49 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 345838080; Write bytes: 345839616; Read time: 27158; Write time: 707 08:05:49 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 35185664; Write bytes: 305514496; Read time: 217; Write time: 1143 08:05:50 INFO - Running post-run listener: _upload_blobber_files 08:05:50 INFO - Blob upload gear active. 08:05:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:05:50 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 08:05:50 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:05:50 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:05:50 INFO - (blobuploader) - INFO - Open directory for files ... 08:05:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:05:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:05:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:05:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:05:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:05:51 INFO - (blobuploader) - INFO - Done attempting. 08:05:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:05:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:05:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:05:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:05:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:05:52 INFO - (blobuploader) - INFO - Done attempting. 08:05:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:05:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:05:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:05:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:05:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:05:56 INFO - (blobuploader) - INFO - Done attempting. 08:05:56 INFO - (blobuploader) - INFO - Iteration through files over. 08:05:56 INFO - Return code: 0 08:05:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:05:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:05:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6529e6012cca728c4d5290fbce3ead7226f379d70933f1101fae58c2c26b69ca2ead3755326de5126ffe2082d1da0314270e8c971860f3832fecbd7696cc3560", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/87f5d6c1a84ba2ce8f273992b9a4402452804321662dcab52bc1d482b02387b4e803f4e7e1bcecf8f093c143af9d2d0226a9a43aa0f181dcb9691ada0d08f5da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/35de7de5df53b92615a0b03a386dd9e3cdd7a4ed1e23da4962bf0e1071b46a0c6d4e652476b3712110532ba93f3bfbeedbc6900c7ae22b96c0f44e18819b672c"} 08:05:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:05:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:05:56 INFO - Contents: 08:05:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6529e6012cca728c4d5290fbce3ead7226f379d70933f1101fae58c2c26b69ca2ead3755326de5126ffe2082d1da0314270e8c971860f3832fecbd7696cc3560", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/87f5d6c1a84ba2ce8f273992b9a4402452804321662dcab52bc1d482b02387b4e803f4e7e1bcecf8f093c143af9d2d0226a9a43aa0f181dcb9691ada0d08f5da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/35de7de5df53b92615a0b03a386dd9e3cdd7a4ed1e23da4962bf0e1071b46a0c6d4e652476b3712110532ba93f3bfbeedbc6900c7ae22b96c0f44e18819b672c"} 08:05:56 INFO - Running post-run listener: copy_logs_to_upload_dir 08:05:56 INFO - Copying logs to upload dir... 08:05:56 INFO - mkdir: /builds/slave/test/build/upload/logs 08:05:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.937054 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-12-19 08:05:56.256326) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-19 08:05:56.259843) ========= 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.xf42QG4GRr/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.w6zUBv3xza/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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/Mozilla-Aurora/sha512/6529e6012cca728c4d5290fbce3ead7226f379d70933f1101fae58c2c26b69ca2ead3755326de5126ffe2082d1da0314270e8c971860f3832fecbd7696cc3560", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/87f5d6c1a84ba2ce8f273992b9a4402452804321662dcab52bc1d482b02387b4e803f4e7e1bcecf8f093c143af9d2d0226a9a43aa0f181dcb9691ada0d08f5da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/35de7de5df53b92615a0b03a386dd9e3cdd7a4ed1e23da4962bf0e1071b46a0c6d4e652476b3712110532ba93f3bfbeedbc6900c7ae22b96c0f44e18819b672c"} build_url:https://queue.taskcluster.net/v1/task/FwRh5zyWRTuYXpJJ2MjuGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010901 build_url: 'https://queue.taskcluster.net/v1/task/FwRh5zyWRTuYXpJJ2MjuGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6529e6012cca728c4d5290fbce3ead7226f379d70933f1101fae58c2c26b69ca2ead3755326de5126ffe2082d1da0314270e8c971860f3832fecbd7696cc3560", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/87f5d6c1a84ba2ce8f273992b9a4402452804321662dcab52bc1d482b02387b4e803f4e7e1bcecf8f093c143af9d2d0226a9a43aa0f181dcb9691ada0d08f5da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/35de7de5df53b92615a0b03a386dd9e3cdd7a4ed1e23da4962bf0e1071b46a0c6d4e652476b3712110532ba93f3bfbeedbc6900c7ae22b96c0f44e18819b672c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-19 08:05:56.295520) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 08:05:56.296068) ========= 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.xf42QG4GRr/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.w6zUBv3xza/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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.004985 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 08:05:56.346369) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-19 08:05:56.346825) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-19 08:05:56.347189) ========= ========= Total master_lag: 0.18 =========