builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0016 starttime: 1483094451.22 results: success (0) buildid: 20161230004019 builduid: 1b9bbea94c0e42749c18886597f0541f revision: b213de949cddebe0a123654be580ba114b951020 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-30 02:40:51.220476) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-30 02:40:51.220943) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-30 02:40:51.221223) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-30 02:40:51.443660) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 02:40:51.444033) ========= 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.HqRzRZ31nq/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.89hKR3Tz7l/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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-30 02:40:51-- 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% 12.7M=0.001s 2016-12-30 02:40:51 (12.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.466987 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 02:40:51.943168) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 02:40:51.943479) ========= 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.HqRzRZ31nq/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.89hKR3Tz7l/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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.025880 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 02:40:51.991435) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 02:40:51.991779) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b213de949cddebe0a123654be580ba114b951020 --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 b213de949cddebe0a123654be580ba114b951020 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HqRzRZ31nq/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.89hKR3Tz7l/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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-30 02:40:52,077 truncating revision to first 12 chars 2016-12-30 02:40:52,077 Setting DEBUG logging. 2016-12-30 02:40:52,077 attempt 1/10 2016-12-30 02:40:52,077 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/b213de949cdd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-30 02:40:52,370 unpacking tar archive at: mozilla-aurora-b213de949cdd/testing/mozharness/ program finished with exit code 0 elapsedTime=0.571741 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 02:40:52.588301) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-30 02:40:52.588579) ========= script_repo_revision: b213de949cddebe0a123654be580ba114b951020 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-30 02:40:52.589024) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-30 02:40:52.589302) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-30 02:40:52.610361) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-12-30 02:40:52.610631) ========= /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.HqRzRZ31nq/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.89hKR3Tz7l/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 02:40:52 INFO - MultiFileLogger online at 20161230 02:40:52 in /builds/slave/test 02:40:52 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 02:40:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:40:52 INFO - {'append_to_log': False, 02:40:52 INFO - 'base_work_dir': '/builds/slave/test', 02:40:52 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:40:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:40:52 INFO - 'branch': 'Mozilla-Aurora', 02:40:52 INFO - 'buildbot_json_path': 'buildprops.json', 02:40:52 INFO - 'config_files': ('talos/mac_config.py',), 02:40:52 INFO - 'default_actions': ('clobber', 02:40:52 INFO - 'read-buildbot-config', 02:40:52 INFO - 'download-and-extract', 02:40:52 INFO - 'populate-webroot', 02:40:52 INFO - 'create-virtualenv', 02:40:52 INFO - 'install', 02:40:52 INFO - 'run-tests'), 02:40:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:40:52 INFO - 'download_minidump_stackwalk': True, 02:40:52 INFO - 'download_symbols': 'ondemand', 02:40:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:40:52 INFO - 'tooltool.py': '/tools/tooltool.py', 02:40:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:40:52 INFO - '/tools/misc-python/virtualenv.py')}, 02:40:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:40:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:40:52 INFO - 'installer_path': 'installer.exe', 02:40:52 INFO - 'log_level': 'info', 02:40:52 INFO - 'log_name': 'talos', 02:40:52 INFO - 'log_to_console': True, 02:40:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:40:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:40:52 INFO - 'opt_config_files': (), 02:40:52 INFO - 'pip_index': False, 02:40:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:40:52 INFO - 'cmd': ('bash', 02:40:52 INFO - '-c', 02:40:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:40:52 INFO - 'enabled': True, 02:40:52 INFO - 'halt_on_failure': False, 02:40:52 INFO - 'name': 'check_screen_resolution'},), 02:40:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:40:52 INFO - 'cmd': ('bash', 02:40:52 INFO - '-c', 02:40:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:40:52 INFO - 'enabled': True, 02:40:52 INFO - 'halt_on_failure': False, 02:40:52 INFO - 'name': 'check_screen_resolution'},), 02:40:52 INFO - 'run_cmd_checks_enabled': True, 02:40:52 INFO - 'sps_profile': False, 02:40:52 INFO - 'sps_profile_interval': 0, 02:40:52 INFO - 'suite': 'dromaeojs', 02:40:52 INFO - 'system_bits': '32', 02:40:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:40:52 INFO - 'title': 't-yosemite-r7-0016', 02:40:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:40:52 INFO - 'use_talos_json': True, 02:40:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:40:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:40:52 INFO - 'work_dir': 'build'} 02:40:52 INFO - [mozharness: 2016-12-30 10:40:52.813217Z] Running clobber step. 02:40:52 INFO - Running pre-action listener: _resource_record_pre_action 02:40:52 INFO - Running main action method: clobber 02:40:52 INFO - rmtree: /builds/slave/test/build 02:40:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:40:55 INFO - Running post-action listener: _resource_record_post_action 02:40:55 INFO - [mozharness: 2016-12-30 10:40:55.143301Z] Finished clobber step (success) 02:40:55 INFO - [mozharness: 2016-12-30 10:40:55.143452Z] Running read-buildbot-config step. 02:40:55 INFO - Running pre-action listener: _resource_record_pre_action 02:40:55 INFO - Running main action method: read_buildbot_config 02:40:55 INFO - Using buildbot properties: 02:40:55 INFO - { 02:40:55 INFO - "project": "", 02:40:55 INFO - "product": "firefox", 02:40:55 INFO - "script_repo_revision": "production", 02:40:55 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:40:55 INFO - "repository": "", 02:40:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:40:55 INFO - "buildid": "20161230004019", 02:40:55 INFO - "stage_platform": "macosx64", 02:40:55 INFO - "basedir": "/builds/slave/test", 02:40:55 INFO - "nightly_build": "True", 02:40:55 INFO - "buildnumber": 24, 02:40:55 INFO - "slavename": "t-yosemite-r7-0016", 02:40:55 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 02:40:55 INFO - "platform": "yosemite_r7", 02:40:55 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:40:55 INFO - "branch": "mozilla-aurora", 02:40:55 INFO - "repo_path": "releases/mozilla-aurora", 02:40:55 INFO - "revision": "b213de949cddebe0a123654be580ba114b951020", 02:40:55 INFO - "pgo_build": "False", 02:40:55 INFO - "builduid": "1b9bbea94c0e42749c18886597f0541f", 02:40:55 INFO - "slavebuilddir": "test" 02:40:55 INFO - } 02:40:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/DKKKlItpRyaJjiox7AGC8A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 02:40:55 INFO - Running post-action listener: _resource_record_post_action 02:40:55 INFO - [mozharness: 2016-12-30 10:40:55.146832Z] Finished read-buildbot-config step (success) 02:40:55 INFO - [mozharness: 2016-12-30 10:40:55.146937Z] Running download-and-extract step. 02:40:55 INFO - Running pre-action listener: _resource_record_pre_action 02:40:55 INFO - Running main action method: download_and_extract 02:40:55 INFO - mkdir: /builds/slave/test/build/tests 02:40:55 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')]} 02:40:55 INFO - trying https://queue.taskcluster.net/v1/task/DKKKlItpRyaJjiox7AGC8A/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 02:40:55 INFO - Downloading https://queue.taskcluster.net/v1/task/DKKKlItpRyaJjiox7AGC8A/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 02:40:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DKKKlItpRyaJjiox7AGC8A/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 02:40:56 INFO - Downloaded 1199 bytes. 02:40:56 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:40:56 INFO - Using the following test package requirements: 02:40:56 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:40:56 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:40:56 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 02:40:56 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:40:56 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 02:40:56 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:40:56 INFO - u'jsshell-mac.zip'], 02:40:56 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:40:56 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 02:40:56 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:40:56 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:40:56 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 02:40:56 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:40:56 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 02:40:56 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:40:56 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 02:40:56 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:40:56 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 02:40:56 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:40:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DKKKlItpRyaJjiox7AGC8A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:40:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DKKKlItpRyaJjiox7AGC8A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:40:56 INFO - Fetch https://queue.taskcluster.net/v1/task/DKKKlItpRyaJjiox7AGC8A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:40:56 INFO - Http code: 200 02:40:56 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 02:40:56 INFO - x-amz-cf-id: EDq7YUtbT2fRomTzRcMouxJK35TTa561hTmtCTCfsZZl6n_7NWIS8A== 02:40:56 INFO - x-amz-version-id: YeVVpBLvXw93mkdZYYMP.hqnPdD8OtEt 02:40:57 INFO - Expected file size: 16268467 02:40:57 INFO - Obtained file size: 16268467 02:41:03 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 02:41:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DKKKlItpRyaJjiox7AGC8A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:41:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DKKKlItpRyaJjiox7AGC8A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:41:03 INFO - Fetch https://queue.taskcluster.net/v1/task/DKKKlItpRyaJjiox7AGC8A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:41:04 INFO - Http code: 200 02:41:04 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 02:41:04 INFO - x-amz-cf-id: 9uVR2pqszyC-wOcElKGiVrCYavzuh7Z39EyW4LvT9GWZ7Pq6fz-AOw== 02:41:04 INFO - x-amz-version-id: YeVVpBLvXw93mkdZYYMP.hqnPdD8OtEt 02:41:04 INFO - Expected file size: 16268467 02:41:04 INFO - Obtained file size: 16268467 02:41:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DKKKlItpRyaJjiox7AGC8A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 02:41:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DKKKlItpRyaJjiox7AGC8A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:41:11 INFO - Fetch https://queue.taskcluster.net/v1/task/DKKKlItpRyaJjiox7AGC8A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 02:41:12 INFO - Http code: 200 02:41:12 INFO - via: 1.1 e30ae5b3d9f6779a9b8bc992faad0b09.cloudfront.net (CloudFront) 02:41:12 INFO - x-amz-cf-id: X51ZqSTJIayvAZys-6bpe_D5WeITY1w1P5YbIpwsVVeNVv40WqFURg== 02:41:12 INFO - x-amz-version-id: LryHjd6FidWRwXQdfB27qZFbgLkdxOPF 02:41:13 INFO - Expected file size: 14409038 02:41:13 INFO - Obtained file size: 14409038 02:41:13 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')]} 02:41:13 INFO - trying https://queue.taskcluster.net/v1/task/DKKKlItpRyaJjiox7AGC8A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:41:13 INFO - Downloading https://queue.taskcluster.net/v1/task/DKKKlItpRyaJjiox7AGC8A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 02:41:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DKKKlItpRyaJjiox7AGC8A/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 02:41:16 INFO - Downloaded 109952817 bytes. 02:41:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DKKKlItpRyaJjiox7AGC8A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:41:16 INFO - mkdir: /builds/slave/test/properties 02:41:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:41:16 INFO - Writing to file /builds/slave/test/properties/build_url 02:41:16 INFO - Contents: 02:41:16 INFO - build_url:https://queue.taskcluster.net/v1/task/DKKKlItpRyaJjiox7AGC8A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:41:16 INFO - Running post-action listener: _resource_record_post_action 02:41:16 INFO - Running post-action listener: set_extra_try_arguments 02:41:16 INFO - [mozharness: 2016-12-30 10:41:16.832887Z] Finished download-and-extract step (success) 02:41:16 INFO - [mozharness: 2016-12-30 10:41:16.833005Z] Running populate-webroot step. 02:41:16 INFO - Running pre-action listener: _resource_record_pre_action 02:41:16 INFO - Running main action method: populate_webroot 02:41:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:41:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:41:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:41:16 INFO - '--tppagecycles', 02:41:16 INFO - '7'], 02:41:16 INFO - 'tests': ['tsvgm']}}, 02:41:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:41:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:41:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:41:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:41:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:41:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:41:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:16 INFO - 'talos_options': ['--disable-e10s'], 02:41:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:41:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:41:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:16 INFO - 'talos_options': ['--disable-e10s'], 02:41:16 INFO - 'tests': ['damp', 'tps']}, 02:41:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:16 INFO - 'tests': ['damp', 'tps']}, 02:41:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:41:16 INFO - 'tests': ['dromaeo_dom']}, 02:41:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:41:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:41:16 INFO - 'tests': ['basic_compositor_video']}, 02:41:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:41:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:41:16 INFO - 'tests': ['a11yr', 02:41:16 INFO - 'ts_paint', 02:41:16 INFO - 'tpaint', 02:41:16 INFO - 'sessionrestore', 02:41:16 INFO - 'sessionrestore_no_auto_restore', 02:41:16 INFO - 'tabpaint']}, 02:41:16 INFO - 'other-e10s': {'tests': ['a11yr', 02:41:16 INFO - 'ts_paint', 02:41:16 INFO - 'tpaint', 02:41:16 INFO - 'sessionrestore', 02:41:16 INFO - 'sessionrestore_no_auto_restore', 02:41:16 INFO - 'tabpaint']}, 02:41:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:41:16 INFO - 'ts_paint', 02:41:16 INFO - 'tpaint', 02:41:16 INFO - 'sessionrestore', 02:41:16 INFO - 'sessionrestore_no_auto_restore', 02:41:16 INFO - 'tabpaint']}, 02:41:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:41:16 INFO - 'ts_paint', 02:41:16 INFO - 'tpaint', 02:41:16 INFO - 'sessionrestore', 02:41:16 INFO - 'sessionrestore_no_auto_restore', 02:41:16 INFO - 'tabpaint']}, 02:41:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:41:16 INFO - 'tests': ['a11yr', 02:41:16 INFO - 'ts_paint', 02:41:16 INFO - 'tpaint', 02:41:16 INFO - 'sessionrestore', 02:41:16 INFO - 'sessionrestore_no_auto_restore', 02:41:16 INFO - 'tabpaint']}, 02:41:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:41:16 INFO - 'tests': ['a11yr', 02:41:16 INFO - 'ts_paint', 02:41:16 INFO - 'tpaint', 02:41:16 INFO - 'sessionrestore', 02:41:16 INFO - 'sessionrestore_no_auto_restore', 02:41:16 INFO - 'tabpaint']}, 02:41:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:41:16 INFO - 'tests': ['tsvgx', 02:41:16 INFO - 'tsvgr_opacity', 02:41:16 INFO - 'tart', 02:41:16 INFO - 'tscrollx', 02:41:16 INFO - 'cart']}, 02:41:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:41:16 INFO - 'tsvgr_opacity', 02:41:16 INFO - 'tart', 02:41:16 INFO - 'tscrollx', 02:41:16 INFO - 'cart']}, 02:41:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:16 INFO - 'talos_options': ['--disable-e10s'], 02:41:16 INFO - 'tests': ['tp5o']}, 02:41:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:16 INFO - 'tests': ['tp5o']}, 02:41:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:16 INFO - 'talos_options': ['--disable-e10s', 02:41:16 INFO - '--xperf_path', 02:41:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:41:16 INFO - 'tests': ['tp5n']}, 02:41:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:16 INFO - 'talos_options': ['--xperf_path', 02:41:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:41:16 INFO - 'tests': ['tp5n']}}, 02:41:16 INFO - 'talos.zip': {'path': '', 02:41:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:41:16 INFO - Running post-action listener: _resource_record_post_action 02:41:16 INFO - [mozharness: 2016-12-30 10:41:16.851797Z] Finished populate-webroot step (success) 02:41:16 INFO - [mozharness: 2016-12-30 10:41:16.851927Z] Running create-virtualenv step. 02:41:16 INFO - Running pre-action listener: _resource_record_pre_action 02:41:16 INFO - Running main action method: create_virtualenv 02:41:16 INFO - Creating virtualenv /builds/slave/test/build/venv 02:41:16 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 02:41:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:41:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:41:16 INFO - Using real prefix '/tools/python27' 02:41:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:41:17 INFO - Installing distribute.............................................................................................................................................................................................done. 02:41:20 INFO - Installing pip.................done. 02:41:20 INFO - Return code: 0 02:41:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:41:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:41:20 INFO - Reading from file tmpfile_stdout 02:41:20 INFO - Output received: 02:41:20 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) 02:41:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:41:20 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')]} 02:41:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:20 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')]} 02:41:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:20 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 0x107818490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10778e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c384d0c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10762cd30>, '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 0x107492db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107624b20>, '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.HqRzRZ31nq/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.89hKR3Tz7l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 02:41:20 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 02:41:20 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 02:41:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HqRzRZ31nq/Render', 02:41:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:20 INFO - 'HOME': '/Users/cltbld', 02:41:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:20 INFO - 'LOGNAME': 'cltbld', 02:41:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:20 INFO - 'MOZ_NO_REMOTE': '1', 02:41:20 INFO - 'NO_EM_RESTART': '1', 02:41:20 INFO - 'PAGER': '/bin/cat', 02:41:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:20 INFO - 'PWD': '/builds/slave/test', 02:41:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:20 INFO - 'SHELL': '/bin/bash', 02:41:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.89hKR3Tz7l/Listeners', 02:41:20 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 02:41:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:20 INFO - 'USER': 'cltbld', 02:41:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:20 INFO - 'XPC_FLAGS': '0x0', 02:41:20 INFO - 'XPC_SERVICE_NAME': '0', 02:41:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:21 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 02:41:21 INFO - Cleaning up... 02:41:21 INFO - Return code: 0 02:41:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:41: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')]} 02:41:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41: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')]} 02:41:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41: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', '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 0x107818490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10778e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c384d0c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10762cd30>, '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 0x107492db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107624b20>, '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.HqRzRZ31nq/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.89hKR3Tz7l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 02:41: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', 'psutil>=3.1.1'] in /builds/slave/test/build 02:41: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 psutil>=3.1.1 02:41:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HqRzRZ31nq/Render', 02:41:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:21 INFO - 'HOME': '/Users/cltbld', 02:41:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:21 INFO - 'LOGNAME': 'cltbld', 02:41:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:21 INFO - 'MOZ_NO_REMOTE': '1', 02:41:21 INFO - 'NO_EM_RESTART': '1', 02:41:21 INFO - 'PAGER': '/bin/cat', 02:41:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:21 INFO - 'PWD': '/builds/slave/test', 02:41:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:21 INFO - 'SHELL': '/bin/bash', 02:41:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.89hKR3Tz7l/Listeners', 02:41:21 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 02:41:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:21 INFO - 'USER': 'cltbld', 02:41:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:21 INFO - 'XPC_FLAGS': '0x0', 02:41:21 INFO - 'XPC_SERVICE_NAME': '0', 02:41:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:21 INFO - Downloading/unpacking psutil>=3.1.1 02:41:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:41:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:41:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:41:25 INFO - Installing collected packages: psutil 02:41:25 INFO - Running setup.py install for psutil 02:41:25 INFO - building 'psutil._psutil_osx' extension 02:41:25 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 02:41:26 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 02:41:26 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 02:41:26 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 02:41:26 INFO - building 'psutil._psutil_posix' extension 02:41:26 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 02:41:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:41:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:41:26 INFO - ^ 02:41:26 INFO - 1 warning generated. 02:41:26 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 02:41:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:41:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:41:26 INFO - Successfully installed psutil 02:41:26 INFO - Cleaning up... 02:41:26 INFO - Return code: 0 02:41:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:41: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')]} 02:41:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41: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')]} 02:41:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107818490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10778e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c384d0c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10762cd30>, '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 0x107492db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107624b20>, '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.HqRzRZ31nq/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.89hKR3Tz7l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 02:41:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:41:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:41:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HqRzRZ31nq/Render', 02:41:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:26 INFO - 'HOME': '/Users/cltbld', 02:41:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:26 INFO - 'LOGNAME': 'cltbld', 02:41:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:26 INFO - 'MOZ_NO_REMOTE': '1', 02:41:26 INFO - 'NO_EM_RESTART': '1', 02:41:26 INFO - 'PAGER': '/bin/cat', 02:41:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:26 INFO - 'PWD': '/builds/slave/test', 02:41:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:26 INFO - 'SHELL': '/bin/bash', 02:41:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.89hKR3Tz7l/Listeners', 02:41:26 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 02:41:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:26 INFO - 'USER': 'cltbld', 02:41:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:26 INFO - 'XPC_FLAGS': '0x0', 02:41:26 INFO - 'XPC_SERVICE_NAME': '0', 02:41:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:41:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:41:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:41:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:41:29 INFO - Installing collected packages: mozsystemmonitor 02:41:29 INFO - Running setup.py install for mozsystemmonitor 02:41:29 INFO - Successfully installed mozsystemmonitor 02:41:29 INFO - Cleaning up... 02:41:30 INFO - Return code: 0 02:41:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107818490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10778e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c384d0c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10762cd30>, '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 0x107492db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107624b20>, '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.HqRzRZ31nq/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.89hKR3Tz7l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 02:41:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:41:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:41:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HqRzRZ31nq/Render', 02:41:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:30 INFO - 'HOME': '/Users/cltbld', 02:41:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:30 INFO - 'LOGNAME': 'cltbld', 02:41:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:30 INFO - 'MOZ_NO_REMOTE': '1', 02:41:30 INFO - 'NO_EM_RESTART': '1', 02:41:30 INFO - 'PAGER': '/bin/cat', 02:41:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:30 INFO - 'PWD': '/builds/slave/test', 02:41:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:30 INFO - 'SHELL': '/bin/bash', 02:41:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.89hKR3Tz7l/Listeners', 02:41:30 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 02:41:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:30 INFO - 'USER': 'cltbld', 02:41:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:30 INFO - 'XPC_FLAGS': '0x0', 02:41:30 INFO - 'XPC_SERVICE_NAME': '0', 02:41:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:30 INFO - Downloading/unpacking jsonschema==2.5.1 02:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:41:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:41:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:33 INFO - Downloading functools32-3.2.3-2.tar.gz 02:41:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:41:33 INFO - warning: no files found matching '*.txt' 02:41:33 INFO - no previously-included directories found matching 'build' 02:41:33 INFO - no previously-included directories found matching 'dist' 02:41:33 INFO - no previously-included directories found matching '.git*' 02:41:33 INFO - Installing collected packages: jsonschema, functools32 02:41:33 INFO - Running setup.py install for functools32 02:41:33 INFO - warning: no files found matching '*.txt' 02:41:33 INFO - no previously-included directories found matching 'build' 02:41:33 INFO - no previously-included directories found matching 'dist' 02:41:33 INFO - no previously-included directories found matching '.git*' 02:41:33 INFO - Successfully installed jsonschema functools32 02:41:33 INFO - Cleaning up... 02:41:33 INFO - Return code: 0 02:41:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:41:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:33 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 0x107818490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10778e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c384d0c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10762cd30>, '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 0x107492db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107624b20>, '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.HqRzRZ31nq/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.89hKR3Tz7l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 02:41:33 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 02:41:33 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 02:41:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HqRzRZ31nq/Render', 02:41:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:33 INFO - 'HOME': '/Users/cltbld', 02:41:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:33 INFO - 'LOGNAME': 'cltbld', 02:41:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:33 INFO - 'MOZ_NO_REMOTE': '1', 02:41:33 INFO - 'NO_EM_RESTART': '1', 02:41:33 INFO - 'PAGER': '/bin/cat', 02:41:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:33 INFO - 'PWD': '/builds/slave/test', 02:41:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:33 INFO - 'SHELL': '/bin/bash', 02:41:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.89hKR3Tz7l/Listeners', 02:41:33 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 02:41:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:33 INFO - 'USER': 'cltbld', 02:41:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:33 INFO - 'XPC_FLAGS': '0x0', 02:41:33 INFO - 'XPC_SERVICE_NAME': '0', 02:41:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:41:33 INFO - Cleaning up... 02:41:33 INFO - Return code: 0 02:41:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:41:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:33 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 0x107818490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10778e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c384d0c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10762cd30>, '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 0x107492db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107624b20>, '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.HqRzRZ31nq/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.89hKR3Tz7l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 02:41:33 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 02:41:33 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 02:41:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HqRzRZ31nq/Render', 02:41:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:33 INFO - 'HOME': '/Users/cltbld', 02:41:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:33 INFO - 'LOGNAME': 'cltbld', 02:41:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:33 INFO - 'MOZ_NO_REMOTE': '1', 02:41:33 INFO - 'NO_EM_RESTART': '1', 02:41:33 INFO - 'PAGER': '/bin/cat', 02:41:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:33 INFO - 'PWD': '/builds/slave/test', 02:41:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:33 INFO - 'SHELL': '/bin/bash', 02:41:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.89hKR3Tz7l/Listeners', 02:41:33 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 02:41:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:33 INFO - 'USER': 'cltbld', 02:41:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:33 INFO - 'XPC_FLAGS': '0x0', 02:41:33 INFO - 'XPC_SERVICE_NAME': '0', 02:41:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:34 INFO - Downloading/unpacking blobuploader==1.2.4 02:41:34 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 02:41:34 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 02:41:34 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 02:41:34 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 02:41:34 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 02:41:34 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 02:41:36 INFO - Downloading blobuploader-1.2.4.tar.gz 02:41:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:41:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:41:36 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 02:41:36 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 02:41:36 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 02:41:36 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 02:41:36 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 02:41:36 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 02:41:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:41:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:41:37 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 02:41:37 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 02:41:37 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 02:41:37 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 02:41:37 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 02:41:37 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 02:41:38 INFO - Downloading docopt-0.6.1.tar.gz 02:41:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:41:38 INFO - Installing collected packages: blobuploader, requests, docopt 02:41:38 INFO - Running setup.py install for blobuploader 02:41:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:41:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:41:38 INFO - Running setup.py install for requests 02:41:38 INFO - Running setup.py install for docopt 02:41:38 INFO - Successfully installed blobuploader requests docopt 02:41:38 INFO - Cleaning up... 02:41:39 INFO - Return code: 0 02:41:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:41:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:39 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 0x107818490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10778e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c384d0c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10762cd30>, '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 0x107492db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107624b20>, '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.HqRzRZ31nq/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.89hKR3Tz7l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 02:41:39 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 02:41:39 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 02:41:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HqRzRZ31nq/Render', 02:41:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:39 INFO - 'HOME': '/Users/cltbld', 02:41:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:39 INFO - 'LOGNAME': 'cltbld', 02:41:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:39 INFO - 'MOZ_NO_REMOTE': '1', 02:41:39 INFO - 'NO_EM_RESTART': '1', 02:41:39 INFO - 'PAGER': '/bin/cat', 02:41:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:39 INFO - 'PWD': '/builds/slave/test', 02:41:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:39 INFO - 'SHELL': '/bin/bash', 02:41:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.89hKR3Tz7l/Listeners', 02:41:39 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 02:41:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:39 INFO - 'USER': 'cltbld', 02:41:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:39 INFO - 'XPC_FLAGS': '0x0', 02:41:39 INFO - 'XPC_SERVICE_NAME': '0', 02:41:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:41:39 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-pYsq4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:41:39 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-_lhhpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:41:39 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-QCvVDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:41:39 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-GbG6GQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:41:39 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-ZkXr50-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:41:39 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-KpQSc6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:41:39 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-RUvPyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:41:40 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-9floO7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:41:40 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-wYQeiQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:41:40 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-IuYIXg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:41:40 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-5V35nH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:41:40 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-UyrhF3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:41:40 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-n4fgt_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:41:40 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-t4_WEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:41:40 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-9sgga8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:41:40 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-ljJ0vw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:41:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:41:41 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-FlNPf1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:41:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:41:41 INFO - Running setup.py install for manifestparser 02:41:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:41:41 INFO - Running setup.py install for mozcrash 02:41:41 INFO - Running setup.py install for mozdebug 02:41:41 INFO - Running setup.py install for mozdevice 02:41:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:41:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:41:41 INFO - Running setup.py install for mozfile 02:41:41 INFO - Running setup.py install for mozhttpd 02:41:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:41:42 INFO - Running setup.py install for mozinfo 02:41:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:41:42 INFO - Running setup.py install for mozInstall 02:41:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:41:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:41:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:41:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:41:42 INFO - Running setup.py install for mozleak 02:41:42 INFO - Running setup.py install for mozlog 02:41:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:41:42 INFO - Running setup.py install for moznetwork 02:41:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:41:42 INFO - Running setup.py install for mozprocess 02:41:42 INFO - Running setup.py install for mozprofile 02:41:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:41:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:41:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:41:43 INFO - Running setup.py install for mozrunner 02:41:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:41:43 INFO - Running setup.py install for mozscreenshot 02:41:43 INFO - Running setup.py install for moztest 02:41:43 INFO - Running setup.py install for mozversion 02:41:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:41:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:41:43 INFO - Cleaning up... 02:41:43 INFO - Return code: 0 02:41:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:41:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:43 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 0x107818490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10778e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c384d0c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10762cd30>, '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 0x107492db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107624b20>, '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.HqRzRZ31nq/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.89hKR3Tz7l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 02:41:43 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 02:41:43 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 02:41:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HqRzRZ31nq/Render', 02:41:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:43 INFO - 'HOME': '/Users/cltbld', 02:41:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:43 INFO - 'LOGNAME': 'cltbld', 02:41:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:43 INFO - 'MOZ_NO_REMOTE': '1', 02:41:43 INFO - 'NO_EM_RESTART': '1', 02:41:43 INFO - 'PAGER': '/bin/cat', 02:41:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:43 INFO - 'PWD': '/builds/slave/test', 02:41:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:43 INFO - 'SHELL': '/bin/bash', 02:41:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.89hKR3Tz7l/Listeners', 02:41:43 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 02:41:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:43 INFO - 'USER': 'cltbld', 02:41:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:43 INFO - 'XPC_FLAGS': '0x0', 02:41:43 INFO - 'XPC_SERVICE_NAME': '0', 02:41:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:41:44 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-48rTrz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:41:44 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)) 02:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:41:44 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-G9TmZW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:41:44 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)) 02:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:41:44 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-XTWrb2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:41:44 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)) 02:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:41:44 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-6H0uHf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:41:44 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)) 02:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:41:44 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-_vCL_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:41:44 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)) 02:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:41:44 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-as3sDQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:41:44 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)) 02:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:41:44 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-mDI4k3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:41:44 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)) 02:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:41:44 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-tSC6Wa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:41:44 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)) 02:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:41:44 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-Mkjee4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:41:44 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)) 02:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:41:44 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-rkugfb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:41:45 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)) 02:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:41:45 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-i7lhgP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:41:45 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)) 02:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:41:45 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-sGHuUq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:41:45 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)) 02:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:41:45 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-7HAaud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:41:45 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)) 02:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:41:45 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-0ooOnx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:41:45 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)) 02:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:41:45 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-iZ5JRo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:41:45 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)) 02:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:41:45 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-ARhcIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:41:45 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)) 02:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:41:45 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-rMmXNh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:41:45 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)) 02:41:45 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)) 02:41:45 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)) 02:41:45 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)) 02:41:45 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)) 02:41:45 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)) 02:41:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:41:45 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 02:41:45 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 02:41:45 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 02:41:45 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 02:41:45 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 02:41:45 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 02:41:48 INFO - Downloading blessings-1.6.tar.gz 02:41:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:41:48 INFO - Installing collected packages: blessings 02:41:48 INFO - Running setup.py install for blessings 02:41:48 INFO - Successfully installed blessings 02:41:48 INFO - Cleaning up... 02:41:48 INFO - Return code: 0 02:41:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:41:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:41:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:41:49 INFO - Reading from file tmpfile_stdout 02:41:49 INFO - Current package versions: 02:41:49 INFO - blessings == 1.6 02:41:49 INFO - blobuploader == 1.2.4 02:41:49 INFO - docopt == 0.6.1 02:41:49 INFO - functools32 == 3.2.3-2 02:41:49 INFO - jsonschema == 2.5.1 02:41:49 INFO - manifestparser == 1.1 02:41:49 INFO - mozInstall == 1.12 02:41:49 INFO - mozcrash == 1.0 02:41:49 INFO - mozdebug == 0.1 02:41:49 INFO - mozdevice == 0.48 02:41:49 INFO - mozfile == 1.2 02:41:49 INFO - mozhttpd == 0.7 02:41:49 INFO - mozinfo == 0.9 02:41:49 INFO - mozleak == 0.1 02:41:49 INFO - mozlog == 3.4 02:41:49 INFO - moznetwork == 0.27 02:41:49 INFO - mozprocess == 0.23 02:41:49 INFO - mozprofile == 0.28 02:41:49 INFO - mozrunner == 6.13 02:41:49 INFO - mozscreenshot == 0.1 02:41:49 INFO - mozsystemmonitor == 0.3 02:41:49 INFO - moztest == 0.7 02:41:49 INFO - mozversion == 1.4 02:41:49 INFO - psutil == 3.1.1 02:41:49 INFO - requests == 1.2.3 02:41:49 INFO - wsgiref == 0.1.2 02:41:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:41:49 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')]} 02:41:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:49 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')]} 02:41:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:49 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 0x107818490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10778e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c384d0c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10762cd30>, '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 0x107492db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107624b20>, '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.HqRzRZ31nq/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.89hKR3Tz7l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 02:41:49 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 02:41:49 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 02:41:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HqRzRZ31nq/Render', 02:41:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:49 INFO - 'HOME': '/Users/cltbld', 02:41:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:49 INFO - 'LOGNAME': 'cltbld', 02:41:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:49 INFO - 'MOZ_NO_REMOTE': '1', 02:41:49 INFO - 'NO_EM_RESTART': '1', 02:41:49 INFO - 'PAGER': '/bin/cat', 02:41:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:49 INFO - 'PWD': '/builds/slave/test', 02:41:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:49 INFO - 'SHELL': '/bin/bash', 02:41:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.89hKR3Tz7l/Listeners', 02:41:49 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 02:41:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:49 INFO - 'USER': 'cltbld', 02:41:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:49 INFO - 'XPC_FLAGS': '0x0', 02:41:49 INFO - 'XPC_SERVICE_NAME': '0', 02:41:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:49 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)) 02:41:49 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)) 02:41:49 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)) 02:41:49 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)) 02:41:49 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)) 02:41:49 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)) 02:41:49 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)) 02:41:49 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)) 02:41:49 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)) 02:41:49 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)) 02:41:49 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)) 02:41:49 INFO - Cleaning up... 02:41:49 INFO - Return code: 0 02:41:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:41:49 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')]} 02:41:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:49 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')]} 02:41:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:49 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 0x107818490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10778e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c384d0c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10762cd30>, '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 0x107492db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107624b20>, '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.HqRzRZ31nq/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.89hKR3Tz7l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 02:41:49 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 02:41:49 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 02:41:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HqRzRZ31nq/Render', 02:41:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:49 INFO - 'HOME': '/Users/cltbld', 02:41:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:49 INFO - 'LOGNAME': 'cltbld', 02:41:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:49 INFO - 'MOZ_NO_REMOTE': '1', 02:41:49 INFO - 'NO_EM_RESTART': '1', 02:41:49 INFO - 'PAGER': '/bin/cat', 02:41:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:49 INFO - 'PWD': '/builds/slave/test', 02:41:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:49 INFO - 'SHELL': '/bin/bash', 02:41:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.89hKR3Tz7l/Listeners', 02:41:49 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 02:41:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:49 INFO - 'USER': 'cltbld', 02:41:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:49 INFO - 'XPC_FLAGS': '0x0', 02:41:49 INFO - 'XPC_SERVICE_NAME': '0', 02:41:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:41:49 INFO - Cleaning up... 02:41:49 INFO - Return code: 0 02:41:49 INFO - Running post-action listener: _resource_record_post_action 02:41:49 INFO - Running post-action listener: _start_resource_monitoring 02:41:49 INFO - Starting resource monitoring. 02:41:49 INFO - [mozharness: 2016-12-30 10:41:49.702668Z] Finished create-virtualenv step (success) 02:41:49 INFO - [mozharness: 2016-12-30 10:41:49.703612Z] Running install step. 02:41:49 INFO - Running pre-action listener: _resource_record_pre_action 02:41:49 INFO - Running main action method: install 02:41:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:41:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:41:49 INFO - Reading from file tmpfile_stdout 02:41:49 INFO - Detecting whether we're running mozinstall >=1.0... 02:41:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:41:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:41:50 INFO - Reading from file tmpfile_stdout 02:41:50 INFO - Output received: 02:41:50 INFO - Usage: mozinstall [options] installer 02:41:50 INFO - Options: 02:41:50 INFO - -h, --help show this help message and exit 02:41:50 INFO - -d DEST, --destination=DEST 02:41:50 INFO - Directory to install application into. [default: 02:41:50 INFO - "/builds/slave/test"] 02:41:50 INFO - --app=APP Application being installed. [default: firefox] 02:41:50 INFO - mkdir: /builds/slave/test/build/application 02:41:50 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'] 02:41:50 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 02:42:20 INFO - Reading from file tmpfile_stdout 02:42:20 INFO - Output received: 02:42:20 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:42:20 INFO - Running post-action listener: _resource_record_post_action 02:42:20 INFO - [mozharness: 2016-12-30 10:42:20.976121Z] Finished install step (success) 02:42:20 INFO - [mozharness: 2016-12-30 10:42:20.976269Z] Running run-tests step. 02:42:20 INFO - Running pre-action listener: _resource_record_pre_action 02:42:20 INFO - Running main action method: run_tests 02:42:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:42:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:42:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:42:20 INFO - 2016-12-30 02:42:20.990 screenresolution[1626:8736] starting screenresolution argv=screenresolution get 02:42:20 INFO - 2016-12-30 02:42:20.993 screenresolution[1626:8736] Display 0: 1600x1200x32@60 02:42:21 INFO - 2016-12-30 02:42:20.999 screenresolution[1627:8738] starting screenresolution argv=screenresolution list 02:42:21 INFO - Available Modes on Display 0 02:42:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:42:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:42:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:42:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:42:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:42:21 INFO - Intel Iris: 02:42:21 INFO - Chipset Model: Intel Iris 02:42:21 INFO - Type: GPU 02:42:21 INFO - Bus: Built-In 02:42:21 INFO - VRAM (Dynamic, Max): 1536 MB 02:42:21 INFO - Vendor: Intel (0x8086) 02:42:21 INFO - Device ID: 0x0a2e 02:42:21 INFO - Revision ID: 0x0009 02:42:21 INFO - Displays: 02:42:21 INFO - Display: 02:42:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:42:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:42:21 INFO - Display Serial Number: 1600x1200 60 02:42:21 INFO - Main Display: Yes 02:42:21 INFO - Mirror: Off 02:42:21 INFO - Online: Yes 02:42:21 INFO - Rotation: Supported 02:42:21 INFO - Return code: 0 02:42:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:42:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:42:21 INFO - Python 2.7.3 02:42:21 INFO - Return code: 0 02:42:21 INFO - grabbing minidump binary from tooltool 02:42: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')]} 02:42:21 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 0x10762cd30>, '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 0x107492db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107624b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:42:21 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 02:42:21 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 02:42:21 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 02:42:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:42:21 INFO - Return code: 0 02:42:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:42:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:42:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:42:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:42:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:42:21 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/DKKKlItpRyaJjiox7AGC8A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0016', '--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 02:42:21 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/DKKKlItpRyaJjiox7AGC8A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0016 --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 02:42:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HqRzRZ31nq/Render', 02:42:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:21 INFO - 'HOME': '/Users/cltbld', 02:42:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:21 INFO - 'LOGNAME': 'cltbld', 02:42:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:42:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:42:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:21 INFO - 'MOZ_NO_REMOTE': '1', 02:42:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:42:21 INFO - 'NO_EM_RESTART': '1', 02:42:21 INFO - 'PAGER': '/bin/cat', 02:42:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:21 INFO - 'PWD': '/builds/slave/test', 02:42:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:42:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:21 INFO - 'SHELL': '/bin/bash', 02:42:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.89hKR3Tz7l/Listeners', 02:42:21 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 02:42:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:21 INFO - 'USER': 'cltbld', 02:42:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:21 INFO - 'XPC_FLAGS': '0x0', 02:42:21 INFO - 'XPC_SERVICE_NAME': '0', 02:42:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:21 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/DKKKlItpRyaJjiox7AGC8A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0016', '--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 02:42:21 INFO - mozversion application_buildid: 20161230004019 02:42:21 INFO - mozversion application_changeset: b213de949cddebe0a123654be580ba114b951020 02:42:21 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:42:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:42:21 INFO - mozversion application_name: Firefox 02:42:21 INFO - mozversion application_remotingname: firefox-dev 02:42:21 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:42:21 INFO - mozversion application_vendor: Mozilla 02:42:21 INFO - mozversion application_version: 52.0a2 02:42:21 INFO - mozversion platform_buildid: 20161230004019 02:42:21 INFO - mozversion platform_changeset: b213de949cddebe0a123654be580ba114b951020 02:42:21 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:42:21 INFO - mozversion platform_version: 52.0a2 02:42:21 INFO - using testdate: 1483094541 02:42:21 INFO - actual date: 1483094541 02:42:21 INFO - starting webserver on 'localhost:49244' 02:42:21 INFO - SUITE-START | Running 2 tests 02:42:21 INFO - TEST-START | dromaeo_css 02:42:21 INFO - operating with platform_type : mac_ 02:42:21 INFO - Initialising browser for dromaeo_css test... 02:42:21 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmp3Dbsa9/profile http://localhost:49244/getInfo.html) 02:42:24 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 02:42:24 INFO - PROCESS | 1637 | colorDepth:24 02:42:24 INFO - PROCESS | 1637 | Browser inner width/height: 1024/689 02:42:24 INFO - PROCESS | 1637 | __metrics 02:42:24 INFO - PROCESS | 1637 | 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" 02:42:24 INFO - PROCESS | 1637 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:42:25 INFO - TEST-INFO | 1637: exit 0 02:42:25 INFO - Browser initialized. 02:42:25 INFO - Running cycle 1/1 for dromaeo_css test... 02:42:25 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmp3Dbsa9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:42:37 INFO - PROCESS | 1641 | RSS: Main: 222482432 02:42:37 INFO - PROCESS | 1641 | 02:43:28 INFO - PROCESS | 1641 | 1483094608894 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] 02:45:42 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 02:45:44 INFO - PROCESS | 1641 | RSS: Main: 421142528 02:45:44 INFO - PROCESS | 1641 | 02:48:47 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 02:48:48 INFO - PROCESS | 1641 | RSS: Main: 350351360 02:48:48 INFO - PROCESS | 1641 | 02:51:50 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 02:51:51 INFO - PROCESS | 1641 | RSS: Main: 318103552 02:51:51 INFO - PROCESS | 1641 | 02:54:53 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 02:54:54 INFO - PROCESS | 1641 | RSS: Main: 318517248 02:54:54 INFO - PROCESS | 1641 | 02:57:57 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 02:57:58 INFO - PROCESS | 1641 | RSS: Main: 308985856 02:57:58 INFO - PROCESS | 1641 | 03:01:01 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 03:01:01 INFO - PROCESS | 1641 | RSS: Main: 412823552 03:01:01 INFO - PROCESS | 1641 | 03:01:01 INFO - PROCESS | 1641 | __start_tp_report 03:01:01 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 03:01:01 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 03:01:01 INFO - PROCESS | 1641 | |i|pagename|runs| 03:01:01 INFO - PROCESS | 1641 | |0;dojo.html;3273.9345213095735;3316.121227874614;3346.2136397946465;3355.3121610069925;3393.981805458362;5725.914111288334;5756.194132821408;5768.653880767157;5790.855228619286;5792.131180322948;5754.108113242443;5777.393373695754;5802.970985145068;5815.941840581593;5823;5220.895582088356;5257.290265814124;5263.921041184385;5286.286351342577;5322.8137015204475;5218.1650935850275;5220.086484865153;5228.058949389108;5321.281626980347;5322.8137015204475;5229.189475631283;5273.472652734734;5281.049411106;5283.656562323456;5297.231901374298;5270.446603106676;5300.443453437372;5324.467553244684;5331.093714068579;5341.359036915556;5112.769925353368;5196.0647083524955;5283;5285.312909321802;5309.65487243328;5635.8591035224035;5664.915026274609;5670.979223739725;5695.288088988877;5706.258186435778;6113.658745237732;6206.34833342497;6207.81376558704;6228.626282423069;6230.221222347207;5852.385499522531;5966.194563733927;6324.525660575475;6358.523110766711;6377.681115944184;5665.631733937281;5717.399673034354;5758.1362795581;5807.593468457168;5807.767689292381;6319.778807741799;6323.841903952438;6334.398232167937;6363.363663633692;6372.776952806629;6165.445109940127;6179.5056395488255;6180.4437600615265;6183.96908015466;6198.132261483396;5194.142966410575;6093.39066093396;6106.511479081662;6127.617148554337;6214.25428948529;6113.024264983944;6128.233970753656;6137.285055221596;6137.938620613826;6168.383161683796;6215.595986260879;6255.812325630239;6284.12022316876;6305.306416294204;6305.495560355161;4940.802367905316;4954.925676114861;4963.205887058126;4966.90066198674;4987.650864439454;4375.081232941055;4388.4953230378915;4424.269995450714;4470.552944705503;4472.955270447319;5728.226689396962;5738;5744.569157313218;5745.109508026263;5746.02317606008;6271.184745982993;6281.089242059968;6286.402791734778;6287.685615719287;6288.245410550763;5666.376698563155;5734.369219385864;5736.1682556029145;5740.454656807597;5753.309602847685;5363.570914326844;5392.40683524812;5400.594955378362;5402.459754024566;5410.8917821643345;6228.719707613259;6240.625562466266;6272.8118156455375;6280.214973128359;6297.52768542352;6247.937520624736;6307.05394190875;6327.715252813542;6329.715162817776;6342.39747224013;6177.536684748572;6276.435120839146;6291.150694656163;6304.432601065742;6306.0225665022;6070.514358851369;6100.694965251809;6220.502359811114;6226.62640241587;6243.562950593415;6168.722407491763;6191.349908259568;6221.253449586078;6247.875042499034;6256.7810126645345;6151.446369826737;6162.815115546541;6182.536309776875;6187.752489900354;6191.628502289877;6034.974054410848;6096.177016102769;6126.154810528965;6150.477209437069;6172.22847144107;5800.56495762811;5812.651240925558;5866.5893387462465;5971.671558064349;6024.246969128859;6072.089186622042;6078.483328917092;6094.54290928191;6099;6103.3591472894705;5982.910256346071;5993.460588547052;6006.759729610767;6007.759689612367;6053.182820319201 03:01:01 INFO - PROCESS | 1641 | |1;ext.html;453.88571058052474;500.0548962460949;517.5574883474629;530.2417542913632;555.558326143657;30030;30734.69265307349;39057.414138787906;39952.60047399525;41595;41084.58915410845;41317;41337;41338.793306033505;41416;9306.674266400678;9482.857757133648;9499.002604726507;9500.762480937998;9504.619815207418;8545.487270763773;8757.649694012232;11183.608573699923;11409.771804563903;11453.427328633536;11399.772004559904;11446.771064578703;11457.770844583103;11464.14018948578;11470.655880323604;11337.659870203866;11411.03006244466;11413.315201087962;11418.143639227092;11439.256448330914;8477.406581539295;8719.782005449852;11001;11327.660170194908;11343.319400835977;38951.63669271562;39275.803620982;39324.60675393238;39412;39490;3209.5667084943702;3214.0839860639608;3281.212508997847;3314.5028245763087;3390.525326454274;29025;29259.56110658342;29518.852405737834;29550.408991820044;29752.70247297521;3057.149369246166;3108.8445577721022;3159.573457583243;3168.9207769805707;3229.4832826747606;473888;473921;475089;477016.9999999965;481512;58874;59275.40724592699;59442.702786486654;59446.70276648589;59458.40541594615;55012.62468438241;63198;63233.68383158055;63267.683661581395;63359;15638.687226255639;16310.592235193977;16346;16364.918175409046;16371.181440927907;11223.326600403838;11501.597444089417;12588.748225035448;12608.369581520887;12614.243145411305;15260.465883694018;16845.83154168467;17050.74423883643;17304.394346197823;17613.38353157659;6176.085413521822;6299.968500157562;6350.491960643138;6367.204099487564;6367.649779262092;820.9055958564722;973.732223638491;975.9707208783748;997.536145692242;1037.9377237365782;28314;28598.285042874093;28990.727372855068;29303;29702.108936731933;984.0749695286407;1007.3754272351189;1007.4106647611229;1019.9949000254951;1089.5097206257217;14558.85441145575;15029.699406011818;15166.14683301333;15301.770473442908;15467;543679;649121;649205;649206;651218;56150.15774763382;56194;56218;56260.71869640707;56263.71868140633;1203.7472130852443;1244.813278008306;1310.351376068852;1336.8462626797848;1351.5472316773992;28723.994660186792;29362.265943351587;29428.969986050386;30038;30094.548581771727;47642;48089.278660819415;48162.518374817206;48272.275915861996;48313;423981;426961;427554;428285;428562;37556.436653450764;39156.80421597874;39184.412233817086;39241.82274531769;39274.410883837336;44640.77679611581;45043.77478112588;45345;45581.64937192843;46118.847028824544;36271.818640906626;36652.083697907765;36686.69819716238;36790.8160459196;36940.26119477649;32230.19424514312;36728.63271367252;37405.0648733773;37464.625353746116;37599.30803113812;36513.81743091268;36600.81699591485;36874.70938517139;37124.6287537132;37386.43920341251;31074;32936;33069.66930330666;33098.83450582732;33129.83435082809;24362.51274974455;24865.005399783808;24883.75116248887;25052.49895002126;25274.36814079663 03:01:01 INFO - PROCESS | 1641 | |2;jquery.html;351.7854108993515;366.43568903888007;369.52516016918264;379.7930128080197;395.8950878017324;14740.852591474082;14904.403823847048;15656.921715391436;16747.330106795744;19764.20943162272;18621.534461638465;18793;19158.13788379523;19307.51731206717;19385;5226.216067589863;5497.890042199154;5499.1476321170085;5507.421720719327;5513.889722205554;4356.607905288523;5421.457854214566;6046.213992181032;6092.939070609304;6104.206453161083;6039.939600604003;6048.45563899249;6050.001749711292;6054.212952316193;6056.424639659225;5261.184516399945;5361.812336568201;5408.432114627986;5410.026195284847;5412.161115027177;5022.4726403727445;5248.134057880445;5254;5277.261183434322;5281.577473802086;24161.275161745176;24417.87791061051;24602.29374768774;24903.501929961298;24987.750122498906;2203.7796220377995;2477.913273035435;2617.9345516362055;2721.6053672217618;2723.400851812618;7352.852942941165;9614.855777163348;9893.604255829758;10094.596216151418;10234.795304093876;1776.289484206315;1872.8595355348268;1886.3869242496107;1900.8859468431938;1920.711893216015;83979.5801020991;92918.67703307404;105172;108569;112001.43999280033;8556.486610803371;11024;11421.37182454973;11596.826047609293;11627.825582616098;14083.788743168861;14085.85914140866;14510.854891451161;14545.709085818437;14624.926875365554;7051.5063945523325;7052.823679408056;8330.416870819106;8507.914920850711;8647.048824629286;8413.326933845277;8426.620802063922;8430.32557395407;8582.485050896967;8617.396782225183;3946.8356834733822;4189.329707246887;4405.07493426384;4569.588737013684;4577.176108300538;1736.5484973906875;1827.5431142214447;1832.1114259584092;1887.8112188781286;1890.4990177602947;757.5492581913752;892.5894088719132;893.6961433112773;899.7480705402497;919.595378033663;19219.80780192208;19458.513537161685;20715.689264661043;20810.113699293022;21135.577288454144;888.6090120347027;905.5789058088021;913.6254135804288;949.4540639132526;973.4256788494822;10300.433476158883;10642.73393165177;10756.300840445347;10801.243912926177;10912.345259284468;586989;587448.1255187478;594999;606720.6639667999;606817;98720;124449;129905.35047324703;131221;133264.333678331;902.5036230073433;943.0852073488568;963.3882484622176;963.5086106085946;965.5703212070562;17772.377966771102;17847.553811154306;18130;18155.909220453814;18182.45452636423;36900.81549592235;44844.77577612222;48861;53793.462065378844;54567.90864182615;72766.63616682094;76673.84989225054;77160;77221.61389193017;77814;7055.823604409726;7329.377002954595;7341.926580734124;7569.97805296278;7808.84382312362;10651;11837.408129593314;12678.683032924251;12853.293068881301;12989.48042078344;10878.836817447904;10942.835857461985;10951.671449856518;11203.327800332007;11316.20786544934;10729.731756706145;10878.347299161758;11008.504617292081;11276.774464510829;11377.772444551229;10924.579804625348;11007.339559626447;11399.544018239178;11482.253653512495;11535.250208736405;7037.964810175917;7363.190049094703;7373.5575865448245;7497.2877576630135;7984.24149705777;9095.954520227357;10431.739206519898;10799.730006749895;10974.945125274322;11035.130865975065 03:01:01 INFO - PROCESS | 1641 | |3;mootools.html;1379.7651102263478;1463.6560408304053;1468.1851572377334;1783.6878546254402;1805.8555315574756;7899.644515996787;8202;8231.91768082319;8252;8252.628631711572;7336.3397294243505;7642.235776422355;7932.206779322065;8162.14297498763;8174.836503269931;2303.7465878753324;2335.6963594732747;2338.4621537046405;2341.438054866828;2344.0155134843408;2323.825713071518;2353.1763882641026;2370.3244575296044;2374.3351861478814;2376.9168079117235;2310.549442858643;2311.919082832101;2333.7782910623464;2335.5095429959642;2346.296111166652;2302.5625131225015;2306.5848147333472;2323.0127195941827;2329.94175145621;2337.2520793346134;2337.4039619896816;2347.941301467477;2355.611324131516;2356.422676444265;2362.893669784864;8198.754037378889;8264.297534709556;8334.333253339719;8363.874541881876;8376.288015518689;4949.653524253304;4997;5148.304978827848;5154.4072431670465;5159.793608255665;3322.4517954537473;3386.9153271168416;3484.390231709466;3517.103138699615;3522.964770352289;913.4701872913694;1037.6435077365159;1048.895110488945;1067.0449947297204;1091.6070214722772;10741.892581074168;10871.728206794814;10875.836862446991;10879.292845965067;10890.61882834097;4454.554544545519;4609.631229501631;4711.269753188261;4730.88172795676;4739.3601863748645;9274.562442821238;9292.535373231447;9297.860532092023;9309.115634014757;9316.114969077928;5171.689698618095;5180.248863914711;5185.740712964337;5188.377394712658;5192.584593232532;4256.297710877732;4382.386465894713;4420.9336859947125;4673.696209746424;4753.025629745894;3741.869034583831;3758.041699366644;3831.1571454280015;3869.187470631199;3882.650561449483;1236.3385588709998;1241.5592464675092;1241.6709571963436;1252.9060320476;1253.5863165155477;858.1547176031604;876.8860048193822;879.6393478673841;901.9909800902038;905.5970093308415;4786.973677638465;5703.429657034263;5788.218590490314;5828.388019258002;5903.291605007426;900.9413938622093;914.4696076275744;919.5769945824995;940.2149205413556;947.9478628675489;3084.290613158941;3533.6819686228364;3871.457995880579;3899.6100389960775;3937.9606203938165;7873.488223265469;10138.594456221816;10253.948730256301;10274.383536987805;10285.640002599874;9991;10211.234157438223;10214.285000050073;10234.795304093876;10251.333663311862;7410.96294518524;7763.107242667053;7765.689372425039;7822;7859.174786647548;5642.520385767256;5736.053551164178;5784.363719990713;5819.563532734977;5858.47273745365;9107.27141828639;9304.30217733659;9410.670626528039;9487.193588544775;9500.382475139093;7883.5664038478435;9447.1969882558;9545.427274363561;9647.903520964981;9666.371685840399;6551.836204094745;6574.408303252731;6802.319768023158;7073.929260707327;7243.891341629986;7702.383809295131;7869.252421019994;7871.48835325702;7918;7922.564258965812;7017.228104908558;7135.714571417086;7162;7195.172555156224;7259.745908893164;5941.940580594139;6769.1876974763345;7006.334398232159;7031.718731250693;7036.612986285803;6510.251321098134;6644.302348253557;6731.158605174353;6748.93251067483;6753.425958793558;4823.95176048235;4832.275158726219;4852.0295940811275;4876.927076043265;4896.681715688469;4764.49972752856;4815.855524334275;4891.559759621651;4951.231814935703;4981.427135879276 03:01:01 INFO - PROCESS | 1641 | |4;prototype.html;291.2398639550772;298.1725711151554;306.6060112755109;312.2957730318134;312.7154289596468;4908.31283620293;5214.713190774506;5279.498447647472;5284.867878303045;5286.894262114755;4677.976610116953;5023.899522009558;5027.296178534999;5059.2916991621205;5171.758777893307;3356.6475520070408;3995.240904228202;4051.2910240707906;4092.3452247640384;4097.67218622511;4031.6774658027434;4036.697247706419;4040.878773636796;4042.9191416171657;4076.9388459173138;3828.712846536521;3995.7224382102213;4011.638952494275;4047.4535937648334;4056;3427.7771944823508;3940.3695408734466;4035.636792688672;4043.8382464701376;4059.7564146151326;3086.0279012111114;3172.159377764894;3305.9833501564026;3508.7543871928974;3511.877084302037;4642.97854472019;4697.859064228079;4759.190937540627;5004.824831130929;5083.415407228142;3540.858365665396;4444.577765112309;4498.482674492442;4519.141363141026;4520.50274469808;4713.764311784397;4786.688865223784;4834.7824347904425;5347.930413917232;5512.531434828044;2912.965897106539;3319.8421026332835;3755.3991361382323;3762.360398732222;3765.0963768695588;5037.4458809530925;5502.147167189052;5646.209530665669;5693.572982026365;5767.318197038356;3386.593608766964;3437;3792.2036372361563;3813.8093095345675;3831.5593706724085;3416.4362880125;3828.559715632738;3861.189150278473;3861.2663593917628;3863.64772329688;4129.943841557226;4378.408914796525;4502.234620114588;4519.932201016987;4534.6849413670325;5438.374586922476;5506;5513;5532.446755324516;5558.416366281563;1183.8579370475597;1237.2020047069589;1245.2092920995233;1245.7134858982304;1262.5833474953242;786.9921300787033;802.7190483330787;803.9227435236811;843.8692002739585;853.5092321914924;3928.744631598937;4687.554682305175;4687.5781179693995;4899.4365647950945;4927.482614325444;4950.900981980412;5070.645054846199;5293.285406470154;5293.311869456946;5323;4252.87241382759;4254;4778.37881075458;5025.447200807908;5233.267342572043;2487.564676181661;2583.974160258411;2823.3082894690933;2854.787539656741;2858.771298296173;5153.330067091254;5317.441668624816;5400.865818178227;5585.776568937197;5627.876528526707;5243.947560524422;5310.734463276821;5458.536024437888;5489.560835133259;5508.785778879161;4647.86056418308;5524.530414914777;5589.944100558942;5632.324121105494;5637.351704553947;4918.6065114791445;4994.525520075587;5309.203619457112;5348.037353276292;5408.702521361363;4754.4532378776885;4809.278608208797;5463.699496527729;5717.742701578356;5729.570282228933;5208.583313335;5290.947090529045;5743.0811070228565;5972.342307673044;5972.552058595709;3230.82230477326;3668.0288965937675;3856.6914646827627;3872.3997780344093;3934.9016274593364;4791.832285869978;5692.345380281321;5721.113227449752;5727.914081288701;5730.71346432685;4852.393450818648;5521.018649928993;5576.86057848557;5585.609007369445;5643.209950606836;4947.3321101652255;5163.586913047023;5238.847453560211;5611.747471363717;5636.605437619327;4785.688930219525;5162.922556161586;5432.375276843056;5433;5457.645253058538;4749.35883655702;4769.713817170981;5430.484104010113;5519.779208831602;5543.972280138573;5412.485813847836;5444.671486168585;5452.672839629635;5455;5490.286262785812 03:01:01 INFO - PROCESS | 1641 | |5;yui.html;687.168526083439;747.835476195237;754.2005474197351;765.1506827421565;770.6052045797106;14610.926945365272;14913.850861491383;14966.700665986673;14973.700525989487;14977.775333370008;13108.41012154453;13227.603171904833;13238.470461181565;13255.53605623801;13309.667258318546;642.0914406115342;664.5746722097862;666.3503084492629;666.6366830077595;810.2869474862113;664.5746722097862;666.4834753066364;791.0507391130637;817.8200795824939;819.5820131732829;856.618804631936;871.2289623683055;874.8775171475999;875.308506280038;876.51791514667;824.8350329933977;824.9051359093721;825.005867929144;826.8511667899776;867.3884911137695;501.4634341254859;515.0548743056496;611.6360765344613;644.5778015399874;645.1838424393146;13173.473061077546;13675.521356752566;13730.382132804049;13997.79003314941;14023.368948397347;13533.661658458519;13764.380602872894;13944.51194208208;13959.930200349036;13970.650733731636;9227.67703130394;10723;10837.83743243852;11104.119701736536;11484.138689598314;3140.822191678121;3484.61669216386;3540.061883600848;3647.6717095461536;3663.4504824276305;92556;96542;96983.51508242414;97694;98816.50591747067;8951.820963580692;10402.895971040343;10519.368837869752;10618.522166502526;10639.521221544894;20662.17351305961;20859;20960.895195523924;21028.94855257231;21147.154113835273;7551.697932082655;7584.355329796918;7636.3890888728765;7684;7911.525308481395;4209.726367786084;4240.236757383639;4319.524852266248;4410.470743510735;4451.554844515523;3240.2709390386976;3354.5974483062187;3361.2269178089177;3362.69735723786;3365.1418888183357;622.8256088295285;623.8284471770208;624.1012941364421;628.1112226199876;640.9230892292954;523.9816606418757;608.8660494691161;627.7457629659995;648.4391001783423;658.3350815676106;7325.194228634846;8307.667693292322;8419.452735572168;8491;8705.738827835175;506.06630766236236;559.7397210297231;574.319431473705;595.1251660059705;660.303379934174;4061.6953728470485;4268.829246830092;4327.32926396409;4380.233459144637;4415.933760993587;95617;99479.00520994698;101549;104475.95524044662;120687;66379;67025.66487167533;75755.86366204511;92924;95583.52208239054;69036.92889213332;75061.37039721933;75313;77651.61174194206;84830;7363.595002274927;9346.19442951295;9375;9390;9565.808683826424;50860;51487.97024059573;52030.47969520408;52053.479465204866;53937.190942135116;48244.7587762059;62954.685226575406;66636.66726665536;75034;79080;11663.533458661559;11923.284602923852;12122.878771212176;12155.513779448724;12620.873791261969;13251.469941202244;13921.373538191008;14237.572872813833;14379.281035947952;14985.550433487013;12720.427580759073;12870.485180593047;12959.611211663665;13341.066125371131;13515.256660883746;9449.763755905882;12436.751264974831;12835.743285134431;13008;13219.206847589176;12085.274883507018;12234.877651223374;12285.754284914074;12947.158434701714;13018.80471792905;10814.783704325711;10824.296420732628;11035.39305338214;11047.668569942914;11312.20814542974;9747.512624368612;9857.802843943224;9880.703578892644;10987.39569323695;11184.664460066211 03:01:01 INFO - PROCESS | 1641 | __end_tp_report 03:01:01 INFO - PROCESS | 1641 | __start_cc_report 03:01:01 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,2231 03:01:01 INFO - PROCESS | 1641 | __end_cc_report 03:01:01 INFO - PROCESS | 1641 | __startTimestamp1483095661805__endTimestamp 03:01:01 INFO - PROCESS | 1641 | 03:01:01 INFO - PROCESS | 1641 | ------- Summary: start ------- 03:01:01 INFO - PROCESS | 1641 | Number of tests: 6 03:01:01 INFO - PROCESS | 1641 | 03:01:01 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:5757.22 Median:5982.91 stddev:633.06 (10.6%) stddev-sans-first:604.29 03:01:01 INFO - PROCESS | 1641 | Values: 3273.9 3316.1 3346.2 3355.3 3394.0 5725.9 5756.2 5768.7 5790.9 5792.1 5754.1 5777.4 5803.0 5815.9 5823.0 5220.9 5257.3 5263.9 5286.3 5322.8 5218.2 5220.1 5228.1 5321.3 5322.8 5229.2 5273.5 5281.0 5283.7 5297.2 5270.4 5300.4 5324.5 5331.1 5341.4 5112.8 5196.1 5283.0 5285.3 5309.7 5635.9 5664.9 5671.0 5695.3 5706.3 6113.7 6206.3 6207.8 6228.6 6230.2 5852.4 5966.2 6324.5 6358.5 6377.7 5665.6 5717.4 5758.1 5807.6 5807.8 6319.8 6323.8 6334.4 6363.4 6372.8 6165.4 6179.5 6180.4 6184.0 6198.1 5194.1 6093.4 6106.5 6127.6 6214.3 6113.0 6128.2 6137.3 6137.9 6168.4 6215.6 6255.8 6284.1 6305.3 6305.5 4940.8 4954.9 4963.2 4966.9 4987.7 4375.1 4388.5 4424.3 4470.6 4473.0 5728.2 5738.0 5744.6 5745.1 5746.0 6271.2 6281.1 6286.4 6287.7 6288.2 5666.4 5734.4 5736.2 5740.5 5753.3 5363.6 5392.4 5400.6 5402.5 5410.9 6228.7 6240.6 6272.8 6280.2 6297.5 6247.9 6307.1 6327.7 6329.7 6342.4 6177.5 6276.4 6291.2 6304.4 6306.0 6070.5 6100.7 6220.5 6226.6 6243.6 6168.7 6191.3 6221.3 6247.9 6256.8 6151.4 6162.8 6182.5 6187.8 6191.6 6035.0 6096.2 6126.2 6150.5 6172.2 5800.6 5812.7 5866.6 5971.7 6024.2 6072.1 6078.5 6094.5 6099.0 6103.4 5982.9 5993.5 6006.8 6007.8 6053.2 03:01:01 INFO - PROCESS | 1641 | 03:01:01 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:65083.47 Median:36832.76 stddev:138259.79 (375.4%) stddev-sans-first:138562.49 03:01:01 INFO - PROCESS | 1641 | Values: 453.9 500.1 517.6 530.2 555.6 30030.0 30734.7 39057.4 39952.6 41595.0 41084.6 41317.0 41337.0 41338.8 41416.0 9306.7 9482.9 9499.0 9500.8 9504.6 8545.5 8757.6 11183.6 11409.8 11453.4 11399.8 11446.8 11457.8 11464.1 11470.7 11337.7 11411.0 11413.3 11418.1 11439.3 8477.4 8719.8 11001.0 11327.7 11343.3 38951.6 39275.8 39324.6 39412.0 39490.0 3209.6 3214.1 3281.2 3314.5 3390.5 29025.0 29259.6 29518.9 29550.4 29752.7 3057.1 3108.8 3159.6 3168.9 3229.5 473888.0 473921.0 475089.0 477017.0 481512.0 58874.0 59275.4 59442.7 59446.7 59458.4 55012.6 63198.0 63233.7 63267.7 63359.0 15638.7 16310.6 16346.0 16364.9 16371.2 11223.3 11501.6 12588.7 12608.4 12614.2 15260.5 16845.8 17050.7 17304.4 17613.4 6176.1 6300.0 6350.5 6367.2 6367.6 820.9 973.7 976.0 997.5 1037.9 28314.0 28598.3 28990.7 29303.0 29702.1 984.1 1007.4 1007.4 1020.0 1089.5 14558.9 15029.7 15166.1 15301.8 15467.0 543679.0 649121.0 649205.0 649206.0 651218.0 56150.2 56194.0 56218.0 56260.7 56263.7 1203.7 1244.8 1310.4 1336.8 1351.5 28724.0 29362.3 29429.0 30038.0 30094.5 47642.0 48089.3 48162.5 48272.3 48313.0 423981.0 426961.0 427554.0 428285.0 428562.0 37556.4 39156.8 39184.4 39241.8 39274.4 44640.8 45043.8 45345.0 45581.6 46118.8 36271.8 36652.1 36686.7 36790.8 36940.3 32230.2 36728.6 37405.1 37464.6 37599.3 36513.8 36600.8 36874.7 37124.6 37386.4 31074.0 32936.0 33069.7 33098.8 33129.8 24362.5 24865.0 24883.8 25052.5 25274.4 03:01:01 INFO - PROCESS | 1641 | 03:01:01 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:33809.63 Median:18640.30 stddev:99188.42 (532.1%) stddev-sans-first:99434.85 03:01:01 INFO - PROCESS | 1641 | Values: 351.8 366.4 369.5 379.8 395.9 14740.9 14904.4 15656.9 16747.3 19764.2 18621.5 18793.0 19158.1 19307.5 19385.0 5226.2 5497.9 5499.1 5507.4 5513.9 4356.6 5421.5 6046.2 6092.9 6104.2 6039.9 6048.5 6050.0 6054.2 6056.4 5261.2 5361.8 5408.4 5410.0 5412.2 5022.5 5248.1 5254.0 5277.3 5281.6 24161.3 24417.9 24602.3 24903.5 24987.8 2203.8 2477.9 2617.9 2721.6 2723.4 7352.9 9614.9 9893.6 10094.6 10234.8 1776.3 1872.9 1886.4 1900.9 1920.7 83979.6 92918.7 105172.0 108569.0 112001.4 8556.5 11024.0 11421.4 11596.8 11627.8 14083.8 14085.9 14510.9 14545.7 14624.9 7051.5 7052.8 8330.4 8507.9 8647.0 8413.3 8426.6 8430.3 8582.5 8617.4 3946.8 4189.3 4405.1 4569.6 4577.2 1736.5 1827.5 1832.1 1887.8 1890.5 757.5 892.6 893.7 899.7 919.6 19219.8 19458.5 20715.7 20810.1 21135.6 888.6 905.6 913.6 949.5 973.4 10300.4 10642.7 10756.3 10801.2 10912.3 586989.0 587448.1 594999.0 606720.7 606817.0 98720.0 124449.0 129905.4 131221.0 133264.3 902.5 943.1 963.4 963.5 965.6 17772.4 17847.6 18130.0 18155.9 18182.5 36900.8 44844.8 48861.0 53793.5 54567.9 72766.6 76673.8 77160.0 77221.6 77814.0 7055.8 7329.4 7341.9 7570.0 7808.8 10651.0 11837.4 12678.7 12853.3 12989.5 10878.8 10942.8 10951.7 11203.3 11316.2 10729.7 10878.3 11008.5 11276.8 11377.8 10924.6 11007.3 11399.5 11482.3 11535.3 7038.0 7363.2 7373.6 7497.3 7984.2 9096.0 10431.7 10799.7 10974.9 11035.1 03:01:01 INFO - PROCESS | 1641 | 03:01:01 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:5412.38 Median:5151.36 stddev:2949.68 (57.3%) stddev-sans-first:2942.38 03:01:01 INFO - PROCESS | 1641 | Values: 1379.8 1463.7 1468.2 1783.7 1805.9 7899.6 8202.0 8231.9 8252.0 8252.6 7336.3 7642.2 7932.2 8162.1 8174.8 2303.7 2335.7 2338.5 2341.4 2344.0 2323.8 2353.2 2370.3 2374.3 2376.9 2310.5 2311.9 2333.8 2335.5 2346.3 2302.6 2306.6 2323.0 2329.9 2337.3 2337.4 2347.9 2355.6 2356.4 2362.9 8198.8 8264.3 8334.3 8363.9 8376.3 4949.7 4997.0 5148.3 5154.4 5159.8 3322.5 3386.9 3484.4 3517.1 3523.0 913.5 1037.6 1048.9 1067.0 1091.6 10741.9 10871.7 10875.8 10879.3 10890.6 4454.6 4609.6 4711.3 4730.9 4739.4 9274.6 9292.5 9297.9 9309.1 9316.1 5171.7 5180.2 5185.7 5188.4 5192.6 4256.3 4382.4 4420.9 4673.7 4753.0 3741.9 3758.0 3831.2 3869.2 3882.7 1236.3 1241.6 1241.7 1252.9 1253.6 858.2 876.9 879.6 902.0 905.6 4787.0 5703.4 5788.2 5828.4 5903.3 900.9 914.5 919.6 940.2 947.9 3084.3 3533.7 3871.5 3899.6 3938.0 7873.5 10138.6 10253.9 10274.4 10285.6 9991.0 10211.2 10214.3 10234.8 10251.3 7411.0 7763.1 7765.7 7822.0 7859.2 5642.5 5736.1 5784.4 5819.6 5858.5 9107.3 9304.3 9410.7 9487.2 9500.4 7883.6 9447.2 9545.4 9647.9 9666.4 6551.8 6574.4 6802.3 7073.9 7243.9 7702.4 7869.3 7871.5 7918.0 7922.6 7017.2 7135.7 7162.0 7195.2 7259.7 5941.9 6769.2 7006.3 7031.7 7036.6 6510.3 6644.3 6731.2 6748.9 6753.4 4824.0 4832.3 4852.0 4876.9 4896.7 4764.5 4815.9 4891.6 4951.2 4981.4 03:01:01 INFO - PROCESS | 1641 | 03:01:01 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4382.66 Median:4875.92 stddev:1351.39 (27.7%) stddev-sans-first:1319.83 03:01:01 INFO - PROCESS | 1641 | Values: 291.2 298.2 306.6 312.3 312.7 4908.3 5214.7 5279.5 5284.9 5286.9 4678.0 5023.9 5027.3 5059.3 5171.8 3356.6 3995.2 4051.3 4092.3 4097.7 4031.7 4036.7 4040.9 4042.9 4076.9 3828.7 3995.7 4011.6 4047.5 4056.0 3427.8 3940.4 4035.6 4043.8 4059.8 3086.0 3172.2 3306.0 3508.8 3511.9 4643.0 4697.9 4759.2 5004.8 5083.4 3540.9 4444.6 4498.5 4519.1 4520.5 4713.8 4786.7 4834.8 5347.9 5512.5 2913.0 3319.8 3755.4 3762.4 3765.1 5037.4 5502.1 5646.2 5693.6 5767.3 3386.6 3437.0 3792.2 3813.8 3831.6 3416.4 3828.6 3861.2 3861.3 3863.6 4129.9 4378.4 4502.2 4519.9 4534.7 5438.4 5506.0 5513.0 5532.4 5558.4 1183.9 1237.2 1245.2 1245.7 1262.6 787.0 802.7 803.9 843.9 853.5 3928.7 4687.6 4687.6 4899.4 4927.5 4950.9 5070.6 5293.3 5293.3 5323.0 4252.9 4254.0 4778.4 5025.4 5233.3 2487.6 2584.0 2823.3 2854.8 2858.8 5153.3 5317.4 5400.9 5585.8 5627.9 5243.9 5310.7 5458.5 5489.6 5508.8 4647.9 5524.5 5589.9 5632.3 5637.4 4918.6 4994.5 5309.2 5348.0 5408.7 4754.5 4809.3 5463.7 5717.7 5729.6 5208.6 5290.9 5743.1 5972.3 5972.6 3230.8 3668.0 3856.7 3872.4 3934.9 4791.8 5692.3 5721.1 5727.9 5730.7 4852.4 5521.0 5576.9 5585.6 5643.2 4947.3 5163.6 5238.8 5611.7 5636.6 4785.7 5162.9 5432.4 5433.0 5457.6 4749.4 4769.7 5430.5 5519.8 5544.0 5412.5 5444.7 5452.7 5455.0 5490.3 03:01:01 INFO - PROCESS | 1641 | 03:01:01 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:19578.15 Median:51173.99 stddev:28376.44 (55.5%) stddev-sans-first:28420.59 03:01:01 INFO - PROCESS | 1641 | Values: 687.2 747.8 754.2 765.2 770.6 14610.9 14913.9 14966.7 14973.7 14977.8 13108.4 13227.6 13238.5 13255.5 13309.7 642.1 664.6 666.4 666.6 810.3 664.6 666.5 791.1 817.8 819.6 856.6 871.2 874.9 875.3 876.5 824.8 824.9 825.0 826.9 867.4 501.5 515.1 611.6 644.6 645.2 13173.5 13675.5 13730.4 13997.8 14023.4 13533.7 13764.4 13944.5 13959.9 13970.7 9227.7 10723.0 10837.8 11104.1 11484.1 3140.8 3484.6 3540.1 3647.7 3663.5 92556.0 96542.0 96983.5 97694.0 98816.5 8951.8 10402.9 10519.4 10618.5 10639.5 20662.2 20859.0 20960.9 21028.9 21147.2 7551.7 7584.4 7636.4 7684.0 7911.5 4209.7 4240.2 4319.5 4410.5 4451.6 3240.3 3354.6 3361.2 3362.7 3365.1 622.8 623.8 624.1 628.1 640.9 524.0 608.9 627.7 648.4 658.3 7325.2 8307.7 8419.5 8491.0 8705.7 506.1 559.7 574.3 595.1 660.3 4061.7 4268.8 4327.3 4380.2 4415.9 95617.0 99479.0 101549.0 104476.0 120687.0 66379.0 67025.7 75755.9 92924.0 95583.5 69036.9 75061.4 75313.0 77651.6 84830.0 7363.6 9346.2 9375.0 9390.0 9565.8 50860.0 51488.0 52030.5 52053.5 53937.2 48244.8 62954.7 66636.7 75034.0 79080.0 11663.5 11923.3 12122.9 12155.5 12620.9 13251.5 13921.4 14237.6 14379.3 14985.6 12720.4 12870.5 12959.6 13341.1 13515.3 9449.8 12436.8 12835.7 13008.0 13219.2 12085.3 12234.9 12285.8 12947.2 13018.8 10814.8 10824.3 11035.4 11047.7 11312.2 9747.5 9857.8 9880.7 10987.4 11184.7 03:01:01 INFO - PROCESS | 1641 | -------- Summary: end -------- 03:01:01 INFO - PROCESS | 1641 | 03:01:02 INFO - TEST-INFO | 1641: exit 0 03:01:02 INFO - TEST-OK | dromaeo_css | took 1120875ms 03:01:02 INFO - TEST-START | kraken 03:01:02 INFO - operating with platform_type : mac_ 03:01:02 INFO - Initialising browser for kraken test... 03:01:02 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmp_73RAe/profile http://localhost:49244/getInfo.html) 03:01:05 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 03:01:05 INFO - PROCESS | 1647 | colorDepth:24 03:01:05 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 03:01:05 INFO - PROCESS | 1647 | __metrics 03:01:05 INFO - PROCESS | 1647 | 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" 03:01:05 INFO - PROCESS | 1647 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:01:06 INFO - TEST-INFO | 1647: exit 0 03:01:06 INFO - Browser initialized. 03:01:06 INFO - Running cycle 1/1 for kraken test... 03:01:06 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmp_73RAe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:01:18 INFO - PROCESS | 1649 | RSS: Main: 222875648 03:01:18 INFO - PROCESS | 1649 | 03:01:21 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 03:01:22 INFO - PROCESS | 1649 | RSS: Main: 271626240 03:01:22 INFO - PROCESS | 1649 | 03:01:24 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 03:01:25 INFO - PROCESS | 1649 | RSS: Main: 286027776 03:01:25 INFO - PROCESS | 1649 | 03:01:28 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 03:01:29 INFO - PROCESS | 1649 | RSS: Main: 380493824 03:01:29 INFO - PROCESS | 1649 | 03:01:31 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 03:01:31 INFO - PROCESS | 1649 | RSS: Main: 284352512 03:01:31 INFO - PROCESS | 1649 | 03:01:33 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:01:34 INFO - PROCESS | 1649 | RSS: Main: 286318592 03:01:34 INFO - PROCESS | 1649 | 03:01:37 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 03:01:37 INFO - PROCESS | 1649 | RSS: Main: 326979584 03:01:37 INFO - PROCESS | 1649 | 03:01:40 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 03:01:41 INFO - PROCESS | 1649 | RSS: Main: 326631424 03:01:41 INFO - PROCESS | 1649 | 03:01:44 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 03:01:44 INFO - PROCESS | 1649 | RSS: Main: 325410816 03:01:44 INFO - PROCESS | 1649 | 03:01:46 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:01:47 INFO - PROCESS | 1649 | RSS: Main: 298717184 03:01:47 INFO - PROCESS | 1649 | 03:01:48 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:01:49 INFO - PROCESS | 1649 | RSS: Main: 301285376 03:01:49 INFO - PROCESS | 1649 | 03:01:51 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:01:52 INFO - PROCESS | 1649 | RSS: Main: 302899200 03:01:52 INFO - PROCESS | 1649 | 03:01:55 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:01:56 INFO - PROCESS | 1649 | RSS: Main: 320872448 03:01:56 INFO - PROCESS | 1649 | 03:01:59 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:01:59 INFO - PROCESS | 1649 | RSS: Main: 314449920 03:01:59 INFO - PROCESS | 1649 | 03:02:01 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 03:02:01 INFO - PROCESS | 1649 | RSS: Main: 358686720 03:02:01 INFO - PROCESS | 1649 | 03:02:01 INFO - PROCESS | 1649 | __start_tp_report 03:02:01 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 03:02:01 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 03:02:01 INFO - PROCESS | 1649 | |i|pagename|runs| 03:02:01 INFO - PROCESS | 1649 | |0;ai-astar;104;119;160;170;115;117;116;115;115;115 03:02:01 INFO - PROCESS | 1649 | |1;audio-beat-detection;147;109;112;143;111;109;109;110;142;114 03:02:01 INFO - PROCESS | 1649 | |2;audio-dft;183;180;185;186;184;186;183;185;182;186 03:02:01 INFO - PROCESS | 1649 | |3;audio-fft;81;59;59;89;63;60;59;59;81;58 03:02:01 INFO - PROCESS | 1649 | |4;audio-oscillator;83;71;95;71;68;70;91;68;69;93 03:02:01 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;87;85;87;128;89;85;89;85;87;85 03:02:01 INFO - PROCESS | 1649 | |6;imaging-darkroom;94;94;94;94;93;94;94;94;94;94 03:02:01 INFO - PROCESS | 1649 | |7;imaging-desaturate;95;125;120;80;81;82;83;85;84;81 03:02:01 INFO - PROCESS | 1649 | |8;json-parse-financial;56;52;53;54;55;58;59;58;56;57 03:02:01 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;64;75;81;74;73;70;70;70;69;71 03:02:01 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;85;82;83;83;84;97;101;102;101;85 03:02:01 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;147;162;158;142;144;143;160;158;211;149 03:02:01 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;158;147;201;171;164;166;177;163;164;163 03:02:01 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;67;68;67;67;68;68;67;67;75;81 03:02:01 INFO - PROCESS | 1649 | __end_tp_report 03:02:01 INFO - PROCESS | 1649 | __start_cc_report 03:02:01 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,1983 03:02:01 INFO - PROCESS | 1649 | __end_cc_report 03:02:01 INFO - PROCESS | 1649 | __startTimestamp1483095721637__endTimestamp 03:02:01 INFO - PROCESS | 1649 | 03:02:01 INFO - PROCESS | 1649 | ------- Summary: start ------- 03:02:01 INFO - PROCESS | 1649 | Number of tests: 14 03:02:01 INFO - PROCESS | 1649 | 03:02:01 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:124.60 Median:116.50 stddev:21.78 (18.7%) stddev-sans-first:21.79 03:02:01 INFO - PROCESS | 1649 | Values: 104.0 119.0 160.0 170.0 115.0 117.0 116.0 115.0 115.0 115.0 03:02:01 INFO - PROCESS | 1649 | 03:02:01 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:120.60 Median:113.00 stddev:16.27 (14.4%) stddev-sans-first:14.18 03:02:01 INFO - PROCESS | 1649 | Values: 147.0 109.0 112.0 143.0 111.0 109.0 109.0 110.0 142.0 114.0 03:02:01 INFO - PROCESS | 1649 | 03:02:01 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:184.00 Median:185.00 stddev:2.00 (1.1%) stddev-sans-first:2.09 03:02:01 INFO - PROCESS | 1649 | Values: 183.0 180.0 185.0 186.0 184.0 186.0 183.0 185.0 182.0 186.0 03:02:01 INFO - PROCESS | 1649 | 03:02:01 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:66.80 Median:61.50 stddev:11.91 (19.4%) stddev-sans-first:11.48 03:02:01 INFO - PROCESS | 1649 | Values: 81.0 59.0 59.0 89.0 63.0 60.0 59.0 59.0 81.0 58.0 03:02:01 INFO - PROCESS | 1649 | 03:02:01 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:77.90 Median:77.00 stddev:11.31 (14.7%) stddev-sans-first:11.84 03:02:01 INFO - PROCESS | 1649 | Values: 83.0 71.0 95.0 71.0 68.0 70.0 91.0 68.0 69.0 93.0 03:02:01 INFO - PROCESS | 1649 | 03:02:01 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:90.70 Median:87.00 stddev:13.20 (15.2%) stddev-sans-first:13.93 03:02:01 INFO - PROCESS | 1649 | Values: 87.0 85.0 87.0 128.0 89.0 85.0 89.0 85.0 87.0 85.0 03:02:01 INFO - PROCESS | 1649 | 03:02:01 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:93.90 Median:94.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 03:02:01 INFO - PROCESS | 1649 | Values: 94.0 94.0 94.0 94.0 93.0 94.0 94.0 94.0 94.0 94.0 03:02:01 INFO - PROCESS | 1649 | 03:02:01 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:91.60 Median:82.50 stddev:16.87 (20.4%) stddev-sans-first:17.85 03:02:01 INFO - PROCESS | 1649 | Values: 95.0 125.0 120.0 80.0 81.0 82.0 83.0 85.0 84.0 81.0 03:02:01 INFO - PROCESS | 1649 | 03:02:01 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:55.80 Median:56.50 stddev:2.30 (4.1%) stddev-sans-first:2.44 03:02:01 INFO - PROCESS | 1649 | Values: 56.0 52.0 53.0 54.0 55.0 58.0 59.0 58.0 56.0 57.0 03:02:01 INFO - PROCESS | 1649 | 03:02:01 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:71.70 Median:72.00 stddev:4.47 (6.2%) stddev-sans-first:3.78 03:02:01 INFO - PROCESS | 1649 | Values: 64.0 75.0 81.0 74.0 73.0 70.0 70.0 70.0 69.0 71.0 03:02:01 INFO - PROCESS | 1649 | 03:02:01 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:90.30 Median:83.50 stddev:8.71 (10.4%) stddev-sans-first:9.02 03:02:01 INFO - PROCESS | 1649 | Values: 85.0 82.0 83.0 83.0 84.0 97.0 101.0 102.0 101.0 85.0 03:02:01 INFO - PROCESS | 1649 | 03:02:01 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:157.40 Median:158.00 stddev:20.29 (12.8%) stddev-sans-first:21.17 03:02:01 INFO - PROCESS | 1649 | Values: 147.0 162.0 158.0 142.0 144.0 143.0 160.0 158.0 211.0 149.0 03:02:01 INFO - PROCESS | 1649 | 03:02:01 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.40 Median:165.00 stddev:14.15 (8.6%) stddev-sans-first:14.60 03:02:01 INFO - PROCESS | 1649 | Values: 158.0 147.0 201.0 171.0 164.0 166.0 177.0 163.0 164.0 163.0 03:02:01 INFO - PROCESS | 1649 | 03:02:01 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:68.00 stddev:4.72 (6.9%) stddev-sans-first:4.92 03:02:01 INFO - PROCESS | 1649 | Values: 67.0 68.0 67.0 67.0 68.0 68.0 67.0 67.0 75.0 81.0 03:02:01 INFO - PROCESS | 1649 | -------- Summary: end -------- 03:02:01 INFO - PROCESS | 1649 | 03:02:02 INFO - TEST-INFO | 1649: exit 0 03:02:02 INFO - TEST-OK | kraken | took 60452ms 03:02:02 INFO - SUITE-END | took 1181s 03:02:03 INFO - Completed test suite (00:19:41) 03:02:03 INFO - javascript benchmark 03:02:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3273.9345213095735, 3316.121227874614, 3346.2136397946465, 3355.3121610069925, 3393.981805458362, 5725.914111288334, 5756.194132821408, 5768.653880767157, 5790.855228619286, 5792.131180322948, 5754.108113242443, 5777.393373695754, 5802.970985145068, 5815.941840581593, 5823.0, 5220.895582088356, 5257.290265814124, 5263.921041184385, 5286.286351342577, 5322.8137015204475, 5218.1650935850275, 5220.086484865153, 5228.058949389108, 5321.281626980347, 5322.8137015204475, 5229.189475631283, 5273.472652734734, 5281.049411106, 5283.656562323456, 5297.231901374298, 5270.446603106676, 5300.443453437372, 5324.467553244684, 5331.093714068579, 5341.359036915556, 5112.769925353368, 5196.0647083524955, 5283.0, 5285.312909321802, 5309.65487243328, 5635.8591035224035, 5664.915026274609, 5670.979223739725, 5695.288088988877, 5706.258186435778, 6113.658745237732, 6206.34833342497, 6207.81376558704, 6228.626282423069, 6230.221222347207, 5852.385499522531, 5966.194563733927, 6324.525660575475, 6358.523110766711, 6377.681115944184, 5665.631733937281, 5717.399673034354, 5758.1362795581, 5807.593468457168, 5807.767689292381, 6319.778807741799, 6323.841903952438, 6334.398232167937, 6363.363663633692, 6372.776952806629, 6165.445109940127, 6179.5056395488255, 6180.4437600615265, 6183.96908015466, 6198.132261483396, 5194.142966410575, 6093.39066093396, 6106.511479081662, 6127.617148554337, 6214.25428948529, 6113.024264983944, 6128.233970753656, 6137.285055221596, 6137.938620613826, 6168.383161683796, 6215.595986260879, 6255.812325630239, 6284.12022316876, 6305.306416294204, 6305.495560355161, 4940.802367905316, 4954.925676114861, 4963.205887058126, 4966.90066198674, 4987.650864439454, 4375.081232941055, 4388.4953230378915, 4424.269995450714, 4470.552944705503, 4472.955270447319, 5728.226689396962, 5738.0, 5744.569157313218, 5745.109508026263, 5746.02317606008, 6271.184745982993, 6281.089242059968, 6286.402791734778, 6287.685615719287, 6288.245410550763, 5666.376698563155, 5734.369219385864, 5736.1682556029145, 5740.454656807597, 5753.309602847685, 5363.570914326844, 5392.40683524812, 5400.594955378362, 5402.459754024566, 5410.8917821643345, 6228.719707613259, 6240.625562466266, 6272.8118156455375, 6280.214973128359, 6297.52768542352, 6247.937520624736, 6307.05394190875, 6327.715252813542, 6329.715162817776, 6342.39747224013, 6177.536684748572, 6276.435120839146, 6291.150694656163, 6304.432601065742, 6306.0225665022, 6070.514358851369, 6100.694965251809, 6220.502359811114, 6226.62640241587, 6243.562950593415, 6168.722407491763, 6191.349908259568, 6221.253449586078, 6247.875042499034, 6256.7810126645345, 6151.446369826737, 6162.815115546541, 6182.536309776875, 6187.752489900354, 6191.628502289877, 6034.974054410848, 6096.177016102769, 6126.154810528965, 6150.477209437069, 6172.22847144107, 5800.56495762811, 5812.651240925558, 5866.5893387462465, 5971.671558064349, 6024.246969128859, 6072.089186622042, 6078.483328917092, 6094.54290928191, 6099.0, 6103.3591472894705, 5982.910256346071, 5993.460588547052, 6006.759729610767, 6007.759689612367, 6053.182820319201], "value": 5716.2181139240065, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [453.88571058052474, 500.0548962460949, 517.5574883474629, 530.2417542913632, 555.558326143657, 30030.0, 30734.69265307349, 39057.414138787906, 39952.60047399525, 41595.0, 41084.58915410845, 41317.0, 41337.0, 41338.793306033505, 41416.0, 9306.674266400678, 9482.857757133648, 9499.002604726507, 9500.762480937998, 9504.619815207418, 8545.487270763773, 8757.649694012232, 11183.608573699923, 11409.771804563903, 11453.427328633536, 11399.772004559904, 11446.771064578703, 11457.770844583103, 11464.14018948578, 11470.655880323604, 11337.659870203866, 11411.03006244466, 11413.315201087962, 11418.143639227092, 11439.256448330914, 8477.406581539295, 8719.782005449852, 11001.0, 11327.660170194908, 11343.319400835977, 38951.63669271562, 39275.803620982, 39324.60675393238, 39412.0, 39490.0, 3209.5667084943702, 3214.0839860639608, 3281.212508997847, 3314.5028245763087, 3390.525326454274, 29025.0, 29259.56110658342, 29518.852405737834, 29550.408991820044, 29752.70247297521, 3057.149369246166, 3108.8445577721022, 3159.573457583243, 3168.9207769805707, 3229.4832826747606, 473888.0, 473921.0, 475089.0, 477016.9999999965, 481512.0, 58874.0, 59275.40724592699, 59442.702786486654, 59446.70276648589, 59458.40541594615, 55012.62468438241, 63198.0, 63233.68383158055, 63267.683661581395, 63359.0, 15638.687226255639, 16310.592235193977, 16346.0, 16364.918175409046, 16371.181440927907, 11223.326600403838, 11501.597444089417, 12588.748225035448, 12608.369581520887, 12614.243145411305, 15260.465883694018, 16845.83154168467, 17050.74423883643, 17304.394346197823, 17613.38353157659, 6176.085413521822, 6299.968500157562, 6350.491960643138, 6367.204099487564, 6367.649779262092, 820.9055958564722, 973.732223638491, 975.9707208783748, 997.536145692242, 1037.9377237365782, 28314.0, 28598.285042874093, 28990.727372855068, 29303.0, 29702.108936731933, 984.0749695286407, 1007.3754272351189, 1007.4106647611229, 1019.9949000254951, 1089.5097206257217, 14558.85441145575, 15029.699406011818, 15166.14683301333, 15301.770473442908, 15467.0, 543679.0, 649121.0, 649205.0, 649206.0, 651218.0, 56150.15774763382, 56194.0, 56218.0, 56260.71869640707, 56263.71868140633, 1203.7472130852443, 1244.813278008306, 1310.351376068852, 1336.8462626797848, 1351.5472316773992, 28723.994660186792, 29362.265943351587, 29428.969986050386, 30038.0, 30094.548581771727, 47642.0, 48089.278660819415, 48162.518374817206, 48272.275915861996, 48313.0, 423981.0, 426961.0, 427554.0, 428285.0, 428562.0, 37556.436653450764, 39156.80421597874, 39184.412233817086, 39241.82274531769, 39274.410883837336, 44640.77679611581, 45043.77478112588, 45345.0, 45581.64937192843, 46118.847028824544, 36271.818640906626, 36652.083697907765, 36686.69819716238, 36790.8160459196, 36940.26119477649, 32230.19424514312, 36728.63271367252, 37405.0648733773, 37464.625353746116, 37599.30803113812, 36513.81743091268, 36600.81699591485, 36874.70938517139, 37124.6287537132, 37386.43920341251, 31074.0, 32936.0, 33069.66930330666, 33098.83450582732, 33129.83435082809, 24362.51274974455, 24865.005399783808, 24883.75116248887, 25052.49895002126, 25274.36814079663], "value": 19642.646686787695, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.7854108993515, 366.43568903888007, 369.52516016918264, 379.7930128080197, 395.8950878017324, 14740.852591474082, 14904.403823847048, 15656.921715391436, 16747.330106795744, 19764.20943162272, 18621.534461638465, 18793.0, 19158.13788379523, 19307.51731206717, 19385.0, 5226.216067589863, 5497.890042199154, 5499.1476321170085, 5507.421720719327, 5513.889722205554, 4356.607905288523, 5421.457854214566, 6046.213992181032, 6092.939070609304, 6104.206453161083, 6039.939600604003, 6048.45563899249, 6050.001749711292, 6054.212952316193, 6056.424639659225, 5261.184516399945, 5361.812336568201, 5408.432114627986, 5410.026195284847, 5412.161115027177, 5022.4726403727445, 5248.134057880445, 5254.0, 5277.261183434322, 5281.577473802086, 24161.275161745176, 24417.87791061051, 24602.29374768774, 24903.501929961298, 24987.750122498906, 2203.7796220377995, 2477.913273035435, 2617.9345516362055, 2721.6053672217618, 2723.400851812618, 7352.852942941165, 9614.855777163348, 9893.604255829758, 10094.596216151418, 10234.795304093876, 1776.289484206315, 1872.8595355348268, 1886.3869242496107, 1900.8859468431938, 1920.711893216015, 83979.5801020991, 92918.67703307404, 105172.0, 108569.0, 112001.43999280033, 8556.486610803371, 11024.0, 11421.37182454973, 11596.826047609293, 11627.825582616098, 14083.788743168861, 14085.85914140866, 14510.854891451161, 14545.709085818437, 14624.926875365554, 7051.5063945523325, 7052.823679408056, 8330.416870819106, 8507.914920850711, 8647.048824629286, 8413.326933845277, 8426.620802063922, 8430.32557395407, 8582.485050896967, 8617.396782225183, 3946.8356834733822, 4189.329707246887, 4405.07493426384, 4569.588737013684, 4577.176108300538, 1736.5484973906875, 1827.5431142214447, 1832.1114259584092, 1887.8112188781286, 1890.4990177602947, 757.5492581913752, 892.5894088719132, 893.6961433112773, 899.7480705402497, 919.595378033663, 19219.80780192208, 19458.513537161685, 20715.689264661043, 20810.113699293022, 21135.577288454144, 888.6090120347027, 905.5789058088021, 913.6254135804288, 949.4540639132526, 973.4256788494822, 10300.433476158883, 10642.73393165177, 10756.300840445347, 10801.243912926177, 10912.345259284468, 586989.0, 587448.1255187478, 594999.0, 606720.6639667999, 606817.0, 98720.0, 124449.0, 129905.35047324703, 131221.0, 133264.333678331, 902.5036230073433, 943.0852073488568, 963.3882484622176, 963.5086106085946, 965.5703212070562, 17772.377966771102, 17847.553811154306, 18130.0, 18155.909220453814, 18182.45452636423, 36900.81549592235, 44844.77577612222, 48861.0, 53793.462065378844, 54567.90864182615, 72766.63616682094, 76673.84989225054, 77160.0, 77221.61389193017, 77814.0, 7055.823604409726, 7329.377002954595, 7341.926580734124, 7569.97805296278, 7808.84382312362, 10651.0, 11837.408129593314, 12678.683032924251, 12853.293068881301, 12989.48042078344, 10878.836817447904, 10942.835857461985, 10951.671449856518, 11203.327800332007, 11316.20786544934, 10729.731756706145, 10878.347299161758, 11008.504617292081, 11276.774464510829, 11377.772444551229, 10924.579804625348, 11007.339559626447, 11399.544018239178, 11482.253653512495, 11535.250208736405, 7037.964810175917, 7363.190049094703, 7373.5575865448245, 7497.2877576630135, 7984.24149705777, 9095.954520227357, 10431.739206519898, 10799.730006749895, 10974.945125274322, 11035.130865975065], "value": 9168.629736087163, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1379.7651102263478, 1463.6560408304053, 1468.1851572377334, 1783.6878546254402, 1805.8555315574756, 7899.644515996787, 8202.0, 8231.91768082319, 8252.0, 8252.628631711572, 7336.3397294243505, 7642.235776422355, 7932.206779322065, 8162.14297498763, 8174.836503269931, 2303.7465878753324, 2335.6963594732747, 2338.4621537046405, 2341.438054866828, 2344.0155134843408, 2323.825713071518, 2353.1763882641026, 2370.3244575296044, 2374.3351861478814, 2376.9168079117235, 2310.549442858643, 2311.919082832101, 2333.7782910623464, 2335.5095429959642, 2346.296111166652, 2302.5625131225015, 2306.5848147333472, 2323.0127195941827, 2329.94175145621, 2337.2520793346134, 2337.4039619896816, 2347.941301467477, 2355.611324131516, 2356.422676444265, 2362.893669784864, 8198.754037378889, 8264.297534709556, 8334.333253339719, 8363.874541881876, 8376.288015518689, 4949.653524253304, 4997.0, 5148.304978827848, 5154.4072431670465, 5159.793608255665, 3322.4517954537473, 3386.9153271168416, 3484.390231709466, 3517.103138699615, 3522.964770352289, 913.4701872913694, 1037.6435077365159, 1048.895110488945, 1067.0449947297204, 1091.6070214722772, 10741.892581074168, 10871.728206794814, 10875.836862446991, 10879.292845965067, 10890.61882834097, 4454.554544545519, 4609.631229501631, 4711.269753188261, 4730.88172795676, 4739.3601863748645, 9274.562442821238, 9292.535373231447, 9297.860532092023, 9309.115634014757, 9316.114969077928, 5171.689698618095, 5180.248863914711, 5185.740712964337, 5188.377394712658, 5192.584593232532, 4256.297710877732, 4382.386465894713, 4420.9336859947125, 4673.696209746424, 4753.025629745894, 3741.869034583831, 3758.041699366644, 3831.1571454280015, 3869.187470631199, 3882.650561449483, 1236.3385588709998, 1241.5592464675092, 1241.6709571963436, 1252.9060320476, 1253.5863165155477, 858.1547176031604, 876.8860048193822, 879.6393478673841, 901.9909800902038, 905.5970093308415, 4786.973677638465, 5703.429657034263, 5788.218590490314, 5828.388019258002, 5903.291605007426, 900.9413938622093, 914.4696076275744, 919.5769945824995, 940.2149205413556, 947.9478628675489, 3084.290613158941, 3533.6819686228364, 3871.457995880579, 3899.6100389960775, 3937.9606203938165, 7873.488223265469, 10138.594456221816, 10253.948730256301, 10274.383536987805, 10285.640002599874, 9991.0, 10211.234157438223, 10214.285000050073, 10234.795304093876, 10251.333663311862, 7410.96294518524, 7763.107242667053, 7765.689372425039, 7822.0, 7859.174786647548, 5642.520385767256, 5736.053551164178, 5784.363719990713, 5819.563532734977, 5858.47273745365, 9107.27141828639, 9304.30217733659, 9410.670626528039, 9487.193588544775, 9500.382475139093, 7883.5664038478435, 9447.1969882558, 9545.427274363561, 9647.903520964981, 9666.371685840399, 6551.836204094745, 6574.408303252731, 6802.319768023158, 7073.929260707327, 7243.891341629986, 7702.383809295131, 7869.252421019994, 7871.48835325702, 7918.0, 7922.564258965812, 7017.228104908558, 7135.714571417086, 7162.0, 7195.172555156224, 7259.745908893164, 5941.940580594139, 6769.1876974763345, 7006.334398232159, 7031.718731250693, 7036.612986285803, 6510.251321098134, 6644.302348253557, 6731.158605174353, 6748.93251067483, 6753.425958793558, 4823.95176048235, 4832.275158726219, 4852.0295940811275, 4876.927076043265, 4896.681715688469, 4764.49972752856, 4815.855524334275, 4891.559759621651, 4951.231814935703, 4981.427135879276], "value": 4400.67788811436, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.2398639550772, 298.1725711151554, 306.6060112755109, 312.2957730318134, 312.7154289596468, 4908.31283620293, 5214.713190774506, 5279.498447647472, 5284.867878303045, 5286.894262114755, 4677.976610116953, 5023.899522009558, 5027.296178534999, 5059.2916991621205, 5171.758777893307, 3356.6475520070408, 3995.240904228202, 4051.2910240707906, 4092.3452247640384, 4097.67218622511, 4031.6774658027434, 4036.697247706419, 4040.878773636796, 4042.9191416171657, 4076.9388459173138, 3828.712846536521, 3995.7224382102213, 4011.638952494275, 4047.4535937648334, 4056.0, 3427.7771944823508, 3940.3695408734466, 4035.636792688672, 4043.8382464701376, 4059.7564146151326, 3086.0279012111114, 3172.159377764894, 3305.9833501564026, 3508.7543871928974, 3511.877084302037, 4642.97854472019, 4697.859064228079, 4759.190937540627, 5004.824831130929, 5083.415407228142, 3540.858365665396, 4444.577765112309, 4498.482674492442, 4519.141363141026, 4520.50274469808, 4713.764311784397, 4786.688865223784, 4834.7824347904425, 5347.930413917232, 5512.531434828044, 2912.965897106539, 3319.8421026332835, 3755.3991361382323, 3762.360398732222, 3765.0963768695588, 5037.4458809530925, 5502.147167189052, 5646.209530665669, 5693.572982026365, 5767.318197038356, 3386.593608766964, 3437.0, 3792.2036372361563, 3813.8093095345675, 3831.5593706724085, 3416.4362880125, 3828.559715632738, 3861.189150278473, 3861.2663593917628, 3863.64772329688, 4129.943841557226, 4378.408914796525, 4502.234620114588, 4519.932201016987, 4534.6849413670325, 5438.374586922476, 5506.0, 5513.0, 5532.446755324516, 5558.416366281563, 1183.8579370475597, 1237.2020047069589, 1245.2092920995233, 1245.7134858982304, 1262.5833474953242, 786.9921300787033, 802.7190483330787, 803.9227435236811, 843.8692002739585, 853.5092321914924, 3928.744631598937, 4687.554682305175, 4687.5781179693995, 4899.4365647950945, 4927.482614325444, 4950.900981980412, 5070.645054846199, 5293.285406470154, 5293.311869456946, 5323.0, 4252.87241382759, 4254.0, 4778.37881075458, 5025.447200807908, 5233.267342572043, 2487.564676181661, 2583.974160258411, 2823.3082894690933, 2854.787539656741, 2858.771298296173, 5153.330067091254, 5317.441668624816, 5400.865818178227, 5585.776568937197, 5627.876528526707, 5243.947560524422, 5310.734463276821, 5458.536024437888, 5489.560835133259, 5508.785778879161, 4647.86056418308, 5524.530414914777, 5589.944100558942, 5632.324121105494, 5637.351704553947, 4918.6065114791445, 4994.525520075587, 5309.203619457112, 5348.037353276292, 5408.702521361363, 4754.4532378776885, 4809.278608208797, 5463.699496527729, 5717.742701578356, 5729.570282228933, 5208.583313335, 5290.947090529045, 5743.0811070228565, 5972.342307673044, 5972.552058595709, 3230.82230477326, 3668.0288965937675, 3856.6914646827627, 3872.3997780344093, 3934.9016274593364, 4791.832285869978, 5692.345380281321, 5721.113227449752, 5727.914081288701, 5730.71346432685, 4852.393450818648, 5521.018649928993, 5576.86057848557, 5585.609007369445, 5643.209950606836, 4947.3321101652255, 5163.586913047023, 5238.847453560211, 5611.747471363717, 5636.605437619327, 4785.688930219525, 5162.922556161586, 5432.375276843056, 5433.0, 5457.645253058538, 4749.35883655702, 4769.713817170981, 5430.484104010113, 5519.779208831602, 5543.972280138573, 5412.485813847836, 5444.671486168585, 5452.672839629635, 5455.0, 5490.286262785812], "value": 3948.8432924796684, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [687.168526083439, 747.835476195237, 754.2005474197351, 765.1506827421565, 770.6052045797106, 14610.926945365272, 14913.850861491383, 14966.700665986673, 14973.700525989487, 14977.775333370008, 13108.41012154453, 13227.603171904833, 13238.470461181565, 13255.53605623801, 13309.667258318546, 642.0914406115342, 664.5746722097862, 666.3503084492629, 666.6366830077595, 810.2869474862113, 664.5746722097862, 666.4834753066364, 791.0507391130637, 817.8200795824939, 819.5820131732829, 856.618804631936, 871.2289623683055, 874.8775171475999, 875.308506280038, 876.51791514667, 824.8350329933977, 824.9051359093721, 825.005867929144, 826.8511667899776, 867.3884911137695, 501.4634341254859, 515.0548743056496, 611.6360765344613, 644.5778015399874, 645.1838424393146, 13173.473061077546, 13675.521356752566, 13730.382132804049, 13997.79003314941, 14023.368948397347, 13533.661658458519, 13764.380602872894, 13944.51194208208, 13959.930200349036, 13970.650733731636, 9227.67703130394, 10723.0, 10837.83743243852, 11104.119701736536, 11484.138689598314, 3140.822191678121, 3484.61669216386, 3540.061883600848, 3647.6717095461536, 3663.4504824276305, 92556.0, 96542.0, 96983.51508242414, 97694.0, 98816.50591747067, 8951.820963580692, 10402.895971040343, 10519.368837869752, 10618.522166502526, 10639.521221544894, 20662.17351305961, 20859.0, 20960.895195523924, 21028.94855257231, 21147.154113835273, 7551.697932082655, 7584.355329796918, 7636.3890888728765, 7684.0, 7911.525308481395, 4209.726367786084, 4240.236757383639, 4319.524852266248, 4410.470743510735, 4451.554844515523, 3240.2709390386976, 3354.5974483062187, 3361.2269178089177, 3362.69735723786, 3365.1418888183357, 622.8256088295285, 623.8284471770208, 624.1012941364421, 628.1112226199876, 640.9230892292954, 523.9816606418757, 608.8660494691161, 627.7457629659995, 648.4391001783423, 658.3350815676106, 7325.194228634846, 8307.667693292322, 8419.452735572168, 8491.0, 8705.738827835175, 506.06630766236236, 559.7397210297231, 574.319431473705, 595.1251660059705, 660.303379934174, 4061.6953728470485, 4268.829246830092, 4327.32926396409, 4380.233459144637, 4415.933760993587, 95617.0, 99479.00520994698, 101549.0, 104475.95524044662, 120687.0, 66379.0, 67025.66487167533, 75755.86366204511, 92924.0, 95583.52208239054, 69036.92889213332, 75061.37039721933, 75313.0, 77651.61174194206, 84830.0, 7363.595002274927, 9346.19442951295, 9375.0, 9390.0, 9565.808683826424, 50860.0, 51487.97024059573, 52030.47969520408, 52053.479465204866, 53937.190942135116, 48244.7587762059, 62954.685226575406, 66636.66726665536, 75034.0, 79080.0, 11663.533458661559, 11923.284602923852, 12122.878771212176, 12155.513779448724, 12620.873791261969, 13251.469941202244, 13921.373538191008, 14237.572872813833, 14379.281035947952, 14985.550433487013, 12720.427580759073, 12870.485180593047, 12959.611211663665, 13341.066125371131, 13515.256660883746, 9449.763755905882, 12436.751264974831, 12835.743285134431, 13008.0, 13219.206847589176, 12085.274883507018, 12234.877651223374, 12285.754284914074, 12947.158434701714, 13018.80471792905, 10814.783704325711, 10824.296420732628, 11035.39305338214, 11047.668569942914, 11312.20814542974, 9747.512624368612, 9857.802843943224, 9880.703578892644, 10987.39569323695, 11184.664460066211], "value": 7036.751693519056, "unit": "score"}], "name": "dromaeo_css", "value": 7079.519497863389, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 119.0, 160.0, 170.0, 115.0, 117.0, 116.0, 115.0, 115.0, 115.0], "value": 124.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 109.0, 112.0, 143.0, 111.0, 109.0, 109.0, 110.0, 142.0, 114.0], "value": 120.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 180.0, 185.0, 186.0, 184.0, 186.0, 183.0, 185.0, 182.0, 186.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 59.0, 89.0, 63.0, 60.0, 59.0, 59.0, 81.0, 58.0], "value": 66.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 71.0, 95.0, 71.0, 68.0, 70.0, 91.0, 68.0, 69.0, 93.0], "value": 77.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 128.0, 89.0, 85.0, 89.0, 85.0, 87.0, 85.0], "value": 90.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 93.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 125.0, 120.0, 80.0, 81.0, 82.0, 83.0, 85.0, 84.0, 81.0], "value": 91.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 52.0, 53.0, 54.0, 55.0, 58.0, 59.0, 58.0, 56.0, 57.0], "value": 55.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 75.0, 81.0, 74.0, 73.0, 70.0, 70.0, 70.0, 69.0, 71.0], "value": 71.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 83.0, 83.0, 84.0, 97.0, 101.0, 102.0, 101.0, 85.0], "value": 90.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 162.0, 158.0, 142.0, 144.0, 143.0, 160.0, 158.0, 211.0, 149.0], "value": 157.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 147.0, 201.0, 171.0, 164.0, 166.0, 177.0, 163.0, 164.0, 163.0], "value": 167.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 67.0, 68.0, 68.0, 67.0, 67.0, 75.0, 81.0], "value": 69.5, "unit": "score"}], "name": "kraken", "value": 1462.2000000000003, "alertThreshold": 2.0}]} 03:02:03 INFO - Return code: 0 03:02:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:02:03 INFO - # TBPL SUCCESS # 03:02:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:02:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:02:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:02:03 INFO - 2016-12-30 03:02:03.427 screenresolution[1653:11113] starting screenresolution argv=screenresolution get 03:02:03 INFO - 2016-12-30 03:02:03.429 screenresolution[1653:11113] Display 0: 1600x1200x32@60 03:02:03 INFO - 2016-12-30 03:02:03.435 screenresolution[1654:11116] starting screenresolution argv=screenresolution list 03:02:03 INFO - Available Modes on Display 0 03:02:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:02:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:02:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:02:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:02:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:02:03 INFO - Intel Iris: 03:02:03 INFO - Chipset Model: Intel Iris 03:02:03 INFO - Type: GPU 03:02:03 INFO - Bus: Built-In 03:02:03 INFO - VRAM (Dynamic, Max): 1536 MB 03:02:03 INFO - Vendor: Intel (0x8086) 03:02:03 INFO - Device ID: 0x0a2e 03:02:03 INFO - Revision ID: 0x0009 03:02:03 INFO - Displays: 03:02:03 INFO - Display: 03:02:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:02:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:02:03 INFO - Display Serial Number: 1600x1200 60 03:02:03 INFO - Main Display: Yes 03:02:03 INFO - Mirror: Off 03:02:03 INFO - Online: Yes 03:02:03 INFO - Rotation: Supported 03:02:03 INFO - Return code: 0 03:02:03 INFO - Running post-action listener: _resource_record_post_action 03:02:03 INFO - [mozharness: 2016-12-30 11:02:03.670836Z] Finished run-tests step (success) 03:02:03 INFO - Running post-run listener: _resource_record_post_run 03:02:03 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 33631232; Write bytes: 660720640; Read time: 313; Write time: 1927 03:02:03 INFO - TinderboxPrint: CPU usage
53.6% 03:02:03 INFO - TinderboxPrint: I/O read bytes / time
33,631,232 / 313 03:02:03 INFO - TinderboxPrint: I/O write bytes / time
660,720,640 / 1,927 03:02:03 INFO - TinderboxPrint: CPU idle
2,253.7 (46.4%) 03:02:03 INFO - TinderboxPrint: CPU system
973.8 (20.1%) 03:02:03 INFO - TinderboxPrint: CPU user
1,626.2 (33.5%) 03:02:03 INFO - TinderboxPrint: Swap in / out
379,015,168 / 0 03:02:03 INFO - install - Wall time: 31s; CPU: 55.0%; Read bytes: 348287488; Write bytes: 346957824; Read time: 26826; Write time: 653 03:02:03 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 31525888; Write bytes: 306615296; Read time: 289; Write time: 1244 03:02:04 INFO - Running post-run listener: _upload_blobber_files 03:02:04 INFO - Blob upload gear active. 03:02:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:02:04 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:02:04 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'] 03:02:04 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 03:02:04 INFO - (blobuploader) - INFO - Open directory for files ... 03:02:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:02:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:02:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:02:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:02:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:02:05 INFO - (blobuploader) - INFO - Done attempting. 03:02:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:02:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:02:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:02:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:02:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:02:06 INFO - (blobuploader) - INFO - Done attempting. 03:02:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:02:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:02:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:02:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:02:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:02:09 INFO - (blobuploader) - INFO - Done attempting. 03:02:09 INFO - (blobuploader) - INFO - Iteration through files over. 03:02:09 INFO - Return code: 0 03:02:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:02:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:02:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d913f18717b03f605b3e5866dfb36a26ea0542ab8c48a6ee13067deda62fef1b1a28e5e3063dad35f5edcc8eaa930a94368af83bb8c085b1766c8766d2b67a85", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/68af339a9aff5118a158f7c2ddbfe75af739b91cf5654b49f706b0a4874e642f05f48efe7fa89c732d78e6fad6fda96e0857b8bacf73cb115fa61a56758c8077", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dd38f8301496e1957d4b13fae63fb924e9c2e9e35718fe0e7d523c4e2eb605812fffb4bc70f9f3f0aea94616f6a3259ad898e2d7cf6f99d86229eb7bac1f9d99"} 03:02:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:02:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:02:09 INFO - Contents: 03:02:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d913f18717b03f605b3e5866dfb36a26ea0542ab8c48a6ee13067deda62fef1b1a28e5e3063dad35f5edcc8eaa930a94368af83bb8c085b1766c8766d2b67a85", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/68af339a9aff5118a158f7c2ddbfe75af739b91cf5654b49f706b0a4874e642f05f48efe7fa89c732d78e6fad6fda96e0857b8bacf73cb115fa61a56758c8077", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dd38f8301496e1957d4b13fae63fb924e9c2e9e35718fe0e7d523c4e2eb605812fffb4bc70f9f3f0aea94616f6a3259ad898e2d7cf6f99d86229eb7bac1f9d99"} 03:02:09 INFO - Running post-run listener: copy_logs_to_upload_dir 03:02:09 INFO - Copying logs to upload dir... 03:02:09 INFO - mkdir: /builds/slave/test/build/upload/logs 03:02:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.555713 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-12-30 03:02:09.244679) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-30 03:02:09.248390) ========= 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.HqRzRZ31nq/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.89hKR3Tz7l/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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/d913f18717b03f605b3e5866dfb36a26ea0542ab8c48a6ee13067deda62fef1b1a28e5e3063dad35f5edcc8eaa930a94368af83bb8c085b1766c8766d2b67a85", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/68af339a9aff5118a158f7c2ddbfe75af739b91cf5654b49f706b0a4874e642f05f48efe7fa89c732d78e6fad6fda96e0857b8bacf73cb115fa61a56758c8077", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dd38f8301496e1957d4b13fae63fb924e9c2e9e35718fe0e7d523c4e2eb605812fffb4bc70f9f3f0aea94616f6a3259ad898e2d7cf6f99d86229eb7bac1f9d99"} build_url:https://queue.taskcluster.net/v1/task/DKKKlItpRyaJjiox7AGC8A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011268 build_url: 'https://queue.taskcluster.net/v1/task/DKKKlItpRyaJjiox7AGC8A/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/d913f18717b03f605b3e5866dfb36a26ea0542ab8c48a6ee13067deda62fef1b1a28e5e3063dad35f5edcc8eaa930a94368af83bb8c085b1766c8766d2b67a85", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/68af339a9aff5118a158f7c2ddbfe75af739b91cf5654b49f706b0a4874e642f05f48efe7fa89c732d78e6fad6fda96e0857b8bacf73cb115fa61a56758c8077", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dd38f8301496e1957d4b13fae63fb924e9c2e9e35718fe0e7d523c4e2eb605812fffb4bc70f9f3f0aea94616f6a3259ad898e2d7cf6f99d86229eb7bac1f9d99"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-30 03:02:09.282495) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 03:02:09.282886) ========= 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.HqRzRZ31nq/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.89hKR3Tz7l/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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.005153 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 03:02:09.331723) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-30 03:02:09.332053) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-30 03:02:09.332377) ========= ========= Total master_lag: 0.22 =========