builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0222 starttime: 1480958810.93 results: success (0) buildid: 20161205070638 builduid: ca6df37595034678b897c7b624369dfe revision: 46049b21980d67b13d6915c052a3025c273a1788 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-05 09:26:50.926060) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-05 09:26:50.926867) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-05 09:26:50.927230) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-05 09:26:50.953977) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 09:26:50.954393) ========= 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.UbR4I2wSN5/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.Nwd6lYAbbl/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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-05 09:26:50-- 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% 21.2M=0.001s 2016-12-05 09:26:51 (21.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.116094 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 09:26:51.100767) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 09:26:51.101133) ========= 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.UbR4I2wSN5/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.Nwd6lYAbbl/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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.025322 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 09:26:51.149980) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 09:26:51.150411) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 46049b21980d67b13d6915c052a3025c273a1788 --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 46049b21980d67b13d6915c052a3025c273a1788 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UbR4I2wSN5/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.Nwd6lYAbbl/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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-05 09:26:51,250 truncating revision to first 12 chars 2016-12-05 09:26:51,250 Setting DEBUG logging. 2016-12-05 09:26:51,250 attempt 1/10 2016-12-05 09:26:51,250 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/46049b21980d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-05 09:26:51,543 unpacking tar archive at: mozilla-aurora-46049b21980d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.572683 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 09:26:51.747230) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-05 09:26:51.747585) ========= script_repo_revision: 46049b21980d67b13d6915c052a3025c273a1788 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-05 09:26:51.748061) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-05 09:26:51.748379) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-05 09:26:51.768427) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-12-05 09:26:51.768882) ========= /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.UbR4I2wSN5/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.Nwd6lYAbbl/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:26:51 INFO - MultiFileLogger online at 20161205 09:26:51 in /builds/slave/test 09:26:51 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 09:26:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:26:51 INFO - {'append_to_log': False, 09:26:51 INFO - 'base_work_dir': '/builds/slave/test', 09:26:51 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:26:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:26:51 INFO - 'branch': 'Mozilla-Aurora', 09:26:51 INFO - 'buildbot_json_path': 'buildprops.json', 09:26:51 INFO - 'config_files': ('talos/mac_config.py',), 09:26:51 INFO - 'default_actions': ('clobber', 09:26:51 INFO - 'read-buildbot-config', 09:26:51 INFO - 'download-and-extract', 09:26:51 INFO - 'populate-webroot', 09:26:51 INFO - 'create-virtualenv', 09:26:51 INFO - 'install', 09:26:51 INFO - 'run-tests'), 09:26:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:26:51 INFO - 'download_minidump_stackwalk': True, 09:26:51 INFO - 'download_symbols': 'ondemand', 09:26:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:26:51 INFO - 'tooltool.py': '/tools/tooltool.py', 09:26:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:26:51 INFO - '/tools/misc-python/virtualenv.py')}, 09:26:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:26:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:26:51 INFO - 'installer_path': 'installer.exe', 09:26:51 INFO - 'log_level': 'info', 09:26:51 INFO - 'log_name': 'talos', 09:26:51 INFO - 'log_to_console': True, 09:26:51 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:26:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:26:51 INFO - 'opt_config_files': (), 09:26:51 INFO - 'pip_index': False, 09:26:51 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:26:51 INFO - 'cmd': ('bash', 09:26:51 INFO - '-c', 09:26:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:26:51 INFO - 'enabled': True, 09:26:51 INFO - 'halt_on_failure': False, 09:26:51 INFO - 'name': 'check_screen_resolution'},), 09:26:51 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:26:51 INFO - 'cmd': ('bash', 09:26:51 INFO - '-c', 09:26:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:26:51 INFO - 'enabled': True, 09:26:51 INFO - 'halt_on_failure': False, 09:26:51 INFO - 'name': 'check_screen_resolution'},), 09:26:51 INFO - 'run_cmd_checks_enabled': True, 09:26:51 INFO - 'sps_profile': False, 09:26:51 INFO - 'sps_profile_interval': 0, 09:26:51 INFO - 'suite': 'dromaeojs', 09:26:51 INFO - 'system_bits': '32', 09:26:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:26:51 INFO - 'title': 't-yosemite-r7-0222', 09:26:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:26:51 INFO - 'use_talos_json': True, 09:26:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:26:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:26:51 INFO - 'work_dir': 'build'} 09:26:51 INFO - [mozharness: 2016-12-05 17:26:51.993652Z] Running clobber step. 09:26:51 INFO - Running pre-action listener: _resource_record_pre_action 09:26:51 INFO - Running main action method: clobber 09:26:51 INFO - rmtree: /builds/slave/test/build 09:26:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:26:54 INFO - Running post-action listener: _resource_record_post_action 09:26:54 INFO - [mozharness: 2016-12-05 17:26:54.290801Z] Finished clobber step (success) 09:26:54 INFO - [mozharness: 2016-12-05 17:26:54.290976Z] Running read-buildbot-config step. 09:26:54 INFO - Running pre-action listener: _resource_record_pre_action 09:26:54 INFO - Running main action method: read_buildbot_config 09:26:54 INFO - Using buildbot properties: 09:26:54 INFO - { 09:26:54 INFO - "project": "", 09:26:54 INFO - "product": "firefox", 09:26:54 INFO - "script_repo_revision": "production", 09:26:54 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 09:26:54 INFO - "repository": "", 09:26:54 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 09:26:54 INFO - "buildid": "20161205070638", 09:26:54 INFO - "pgo_build": "False", 09:26:54 INFO - "basedir": "/builds/slave/test", 09:26:54 INFO - "buildnumber": 5, 09:26:54 INFO - "slavename": "t-yosemite-r7-0222", 09:26:54 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 09:26:54 INFO - "platform": "yosemite_r7", 09:26:54 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:26:54 INFO - "branch": "mozilla-aurora", 09:26:54 INFO - "repo_path": "releases/mozilla-aurora", 09:26:54 INFO - "revision": "46049b21980d67b13d6915c052a3025c273a1788", 09:26:54 INFO - "stage_platform": "macosx64", 09:26:54 INFO - "builduid": "ca6df37595034678b897c7b624369dfe", 09:26:54 INFO - "slavebuilddir": "test" 09:26:54 INFO - } 09:26:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 09:26:54 INFO - Running post-action listener: _resource_record_post_action 09:26:54 INFO - [mozharness: 2016-12-05 17:26:54.294659Z] Finished read-buildbot-config step (success) 09:26:54 INFO - [mozharness: 2016-12-05 17:26:54.294799Z] Running download-and-extract step. 09:26:54 INFO - Running pre-action listener: _resource_record_pre_action 09:26:54 INFO - Running main action method: download_and_extract 09:26:54 INFO - mkdir: /builds/slave/test/build/tests 09:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:54 INFO - trying https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 09:26:54 INFO - Downloading https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/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 09:26:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/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 09:26:55 INFO - Downloaded 1199 bytes. 09:26:55 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:26:55 INFO - Using the following test package requirements: 09:26:55 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:26:55 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:26:55 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 09:26:55 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:26:55 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 09:26:55 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:26:55 INFO - u'jsshell-mac.zip'], 09:26:55 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:26:55 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 09:26:55 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:26:55 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:26:55 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 09:26:55 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:26:55 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 09:26:55 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:26:55 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 09:26:55 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:26:55 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 09:26:55 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:26:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:26:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:26:55 INFO - Fetch https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:26:55 INFO - Http code: 200 09:26:55 INFO - via: 1.1 c0740de9f16cf32ffedfd2f9806b8483.cloudfront.net (CloudFront) 09:26:55 INFO - x-amz-cf-id: c0c-Cbt8odQH9UOU68mlyWIYkxTx1dP65v4coJLm8vFGaM5TS6o0rA== 09:26:55 INFO - x-amz-version-id: t9xxaFr6hPXSmRJLA66VGMjwUVK_akvk 09:26:56 INFO - Expected file size: 16282010 09:26:56 INFO - Obtained file size: 16282010 09:27: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 09:27:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:27:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:27:03 INFO - Fetch https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:27:03 INFO - Http code: 200 09:27:03 INFO - via: 1.1 d2bd759914e30b1d5aee2929535c55f9.cloudfront.net (CloudFront) 09:27:03 INFO - x-amz-cf-id: MUU24nXB5_C99tGB_fYNxk2QmCunHSqWgMC8i5IPKMcYz7maZpkzgQ== 09:27:03 INFO - x-amz-version-id: t9xxaFr6hPXSmRJLA66VGMjwUVK_akvk 09:27:03 INFO - Expected file size: 16282010 09:27:03 INFO - Obtained file size: 16282010 09:27:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 09:27:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:27:10 INFO - Fetch https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 09:27:10 INFO - Http code: 200 09:27:10 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 09:27:10 INFO - x-amz-cf-id: 6KaZaMFAwf9mpq-b50zpraG3IBtd_QPxP-piM1wlkT9qIt13xJuR4A== 09:27:10 INFO - x-amz-version-id: BePxp_qK1o2iig7g1R8kQ1LEMUwGwUAH 09:27:11 INFO - Expected file size: 14409038 09:27:11 INFO - Obtained file size: 14409038 09:27:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:11 INFO - trying https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:27:11 INFO - Downloading https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 09:27:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/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 09:27:19 INFO - Downloaded 109762604 bytes. 09:27:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:27:19 INFO - mkdir: /builds/slave/test/properties 09:27:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:27:19 INFO - Writing to file /builds/slave/test/properties/build_url 09:27:19 INFO - Contents: 09:27:19 INFO - build_url:https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:27:20 INFO - Running post-action listener: _resource_record_post_action 09:27:20 INFO - Running post-action listener: set_extra_try_arguments 09:27:20 INFO - [mozharness: 2016-12-05 17:27:20.651068Z] Finished download-and-extract step (success) 09:27:20 INFO - [mozharness: 2016-12-05 17:27:20.651205Z] Running populate-webroot step. 09:27:20 INFO - Running pre-action listener: _resource_record_pre_action 09:27:20 INFO - Running main action method: populate_webroot 09:27:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:27:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:27:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:27:20 INFO - '--tppagecycles', 09:27:20 INFO - '7'], 09:27:20 INFO - 'tests': ['tsvgm']}}, 09:27:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:27:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:27:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:27:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:27:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:27:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:27:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:20 INFO - 'talos_options': ['--disable-e10s'], 09:27:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:27:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:27:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:20 INFO - 'talos_options': ['--disable-e10s'], 09:27:20 INFO - 'tests': ['damp', 'tps']}, 09:27:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:20 INFO - 'tests': ['damp', 'tps']}, 09:27:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:27:20 INFO - 'tests': ['dromaeo_dom']}, 09:27:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:27:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:27:20 INFO - 'tests': ['basic_compositor_video']}, 09:27:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:27:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:27:20 INFO - 'tests': ['a11yr', 09:27:20 INFO - 'ts_paint', 09:27:20 INFO - 'tpaint', 09:27:20 INFO - 'sessionrestore', 09:27:20 INFO - 'sessionrestore_no_auto_restore', 09:27:20 INFO - 'tabpaint']}, 09:27:20 INFO - 'other-e10s': {'tests': ['a11yr', 09:27:20 INFO - 'ts_paint', 09:27:20 INFO - 'tpaint', 09:27:20 INFO - 'sessionrestore', 09:27:20 INFO - 'sessionrestore_no_auto_restore', 09:27:20 INFO - 'tabpaint']}, 09:27:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:27:20 INFO - 'ts_paint', 09:27:20 INFO - 'tpaint', 09:27:20 INFO - 'sessionrestore', 09:27:20 INFO - 'sessionrestore_no_auto_restore', 09:27:20 INFO - 'tabpaint']}, 09:27:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:27:20 INFO - 'ts_paint', 09:27:20 INFO - 'tpaint', 09:27:20 INFO - 'sessionrestore', 09:27:20 INFO - 'sessionrestore_no_auto_restore', 09:27:20 INFO - 'tabpaint']}, 09:27:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:27:20 INFO - 'tests': ['a11yr', 09:27:20 INFO - 'ts_paint', 09:27:20 INFO - 'tpaint', 09:27:20 INFO - 'sessionrestore', 09:27:20 INFO - 'sessionrestore_no_auto_restore', 09:27:20 INFO - 'tabpaint']}, 09:27:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:27:20 INFO - 'tests': ['a11yr', 09:27:20 INFO - 'ts_paint', 09:27:20 INFO - 'tpaint', 09:27:20 INFO - 'sessionrestore', 09:27:20 INFO - 'sessionrestore_no_auto_restore', 09:27:20 INFO - 'tabpaint']}, 09:27:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:27:20 INFO - 'tests': ['tsvgx', 09:27:20 INFO - 'tsvgr_opacity', 09:27:20 INFO - 'tart', 09:27:20 INFO - 'tscrollx', 09:27:20 INFO - 'cart']}, 09:27:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:27:20 INFO - 'tsvgr_opacity', 09:27:20 INFO - 'tart', 09:27:20 INFO - 'tscrollx', 09:27:20 INFO - 'cart']}, 09:27:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:20 INFO - 'talos_options': ['--disable-e10s'], 09:27:20 INFO - 'tests': ['tp5o']}, 09:27:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:20 INFO - 'tests': ['tp5o']}, 09:27:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:20 INFO - 'talos_options': ['--disable-e10s', 09:27:20 INFO - '--xperf_path', 09:27:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:27:20 INFO - 'tests': ['tp5n']}, 09:27:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:20 INFO - 'talos_options': ['--xperf_path', 09:27:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:27:20 INFO - 'tests': ['tp5n']}}, 09:27:20 INFO - 'talos.zip': {'path': '', 09:27:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:27:20 INFO - Running post-action listener: _resource_record_post_action 09:27:20 INFO - [mozharness: 2016-12-05 17:27:20.670228Z] Finished populate-webroot step (success) 09:27:20 INFO - [mozharness: 2016-12-05 17:27:20.670366Z] Running create-virtualenv step. 09:27:20 INFO - Running pre-action listener: _resource_record_pre_action 09:27:20 INFO - Running main action method: create_virtualenv 09:27:20 INFO - Creating virtualenv /builds/slave/test/build/venv 09:27:20 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:27:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:27:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:27:20 INFO - Using real prefix '/tools/python27' 09:27:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:27:21 INFO - Installing distribute.............................................................................................................................................................................................done. 09:27:24 INFO - Installing pip.................done. 09:27:24 INFO - Return code: 0 09:27:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:27:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:27:24 INFO - Reading from file tmpfile_stdout 09:27:24 INFO - Output received: 09:27:24 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) 09:27:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:27:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104205490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10417b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd291597200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104019d30>, '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 0x103e7fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104011b20>, '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.UbR4I2wSN5/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.Nwd6lYAbbl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:27:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:27:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:27:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UbR4I2wSN5/Render', 09:27:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:24 INFO - 'HOME': '/Users/cltbld', 09:27:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:24 INFO - 'LOGNAME': 'cltbld', 09:27:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:24 INFO - 'MOZ_NO_REMOTE': '1', 09:27:24 INFO - 'NO_EM_RESTART': '1', 09:27:24 INFO - 'PAGER': '/bin/cat', 09:27:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:24 INFO - 'PWD': '/builds/slave/test', 09:27:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:24 INFO - 'SHELL': '/bin/bash', 09:27:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Nwd6lYAbbl/Listeners', 09:27:24 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 09:27:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:24 INFO - 'USER': 'cltbld', 09:27:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:24 INFO - 'XPC_FLAGS': '0x0', 09:27:24 INFO - 'XPC_SERVICE_NAME': '0', 09:27:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:24 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:27:24 INFO - Cleaning up... 09:27:24 INFO - Return code: 0 09:27:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:27:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104205490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10417b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd291597200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104019d30>, '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 0x103e7fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104011b20>, '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.UbR4I2wSN5/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.Nwd6lYAbbl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:27:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:27:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:27:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UbR4I2wSN5/Render', 09:27:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:24 INFO - 'HOME': '/Users/cltbld', 09:27:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:24 INFO - 'LOGNAME': 'cltbld', 09:27:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:24 INFO - 'MOZ_NO_REMOTE': '1', 09:27:24 INFO - 'NO_EM_RESTART': '1', 09:27:24 INFO - 'PAGER': '/bin/cat', 09:27:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:24 INFO - 'PWD': '/builds/slave/test', 09:27:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:24 INFO - 'SHELL': '/bin/bash', 09:27:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Nwd6lYAbbl/Listeners', 09:27:24 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 09:27:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:24 INFO - 'USER': 'cltbld', 09:27:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:24 INFO - 'XPC_FLAGS': '0x0', 09:27:24 INFO - 'XPC_SERVICE_NAME': '0', 09:27:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:25 INFO - Downloading/unpacking psutil>=3.1.1 09:27:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:27:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:27:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:27:29 INFO - Installing collected packages: psutil 09:27:29 INFO - Running setup.py install for psutil 09:27:29 INFO - building 'psutil._psutil_osx' extension 09:27:29 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:27:29 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:27:29 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:27:29 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:27:30 INFO - building 'psutil._psutil_posix' extension 09:27:30 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:27:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:27:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:27:30 INFO - ^ 09:27:30 INFO - 1 warning generated. 09:27:30 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:27:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:27:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:27:30 INFO - Successfully installed psutil 09:27:30 INFO - Cleaning up... 09:27:30 INFO - Return code: 0 09:27:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:27: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')]} 09:27:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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')]} 09:27:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104205490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10417b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd291597200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104019d30>, '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 0x103e7fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104011b20>, '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.UbR4I2wSN5/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.Nwd6lYAbbl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:27: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:27: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 mozsystemmonitor==0.3 09:27:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UbR4I2wSN5/Render', 09:27:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:30 INFO - 'HOME': '/Users/cltbld', 09:27:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:30 INFO - 'LOGNAME': 'cltbld', 09:27:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:30 INFO - 'MOZ_NO_REMOTE': '1', 09:27:30 INFO - 'NO_EM_RESTART': '1', 09:27:30 INFO - 'PAGER': '/bin/cat', 09:27:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:30 INFO - 'PWD': '/builds/slave/test', 09:27:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:30 INFO - 'SHELL': '/bin/bash', 09:27:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Nwd6lYAbbl/Listeners', 09:27:30 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 09:27:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:30 INFO - 'USER': 'cltbld', 09:27:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:30 INFO - 'XPC_FLAGS': '0x0', 09:27:30 INFO - 'XPC_SERVICE_NAME': '0', 09:27:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:27:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:27:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:27:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:27:33 INFO - Installing collected packages: mozsystemmonitor 09:27:33 INFO - Running setup.py install for mozsystemmonitor 09:27:33 INFO - Successfully installed mozsystemmonitor 09:27:33 INFO - Cleaning up... 09:27:33 INFO - Return code: 0 09:27:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:27: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')]} 09:27:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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')]} 09:27:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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', '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 0x104205490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10417b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd291597200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104019d30>, '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 0x103e7fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104011b20>, '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.UbR4I2wSN5/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.Nwd6lYAbbl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:27: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:27: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 jsonschema==2.5.1 09:27:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UbR4I2wSN5/Render', 09:27:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:33 INFO - 'HOME': '/Users/cltbld', 09:27:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:33 INFO - 'LOGNAME': 'cltbld', 09:27:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:33 INFO - 'MOZ_NO_REMOTE': '1', 09:27:33 INFO - 'NO_EM_RESTART': '1', 09:27:33 INFO - 'PAGER': '/bin/cat', 09:27:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:33 INFO - 'PWD': '/builds/slave/test', 09:27:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:33 INFO - 'SHELL': '/bin/bash', 09:27:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Nwd6lYAbbl/Listeners', 09:27:33 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 09:27:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:33 INFO - 'USER': 'cltbld', 09:27:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:33 INFO - 'XPC_FLAGS': '0x0', 09:27:33 INFO - 'XPC_SERVICE_NAME': '0', 09:27:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:33 INFO - Downloading/unpacking jsonschema==2.5.1 09:27:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:27:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:27: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 09:27: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 09:27: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 09:27: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 09:27: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 09:27: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 09:27:36 INFO - Downloading functools32-3.2.3-2.tar.gz 09:27:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:27:36 INFO - warning: no files found matching '*.txt' 09:27:36 INFO - no previously-included directories found matching 'build' 09:27:36 INFO - no previously-included directories found matching 'dist' 09:27:36 INFO - no previously-included directories found matching '.git*' 09:27:36 INFO - Installing collected packages: jsonschema, functools32 09:27:36 INFO - Running setup.py install for functools32 09:27:36 INFO - warning: no files found matching '*.txt' 09:27:36 INFO - no previously-included directories found matching 'build' 09:27:36 INFO - no previously-included directories found matching 'dist' 09:27:36 INFO - no previously-included directories found matching '.git*' 09:27:37 INFO - Successfully installed jsonschema functools32 09:27:37 INFO - Cleaning up... 09:27:37 INFO - Return code: 0 09:27:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:27:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104205490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10417b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd291597200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104019d30>, '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 0x103e7fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104011b20>, '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.UbR4I2wSN5/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.Nwd6lYAbbl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:27:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:27:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:27:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UbR4I2wSN5/Render', 09:27:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:37 INFO - 'HOME': '/Users/cltbld', 09:27:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:37 INFO - 'LOGNAME': 'cltbld', 09:27:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:37 INFO - 'MOZ_NO_REMOTE': '1', 09:27:37 INFO - 'NO_EM_RESTART': '1', 09:27:37 INFO - 'PAGER': '/bin/cat', 09:27:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:37 INFO - 'PWD': '/builds/slave/test', 09:27:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:37 INFO - 'SHELL': '/bin/bash', 09:27:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Nwd6lYAbbl/Listeners', 09:27:37 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 09:27:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:37 INFO - 'USER': 'cltbld', 09:27:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:37 INFO - 'XPC_FLAGS': '0x0', 09:27:37 INFO - 'XPC_SERVICE_NAME': '0', 09:27:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:27:37 INFO - Cleaning up... 09:27:37 INFO - Return code: 0 09:27:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:27:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104205490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10417b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd291597200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104019d30>, '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 0x103e7fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104011b20>, '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.UbR4I2wSN5/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.Nwd6lYAbbl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:27:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:27:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:27:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UbR4I2wSN5/Render', 09:27:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:37 INFO - 'HOME': '/Users/cltbld', 09:27:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:37 INFO - 'LOGNAME': 'cltbld', 09:27:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:37 INFO - 'MOZ_NO_REMOTE': '1', 09:27:37 INFO - 'NO_EM_RESTART': '1', 09:27:37 INFO - 'PAGER': '/bin/cat', 09:27:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:37 INFO - 'PWD': '/builds/slave/test', 09:27:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:37 INFO - 'SHELL': '/bin/bash', 09:27:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Nwd6lYAbbl/Listeners', 09:27:37 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 09:27:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:37 INFO - 'USER': 'cltbld', 09:27:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:37 INFO - 'XPC_FLAGS': '0x0', 09:27:37 INFO - 'XPC_SERVICE_NAME': '0', 09:27:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:37 INFO - Downloading/unpacking blobuploader==1.2.4 09:27: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 09:27: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 09:27: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 09:27: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 09:27: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 09:27: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 09:27:40 INFO - Downloading blobuploader-1.2.4.tar.gz 09:27:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:27:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:27:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:27:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:27:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:41 INFO - Downloading docopt-0.6.1.tar.gz 09:27:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:27:41 INFO - Installing collected packages: blobuploader, requests, docopt 09:27:41 INFO - Running setup.py install for blobuploader 09:27:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:27:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:27:41 INFO - Running setup.py install for requests 09:27:42 INFO - Running setup.py install for docopt 09:27:42 INFO - Successfully installed blobuploader requests docopt 09:27:42 INFO - Cleaning up... 09:27:42 INFO - Return code: 0 09:27:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:42 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 0x104205490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10417b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd291597200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104019d30>, '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 0x103e7fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104011b20>, '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.UbR4I2wSN5/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.Nwd6lYAbbl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:27:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:27:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:27:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UbR4I2wSN5/Render', 09:27:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:42 INFO - 'HOME': '/Users/cltbld', 09:27:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:42 INFO - 'LOGNAME': 'cltbld', 09:27:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:42 INFO - 'MOZ_NO_REMOTE': '1', 09:27:42 INFO - 'NO_EM_RESTART': '1', 09:27:42 INFO - 'PAGER': '/bin/cat', 09:27:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:42 INFO - 'PWD': '/builds/slave/test', 09:27:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:42 INFO - 'SHELL': '/bin/bash', 09:27:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Nwd6lYAbbl/Listeners', 09:27:42 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 09:27:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:42 INFO - 'USER': 'cltbld', 09:27:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:42 INFO - 'XPC_FLAGS': '0x0', 09:27:42 INFO - 'XPC_SERVICE_NAME': '0', 09:27:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:27:42 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-alw6Ww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:27:42 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-l6Nfpd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:27:42 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-TN1BaG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:27:42 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-Aos45W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:27:43 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-hj3nqj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:27:43 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-GamiiE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:27:43 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-k5C1Ln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:27:43 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-novNLJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:27:43 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-pxP5P9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:27:43 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-KSJCB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:27:43 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-4Xw_Yk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:27:43 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-RYYs6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:27:43 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-9uS4RA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:27:43 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-k1N2Hs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:44 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-5mYhcU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:27:44 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-r_7t4O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:27:44 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-Ne4b1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:27:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:27:44 INFO - Running setup.py install for manifestparser 09:27:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:27:44 INFO - Running setup.py install for mozcrash 09:27:44 INFO - Running setup.py install for mozdebug 09:27:44 INFO - Running setup.py install for mozdevice 09:27:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:27:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:27:44 INFO - Running setup.py install for mozfile 09:27:45 INFO - Running setup.py install for mozhttpd 09:27:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:27:45 INFO - Running setup.py install for mozinfo 09:27:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:27:45 INFO - Running setup.py install for mozInstall 09:27:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:27:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:27:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:27:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:27:45 INFO - Running setup.py install for mozleak 09:27:45 INFO - Running setup.py install for mozlog 09:27:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:27:45 INFO - Running setup.py install for moznetwork 09:27:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:27:46 INFO - Running setup.py install for mozprocess 09:27:46 INFO - Running setup.py install for mozprofile 09:27:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:27:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:27:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:27:46 INFO - Running setup.py install for mozrunner 09:27:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:27:46 INFO - Running setup.py install for mozscreenshot 09:27:46 INFO - Running setup.py install for moztest 09:27:46 INFO - Running setup.py install for mozversion 09:27:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:27:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:27:46 INFO - Cleaning up... 09:27:46 INFO - Return code: 0 09:27:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:46 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 0x104205490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10417b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd291597200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104019d30>, '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 0x103e7fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104011b20>, '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.UbR4I2wSN5/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.Nwd6lYAbbl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:27:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:27:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:27:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UbR4I2wSN5/Render', 09:27:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:46 INFO - 'HOME': '/Users/cltbld', 09:27:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:46 INFO - 'LOGNAME': 'cltbld', 09:27:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:46 INFO - 'MOZ_NO_REMOTE': '1', 09:27:46 INFO - 'NO_EM_RESTART': '1', 09:27:46 INFO - 'PAGER': '/bin/cat', 09:27:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:46 INFO - 'PWD': '/builds/slave/test', 09:27:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:46 INFO - 'SHELL': '/bin/bash', 09:27:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Nwd6lYAbbl/Listeners', 09:27:46 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 09:27:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:46 INFO - 'USER': 'cltbld', 09:27:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:46 INFO - 'XPC_FLAGS': '0x0', 09:27:46 INFO - 'XPC_SERVICE_NAME': '0', 09:27:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:27:47 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-3Bm6Po-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:27:47 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:27:47 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-LNexZj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:27:47 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)) 09:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:27:47 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-7KpUQK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:27:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:27:47 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-Cr9XOs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:27:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:27:47 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-H69qqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:27:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:27:47 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-QwgKYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:27:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:27:47 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-kHy4AW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:27:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:27:47 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-4yBR1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:27:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:27:48 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-RD_FMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:27:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:27:48 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-BEnesG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:27:48 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)) 09:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:27:48 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-fbLGGa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:27:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:27:48 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-84S5_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:27:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:27:48 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-AtjNwF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:27:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:27:48 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-m4sNDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:27:48 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)) 09:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:48 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-E5WBJh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:27:48 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-7WceLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:27:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:27:48 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-J0Jf_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:27:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:27:49 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)) 09:27:49 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)) 09:27:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:27:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:27:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:27:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:51 INFO - Downloading blessings-1.6.tar.gz 09:27:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:27:51 INFO - Installing collected packages: blessings 09:27:51 INFO - Running setup.py install for blessings 09:27:51 INFO - Successfully installed blessings 09:27:51 INFO - Cleaning up... 09:27:51 INFO - Return code: 0 09:27:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:27:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:27:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:27:52 INFO - Reading from file tmpfile_stdout 09:27:52 INFO - Current package versions: 09:27:52 INFO - blessings == 1.6 09:27:52 INFO - blobuploader == 1.2.4 09:27:52 INFO - docopt == 0.6.1 09:27:52 INFO - functools32 == 3.2.3-2 09:27:52 INFO - jsonschema == 2.5.1 09:27:52 INFO - manifestparser == 1.1 09:27:52 INFO - mozInstall == 1.12 09:27:52 INFO - mozcrash == 1.0 09:27:52 INFO - mozdebug == 0.1 09:27:52 INFO - mozdevice == 0.48 09:27:52 INFO - mozfile == 1.2 09:27:52 INFO - mozhttpd == 0.7 09:27:52 INFO - mozinfo == 0.9 09:27:52 INFO - mozleak == 0.1 09:27:52 INFO - mozlog == 3.4 09:27:52 INFO - moznetwork == 0.27 09:27:52 INFO - mozprocess == 0.23 09:27:52 INFO - mozprofile == 0.28 09:27:52 INFO - mozrunner == 6.13 09:27:52 INFO - mozscreenshot == 0.1 09:27:52 INFO - mozsystemmonitor == 0.3 09:27:52 INFO - moztest == 0.7 09:27:52 INFO - mozversion == 1.4 09:27:52 INFO - psutil == 3.1.1 09:27:52 INFO - requests == 1.2.3 09:27:52 INFO - wsgiref == 0.1.2 09:27:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:52 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 0x104205490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10417b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd291597200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104019d30>, '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 0x103e7fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104011b20>, '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.UbR4I2wSN5/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.Nwd6lYAbbl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:27:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:27:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:27:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UbR4I2wSN5/Render', 09:27:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:52 INFO - 'HOME': '/Users/cltbld', 09:27:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:52 INFO - 'LOGNAME': 'cltbld', 09:27:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:52 INFO - 'MOZ_NO_REMOTE': '1', 09:27:52 INFO - 'NO_EM_RESTART': '1', 09:27:52 INFO - 'PAGER': '/bin/cat', 09:27:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:52 INFO - 'PWD': '/builds/slave/test', 09:27:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:52 INFO - 'SHELL': '/bin/bash', 09:27:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Nwd6lYAbbl/Listeners', 09:27:52 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 09:27:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:52 INFO - 'USER': 'cltbld', 09:27:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:52 INFO - 'XPC_FLAGS': '0x0', 09:27:52 INFO - 'XPC_SERVICE_NAME': '0', 09:27:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:27:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:27:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:27:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:27:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:27:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:27:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:27:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:27:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:27:52 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:27:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:27:52 INFO - Cleaning up... 09:27:52 INFO - Return code: 0 09:27:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:27:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:52 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 0x104205490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10417b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd291597200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104019d30>, '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 0x103e7fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104011b20>, '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.UbR4I2wSN5/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.Nwd6lYAbbl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:27:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:27:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:27:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UbR4I2wSN5/Render', 09:27:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:52 INFO - 'HOME': '/Users/cltbld', 09:27:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:52 INFO - 'LOGNAME': 'cltbld', 09:27:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:52 INFO - 'MOZ_NO_REMOTE': '1', 09:27:52 INFO - 'NO_EM_RESTART': '1', 09:27:52 INFO - 'PAGER': '/bin/cat', 09:27:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:52 INFO - 'PWD': '/builds/slave/test', 09:27:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:52 INFO - 'SHELL': '/bin/bash', 09:27:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Nwd6lYAbbl/Listeners', 09:27:52 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 09:27:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:52 INFO - 'USER': 'cltbld', 09:27:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:52 INFO - 'XPC_FLAGS': '0x0', 09:27:52 INFO - 'XPC_SERVICE_NAME': '0', 09:27:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:27:52 INFO - Cleaning up... 09:27:52 INFO - Return code: 0 09:27:52 INFO - Running post-action listener: _resource_record_post_action 09:27:52 INFO - Running post-action listener: _start_resource_monitoring 09:27:52 INFO - Starting resource monitoring. 09:27:52 INFO - [mozharness: 2016-12-05 17:27:52.742264Z] Finished create-virtualenv step (success) 09:27:52 INFO - [mozharness: 2016-12-05 17:27:52.743322Z] Running install step. 09:27:52 INFO - Running pre-action listener: _resource_record_pre_action 09:27:52 INFO - Running main action method: install 09:27:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:27:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:27:53 INFO - Reading from file tmpfile_stdout 09:27:53 INFO - Detecting whether we're running mozinstall >=1.0... 09:27:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:27:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:27:53 INFO - Reading from file tmpfile_stdout 09:27:53 INFO - Output received: 09:27:53 INFO - Usage: mozinstall [options] installer 09:27:53 INFO - Options: 09:27:53 INFO - -h, --help show this help message and exit 09:27:53 INFO - -d DEST, --destination=DEST 09:27:53 INFO - Directory to install application into. [default: 09:27:53 INFO - "/builds/slave/test"] 09:27:53 INFO - --app=APP Application being installed. [default: firefox] 09:27:53 INFO - mkdir: /builds/slave/test/build/application 09:27:53 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'] 09:27:53 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 09:28:23 INFO - Reading from file tmpfile_stdout 09:28:23 INFO - Output received: 09:28:23 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:28:23 INFO - Running post-action listener: _resource_record_post_action 09:28:23 INFO - [mozharness: 2016-12-05 17:28:23.834685Z] Finished install step (success) 09:28:23 INFO - [mozharness: 2016-12-05 17:28:23.834851Z] Running run-tests step. 09:28:23 INFO - Running pre-action listener: _resource_record_pre_action 09:28:23 INFO - Running main action method: run_tests 09:28:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:28:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:28:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:28:23 INFO - 2016-12-05 09:28:23.849 screenresolution[1636:8680] starting screenresolution argv=screenresolution get 09:28:23 INFO - 2016-12-05 09:28:23.851 screenresolution[1636:8680] Display 0: 1600x1200x32@60 09:28:23 INFO - 2016-12-05 09:28:23.857 screenresolution[1637:8684] starting screenresolution argv=screenresolution list 09:28:23 INFO - Available Modes on Display 0 09:28:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:28:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:28:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:28:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:28:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:28:24 INFO - Intel Iris: 09:28:24 INFO - Chipset Model: Intel Iris 09:28:24 INFO - Type: GPU 09:28:24 INFO - Bus: Built-In 09:28:24 INFO - VRAM (Dynamic, Max): 1536 MB 09:28:24 INFO - Vendor: Intel (0x8086) 09:28:24 INFO - Device ID: 0x0a2e 09:28:24 INFO - Revision ID: 0x0009 09:28:24 INFO - Displays: 09:28:24 INFO - Display: 09:28:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:28:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:28:24 INFO - Display Serial Number: 1600x1200 60 09:28:24 INFO - Main Display: Yes 09:28:24 INFO - Mirror: Off 09:28:24 INFO - Online: Yes 09:28:24 INFO - Rotation: Supported 09:28:24 INFO - Return code: 0 09:28:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:28:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:28:24 INFO - Python 2.7.3 09:28:24 INFO - Return code: 0 09:28:24 INFO - grabbing minidump binary from tooltool 09:28:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:28:24 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 0x104019d30>, '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 0x103e7fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104011b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:28:24 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:28:24 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:28:24 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:28:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:28:24 INFO - Return code: 0 09:28:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:28:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:28:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:28:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:28:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:28:24 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/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0222', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:28:24 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/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0222 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:28:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UbR4I2wSN5/Render', 09:28:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:28:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:28:24 INFO - 'HOME': '/Users/cltbld', 09:28:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:28:24 INFO - 'LOGNAME': 'cltbld', 09:28:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:28:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:28:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:24 INFO - 'MOZ_NO_REMOTE': '1', 09:28:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:28:24 INFO - 'NO_EM_RESTART': '1', 09:28:24 INFO - 'PAGER': '/bin/cat', 09:28:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:28:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:24 INFO - 'PWD': '/builds/slave/test', 09:28:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:28:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:28:24 INFO - 'SHELL': '/bin/bash', 09:28:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Nwd6lYAbbl/Listeners', 09:28:24 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 09:28:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:28:24 INFO - 'USER': 'cltbld', 09:28:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:28:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:28:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:24 INFO - 'XPC_FLAGS': '0x0', 09:28:24 INFO - 'XPC_SERVICE_NAME': '0', 09:28:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:28:24 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/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0222', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:28:24 INFO - mozversion application_buildid: 20161205070638 09:28:24 INFO - mozversion application_changeset: 46049b21980d67b13d6915c052a3025c273a1788 09:28:24 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:28:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:28:24 INFO - mozversion application_name: Firefox 09:28:24 INFO - mozversion application_remotingname: firefox-dev 09:28:24 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:28:24 INFO - mozversion application_vendor: Mozilla 09:28:24 INFO - mozversion application_version: 52.0a2 09:28:24 INFO - mozversion platform_buildid: 20161205070638 09:28:24 INFO - mozversion platform_changeset: 46049b21980d67b13d6915c052a3025c273a1788 09:28:24 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:28:24 INFO - mozversion platform_version: 52.0a2 09:28:24 INFO - using testdate: 1480958904 09:28:24 INFO - actual date: 1480958904 09:28:24 INFO - starting webserver on 'localhost:49255' 09:28:24 INFO - SUITE-START | Running 2 tests 09:28:24 INFO - TEST-START | dromaeo_css 09:28:24 INFO - operating with platform_type : mac_ 09:28:24 INFO - Initialising browser for dromaeo_css test... 09:28:24 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/tmpyKgsNs/profile http://localhost:49255/getInfo.html) 09:28:27 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 09:28:27 INFO - PROCESS | 1647 | colorDepth:24 09:28:27 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 09:28:27 INFO - PROCESS | 1647 | __metrics 09:28:27 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" 09:28:27 INFO - PROCESS | 1647 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:28:27 INFO - TEST-INFO | 1647: exit 0 09:28:27 INFO - Browser initialized. 09:28:27 INFO - Running cycle 1/1 for dromaeo_css test... 09:28:27 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/tmpyKgsNs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:28:40 INFO - PROCESS | 1650 | RSS: Main: 222646272 09:28:40 INFO - PROCESS | 1650 | 09:29:31 INFO - PROCESS | 1650 | 1480958971914 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:31:45 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49255/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49255/tests/dromaeo/cssquery-ext.html) 09:31:47 INFO - PROCESS | 1650 | RSS: Main: 397869056 09:31:47 INFO - PROCESS | 1650 | 09:34:50 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49255/tests/dromaeo/cssquery-ext.html (next: http://localhost:49255/tests/dromaeo/cssquery-jquery.html) 09:34:50 INFO - PROCESS | 1650 | RSS: Main: 351977472 09:34:50 INFO - PROCESS | 1650 | 09:37:53 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49255/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49255/tests/dromaeo/cssquery-mootools.html) 09:37:53 INFO - PROCESS | 1650 | RSS: Main: 326840320 09:37:53 INFO - PROCESS | 1650 | 09:40:56 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49255/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49255/tests/dromaeo/cssquery-prototype.html) 09:40:56 INFO - PROCESS | 1650 | RSS: Main: 316526592 09:40:56 INFO - PROCESS | 1650 | 09:44:00 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49255/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49255/tests/dromaeo/cssquery-yui.html) 09:44:00 INFO - PROCESS | 1650 | RSS: Main: 308948992 09:44:00 INFO - PROCESS | 1650 | 09:47:04 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49255/tests/dromaeo/cssquery-yui.html (next: http://localhost:49255/tests/dromaeo/cssquery-dojo.html) 09:47:04 INFO - PROCESS | 1650 | RSS: Main: 355663872 09:47:04 INFO - PROCESS | 1650 | 09:47:04 INFO - PROCESS | 1650 | __start_tp_report 09:47:04 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 09:47:04 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 09:47:04 INFO - PROCESS | 1650 | |i|pagename|runs| 09:47:04 INFO - PROCESS | 1650 | |0;dojo.html;3334.083127140036;3355.7986520808754;3375.0718552398102;3399.456087026076;3424.623291437943;5705.087186050233;5749.453801888824;5781.421857814216;5800.216970708953;5801.53587712983;5691.573132015094;5780.393058728836;5791.913121303184;5794.942050579493;5798.507126894219;5175.767090480928;5235.947640523603;5252.501012403815;5255.185446255818;5295.973520132394;5166.17341225404;5197.636165468419;5206.635535512516;5311.62818602698;5333.893322133555;4879.170541008019;5204.193350030751;5251.816186433475;5262.368515778112;5304.840854774344;5256.763445644936;5263.157894736862;5299.496547827989;5327.733613319358;5360.16917377807;5075.289459475676;5190.92213616796;5250.868728281824;5254;5266.078436273637;4894.41267047953;5650.886982260373;5661.46216109469;5687.40282270364;5718.256626638552;6137.2942111657285;6214.378562143795;6269.310375858652;6274.749010039639;6278.937210627881;6177.25872895251;6295;6313.147725057151;6324.71538780756;6335.04974253861;5673.801416950387;5736.5123964462655;5767.423257674199;5836.824895253149;5848.035074212792;5674;6283.59156654816;6298;6299.622022678654;6324.146240257598;6125.938740612626;6130.325664176937;6145.9692701536205;6148.661823599656;6149.754009839646;6113.263474096239;6124.594542075475;6160.414760597736;6185.876282474415;6258.718357673915;6239.469645080219;6247.0629405589525;6259.029850373199;6260.7495700171485;6263.029230469285;6216.409441103088;6261;6276.529260305495;6286.559940804192;6307.085472606538;4856.1016212000895;4860.562549370574;4904.239842824368;4907.9263811042865;4945.2582112683385;4436.489803672554;4449.488308844459;4458.665600079942;4490.416245888079;4492.438445194351;5802.390748971382;5809.854753631109;5810.709464526757;5813.128030795415;5821.155932389865;6196.349383314777;6229.377062293825;6256.061590761331;6256.5307601930035;6286.025666021774;5815.88368232633;5830.038043722739;5886.470217680429;5946.524278057745;5954.761809527657;5447.428020057916;5455.427180146107;5463.48096930791;5479.890402192013;5504.0643090674685;5531.668099914018;6328.272248691367;6334.493240540838;6351.777687780906;6353.872922541431;6017.247844019497;6112.0171844544475;6170.660613666291;6221.844453888689;6333.20834895638;6229.96885015572;6266.717997690023;6305;6308.71610777507;6310.5898116622275;6254.061890716429;6259.061140828912;6261.311255761953;6261.906071408841;6278.843028924313;6123.679189354688;6138.6009909357535;6186.907196391968;6277.497800175884;6327.335629758809;6095.05526643371;6101.450869421773;6104.145419641342;6129.8467538310115;6182.072689096672;6160.876782464416;6164.352742962104;6165.599236049642;6174.44430001302;6182.907256391248;6009.369016253405;6010.1585777991995;6025.969870150621;6027.939720602738;6080.695965201634;5991.700414979146;6059.666718330534;6074.514038876968;6114.084479019511;6134.202553667995;5933.2880054393745;5947.405259474018;5957.880842383215;5957.957357462514;5958.285005799158 09:47:04 INFO - PROCESS | 1650 | |1;ext.html;464.98605041848754;489.6474538332399;501.60373305088984;506.9366329208849;531.2005431825103;29700.554491682615;38983.830485085455;39978.60021399785;40151.99620009501;42141.36787948183;40308.59691403085;40390.990225244386;40497.59502404975;40647.59352406475;40686.796566017205;9352.485613291266;9511.904880951206;9514.571844267024;9517.524123793817;9536.380135291218;9423.34036617437;9534.427934323929;9544.379615324982;9545.284103692216;9561.665341713042;9520.952395238015;9584.664536741215;9599.376040557376;9599.664011759589;9621.134097931184;9472.81054378912;9539.90460095397;9540.856887146698;9548.952255238679;9559.56981935811;9269.397489163182;9338.299627527962;9361.297902657328;9417.011213822518;9427.245820334358;39000;39204;39208.60791392078;39252.803735981426;39370.03149842525;3266.591676040495;3302.108430723715;3373.1735724747596;3481.7736847104857;3707.480952666629;27245.158860721924;28800.423991520263;29972.700272997427;30098.84950575255;30711.692883071108;3087.969120308813;3229.337985712923;3237.983810080958;3264.4287249731437;3274.1487213324467;505559.4722026403;505846;506077;506829;510734;33615.49576756351;61122;61679.38320616826;61704.382956169866;61822.38177618166;34519.4822077669;35297.64702352943;35390.292194155976;35399.64600354015;35553;14232;14331.498397556035;14337.35481903317;14368.353424095942;14373.496927607484;11610.535578576762;11987.460564274628;12085.758284834254;12086.637400877988;12119.575814846437;7586.089669239726;7808.492447990862;7871.370290376756;7905.802354941057;7935.285824275843;2847.5728640703646;3211.132994091582;3221.597300337458;3230.660780618048;3265.363254165415;892.9821403572022;929.3494553812224;995.4873240281261;1014.0822555587206;1017.2523195451338;28333;29217.561736573967;29401.705982939897;30051.699483005326;30184.84907575492;1001.6744558018673;1017.6896046705677;1092.4974511724695;1102.7538881064506;1129.9604513842141;14797.482088127079;15300.846991529943;15414.612684858417;15464.845351546566;15648.765268520981;701958;708258;710735;711904;713047;57297.71351143218;57720.84558308811;57722.55693607694;57902;58142.4185758137;1333.9666508337175;1355.491690616019;1367.9179249245078;1369.5754316162022;1374.1274290825397;27548.58677119885;28729.42541149207;29188.789233183907;29242.707572924;29997.850010749808;50413.49586504235;50602.493975061254;50765;50824;50835;597812;601365;603454;605292;605811;26746.19761407161;26801.463970720866;27014;27123.050693225643;27217;45687.54312456833;45716.54283457256;46292.768536157106;46790.532094678616;47317;30824.229394264425;37521.06197345088;38251.617483824804;38468.807655961544;38838.80580597079;34022.12878291718;35644;37609.81195094007;38194.618053819104;38479.03802404962;34242;36628.08429789277;38116;38123.42814857724;38140.809295953346;31821.20446988751;33105;33610.327793444485;33653.91776686497;33973.5834341534;25083.87458062698;25238.49523009566;25332.322819334586;25542;25903.481930361664 09:47:04 INFO - PROCESS | 1650 | |2;jquery.html;354.8828886467466;368.2744992365041;375.25699115750814;383.8790780904016;403.44929171680667;14783.482578109768;14952.401903923843;15803.604909877258;19969.400917972485;20491.897540512276;17869.91065044673;18454.354097606585;19340.806591934077;20477.48806279844;20618.072186751593;5055.342805435297;6006.759729610832;6130.325664176937;6138.570300078996;6151.692415379235;4573.794179261941;6051.848703782397;6115.205023346959;6129.570930034899;6142.109394137848;5701.660109874177;6043.6675982820925;6044.486218671418;6055.424734650202;6064.241969753781;5992.101184822268;6096.4817990470865;6106.572539922207;6108.297545782248;6108.816735497942;5696.088625819849;5831.562632802515;5918.615290006136;5930.703464826742;5940.405959404068;13198.802017969738;13427.86572134276;13458.663533411645;17024.744628830576;24472;2301.4476525633895;2470.481198948205;2534.51844149611;2641.313258552771;2885.841278729669;9713.61145554177;10039.69880903574;10337.534810933526;13170.078094533386;13953.720925581532;1833.761610990576;1843.4561804267773;1882.4446788197515;1891.1300801631266;1918.7793403758608;106752.4662376691;113770.43114784456;118868;120192.39903800425;120658;9582.329236953487;10070.74115735533;10261.486925653837;10298.279120461646;10433.791324173473;12310.569130080583;12560.246385216917;12624.936875315565;12662.366881656064;12673.746525069448;7898.763037108896;8036.838400761386;8096.595170241464;8178.386621003448;8182.959085204536;8358.080611132771;8373.120822313691;8438;8524.104968978168;8559.84442100321;3351.2459696568567;3908.257431088084;3908.7362052685758;3918.5885482024546;3940.527136743609;1867.7011678131435;1912.3689182569997;1914.2917120665165;1915.5441864183322;1950.9707354389695;814.8696208606594;870.5908223135116;912.4206129108064;938.2212763406357;965.4689920543673;20858;21748.565028699337;21835.672464913037;21853.781462185492;21935.890320548297;898.955052247385;911.5487833522449;934.1545900959643;959.1032384720309;969.9078587534173;10583.206259530412;11336.546538138547;11368.602098926498;11421.914918082768;11449.198556101159;581913;593547;603820;606696.6316852528;613931;95685.52157239169;116340.41829790967;118867.40566297113;128361.3581932103;131907;973.5424350555228;990.108901988202;1024.472396715692;1031.7730099378125;1032.4270030133198;19239.230430783173;19540;20073.197072116953;20106.497337566678;20145.19419223273;53156.734216328674;53831.73084134554;55499;56741.43258567527;57649;78601.60699196467;81902.59048704719;83386.5830670867;84784.57607711921;85482.86292842729;7251.637418128967;7745.883811742941;8066.516009039242;8306.335493160412;8365.330773538224;9237.722868313962;12686.555970540987;12691.175073620185;12845.935770321088;13119.27843968591;9499.810003800023;11176.217664763388;11207.60773372928;11300.265482743594;11443.942780286045;10199.592016319264;11063.944680276547;11095.94452027767;11183.664490065312;11501.079913607058;11255.380954047278;11377.146713996317;11403.149212696826;11494.712632184262;11692.824607630722;7055.1886533049355;8008;8204.302634276153;8237.50574965504;8299.29455996247;9257.629694812402;10752.892471075189;10956.780864382827;11112.333260004425;11292.209545331747 09:47:04 INFO - PROCESS | 1650 | |3;mootools.html;1305.7388522295544;1372.1355546006014;1458.1105525629364;1601.4555051282568;1820.135435668057;7275.308845659661;7614.086309642837;7626.351760100391;7628.0464941882265;7635.847283054336;7469.178390377067;8046.356291496681;8281.668733250663;8306.750797476085;8376.916230837689;2288.3249441414823;2320.9303720888315;2321.083172147001;2324.639680849471;2327.3716096653893;2319.512902290521;2357.49313897512;2366.7396586375485;2368.988155059231;2380.27401642499;2292.6790249365104;2304.5506126305377;2327.3018094571644;2329.359426157812;2342.344143639775;2281.1901774870016;2283.303592404316;2308.7575804540447;2312.144506532585;2320.0951628864586;2259.649754288088;2277.430642339415;2278.2481781012225;2282.851614645043;2284.6116160252795;8340.165983401672;8413.537255450949;8413.915860841436;8438.578071096483;8444.986601607785;4516.16450956574;5019.824306149268;5032.723200223986;5190.584753219771;5196.896062078775;3472.722182225416;3551.786892786441;3559.1635965548076;3565.286942611488;3565.4830049642655;1042.6090216168936;1060.0883240413239;1083.6153165626247;1087.152021423291;1098.527633117767;10962.287451315718;11028.338299702044;11033.448327583668;11045.944770276177;11051.502682379312;4403.9559604404185;4523.208438523311;4714.693544919569;4735.763211839463;4737.147313483537;8644.567771611395;8684.78288042804;8686.131386861389;8691.869621955675;8730;5057.595392368602;5076.44159142494;5077.466865979093;5078.289039534468;5082.517160869711;4044.7168698190844;4224.134052519226;4421.4915284742665;4742.834000809955;4749.382580264543;3601.819909004539;3621.149029977953;3656.957767036382;3694;3715.64701353371;1126.8929451702074;1127.7969965406141;1129.4917287220783;1134.9773004540027;1137.0903277378065;878.2271600991087;878.3280790195505;886.1315910407836;900.7748062984253;915.8031023330016;5291.523762861361;5554.250176226238;5555.028912693153;5705.1727499512335;5779.075347944308;437.9136331509494;440.6584896705015;443.8135982887131;452.0439270941934;454.6158496070815;3433.3991551478393;3676.44853272006;3732.64539868712;3834.482344883405;3906.3709504237004;9721.854172187423;10244.02681745233;10255.435951022766;10260.692179234635;10268.691939241835;9762.853557196648;9970.152537034433;10149.137323327452;10211.438370889524;10222.744431389276;7712.537247765152;7868.72459463916;7889.447738658238;7897.684092636345;7912.960435197787;4804.975975120102;5146.639735218499;5169.043726910533;5402.432744561765;5477.945220547744;9224.169824715807;9255.305852061258;9294.25645948309;9396.624135034523;9632.75918102053;8628.352873534635;8776.122387761172;9397.201237894855;9507.001764814595;9551.522423878918;6562.606243625398;6563.638999855054;6592.868142637025;6599.868002640016;6694.464442844658;7741.458097933316;7823.921760782547;7828.060632724109;7830.8825367620675;7841.647125879464;7025.227225005143;7166.820829479305;7206.063211782434;7240.855182896418;7299.160596531467;6366.917624003838;6474.417302442803;6778.389944904982;7071.7878463646175;7088.787336379711;6418.390252925965;6645.435138013323;6649.401553859982;6680.732770689118;6763.391294783493;4201.768902710381;4337.566243375637;4408.691391602557;4458.175237581057;4541.477730060953;4775.092732380836;4854.95145048545;4924.482929292516;4957.206846904478;4964.702117872939 09:47:04 INFO - PROCESS | 1650 | |4;prototype.html;301.70885095882477;309.94885843835766;310.8010873041253;315.0815373187158;319.6372117646471;4242.89392765181;5322.866928326789;5329.307190065296;5358.624896257259;5359.5712343012565;4896.828610998616;5159.226116082589;5163.664361816479;5216;5263.499837577149;3754.2303827715255;3812.485314482537;3905.6875449963936;4014.799260037001;4026.255142798577;3862.257796964159;4004.83980640774;4007.2586571484367;4042.4138499917494;4064.7357921735143;3361.831908404582;3939.9212015759667;3995.9600403996023;3997.660198883084;4021.8391264349393;3236.9352612947728;3631.7094632429607;3731.8320675569666;3887.5140607424073;3996.680265578747;3168.8574014169417;3364.411228035084;3439.8108104054268;3453.879114231015;3465.220325426784;4652.185867473212;4983.252512123175;4990.326305948687;5016.648834581581;5029.75580750414;4239.936400953989;4476.731396116244;4487.586410280751;4503.48209955856;4587.8666420114805;5118.029558619921;5507.614466987313;5532.806351777709;5550.750216240279;5568.554515638739;3848.134169811798;3857.9035524112123;3863.28529222092;3869.535655721303;3881.4371916072296;4888.166937398476;5474;5538.6399884007415;5862.501687356537;5898.852528686774;3406.9148271293416;3453.9654603454146;3682.876722599633;3805.562360328542;3848.499695039627;3942.56631770505;3943.960560394417;3950;3950.802459876995;3956.347202711578;4024.3762216856435;4062.431259623655;4545.65907556928;4547.977260113745;4561.384213131185;4924.09478407734;5252.05463016653;5265.262863199156;5358.571314294847;5663.688497132614;1181.787278289899;1266.1326991011003;1268.9682757931125;1269.2828551868165;1272.306592906868;739.7595781371076;855.1918437076903;861.3109512390063;862.3618522293583;870.0733718589681;4205.572458446356;5122.513361230677;5161.4322424533275;5216.40011398694;5308.991291654574;4976.950230497649;5687.18484350241;5696.287964004499;5740.225069615632;5755.532339253547;4948.90102197954;5030.798768049311;5271.683698978073;5276.92084618731;5284.894302113936;2479.739627339103;2547.6942766867724;2603.38820377215;2846.729560691731;2856.9000084996924;5238.397584277857;5616.522595579339;5667;5710.057840456278;5716.5696585146015;4882.584980276645;5173.301604283449;5530.6128570999645;5593.860153496195;5607.579431542651;4710.69380490267;5075.213341932006;5614.29821272341;5715.257016587901;5718.226660069863;5033.39599248078;5213.142274716758;5416.729163541729;5470.72646367672;5513.42109078557;4817.807287708592;4820.301056346881;5309.495597918191;5386.569074473954;5725.971370143123;4718.882027949328;5295.564040541394;6004.849878753066;6035.879282414415;6046.455818976313;3833.3866581347966;3849.653531182207;3864.8067596620617;3876.5735769065946;3881.3207688653806;4888.5111488850835;5586.636868603528;5755.251817263729;5764.711764411846;5806.912896306474;4636.330920868417;4742.336072949721;5278.287431196893;5515.669059856422;5571.9442805571425;4473.316010393868;4495.617872480876;4714.858554243378;5602.85992850182;5612.494875461228;4360.825566977285;5479.561635069105;5516.9448305516435;5522.088855338824;5543.307086614173;4651.139559160618;4737.857864264078;5342.020194954784;5476.507114359727;5509.421510741315;4765.523447655207;5022.899542009212;5033.043721692867;5525.419830917695;5530.861728456821 09:47:04 INFO - PROCESS | 1650 | |5;yui.html;695.1484431571325;746.9402447804174;747.3012733094557;783.7256960063977;787.330768846481;12314.630561083171;12497.68755781106;12659.746805063916;12764.680882977918;12785.808212876815;13182.604521864336;13254.337283135852;13262.13796103255;13280.93359533201;13297.534586289481;649.9642519661417;678.518252041049;785.6582386661792;794.7933537280305;817.2481317188201;793.4525177627419;802.5706247157772;805.3597390074875;807.3621838747382;807.7051876065224;856.1267507142711;857.9313654907623;859.6002858670748;862.4480332587149;862.9697960571381;686.0361192524512;712.501249125609;845.5941148248813;848.749618862434;849.8597731374316;618.5608218165108;640.942315191635;641.3458272562008;641.6855740687076;643.906633538139;13523.39144738489;13553.051286409955;13613.795793063113;13833.723325533432;13912.234827084507;12956.935215323863;13035.41526080326;13101.803472947913;13116.344182790917;13212;11426.71433214168;11846.822297665542;11945.761084778344;12009.399530023464;12117.878821211763;4317.76252306123;4392.099619578011;4425.35832304314;4558.156741002925;4560.817567297304;86906;88398.55800720955;88669.55665221697;89195.55402222948;89715.55142224312;9673.274504412197;9930.751731206634;10026.799464010679;10361;10369.9481502592;19053.856768593927;19377.709334359995;19383.8061619382;19418.029098545016;19425.611487770166;7126.180489243699;7355.3380195781565;7553;7582.165961744204;7584.848303033908;3692.51997240357;4110.280700877334;4256.61690447855;4346.869593912188;4403.075354175595;3311.7019468247972;3321.4187517184396;3670.3209906167444;3836.5779764225913;3847.711421643388;591.7810410148272;678.443676185533;681.0941447874316;690.8411065454974;692.8198668346165;591.775125452334;658.1575583253443;658.499540349338;660.2308310817939;680.0649107477219;8374.204450577185;9127.908720912706;9166;9208.539573021322;9356.766080847898;648.9610623362613;659.3143131143653;660.3462572053633;674.2246416620925;685.7085738561092;4434.977825110918;4437.600615944581;4540.114677637895;4559.247724125548;4653.95346046542;88597;91538;93032.53483732673;94483;96057.51971240099;82953.75569366344;86621.56689216514;89017;90156;91044.1791164173;79680;82284;82941.58529207436;85393;85744.57127714447;9117.726468205965;9198;9237.769055773659;9520.952395238257;9556.904430955601;52190.21714674207;52945.7352713234;54030.729846350514;54233.915321692555;54841.72579137079;71011.93482097876;73107.90338144825;76911.84632230409;77431.61284193544;77719;11752.65116093988;12078.852509011629;12144.75710485803;12231.510739570673;12408.875911240772;12796.488140474648;13185.670358240737;14001.369938352595;14048.2975851205;14258.215798130788;9309.487978160996;11103.944480277547;12872.871271287167;13082;13387.866121338662;10709.839352409565;12296.262224266215;12911.806322904977;13069.738605228033;13349.332533373487;10095;11942.462589183333;12747.553835615709;12904.16122952005;12913;10746;10960.780784384428;11301.208915375844;11340.886591134313;11578.36319002463;10255.94872025635;11135.665930022113;11213.046891014355;11315.773684526428;11350.52443182381 09:47:04 INFO - PROCESS | 1650 | __end_tp_report 09:47:04 INFO - PROCESS | 1650 | __start_cc_report 09:47:04 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,2109 09:47:04 INFO - PROCESS | 1650 | __end_cc_report 09:47:04 INFO - PROCESS | 1650 | __startTimestamp1480960024384__endTimestamp 09:47:04 INFO - PROCESS | 1650 | 09:47:04 INFO - PROCESS | 1650 | ------- Summary: start ------- 09:47:04 INFO - PROCESS | 1650 | Number of tests: 6 09:47:04 INFO - PROCESS | 1650 | 09:47:04 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5762.14 Median:5991.70 stddev:636.98 (10.6%) stddev-sans-first:609.79 09:47:04 INFO - PROCESS | 1650 | Values: 3334.1 3355.8 3375.1 3399.5 3424.6 5705.1 5749.5 5781.4 5800.2 5801.5 5691.6 5780.4 5791.9 5794.9 5798.5 5175.8 5235.9 5252.5 5255.2 5296.0 5166.2 5197.6 5206.6 5311.6 5333.9 4879.2 5204.2 5251.8 5262.4 5304.8 5256.8 5263.2 5299.5 5327.7 5360.2 5075.3 5190.9 5250.9 5254.0 5266.1 4894.4 5650.9 5661.5 5687.4 5718.3 6137.3 6214.4 6269.3 6274.7 6278.9 6177.3 6295.0 6313.1 6324.7 6335.0 5673.8 5736.5 5767.4 5836.8 5848.0 5674.0 6283.6 6298.0 6299.6 6324.1 6125.9 6130.3 6146.0 6148.7 6149.8 6113.3 6124.6 6160.4 6185.9 6258.7 6239.5 6247.1 6259.0 6260.7 6263.0 6216.4 6261.0 6276.5 6286.6 6307.1 4856.1 4860.6 4904.2 4907.9 4945.3 4436.5 4449.5 4458.7 4490.4 4492.4 5802.4 5809.9 5810.7 5813.1 5821.2 6196.3 6229.4 6256.1 6256.5 6286.0 5815.9 5830.0 5886.5 5946.5 5954.8 5447.4 5455.4 5463.5 5479.9 5504.1 5531.7 6328.3 6334.5 6351.8 6353.9 6017.2 6112.0 6170.7 6221.8 6333.2 6230.0 6266.7 6305.0 6308.7 6310.6 6254.1 6259.1 6261.3 6261.9 6278.8 6123.7 6138.6 6186.9 6277.5 6327.3 6095.1 6101.5 6104.1 6129.8 6182.1 6160.9 6164.4 6165.6 6174.4 6182.9 6009.4 6010.2 6026.0 6027.9 6080.7 5991.7 6059.7 6074.5 6114.1 6134.2 5933.3 5947.4 5957.9 5958.0 5958.3 09:47:04 INFO - PROCESS | 1650 | 09:47:04 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:71597.41 Median:38360.21 stddev:164288.69 (428.3%) stddev-sans-first:164662.75 09:47:04 INFO - PROCESS | 1650 | Values: 465.0 489.6 501.6 506.9 531.2 29700.6 38983.8 39978.6 40152.0 42141.4 40308.6 40391.0 40497.6 40647.6 40686.8 9352.5 9511.9 9514.6 9517.5 9536.4 9423.3 9534.4 9544.4 9545.3 9561.7 9521.0 9584.7 9599.4 9599.7 9621.1 9472.8 9539.9 9540.9 9549.0 9559.6 9269.4 9338.3 9361.3 9417.0 9427.2 39000.0 39204.0 39208.6 39252.8 39370.0 3266.6 3302.1 3373.2 3481.8 3707.5 27245.2 28800.4 29972.7 30098.8 30711.7 3088.0 3229.3 3238.0 3264.4 3274.1 505559.5 505846.0 506077.0 506829.0 510734.0 33615.5 61122.0 61679.4 61704.4 61822.4 34519.5 35297.6 35390.3 35399.6 35553.0 14232.0 14331.5 14337.4 14368.4 14373.5 11610.5 11987.5 12085.8 12086.6 12119.6 7586.1 7808.5 7871.4 7905.8 7935.3 2847.6 3211.1 3221.6 3230.7 3265.4 893.0 929.3 995.5 1014.1 1017.3 28333.0 29217.6 29401.7 30051.7 30184.8 1001.7 1017.7 1092.5 1102.8 1130.0 14797.5 15300.8 15414.6 15464.8 15648.8 701958.0 708258.0 710735.0 711904.0 713047.0 57297.7 57720.8 57722.6 57902.0 58142.4 1334.0 1355.5 1367.9 1369.6 1374.1 27548.6 28729.4 29188.8 29242.7 29997.9 50413.5 50602.5 50765.0 50824.0 50835.0 597812.0 601365.0 603454.0 605292.0 605811.0 26746.2 26801.5 27014.0 27123.1 27217.0 45687.5 45716.5 46292.8 46790.5 47317.0 30824.2 37521.1 38251.6 38468.8 38838.8 34022.1 35644.0 37609.8 38194.6 38479.0 34242.0 36628.1 38116.0 38123.4 38140.8 31821.2 33105.0 33610.3 33653.9 33973.6 25083.9 25238.5 25332.3 25542.0 25903.5 09:47:04 INFO - PROCESS | 1650 | 09:47:04 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:34549.74 Median:2763.58 stddev:100021.23 (3619.3%) stddev-sans-first:100268.86 09:47:04 INFO - PROCESS | 1650 | Values: 354.9 368.3 375.3 383.9 403.4 14783.5 14952.4 15803.6 19969.4 20491.9 17869.9 18454.4 19340.8 20477.5 20618.1 5055.3 6006.8 6130.3 6138.6 6151.7 4573.8 6051.8 6115.2 6129.6 6142.1 5701.7 6043.7 6044.5 6055.4 6064.2 5992.1 6096.5 6106.6 6108.3 6108.8 5696.1 5831.6 5918.6 5930.7 5940.4 13198.8 13427.9 13458.7 17024.7 24472.0 2301.4 2470.5 2534.5 2641.3 2885.8 9713.6 10039.7 10337.5 13170.1 13953.7 1833.8 1843.5 1882.4 1891.1 1918.8 106752.5 113770.4 118868.0 120192.4 120658.0 9582.3 10070.7 10261.5 10298.3 10433.8 12310.6 12560.2 12624.9 12662.4 12673.7 7898.8 8036.8 8096.6 8178.4 8183.0 8358.1 8373.1 8438.0 8524.1 8559.8 3351.2 3908.3 3908.7 3918.6 3940.5 1867.7 1912.4 1914.3 1915.5 1951.0 814.9 870.6 912.4 938.2 965.5 20858.0 21748.6 21835.7 21853.8 21935.9 899.0 911.5 934.2 959.1 969.9 10583.2 11336.5 11368.6 11421.9 11449.2 581913.0 593547.0 603820.0 606696.6 613931.0 95685.5 116340.4 118867.4 128361.4 131907.0 973.5 990.1 1024.5 1031.8 1032.4 19239.2 19540.0 20073.2 20106.5 20145.2 53156.7 53831.7 55499.0 56741.4 57649.0 78601.6 81902.6 83386.6 84784.6 85482.9 7251.6 7745.9 8066.5 8306.3 8365.3 9237.7 12686.6 12691.2 12845.9 13119.3 9499.8 11176.2 11207.6 11300.3 11443.9 10199.6 11063.9 11095.9 11183.7 11501.1 11255.4 11377.1 11403.1 11494.7 11692.8 7055.2 8008.0 8204.3 8237.5 8299.3 9257.6 10752.9 10956.8 11112.3 11292.2 09:47:04 INFO - PROCESS | 1650 | 09:47:04 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:5336.08 Median:4889.72 stddev:2967.13 (60.7%) stddev-sans-first:2959.99 09:47:04 INFO - PROCESS | 1650 | Values: 1305.7 1372.1 1458.1 1601.5 1820.1 7275.3 7614.1 7626.4 7628.0 7635.8 7469.2 8046.4 8281.7 8306.8 8376.9 2288.3 2320.9 2321.1 2324.6 2327.4 2319.5 2357.5 2366.7 2369.0 2380.3 2292.7 2304.6 2327.3 2329.4 2342.3 2281.2 2283.3 2308.8 2312.1 2320.1 2259.6 2277.4 2278.2 2282.9 2284.6 8340.2 8413.5 8413.9 8438.6 8445.0 4516.2 5019.8 5032.7 5190.6 5196.9 3472.7 3551.8 3559.2 3565.3 3565.5 1042.6 1060.1 1083.6 1087.2 1098.5 10962.3 11028.3 11033.4 11045.9 11051.5 4404.0 4523.2 4714.7 4735.8 4737.1 8644.6 8684.8 8686.1 8691.9 8730.0 5057.6 5076.4 5077.5 5078.3 5082.5 4044.7 4224.1 4421.5 4742.8 4749.4 3601.8 3621.1 3657.0 3694.0 3715.6 1126.9 1127.8 1129.5 1135.0 1137.1 878.2 878.3 886.1 900.8 915.8 5291.5 5554.3 5555.0 5705.2 5779.1 437.9 440.7 443.8 452.0 454.6 3433.4 3676.4 3732.6 3834.5 3906.4 9721.9 10244.0 10255.4 10260.7 10268.7 9762.9 9970.2 10149.1 10211.4 10222.7 7712.5 7868.7 7889.4 7897.7 7913.0 4805.0 5146.6 5169.0 5402.4 5477.9 9224.2 9255.3 9294.3 9396.6 9632.8 8628.4 8776.1 9397.2 9507.0 9551.5 6562.6 6563.6 6592.9 6599.9 6694.5 7741.5 7823.9 7828.1 7830.9 7841.6 7025.2 7166.8 7206.1 7240.9 7299.2 6366.9 6474.4 6778.4 7071.8 7088.8 6418.4 6645.4 6649.4 6680.7 6763.4 4201.8 4337.6 4408.7 4458.2 4541.5 4775.1 4855.0 4924.5 4957.2 4964.7 09:47:04 INFO - PROCESS | 1650 | 09:47:04 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4416.93 Median:4936.50 stddev:1357.34 (27.5%) stddev-sans-first:1325.54 09:47:04 INFO - PROCESS | 1650 | Values: 301.7 309.9 310.8 315.1 319.6 4242.9 5322.9 5329.3 5358.6 5359.6 4896.8 5159.2 5163.7 5216.0 5263.5 3754.2 3812.5 3905.7 4014.8 4026.3 3862.3 4004.8 4007.3 4042.4 4064.7 3361.8 3939.9 3996.0 3997.7 4021.8 3236.9 3631.7 3731.8 3887.5 3996.7 3168.9 3364.4 3439.8 3453.9 3465.2 4652.2 4983.3 4990.3 5016.6 5029.8 4239.9 4476.7 4487.6 4503.5 4587.9 5118.0 5507.6 5532.8 5550.8 5568.6 3848.1 3857.9 3863.3 3869.5 3881.4 4888.2 5474.0 5538.6 5862.5 5898.9 3406.9 3454.0 3682.9 3805.6 3848.5 3942.6 3944.0 3950.0 3950.8 3956.3 4024.4 4062.4 4545.7 4548.0 4561.4 4924.1 5252.1 5265.3 5358.6 5663.7 1181.8 1266.1 1269.0 1269.3 1272.3 739.8 855.2 861.3 862.4 870.1 4205.6 5122.5 5161.4 5216.4 5309.0 4977.0 5687.2 5696.3 5740.2 5755.5 4948.9 5030.8 5271.7 5276.9 5284.9 2479.7 2547.7 2603.4 2846.7 2856.9 5238.4 5616.5 5667.0 5710.1 5716.6 4882.6 5173.3 5530.6 5593.9 5607.6 4710.7 5075.2 5614.3 5715.3 5718.2 5033.4 5213.1 5416.7 5470.7 5513.4 4817.8 4820.3 5309.5 5386.6 5726.0 4718.9 5295.6 6004.8 6035.9 6046.5 3833.4 3849.7 3864.8 3876.6 3881.3 4888.5 5586.6 5755.3 5764.7 5806.9 4636.3 4742.3 5278.3 5515.7 5571.9 4473.3 4495.6 4714.9 5602.9 5612.5 4360.8 5479.6 5516.9 5522.1 5543.3 4651.1 4737.9 5342.0 5476.5 5509.4 4765.5 5022.9 5033.0 5525.4 5530.9 09:47:04 INFO - PROCESS | 1650 | 09:47:04 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:19693.15 Median:54537.82 stddev:28142.56 (51.6%) stddev-sans-first:28185.36 09:47:04 INFO - PROCESS | 1650 | Values: 695.1 746.9 747.3 783.7 787.3 12314.6 12497.7 12659.7 12764.7 12785.8 13182.6 13254.3 13262.1 13280.9 13297.5 650.0 678.5 785.7 794.8 817.2 793.5 802.6 805.4 807.4 807.7 856.1 857.9 859.6 862.4 863.0 686.0 712.5 845.6 848.7 849.9 618.6 640.9 641.3 641.7 643.9 13523.4 13553.1 13613.8 13833.7 13912.2 12956.9 13035.4 13101.8 13116.3 13212.0 11426.7 11846.8 11945.8 12009.4 12117.9 4317.8 4392.1 4425.4 4558.2 4560.8 86906.0 88398.6 88669.6 89195.6 89715.6 9673.3 9930.8 10026.8 10361.0 10369.9 19053.9 19377.7 19383.8 19418.0 19425.6 7126.2 7355.3 7553.0 7582.2 7584.8 3692.5 4110.3 4256.6 4346.9 4403.1 3311.7 3321.4 3670.3 3836.6 3847.7 591.8 678.4 681.1 690.8 692.8 591.8 658.2 658.5 660.2 680.1 8374.2 9127.9 9166.0 9208.5 9356.8 649.0 659.3 660.3 674.2 685.7 4435.0 4437.6 4540.1 4559.2 4654.0 88597.0 91538.0 93032.5 94483.0 96057.5 82953.8 86621.6 89017.0 90156.0 91044.2 79680.0 82284.0 82941.6 85393.0 85744.6 9117.7 9198.0 9237.8 9521.0 9556.9 52190.2 52945.7 54030.7 54233.9 54841.7 71011.9 73107.9 76911.8 77431.6 77719.0 11752.7 12078.9 12144.8 12231.5 12408.9 12796.5 13185.7 14001.4 14048.3 14258.2 9309.5 11103.9 12872.9 13082.0 13387.9 10709.8 12296.3 12911.8 13069.7 13349.3 10095.0 11942.5 12747.6 12904.2 12913.0 10746.0 10960.8 11301.2 11340.9 11578.4 10255.9 11135.7 11213.0 11315.8 11350.5 09:47:04 INFO - PROCESS | 1650 | -------- Summary: end -------- 09:47:04 INFO - PROCESS | 1650 | 09:47:04 INFO - TEST-INFO | 1650: exit 0 09:47:05 INFO - TEST-OK | dromaeo_css | took 1120577ms 09:47:05 INFO - TEST-START | kraken 09:47:05 INFO - operating with platform_type : mac_ 09:47:05 INFO - Initialising browser for kraken test... 09:47:05 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/tmp5Acdum/profile http://localhost:49255/getInfo.html) 09:47:07 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 09:47:07 INFO - PROCESS | 1657 | colorDepth:24 09:47:07 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 09:47:07 INFO - PROCESS | 1657 | __metrics 09:47:07 INFO - PROCESS | 1657 | 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" 09:47:07 INFO - PROCESS | 1657 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:47:08 INFO - TEST-INFO | 1657: exit 0 09:47:08 INFO - Browser initialized. 09:47:08 INFO - Running cycle 1/1 for kraken test... 09:47:08 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/tmp5Acdum/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:47:20 INFO - PROCESS | 1658 | RSS: Main: 222642176 09:47:20 INFO - PROCESS | 1658 | 09:47:23 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49255/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49255/tests/kraken/driver.html?testName=audio-beat-detection) 09:47:24 INFO - PROCESS | 1658 | RSS: Main: 272261120 09:47:24 INFO - PROCESS | 1658 | 09:47:26 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49255/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49255/tests/kraken/driver.html?testName=audio-dft) 09:47:27 INFO - PROCESS | 1658 | RSS: Main: 283017216 09:47:27 INFO - PROCESS | 1658 | 09:47:30 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49255/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49255/tests/kraken/driver.html?testName=audio-fft) 09:47:31 INFO - PROCESS | 1658 | RSS: Main: 380403712 09:47:31 INFO - PROCESS | 1658 | 09:47:33 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49255/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49255/tests/kraken/driver.html?testName=audio-oscillator) 09:47:33 INFO - PROCESS | 1658 | RSS: Main: 283467776 09:47:33 INFO - PROCESS | 1658 | 09:47:35 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49255/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49255/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:47:36 INFO - PROCESS | 1658 | RSS: Main: 284663808 09:47:36 INFO - PROCESS | 1658 | 09:47:39 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49255/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49255/tests/kraken/driver.html?testName=imaging-darkroom) 09:47:39 INFO - PROCESS | 1658 | RSS: Main: 325484544 09:47:39 INFO - PROCESS | 1658 | 09:47:42 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49255/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49255/tests/kraken/driver.html?testName=imaging-desaturate) 09:47:43 INFO - PROCESS | 1658 | RSS: Main: 327462912 09:47:43 INFO - PROCESS | 1658 | 09:47:45 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49255/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49255/tests/kraken/driver.html?testName=json-parse-financial) 09:47:46 INFO - PROCESS | 1658 | RSS: Main: 323387392 09:47:46 INFO - PROCESS | 1658 | 09:47:48 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49255/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49255/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:47:48 INFO - PROCESS | 1658 | RSS: Main: 300367872 09:47:48 INFO - PROCESS | 1658 | 09:47:50 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49255/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49255/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:47:51 INFO - PROCESS | 1658 | RSS: Main: 300113920 09:47:51 INFO - PROCESS | 1658 | 09:47:53 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49255/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49255/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:47:54 INFO - PROCESS | 1658 | RSS: Main: 302518272 09:47:54 INFO - PROCESS | 1658 | 09:47:57 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49255/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49255/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:47:57 INFO - PROCESS | 1658 | RSS: Main: 311099392 09:47:57 INFO - PROCESS | 1658 | 09:48:00 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49255/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49255/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:48:01 INFO - PROCESS | 1658 | RSS: Main: 310734848 09:48:01 INFO - PROCESS | 1658 | 09:48:03 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49255/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49255/tests/kraken/driver.html?testName=ai-astar) 09:48:03 INFO - PROCESS | 1658 | RSS: Main: 353558528 09:48:03 INFO - PROCESS | 1658 | 09:48:03 INFO - PROCESS | 1658 | __start_tp_report 09:48:03 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 09:48:03 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 09:48:03 INFO - PROCESS | 1658 | |i|pagename|runs| 09:48:03 INFO - PROCESS | 1658 | |0;ai-astar;117;115;113;115;115;115;115;115;114;114 09:48:03 INFO - PROCESS | 1658 | |1;audio-beat-detection;150;112;112;148;115;115;115;114;146;114 09:48:03 INFO - PROCESS | 1658 | |2;audio-dft;180;179;185;185;183;186;185;183;185;184 09:48:03 INFO - PROCESS | 1658 | |3;audio-fft;81;60;57;89;58;59;58;58;83;57 09:48:03 INFO - PROCESS | 1658 | |4;audio-oscillator;84;71;94;69;69;69;92;68;67;93 09:48:03 INFO - PROCESS | 1658 | |5;imaging-gaussian-blur;88;84;87;84;87;84;87;84;88;86 09:48:03 INFO - PROCESS | 1658 | |6;imaging-darkroom;97;94;95;96;95;95;95;95;95;95 09:48:03 INFO - PROCESS | 1658 | |7;imaging-desaturate;84;81;79;79;92;128;84;85;84;81 09:48:03 INFO - PROCESS | 1658 | |8;json-parse-financial;56;55;57;51;55;58;57;56;56;58 09:48:03 INFO - PROCESS | 1658 | |9;json-stringify-tinderbox;71;76;75;76;71;65;68;68;68;71 09:48:03 INFO - PROCESS | 1658 | |10;stanford-crypto-aes;85;81;84;84;85;89;104;77;84;85 09:48:03 INFO - PROCESS | 1658 | |11;stanford-crypto-ccm;150;147;146;145;149;151;152;153;196;160 09:48:03 INFO - PROCESS | 1658 | |12;stanford-crypto-pbkdf2;168;169;222;207;165;172;165;167;168;166 09:48:03 INFO - PROCESS | 1658 | |13;stanford-crypto-sha256-iterative;67;69;69;69;69;70;73;79;85;86 09:48:03 INFO - PROCESS | 1658 | __end_tp_report 09:48:03 INFO - PROCESS | 1658 | __start_cc_report 09:48:03 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,2057 09:48:03 INFO - PROCESS | 1658 | __end_cc_report 09:48:03 INFO - PROCESS | 1658 | __startTimestamp1480960083613__endTimestamp 09:48:03 INFO - PROCESS | 1658 | 09:48:03 INFO - PROCESS | 1658 | ------- Summary: start ------- 09:48:03 INFO - PROCESS | 1658 | Number of tests: 14 09:48:03 INFO - PROCESS | 1658 | 09:48:03 INFO - PROCESS | 1658 | [#0] ai-astar Cycles:10 Average:114.80 Median:115.00 stddev:1.03 (0.9%) stddev-sans-first:0.73 09:48:03 INFO - PROCESS | 1658 | Values: 117.0 115.0 113.0 115.0 115.0 115.0 115.0 115.0 114.0 114.0 09:48:03 INFO - PROCESS | 1658 | 09:48:03 INFO - PROCESS | 1658 | [#1] audio-beat-detection Cycles:10 Average:124.10 Median:115.00 stddev:16.56 (14.4%) stddev-sans-first:14.67 09:48:03 INFO - PROCESS | 1658 | Values: 150.0 112.0 112.0 148.0 115.0 115.0 115.0 114.0 146.0 114.0 09:48:03 INFO - PROCESS | 1658 | 09:48:03 INFO - PROCESS | 1658 | [#2] audio-dft Cycles:10 Average:183.50 Median:185.00 stddev:2.32 (1.3%) stddev-sans-first:2.09 09:48:03 INFO - PROCESS | 1658 | Values: 180.0 179.0 185.0 185.0 183.0 186.0 185.0 183.0 185.0 184.0 09:48:03 INFO - PROCESS | 1658 | 09:48:03 INFO - PROCESS | 1658 | [#3] audio-fft Cycles:10 Average:66.00 Median:59.50 stddev:12.83 (21.6%) stddev-sans-first:12.41 09:48:03 INFO - PROCESS | 1658 | Values: 81.0 60.0 57.0 89.0 58.0 59.0 58.0 58.0 83.0 57.0 09:48:03 INFO - PROCESS | 1658 | 09:48:03 INFO - PROCESS | 1658 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:77.50 stddev:11.66 (15.1%) stddev-sans-first:12.14 09:48:03 INFO - PROCESS | 1658 | Values: 84.0 71.0 94.0 69.0 69.0 69.0 92.0 68.0 67.0 93.0 09:48:03 INFO - PROCESS | 1658 | 09:48:03 INFO - PROCESS | 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:85.90 Median:87.00 stddev:1.73 (2.0%) stddev-sans-first:1.66 09:48:03 INFO - PROCESS | 1658 | Values: 88.0 84.0 87.0 84.0 87.0 84.0 87.0 84.0 88.0 86.0 09:48:03 INFO - PROCESS | 1658 | 09:48:03 INFO - PROCESS | 1658 | [#6] imaging-darkroom Cycles:10 Average:95.20 Median:95.00 stddev:0.79 (0.8%) stddev-sans-first:0.50 09:48:03 INFO - PROCESS | 1658 | Values: 97.0 94.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 09:48:03 INFO - PROCESS | 1658 | 09:48:03 INFO - PROCESS | 1658 | [#7] imaging-desaturate Cycles:10 Average:87.70 Median:84.00 stddev:14.65 (17.4%) stddev-sans-first:15.48 09:48:03 INFO - PROCESS | 1658 | Values: 84.0 81.0 79.0 79.0 92.0 128.0 84.0 85.0 84.0 81.0 09:48:03 INFO - PROCESS | 1658 | 09:48:03 INFO - PROCESS | 1658 | [#8] json-parse-financial Cycles:10 Average:55.90 Median:56.50 stddev:2.02 (3.6%) stddev-sans-first:2.15 09:48:03 INFO - PROCESS | 1658 | Values: 56.0 55.0 57.0 51.0 55.0 58.0 57.0 56.0 56.0 58.0 09:48:03 INFO - PROCESS | 1658 | 09:48:03 INFO - PROCESS | 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:71.00 stddev:3.78 (5.3%) stddev-sans-first:4.01 09:48:03 INFO - PROCESS | 1658 | Values: 71.0 76.0 75.0 76.0 71.0 65.0 68.0 68.0 68.0 71.0 09:48:03 INFO - PROCESS | 1658 | 09:48:03 INFO - PROCESS | 1658 | [#10] stanford-crypto-aes Cycles:10 Average:85.80 Median:84.50 stddev:7.10 (8.4%) stddev-sans-first:7.52 09:48:03 INFO - PROCESS | 1658 | Values: 85.0 81.0 84.0 84.0 85.0 89.0 104.0 77.0 84.0 85.0 09:48:03 INFO - PROCESS | 1658 | 09:48:03 INFO - PROCESS | 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:154.90 Median:151.50 stddev:15.06 (9.9%) stddev-sans-first:15.87 09:48:03 INFO - PROCESS | 1658 | Values: 150.0 147.0 146.0 145.0 149.0 151.0 152.0 153.0 196.0 160.0 09:48:03 INFO - PROCESS | 1658 | 09:48:03 INFO - PROCESS | 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.90 Median:168.50 stddev:20.23 (12.0%) stddev-sans-first:21.20 09:48:03 INFO - PROCESS | 1658 | Values: 168.0 169.0 222.0 207.0 165.0 172.0 165.0 167.0 168.0 166.0 09:48:03 INFO - PROCESS | 1658 | 09:48:03 INFO - PROCESS | 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.60 Median:71.50 stddev:7.11 (9.9%) stddev-sans-first:7.12 09:48:03 INFO - PROCESS | 1658 | Values: 67.0 69.0 69.0 69.0 69.0 70.0 73.0 79.0 85.0 86.0 09:48:03 INFO - PROCESS | 1658 | -------- Summary: end -------- 09:48:03 INFO - PROCESS | 1658 | 09:48:04 INFO - TEST-INFO | 1658: exit 0 09:48:04 INFO - TEST-OK | kraken | took 59691ms 09:48:04 INFO - SUITE-END | took 1180s 09:48:04 INFO - Completed test suite (00:19:40) 09:48:04 INFO - javascript benchmark 09:48:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3334.083127140036, 3355.7986520808754, 3375.0718552398102, 3399.456087026076, 3424.623291437943, 5705.087186050233, 5749.453801888824, 5781.421857814216, 5800.216970708953, 5801.53587712983, 5691.573132015094, 5780.393058728836, 5791.913121303184, 5794.942050579493, 5798.507126894219, 5175.767090480928, 5235.947640523603, 5252.501012403815, 5255.185446255818, 5295.973520132394, 5166.17341225404, 5197.636165468419, 5206.635535512516, 5311.62818602698, 5333.893322133555, 4879.170541008019, 5204.193350030751, 5251.816186433475, 5262.368515778112, 5304.840854774344, 5256.763445644936, 5263.157894736862, 5299.496547827989, 5327.733613319358, 5360.16917377807, 5075.289459475676, 5190.92213616796, 5250.868728281824, 5254.0, 5266.078436273637, 4894.41267047953, 5650.886982260373, 5661.46216109469, 5687.40282270364, 5718.256626638552, 6137.2942111657285, 6214.378562143795, 6269.310375858652, 6274.749010039639, 6278.937210627881, 6177.25872895251, 6295.0, 6313.147725057151, 6324.71538780756, 6335.04974253861, 5673.801416950387, 5736.5123964462655, 5767.423257674199, 5836.824895253149, 5848.035074212792, 5674.0, 6283.59156654816, 6298.0, 6299.622022678654, 6324.146240257598, 6125.938740612626, 6130.325664176937, 6145.9692701536205, 6148.661823599656, 6149.754009839646, 6113.263474096239, 6124.594542075475, 6160.414760597736, 6185.876282474415, 6258.718357673915, 6239.469645080219, 6247.0629405589525, 6259.029850373199, 6260.7495700171485, 6263.029230469285, 6216.409441103088, 6261.0, 6276.529260305495, 6286.559940804192, 6307.085472606538, 4856.1016212000895, 4860.562549370574, 4904.239842824368, 4907.9263811042865, 4945.2582112683385, 4436.489803672554, 4449.488308844459, 4458.665600079942, 4490.416245888079, 4492.438445194351, 5802.390748971382, 5809.854753631109, 5810.709464526757, 5813.128030795415, 5821.155932389865, 6196.349383314777, 6229.377062293825, 6256.061590761331, 6256.5307601930035, 6286.025666021774, 5815.88368232633, 5830.038043722739, 5886.470217680429, 5946.524278057745, 5954.761809527657, 5447.428020057916, 5455.427180146107, 5463.48096930791, 5479.890402192013, 5504.0643090674685, 5531.668099914018, 6328.272248691367, 6334.493240540838, 6351.777687780906, 6353.872922541431, 6017.247844019497, 6112.0171844544475, 6170.660613666291, 6221.844453888689, 6333.20834895638, 6229.96885015572, 6266.717997690023, 6305.0, 6308.71610777507, 6310.5898116622275, 6254.061890716429, 6259.061140828912, 6261.311255761953, 6261.906071408841, 6278.843028924313, 6123.679189354688, 6138.6009909357535, 6186.907196391968, 6277.497800175884, 6327.335629758809, 6095.05526643371, 6101.450869421773, 6104.145419641342, 6129.8467538310115, 6182.072689096672, 6160.876782464416, 6164.352742962104, 6165.599236049642, 6174.44430001302, 6182.907256391248, 6009.369016253405, 6010.1585777991995, 6025.969870150621, 6027.939720602738, 6080.695965201634, 5991.700414979146, 6059.666718330534, 6074.514038876968, 6114.084479019511, 6134.202553667995, 5933.2880054393745, 5947.405259474018, 5957.880842383215, 5957.957357462514, 5958.285005799158], "value": 5721.3137219778455, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [464.98605041848754, 489.6474538332399, 501.60373305088984, 506.9366329208849, 531.2005431825103, 29700.554491682615, 38983.830485085455, 39978.60021399785, 40151.99620009501, 42141.36787948183, 40308.59691403085, 40390.990225244386, 40497.59502404975, 40647.59352406475, 40686.796566017205, 9352.485613291266, 9511.904880951206, 9514.571844267024, 9517.524123793817, 9536.380135291218, 9423.34036617437, 9534.427934323929, 9544.379615324982, 9545.284103692216, 9561.665341713042, 9520.952395238015, 9584.664536741215, 9599.376040557376, 9599.664011759589, 9621.134097931184, 9472.81054378912, 9539.90460095397, 9540.856887146698, 9548.952255238679, 9559.56981935811, 9269.397489163182, 9338.299627527962, 9361.297902657328, 9417.011213822518, 9427.245820334358, 39000.0, 39204.0, 39208.60791392078, 39252.803735981426, 39370.03149842525, 3266.591676040495, 3302.108430723715, 3373.1735724747596, 3481.7736847104857, 3707.480952666629, 27245.158860721924, 28800.423991520263, 29972.700272997427, 30098.84950575255, 30711.692883071108, 3087.969120308813, 3229.337985712923, 3237.983810080958, 3264.4287249731437, 3274.1487213324467, 505559.4722026403, 505846.0, 506077.0, 506829.0, 510734.0, 33615.49576756351, 61122.0, 61679.38320616826, 61704.382956169866, 61822.38177618166, 34519.4822077669, 35297.64702352943, 35390.292194155976, 35399.64600354015, 35553.0, 14232.0, 14331.498397556035, 14337.35481903317, 14368.353424095942, 14373.496927607484, 11610.535578576762, 11987.460564274628, 12085.758284834254, 12086.637400877988, 12119.575814846437, 7586.089669239726, 7808.492447990862, 7871.370290376756, 7905.802354941057, 7935.285824275843, 2847.5728640703646, 3211.132994091582, 3221.597300337458, 3230.660780618048, 3265.363254165415, 892.9821403572022, 929.3494553812224, 995.4873240281261, 1014.0822555587206, 1017.2523195451338, 28333.0, 29217.561736573967, 29401.705982939897, 30051.699483005326, 30184.84907575492, 1001.6744558018673, 1017.6896046705677, 1092.4974511724695, 1102.7538881064506, 1129.9604513842141, 14797.482088127079, 15300.846991529943, 15414.612684858417, 15464.845351546566, 15648.765268520981, 701958.0, 708258.0, 710735.0, 711904.0, 713047.0, 57297.71351143218, 57720.84558308811, 57722.55693607694, 57902.0, 58142.4185758137, 1333.9666508337175, 1355.491690616019, 1367.9179249245078, 1369.5754316162022, 1374.1274290825397, 27548.58677119885, 28729.42541149207, 29188.789233183907, 29242.707572924, 29997.850010749808, 50413.49586504235, 50602.493975061254, 50765.0, 50824.0, 50835.0, 597812.0, 601365.0, 603454.0, 605292.0, 605811.0, 26746.19761407161, 26801.463970720866, 27014.0, 27123.050693225643, 27217.0, 45687.54312456833, 45716.54283457256, 46292.768536157106, 46790.532094678616, 47317.0, 30824.229394264425, 37521.06197345088, 38251.617483824804, 38468.807655961544, 38838.80580597079, 34022.12878291718, 35644.0, 37609.81195094007, 38194.618053819104, 38479.03802404962, 34242.0, 36628.08429789277, 38116.0, 38123.42814857724, 38140.809295953346, 31821.20446988751, 33105.0, 33610.327793444485, 33653.91776686497, 33973.5834341534, 25083.87458062698, 25238.49523009566, 25332.322819334586, 25542.0, 25903.481930361664], "value": 18472.50661330029, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.8828886467466, 368.2744992365041, 375.25699115750814, 383.8790780904016, 403.44929171680667, 14783.482578109768, 14952.401903923843, 15803.604909877258, 19969.400917972485, 20491.897540512276, 17869.91065044673, 18454.354097606585, 19340.806591934077, 20477.48806279844, 20618.072186751593, 5055.342805435297, 6006.759729610832, 6130.325664176937, 6138.570300078996, 6151.692415379235, 4573.794179261941, 6051.848703782397, 6115.205023346959, 6129.570930034899, 6142.109394137848, 5701.660109874177, 6043.6675982820925, 6044.486218671418, 6055.424734650202, 6064.241969753781, 5992.101184822268, 6096.4817990470865, 6106.572539922207, 6108.297545782248, 6108.816735497942, 5696.088625819849, 5831.562632802515, 5918.615290006136, 5930.703464826742, 5940.405959404068, 13198.802017969738, 13427.86572134276, 13458.663533411645, 17024.744628830576, 24472.0, 2301.4476525633895, 2470.481198948205, 2534.51844149611, 2641.313258552771, 2885.841278729669, 9713.61145554177, 10039.69880903574, 10337.534810933526, 13170.078094533386, 13953.720925581532, 1833.761610990576, 1843.4561804267773, 1882.4446788197515, 1891.1300801631266, 1918.7793403758608, 106752.4662376691, 113770.43114784456, 118868.0, 120192.39903800425, 120658.0, 9582.329236953487, 10070.74115735533, 10261.486925653837, 10298.279120461646, 10433.791324173473, 12310.569130080583, 12560.246385216917, 12624.936875315565, 12662.366881656064, 12673.746525069448, 7898.763037108896, 8036.838400761386, 8096.595170241464, 8178.386621003448, 8182.959085204536, 8358.080611132771, 8373.120822313691, 8438.0, 8524.104968978168, 8559.84442100321, 3351.2459696568567, 3908.257431088084, 3908.7362052685758, 3918.5885482024546, 3940.527136743609, 1867.7011678131435, 1912.3689182569997, 1914.2917120665165, 1915.5441864183322, 1950.9707354389695, 814.8696208606594, 870.5908223135116, 912.4206129108064, 938.2212763406357, 965.4689920543673, 20858.0, 21748.565028699337, 21835.672464913037, 21853.781462185492, 21935.890320548297, 898.955052247385, 911.5487833522449, 934.1545900959643, 959.1032384720309, 969.9078587534173, 10583.206259530412, 11336.546538138547, 11368.602098926498, 11421.914918082768, 11449.198556101159, 581913.0, 593547.0, 603820.0, 606696.6316852528, 613931.0, 95685.52157239169, 116340.41829790967, 118867.40566297113, 128361.3581932103, 131907.0, 973.5424350555228, 990.108901988202, 1024.472396715692, 1031.7730099378125, 1032.4270030133198, 19239.230430783173, 19540.0, 20073.197072116953, 20106.497337566678, 20145.19419223273, 53156.734216328674, 53831.73084134554, 55499.0, 56741.43258567527, 57649.0, 78601.60699196467, 81902.59048704719, 83386.5830670867, 84784.57607711921, 85482.86292842729, 7251.637418128967, 7745.883811742941, 8066.516009039242, 8306.335493160412, 8365.330773538224, 9237.722868313962, 12686.555970540987, 12691.175073620185, 12845.935770321088, 13119.27843968591, 9499.810003800023, 11176.217664763388, 11207.60773372928, 11300.265482743594, 11443.942780286045, 10199.592016319264, 11063.944680276547, 11095.94452027767, 11183.664490065312, 11501.079913607058, 11255.380954047278, 11377.146713996317, 11403.149212696826, 11494.712632184262, 11692.824607630722, 7055.1886533049355, 8008.0, 8204.302634276153, 8237.50574965504, 8299.29455996247, 9257.629694812402, 10752.892471075189, 10956.780864382827, 11112.333260004425, 11292.209545331747], "value": 9356.585480999749, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1305.7388522295544, 1372.1355546006014, 1458.1105525629364, 1601.4555051282568, 1820.135435668057, 7275.308845659661, 7614.086309642837, 7626.351760100391, 7628.0464941882265, 7635.847283054336, 7469.178390377067, 8046.356291496681, 8281.668733250663, 8306.750797476085, 8376.916230837689, 2288.3249441414823, 2320.9303720888315, 2321.083172147001, 2324.639680849471, 2327.3716096653893, 2319.512902290521, 2357.49313897512, 2366.7396586375485, 2368.988155059231, 2380.27401642499, 2292.6790249365104, 2304.5506126305377, 2327.3018094571644, 2329.359426157812, 2342.344143639775, 2281.1901774870016, 2283.303592404316, 2308.7575804540447, 2312.144506532585, 2320.0951628864586, 2259.649754288088, 2277.430642339415, 2278.2481781012225, 2282.851614645043, 2284.6116160252795, 8340.165983401672, 8413.537255450949, 8413.915860841436, 8438.578071096483, 8444.986601607785, 4516.16450956574, 5019.824306149268, 5032.723200223986, 5190.584753219771, 5196.896062078775, 3472.722182225416, 3551.786892786441, 3559.1635965548076, 3565.286942611488, 3565.4830049642655, 1042.6090216168936, 1060.0883240413239, 1083.6153165626247, 1087.152021423291, 1098.527633117767, 10962.287451315718, 11028.338299702044, 11033.448327583668, 11045.944770276177, 11051.502682379312, 4403.9559604404185, 4523.208438523311, 4714.693544919569, 4735.763211839463, 4737.147313483537, 8644.567771611395, 8684.78288042804, 8686.131386861389, 8691.869621955675, 8730.0, 5057.595392368602, 5076.44159142494, 5077.466865979093, 5078.289039534468, 5082.517160869711, 4044.7168698190844, 4224.134052519226, 4421.4915284742665, 4742.834000809955, 4749.382580264543, 3601.819909004539, 3621.149029977953, 3656.957767036382, 3694.0, 3715.64701353371, 1126.8929451702074, 1127.7969965406141, 1129.4917287220783, 1134.9773004540027, 1137.0903277378065, 878.2271600991087, 878.3280790195505, 886.1315910407836, 900.7748062984253, 915.8031023330016, 5291.523762861361, 5554.250176226238, 5555.028912693153, 5705.1727499512335, 5779.075347944308, 437.9136331509494, 440.6584896705015, 443.8135982887131, 452.0439270941934, 454.6158496070815, 3433.3991551478393, 3676.44853272006, 3732.64539868712, 3834.482344883405, 3906.3709504237004, 9721.854172187423, 10244.02681745233, 10255.435951022766, 10260.692179234635, 10268.691939241835, 9762.853557196648, 9970.152537034433, 10149.137323327452, 10211.438370889524, 10222.744431389276, 7712.537247765152, 7868.72459463916, 7889.447738658238, 7897.684092636345, 7912.960435197787, 4804.975975120102, 5146.639735218499, 5169.043726910533, 5402.432744561765, 5477.945220547744, 9224.169824715807, 9255.305852061258, 9294.25645948309, 9396.624135034523, 9632.75918102053, 8628.352873534635, 8776.122387761172, 9397.201237894855, 9507.001764814595, 9551.522423878918, 6562.606243625398, 6563.638999855054, 6592.868142637025, 6599.868002640016, 6694.464442844658, 7741.458097933316, 7823.921760782547, 7828.060632724109, 7830.8825367620675, 7841.647125879464, 7025.227225005143, 7166.820829479305, 7206.063211782434, 7240.855182896418, 7299.160596531467, 6366.917624003838, 6474.417302442803, 6778.389944904982, 7071.7878463646175, 7088.787336379711, 6418.390252925965, 6645.435138013323, 6649.401553859982, 6680.732770689118, 6763.391294783493, 4201.768902710381, 4337.566243375637, 4408.691391602557, 4458.175237581057, 4541.477730060953, 4775.092732380836, 4854.95145048545, 4924.482929292516, 4957.206846904478, 4964.702117872939], "value": 4252.574072888318, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.70885095882477, 309.94885843835766, 310.8010873041253, 315.0815373187158, 319.6372117646471, 4242.89392765181, 5322.866928326789, 5329.307190065296, 5358.624896257259, 5359.5712343012565, 4896.828610998616, 5159.226116082589, 5163.664361816479, 5216.0, 5263.499837577149, 3754.2303827715255, 3812.485314482537, 3905.6875449963936, 4014.799260037001, 4026.255142798577, 3862.257796964159, 4004.83980640774, 4007.2586571484367, 4042.4138499917494, 4064.7357921735143, 3361.831908404582, 3939.9212015759667, 3995.9600403996023, 3997.660198883084, 4021.8391264349393, 3236.9352612947728, 3631.7094632429607, 3731.8320675569666, 3887.5140607424073, 3996.680265578747, 3168.8574014169417, 3364.411228035084, 3439.8108104054268, 3453.879114231015, 3465.220325426784, 4652.185867473212, 4983.252512123175, 4990.326305948687, 5016.648834581581, 5029.75580750414, 4239.936400953989, 4476.731396116244, 4487.586410280751, 4503.48209955856, 4587.8666420114805, 5118.029558619921, 5507.614466987313, 5532.806351777709, 5550.750216240279, 5568.554515638739, 3848.134169811798, 3857.9035524112123, 3863.28529222092, 3869.535655721303, 3881.4371916072296, 4888.166937398476, 5474.0, 5538.6399884007415, 5862.501687356537, 5898.852528686774, 3406.9148271293416, 3453.9654603454146, 3682.876722599633, 3805.562360328542, 3848.499695039627, 3942.56631770505, 3943.960560394417, 3950.0, 3950.802459876995, 3956.347202711578, 4024.3762216856435, 4062.431259623655, 4545.65907556928, 4547.977260113745, 4561.384213131185, 4924.09478407734, 5252.05463016653, 5265.262863199156, 5358.571314294847, 5663.688497132614, 1181.787278289899, 1266.1326991011003, 1268.9682757931125, 1269.2828551868165, 1272.306592906868, 739.7595781371076, 855.1918437076903, 861.3109512390063, 862.3618522293583, 870.0733718589681, 4205.572458446356, 5122.513361230677, 5161.4322424533275, 5216.40011398694, 5308.991291654574, 4976.950230497649, 5687.18484350241, 5696.287964004499, 5740.225069615632, 5755.532339253547, 4948.90102197954, 5030.798768049311, 5271.683698978073, 5276.92084618731, 5284.894302113936, 2479.739627339103, 2547.6942766867724, 2603.38820377215, 2846.729560691731, 2856.9000084996924, 5238.397584277857, 5616.522595579339, 5667.0, 5710.057840456278, 5716.5696585146015, 4882.584980276645, 5173.301604283449, 5530.6128570999645, 5593.860153496195, 5607.579431542651, 4710.69380490267, 5075.213341932006, 5614.29821272341, 5715.257016587901, 5718.226660069863, 5033.39599248078, 5213.142274716758, 5416.729163541729, 5470.72646367672, 5513.42109078557, 4817.807287708592, 4820.301056346881, 5309.495597918191, 5386.569074473954, 5725.971370143123, 4718.882027949328, 5295.564040541394, 6004.849878753066, 6035.879282414415, 6046.455818976313, 3833.3866581347966, 3849.653531182207, 3864.8067596620617, 3876.5735769065946, 3881.3207688653806, 4888.5111488850835, 5586.636868603528, 5755.251817263729, 5764.711764411846, 5806.912896306474, 4636.330920868417, 4742.336072949721, 5278.287431196893, 5515.669059856422, 5571.9442805571425, 4473.316010393868, 4495.617872480876, 4714.858554243378, 5602.85992850182, 5612.494875461228, 4360.825566977285, 5479.561635069105, 5516.9448305516435, 5522.088855338824, 5543.307086614173, 4651.139559160618, 4737.857864264078, 5342.020194954784, 5476.507114359727, 5509.421510741315, 4765.523447655207, 5022.899542009212, 5033.043721692867, 5525.419830917695, 5530.861728456821], "value": 3984.580640443513, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [695.1484431571325, 746.9402447804174, 747.3012733094557, 783.7256960063977, 787.330768846481, 12314.630561083171, 12497.68755781106, 12659.746805063916, 12764.680882977918, 12785.808212876815, 13182.604521864336, 13254.337283135852, 13262.13796103255, 13280.93359533201, 13297.534586289481, 649.9642519661417, 678.518252041049, 785.6582386661792, 794.7933537280305, 817.2481317188201, 793.4525177627419, 802.5706247157772, 805.3597390074875, 807.3621838747382, 807.7051876065224, 856.1267507142711, 857.9313654907623, 859.6002858670748, 862.4480332587149, 862.9697960571381, 686.0361192524512, 712.501249125609, 845.5941148248813, 848.749618862434, 849.8597731374316, 618.5608218165108, 640.942315191635, 641.3458272562008, 641.6855740687076, 643.906633538139, 13523.39144738489, 13553.051286409955, 13613.795793063113, 13833.723325533432, 13912.234827084507, 12956.935215323863, 13035.41526080326, 13101.803472947913, 13116.344182790917, 13212.0, 11426.71433214168, 11846.822297665542, 11945.761084778344, 12009.399530023464, 12117.878821211763, 4317.76252306123, 4392.099619578011, 4425.35832304314, 4558.156741002925, 4560.817567297304, 86906.0, 88398.55800720955, 88669.55665221697, 89195.55402222948, 89715.55142224312, 9673.274504412197, 9930.751731206634, 10026.799464010679, 10361.0, 10369.9481502592, 19053.856768593927, 19377.709334359995, 19383.8061619382, 19418.029098545016, 19425.611487770166, 7126.180489243699, 7355.3380195781565, 7553.0, 7582.165961744204, 7584.848303033908, 3692.51997240357, 4110.280700877334, 4256.61690447855, 4346.869593912188, 4403.075354175595, 3311.7019468247972, 3321.4187517184396, 3670.3209906167444, 3836.5779764225913, 3847.711421643388, 591.7810410148272, 678.443676185533, 681.0941447874316, 690.8411065454974, 692.8198668346165, 591.775125452334, 658.1575583253443, 658.499540349338, 660.2308310817939, 680.0649107477219, 8374.204450577185, 9127.908720912706, 9166.0, 9208.539573021322, 9356.766080847898, 648.9610623362613, 659.3143131143653, 660.3462572053633, 674.2246416620925, 685.7085738561092, 4434.977825110918, 4437.600615944581, 4540.114677637895, 4559.247724125548, 4653.95346046542, 88597.0, 91538.0, 93032.53483732673, 94483.0, 96057.51971240099, 82953.75569366344, 86621.56689216514, 89017.0, 90156.0, 91044.1791164173, 79680.0, 82284.0, 82941.58529207436, 85393.0, 85744.57127714447, 9117.726468205965, 9198.0, 9237.769055773659, 9520.952395238257, 9556.904430955601, 52190.21714674207, 52945.7352713234, 54030.729846350514, 54233.915321692555, 54841.72579137079, 71011.93482097876, 73107.90338144825, 76911.84632230409, 77431.61284193544, 77719.0, 11752.65116093988, 12078.852509011629, 12144.75710485803, 12231.510739570673, 12408.875911240772, 12796.488140474648, 13185.670358240737, 14001.369938352595, 14048.2975851205, 14258.215798130788, 9309.487978160996, 11103.944480277547, 12872.871271287167, 13082.0, 13387.866121338662, 10709.839352409565, 12296.262224266215, 12911.806322904977, 13069.738605228033, 13349.332533373487, 10095.0, 11942.462589183333, 12747.553835615709, 12904.16122952005, 12913.0, 10746.0, 10960.780784384428, 11301.208915375844, 11340.886591134313, 11578.36319002463, 10255.94872025635, 11135.665930022113, 11213.046891014355, 11315.773684526428, 11350.52443182381], "value": 7181.335779260449, "unit": "score"}], "name": "dromaeo_css", "value": 7026.475693988798, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 115.0, 113.0, 115.0, 115.0, 115.0, 115.0, 115.0, 114.0, 114.0], "value": 114.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 112.0, 112.0, 148.0, 115.0, 115.0, 115.0, 114.0, 146.0, 114.0], "value": 124.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 179.0, 185.0, 185.0, 183.0, 186.0, 185.0, 183.0, 185.0, 184.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 57.0, 89.0, 58.0, 59.0, 58.0, 58.0, 83.0, 57.0], "value": 66.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 71.0, 94.0, 69.0, 69.0, 69.0, 92.0, 68.0, 67.0, 93.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 87.0, 84.0, 87.0, 84.0, 87.0, 84.0, 88.0, 86.0], "value": 85.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 94.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 81.0, 79.0, 79.0, 92.0, 128.0, 84.0, 85.0, 84.0, 81.0], "value": 87.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 57.0, 51.0, 55.0, 58.0, 57.0, 56.0, 56.0, 58.0], "value": 55.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 76.0, 75.0, 76.0, 71.0, 65.0, 68.0, 68.0, 68.0, 71.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 81.0, 84.0, 84.0, 85.0, 89.0, 104.0, 77.0, 84.0, 85.0], "value": 85.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 147.0, 146.0, 145.0, 149.0, 151.0, 152.0, 153.0, 196.0, 160.0], "value": 154.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 169.0, 222.0, 207.0, 165.0, 172.0, 165.0, 167.0, 168.0, 166.0], "value": 176.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 69.0, 69.0, 69.0, 70.0, 73.0, 79.0, 85.0, 86.0], "value": 73.6, "unit": "score"}], "name": "kraken", "value": 1452.8000000000002, "alertThreshold": 2.0}]} 09:48:04 INFO - Return code: 0 09:48:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:48:04 INFO - # TBPL SUCCESS # 09:48:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:48:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:48:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:48:04 INFO - 2016-12-05 09:48:04.912 screenresolution[1662:10999] starting screenresolution argv=screenresolution get 09:48:04 INFO - 2016-12-05 09:48:04.915 screenresolution[1662:10999] Display 0: 1600x1200x32@60 09:48:04 INFO - 2016-12-05 09:48:04.921 screenresolution[1663:11002] starting screenresolution argv=screenresolution list 09:48:04 INFO - Available Modes on Display 0 09:48:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:48:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:48:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:48:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:48:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:48:05 INFO - Intel Iris: 09:48:05 INFO - Chipset Model: Intel Iris 09:48:05 INFO - Type: GPU 09:48:05 INFO - Bus: Built-In 09:48:05 INFO - VRAM (Dynamic, Max): 1536 MB 09:48:05 INFO - Vendor: Intel (0x8086) 09:48:05 INFO - Device ID: 0x0a2e 09:48:05 INFO - Revision ID: 0x0009 09:48:05 INFO - Displays: 09:48:05 INFO - Display: 09:48:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:48:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:48:05 INFO - Display Serial Number: 1600x1200 60 09:48:05 INFO - Main Display: Yes 09:48:05 INFO - Mirror: Off 09:48:05 INFO - Online: Yes 09:48:05 INFO - Rotation: Supported 09:48:05 INFO - Return code: 0 09:48:05 INFO - Running post-action listener: _resource_record_post_action 09:48:05 INFO - [mozharness: 2016-12-05 17:48:05.174721Z] Finished run-tests step (success) 09:48:05 INFO - Running post-run listener: _resource_record_post_run 09:48:05 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 32365568; Write bytes: 655184896; Read time: 203; Write time: 1905 09:48:05 INFO - TinderboxPrint: CPU usage
53.5% 09:48:05 INFO - TinderboxPrint: I/O read bytes / time
32,365,568 / 203 09:48:05 INFO - TinderboxPrint: I/O write bytes / time
655,184,896 / 1,905 09:48:05 INFO - TinderboxPrint: CPU idle
2,254.2 (46.5%) 09:48:05 INFO - TinderboxPrint: CPU system
967.5 (20.0%) 09:48:05 INFO - TinderboxPrint: CPU user
1,623.9 (33.5%) 09:48:05 INFO - TinderboxPrint: Swap in / out
377,217,024 / 0 09:48:05 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 349669888; Write bytes: 350356992; Read time: 26891; Write time: 756 09:48:05 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 29207552; Write bytes: 303468032; Read time: 181; Write time: 1144 09:48:05 INFO - Running post-run listener: _upload_blobber_files 09:48:05 INFO - Blob upload gear active. 09:48:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:48:05 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 09:48:05 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'] 09:48:05 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 09:48:06 INFO - (blobuploader) - INFO - Open directory for files ... 09:48:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:48:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:48:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:48:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:48:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:48:07 INFO - (blobuploader) - INFO - Done attempting. 09:48:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:48:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:48:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:48:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:48:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:48:08 INFO - (blobuploader) - INFO - Done attempting. 09:48:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:48:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:48:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:48:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:48:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:48:11 INFO - (blobuploader) - INFO - Done attempting. 09:48:11 INFO - (blobuploader) - INFO - Iteration through files over. 09:48:11 INFO - Return code: 0 09:48:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:48:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:48:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/187b44a388d0b790073a710439ff7e4e000442cf375090ce43ac1ac053ae73cc7b21ed19d337d599fb564ea099c051a826a5ca7885300d20bcfcadd85e28d994", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f2d7cae5217090ad87656c0c939c6862917b42368ce158f05581ee1892d821f592fcb8a08b2ae34fceea9bd6fcf1b88f9203a6d77df2a61c04ef1a1a13d92529", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4eaa9273a0672b0cd72de96b8bca9eedfb767ef8a1cc0e4aa88459bb1c2e45afe363cd0bd1dd338384901dca1becd2fa9d70dd7727c674c096ecb3e0271853f2"} 09:48:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:48:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:48:11 INFO - Contents: 09:48:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/187b44a388d0b790073a710439ff7e4e000442cf375090ce43ac1ac053ae73cc7b21ed19d337d599fb564ea099c051a826a5ca7885300d20bcfcadd85e28d994", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f2d7cae5217090ad87656c0c939c6862917b42368ce158f05581ee1892d821f592fcb8a08b2ae34fceea9bd6fcf1b88f9203a6d77df2a61c04ef1a1a13d92529", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4eaa9273a0672b0cd72de96b8bca9eedfb767ef8a1cc0e4aa88459bb1c2e45afe363cd0bd1dd338384901dca1becd2fa9d70dd7727c674c096ecb3e0271853f2"} 09:48:11 INFO - Running post-run listener: copy_logs_to_upload_dir 09:48:11 INFO - Copying logs to upload dir... 09:48:11 INFO - mkdir: /builds/slave/test/build/upload/logs 09:48:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.490155 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-12-05 09:48:11.295282) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-05 09:48:11.298572) ========= 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.UbR4I2wSN5/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.Nwd6lYAbbl/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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/187b44a388d0b790073a710439ff7e4e000442cf375090ce43ac1ac053ae73cc7b21ed19d337d599fb564ea099c051a826a5ca7885300d20bcfcadd85e28d994", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f2d7cae5217090ad87656c0c939c6862917b42368ce158f05581ee1892d821f592fcb8a08b2ae34fceea9bd6fcf1b88f9203a6d77df2a61c04ef1a1a13d92529", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4eaa9273a0672b0cd72de96b8bca9eedfb767ef8a1cc0e4aa88459bb1c2e45afe363cd0bd1dd338384901dca1becd2fa9d70dd7727c674c096ecb3e0271853f2"} build_url:https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011394 build_url: 'https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/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/187b44a388d0b790073a710439ff7e4e000442cf375090ce43ac1ac053ae73cc7b21ed19d337d599fb564ea099c051a826a5ca7885300d20bcfcadd85e28d994", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f2d7cae5217090ad87656c0c939c6862917b42368ce158f05581ee1892d821f592fcb8a08b2ae34fceea9bd6fcf1b88f9203a6d77df2a61c04ef1a1a13d92529", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4eaa9273a0672b0cd72de96b8bca9eedfb767ef8a1cc0e4aa88459bb1c2e45afe363cd0bd1dd338384901dca1becd2fa9d70dd7727c674c096ecb3e0271853f2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-05 09:48:11.333024) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 09:48:11.333369) ========= 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.UbR4I2wSN5/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.Nwd6lYAbbl/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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.005131 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 09:48:11.383646) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-05 09:48:11.384190) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-05 09:48:11.384713) ========= ========= Total master_lag: 0.18 =========