builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0364 starttime: 1485365563.26 results: success (0) buildid: 20170125065747 builduid: 24b59be5a7a94a7fb07650b1ae633bf4 revision: 005d7fcffdd3103ef2504c086614c61e40d9c50f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-25 09:32:43.261750) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-25 09:32:43.262190) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-25 09:32:43.262482) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-25 09:32:43.293599) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 09:32:43.293938) ========= 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.6q8C4gmqrL/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.biq7lWnfuY/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 --2017-01-25 09:32:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.6M=0.001s 2017-01-25 09:32:43 (18.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.078777 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 09:32:43.399504) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 09:32:43.399835) ========= 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.6q8C4gmqrL/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.biq7lWnfuY/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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.024857 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 09:32:43.447763) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-25 09:32:43.448082) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 005d7fcffdd3103ef2504c086614c61e40d9c50f --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-beta --rev 005d7fcffdd3103ef2504c086614c61e40d9c50f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6q8C4gmqrL/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.biq7lWnfuY/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 2017-01-25 09:32:43,538 truncating revision to first 12 chars 2017-01-25 09:32:43,538 Setting DEBUG logging. 2017-01-25 09:32:43,538 attempt 1/10 2017-01-25 09:32:43,538 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/005d7fcffdd3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-25 09:32:44,852 unpacking tar archive at: mozilla-beta-005d7fcffdd3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.640243 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-25 09:32:45.114268) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-25 09:32:45.114607) ========= script_repo_revision: 005d7fcffdd3103ef2504c086614c61e40d9c50f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-25 09:32:45.114982) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-25 09:32:45.115248) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-25 09:32:45.136505) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-01-25 09:32:45.136772) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6q8C4gmqrL/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.biq7lWnfuY/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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:32:45 INFO - MultiFileLogger online at 20170125 09:32:45 in /builds/slave/test 09:32:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:32:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:32:45 INFO - {'append_to_log': False, 09:32:45 INFO - 'base_work_dir': '/builds/slave/test', 09:32:45 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:32:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:32:45 INFO - 'branch': 'Mozilla-Beta', 09:32:45 INFO - 'buildbot_json_path': 'buildprops.json', 09:32:45 INFO - 'config_files': ('talos/mac_config.py',), 09:32:45 INFO - 'default_actions': ('clobber', 09:32:45 INFO - 'read-buildbot-config', 09:32:45 INFO - 'download-and-extract', 09:32:45 INFO - 'populate-webroot', 09:32:45 INFO - 'create-virtualenv', 09:32:45 INFO - 'install', 09:32:45 INFO - 'run-tests'), 09:32:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:32:45 INFO - 'download_minidump_stackwalk': True, 09:32:45 INFO - 'download_symbols': 'ondemand', 09:32:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:32:45 INFO - 'tooltool.py': '/tools/tooltool.py', 09:32:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:32:45 INFO - '/tools/misc-python/virtualenv.py')}, 09:32:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:32:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:32:45 INFO - 'installer_path': 'installer.exe', 09:32:45 INFO - 'log_level': 'info', 09:32:45 INFO - 'log_name': 'talos', 09:32:45 INFO - 'log_to_console': True, 09:32:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:32:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:32:45 INFO - 'opt_config_files': (), 09:32:45 INFO - 'pip_index': False, 09:32:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:32:45 INFO - 'cmd': ('bash', 09:32:45 INFO - '-c', 09:32:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:32:45 INFO - 'enabled': True, 09:32:45 INFO - 'halt_on_failure': False, 09:32:45 INFO - 'name': 'check_screen_resolution'},), 09:32:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:32:45 INFO - 'cmd': ('bash', 09:32:45 INFO - '-c', 09:32:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:32:45 INFO - 'enabled': True, 09:32:45 INFO - 'halt_on_failure': False, 09:32:45 INFO - 'name': 'check_screen_resolution'},), 09:32:45 INFO - 'run_cmd_checks_enabled': True, 09:32:45 INFO - 'sps_profile': False, 09:32:45 INFO - 'sps_profile_interval': 0, 09:32:45 INFO - 'suite': 'dromaeojs', 09:32:45 INFO - 'system_bits': '32', 09:32:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:32:45 INFO - 'title': 't-yosemite-r7-0364', 09:32:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:32:45 INFO - 'use_talos_json': True, 09:32:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:32:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:32:45 INFO - 'work_dir': 'build'} 09:32:45 INFO - [mozharness: 2017-01-25 17:32:45.331280Z] Running clobber step. 09:32:45 INFO - Running pre-action listener: _resource_record_pre_action 09:32:45 INFO - Running main action method: clobber 09:32:45 INFO - rmtree: /builds/slave/test/build 09:32:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:32:47 INFO - Running post-action listener: _resource_record_post_action 09:32:47 INFO - [mozharness: 2017-01-25 17:32:47.709057Z] Finished clobber step (success) 09:32:47 INFO - [mozharness: 2017-01-25 17:32:47.709315Z] Running read-buildbot-config step. 09:32:47 INFO - Running pre-action listener: _resource_record_pre_action 09:32:47 INFO - Running main action method: read_buildbot_config 09:32:47 INFO - Using buildbot properties: 09:32:47 INFO - { 09:32:47 INFO - "project": "", 09:32:47 INFO - "product": "firefox", 09:32:47 INFO - "script_repo_revision": "production", 09:32:47 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 09:32:47 INFO - "repository": "", 09:32:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 09:32:47 INFO - "buildid": "20170125065747", 09:32:47 INFO - "pgo_build": "False", 09:32:47 INFO - "basedir": "/builds/slave/test", 09:32:47 INFO - "buildnumber": 10, 09:32:47 INFO - "slavename": "t-yosemite-r7-0364", 09:32:47 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:32:47 INFO - "platform": "yosemite_r7", 09:32:47 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 09:32:47 INFO - "branch": "mozilla-beta", 09:32:47 INFO - "repo_path": "releases/mozilla-beta", 09:32:47 INFO - "revision": "005d7fcffdd3103ef2504c086614c61e40d9c50f", 09:32:47 INFO - "stage_platform": "macosx64", 09:32:47 INFO - "builduid": "24b59be5a7a94a7fb07650b1ae633bf4", 09:32:47 INFO - "slavebuilddir": "test" 09:32:47 INFO - } 09:32:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/JyHMYc2iQHaEWAgEDSkiJQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg. 09:32:47 INFO - Running post-action listener: _resource_record_post_action 09:32:47 INFO - [mozharness: 2017-01-25 17:32:47.715226Z] Finished read-buildbot-config step (success) 09:32:47 INFO - [mozharness: 2017-01-25 17:32:47.715457Z] Running download-and-extract step. 09:32:47 INFO - Running pre-action listener: _resource_record_pre_action 09:32:47 INFO - Running main action method: download_and_extract 09:32:47 INFO - mkdir: /builds/slave/test/build/tests 09:32:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:47 INFO - trying https://queue.taskcluster.net/v1/task/JyHMYc2iQHaEWAgEDSkiJQ/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json 09:32:47 INFO - Downloading https://queue.taskcluster.net/v1/task/JyHMYc2iQHaEWAgEDSkiJQ/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 09:32:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JyHMYc2iQHaEWAgEDSkiJQ/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json'}, attempt #1 09:32:49 INFO - Downloaded 1165 bytes. 09:32:49 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 09:32:49 INFO - Using the following test package requirements: 09:32:49 INFO - {u'common': [u'firefox-52.0.en-US.mac.common.tests.zip'], 09:32:49 INFO - u'cppunittest': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:32:49 INFO - u'firefox-52.0.en-US.mac.cppunittest.tests.zip'], 09:32:49 INFO - u'gtest': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:32:49 INFO - u'firefox-52.0.en-US.mac.gtest.tests.zip'], 09:32:49 INFO - u'jittest': [u'firefox-52.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:32:49 INFO - u'mochitest': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:32:49 INFO - u'firefox-52.0.en-US.mac.mochitest.tests.zip'], 09:32:49 INFO - u'mozbase': [u'firefox-52.0.en-US.mac.common.tests.zip'], 09:32:49 INFO - u'reftest': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:32:49 INFO - u'firefox-52.0.en-US.mac.reftest.tests.zip'], 09:32:49 INFO - u'talos': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:32:49 INFO - u'firefox-52.0.en-US.mac.talos.tests.zip'], 09:32:49 INFO - u'web-platform': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:32:49 INFO - u'firefox-52.0.en-US.mac.web-platform.tests.zip'], 09:32:49 INFO - u'xpcshell': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:32:49 INFO - u'firefox-52.0.en-US.mac.xpcshell.tests.zip']} 09:32:49 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip'] for test suite category: common 09:32:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JyHMYc2iQHaEWAgEDSkiJQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 09:32:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JyHMYc2iQHaEWAgEDSkiJQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 09:32:49 INFO - Fetch https://queue.taskcluster.net/v1/task/JyHMYc2iQHaEWAgEDSkiJQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 09:32:49 INFO - Http code: 200 09:32:49 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 09:32:49 INFO - x-amz-cf-id: Ahgg1PZ5keyYFtkDboXShhjZdveFrDJuoC14cDa232k1N5J7_lR3kw== 09:32:49 INFO - x-amz-version-id: YYf5zRA5hzjV3QdeasGTeOi9CE5xCHWJ 09:32:49 INFO - Expected file size: 16086197 09:32:49 INFO - Obtained file size: 16086197 09:32:56 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip', u'firefox-52.0.en-US.mac.talos.tests.zip'] for test suite category: talos 09:32:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JyHMYc2iQHaEWAgEDSkiJQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 09:32:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JyHMYc2iQHaEWAgEDSkiJQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 09:32:56 INFO - Fetch https://queue.taskcluster.net/v1/task/JyHMYc2iQHaEWAgEDSkiJQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 09:32:57 INFO - Http code: 200 09:32:57 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 09:32:57 INFO - x-amz-cf-id: HCUmNnO2xmjSY_bLjBID6yuYnGalvCBjUKACjhfnve_LII_GRYYfRw== 09:32:57 INFO - x-amz-version-id: YYf5zRA5hzjV3QdeasGTeOi9CE5xCHWJ 09:32:57 INFO - Expected file size: 16086197 09:32:57 INFO - Obtained file size: 16086197 09:33:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JyHMYc2iQHaEWAgEDSkiJQ/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip 09:33:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JyHMYc2iQHaEWAgEDSkiJQ/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip'}, attempt #1 09:33:03 INFO - Fetch https://queue.taskcluster.net/v1/task/JyHMYc2iQHaEWAgEDSkiJQ/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip into memory 09:33:04 INFO - Http code: 200 09:33:04 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 09:33:04 INFO - x-amz-cf-id: SjYnRC6KabUG7_dGTJ0UUCv6xPtQFFQpXLsjsIA9qVZ86NL7WMvTYQ== 09:33:04 INFO - x-amz-version-id: nB5ppOCnNelSBEOBnHz71c2VUGrmZBZe 09:33:04 INFO - Expected file size: 14402992 09:33:04 INFO - Obtained file size: 14402992 09:33:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:04 INFO - trying https://queue.taskcluster.net/v1/task/JyHMYc2iQHaEWAgEDSkiJQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg 09:33:04 INFO - Downloading https://queue.taskcluster.net/v1/task/JyHMYc2iQHaEWAgEDSkiJQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0.en-US.mac.dmg 09:33:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JyHMYc2iQHaEWAgEDSkiJQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg'}, attempt #1 09:33:07 INFO - Downloaded 82124122 bytes. 09:33:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JyHMYc2iQHaEWAgEDSkiJQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg 09:33:07 INFO - mkdir: /builds/slave/test/properties 09:33:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:33:07 INFO - Writing to file /builds/slave/test/properties/build_url 09:33:07 INFO - Contents: 09:33:07 INFO - build_url:https://queue.taskcluster.net/v1/task/JyHMYc2iQHaEWAgEDSkiJQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg 09:33:07 INFO - Running post-action listener: _resource_record_post_action 09:33:07 INFO - Running post-action listener: set_extra_try_arguments 09:33:07 INFO - [mozharness: 2017-01-25 17:33:07.891048Z] Finished download-and-extract step (success) 09:33:07 INFO - [mozharness: 2017-01-25 17:33:07.891188Z] Running populate-webroot step. 09:33:07 INFO - Running pre-action listener: _resource_record_pre_action 09:33:07 INFO - Running main action method: populate_webroot 09:33:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:33:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:33:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:33:07 INFO - '--tppagecycles', 09:33:07 INFO - '7'], 09:33:07 INFO - 'tests': ['tsvgm']}}, 09:33:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:33:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:33:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:33:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:33:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:33:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:33:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:07 INFO - 'talos_options': ['--disable-e10s'], 09:33:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:33:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:33:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:07 INFO - 'talos_options': ['--disable-e10s'], 09:33:07 INFO - 'tests': ['damp', 'tps']}, 09:33:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:07 INFO - 'tests': ['damp', 'tps']}, 09:33:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:33:07 INFO - 'tests': ['dromaeo_dom']}, 09:33:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:33:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:33:07 INFO - 'tests': ['basic_compositor_video']}, 09:33:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:33:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:33:07 INFO - 'tests': ['a11yr', 09:33:07 INFO - 'ts_paint', 09:33:07 INFO - 'tpaint', 09:33:07 INFO - 'sessionrestore', 09:33:07 INFO - 'sessionrestore_no_auto_restore', 09:33:07 INFO - 'tabpaint']}, 09:33:07 INFO - 'other-e10s': {'tests': ['a11yr', 09:33:07 INFO - 'ts_paint', 09:33:07 INFO - 'tpaint', 09:33:07 INFO - 'sessionrestore', 09:33:07 INFO - 'sessionrestore_no_auto_restore', 09:33:07 INFO - 'tabpaint']}, 09:33:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:33:07 INFO - 'ts_paint', 09:33:07 INFO - 'tpaint', 09:33:07 INFO - 'sessionrestore', 09:33:07 INFO - 'sessionrestore_no_auto_restore', 09:33:07 INFO - 'tabpaint']}, 09:33:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:33:07 INFO - 'ts_paint', 09:33:07 INFO - 'tpaint', 09:33:07 INFO - 'sessionrestore', 09:33:07 INFO - 'sessionrestore_no_auto_restore', 09:33:07 INFO - 'tabpaint']}, 09:33:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:33:07 INFO - 'tests': ['a11yr', 09:33:07 INFO - 'ts_paint', 09:33:07 INFO - 'tpaint', 09:33:07 INFO - 'sessionrestore', 09:33:07 INFO - 'sessionrestore_no_auto_restore', 09:33:07 INFO - 'tabpaint']}, 09:33:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:33:07 INFO - 'tests': ['a11yr', 09:33:07 INFO - 'ts_paint', 09:33:07 INFO - 'tpaint', 09:33:07 INFO - 'sessionrestore', 09:33:07 INFO - 'sessionrestore_no_auto_restore', 09:33:07 INFO - 'tabpaint']}, 09:33:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:33:07 INFO - 'tests': ['tsvgx', 09:33:07 INFO - 'tsvgr_opacity', 09:33:07 INFO - 'tart', 09:33:07 INFO - 'tscrollx', 09:33:07 INFO - 'cart']}, 09:33:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:33:07 INFO - 'tsvgr_opacity', 09:33:07 INFO - 'tart', 09:33:07 INFO - 'tscrollx', 09:33:07 INFO - 'cart']}, 09:33:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:07 INFO - 'talos_options': ['--disable-e10s'], 09:33:07 INFO - 'tests': ['tp5o']}, 09:33:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:07 INFO - 'tests': ['tp5o']}, 09:33:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:07 INFO - 'talos_options': ['--disable-e10s', 09:33:07 INFO - '--xperf_path', 09:33:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:33:07 INFO - 'tests': ['tp5n']}, 09:33:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:07 INFO - 'talos_options': ['--xperf_path', 09:33:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:33:07 INFO - 'tests': ['tp5n']}}, 09:33:07 INFO - 'talos.zip': {'path': '', 09:33:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:33:07 INFO - Running post-action listener: _resource_record_post_action 09:33:07 INFO - [mozharness: 2017-01-25 17:33:07.910049Z] Finished populate-webroot step (success) 09:33:07 INFO - [mozharness: 2017-01-25 17:33:07.910176Z] Running create-virtualenv step. 09:33:07 INFO - Running pre-action listener: _resource_record_pre_action 09:33:07 INFO - Running main action method: create_virtualenv 09:33:07 INFO - Creating virtualenv /builds/slave/test/build/venv 09:33:07 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:33:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:33:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:33:08 INFO - Using real prefix '/tools/python27' 09:33:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:33:08 INFO - Installing distribute.............................................................................................................................................................................................done. 09:33:11 INFO - Installing pip.................done. 09:33:11 INFO - Return code: 0 09:33:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:33:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:33:11 INFO - Reading from file tmpfile_stdout 09:33:11 INFO - Output received: 09:33:11 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:33:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:33: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:33:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33: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:33:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:11 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 0x100ce2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c58570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe30b465e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100af6d30>, '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 0x10095cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100aeeb20>, '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.6q8C4gmqrL/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.biq7lWnfuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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:33:11 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:33:11 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:33:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6q8C4gmqrL/Render', 09:33:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:11 INFO - 'HOME': '/Users/cltbld', 09:33:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:11 INFO - 'LOGNAME': 'cltbld', 09:33:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:11 INFO - 'MOZ_NO_REMOTE': '1', 09:33:11 INFO - 'NO_EM_RESTART': '1', 09:33:11 INFO - 'PAGER': '/bin/cat', 09:33:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:11 INFO - 'PWD': '/builds/slave/test', 09:33:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:11 INFO - 'SHELL': '/bin/bash', 09:33:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.biq7lWnfuY/Listeners', 09:33:11 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 09:33:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:11 INFO - 'USER': 'cltbld', 09:33:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:11 INFO - 'XPC_FLAGS': '0x0', 09:33:11 INFO - 'XPC_SERVICE_NAME': '0', 09:33:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:12 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:33:12 INFO - Cleaning up... 09:33:12 INFO - Return code: 0 09:33:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:33:12 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:33:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:12 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:33:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:12 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 0x100ce2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c58570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe30b465e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100af6d30>, '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 0x10095cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100aeeb20>, '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.6q8C4gmqrL/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.biq7lWnfuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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:33:12 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:33:12 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:33:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6q8C4gmqrL/Render', 09:33:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:12 INFO - 'HOME': '/Users/cltbld', 09:33:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:12 INFO - 'LOGNAME': 'cltbld', 09:33:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:12 INFO - 'MOZ_NO_REMOTE': '1', 09:33:12 INFO - 'NO_EM_RESTART': '1', 09:33:12 INFO - 'PAGER': '/bin/cat', 09:33:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:12 INFO - 'PWD': '/builds/slave/test', 09:33:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:12 INFO - 'SHELL': '/bin/bash', 09:33:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.biq7lWnfuY/Listeners', 09:33:12 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 09:33:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:12 INFO - 'USER': 'cltbld', 09:33:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:12 INFO - 'XPC_FLAGS': '0x0', 09:33:12 INFO - 'XPC_SERVICE_NAME': '0', 09:33:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:12 INFO - Downloading/unpacking psutil>=3.1.1 09:33:12 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:33:12 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:33:12 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:33:12 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:33:12 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:33:12 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:33:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:33:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:33:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:33:15 INFO - Installing collected packages: psutil 09:33:15 INFO - Running setup.py install for psutil 09:33:15 INFO - building 'psutil._psutil_osx' extension 09:33:15 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:33:16 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:33:16 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:33:16 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:33:16 INFO - building 'psutil._psutil_posix' extension 09:33:16 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:33:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:33:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:33:16 INFO - ^ 09:33:16 INFO - 1 warning generated. 09:33:16 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:33:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:33:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:33:16 INFO - Successfully installed psutil 09:33:16 INFO - Cleaning up... 09:33:17 INFO - Return code: 0 09:33:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:33:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100ce2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c58570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe30b465e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100af6d30>, '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 0x10095cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100aeeb20>, '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.6q8C4gmqrL/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.biq7lWnfuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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:33:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:33:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:33:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6q8C4gmqrL/Render', 09:33:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:17 INFO - 'HOME': '/Users/cltbld', 09:33:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:17 INFO - 'LOGNAME': 'cltbld', 09:33:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:17 INFO - 'MOZ_NO_REMOTE': '1', 09:33:17 INFO - 'NO_EM_RESTART': '1', 09:33:17 INFO - 'PAGER': '/bin/cat', 09:33:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:17 INFO - 'PWD': '/builds/slave/test', 09:33:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:17 INFO - 'SHELL': '/bin/bash', 09:33:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.biq7lWnfuY/Listeners', 09:33:17 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 09:33:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:17 INFO - 'USER': 'cltbld', 09:33:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:17 INFO - 'XPC_FLAGS': '0x0', 09:33:17 INFO - 'XPC_SERVICE_NAME': '0', 09:33:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:33:17 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:33:17 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:33:17 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:33:17 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:33:17 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:33:17 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:33:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:33:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:33:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:33:20 INFO - Installing collected packages: mozsystemmonitor 09:33:20 INFO - Running setup.py install for mozsystemmonitor 09:33:20 INFO - Successfully installed mozsystemmonitor 09:33:20 INFO - Cleaning up... 09:33:20 INFO - Return code: 0 09:33:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:33:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100ce2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c58570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe30b465e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100af6d30>, '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 0x10095cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100aeeb20>, '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.6q8C4gmqrL/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.biq7lWnfuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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:33:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:33:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:33:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6q8C4gmqrL/Render', 09:33:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:20 INFO - 'HOME': '/Users/cltbld', 09:33:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:20 INFO - 'LOGNAME': 'cltbld', 09:33:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:20 INFO - 'MOZ_NO_REMOTE': '1', 09:33:20 INFO - 'NO_EM_RESTART': '1', 09:33:20 INFO - 'PAGER': '/bin/cat', 09:33:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:20 INFO - 'PWD': '/builds/slave/test', 09:33:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:20 INFO - 'SHELL': '/bin/bash', 09:33:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.biq7lWnfuY/Listeners', 09:33:20 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 09:33:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:20 INFO - 'USER': 'cltbld', 09:33:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:20 INFO - 'XPC_FLAGS': '0x0', 09:33:20 INFO - 'XPC_SERVICE_NAME': '0', 09:33:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:20 INFO - Downloading/unpacking jsonschema==2.5.1 09:33:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:33:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:33:23 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:33:23 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:33:23 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:33:23 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:33:23 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:33:23 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:33:23 INFO - Downloading functools32-3.2.3-2.tar.gz 09:33:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:33:23 INFO - warning: no files found matching '*.txt' 09:33:23 INFO - no previously-included directories found matching 'build' 09:33:23 INFO - no previously-included directories found matching 'dist' 09:33:23 INFO - no previously-included directories found matching '.git*' 09:33:23 INFO - Installing collected packages: jsonschema, functools32 09:33:23 INFO - Running setup.py install for functools32 09:33:23 INFO - warning: no files found matching '*.txt' 09:33:23 INFO - no previously-included directories found matching 'build' 09:33:23 INFO - no previously-included directories found matching 'dist' 09:33:23 INFO - no previously-included directories found matching '.git*' 09:33:23 INFO - Successfully installed jsonschema functools32 09:33:23 INFO - Cleaning up... 09:33:23 INFO - Return code: 0 09:33:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:33:23 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:33:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:23 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:33:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:23 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 0x100ce2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c58570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe30b465e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100af6d30>, '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 0x10095cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100aeeb20>, '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.6q8C4gmqrL/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.biq7lWnfuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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:33:23 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:33:23 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:33:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6q8C4gmqrL/Render', 09:33:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:23 INFO - 'HOME': '/Users/cltbld', 09:33:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:23 INFO - 'LOGNAME': 'cltbld', 09:33:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:23 INFO - 'MOZ_NO_REMOTE': '1', 09:33:23 INFO - 'NO_EM_RESTART': '1', 09:33:23 INFO - 'PAGER': '/bin/cat', 09:33:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:23 INFO - 'PWD': '/builds/slave/test', 09:33:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:23 INFO - 'SHELL': '/bin/bash', 09:33:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.biq7lWnfuY/Listeners', 09:33:23 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 09:33:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:23 INFO - 'USER': 'cltbld', 09:33:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:23 INFO - 'XPC_FLAGS': '0x0', 09:33:23 INFO - 'XPC_SERVICE_NAME': '0', 09:33:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:33:24 INFO - Cleaning up... 09:33:24 INFO - Return code: 0 09:33:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:33: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:33:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33: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:33:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33: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', '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 0x100ce2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c58570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe30b465e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100af6d30>, '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 0x10095cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100aeeb20>, '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.6q8C4gmqrL/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.biq7lWnfuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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:33: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:33: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 blobuploader==1.2.4 09:33:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6q8C4gmqrL/Render', 09:33:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:24 INFO - 'HOME': '/Users/cltbld', 09:33:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:24 INFO - 'LOGNAME': 'cltbld', 09:33:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:24 INFO - 'MOZ_NO_REMOTE': '1', 09:33:24 INFO - 'NO_EM_RESTART': '1', 09:33:24 INFO - 'PAGER': '/bin/cat', 09:33:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:24 INFO - 'PWD': '/builds/slave/test', 09:33:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:24 INFO - 'SHELL': '/bin/bash', 09:33:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.biq7lWnfuY/Listeners', 09:33:24 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 09:33:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:24 INFO - 'USER': 'cltbld', 09:33:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:24 INFO - 'XPC_FLAGS': '0x0', 09:33:24 INFO - 'XPC_SERVICE_NAME': '0', 09:33:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:24 INFO - Downloading/unpacking blobuploader==1.2.4 09:33:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:26 INFO - Downloading blobuploader-1.2.4.tar.gz 09:33:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:33:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:33:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:33:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:33:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:28 INFO - Downloading docopt-0.6.1.tar.gz 09:33:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:33:28 INFO - Installing collected packages: blobuploader, requests, docopt 09:33:28 INFO - Running setup.py install for blobuploader 09:33:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:33:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:33:28 INFO - Running setup.py install for requests 09:33:28 INFO - Running setup.py install for docopt 09:33:29 INFO - Successfully installed blobuploader requests docopt 09:33:29 INFO - Cleaning up... 09:33:29 INFO - Return code: 0 09:33:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:33:29 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:33:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:29 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:33:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:29 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 0x100ce2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c58570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe30b465e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100af6d30>, '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 0x10095cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100aeeb20>, '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.6q8C4gmqrL/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.biq7lWnfuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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:33:29 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:33:29 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:33:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6q8C4gmqrL/Render', 09:33:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:29 INFO - 'HOME': '/Users/cltbld', 09:33:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:29 INFO - 'LOGNAME': 'cltbld', 09:33:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:29 INFO - 'MOZ_NO_REMOTE': '1', 09:33:29 INFO - 'NO_EM_RESTART': '1', 09:33:29 INFO - 'PAGER': '/bin/cat', 09:33:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:29 INFO - 'PWD': '/builds/slave/test', 09:33:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:29 INFO - 'SHELL': '/bin/bash', 09:33:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.biq7lWnfuY/Listeners', 09:33:29 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 09:33:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:29 INFO - 'USER': 'cltbld', 09:33:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:29 INFO - 'XPC_FLAGS': '0x0', 09:33:29 INFO - 'XPC_SERVICE_NAME': '0', 09:33:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:33:29 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-XqGtNM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:33:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:33:29 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-nwlpQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:33:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:33:29 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-AisIFW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:33:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:33:29 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-q1Z6iC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:33:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:33:29 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-gbiVlR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:33:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:33:29 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-U1aYRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:33:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:33:30 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-zXBTgl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:33:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:33:30 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-Y_zl7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:33:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:33:30 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-lHizy2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:33:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:33:30 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-eIBLsy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:33:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:33:30 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-sdutdS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:33:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:33:30 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-8o7xlS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:33:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:33:30 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-DA6I57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:33:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:33:30 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-EY_nsn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:33:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:33:30 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-HVjOmW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:33:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:33:30 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-M1dQg9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:33:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:33:31 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-Z3R_xL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:33:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:33:31 INFO - Running setup.py install for manifestparser 09:33:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:33:31 INFO - Running setup.py install for mozcrash 09:33:31 INFO - Running setup.py install for mozdebug 09:33:31 INFO - Running setup.py install for mozdevice 09:33:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:33:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:33:31 INFO - Running setup.py install for mozfile 09:33:31 INFO - Running setup.py install for mozhttpd 09:33:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:33:32 INFO - Running setup.py install for mozinfo 09:33:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:33:32 INFO - Running setup.py install for mozInstall 09:33:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:33:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:33:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:33:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:33:32 INFO - Running setup.py install for mozleak 09:33:32 INFO - Running setup.py install for mozlog 09:33:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:33:32 INFO - Running setup.py install for moznetwork 09:33:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:33:32 INFO - Running setup.py install for mozprocess 09:33:33 INFO - Running setup.py install for mozprofile 09:33:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:33:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:33:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:33:33 INFO - Running setup.py install for mozrunner 09:33:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:33:33 INFO - Running setup.py install for mozscreenshot 09:33:33 INFO - Running setup.py install for moztest 09:33:33 INFO - Running setup.py install for mozversion 09:33:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:33:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:33:33 INFO - Cleaning up... 09:33:33 INFO - Return code: 0 09:33:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:33: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:33:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33: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:33:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:33 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 0x100ce2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c58570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe30b465e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100af6d30>, '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 0x10095cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100aeeb20>, '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.6q8C4gmqrL/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.biq7lWnfuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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:33:33 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:33:33 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:33:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6q8C4gmqrL/Render', 09:33:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:33 INFO - 'HOME': '/Users/cltbld', 09:33:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:33 INFO - 'LOGNAME': 'cltbld', 09:33:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:33 INFO - 'MOZ_NO_REMOTE': '1', 09:33:33 INFO - 'NO_EM_RESTART': '1', 09:33:33 INFO - 'PAGER': '/bin/cat', 09:33:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:33 INFO - 'PWD': '/builds/slave/test', 09:33:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:33 INFO - 'SHELL': '/bin/bash', 09:33:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.biq7lWnfuY/Listeners', 09:33:33 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 09:33:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:33 INFO - 'USER': 'cltbld', 09:33:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:33 INFO - 'XPC_FLAGS': '0x0', 09:33:33 INFO - 'XPC_SERVICE_NAME': '0', 09:33:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:33:34 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-NWSubF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:33:34 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:33:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:33:34 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-onmSPi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:33:34 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:33:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:33:34 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-vh2_li-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:33:34 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:33:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:33:34 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-Csvf24-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:33:34 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:33:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:33:34 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-_c2Dne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:33:34 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:33:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:33:34 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-Bw7GEb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:33:34 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:33:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:33:34 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-WKjdZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:33:34 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:33:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:33:34 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-IrugYw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:33:34 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:33:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:33:34 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-zRCDeT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:33:35 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:33:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:33:35 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-8C9QG3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:33:35 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:33:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:33:35 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-URacvw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:33:35 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:33:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:33:35 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-cAz6N9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:33:35 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:33:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:33:35 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-T8byB5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:33:35 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:33:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:33:35 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-wpBCE0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:33:35 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:33:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:33:35 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-BL_BKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:33:35 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:33:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:33:35 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-vhRZ2a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:33:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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:33:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:33:35 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-cByWlq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:33:35 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:33:35 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:33:35 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:33:35 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:33:35 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:33:35 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:33:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:33:35 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:33:35 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:33:35 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:33:35 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:33:35 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:33:35 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:33:38 INFO - Downloading blessings-1.6.tar.gz 09:33:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:33:38 INFO - Installing collected packages: blessings 09:33:38 INFO - Running setup.py install for blessings 09:33:38 INFO - Successfully installed blessings 09:33:38 INFO - Cleaning up... 09:33:38 INFO - Return code: 0 09:33:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:33:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:33:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:33:39 INFO - Reading from file tmpfile_stdout 09:33:39 INFO - Current package versions: 09:33:39 INFO - blessings == 1.6 09:33:39 INFO - blobuploader == 1.2.4 09:33:39 INFO - docopt == 0.6.1 09:33:39 INFO - functools32 == 3.2.3-2 09:33:39 INFO - jsonschema == 2.5.1 09:33:39 INFO - manifestparser == 1.1 09:33:39 INFO - mozInstall == 1.12 09:33:39 INFO - mozcrash == 1.0 09:33:39 INFO - mozdebug == 0.1 09:33:39 INFO - mozdevice == 0.48 09:33:39 INFO - mozfile == 1.2 09:33:39 INFO - mozhttpd == 0.7 09:33:39 INFO - mozinfo == 0.9 09:33:39 INFO - mozleak == 0.1 09:33:39 INFO - mozlog == 3.4 09:33:39 INFO - moznetwork == 0.27 09:33:39 INFO - mozprocess == 0.23 09:33:39 INFO - mozprofile == 0.28 09:33:39 INFO - mozrunner == 6.13 09:33:39 INFO - mozscreenshot == 0.1 09:33:39 INFO - mozsystemmonitor == 0.3 09:33:39 INFO - moztest == 0.8 09:33:39 INFO - mozversion == 1.4 09:33:39 INFO - psutil == 3.1.1 09:33:39 INFO - requests == 1.2.3 09:33:39 INFO - wsgiref == 0.1.2 09:33:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:33:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:39 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 0x100ce2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c58570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe30b465e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100af6d30>, '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 0x10095cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100aeeb20>, '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.6q8C4gmqrL/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.biq7lWnfuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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:33:39 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:33:39 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:33:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6q8C4gmqrL/Render', 09:33:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:39 INFO - 'HOME': '/Users/cltbld', 09:33:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:39 INFO - 'LOGNAME': 'cltbld', 09:33:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:39 INFO - 'MOZ_NO_REMOTE': '1', 09:33:39 INFO - 'NO_EM_RESTART': '1', 09:33:39 INFO - 'PAGER': '/bin/cat', 09:33:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:39 INFO - 'PWD': '/builds/slave/test', 09:33:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:39 INFO - 'SHELL': '/bin/bash', 09:33:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.biq7lWnfuY/Listeners', 09:33:39 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 09:33:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:39 INFO - 'USER': 'cltbld', 09:33:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:39 INFO - 'XPC_FLAGS': '0x0', 09:33:39 INFO - 'XPC_SERVICE_NAME': '0', 09:33:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:40 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:33:40 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:33:40 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:33:40 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:33:40 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:33:40 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:33:40 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:33:40 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:33:40 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:33:40 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:33:40 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:33:40 INFO - Cleaning up... 09:33:40 INFO - Return code: 0 09:33:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:33:40 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:33:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:40 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:33:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:40 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 0x100ce2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c58570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe30b465e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100af6d30>, '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 0x10095cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100aeeb20>, '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.6q8C4gmqrL/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.biq7lWnfuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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:33:40 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:33:40 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:33:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6q8C4gmqrL/Render', 09:33:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:40 INFO - 'HOME': '/Users/cltbld', 09:33:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:40 INFO - 'LOGNAME': 'cltbld', 09:33:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:40 INFO - 'MOZ_NO_REMOTE': '1', 09:33:40 INFO - 'NO_EM_RESTART': '1', 09:33:40 INFO - 'PAGER': '/bin/cat', 09:33:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:40 INFO - 'PWD': '/builds/slave/test', 09:33:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:40 INFO - 'SHELL': '/bin/bash', 09:33:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.biq7lWnfuY/Listeners', 09:33:40 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 09:33:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:40 INFO - 'USER': 'cltbld', 09:33:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:40 INFO - 'XPC_FLAGS': '0x0', 09:33:40 INFO - 'XPC_SERVICE_NAME': '0', 09:33:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:33:40 INFO - Cleaning up... 09:33:40 INFO - Return code: 0 09:33:40 INFO - Running post-action listener: _resource_record_post_action 09:33:40 INFO - Running post-action listener: _start_resource_monitoring 09:33:40 INFO - Starting resource monitoring. 09:33:40 INFO - [mozharness: 2017-01-25 17:33:40.906556Z] Finished create-virtualenv step (success) 09:33:40 INFO - [mozharness: 2017-01-25 17:33:40.907588Z] Running install step. 09:33:40 INFO - Running pre-action listener: _resource_record_pre_action 09:33:40 INFO - Running main action method: install 09:33:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:33:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:33:41 INFO - Reading from file tmpfile_stdout 09:33:41 INFO - Detecting whether we're running mozinstall >=1.0... 09:33:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:33:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:33:41 INFO - Reading from file tmpfile_stdout 09:33:41 INFO - Output received: 09:33:41 INFO - Usage: mozinstall [options] installer 09:33:41 INFO - Options: 09:33:41 INFO - -h, --help show this help message and exit 09:33:41 INFO - -d DEST, --destination=DEST 09:33:41 INFO - Directory to install application into. [default: 09:33:41 INFO - "/builds/slave/test"] 09:33:41 INFO - --app=APP Application being installed. [default: firefox] 09:33:41 INFO - mkdir: /builds/slave/test/build/application 09:33:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:33:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0.en-US.mac.dmg --destination /builds/slave/test/build/application 09:34:02 INFO - Reading from file tmpfile_stdout 09:34:02 INFO - Output received: 09:34:02 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 09:34:02 INFO - Running post-action listener: _resource_record_post_action 09:34:02 INFO - [mozharness: 2017-01-25 17:34:02.554813Z] Finished install step (success) 09:34:02 INFO - [mozharness: 2017-01-25 17:34:02.554972Z] Running run-tests step. 09:34:02 INFO - Running pre-action listener: _resource_record_pre_action 09:34:02 INFO - Running main action method: run_tests 09:34:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:34:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:34:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:34:02 INFO - 2017-01-25 09:34:02.569 screenresolution[1627:7382] starting screenresolution argv=screenresolution get 09:34:02 INFO - 2017-01-25 09:34:02.572 screenresolution[1627:7382] Display 0: 1600x1200x32@60 09:34:02 INFO - 2017-01-25 09:34:02.578 screenresolution[1628:7384] starting screenresolution argv=screenresolution list 09:34:02 INFO - Available Modes on Display 0 09:34:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:34:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:34:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:34:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:34:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:34:02 INFO - Intel Iris: 09:34:02 INFO - Chipset Model: Intel Iris 09:34:02 INFO - Type: GPU 09:34:02 INFO - Bus: Built-In 09:34:02 INFO - VRAM (Dynamic, Max): 1536 MB 09:34:02 INFO - Vendor: Intel (0x8086) 09:34:02 INFO - Device ID: 0x0a2e 09:34:02 INFO - Revision ID: 0x0009 09:34:02 INFO - Displays: 09:34:02 INFO - Display: 09:34:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:34:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:34:02 INFO - Display Serial Number: 1600x1200 60 09:34:02 INFO - Main Display: Yes 09:34:02 INFO - Mirror: Off 09:34:02 INFO - Online: Yes 09:34:02 INFO - Rotation: Supported 09:34:02 INFO - Return code: 0 09:34:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:34:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:34:02 INFO - Python 2.7.3 09:34:02 INFO - Return code: 0 09:34:02 INFO - grabbing minidump binary from tooltool 09:34:02 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:34:02 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 0x100af6d30>, '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 0x10095cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100aeeb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:34:02 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:34:02 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:34:02 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:34:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:34:02 INFO - Return code: 0 09:34:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:34:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:34:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:34:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:34:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:34:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JyHMYc2iQHaEWAgEDSkiJQ/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0364', '--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:34:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JyHMYc2iQHaEWAgEDSkiJQ/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0364 --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:34:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6q8C4gmqrL/Render', 09:34:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:02 INFO - 'HOME': '/Users/cltbld', 09:34:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:02 INFO - 'LOGNAME': 'cltbld', 09:34:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:34:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:34:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:02 INFO - 'MOZ_NO_REMOTE': '1', 09:34:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:34:02 INFO - 'NO_EM_RESTART': '1', 09:34:02 INFO - 'PAGER': '/bin/cat', 09:34:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:02 INFO - 'PWD': '/builds/slave/test', 09:34:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:34:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:02 INFO - 'SHELL': '/bin/bash', 09:34:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.biq7lWnfuY/Listeners', 09:34:02 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 09:34:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:02 INFO - 'USER': 'cltbld', 09:34:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:02 INFO - 'XPC_FLAGS': '0x0', 09:34:02 INFO - 'XPC_SERVICE_NAME': '0', 09:34:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JyHMYc2iQHaEWAgEDSkiJQ/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0364', '--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:34:03 INFO - mozversion application_buildid: 20170125065747 09:34:03 INFO - mozversion application_changeset: 005d7fcffdd3103ef2504c086614c61e40d9c50f 09:34:03 INFO - mozversion application_display_name: Firefox 09:34:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:34:03 INFO - mozversion application_name: Firefox 09:34:03 INFO - mozversion application_remotingname: firefox 09:34:03 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:34:03 INFO - mozversion application_vendor: Mozilla 09:34:03 INFO - mozversion application_version: 52.0 09:34:03 INFO - mozversion platform_buildid: 20170125065747 09:34:03 INFO - mozversion platform_changeset: 005d7fcffdd3103ef2504c086614c61e40d9c50f 09:34:03 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:34:03 INFO - mozversion platform_version: 52.0 09:34:03 INFO - using testdate: 1485365643 09:34:03 INFO - actual date: 1485365643 09:34:03 INFO - starting webserver on 'localhost:49241' 09:34:03 INFO - SUITE-START | Running 2 tests 09:34:03 INFO - TEST-START | dromaeo_css 09:34:03 INFO - operating with platform_type : mac_ 09:34:03 INFO - Initialising browser for dromaeo_css test... 09:34:03 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/tmpQEsDFa/profile http://localhost:49241/getInfo.html) 09:34:05 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 09:34:05 INFO - PROCESS | 1638 | colorDepth:24 09:34:05 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 09:34:05 INFO - PROCESS | 1638 | __metrics 09:34:06 INFO - PROCESS | 1638 | 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:34:06 INFO - PROCESS | 1638 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:34:06 INFO - TEST-INFO | 1638: exit 0 09:34:06 INFO - Browser initialized. 09:34:06 INFO - Running cycle 1/1 for dromaeo_css test... 09:34:06 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/tmpQEsDFa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:34:18 INFO - PROCESS | 1641 | RSS: Main: 171569152 09:34:18 INFO - PROCESS | 1641 | 09:37:23 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 09:37:25 INFO - PROCESS | 1641 | RSS: Main: 360378368 09:37:25 INFO - PROCESS | 1641 | 09:40:28 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 09:40:29 INFO - PROCESS | 1641 | RSS: Main: 298287104 09:40:29 INFO - PROCESS | 1641 | 09:43:31 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 09:43:32 INFO - PROCESS | 1641 | RSS: Main: 266326016 09:43:32 INFO - PROCESS | 1641 | 09:46:34 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 09:46:34 INFO - PROCESS | 1641 | RSS: Main: 286982144 09:46:34 INFO - PROCESS | 1641 | 09:49:38 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 09:49:38 INFO - PROCESS | 1641 | RSS: Main: 262033408 09:49:38 INFO - PROCESS | 1641 | 09:52:41 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 09:52:42 INFO - PROCESS | 1641 | RSS: Main: 320770048 09:52:42 INFO - PROCESS | 1641 | 09:52:42 INFO - PROCESS | 1641 | __start_tp_report 09:52:42 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 09:52:42 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 09:52:42 INFO - PROCESS | 1641 | |i|pagename|runs| 09:52:42 INFO - PROCESS | 1641 | |0;dojo.html;3477.0090524200605;3509.2104276537784;3519.683228509434;3520;3568.857245710172;5791.247137872076;5827.796027139051;5846.766129354821;5862.208601838751;5917.585768996167;5742.28221472316;5744.626599271055;5795.3335366432875;5821.155932389812;5875.676837773921;5118.104331741949;5271.077561426754;5352.090144675414;5413.431589683086;5446.7276636168235;5282.550983166436;5322.7072511011875;5323.148296272597;5444.646098003617;5465.426130256325;5412.052890744124;5421.078416669176;5427;5432.945670543303;5434.701091439969;5404.513593776559;5412.83761487156;5418.187271909225;5420.647657902224;5426.348838139409;5260.579153667735;5359.705216213107;5367.543758780508;5390.676559406448;5407.378151512547;5914.497267732248;5917.763289468416;5918.496927761145;5934.198883150762;5935.2877654681715;6221.3156552779155;6222.191115155001;6267.96866015667;6268.059791031336;6271.34150914152;6189.566730328879;6259.906101408483;6268.09112678659;6270.6864656766975;6273.96863015682;5893.204417403638;5894.823155305347;5896.321922978869;5908;5943.970280148615;6196.907046394308;6234.189555357774;6250.656213908279;6402.9359706403275;6410.230772307353;5039.697618142924;6155.199824022849;6224.097505861625;6270.623762574169;6285.308616052231;6272.435480806749;6277.623342599459;6278.215223097113;6294.055891616203;6314.55798094129;6148.538859585459;6284.151639528696;6284.465820405317;6295.622262664255;6304.18045654062;6207.534434917309;6216.409441103088;6260.7495700171485;6261.9373806262265;6271.560990730605;4952.306677065214;4988.551030407209;5002.699838009731;5022.84931452057;5028.044671512401;4425.645948324126;4478.261086920622;4483.394741709827;4493.60801481561;4507;5789.218455508537;5898.380670029585;5914.349421563625;5926.614770039933;5930.614510056833;6144.446999770043;6170.043643235305;6204.37956204385;6217.502599792097;6283.868903597305;6060.696965151725;6066.48434883031;6081.483073938764;6092.664903430265;6099.359567245464;5426.891462170814;5521.475459831309;5539.390667026624;5543.972280138573;5549.944500555023;6245.437910588069;6317.842053948688;6385.074164246251;6446.258680251831;6473.902891456726;6235.657038862906;6246.187995560548;6259.061140828912;6261.186045814015;6261.279952805304;6492.220933488012;6493.902591461227;6500.057491663777;6503.837404064936;6509.8698026040165;4670.949036466904;6169.167162433194;6191.752329906753;6234.3453937337745;6285.59143655661;5455.908818236448;6111.257501106225;6228.096925945803;6230.408111229426;6261.624302541862;6228.22147231596;6244.968775156095;6247.219097612799;6252.437280644764;6265.467435267871;6152.784652537319;6246.406591373812;6254.437100660963;6255.030470276933;6284.120223168851;5961.850953726018;6048.758049677964;6065.3934606539;6094.39056094387;6117.755289788537;6215.036669316263;6237.719302631302;6242.032484964838;6244.063390491281;6253.718582663882;5968.612040217372;6011.7595296187665;6049.697515124139;6057.878842423215;6123.877522449437 09:52:42 INFO - PROCESS | 1641 | |1;ext.html;521.6218241774714;540.7184971617266;552.7816512477569;555.4223607448254;556.963797353172;30814.53778193329;32078.87723929666;32121;32708;39423;41362;41670;41733.58266417335;41771.16457670852;41870.7906460468;7787.532748035122;9545.904540954605;9585.089416505456;9623.663171788989;9662.613495460208;9580.904190958106;9688.224942004623;9689.951550242275;9690.467024313695;9699.466529340918;9658.517074146299;9707.660231891885;9713.174380177694;9715.222782177409;9727.27045471589;9654;9679.467629280387;9694.418334899929;9714.951425242829;9728.978457262026;9579.233661307078;9630.75923101921;9638.95180524093;9641.42151470914;9673.564689588986;39378.80310598428;39593.01017474557;39640.80179599112;39717.40423893644;39796;3387.8983630491125;3394.694477497037;3452.5339079224236;3464.532288141119;3467.6358982306747;29555;29593.704062959525;30113.096607101823;30434.84782576095;30659;3097.1018404662623;3199.7680892856183;3203.3753418083484;3219.7102260796405;3220.4364236258793;459387;460033;462917;464204;464927;59691.70154149202;60052.69973650104;60270.698646507364;60328;60345.69827150924;34792.82603587017;35536;63962;64218.03672944909;64319;15028.849711502964;15283.388664453298;15298.005629634261;15313.770293445607;15341.463048793239;11655.475503602358;11741.412929353499;11745.17783755146;11764;11764.352960587079;18681.626367472574;18685.906570467334;18693.626127477648;18694.06529673511;18728.719069213974;7515.361194298436;7551.358134558624;7604.277593628707;7639.714922583343;7669.578173200527;890.7238755985666;915.8031023330016;1038.864947556828;1039.7972395382828;1119.6081371520065;29048.854755726086;29295.414091718045;29328.767873229157;29674;29791.85104074466;881.3610132653903;902.2331018634109;1123.8258069999163;1143.1997601678693;1156.6183159557327;14985.025973311918;15442.382304707686;15449.691006180039;15742.08771377299;15812.525624231292;599996;694920;704204;704298;708138;56162.7191864038;56783.43216567781;56940.14589781156;56975.715121424124;57021.714891425276;1369.3700897587023;1392.449982257023;1412.5974097382198;1415.7663985442698;1417.3338530890464;28662.42675146527;28677.85661071681;28802;29275;29571.556426654046;48472.757636211594;48555;48637.5136248633;48684.75657621689;48732.5126748728;590563;593491;593674;593925;593983;26575.867120665047;26907.04022557556;26923.596146058215;26946.865265673547;27001.0549630762;45139.54860451353;45272.773636131606;45832.541674582826;46456;46616.03763680125;37438.43842342422;37913.525157716525;38370.61629383782;38425;39246.607533924296;37582.624173759;38189.4271585932;38206.61793382031;38945.41581876217;39019;24491.632625510276;31830.84084579562;31907;32395.676043239266;36633.53465861336;27253.318667033484;30579.388412232074;30953.535696964114;34343.656563434044;34483.82758086194;24389.878050609634;25600.74399256058;25671.614925776503;25705.61441578415;26022.828972695894 09:52:42 INFO - PROCESS | 1641 | |2;jquery.html;336.1210434713225;342.8388657331054;365.5960164018758;377.2530389828142;415.93760935859615;15874.920625396871;16386;16731;16808.915955420238;20890.477738056557;20518;20760.27339043134;20895.373138805822;21287.99999999996;21462.248821291258;5826.15520749491;5827.125931110325;6103.908441373383;6113.602615829956;6117.082437634368;6004.969975150118;6074.362191969846;6119.204503414549;6119.724612392431;6126.203593532835;5696.857578560528;6035.336113027563;6060.969695151518;6075.270967483908;6125.234345706786;5847.356790753014;6057.303410107828;6101.481374083208;6101.993171126759;6102.237220347479;5819.82540523785;5906.409359064102;5912.645241285537;5917.8816423671715;5922.052471604523;13639.659008524866;13926.651833704138;22670.886645566832;25295.74704252952;25490.87254563734;2264.558411109834;2275.4993901341845;2386.737458879522;2407.590709579376;2525.4949010198034;10408.063274305274;10625.9468702656;10841;10880.6191783288;15255.466058687893;1884.990575047116;1914.808519148088;1918.1847714721187;1973.9506512337164;1980.7920168382257;84115.5794221025;90784.6382304266;96004;112518.85821618765;123407.38296308462;10406.531706073094;11787.35169565665;11845.822312665317;11925.761484770255;11948.701282468008;14984.475543356148;15009.17449540264;15128.54614361571;15130.54608361751;15174.165420901736;6493.188351456068;6617.139771829728;7909.644066017043;8399.209486165968;8670.82658346842;8254.463459875089;8263.628136733862;8278.379121565908;8409.579521023925;8423.031351394546;3939.6014414882875;4107.260693075275;4380.817179361555;4496.145732310851;4527.7736113194205;1873.7564116664746;1971.1425529894254;1974.9065990050592;1979.9406017819488;1987.1058023889307;842.3640151685561;885.7387070814125;937.1752857485369;941.6562954521551;946.8153710026617;19168.233270669294;20861.061252243686;20935.371938841858;21043.842588657466;21187.83466909304;960.8030353777459;977.4282045003611;984.4044353166231;985.556499575194;990.1237404896574;10436.686899393031;10915.181361397927;11153.553857845758;11191.160662950311;11219;602291;608918;614523;618801;619719;124098.37950810368;127517;128289.35855320664;135871.32064339812;136189.31905340607;934.7663084229079;954.4845783277092;964.2719746591279;987.1263931420744;989.5942663507927;15694.450694225647;18291.63416731631;18339;18551.81448185501;18768.718469223244;49619.50380496149;51876.221856672935;53238.73380633072;54814.177787332424;55615.72192139013;58770;71363.57272854618;74777;79121.60439197767;79973;6767.289434609492;7782.988211532464;7824.530528168328;7988.600569971362;8377.70678026266;10431;10458.581656733646;12034.097442691658;12342.75314493723;12477.875221248034;10330.380177189392;10394.16846652251;10443.843342349719;10552.841707374242;10892.128629709763;10490;10548.789024219626;10831.725337679176;10861.294015888941;10875.782484350428;10310.587576497157;10520.842187367349;10789.892101078889;10920.836187457036;11022;7808.960955195188;7938.5236885787035;8069.112397636138;8082.797930051795;8315.750527484186;7925.326347260548;8300.750977470443;9767.80464390694;10742.89257107419;10790.67627971162 09:52:42 INFO - PROCESS | 1641 | |3;mootools.html;1341.7387655603725;1480.7260656778494;1513.2433783108445;1667.39140213822;1839.4573600787767;7425.7400990965325;7780.533168009923;8114.756557303275;8359.58202089896;8368.246857782799;8270.545120018396;8355.791105222372;8356.122607126255;8358.91641083589;8367.665293388258;2359.9882000590014;2396.688430504032;2396.7243766966762;2397.460571371445;2399.592069348215;2376.607859703147;2406.03758496601;2427.0655797517993;2427.235420842429;2428.1137384854487;2354.105439932832;2371.893264803088;2390.032037025008;2390.916317928873;2397.6643269942133;2304.9884750576307;2334.0663734506165;2362.9881850590723;2366.0062773635027;2371.8814059296965;2351.1770880191966;2369.1707902234252;2394.568977584034;2396.436837343223;2397.1969390254285;6851.897221541681;8175.223353781381;8259.83480330396;8276.793080172984;8320.70877519284;5064.2403639454005;5214.60890433219;5217.50433708797;5220.608454365938;5230.738463076869;3217.3565286942703;3389.661033896615;3511.631278715749;3552.982235088834;3634.491171236029;1061.4798748613123;1071.5445935477392;1081.761383216213;1083.6369816111612;1109.8890110988916;10668.253222274443;10736.979986901231;10795.406252656101;10798.460076996118;10816.188785841015;4289.742615443083;4444.888877778081;4514.548545145459;4526.977365113154;4529.660275479352;8605.13948605147;8605.397622166389;8607.741767746978;8631.784205394915;8649.1350864913;5089.872753181126;5093.923591146136;5096.0827051131155;5104.4895510449405;5117.8720531986255;4128.1330920506425;4344.804483798237;4485.686001979895;4486.237339652267;4662.417197850269;3252.902412927616;3712;3751.437284407361;3775.5469343678933;3821.7515861468914;1165.463886612168;1174.9530018799153;1177.4112943528237;1187.4122309456875;1187.6674531131298;795.5346122518276;903.1465265324337;922.4004397141912;924.67174153176;932.5011119051275;5305.628605997543;5707.4577915098;5747.310322761212;5791.305043394819;5950.85122871923;914.963401463934;919.2508105893676;943.5895385507326;945.7704983521395;998.5756053677159;3792.089898424358;3916.2754890345377;3951.644352008333;4053.8175782089875;4081.6122468365456;9938.204943604494;10164.796704066026;10174.440405777754;10182.439965801952;10184.745381365525;9801.9019809801;10101.494925253708;10120.240981926385;10130.392176469435;10187.439690816927;7600.543967361976;7991.760247192594;7995.2804247618;8001.799955001055;8002.4798388105755;5369.4093649697725;5533.6956467393065;5665.773369065191;5795.275590551181;5846.970765146147;9172.587233574372;9374.531273436438;9377.718668439958;9431.858522122311;9479.052094790466;9220.400673956172;9428.198603118812;9449.149576538144;9525.571349289437;9563;7191.3887319578425;7243.0946131733535;7283.40967934666;7299.014633024686;7302.19675835647;7818.179091195342;7891.960540197262;7897.407694422826;7899.84200316002;7905.56519391439;7150;7284.927150728425;7302.744403945836;7419.517731347445;7421.332080112816;7056.294370562902;7121.82195445118;7155.427565794828;7174.856502870018;7177.282271772781;6659.866802664016;6805.353491418307;6806.65966701653;6811.318868113347;6819.147606549182;4839.274108883555;4883.853484395474;4905.558499735041;4917.164082105979;4952.455229924777;4826.493218212177;4884.413870335582;4893.09477746618;5018.87452813668;5112.948870511247 09:52:42 INFO - PROCESS | 1641 | |4;prototype.html;277.4105026818012;298.7102510564753;312.2537136244376;317.27819211294303;320.1228633544087;5083.745812709359;5232.686038837668;5248.632595718302;5273.367195936493;5318.633208222338;3902.687784977202;4230;5007.349044624194;5193.610479214055;5229.555487783534;4027.536833264183;4046.7167298289132;4051.4733084698946;4067.1865626874596;4073.674106071522;4048.251073551388;4050.898727531806;4054.9391759123637;4060.47213862199;4077;3319.468884978404;3760.6051364606733;3976.6479397005014;3993.5008123984503;4008.4588580541545;3293.4071867063917;3473.975177322696;3797.240551889633;3865.3715473478023;4023.1752490739327;2563.448858495414;2848.7151284871557;3295.2750394913082;3457.861685532576;3476.4263896457055;4740.525947405266;4893.486183950705;5067.961067981092;5092.821751238689;5132.820351287724;3800.7339486235974;4264.211120942605;4535.478419981711;4591.51789062147;4619.514950930137;4791.47293797682;5571.303587051619;5573.721313934287;5590.636608620467;5595.468430499137;3790.6019867913747;3801.9239615207816;3820.6884834674897;3827.870778120461;3833.731638785286;4532.841350552715;5396.568274538027;5420.945790542123;5704.600677952587;5776.97111514444;3448.6723761242642;3858.479105320746;3864.594217607167;3865.632764887331;3875.8449662013204;3679.392900171495;3916.1776027034643;3921.6666583340166;3921.9019524512114;3929.646331830149;3888;3988.5840526613215;4136.689748268892;4385.342198670161;4615.607673347803;5389.541888939484;5450.511672529584;5604.8318550443555;5619.7636519965545;5630.155476678449;1090.6128324444867;1162.8604567451791;1170.7599942011866;1209.60687776473;1238.1766125526603;778.3578547698172;822.5558198572824;864.7794812322818;866.2247288676599;871.1767379826131;4178.310578754532;4667.906641867144;4967.392119137482;4971.6271279654175;4980.8256711015665;4511.300748384006;5306.787728490895;5400.677708585346;5410.729463526808;5446.291982042309;4435.689501734848;4926.118220840776;4935.185694360391;4966.329545511382;4986.426560945538;2535.4929014196864;2703.715735025847;2804.9383702978294;2854.5860850177023;2856.300206449433;4462.754548499799;5381.7978302909705;5622.803201887914;5644.407337229614;5658.066419040894;4600.2869555218995;5172.482751724798;5520.012795393655;5522.779088836402;5560.582956278215;4554.726716397026;4823.879403014883;5068.4424713281505;5531.8893622126525;5614.326280846243;4932.4327702314695;5385.030694475032;5400.324959380077;5427.4301198373605;5439.156930675752;4809.78355973989;5023.508661480236;5072.5688316493515;5147.738803993046;5300.734963251899;4665.510121437336;5424.620276580601;5615.92845187236;5635.914711556587;5848.502877255481;3559.9644003559633;3778.4130665120165;3834.405667121601;3837.982934522298;3852;5059.898802024012;5614.775408983595;5650.63270887391;5670.914936275876;5677.77288908439;4417.470402250061;4616.722996620214;5145.867909060001;5485.451454854483;5495.835124946258;4902.411710594752;5418.02475554404;5466.851961088216;5510.366307874486;5524.779008839762;4125.731827431207;5187.662801917863;5376.919346209888;5407.810281738169;5409.215663728659;4663.393758811333;4950.401039454316;5087.465816089405;5479.178522578724;5486.6982315973;4609.78730209508;4860.538248866352;5263.421023687315;5370.355557333145;5401.6218864679095 09:52:42 INFO - PROCESS | 1641 | |5;yui.html;684.8048306232724;717.9533330333525;766;792.8374683189946;806.455642441352;14482.855171448282;15388.076715397085;15396.923015384908;15403.306851191695;15412.3835046598;13693.65765855854;13799.44802207913;13812.79280810786;13822.377992990314;13830.03189776716;658.5094104891863;679.0357692077262;811.8335741173046;828.9419740618144;837.6649340263882;826.8718348655968;830.1532436914343;838.6310023589632;841.364769598952;842.5787106446777;875.5272153037353;875.5884734174928;875.6759998800453;876.7676565710092;877.4165180155189;732.8314487667813;868.7133246028859;870.5734190246765;871.5772850167664;871.7079778274285;627.5701144715839;644.5584774429531;646.7800947677765;646.9256035555877;647.3882181338637;13727.519536816164;14032.71934561303;14067.507637232648;14068.577942661737;14930.402783888632;14127.78808317876;14278;14404.855951440457;14450.007979572329;14569.635759106;11395.54417823286;11499.367534785584;11523.366214858179;11610;11900.761984760256;3782.281366540376;3828.062124779419;3861.8841434757;3944.980275098606;3975.9006024849323;87849;90664.54667726684;92273.53863230642;92541;92557.99999999933;10282.239948883273;10654.840177397346;10728.624498142528;10943.616973405893;11146.331220126818;21323.893380532998;21441.142354305965;21669.24157654506;21731.565368692853;21759.347219583437;7676.923230767621;7694.461387702919;7698.615069246515;7833.843323133506;7866.292033716879;4334.804933777988;4381.715188512737;4416.668749843775;4485.439320084989;4580.610648094883;3304.7851889627577;3423.3495635829113;3426.5716785401823;3431.6396778337917;3437.4500079987083;617.8393617659441;630.8805134062087;635.6980434293673;644.1851058411114;651.4755621724463;531.5732790562123;542.5361316074778;584.0450862839203;627.0813258576208;678.9966050169817;7287.307705767944;8431;8569.914300856912;8584.141585841491;9084.137006984325;546.6993153765413;567.7870798450581;587.8941790477755;633.2464367402775;653.9019147127968;4246.660267178619;4363.258246098171;4411.911761764811;4418.80115394808;4486.685931984795;77111.61444192744;108439;111511.44244278727;119841.40079299721;121648;75337;97102;104838;106813.46593266985;111597.88402116037;93419;94211.58682619766;95156.52421737846;96249;96458.51770741101;7209.495335326476;8326.417150799385;8384.203500667427;8556.229939305493;8790.692325768567;44367;50528.74735626298;60571.69714151401;61855.6907215461;62349.68825155845;53790.46209537855;65421;66140.66929665321;70124.29875701178;72975.27024729685;12412.193207441487;12603.117781755189;12783.680407989503;13177.538786142439;13177.604671859859;13321.866781332063;14312.57062288133;14552.781708274173;14873.92563037178;15518.84481155174;12499.93750031244;13867.65330866696;13932.651683707989;14169.433222670978;14410.783838242225;13177.868221317663;13850.376733047238;14446.277686115442;14752.778708319169;14770.926145369205;12314.630561083182;13866.722665546835;13874.722505549631;14145;14164.716705666035;10286.691399258034;10860.294080884718;11599;11709.76580468403;11847.289162650268;11544.365059921449;11565.421728913689;11567.248128871934;11697.766044678889;11719.941400292944 09:52:42 INFO - PROCESS | 1641 | __end_tp_report 09:52:42 INFO - PROCESS | 1641 | __start_cc_report 09:52:42 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,2322 09:52:42 INFO - PROCESS | 1641 | __end_cc_report 09:52:42 INFO - PROCESS | 1641 | __startTimestamp1485366762229__endTimestamp 09:52:42 INFO - PROCESS | 1641 | 09:52:42 INFO - PROCESS | 1641 | ------- Summary: start ------- 09:52:42 INFO - PROCESS | 1641 | Number of tests: 6 09:52:42 INFO - PROCESS | 1641 | 09:52:42 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:5835.19 Median:6081.48 stddev:623.31 (10.2%) stddev-sans-first:597.14 09:52:42 INFO - PROCESS | 1641 | Values: 3477.0 3509.2 3519.7 3520.0 3568.9 5791.2 5827.8 5846.8 5862.2 5917.6 5742.3 5744.6 5795.3 5821.2 5875.7 5118.1 5271.1 5352.1 5413.4 5446.7 5282.6 5322.7 5323.1 5444.6 5465.4 5412.1 5421.1 5427.0 5432.9 5434.7 5404.5 5412.8 5418.2 5420.6 5426.3 5260.6 5359.7 5367.5 5390.7 5407.4 5914.5 5917.8 5918.5 5934.2 5935.3 6221.3 6222.2 6268.0 6268.1 6271.3 6189.6 6259.9 6268.1 6270.7 6274.0 5893.2 5894.8 5896.3 5908.0 5944.0 6196.9 6234.2 6250.7 6402.9 6410.2 5039.7 6155.2 6224.1 6270.6 6285.3 6272.4 6277.6 6278.2 6294.1 6314.6 6148.5 6284.2 6284.5 6295.6 6304.2 6207.5 6216.4 6260.7 6261.9 6271.6 4952.3 4988.6 5002.7 5022.8 5028.0 4425.6 4478.3 4483.4 4493.6 4507.0 5789.2 5898.4 5914.3 5926.6 5930.6 6144.4 6170.0 6204.4 6217.5 6283.9 6060.7 6066.5 6081.5 6092.7 6099.4 5426.9 5521.5 5539.4 5544.0 5549.9 6245.4 6317.8 6385.1 6446.3 6473.9 6235.7 6246.2 6259.1 6261.2 6261.3 6492.2 6493.9 6500.1 6503.8 6509.9 4670.9 6169.2 6191.8 6234.3 6285.6 5455.9 6111.3 6228.1 6230.4 6261.6 6228.2 6245.0 6247.2 6252.4 6265.5 6152.8 6246.4 6254.4 6255.0 6284.1 5961.9 6048.8 6065.4 6094.4 6117.8 6215.0 6237.7 6242.0 6244.1 6253.7 5968.6 6011.8 6049.7 6057.9 6123.9 09:52:42 INFO - PROCESS | 1641 | 09:52:42 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:70117.64 Median:39132.80 stddev:157221.82 (401.8%) stddev-sans-first:157576.03 09:52:42 INFO - PROCESS | 1641 | Values: 521.6 540.7 552.8 555.4 557.0 30814.5 32078.9 32121.0 32708.0 39423.0 41362.0 41670.0 41733.6 41771.2 41870.8 7787.5 9545.9 9585.1 9623.7 9662.6 9580.9 9688.2 9690.0 9690.5 9699.5 9658.5 9707.7 9713.2 9715.2 9727.3 9654.0 9679.5 9694.4 9715.0 9729.0 9579.2 9630.8 9639.0 9641.4 9673.6 39378.8 39593.0 39640.8 39717.4 39796.0 3387.9 3394.7 3452.5 3464.5 3467.6 29555.0 29593.7 30113.1 30434.8 30659.0 3097.1 3199.8 3203.4 3219.7 3220.4 459387.0 460033.0 462917.0 464204.0 464927.0 59691.7 60052.7 60270.7 60328.0 60345.7 34792.8 35536.0 63962.0 64218.0 64319.0 15028.8 15283.4 15298.0 15313.8 15341.5 11655.5 11741.4 11745.2 11764.0 11764.4 18681.6 18685.9 18693.6 18694.1 18728.7 7515.4 7551.4 7604.3 7639.7 7669.6 890.7 915.8 1038.9 1039.8 1119.6 29048.9 29295.4 29328.8 29674.0 29791.9 881.4 902.2 1123.8 1143.2 1156.6 14985.0 15442.4 15449.7 15742.1 15812.5 599996.0 694920.0 704204.0 704298.0 708138.0 56162.7 56783.4 56940.1 56975.7 57021.7 1369.4 1392.4 1412.6 1415.8 1417.3 28662.4 28677.9 28802.0 29275.0 29571.6 48472.8 48555.0 48637.5 48684.8 48732.5 590563.0 593491.0 593674.0 593925.0 593983.0 26575.9 26907.0 26923.6 26946.9 27001.1 45139.5 45272.8 45832.5 46456.0 46616.0 37438.4 37913.5 38370.6 38425.0 39246.6 37582.6 38189.4 38206.6 38945.4 39019.0 24491.6 31830.8 31907.0 32395.7 36633.5 27253.3 30579.4 30953.5 34343.7 34483.8 24389.9 25600.7 25671.6 25705.6 26022.8 09:52:42 INFO - PROCESS | 1641 | 09:52:42 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:34592.80 Median:354.22 stddev:101911.93 (28771.0%) stddev-sans-first:102165.36 09:52:42 INFO - PROCESS | 1641 | Values: 336.1 342.8 365.6 377.3 415.9 15874.9 16386.0 16731.0 16808.9 20890.5 20518.0 20760.3 20895.4 21288.0 21462.2 5826.2 5827.1 6103.9 6113.6 6117.1 6005.0 6074.4 6119.2 6119.7 6126.2 5696.9 6035.3 6061.0 6075.3 6125.2 5847.4 6057.3 6101.5 6102.0 6102.2 5819.8 5906.4 5912.6 5917.9 5922.1 13639.7 13926.7 22670.9 25295.7 25490.9 2264.6 2275.5 2386.7 2407.6 2525.5 10408.1 10625.9 10841.0 10880.6 15255.5 1885.0 1914.8 1918.2 1974.0 1980.8 84115.6 90784.6 96004.0 112518.9 123407.4 10406.5 11787.4 11845.8 11925.8 11948.7 14984.5 15009.2 15128.5 15130.5 15174.2 6493.2 6617.1 7909.6 8399.2 8670.8 8254.5 8263.6 8278.4 8409.6 8423.0 3939.6 4107.3 4380.8 4496.1 4527.8 1873.8 1971.1 1974.9 1979.9 1987.1 842.4 885.7 937.2 941.7 946.8 19168.2 20861.1 20935.4 21043.8 21187.8 960.8 977.4 984.4 985.6 990.1 10436.7 10915.2 11153.6 11191.2 11219.0 602291.0 608918.0 614523.0 618801.0 619719.0 124098.4 127517.0 128289.4 135871.3 136189.3 934.8 954.5 964.3 987.1 989.6 15694.5 18291.6 18339.0 18551.8 18768.7 49619.5 51876.2 53238.7 54814.2 55615.7 58770.0 71363.6 74777.0 79121.6 79973.0 6767.3 7783.0 7824.5 7988.6 8377.7 10431.0 10458.6 12034.1 12342.8 12477.9 10330.4 10394.2 10443.8 10552.8 10892.1 10490.0 10548.8 10831.7 10861.3 10875.8 10310.6 10520.8 10789.9 10920.8 11022.0 7809.0 7938.5 8069.1 8082.8 8315.8 7925.3 8300.8 9767.8 10742.9 10790.7 09:52:42 INFO - PROCESS | 1641 | 09:52:42 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:5445.52 Median:5100.29 stddev:2947.22 (57.8%) stddev-sans-first:2939.34 09:52:42 INFO - PROCESS | 1641 | Values: 1341.7 1480.7 1513.2 1667.4 1839.5 7425.7 7780.5 8114.8 8359.6 8368.2 8270.5 8355.8 8356.1 8358.9 8367.7 2360.0 2396.7 2396.7 2397.5 2399.6 2376.6 2406.0 2427.1 2427.2 2428.1 2354.1 2371.9 2390.0 2390.9 2397.7 2305.0 2334.1 2363.0 2366.0 2371.9 2351.2 2369.2 2394.6 2396.4 2397.2 6851.9 8175.2 8259.8 8276.8 8320.7 5064.2 5214.6 5217.5 5220.6 5230.7 3217.4 3389.7 3511.6 3553.0 3634.5 1061.5 1071.5 1081.8 1083.6 1109.9 10668.3 10737.0 10795.4 10798.5 10816.2 4289.7 4444.9 4514.5 4527.0 4529.7 8605.1 8605.4 8607.7 8631.8 8649.1 5089.9 5093.9 5096.1 5104.5 5117.9 4128.1 4344.8 4485.7 4486.2 4662.4 3252.9 3712.0 3751.4 3775.5 3821.8 1165.5 1175.0 1177.4 1187.4 1187.7 795.5 903.1 922.4 924.7 932.5 5305.6 5707.5 5747.3 5791.3 5950.9 915.0 919.3 943.6 945.8 998.6 3792.1 3916.3 3951.6 4053.8 4081.6 9938.2 10164.8 10174.4 10182.4 10184.7 9801.9 10101.5 10120.2 10130.4 10187.4 7600.5 7991.8 7995.3 8001.8 8002.5 5369.4 5533.7 5665.8 5795.3 5847.0 9172.6 9374.5 9377.7 9431.9 9479.1 9220.4 9428.2 9449.1 9525.6 9563.0 7191.4 7243.1 7283.4 7299.0 7302.2 7818.2 7892.0 7897.4 7899.8 7905.6 7150.0 7284.9 7302.7 7419.5 7421.3 7056.3 7121.8 7155.4 7174.9 7177.3 6659.9 6805.4 6806.7 6811.3 6819.1 4839.3 4883.9 4905.6 4917.2 4952.5 4826.5 4884.4 4893.1 5018.9 5112.9 09:52:42 INFO - PROCESS | 1641 | 09:52:42 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4348.38 Median:4877.01 stddev:1332.83 (27.3%) stddev-sans-first:1301.07 09:52:42 INFO - PROCESS | 1641 | Values: 277.4 298.7 312.3 317.3 320.1 5083.7 5232.7 5248.6 5273.4 5318.6 3902.7 4230.0 5007.3 5193.6 5229.6 4027.5 4046.7 4051.5 4067.2 4073.7 4048.3 4050.9 4054.9 4060.5 4077.0 3319.5 3760.6 3976.6 3993.5 4008.5 3293.4 3474.0 3797.2 3865.4 4023.2 2563.4 2848.7 3295.3 3457.9 3476.4 4740.5 4893.5 5068.0 5092.8 5132.8 3800.7 4264.2 4535.5 4591.5 4619.5 4791.5 5571.3 5573.7 5590.6 5595.5 3790.6 3801.9 3820.7 3827.9 3833.7 4532.8 5396.6 5420.9 5704.6 5777.0 3448.7 3858.5 3864.6 3865.6 3875.8 3679.4 3916.2 3921.7 3921.9 3929.6 3888.0 3988.6 4136.7 4385.3 4615.6 5389.5 5450.5 5604.8 5619.8 5630.2 1090.6 1162.9 1170.8 1209.6 1238.2 778.4 822.6 864.8 866.2 871.2 4178.3 4667.9 4967.4 4971.6 4980.8 4511.3 5306.8 5400.7 5410.7 5446.3 4435.7 4926.1 4935.2 4966.3 4986.4 2535.5 2703.7 2804.9 2854.6 2856.3 4462.8 5381.8 5622.8 5644.4 5658.1 4600.3 5172.5 5520.0 5522.8 5560.6 4554.7 4823.9 5068.4 5531.9 5614.3 4932.4 5385.0 5400.3 5427.4 5439.2 4809.8 5023.5 5072.6 5147.7 5300.7 4665.5 5424.6 5615.9 5635.9 5848.5 3560.0 3778.4 3834.4 3838.0 3852.0 5059.9 5614.8 5650.6 5670.9 5677.8 4417.5 4616.7 5145.9 5485.5 5495.8 4902.4 5418.0 5466.9 5510.4 5524.8 4125.7 5187.7 5376.9 5407.8 5409.2 4663.4 4950.4 5087.5 5479.2 5486.7 4609.8 4860.5 5263.4 5370.4 5401.6 09:52:42 INFO - PROCESS | 1641 | 09:52:42 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:20975.97 Median:4396.81 stddev:30792.75 (700.3%) stddev-sans-first:30841.44 09:52:42 INFO - PROCESS | 1641 | Values: 684.8 718.0 766.0 792.8 806.5 14482.9 15388.1 15396.9 15403.3 15412.4 13693.7 13799.4 13812.8 13822.4 13830.0 658.5 679.0 811.8 828.9 837.7 826.9 830.2 838.6 841.4 842.6 875.5 875.6 875.7 876.8 877.4 732.8 868.7 870.6 871.6 871.7 627.6 644.6 646.8 646.9 647.4 13727.5 14032.7 14067.5 14068.6 14930.4 14127.8 14278.0 14404.9 14450.0 14569.6 11395.5 11499.4 11523.4 11610.0 11900.8 3782.3 3828.1 3861.9 3945.0 3975.9 87849.0 90664.5 92273.5 92541.0 92558.0 10282.2 10654.8 10728.6 10943.6 11146.3 21323.9 21441.1 21669.2 21731.6 21759.3 7676.9 7694.5 7698.6 7833.8 7866.3 4334.8 4381.7 4416.7 4485.4 4580.6 3304.8 3423.3 3426.6 3431.6 3437.5 617.8 630.9 635.7 644.2 651.5 531.6 542.5 584.0 627.1 679.0 7287.3 8431.0 8569.9 8584.1 9084.1 546.7 567.8 587.9 633.2 653.9 4246.7 4363.3 4411.9 4418.8 4486.7 77111.6 108439.0 111511.4 119841.4 121648.0 75337.0 97102.0 104838.0 106813.5 111597.9 93419.0 94211.6 95156.5 96249.0 96458.5 7209.5 8326.4 8384.2 8556.2 8790.7 44367.0 50528.7 60571.7 61855.7 62349.7 53790.5 65421.0 66140.7 70124.3 72975.3 12412.2 12603.1 12783.7 13177.5 13177.6 13321.9 14312.6 14552.8 14873.9 15518.8 12499.9 13867.7 13932.7 14169.4 14410.8 13177.9 13850.4 14446.3 14752.8 14770.9 12314.6 13866.7 13874.7 14145.0 14164.7 10286.7 10860.3 11599.0 11709.8 11847.3 11544.4 11565.4 11567.2 11697.8 11719.9 09:52:42 INFO - PROCESS | 1641 | -------- Summary: end -------- 09:52:42 INFO - PROCESS | 1641 | 09:52:42 INFO - TEST-INFO | 1641: exit 0 09:52:42 INFO - TEST-OK | dromaeo_css | took 1119673ms 09:52:42 INFO - TEST-START | kraken 09:52:42 INFO - operating with platform_type : mac_ 09:52:42 INFO - Initialising browser for kraken test... 09:52:42 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/tmpDylIIs/profile http://localhost:49241/getInfo.html) 09:52:45 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 09:52:45 INFO - PROCESS | 1650 | colorDepth:24 09:52:45 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 09:52:45 INFO - PROCESS | 1650 | __metrics 09:52:45 INFO - PROCESS | 1650 | 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:52:45 INFO - PROCESS | 1650 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:52:46 INFO - TEST-INFO | 1650: exit 0 09:52:46 INFO - Browser initialized. 09:52:46 INFO - Running cycle 1/1 for kraken test... 09:52:46 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/tmpDylIIs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:52:58 INFO - PROCESS | 1652 | RSS: Main: 167469056 09:52:58 INFO - PROCESS | 1652 | 09:53:00 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 09:53:01 INFO - PROCESS | 1652 | RSS: Main: 217944064 09:53:01 INFO - PROCESS | 1652 | 09:53:04 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 09:53:04 INFO - PROCESS | 1652 | RSS: Main: 231284736 09:53:04 INFO - PROCESS | 1652 | 09:53:07 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 09:53:08 INFO - PROCESS | 1652 | RSS: Main: 327692288 09:53:08 INFO - PROCESS | 1652 | 09:53:10 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 09:53:11 INFO - PROCESS | 1652 | RSS: Main: 232669184 09:53:11 INFO - PROCESS | 1652 | 09:53:13 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:53:13 INFO - PROCESS | 1652 | RSS: Main: 233603072 09:53:13 INFO - PROCESS | 1652 | 09:53:16 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 09:53:17 INFO - PROCESS | 1652 | RSS: Main: 272912384 09:53:17 INFO - PROCESS | 1652 | 09:53:20 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 09:53:20 INFO - PROCESS | 1652 | RSS: Main: 272384000 09:53:20 INFO - PROCESS | 1652 | 09:53:23 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 09:53:24 INFO - PROCESS | 1652 | RSS: Main: 269697024 09:53:24 INFO - PROCESS | 1652 | 09:53:25 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:53:26 INFO - PROCESS | 1652 | RSS: Main: 249114624 09:53:26 INFO - PROCESS | 1652 | 09:53:28 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:53:29 INFO - PROCESS | 1652 | RSS: Main: 250220544 09:53:29 INFO - PROCESS | 1652 | 09:53:31 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:53:31 INFO - PROCESS | 1652 | RSS: Main: 252694528 09:53:31 INFO - PROCESS | 1652 | 09:53:34 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:53:35 INFO - PROCESS | 1652 | RSS: Main: 271163392 09:53:35 INFO - PROCESS | 1652 | 09:53:38 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:53:38 INFO - PROCESS | 1652 | RSS: Main: 264691712 09:53:38 INFO - PROCESS | 1652 | 09:53:40 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 09:53:40 INFO - PROCESS | 1652 | RSS: Main: 308822016 09:53:40 INFO - PROCESS | 1652 | 09:53:40 INFO - PROCESS | 1652 | __start_tp_report 09:53:40 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 09:53:40 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 09:53:40 INFO - PROCESS | 1652 | |i|pagename|runs| 09:53:40 INFO - PROCESS | 1652 | |0;ai-astar;103;114;113;114;116;115;113;115;113;115 09:53:40 INFO - PROCESS | 1652 | |1;audio-beat-detection;143;110;109;140;110;107;108;108;138;110 09:53:40 INFO - PROCESS | 1652 | |2;audio-dft;181;183;183;181;181;183;182;186;182;182 09:53:40 INFO - PROCESS | 1652 | |3;audio-fft;81;58;60;89;59;59;77;57;80;57 09:53:40 INFO - PROCESS | 1652 | |4;audio-oscillator;87;92;129;101;70;68;90;68;69;90 09:53:40 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;90;87;89;86;134;130;90;88;89;85 09:53:40 INFO - PROCESS | 1652 | |6;imaging-darkroom;95;95;95;94;94;94;95;95;102;95 09:53:40 INFO - PROCESS | 1652 | |7;imaging-desaturate;83;81;80;81;81;81;84;85;81;81 09:53:40 INFO - PROCESS | 1652 | |8;json-parse-financial;54;52;51;75;89;90;89;55;55;56 09:53:40 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;71;67;64;73;71;69;67;67;70;71 09:53:40 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;83;84;83;81;81;80;83;96;101;87 09:53:40 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;147;140;148;152;128;147;153;141;184;136 09:53:40 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;163;162;164;161;165;163;162;164;161;161 09:53:40 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;65;65;65;66;65;63;64;65;66;76 09:53:40 INFO - PROCESS | 1652 | __end_tp_report 09:53:40 INFO - PROCESS | 1652 | __start_cc_report 09:53:40 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1929 09:53:40 INFO - PROCESS | 1652 | __end_cc_report 09:53:40 INFO - PROCESS | 1652 | __startTimestamp1485366820813__endTimestamp 09:53:40 INFO - PROCESS | 1652 | 09:53:40 INFO - PROCESS | 1652 | ------- Summary: start ------- 09:53:40 INFO - PROCESS | 1652 | Number of tests: 14 09:53:40 INFO - PROCESS | 1652 | 09:53:40 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:113.10 Median:114.50 stddev:3.70 (3.2%) stddev-sans-first:1.09 09:53:40 INFO - PROCESS | 1652 | Values: 103.0 114.0 113.0 114.0 116.0 115.0 113.0 115.0 113.0 115.0 09:53:40 INFO - PROCESS | 1652 | 09:53:40 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:118.30 Median:110.00 stddev:15.28 (13.9%) stddev-sans-first:13.34 09:53:40 INFO - PROCESS | 1652 | Values: 143.0 110.0 109.0 140.0 110.0 107.0 108.0 108.0 138.0 110.0 09:53:40 INFO - PROCESS | 1652 | 09:53:40 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:182.40 Median:182.50 stddev:1.51 (0.8%) stddev-sans-first:1.51 09:53:40 INFO - PROCESS | 1652 | Values: 181.0 183.0 183.0 181.0 181.0 183.0 182.0 186.0 182.0 182.0 09:53:40 INFO - PROCESS | 1652 | 09:53:40 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:67.70 Median:68.50 stddev:12.48 (18.2%) stddev-sans-first:12.28 09:53:40 INFO - PROCESS | 1652 | Values: 81.0 58.0 60.0 89.0 59.0 59.0 77.0 57.0 80.0 57.0 09:53:40 INFO - PROCESS | 1652 | 09:53:40 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:86.40 Median:78.50 stddev:19.25 (24.5%) stddev-sans-first:20.41 09:53:40 INFO - PROCESS | 1652 | Values: 87.0 92.0 129.0 101.0 70.0 68.0 90.0 68.0 69.0 90.0 09:53:40 INFO - PROCESS | 1652 | 09:53:40 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:96.80 Median:88.50 stddev:18.65 (21.1%) stddev-sans-first:19.62 09:53:40 INFO - PROCESS | 1652 | Values: 90.0 87.0 89.0 86.0 134.0 130.0 90.0 88.0 89.0 85.0 09:53:40 INFO - PROCESS | 1652 | 09:53:40 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:95.40 Median:95.00 stddev:2.37 (2.5%) stddev-sans-first:2.51 09:53:40 INFO - PROCESS | 1652 | Values: 95.0 95.0 95.0 94.0 94.0 94.0 95.0 95.0 102.0 95.0 09:53:40 INFO - PROCESS | 1652 | 09:53:40 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:81.80 Median:81.00 stddev:1.62 (2.0%) stddev-sans-first:1.66 09:53:40 INFO - PROCESS | 1652 | Values: 83.0 81.0 80.0 81.0 81.0 81.0 84.0 85.0 81.0 81.0 09:53:40 INFO - PROCESS | 1652 | 09:53:40 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:66.60 Median:65.50 stddev:17.06 (26.0%) stddev-sans-first:17.47 09:53:40 INFO - PROCESS | 1652 | Values: 54.0 52.0 51.0 75.0 89.0 90.0 89.0 55.0 55.0 56.0 09:53:40 INFO - PROCESS | 1652 | 09:53:40 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:69.00 Median:70.50 stddev:2.71 (3.8%) stddev-sans-first:2.77 09:53:40 INFO - PROCESS | 1652 | Values: 71.0 67.0 64.0 73.0 71.0 69.0 67.0 67.0 70.0 71.0 09:53:40 INFO - PROCESS | 1652 | 09:53:40 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:85.90 Median:83.00 stddev:7.02 (8.5%) stddev-sans-first:7.36 09:53:40 INFO - PROCESS | 1652 | Values: 83.0 84.0 83.0 81.0 81.0 80.0 83.0 96.0 101.0 87.0 09:53:40 INFO - PROCESS | 1652 | 09:53:40 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:147.60 Median:147.50 stddev:14.89 (10.1%) stddev-sans-first:15.79 09:53:40 INFO - PROCESS | 1652 | Values: 147.0 140.0 148.0 152.0 128.0 147.0 153.0 141.0 184.0 136.0 09:53:40 INFO - PROCESS | 1652 | 09:53:40 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.60 Median:163.00 stddev:1.43 (0.9%) stddev-sans-first:1.51 09:53:40 INFO - PROCESS | 1652 | Values: 163.0 162.0 164.0 161.0 165.0 163.0 162.0 164.0 161.0 161.0 09:53:40 INFO - PROCESS | 1652 | 09:53:40 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:65.00 stddev:3.62 (5.6%) stddev-sans-first:3.82 09:53:40 INFO - PROCESS | 1652 | Values: 65.0 65.0 65.0 66.0 65.0 63.0 64.0 65.0 66.0 76.0 09:53:40 INFO - PROCESS | 1652 | -------- Summary: end -------- 09:53:40 INFO - PROCESS | 1652 | 09:53:41 INFO - TEST-INFO | 1652: exit 0 09:53:41 INFO - TEST-OK | kraken | took 59042ms 09:53:41 INFO - SUITE-END | took 1178s 09:53:41 INFO - Completed test suite (00:19:38) 09:53:41 INFO - javascript benchmark 09:53:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3477.0090524200605, 3509.2104276537784, 3519.683228509434, 3520.0, 3568.857245710172, 5791.247137872076, 5827.796027139051, 5846.766129354821, 5862.208601838751, 5917.585768996167, 5742.28221472316, 5744.626599271055, 5795.3335366432875, 5821.155932389812, 5875.676837773921, 5118.104331741949, 5271.077561426754, 5352.090144675414, 5413.431589683086, 5446.7276636168235, 5282.550983166436, 5322.7072511011875, 5323.148296272597, 5444.646098003617, 5465.426130256325, 5412.052890744124, 5421.078416669176, 5427.0, 5432.945670543303, 5434.701091439969, 5404.513593776559, 5412.83761487156, 5418.187271909225, 5420.647657902224, 5426.348838139409, 5260.579153667735, 5359.705216213107, 5367.543758780508, 5390.676559406448, 5407.378151512547, 5914.497267732248, 5917.763289468416, 5918.496927761145, 5934.198883150762, 5935.2877654681715, 6221.3156552779155, 6222.191115155001, 6267.96866015667, 6268.059791031336, 6271.34150914152, 6189.566730328879, 6259.906101408483, 6268.09112678659, 6270.6864656766975, 6273.96863015682, 5893.204417403638, 5894.823155305347, 5896.321922978869, 5908.0, 5943.970280148615, 6196.907046394308, 6234.189555357774, 6250.656213908279, 6402.9359706403275, 6410.230772307353, 5039.697618142924, 6155.199824022849, 6224.097505861625, 6270.623762574169, 6285.308616052231, 6272.435480806749, 6277.623342599459, 6278.215223097113, 6294.055891616203, 6314.55798094129, 6148.538859585459, 6284.151639528696, 6284.465820405317, 6295.622262664255, 6304.18045654062, 6207.534434917309, 6216.409441103088, 6260.7495700171485, 6261.9373806262265, 6271.560990730605, 4952.306677065214, 4988.551030407209, 5002.699838009731, 5022.84931452057, 5028.044671512401, 4425.645948324126, 4478.261086920622, 4483.394741709827, 4493.60801481561, 4507.0, 5789.218455508537, 5898.380670029585, 5914.349421563625, 5926.614770039933, 5930.614510056833, 6144.446999770043, 6170.043643235305, 6204.37956204385, 6217.502599792097, 6283.868903597305, 6060.696965151725, 6066.48434883031, 6081.483073938764, 6092.664903430265, 6099.359567245464, 5426.891462170814, 5521.475459831309, 5539.390667026624, 5543.972280138573, 5549.944500555023, 6245.437910588069, 6317.842053948688, 6385.074164246251, 6446.258680251831, 6473.902891456726, 6235.657038862906, 6246.187995560548, 6259.061140828912, 6261.186045814015, 6261.279952805304, 6492.220933488012, 6493.902591461227, 6500.057491663777, 6503.837404064936, 6509.8698026040165, 4670.949036466904, 6169.167162433194, 6191.752329906753, 6234.3453937337745, 6285.59143655661, 5455.908818236448, 6111.257501106225, 6228.096925945803, 6230.408111229426, 6261.624302541862, 6228.22147231596, 6244.968775156095, 6247.219097612799, 6252.437280644764, 6265.467435267871, 6152.784652537319, 6246.406591373812, 6254.437100660963, 6255.030470276933, 6284.120223168851, 5961.850953726018, 6048.758049677964, 6065.3934606539, 6094.39056094387, 6117.755289788537, 6215.036669316263, 6237.719302631302, 6242.032484964838, 6244.063390491281, 6253.718582663882, 5968.612040217372, 6011.7595296187665, 6049.697515124139, 6057.878842423215, 6123.877522449437], "value": 5798.030432488445, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [521.6218241774714, 540.7184971617266, 552.7816512477569, 555.4223607448254, 556.963797353172, 30814.53778193329, 32078.87723929666, 32121.0, 32708.0, 39423.0, 41362.0, 41670.0, 41733.58266417335, 41771.16457670852, 41870.7906460468, 7787.532748035122, 9545.904540954605, 9585.089416505456, 9623.663171788989, 9662.613495460208, 9580.904190958106, 9688.224942004623, 9689.951550242275, 9690.467024313695, 9699.466529340918, 9658.517074146299, 9707.660231891885, 9713.174380177694, 9715.222782177409, 9727.27045471589, 9654.0, 9679.467629280387, 9694.418334899929, 9714.951425242829, 9728.978457262026, 9579.233661307078, 9630.75923101921, 9638.95180524093, 9641.42151470914, 9673.564689588986, 39378.80310598428, 39593.01017474557, 39640.80179599112, 39717.40423893644, 39796.0, 3387.8983630491125, 3394.694477497037, 3452.5339079224236, 3464.532288141119, 3467.6358982306747, 29555.0, 29593.704062959525, 30113.096607101823, 30434.84782576095, 30659.0, 3097.1018404662623, 3199.7680892856183, 3203.3753418083484, 3219.7102260796405, 3220.4364236258793, 459387.0, 460033.0, 462917.0, 464204.0, 464927.0, 59691.70154149202, 60052.69973650104, 60270.698646507364, 60328.0, 60345.69827150924, 34792.82603587017, 35536.0, 63962.0, 64218.03672944909, 64319.0, 15028.849711502964, 15283.388664453298, 15298.005629634261, 15313.770293445607, 15341.463048793239, 11655.475503602358, 11741.412929353499, 11745.17783755146, 11764.0, 11764.352960587079, 18681.626367472574, 18685.906570467334, 18693.626127477648, 18694.06529673511, 18728.719069213974, 7515.361194298436, 7551.358134558624, 7604.277593628707, 7639.714922583343, 7669.578173200527, 890.7238755985666, 915.8031023330016, 1038.864947556828, 1039.7972395382828, 1119.6081371520065, 29048.854755726086, 29295.414091718045, 29328.767873229157, 29674.0, 29791.85104074466, 881.3610132653903, 902.2331018634109, 1123.8258069999163, 1143.1997601678693, 1156.6183159557327, 14985.025973311918, 15442.382304707686, 15449.691006180039, 15742.08771377299, 15812.525624231292, 599996.0, 694920.0, 704204.0, 704298.0, 708138.0, 56162.7191864038, 56783.43216567781, 56940.14589781156, 56975.715121424124, 57021.714891425276, 1369.3700897587023, 1392.449982257023, 1412.5974097382198, 1415.7663985442698, 1417.3338530890464, 28662.42675146527, 28677.85661071681, 28802.0, 29275.0, 29571.556426654046, 48472.757636211594, 48555.0, 48637.5136248633, 48684.75657621689, 48732.5126748728, 590563.0, 593491.0, 593674.0, 593925.0, 593983.0, 26575.867120665047, 26907.04022557556, 26923.596146058215, 26946.865265673547, 27001.0549630762, 45139.54860451353, 45272.773636131606, 45832.541674582826, 46456.0, 46616.03763680125, 37438.43842342422, 37913.525157716525, 38370.61629383782, 38425.0, 39246.607533924296, 37582.624173759, 38189.4271585932, 38206.61793382031, 38945.41581876217, 39019.0, 24491.632625510276, 31830.84084579562, 31907.0, 32395.676043239266, 36633.53465861336, 27253.318667033484, 30579.388412232074, 30953.535696964114, 34343.656563434044, 34483.82758086194, 24389.878050609634, 25600.74399256058, 25671.614925776503, 25705.61441578415, 26022.828972695894], "value": 19536.797890554546, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.1210434713225, 342.8388657331054, 365.5960164018758, 377.2530389828142, 415.93760935859615, 15874.920625396871, 16386.0, 16731.0, 16808.915955420238, 20890.477738056557, 20518.0, 20760.27339043134, 20895.373138805822, 21287.99999999996, 21462.248821291258, 5826.15520749491, 5827.125931110325, 6103.908441373383, 6113.602615829956, 6117.082437634368, 6004.969975150118, 6074.362191969846, 6119.204503414549, 6119.724612392431, 6126.203593532835, 5696.857578560528, 6035.336113027563, 6060.969695151518, 6075.270967483908, 6125.234345706786, 5847.356790753014, 6057.303410107828, 6101.481374083208, 6101.993171126759, 6102.237220347479, 5819.82540523785, 5906.409359064102, 5912.645241285537, 5917.8816423671715, 5922.052471604523, 13639.659008524866, 13926.651833704138, 22670.886645566832, 25295.74704252952, 25490.87254563734, 2264.558411109834, 2275.4993901341845, 2386.737458879522, 2407.590709579376, 2525.4949010198034, 10408.063274305274, 10625.9468702656, 10841.0, 10880.6191783288, 15255.466058687893, 1884.990575047116, 1914.808519148088, 1918.1847714721187, 1973.9506512337164, 1980.7920168382257, 84115.5794221025, 90784.6382304266, 96004.0, 112518.85821618765, 123407.38296308462, 10406.531706073094, 11787.35169565665, 11845.822312665317, 11925.761484770255, 11948.701282468008, 14984.475543356148, 15009.17449540264, 15128.54614361571, 15130.54608361751, 15174.165420901736, 6493.188351456068, 6617.139771829728, 7909.644066017043, 8399.209486165968, 8670.82658346842, 8254.463459875089, 8263.628136733862, 8278.379121565908, 8409.579521023925, 8423.031351394546, 3939.6014414882875, 4107.260693075275, 4380.817179361555, 4496.145732310851, 4527.7736113194205, 1873.7564116664746, 1971.1425529894254, 1974.9065990050592, 1979.9406017819488, 1987.1058023889307, 842.3640151685561, 885.7387070814125, 937.1752857485369, 941.6562954521551, 946.8153710026617, 19168.233270669294, 20861.061252243686, 20935.371938841858, 21043.842588657466, 21187.83466909304, 960.8030353777459, 977.4282045003611, 984.4044353166231, 985.556499575194, 990.1237404896574, 10436.686899393031, 10915.181361397927, 11153.553857845758, 11191.160662950311, 11219.0, 602291.0, 608918.0, 614523.0, 618801.0, 619719.0, 124098.37950810368, 127517.0, 128289.35855320664, 135871.32064339812, 136189.31905340607, 934.7663084229079, 954.4845783277092, 964.2719746591279, 987.1263931420744, 989.5942663507927, 15694.450694225647, 18291.63416731631, 18339.0, 18551.81448185501, 18768.718469223244, 49619.50380496149, 51876.221856672935, 53238.73380633072, 54814.177787332424, 55615.72192139013, 58770.0, 71363.57272854618, 74777.0, 79121.60439197767, 79973.0, 6767.289434609492, 7782.988211532464, 7824.530528168328, 7988.600569971362, 8377.70678026266, 10431.0, 10458.581656733646, 12034.097442691658, 12342.75314493723, 12477.875221248034, 10330.380177189392, 10394.16846652251, 10443.843342349719, 10552.841707374242, 10892.128629709763, 10490.0, 10548.789024219626, 10831.725337679176, 10861.294015888941, 10875.782484350428, 10310.587576497157, 10520.842187367349, 10789.892101078889, 10920.836187457036, 11022.0, 7808.960955195188, 7938.5236885787035, 8069.112397636138, 8082.797930051795, 8315.750527484186, 7925.326347260548, 8300.750977470443, 9767.80464390694, 10742.89257107419, 10790.67627971162], "value": 9367.675818870595, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1341.7387655603725, 1480.7260656778494, 1513.2433783108445, 1667.39140213822, 1839.4573600787767, 7425.7400990965325, 7780.533168009923, 8114.756557303275, 8359.58202089896, 8368.246857782799, 8270.545120018396, 8355.791105222372, 8356.122607126255, 8358.91641083589, 8367.665293388258, 2359.9882000590014, 2396.688430504032, 2396.7243766966762, 2397.460571371445, 2399.592069348215, 2376.607859703147, 2406.03758496601, 2427.0655797517993, 2427.235420842429, 2428.1137384854487, 2354.105439932832, 2371.893264803088, 2390.032037025008, 2390.916317928873, 2397.6643269942133, 2304.9884750576307, 2334.0663734506165, 2362.9881850590723, 2366.0062773635027, 2371.8814059296965, 2351.1770880191966, 2369.1707902234252, 2394.568977584034, 2396.436837343223, 2397.1969390254285, 6851.897221541681, 8175.223353781381, 8259.83480330396, 8276.793080172984, 8320.70877519284, 5064.2403639454005, 5214.60890433219, 5217.50433708797, 5220.608454365938, 5230.738463076869, 3217.3565286942703, 3389.661033896615, 3511.631278715749, 3552.982235088834, 3634.491171236029, 1061.4798748613123, 1071.5445935477392, 1081.761383216213, 1083.6369816111612, 1109.8890110988916, 10668.253222274443, 10736.979986901231, 10795.406252656101, 10798.460076996118, 10816.188785841015, 4289.742615443083, 4444.888877778081, 4514.548545145459, 4526.977365113154, 4529.660275479352, 8605.13948605147, 8605.397622166389, 8607.741767746978, 8631.784205394915, 8649.1350864913, 5089.872753181126, 5093.923591146136, 5096.0827051131155, 5104.4895510449405, 5117.8720531986255, 4128.1330920506425, 4344.804483798237, 4485.686001979895, 4486.237339652267, 4662.417197850269, 3252.902412927616, 3712.0, 3751.437284407361, 3775.5469343678933, 3821.7515861468914, 1165.463886612168, 1174.9530018799153, 1177.4112943528237, 1187.4122309456875, 1187.6674531131298, 795.5346122518276, 903.1465265324337, 922.4004397141912, 924.67174153176, 932.5011119051275, 5305.628605997543, 5707.4577915098, 5747.310322761212, 5791.305043394819, 5950.85122871923, 914.963401463934, 919.2508105893676, 943.5895385507326, 945.7704983521395, 998.5756053677159, 3792.089898424358, 3916.2754890345377, 3951.644352008333, 4053.8175782089875, 4081.6122468365456, 9938.204943604494, 10164.796704066026, 10174.440405777754, 10182.439965801952, 10184.745381365525, 9801.9019809801, 10101.494925253708, 10120.240981926385, 10130.392176469435, 10187.439690816927, 7600.543967361976, 7991.760247192594, 7995.2804247618, 8001.799955001055, 8002.4798388105755, 5369.4093649697725, 5533.6956467393065, 5665.773369065191, 5795.275590551181, 5846.970765146147, 9172.587233574372, 9374.531273436438, 9377.718668439958, 9431.858522122311, 9479.052094790466, 9220.400673956172, 9428.198603118812, 9449.149576538144, 9525.571349289437, 9563.0, 7191.3887319578425, 7243.0946131733535, 7283.40967934666, 7299.014633024686, 7302.19675835647, 7818.179091195342, 7891.960540197262, 7897.407694422826, 7899.84200316002, 7905.56519391439, 7150.0, 7284.927150728425, 7302.744403945836, 7419.517731347445, 7421.332080112816, 7056.294370562902, 7121.82195445118, 7155.427565794828, 7174.856502870018, 7177.282271772781, 6659.866802664016, 6805.353491418307, 6806.65966701653, 6811.318868113347, 6819.147606549182, 4839.274108883555, 4883.853484395474, 4905.558499735041, 4917.164082105979, 4952.455229924777, 4826.493218212177, 4884.413870335582, 4893.09477746618, 5018.87452813668, 5112.948870511247], "value": 4432.585396293192, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [277.4105026818012, 298.7102510564753, 312.2537136244376, 317.27819211294303, 320.1228633544087, 5083.745812709359, 5232.686038837668, 5248.632595718302, 5273.367195936493, 5318.633208222338, 3902.687784977202, 4230.0, 5007.349044624194, 5193.610479214055, 5229.555487783534, 4027.536833264183, 4046.7167298289132, 4051.4733084698946, 4067.1865626874596, 4073.674106071522, 4048.251073551388, 4050.898727531806, 4054.9391759123637, 4060.47213862199, 4077.0, 3319.468884978404, 3760.6051364606733, 3976.6479397005014, 3993.5008123984503, 4008.4588580541545, 3293.4071867063917, 3473.975177322696, 3797.240551889633, 3865.3715473478023, 4023.1752490739327, 2563.448858495414, 2848.7151284871557, 3295.2750394913082, 3457.861685532576, 3476.4263896457055, 4740.525947405266, 4893.486183950705, 5067.961067981092, 5092.821751238689, 5132.820351287724, 3800.7339486235974, 4264.211120942605, 4535.478419981711, 4591.51789062147, 4619.514950930137, 4791.47293797682, 5571.303587051619, 5573.721313934287, 5590.636608620467, 5595.468430499137, 3790.6019867913747, 3801.9239615207816, 3820.6884834674897, 3827.870778120461, 3833.731638785286, 4532.841350552715, 5396.568274538027, 5420.945790542123, 5704.600677952587, 5776.97111514444, 3448.6723761242642, 3858.479105320746, 3864.594217607167, 3865.632764887331, 3875.8449662013204, 3679.392900171495, 3916.1776027034643, 3921.6666583340166, 3921.9019524512114, 3929.646331830149, 3888.0, 3988.5840526613215, 4136.689748268892, 4385.342198670161, 4615.607673347803, 5389.541888939484, 5450.511672529584, 5604.8318550443555, 5619.7636519965545, 5630.155476678449, 1090.6128324444867, 1162.8604567451791, 1170.7599942011866, 1209.60687776473, 1238.1766125526603, 778.3578547698172, 822.5558198572824, 864.7794812322818, 866.2247288676599, 871.1767379826131, 4178.310578754532, 4667.906641867144, 4967.392119137482, 4971.6271279654175, 4980.8256711015665, 4511.300748384006, 5306.787728490895, 5400.677708585346, 5410.729463526808, 5446.291982042309, 4435.689501734848, 4926.118220840776, 4935.185694360391, 4966.329545511382, 4986.426560945538, 2535.4929014196864, 2703.715735025847, 2804.9383702978294, 2854.5860850177023, 2856.300206449433, 4462.754548499799, 5381.7978302909705, 5622.803201887914, 5644.407337229614, 5658.066419040894, 4600.2869555218995, 5172.482751724798, 5520.012795393655, 5522.779088836402, 5560.582956278215, 4554.726716397026, 4823.879403014883, 5068.4424713281505, 5531.8893622126525, 5614.326280846243, 4932.4327702314695, 5385.030694475032, 5400.324959380077, 5427.4301198373605, 5439.156930675752, 4809.78355973989, 5023.508661480236, 5072.5688316493515, 5147.738803993046, 5300.734963251899, 4665.510121437336, 5424.620276580601, 5615.92845187236, 5635.914711556587, 5848.502877255481, 3559.9644003559633, 3778.4130665120165, 3834.405667121601, 3837.982934522298, 3852.0, 5059.898802024012, 5614.775408983595, 5650.63270887391, 5670.914936275876, 5677.77288908439, 4417.470402250061, 4616.722996620214, 5145.867909060001, 5485.451454854483, 5495.835124946258, 4902.411710594752, 5418.02475554404, 5466.851961088216, 5510.366307874486, 5524.779008839762, 4125.731827431207, 5187.662801917863, 5376.919346209888, 5407.810281738169, 5409.215663728659, 4663.393758811333, 4950.401039454316, 5087.465816089405, 5479.178522578724, 5486.6982315973, 4609.78730209508, 4860.538248866352, 5263.421023687315, 5370.355557333145, 5401.6218864679095], "value": 3923.0352492387597, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [684.8048306232724, 717.9533330333525, 766.0, 792.8374683189946, 806.455642441352, 14482.855171448282, 15388.076715397085, 15396.923015384908, 15403.306851191695, 15412.3835046598, 13693.65765855854, 13799.44802207913, 13812.79280810786, 13822.377992990314, 13830.03189776716, 658.5094104891863, 679.0357692077262, 811.8335741173046, 828.9419740618144, 837.6649340263882, 826.8718348655968, 830.1532436914343, 838.6310023589632, 841.364769598952, 842.5787106446777, 875.5272153037353, 875.5884734174928, 875.6759998800453, 876.7676565710092, 877.4165180155189, 732.8314487667813, 868.7133246028859, 870.5734190246765, 871.5772850167664, 871.7079778274285, 627.5701144715839, 644.5584774429531, 646.7800947677765, 646.9256035555877, 647.3882181338637, 13727.519536816164, 14032.71934561303, 14067.507637232648, 14068.577942661737, 14930.402783888632, 14127.78808317876, 14278.0, 14404.855951440457, 14450.007979572329, 14569.635759106, 11395.54417823286, 11499.367534785584, 11523.366214858179, 11610.0, 11900.761984760256, 3782.281366540376, 3828.062124779419, 3861.8841434757, 3944.980275098606, 3975.9006024849323, 87849.0, 90664.54667726684, 92273.53863230642, 92541.0, 92557.99999999933, 10282.239948883273, 10654.840177397346, 10728.624498142528, 10943.616973405893, 11146.331220126818, 21323.893380532998, 21441.142354305965, 21669.24157654506, 21731.565368692853, 21759.347219583437, 7676.923230767621, 7694.461387702919, 7698.615069246515, 7833.843323133506, 7866.292033716879, 4334.804933777988, 4381.715188512737, 4416.668749843775, 4485.439320084989, 4580.610648094883, 3304.7851889627577, 3423.3495635829113, 3426.5716785401823, 3431.6396778337917, 3437.4500079987083, 617.8393617659441, 630.8805134062087, 635.6980434293673, 644.1851058411114, 651.4755621724463, 531.5732790562123, 542.5361316074778, 584.0450862839203, 627.0813258576208, 678.9966050169817, 7287.307705767944, 8431.0, 8569.914300856912, 8584.141585841491, 9084.137006984325, 546.6993153765413, 567.7870798450581, 587.8941790477755, 633.2464367402775, 653.9019147127968, 4246.660267178619, 4363.258246098171, 4411.911761764811, 4418.80115394808, 4486.685931984795, 77111.61444192744, 108439.0, 111511.44244278727, 119841.40079299721, 121648.0, 75337.0, 97102.0, 104838.0, 106813.46593266985, 111597.88402116037, 93419.0, 94211.58682619766, 95156.52421737846, 96249.0, 96458.51770741101, 7209.495335326476, 8326.417150799385, 8384.203500667427, 8556.229939305493, 8790.692325768567, 44367.0, 50528.74735626298, 60571.69714151401, 61855.6907215461, 62349.68825155845, 53790.46209537855, 65421.0, 66140.66929665321, 70124.29875701178, 72975.27024729685, 12412.193207441487, 12603.117781755189, 12783.680407989503, 13177.538786142439, 13177.604671859859, 13321.866781332063, 14312.57062288133, 14552.781708274173, 14873.92563037178, 15518.84481155174, 12499.93750031244, 13867.65330866696, 13932.651683707989, 14169.433222670978, 14410.783838242225, 13177.868221317663, 13850.376733047238, 14446.277686115442, 14752.778708319169, 14770.926145369205, 12314.630561083182, 13866.722665546835, 13874.722505549631, 14145.0, 14164.716705666035, 10286.691399258034, 10860.294080884718, 11599.0, 11709.76580468403, 11847.289162650268, 11544.365059921449, 11565.421728913689, 11567.248128871934, 11697.766044678889, 11719.941400292944], "value": 7385.605832321634, "unit": "score"}], "name": "dromaeo_css", "value": 7173.754066650842, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 113.0, 114.0, 116.0, 115.0, 113.0, 115.0, 113.0, 115.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 110.0, 109.0, 140.0, 110.0, 107.0, 108.0, 108.0, 138.0, 110.0], "value": 118.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 183.0, 183.0, 181.0, 181.0, 183.0, 182.0, 186.0, 182.0, 182.0], "value": 182.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 60.0, 89.0, 59.0, 59.0, 77.0, 57.0, 80.0, 57.0], "value": 67.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 92.0, 129.0, 101.0, 70.0, 68.0, 90.0, 68.0, 69.0, 90.0], "value": 86.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 89.0, 86.0, 134.0, 130.0, 90.0, 88.0, 89.0, 85.0], "value": 96.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 94.0, 94.0, 95.0, 95.0, 102.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 80.0, 81.0, 81.0, 81.0, 84.0, 85.0, 81.0, 81.0], "value": 81.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 51.0, 75.0, 89.0, 90.0, 89.0, 55.0, 55.0, 56.0], "value": 66.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 67.0, 64.0, 73.0, 71.0, 69.0, 67.0, 67.0, 70.0, 71.0], "value": 69.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 83.0, 81.0, 81.0, 80.0, 83.0, 96.0, 101.0, 87.0], "value": 85.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 140.0, 148.0, 152.0, 128.0, 147.0, 153.0, 141.0, 184.0, 136.0], "value": 147.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 164.0, 161.0, 165.0, 163.0, 162.0, 164.0, 161.0, 161.0], "value": 162.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 66.0, 65.0, 63.0, 64.0, 65.0, 66.0, 76.0], "value": 66.0, "unit": "score"}], "name": "kraken", "value": 1439.5999999999997, "alertThreshold": 2.0}]} 09:53:42 INFO - Return code: 0 09:53:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:53:42 INFO - # TBPL SUCCESS # 09:53:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:53:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:53:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:53:42 INFO - 2017-01-25 09:53:42.117 screenresolution[1656:9743] starting screenresolution argv=screenresolution get 09:53:42 INFO - 2017-01-25 09:53:42.121 screenresolution[1656:9743] Display 0: 1600x1200x32@60 09:53:42 INFO - 2017-01-25 09:53:42.127 screenresolution[1657:9746] starting screenresolution argv=screenresolution list 09:53:42 INFO - Available Modes on Display 0 09:53:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:53:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:53:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:53:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:53:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:53:42 INFO - Intel Iris: 09:53:42 INFO - Chipset Model: Intel Iris 09:53:42 INFO - Type: GPU 09:53:42 INFO - Bus: Built-In 09:53:42 INFO - VRAM (Dynamic, Max): 1536 MB 09:53:42 INFO - Vendor: Intel (0x8086) 09:53:42 INFO - Device ID: 0x0a2e 09:53:42 INFO - Revision ID: 0x0009 09:53:42 INFO - Displays: 09:53:42 INFO - Display: 09:53:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:53:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:53:42 INFO - Display Serial Number: 1600x1200 60 09:53:42 INFO - Main Display: Yes 09:53:42 INFO - Mirror: Off 09:53:42 INFO - Online: Yes 09:53:42 INFO - Rotation: Supported 09:53:42 INFO - Return code: 0 09:53:42 INFO - Running post-action listener: _resource_record_post_action 09:53:42 INFO - [mozharness: 2017-01-25 17:53:42.380740Z] Finished run-tests step (success) 09:53:42 INFO - Running post-run listener: _resource_record_post_run 09:53:42 INFO - Total resource usage - Wall time: 1201s; CPU: 53.0%; Read bytes: 37864448; Write bytes: 499064832; Read time: 245; Write time: 1584 09:53:42 INFO - TinderboxPrint: CPU usage
53.2% 09:53:42 INFO - TinderboxPrint: I/O read bytes / time
37,864,448 / 245 09:53:42 INFO - TinderboxPrint: I/O write bytes / time
499,064,832 / 1,584 09:53:42 INFO - TinderboxPrint: CPU idle
2,247.0 (46.8%) 09:53:42 INFO - TinderboxPrint: CPU system
958.2 (19.9%) 09:53:42 INFO - TinderboxPrint: CPU user
1,600.5 (33.3%) 09:53:42 INFO - TinderboxPrint: Swap in / out
229,126,144 / 0 09:53:42 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 185231872; Write bytes: 179250688; Read time: 16734; Write time: 395 09:53:42 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 36078592; Write bytes: 304364032; Read time: 231; Write time: 1140 09:53:42 INFO - Running post-run listener: _upload_blobber_files 09:53:42 INFO - Blob upload gear active. 09:53:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:53:42 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:53:42 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:53:42 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:53:43 INFO - (blobuploader) - INFO - Open directory for files ... 09:53:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:53:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:53:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:53:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:53:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:53:44 INFO - (blobuploader) - INFO - Done attempting. 09:53:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:53:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:53:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:53:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:53:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:53:45 INFO - (blobuploader) - INFO - Done attempting. 09:53:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:53:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:53:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:53:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:53:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:53:47 INFO - (blobuploader) - INFO - Done attempting. 09:53:47 INFO - (blobuploader) - INFO - Iteration through files over. 09:53:47 INFO - Return code: 0 09:53:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:53:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:53:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f422f67706a4ad2c4eff9fec3430e19b54a181e04b12e80b56314bebba28586e26dd40162b1f2d16d73833ca73ce0ee8389a040d829fbbca00da948e48de6aad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c6ac8c68f58c2ce8f786e8155381812f55a35c5189fcdae55fb49e7ceeca7705fa256b10f7316de2815e7552a9a7ee1fd1a9a9276c638831130c65798dd40331", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5320466992dc5f839da6ca6c8c26a7580cf6759719802f6d110f62b72fa431783412c4f8ad2f2ba6725e74649029c7ef4aea7a856ff23632cec785dedcb86021"} 09:53:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:53:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:53:47 INFO - Contents: 09:53:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f422f67706a4ad2c4eff9fec3430e19b54a181e04b12e80b56314bebba28586e26dd40162b1f2d16d73833ca73ce0ee8389a040d829fbbca00da948e48de6aad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c6ac8c68f58c2ce8f786e8155381812f55a35c5189fcdae55fb49e7ceeca7705fa256b10f7316de2815e7552a9a7ee1fd1a9a9276c638831130c65798dd40331", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5320466992dc5f839da6ca6c8c26a7580cf6759719802f6d110f62b72fa431783412c4f8ad2f2ba6725e74649029c7ef4aea7a856ff23632cec785dedcb86021"} 09:53:47 INFO - Running post-run listener: copy_logs_to_upload_dir 09:53:47 INFO - Copying logs to upload dir... 09:53:47 INFO - mkdir: /builds/slave/test/build/upload/logs 09:53:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.779956 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-01-25 09:53:47.967162) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-25 09:53:47.971666) ========= 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.6q8C4gmqrL/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.biq7lWnfuY/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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-Beta/sha512/f422f67706a4ad2c4eff9fec3430e19b54a181e04b12e80b56314bebba28586e26dd40162b1f2d16d73833ca73ce0ee8389a040d829fbbca00da948e48de6aad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c6ac8c68f58c2ce8f786e8155381812f55a35c5189fcdae55fb49e7ceeca7705fa256b10f7316de2815e7552a9a7ee1fd1a9a9276c638831130c65798dd40331", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5320466992dc5f839da6ca6c8c26a7580cf6759719802f6d110f62b72fa431783412c4f8ad2f2ba6725e74649029c7ef4aea7a856ff23632cec785dedcb86021"} build_url:https://queue.taskcluster.net/v1/task/JyHMYc2iQHaEWAgEDSkiJQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011283 build_url: 'https://queue.taskcluster.net/v1/task/JyHMYc2iQHaEWAgEDSkiJQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f422f67706a4ad2c4eff9fec3430e19b54a181e04b12e80b56314bebba28586e26dd40162b1f2d16d73833ca73ce0ee8389a040d829fbbca00da948e48de6aad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c6ac8c68f58c2ce8f786e8155381812f55a35c5189fcdae55fb49e7ceeca7705fa256b10f7316de2815e7552a9a7ee1fd1a9a9276c638831130c65798dd40331", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5320466992dc5f839da6ca6c8c26a7580cf6759719802f6d110f62b72fa431783412c4f8ad2f2ba6725e74649029c7ef4aea7a856ff23632cec785dedcb86021"}' ========= master_lag: 0.21 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-25 09:53:48.192104) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 09:53:48.193161) ========= 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.6q8C4gmqrL/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.biq7lWnfuY/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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.005409 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 09:53:48.219310) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-25 09:53:48.219589) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-25 09:53:48.219872) ========= ========= Total master_lag: 0.36 =========