builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0060 starttime: 1490653264.26 results: success (0) buildid: 20170327134621 builduid: 6640301eadbf45538204e053225c1391 revision: d9da8b1ac1fc06b02a8b04ff4ef28290fc980374 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 15:21:04.265075) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 15:21:04.265511) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 15:21:04.265842) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 15:21:04.292075) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 15:21:04.292417) ========= 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.3gIxzuiGX4/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.OrwSIksOOv/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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-03-27 15:21:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.40M=0.001s 2017-03-27 15:21:04 (8.40 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.099846 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 15:21:04.416167) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 15:21:04.416477) ========= 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.3gIxzuiGX4/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.OrwSIksOOv/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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.034323 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 15:21:04.474824) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-27 15:21:04.475173) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d9da8b1ac1fc06b02a8b04ff4ef28290fc980374 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d9da8b1ac1fc06b02a8b04ff4ef28290fc980374 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3gIxzuiGX4/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.OrwSIksOOv/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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-03-27 15:21:04,584 truncating revision to first 12 chars 2017-03-27 15:21:04,584 Setting DEBUG logging. 2017-03-27 15:21:04,584 attempt 1/10 2017-03-27 15:21:04,584 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d9da8b1ac1fc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-27 15:21:05,628 unpacking tar archive at: mozilla-aurora-d9da8b1ac1fc/testing/mozharness/ program finished with exit code 0 elapsedTime=1.318964 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-27 15:21:05.817112) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 15:21:05.817429) ========= script_repo_revision: d9da8b1ac1fc06b02a8b04ff4ef28290fc980374 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 15:21:05.817830) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 15:21:05.818092) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 15:21:05.838009) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-27 15:21:05.838374) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3gIxzuiGX4/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.OrwSIksOOv/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 15:21:06 INFO - MultiFileLogger online at 20170327 15:21:06 in /builds/slave/test 15:21:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 15:21:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:21:06 INFO - {'append_to_log': False, 15:21:06 INFO - 'base_work_dir': '/builds/slave/test', 15:21:06 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:21:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:21:06 INFO - 'branch': 'Mozilla-Aurora', 15:21:06 INFO - 'buildbot_json_path': 'buildprops.json', 15:21:06 INFO - 'config_files': ('talos/mac_config.py',), 15:21:06 INFO - 'default_actions': ('clobber', 15:21:06 INFO - 'read-buildbot-config', 15:21:06 INFO - 'download-and-extract', 15:21:06 INFO - 'populate-webroot', 15:21:06 INFO - 'create-virtualenv', 15:21:06 INFO - 'install', 15:21:06 INFO - 'run-tests'), 15:21:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:21:06 INFO - 'download_minidump_stackwalk': True, 15:21:06 INFO - 'download_symbols': 'ondemand', 15:21:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:21:06 INFO - 'tooltool.py': '/tools/tooltool.py', 15:21:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:21:06 INFO - '/tools/misc-python/virtualenv.py')}, 15:21:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:21:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:21:06 INFO - 'gecko_profile': False, 15:21:06 INFO - 'gecko_profile_interval': 0, 15:21:06 INFO - 'installer_path': 'installer.exe', 15:21:06 INFO - 'log_level': 'info', 15:21:06 INFO - 'log_name': 'talos', 15:21:06 INFO - 'log_to_console': True, 15:21:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:21:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:21:06 INFO - 'opt_config_files': (), 15:21:06 INFO - 'pip_index': False, 15:21:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:21:06 INFO - 'cmd': ('bash', 15:21:06 INFO - '-c', 15:21:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:21:06 INFO - 'enabled': True, 15:21:06 INFO - 'halt_on_failure': False, 15:21:06 INFO - 'name': 'check_screen_resolution'},), 15:21:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:21:06 INFO - 'cmd': ('bash', 15:21:06 INFO - '-c', 15:21:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:21:06 INFO - 'enabled': True, 15:21:06 INFO - 'halt_on_failure': False, 15:21:06 INFO - 'name': 'check_screen_resolution'},), 15:21:06 INFO - 'run_cmd_checks_enabled': True, 15:21:06 INFO - 'suite': 'dromaeojs-e10s', 15:21:06 INFO - 'system_bits': '32', 15:21:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:21:06 INFO - 'title': 't-yosemite-r7-0060', 15:21:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:21:06 INFO - 'use_talos_json': True, 15:21:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:21:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:21:06 INFO - 'work_dir': 'build'} 15:21:06 INFO - [mozharness: 2017-03-27 22:21:06.054373Z] Running clobber step. 15:21:06 INFO - Running pre-action listener: _resource_record_pre_action 15:21:06 INFO - Running main action method: clobber 15:21:06 INFO - rmtree: /builds/slave/test/build 15:21:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:21:08 INFO - Running post-action listener: _resource_record_post_action 15:21:08 INFO - [mozharness: 2017-03-27 22:21:08.484847Z] Finished clobber step (success) 15:21:08 INFO - [mozharness: 2017-03-27 22:21:08.484990Z] Running read-buildbot-config step. 15:21:08 INFO - Running pre-action listener: _resource_record_pre_action 15:21:08 INFO - Running main action method: read_buildbot_config 15:21:08 INFO - Using buildbot properties: 15:21:08 INFO - { 15:21:08 INFO - "project": "", 15:21:08 INFO - "product": "firefox", 15:21:08 INFO - "script_repo_revision": "production", 15:21:08 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 15:21:08 INFO - "repository": "", 15:21:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 15:21:08 INFO - "buildid": "20170327134621", 15:21:08 INFO - "pgo_build": "False", 15:21:08 INFO - "basedir": "/builds/slave/test", 15:21:08 INFO - "buildnumber": 89, 15:21:08 INFO - "slavename": "t-yosemite-r7-0060", 15:21:08 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 15:21:08 INFO - "platform": "yosemite_r7", 15:21:08 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 15:21:08 INFO - "branch": "mozilla-aurora", 15:21:08 INFO - "repo_path": "releases/mozilla-aurora", 15:21:08 INFO - "revision": "d9da8b1ac1fc06b02a8b04ff4ef28290fc980374", 15:21:08 INFO - "stage_platform": "macosx64", 15:21:08 INFO - "builduid": "6640301eadbf45538204e053225c1391", 15:21:08 INFO - "slavebuilddir": "test" 15:21:08 INFO - } 15:21:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/X6ybCyeEQoaxwTyhMSJGUA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 15:21:08 INFO - Running post-action listener: _resource_record_post_action 15:21:08 INFO - [mozharness: 2017-03-27 22:21:08.488457Z] Finished read-buildbot-config step (success) 15:21:08 INFO - [mozharness: 2017-03-27 22:21:08.488569Z] Running download-and-extract step. 15:21:08 INFO - Running pre-action listener: _resource_record_pre_action 15:21:08 INFO - Running main action method: download_and_extract 15:21:08 INFO - mkdir: /builds/slave/test/build/tests 15:21:08 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')]} 15:21:08 INFO - trying https://queue.taskcluster.net/v1/task/X6ybCyeEQoaxwTyhMSJGUA/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 15:21:08 INFO - Downloading https://queue.taskcluster.net/v1/task/X6ybCyeEQoaxwTyhMSJGUA/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 15:21:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X6ybCyeEQoaxwTyhMSJGUA/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 15:21:09 INFO - Downloaded 1199 bytes. 15:21:09 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 15:21:09 INFO - Using the following test package requirements: 15:21:09 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 15:21:09 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:21:09 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 15:21:09 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:21:09 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 15:21:09 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:21:09 INFO - u'jsshell-mac.zip'], 15:21:09 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:21:09 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 15:21:09 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 15:21:09 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:21:09 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 15:21:09 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:21:09 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 15:21:09 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:21:09 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 15:21:09 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:21:09 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 15:21:09 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 15:21:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X6ybCyeEQoaxwTyhMSJGUA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 15:21:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X6ybCyeEQoaxwTyhMSJGUA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:21:09 INFO - Fetch https://queue.taskcluster.net/v1/task/X6ybCyeEQoaxwTyhMSJGUA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 15:21:11 INFO - Expected file size: 51785597 15:21:11 INFO - Obtained file size: 51785597 15:21:27 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 15:21:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X6ybCyeEQoaxwTyhMSJGUA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 15:21:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X6ybCyeEQoaxwTyhMSJGUA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:21:27 INFO - Fetch https://queue.taskcluster.net/v1/task/X6ybCyeEQoaxwTyhMSJGUA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 15:21:29 INFO - Expected file size: 51785597 15:21:29 INFO - Obtained file size: 51785597 15:21:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X6ybCyeEQoaxwTyhMSJGUA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 15:21:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X6ybCyeEQoaxwTyhMSJGUA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 15:21:44 INFO - Fetch https://queue.taskcluster.net/v1/task/X6ybCyeEQoaxwTyhMSJGUA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 15:21:46 INFO - Expected file size: 14404438 15:21:46 INFO - Obtained file size: 14404438 15:21:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:21:46 INFO - trying https://queue.taskcluster.net/v1/task/X6ybCyeEQoaxwTyhMSJGUA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 15:21:46 INFO - Downloading https://queue.taskcluster.net/v1/task/X6ybCyeEQoaxwTyhMSJGUA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 15:21:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X6ybCyeEQoaxwTyhMSJGUA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 15:21:48 INFO - Downloaded 74671316 bytes. 15:21:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/X6ybCyeEQoaxwTyhMSJGUA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 15:21:48 INFO - mkdir: /builds/slave/test/properties 15:21:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:21:48 INFO - Writing to file /builds/slave/test/properties/build_url 15:21:48 INFO - Contents: 15:21:48 INFO - build_url:https://queue.taskcluster.net/v1/task/X6ybCyeEQoaxwTyhMSJGUA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 15:21:49 INFO - Running post-action listener: _resource_record_post_action 15:21:49 INFO - Running post-action listener: set_extra_try_arguments 15:21:49 INFO - [mozharness: 2017-03-27 22:21:49.328878Z] Finished download-and-extract step (success) 15:21:49 INFO - [mozharness: 2017-03-27 22:21:49.329016Z] Running populate-webroot step. 15:21:49 INFO - Running pre-action listener: _resource_record_pre_action 15:21:49 INFO - Running main action method: populate_webroot 15:21:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:21:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:21:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:21:49 INFO - '--tppagecycles', 15:21:49 INFO - '7'], 15:21:49 INFO - 'tests': ['tsvgm']}}, 15:21:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:21:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:21:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:21:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:21:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:21:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:21:49 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:21:49 INFO - 'talos_options': ['--disable-e10s'], 15:21:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:21:49 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:21:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:21:49 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:21:49 INFO - 'talos_options': ['--disable-e10s'], 15:21:49 INFO - 'tests': ['damp', 'tps']}, 15:21:49 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:21:49 INFO - 'tests': ['damp', 'tps']}, 15:21:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:21:49 INFO - 'tests': ['dromaeo_dom']}, 15:21:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:21:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:21:49 INFO - 'tests': ['basic_compositor_video']}, 15:21:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:21:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:21:49 INFO - 'tests': ['a11yr', 15:21:49 INFO - 'ts_paint', 15:21:49 INFO - 'tpaint', 15:21:49 INFO - 'sessionrestore', 15:21:49 INFO - 'sessionrestore_no_auto_restore', 15:21:49 INFO - 'tabpaint']}, 15:21:49 INFO - 'other-e10s': {'tests': ['a11yr', 15:21:49 INFO - 'ts_paint', 15:21:49 INFO - 'tpaint', 15:21:49 INFO - 'sessionrestore', 15:21:49 INFO - 'sessionrestore_no_auto_restore', 15:21:49 INFO - 'tabpaint']}, 15:21:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:21:49 INFO - 'ts_paint', 15:21:49 INFO - 'tpaint', 15:21:49 INFO - 'sessionrestore', 15:21:49 INFO - 'sessionrestore_no_auto_restore', 15:21:49 INFO - 'tabpaint']}, 15:21:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:21:49 INFO - 'ts_paint', 15:21:49 INFO - 'tpaint', 15:21:49 INFO - 'sessionrestore', 15:21:49 INFO - 'sessionrestore_no_auto_restore', 15:21:49 INFO - 'tabpaint']}, 15:21:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:21:49 INFO - 'tests': ['a11yr', 15:21:49 INFO - 'ts_paint', 15:21:49 INFO - 'tpaint', 15:21:49 INFO - 'sessionrestore', 15:21:49 INFO - 'sessionrestore_no_auto_restore', 15:21:49 INFO - 'tabpaint']}, 15:21:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:21:49 INFO - 'tests': ['a11yr', 15:21:49 INFO - 'ts_paint', 15:21:49 INFO - 'tpaint', 15:21:49 INFO - 'sessionrestore', 15:21:49 INFO - 'sessionrestore_no_auto_restore', 15:21:49 INFO - 'tabpaint']}, 15:21:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:21:49 INFO - 'tests': ['tsvgx', 15:21:49 INFO - 'tsvgr_opacity', 15:21:49 INFO - 'tart', 15:21:49 INFO - 'tscrollx', 15:21:49 INFO - 'cart', 15:21:49 INFO - 'tsvg_static']}, 15:21:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:21:49 INFO - 'tsvgr_opacity', 15:21:49 INFO - 'tart', 15:21:49 INFO - 'tscrollx', 15:21:49 INFO - 'cart', 15:21:49 INFO - 'tsvg_static']}, 15:21:49 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:21:49 INFO - 'talos_options': ['--disable-e10s'], 15:21:49 INFO - 'tests': ['tp5o']}, 15:21:49 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:21:49 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:21:49 INFO - 'talos_options': ['--disable-e10s', 15:21:49 INFO - '--xperf_path', 15:21:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:21:49 INFO - 'tests': ['tp5n']}, 15:21:49 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:21:49 INFO - 'talos_options': ['--xperf_path', 15:21:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:21:49 INFO - 'tests': ['tp5n']}}, 15:21:49 INFO - 'talos.zip': {'path': '', 15:21:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:21:49 INFO - Running post-action listener: _resource_record_post_action 15:21:49 INFO - [mozharness: 2017-03-27 22:21:49.347795Z] Finished populate-webroot step (success) 15:21:49 INFO - [mozharness: 2017-03-27 22:21:49.347910Z] Running create-virtualenv step. 15:21:49 INFO - Running pre-action listener: _resource_record_pre_action 15:21:49 INFO - Running main action method: create_virtualenv 15:21:49 INFO - Creating virtualenv /builds/slave/test/build/venv 15:21:49 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 15:21:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:21:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:21:49 INFO - Using real prefix '/tools/python27' 15:21:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:21:50 INFO - Installing distribute.............................................................................................................................................................................................done. 15:21:53 INFO - Installing pip.................done. 15:21:53 INFO - Return code: 0 15:21:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:21:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:21:53 INFO - Reading from file tmpfile_stdout 15:21:53 INFO - Output received: 15:21:53 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) 15:21:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:21:53 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')]} 15:21:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:21:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:21:53 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')]} 15:21:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:21:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:21:53 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 0x1040bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e08497e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e98d30>, '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 0x103dbfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e8dbe8>, '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.3gIxzuiGX4/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.OrwSIksOOv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 15:21:53 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 15:21:53 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 15:21:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3gIxzuiGX4/Render', 15:21:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:21:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:21:53 INFO - 'HOME': '/Users/cltbld', 15:21:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:21:53 INFO - 'LOGNAME': 'cltbld', 15:21:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:21:53 INFO - 'MOZ_NO_REMOTE': '1', 15:21:53 INFO - 'NO_EM_RESTART': '1', 15:21:53 INFO - 'PAGER': '/bin/cat', 15:21:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:21:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:21:53 INFO - 'PWD': '/builds/slave/test', 15:21:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:21:53 INFO - 'SHELL': '/bin/bash', 15:21:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OrwSIksOOv/Listeners', 15:21:53 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 15:21:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:21:53 INFO - 'USER': 'cltbld', 15:21:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:21:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:21:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:21:53 INFO - 'XPC_FLAGS': '0x0', 15:21:53 INFO - 'XPC_SERVICE_NAME': '0', 15:21:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:21:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:21:53 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 15:21:53 INFO - Cleaning up... 15:21:53 INFO - Return code: 0 15:21:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:21:53 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')]} 15:21:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:21:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:21:53 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')]} 15:21:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:21:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:21:53 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 0x1040bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e08497e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e98d30>, '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 0x103dbfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e8dbe8>, '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.3gIxzuiGX4/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.OrwSIksOOv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 15:21:53 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 15:21:53 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 15:21:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3gIxzuiGX4/Render', 15:21:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:21:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:21:53 INFO - 'HOME': '/Users/cltbld', 15:21:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:21:53 INFO - 'LOGNAME': 'cltbld', 15:21:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:21:53 INFO - 'MOZ_NO_REMOTE': '1', 15:21:53 INFO - 'NO_EM_RESTART': '1', 15:21:53 INFO - 'PAGER': '/bin/cat', 15:21:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:21:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:21:53 INFO - 'PWD': '/builds/slave/test', 15:21:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:21:53 INFO - 'SHELL': '/bin/bash', 15:21:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OrwSIksOOv/Listeners', 15:21:53 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 15:21:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:21:53 INFO - 'USER': 'cltbld', 15:21:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:21:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:21:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:21:53 INFO - 'XPC_FLAGS': '0x0', 15:21:53 INFO - 'XPC_SERVICE_NAME': '0', 15:21:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:21:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:21:53 INFO - Downloading/unpacking psutil>=3.1.1 15:21:53 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 15:21:53 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 15:21:53 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 15:21:53 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 15:21:53 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 15:21:53 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 15:21:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:21:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:21:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:21:57 INFO - Installing collected packages: psutil 15:21:57 INFO - Running setup.py install for psutil 15:21:57 INFO - building 'psutil._psutil_osx' extension 15:21:57 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 15:21:58 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 15:21:58 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 15:21:58 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 15:21:58 INFO - building 'psutil._psutil_posix' extension 15:21:58 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 15:21:58 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:21:58 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:21:58 INFO - ^ 15:21:58 INFO - 1 warning generated. 15:21:58 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 15:21:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:21:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:21:58 INFO - Successfully installed psutil 15:21:58 INFO - Cleaning up... 15:21:58 INFO - Return code: 0 15:21:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:21:58 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')]} 15:21:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:21:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:21:58 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')]} 15:21:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:21:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:21:58 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 0x1040bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e08497e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e98d30>, '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 0x103dbfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e8dbe8>, '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.3gIxzuiGX4/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.OrwSIksOOv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 15:21:58 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 15:21:58 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 15:21:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3gIxzuiGX4/Render', 15:21:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:21:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:21:58 INFO - 'HOME': '/Users/cltbld', 15:21:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:21:58 INFO - 'LOGNAME': 'cltbld', 15:21:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:21:58 INFO - 'MOZ_NO_REMOTE': '1', 15:21:58 INFO - 'NO_EM_RESTART': '1', 15:21:58 INFO - 'PAGER': '/bin/cat', 15:21:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:21:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:21:58 INFO - 'PWD': '/builds/slave/test', 15:21:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:21:58 INFO - 'SHELL': '/bin/bash', 15:21:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OrwSIksOOv/Listeners', 15:21:58 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 15:21:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:21:58 INFO - 'USER': 'cltbld', 15:21:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:21:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:21:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:21:58 INFO - 'XPC_FLAGS': '0x0', 15:21:58 INFO - 'XPC_SERVICE_NAME': '0', 15:21:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:21:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:21:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:21:58 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 15:21:58 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 15:21:58 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 15:21:58 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 15:21:58 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 15:21:58 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 15:22:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:22:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:22:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:22:01 INFO - Installing collected packages: mozsystemmonitor 15:22:01 INFO - Running setup.py install for mozsystemmonitor 15:22:01 INFO - Successfully installed mozsystemmonitor 15:22:01 INFO - Cleaning up... 15:22:01 INFO - Return code: 0 15:22:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:22:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:22:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22: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')]} 15:22:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:02 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 0x1040bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e08497e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e98d30>, '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 0x103dbfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e8dbe8>, '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.3gIxzuiGX4/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.OrwSIksOOv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 15:22:02 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 15:22:02 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 15:22:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3gIxzuiGX4/Render', 15:22:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:22:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:22:02 INFO - 'HOME': '/Users/cltbld', 15:22:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:22:02 INFO - 'LOGNAME': 'cltbld', 15:22:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:02 INFO - 'MOZ_NO_REMOTE': '1', 15:22:02 INFO - 'NO_EM_RESTART': '1', 15:22:02 INFO - 'PAGER': '/bin/cat', 15:22:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:22:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:02 INFO - 'PWD': '/builds/slave/test', 15:22:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:22:02 INFO - 'SHELL': '/bin/bash', 15:22:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OrwSIksOOv/Listeners', 15:22:02 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 15:22:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:22:02 INFO - 'USER': 'cltbld', 15:22:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:22:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:22:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:02 INFO - 'XPC_FLAGS': '0x0', 15:22:02 INFO - 'XPC_SERVICE_NAME': '0', 15:22:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:22:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:02 INFO - Downloading/unpacking jsonschema==2.5.1 15:22:02 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 15:22:02 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 15:22:02 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 15:22:02 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 15:22:02 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 15:22:02 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 15:22:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:22:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:22:05 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 15:22:05 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 15:22:05 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 15:22:05 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 15:22:05 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 15:22:05 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 15:22:06 INFO - Downloading functools32-3.2.3-2.tar.gz 15:22:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:22:06 INFO - warning: no files found matching '*.txt' 15:22:06 INFO - no previously-included directories found matching 'build' 15:22:06 INFO - no previously-included directories found matching 'dist' 15:22:06 INFO - no previously-included directories found matching '.git*' 15:22:06 INFO - Installing collected packages: jsonschema, functools32 15:22:06 INFO - Running setup.py install for functools32 15:22:06 INFO - warning: no files found matching '*.txt' 15:22:06 INFO - no previously-included directories found matching 'build' 15:22:06 INFO - no previously-included directories found matching 'dist' 15:22:06 INFO - no previously-included directories found matching '.git*' 15:22:06 INFO - Successfully installed jsonschema functools32 15:22:06 INFO - Cleaning up... 15:22:06 INFO - Return code: 0 15:22:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:22:06 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')]} 15:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:06 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')]} 15:22:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:06 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 0x1040bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e08497e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e98d30>, '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 0x103dbfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e8dbe8>, '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.3gIxzuiGX4/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.OrwSIksOOv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 15:22:06 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 15:22:06 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 15:22:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3gIxzuiGX4/Render', 15:22:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:22:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:22:06 INFO - 'HOME': '/Users/cltbld', 15:22:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:22:06 INFO - 'LOGNAME': 'cltbld', 15:22:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:06 INFO - 'MOZ_NO_REMOTE': '1', 15:22:06 INFO - 'NO_EM_RESTART': '1', 15:22:06 INFO - 'PAGER': '/bin/cat', 15:22:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:22:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:06 INFO - 'PWD': '/builds/slave/test', 15:22:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:22:06 INFO - 'SHELL': '/bin/bash', 15:22:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OrwSIksOOv/Listeners', 15:22:06 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 15:22:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:22:06 INFO - 'USER': 'cltbld', 15:22:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:22:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:22:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:06 INFO - 'XPC_FLAGS': '0x0', 15:22:06 INFO - 'XPC_SERVICE_NAME': '0', 15:22:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:22:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:22:07 INFO - Cleaning up... 15:22:07 INFO - Return code: 0 15:22:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:22:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:22:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:22:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:07 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 0x1040bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e08497e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e98d30>, '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 0x103dbfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e8dbe8>, '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.3gIxzuiGX4/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.OrwSIksOOv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 15:22:07 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 15:22:07 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 15:22:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3gIxzuiGX4/Render', 15:22:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:22:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:22:07 INFO - 'HOME': '/Users/cltbld', 15:22:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:22:07 INFO - 'LOGNAME': 'cltbld', 15:22:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:07 INFO - 'MOZ_NO_REMOTE': '1', 15:22:07 INFO - 'NO_EM_RESTART': '1', 15:22:07 INFO - 'PAGER': '/bin/cat', 15:22:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:22:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:07 INFO - 'PWD': '/builds/slave/test', 15:22:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:22:07 INFO - 'SHELL': '/bin/bash', 15:22:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OrwSIksOOv/Listeners', 15:22:07 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 15:22:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:22:07 INFO - 'USER': 'cltbld', 15:22:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:22:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:22:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:07 INFO - 'XPC_FLAGS': '0x0', 15:22:07 INFO - 'XPC_SERVICE_NAME': '0', 15:22:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:22:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:07 INFO - Downloading/unpacking blobuploader==1.2.4 15:22:07 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 15:22:07 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 15:22:07 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 15:22:07 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 15:22:07 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 15:22:07 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 15:22:09 INFO - Downloading blobuploader-1.2.4.tar.gz 15:22:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:22:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:22:10 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 15:22:10 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 15:22:10 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 15:22:10 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 15:22:10 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 15:22:10 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 15:22:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:22:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:22:10 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 15:22:10 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 15:22:10 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 15:22:10 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 15:22:10 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 15:22:10 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 15:22:11 INFO - Downloading docopt-0.6.1.tar.gz 15:22:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:22:11 INFO - Installing collected packages: blobuploader, requests, docopt 15:22:11 INFO - Running setup.py install for blobuploader 15:22:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:22:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:22:11 INFO - Running setup.py install for requests 15:22:11 INFO - Running setup.py install for docopt 15:22:12 INFO - Successfully installed blobuploader requests docopt 15:22:12 INFO - Cleaning up... 15:22:12 INFO - Return code: 0 15:22:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:22: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')]} 15:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22: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')]} 15:22:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:12 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 0x1040bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e08497e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e98d30>, '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 0x103dbfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e8dbe8>, '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.3gIxzuiGX4/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.OrwSIksOOv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 15:22:12 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 15:22:12 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 15:22:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3gIxzuiGX4/Render', 15:22:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:22:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:22:12 INFO - 'HOME': '/Users/cltbld', 15:22:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:22:12 INFO - 'LOGNAME': 'cltbld', 15:22:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:12 INFO - 'MOZ_NO_REMOTE': '1', 15:22:12 INFO - 'NO_EM_RESTART': '1', 15:22:12 INFO - 'PAGER': '/bin/cat', 15:22:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:22:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:12 INFO - 'PWD': '/builds/slave/test', 15:22:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:22:12 INFO - 'SHELL': '/bin/bash', 15:22:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OrwSIksOOv/Listeners', 15:22:12 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 15:22:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:22:12 INFO - 'USER': 'cltbld', 15:22:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:22:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:22:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:12 INFO - 'XPC_FLAGS': '0x0', 15:22:12 INFO - 'XPC_SERVICE_NAME': '0', 15:22:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:22:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:22:12 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-SERdHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:22:12 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-U_y2Ik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:22:12 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-I0hlbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:22:12 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-EGRsCB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:22:12 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-7p5hoB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:22:12 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-bTYifO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:22:13 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-2XJNkf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:22:13 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-dOVaE_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:22:13 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-gJhIyV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:22:13 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-azGqH8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:22:13 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-nyRpug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:22:13 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-_HmwRM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:22:13 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-Wu0uuE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:22:13 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-Fe2Ex8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:22:13 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-xAZSwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:22:13 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-eFKn7Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:22:14 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-w3VkEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:22:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:22:14 INFO - Running setup.py install for manifestparser 15:22:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:22:14 INFO - Running setup.py install for mozcrash 15:22:14 INFO - Running setup.py install for mozdebug 15:22:14 INFO - Running setup.py install for mozdevice 15:22:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:22:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:22:14 INFO - Running setup.py install for mozfile 15:22:14 INFO - Running setup.py install for mozhttpd 15:22:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:22:15 INFO - Running setup.py install for mozinfo 15:22:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:22:15 INFO - Running setup.py install for mozInstall 15:22:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:22:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:22:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:22:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:22:15 INFO - Running setup.py install for mozleak 15:22:15 INFO - Running setup.py install for mozlog 15:22:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:22:15 INFO - Running setup.py install for moznetwork 15:22:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:22:15 INFO - Running setup.py install for mozprocess 15:22:15 INFO - Running setup.py install for mozprofile 15:22:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:22:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:22:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:22:16 INFO - Running setup.py install for mozrunner 15:22:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:22:16 INFO - Running setup.py install for mozscreenshot 15:22:16 INFO - Running setup.py install for moztest 15:22:16 INFO - Running setup.py install for mozversion 15:22:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:22:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:22:16 INFO - Cleaning up... 15:22:16 INFO - Return code: 0 15:22:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:22:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:22:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:16 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 0x1040bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e08497e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e98d30>, '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 0x103dbfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e8dbe8>, '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.3gIxzuiGX4/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.OrwSIksOOv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 15:22:16 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 15:22:16 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 15:22:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3gIxzuiGX4/Render', 15:22:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:22:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:22:16 INFO - 'HOME': '/Users/cltbld', 15:22:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:22:16 INFO - 'LOGNAME': 'cltbld', 15:22:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:16 INFO - 'MOZ_NO_REMOTE': '1', 15:22:16 INFO - 'NO_EM_RESTART': '1', 15:22:16 INFO - 'PAGER': '/bin/cat', 15:22:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:22:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:16 INFO - 'PWD': '/builds/slave/test', 15:22:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:22:16 INFO - 'SHELL': '/bin/bash', 15:22:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OrwSIksOOv/Listeners', 15:22:16 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 15:22:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:22:16 INFO - 'USER': 'cltbld', 15:22:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:22:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:22:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:16 INFO - 'XPC_FLAGS': '0x0', 15:22:16 INFO - 'XPC_SERVICE_NAME': '0', 15:22:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:22:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:22:17 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-IaMbm4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:22:17 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)) 15:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:22:17 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-d6HTzH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:22:17 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)) 15:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:22:17 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-UsfLoI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:22:17 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)) 15:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:22:17 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-RLvZL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:22:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 15:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:22:17 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-g9sAIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:22:17 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)) 15:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:22:17 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-VABvU0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:22:17 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)) 15:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:22:17 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-3LQr2b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:22:17 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)) 15:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:22:17 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-WYZKDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:22:17 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)) 15:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:22:17 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-KjFV7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:22:18 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)) 15:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:22:18 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-TGH1Pm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:22:18 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)) 15:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:22:18 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-KKCD9v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:22:18 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)) 15:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:22:18 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-k78si1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:22:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 15:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:22:18 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-mUvLjp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:22:18 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)) 15:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:22:18 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-er4Ebq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:22:18 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)) 15:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:22:18 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-bzQqB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:22:18 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)) 15:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:22:18 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-gtAPQV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:22:18 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)) 15:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:22:18 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-AQIlEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:22:18 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)) 15:22:18 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)) 15:22:18 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)) 15:22:18 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)) 15:22:18 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:22:18 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:22:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:22:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:22:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:22:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:22:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:22:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:22:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:22:21 INFO - Downloading blessings-1.6.tar.gz 15:22:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:22:21 INFO - Installing collected packages: blessings 15:22:21 INFO - Running setup.py install for blessings 15:22:21 INFO - Successfully installed blessings 15:22:21 INFO - Cleaning up... 15:22:21 INFO - Return code: 0 15:22:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:22:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:22:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:22:22 INFO - Reading from file tmpfile_stdout 15:22:22 INFO - Current package versions: 15:22:22 INFO - blessings == 1.6 15:22:22 INFO - blobuploader == 1.2.4 15:22:22 INFO - docopt == 0.6.1 15:22:22 INFO - functools32 == 3.2.3-2 15:22:22 INFO - jsonschema == 2.5.1 15:22:22 INFO - manifestparser == 1.1 15:22:22 INFO - mozInstall == 1.12 15:22:22 INFO - mozcrash == 1.0 15:22:22 INFO - mozdebug == 0.1 15:22:22 INFO - mozdevice == 0.49 15:22:22 INFO - mozfile == 1.2 15:22:22 INFO - mozhttpd == 0.7 15:22:22 INFO - mozinfo == 0.9 15:22:22 INFO - mozleak == 0.1 15:22:22 INFO - mozlog == 3.4 15:22:22 INFO - moznetwork == 0.27 15:22:22 INFO - mozprocess == 0.25 15:22:22 INFO - mozprofile == 0.28 15:22:22 INFO - mozrunner == 6.13 15:22:22 INFO - mozscreenshot == 0.1 15:22:22 INFO - mozsystemmonitor == 0.3 15:22:22 INFO - moztest == 0.8 15:22:22 INFO - mozversion == 1.4 15:22:22 INFO - psutil == 3.1.1 15:22:22 INFO - requests == 1.2.3 15:22:22 INFO - wsgiref == 0.1.2 15:22:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:22:22 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')]} 15:22:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:22 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')]} 15:22:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:22 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 0x1040bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e08497e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e98d30>, '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 0x103dbfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e8dbe8>, '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.3gIxzuiGX4/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.OrwSIksOOv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 15:22:22 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 15:22:22 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 15:22:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3gIxzuiGX4/Render', 15:22:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:22:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:22:22 INFO - 'HOME': '/Users/cltbld', 15:22:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:22:22 INFO - 'LOGNAME': 'cltbld', 15:22:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:22 INFO - 'MOZ_NO_REMOTE': '1', 15:22:22 INFO - 'NO_EM_RESTART': '1', 15:22:22 INFO - 'PAGER': '/bin/cat', 15:22:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:22:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:22 INFO - 'PWD': '/builds/slave/test', 15:22:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:22:22 INFO - 'SHELL': '/bin/bash', 15:22:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OrwSIksOOv/Listeners', 15:22:22 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 15:22:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:22:22 INFO - 'USER': 'cltbld', 15:22:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:22:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:22:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:22 INFO - 'XPC_FLAGS': '0x0', 15:22:22 INFO - 'XPC_SERVICE_NAME': '0', 15:22:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:22:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:22 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)) 15:22:22 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)) 15:22:22 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)) 15:22:22 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)) 15:22:22 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)) 15:22:22 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)) 15:22:22 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)) 15:22:22 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)) 15:22:22 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)) 15:22:22 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)) 15:22:22 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)) 15:22:22 INFO - Cleaning up... 15:22:22 INFO - Return code: 0 15:22:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:22:22 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')]} 15:22:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:22 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')]} 15:22:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:22 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 0x1040bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e08497e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e98d30>, '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 0x103dbfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e8dbe8>, '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.3gIxzuiGX4/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.OrwSIksOOv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 15:22:22 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 15:22:22 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 15:22:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3gIxzuiGX4/Render', 15:22:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:22:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:22:22 INFO - 'HOME': '/Users/cltbld', 15:22:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:22:22 INFO - 'LOGNAME': 'cltbld', 15:22:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:22 INFO - 'MOZ_NO_REMOTE': '1', 15:22:22 INFO - 'NO_EM_RESTART': '1', 15:22:22 INFO - 'PAGER': '/bin/cat', 15:22:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:22:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:22 INFO - 'PWD': '/builds/slave/test', 15:22:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:22:22 INFO - 'SHELL': '/bin/bash', 15:22:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OrwSIksOOv/Listeners', 15:22:22 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 15:22:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:22:22 INFO - 'USER': 'cltbld', 15:22:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:22:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:22:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:22 INFO - 'XPC_FLAGS': '0x0', 15:22:22 INFO - 'XPC_SERVICE_NAME': '0', 15:22:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:22:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:22:22 INFO - Cleaning up... 15:22:22 INFO - Return code: 0 15:22:22 INFO - Running post-action listener: _resource_record_post_action 15:22:22 INFO - Running post-action listener: _start_resource_monitoring 15:22:22 INFO - Starting resource monitoring. 15:22:22 INFO - [mozharness: 2017-03-27 22:22:22.800530Z] Finished create-virtualenv step (success) 15:22:22 INFO - [mozharness: 2017-03-27 22:22:22.801462Z] Running install step. 15:22:22 INFO - Running pre-action listener: _resource_record_pre_action 15:22:22 INFO - Running main action method: install 15:22:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:22:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:22:23 INFO - Reading from file tmpfile_stdout 15:22:23 INFO - Detecting whether we're running mozinstall >=1.0... 15:22:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:22:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:22:23 INFO - Reading from file tmpfile_stdout 15:22:23 INFO - Output received: 15:22:23 INFO - Usage: mozinstall [options] installer 15:22:23 INFO - Options: 15:22:23 INFO - -h, --help show this help message and exit 15:22:23 INFO - -d DEST, --destination=DEST 15:22:23 INFO - Directory to install application into. [default: 15:22:23 INFO - "/builds/slave/test"] 15:22:23 INFO - --app=APP Application being installed. [default: firefox] 15:22:23 INFO - mkdir: /builds/slave/test/build/application 15:22:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:22:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 15:22:43 INFO - Reading from file tmpfile_stdout 15:22:43 INFO - Output received: 15:22:43 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 15:22:43 INFO - Running post-action listener: _resource_record_post_action 15:22:43 INFO - [mozharness: 2017-03-27 22:22:43.504662Z] Finished install step (success) 15:22:43 INFO - [mozharness: 2017-03-27 22:22:43.504800Z] Running run-tests step. 15:22:43 INFO - Running pre-action listener: _resource_record_pre_action 15:22:43 INFO - Running main action method: run_tests 15:22:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:22:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:22:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:22:43 INFO - 2017-03-27 15:22:43.519 screenresolution[1623:7362] starting screenresolution argv=screenresolution get 15:22:43 INFO - 2017-03-27 15:22:43.521 screenresolution[1623:7362] Display 0: 1600x1200x32@60 15:22:43 INFO - 2017-03-27 15:22:43.527 screenresolution[1624:7365] starting screenresolution argv=screenresolution list 15:22:43 INFO - Available Modes on Display 0 15:22:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:22:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:22:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:22:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:22:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:22:43 INFO - Intel Iris: 15:22:43 INFO - Chipset Model: Intel Iris 15:22:43 INFO - Type: GPU 15:22:43 INFO - Bus: Built-In 15:22:43 INFO - VRAM (Dynamic, Max): 1536 MB 15:22:43 INFO - Vendor: Intel (0x8086) 15:22:43 INFO - Device ID: 0x0a2e 15:22:43 INFO - Revision ID: 0x0009 15:22:43 INFO - Displays: 15:22:43 INFO - Display: 15:22:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:22:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:22:43 INFO - Display Serial Number: 1600x1200 60 15:22:43 INFO - Main Display: Yes 15:22:43 INFO - Mirror: Off 15:22:43 INFO - Online: Yes 15:22:43 INFO - Rotation: Supported 15:22:43 INFO - Return code: 0 15:22:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:22:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:22:43 INFO - Python 2.7.3 15:22:43 INFO - Return code: 0 15:22:43 INFO - grabbing minidump binary from tooltool 15:22:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:22:43 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 0x103e98d30>, '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 0x103dbfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e8dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:22:43 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 15:22:43 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 15:22:43 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 15:22:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:22:43 INFO - Return code: 0 15:22:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:22:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:22:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:22:43 INFO - ENV: RUST_BACKTRACE is now 1 15:22:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:22:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:22:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X6ybCyeEQoaxwTyhMSJGUA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0060', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:22:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/X6ybCyeEQoaxwTyhMSJGUA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0060 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:22:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3gIxzuiGX4/Render', 15:22:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:22:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:22:43 INFO - 'HOME': '/Users/cltbld', 15:22:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:22:43 INFO - 'LOGNAME': 'cltbld', 15:22:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:22:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:22:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:43 INFO - 'MOZ_NO_REMOTE': '1', 15:22:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:22:43 INFO - 'NO_EM_RESTART': '1', 15:22:43 INFO - 'PAGER': '/bin/cat', 15:22:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:22:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:43 INFO - 'PWD': '/builds/slave/test', 15:22:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:22:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:22:43 INFO - 'RUST_BACKTRACE': '1', 15:22:43 INFO - 'SHELL': '/bin/bash', 15:22:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OrwSIksOOv/Listeners', 15:22:43 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 15:22:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:22:43 INFO - 'USER': 'cltbld', 15:22:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:22:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:22:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:43 INFO - 'XPC_FLAGS': '0x0', 15:22:43 INFO - 'XPC_SERVICE_NAME': '0', 15:22:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:22:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X6ybCyeEQoaxwTyhMSJGUA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0060', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:22:44 INFO - mozversion application_buildid: 20170327134621 15:22:44 INFO - mozversion application_changeset: d9da8b1ac1fc06b02a8b04ff4ef28290fc980374 15:22:44 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:22:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:22:44 INFO - mozversion application_name: Firefox 15:22:44 INFO - mozversion application_remotingname: firefox-dev 15:22:44 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:22:44 INFO - mozversion application_vendor: Mozilla 15:22:44 INFO - mozversion application_version: 54.0a2 15:22:44 INFO - mozversion platform_buildid: 20170327134621 15:22:44 INFO - mozversion platform_changeset: d9da8b1ac1fc06b02a8b04ff4ef28290fc980374 15:22:44 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:22:44 INFO - mozversion platform_version: 54.0a2 15:22:44 INFO - using testdate: 1490653364 15:22:44 INFO - actual date: 1490653364 15:22:44 INFO - starting webserver on 'localhost:49242' 15:22:44 INFO - SUITE-START | Running 2 tests 15:22:44 INFO - TEST-START | dromaeo_css 15:22:44 INFO - operating with platform_type : mac_ 15:22:44 INFO - Initialising browser for dromaeo_css test... 15:22:44 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/tmpftZV_v/profile http://localhost:49242/getInfo.html) 15:22:47 INFO - PROCESS | 1634 | 2017-03-27 15:22:47.046 plugin-container[1636:7541] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3b, name = 'com.apple.tsm.portname' 15:22:47 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:22:47 INFO - PROCESS | 1634 | 2017-03-27 15:22:47.049 plugin-container[1636:7541] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x921f, name = 'com.apple.CFPasteboardClient' 15:22:47 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:22:47 INFO - PROCESS | 1634 | 2017-03-27 15:22:47.049 plugin-container[1636:7541] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:22:47 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 15:22:47 INFO - PROCESS | 1634 | colorDepth:24 15:22:47 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 15:22:47 INFO - PROCESS | 1634 | __metrics 15:22:47 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 15:22:47 INFO - TEST-INFO | 1634: exit 0 15:22:47 INFO - Browser initialized. 15:22:47 INFO - Running cycle 1/1 for dromaeo_css test... 15:22:47 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/tmpftZV_v/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:22:50 INFO - PROCESS | 1637 | 2017-03-27 15:22:50.327 plugin-container[1638:7669] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443f, name = 'com.apple.tsm.portname' 15:22:50 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:22:50 INFO - PROCESS | 1637 | 2017-03-27 15:22:50.328 plugin-container[1638:7669] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 15:22:50 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:22:50 INFO - PROCESS | 1637 | 2017-03-27 15:22:50.328 plugin-container[1638:7669] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:23:00 INFO - PROCESS | 1637 | RSS: Main: 220917760 15:23:00 INFO - PROCESS | 1637 | 15:26:03 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 15:26:05 INFO - PROCESS | 1637 | RSS: Main: 241836032 15:26:05 INFO - PROCESS | 1637 | 15:27:48 INFO - PROCESS | 1637 | ************************* 15:27:48 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 15:27:48 INFO - PROCESS | 1637 | 15:27:48 ERROR - PROCESS | 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:27:48 INFO - PROCESS | 1637 | Full stack: 15:27:48 INFO - PROCESS | 1637 | ************************* 15:29:08 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 15:29:08 INFO - PROCESS | 1637 | RSS: Main: 251543552 15:29:08 INFO - PROCESS | 1637 | 15:32:10 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 15:32:11 INFO - PROCESS | 1637 | RSS: Main: 258121728 15:32:11 INFO - PROCESS | 1637 | 15:35:13 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 15:35:14 INFO - PROCESS | 1637 | RSS: Main: 254930944 15:35:14 INFO - PROCESS | 1637 | 15:38:17 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 15:38:17 INFO - PROCESS | 1637 | RSS: Main: 260161536 15:38:17 INFO - PROCESS | 1637 | 15:41:20 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 15:41:20 INFO - PROCESS | 1637 | RSS: Main: 256372736 15:41:20 INFO - PROCESS | 1637 | 15:41:20 INFO - PROCESS | 1637 | __start_tp_report 15:41:20 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 15:41:20 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 15:41:20 INFO - PROCESS | 1637 | |i|pagename|runs| 15:41:20 INFO - PROCESS | 1637 | |0;dojo.html;2610.138654244099;3440.8451619677103;3501.597316308625;3508.631593682663;3512.8067956262394;6042.063480160571;6050.122732203828;6086.965215913295;6111.877762444748;6167.7841275555365;5535.501804837564;6085.878282434349;6102.542309326807;6119.479844213236;6136.048912418582;3960.6435420812195;5398.460153984609;5481.493995299598;5563;5587.3015873015875;5525.557955363582;5587.3015873015875;5593.188987596816;5605.131204663284;5607.719614019303;4120.814563344642;4611.30830375443;5346.331708536433;5591.524720398771;5595.580331475136;4442.955570444303;5514.724263786815;5529.170624406331;5547.389787123413;5554.361248456398;4002.8598999035185;5332.120200166968;5398.460153984609;5500.009998200322;5509.449055094498;5764.135379693038;5800.564957628195;6026.6986650667295;6182.350853160398;6209.9068513972325;6360.77737279193;6373.9681301593655;6376.649284289362;6403.7438502459845;6418.1656384669695;5923.466887980103;6567.967160164217;6603.537752357337;6606.075149479077;6606.471482281406;5745.454181852759;6231.750729970841;6249.0626406039455;6271.3728627136925;6316.873662526816;6405.647689377036;6461.321561236003;6586.1438013058;6652.467802575782;6663.566868153554;6412.038194270804;6425.74297028123;6455.4835613150835;6463.612183269019;6480.57876238043;6468.417842394207;6583.012548117821;6607.174103237096;6609.975453804668;6619.669016549154;6288.779892703734;6324.652144132025;6469.708863101172;6503.349665033459;6523.608583485006;6400.551961362753;6402.135711678864;6439.839004024937;6446.258680251737;6460.773872914519;4404.339349097661;4911.214205727067;5271.71005594696;5285.471452854761;5292.391374991926;4584.954150458453;4718.764061796896;4731.9589690268085;4737.336772851803;4745;4840.741407234074;5986.970065149733;6114.388610881752;6137.560853825989;6152.107944348045;5785;6577.243616984012;6635.767748128792;6654.3345665434035;6657.733690652417;5910.438508341701;5929.940700592939;6142.232220972379;6226.657533835682;6266.185395898504;5814.418558144152;5896.882062358729;5909.379515150933;5911.970440147772;5968.671723055187;6783.646708317264;6838.726450942106;6839.89740153908;6847.794566162824;6869.347411995853;6286.057091436322;6347.111404403479;6577.572457790228;6599.076129341992;6675.766348177791;5468.26178465902;6645.468362531083;6675.766348177791;6682.665866706742;6757.256701762703;6428.228612566522;6456.451201647913;6458.580192287486;6459.354064593691;6489.805305840832;5861.882762344814;5889.587728858939;6459.644719540471;6508.088867558451;6534.934650653433;4924.187509060965;6111.938880611315;6210.7515699373325;6271.529635277281;6315.179026726496;6290.1508296381235;6342.841428964312;6348.396902294275;6349.269833968968;6364.681765911593;6192.969035154795;6202.81391558254;6295.96852015737;6304.873902521832;6310.463610592968;6145.3407579954055;6208.0998255253635;6308.495320374451;6314.368563143649;6411.647359395091;6263.749450021948;6278.058291256348;6307.621542707452;6318.115463834974;6319.6208227506495 15:41:20 INFO - PROCESS | 1637 | |1;ext.html;720.4056653261058;754.5661244784249;776.425445170574;810.2505182705995;959.6305422412373;33696.15759606011;37762.24475510488;38449.61550384495;38661.80669096651;39696.60303396965;38344.80827595858;38475.807620961925;38502.807485962534;38542.22915541694;38568.03579910503;7797.805054873631;7959.2836644701965;9348.39235449697;9451.196648284904;10787.46062696866;10583.629572964948;10591.894081059168;10641.095506881924;10642.255042147053;10643.414612196326;10631.202659800507;10643.361398316125;10670;10670.893291067068;10682.893171068268;8945.150210730002;10595.152387808996;10621.097206737437;10627.468626568641;10630.415327157003;10399.32404393712;10486.213533984981;10489.737756556147;10493.213009024277;10526.000029997138;33786.662133378595;33796.49305260398;33844.98465046052;33944.49083263753;34063.97808065762;3675.650813172744;3721.3301605710963;3746.1758413149064;3753.1555400035045;3790.639889210521;24742.505149897082;24809.50380992388;25223;26151.476970460673;26193.476130477473;3000.294930691286;3010.8795648174264;3118.6257649082027;3189.3302406494663;3220.533022711717;350231;354378;355793;359074;359873.4012659866;45656.3151552727;45737.77131114323;45763.31355029677;45794.542054579695;45863.85340366451;46221;46552;46639.76680116577;46662.53337466582;46849.53150468451;13704.040717149705;13994;14025;14027.298635068206;14060;11870.525178992917;12110.333931633844;12121.333326667118;12133.029357651543;12168.81746773799;11417.314961102195;11894.405279735978;11940.10449216295;11940.462679179458;12007.939960300142;4984.750762461862;5379.919301210485;5603.8879222416135;5621.381648018714;5634.211210430543;1088.9618863339745;1100.6422912553453;1121.4673030310537;1123.8482804821408;1167.3521195736448;21444.356669299945;26909.596356054673;27225.45549089007;27286;27301.726982730317;871.2468599226414;1007.6221416968637;1222.6698791326291;1234.2409418207737;1239.535174309634;10985.830029991215;11213.887861121448;13166.539171129156;13919.234442105579;13974.882009439221;511648;512170;513197;513786;515525;34088.82955585206;41426.75719728413;45496.54503454989;47356.763216183695;48012.5198748015;1398.091240693537;1423.7437261293067;1432.3411230833933;1434.1538492289808;1436.1957303909844;26679.466410672067;27068.45863082688;27085.59371609388;27854.025109121085;28003.159905202876;40833.774986750446;41018.17963640651;41099;41115.588844112375;41215.7939210314;486222.5688871533;487995;489661;491609;492375;30217.848910755307;37974.62025379711;39420.60579394169;39558.40662390009;39738.60261397349;48279.310224141984;48550.5144948546;48939;49978.500214998836;50214;32648.51027234546;32667.673323266463;32700.672993269764;32795.50806737949;33689.831550842086;28647.856760716062;32505.674943250266;33199.83400083081;33226.83706070276;33673.81120227275;32439.675603243664;37394.69118580837;39125.80437097796;41072.38391424191;41551;32188.678113219506;33376.83311583426;33752.49371259482;34231.144221394665;34611.653883460844;21741.759004143198;25390.619140713272;26839;26914;27190.864045679646 15:41:20 INFO - PROCESS | 1637 | |2;jquery.html;404.633806405203;406.56497547624036;420.2225882118082;434.704401007315;448.3319853418408;18941.526461838443;20078.397648070564;21207.151713931464;21219.6817047744;21391.893040534796;21867.90660466978;22129.55740885181;22264;22876;22955.08179672811;5729.914051289234;6264.091706702526;6653.900191497131;6691.464682825386;6713.798586042427;6386.968065159667;6455.32219116993;6601.174853143357;6644.970029645388;6662.733490660367;6616.768413105518;6620.04009418634;6628;6639.701213445383;7178.353948144666;7514.586697731623;7565.243475652445;7608.315251627325;7618.50479718822;7633.389328853678;7166.964165179141;7376.077990251219;7385.8522829543645;7425.331720145213;7428.108626964745;21165.047572859257;21260.04329805147;26144.477110457683;26175.476490470275;26296.685165741637;3253.869845206189;3258.2343149360117;3286.96713032869;3294.5552350432627;3311.536384906115;15506.224688765516;15549.222538873011;16044.35822567096;16171.91914040434;16198.514044578682;1924.3457224543583;1940.8544359172977;2052.917883284667;2053.250563544296;2084.80194381535;172836.4074538883;175348;180877.9999999987;181339;182462.0876895607;16309;16616.66766664684;20914.058867351006;21107.78892211089;21130.78869211319;32556.837215813768;32888.67111328904;33006.669933300356;33036;33066.00801975944;10192.745181370377;10415.843762343571;10419.635312764169;10553.63062292816;10712.89287107119;7094.503384763017;7372.815679608053;8618.525981070976;8867.290616750644;8871.955640221757;9296.442213467079;9623.56693948774;9625.903740962642;9701.6119355225;9704.417734935927;3856.2094770571966;3922.8038598069984;3941.7240793144206;3951.841926322915;3956.3669812829808;895.9507227102441;913.794396260836;915.6062892956093;946.3517490519016;957.5403806172936;21560.353189404344;21647.675284870424;21745.021474033707;21746.782532174475;21821.0180541876;853.628671527875;874.7681864305964;936.4989730494153;947.8720372749729;964.1081999150898;10721;11145.665630031112;11163.609273675545;11169.385683787306;11186.496607652674;743984;758753;766526;768185;781020;123677.38161309136;169236;170398.14800925917;174298;175900;1178.3048001679051;1225.1301575881225;1245.0786418050577;1248.6691026877884;1270.7776139175608;18979.481641468927;19695.507612309233;20007.399778006682;20167.59664806725;20331.706345253628;52100.436986890454;62312.688436557524;63773;68606.65696671484;69128;81749.36501269822;82910.58544707237;83121.675132994;89054;91340;11412.201145919706;11670.416479175838;11813.173077884465;12060.336681482602;12118.818217726917;12978.415971281504;13000.544980925622;13304.268265245504;13347.66630834237;13443.731125377242;9509.429434233969;11321.981021708087;11322.490487928228;11324.547018119512;11599.246049006788;10171.54228059754;10286.897131028594;10423.426711530636;10604.628837990633;10739.979701928305;9571.856422153534;11384.601538946097;11685;11751.647450576496;11795.469203886018;11683.649490515298;11694.941525292319;11916.16586838913;11990.160688751703;12009.63971080834;11205.159613028893;11267.492962816528;11463.656090317303;11533.538658453568;11567.479463424335 15:41:20 INFO - PROCESS | 1637 | |3;mootools.html;1317.7232781115965;1360.8503064662889;1553.0603984589316;1555.6733086051936;1568.5372815019575;10698.411587362692;11128.221024528266;11303.095752339814;11315;11458.942705286461;11213.551457941672;11353.659390218307;11419.942900285487;11433.828492572597;11460.31238125713;2429.7084349877996;2455.607102863542;2459.1638842793445;2463.051725085846;2464.6179842124498;2358.5636657218383;2409.6144616861307;2434.47658753368;2440.670509481224;2457.987710061447;2372.9644055339186;2413.6862207913036;2420.455397535558;2426.8665223412704;2442.2795275393714;2205.514786746913;2349.5066036132434;2397.520495900818;2416.709994800626;2422.769836865495;2304.6197377432873;2306.7001289832383;2319.130326127702;2320.93037208884;2328.4528135888054;10872.347659140398;11276;11277.943610281978;11279.436028198557;11283.717907052389;4985.277134815467;5733.713314334308;5917.349091599921;5935.970320148414;5936.584439089265;3585.2829434113282;3603.6126091454717;3651.43402772568;3679.1537946272238;3730.9253814923823;1182.3851597169423;1184.2006645514243;1207.3963018490754;1235.1600911380258;1236.8824961628632;13904.374303156383;14387.136771793726;15638.98346607467;15783.605409864731;15790.684186316324;4273.807678654468;4478.768338706816;4601.12578610063;4734.715917044989;4757.096151731229;11806.940965295118;11815.172937894265;11858.16992810495;11865.822012669816;11880.702982425508;5770.826875193751;5775.27809023872;5775.422457754191;5777.740001699934;5796.5072968797285;5170.121079416508;5376.784928602812;5402.216678581583;5411.323584551931;5461.2627295315415;3782.470454136423;3905.843766249375;3927.293087244266;3969.622885825842;3984.0438294809037;882.7175303902818;1042.9582816687248;1048.3709774135457;1060.9363438193734;1065.0946695308926;926.8321914387919;933.73855320509;942.3686130292721;944.6882528765491;990.8266053440619;6432.131662225634;6495.642739649365;6591.373819487141;6681.231658359254;6743.797686069426;1032.0866033560203;1034.7102811212872;1053.9776416875618;1061.7080302916759;1072.201210098466;3762.2099359134886;4142.212979533879;4224.9155016899485;4251.341042138473;4331.177076355482;13359.599212023655;13827.170369777645;13891.930540347234;13909.817665498545;13932.58202253934;10572.471376431147;13621;13817.032807703565;13823.51617693376;13834.377453014638;10063.849042264372;10352.53413596375;10364.274500785023;10380.896191038144;10407.219458540489;5503.367112781922;6496.480281577557;6733.595984240766;6775.322467753185;6977.441804655717;11985;12269.386530673251;12418.441170147184;12508.374581270718;12656.746865062463;11534.82697759551;11837.348945807718;12010.219335743148;12890.162139460905;12897.48410063587;9163.266938645025;9308.674196403093;9388.436693798394;9450.858237126311;9476.62588924616;9954.601815927283;10265.178785696977;10326.793464130526;10425.9478702606;10426.895731042592;8877.712731654003;8924.821503569763;8929.10708929102;8958.969718482198;8968.68609598661;8614.138586141586;8643.783905402164;8701;8716.43343182691;8865.024847266666;7814.765557033298;7864.410169237444;7864.921350786648;7879.133295337623;7951.721689740832;4819.855404337875;5850.0932355484965;5877.118432235199;6064.4541991221;6122.938770612237;5961.7615295387695;5966.134910437875;5997.520198384032;6102.389761023862;6124 15:41:20 INFO - PROCESS | 1637 | |4;prototype.html;309.74755574207035;325.23731848814526;372.11338513735336;372.7912369073319;384.47519136378844;4150.9245377311345;4587.79785702862;5323.4828073998915;5439.972800135999;5472.398036216018;4060.1879624075154;4742.454617718963;5218.895622087556;5259.053370393328;5308.177232528964;3780.962190378102;4197.208856110683;4344.174606824709;4385.42989411376;4443.311286750559;4298.505671847731;4412.955870441287;4413.073254616518;4446.733196008234;4450.109978004396;3798.335291324021;3918.864219000637;4142.8757137285775;4206.221848957937;4359.28071868143;3693.2244228712;3915.3571339345826;3952.359770695158;4079.2261262341594;4334;3433.9484907726405;3725.4598083277774;3976.70174736893;4011.4183443400834;4018.738781979162;4759.881002974918;5138.922916156261;5147.716875571842;5160.483951604847;5242.318498595197;4281.978590107061;4649.139909116824;4761.880952976168;4852.0213424991525;5107.948920510785;4743.0988112258565;5240.764165612557;5374.247605335257;5662.405447428002;5711.657300561938;4516.88707782308;4525.26665500963;4531.365608814768;4537.321191159259;4555.407796986404;5494.532964698045;5768.567357448208;5773.268019594105;5846.561507886883;5931.970340148272;3941.428492868518;4225.260579398593;4698.694584851974;4706.670533062721;4755.881102972384;4524.959259370363;4546.181687296318;4627.884302892455;4708.670393072521;4805.255185446262;4321.7034889533015;4498.617617502549;4549.954500454953;4592.471865735483;5107.412647545506;4722.669413141047;5536.951328008662;5747;5749.367569567345;5782.45066718661;1407.7817938219591;1466.8973171877867;1580.1862041048869;1585.8581821088799;1596.7525033619806;1023.8668973033458;1110.5280255891375;1183.1954271095738;1197.2816310213802;1228.846394200725;5014.348134742533;5200.8959820803375;5239.318888544541;5242.423333433319;5348.57211423093;5884.3226332019685;5903.498202652822;5922.703864806743;5977.402259773988;6017.969910150421;4592.402987611589;4825.734584597811;4891.899322652446;4936.777844996984;5276.604254680914;2693.3101650738613;2911.005960916779;2913.3539550154096;3070.6161729783776;3121.6119668453653;5372.132064913256;5807.299605683377;5850.385709500441;6028.728707208186;6231.40801623845;4960.561437183249;5715.684658083121;5835.912461313083;6141.7543298268465;6155.476254735537;5427.891442171055;5879.970600146972;6000.819975400745;6074.782704312894;6243.687815609293;5018.322526459027;5327.893442131213;5520.06158952987;5951.55363347742;6015.759369625166;5089.898202035864;5173.611979101658;5385.165299378603;5497.47773961473;5902.763889444546;5661.264035675501;5932.733027013881;6190.2571691397325;6231.2522497300615;6241.812745617639;4197.244495990751;4215.57844215576;4363.192809330284;4605;4651.930221046755;5126.948730512647;5141.7686204120155;5385.3268341457315;5588.552915766648;5766.019776638065;4600.07998400328;4820.806533027394;4983.426905905867;5470.835874923759;5531.502164805187;4754;5087.974560127176;5266;5475.5072043516275;5766.509846663081;4834.468208496992;5096.712620359921;5347.679139251657;5427.945720542744;5547.006155567985;4851.490593487633;4927.452234391232;5492.81904390542;5720.399358067343;5735.99620066495;5342.649234336879;5378.638271690686;5548.306461692288;5672.971635141798;5678.233438485751 15:41:20 INFO - PROCESS | 1637 | |5;yui.html;695.6174104242665;704.7639040921288;763.1529002806886;766.5860435364904;851.9020312664042;15295.311710973005;15878.920605396957;16454.177291135424;16468.588285292873;16570.66858662829;14931.925340373311;15186.620334491616;15214.467493637725;15575.143367114833;15577.610559736013;939.3518472254123;959.6737109382808;960.7982323711994;962.5764663548051;964.8600952861831;899.5187574647565;951.5622813505796;953.7949340891707;966.9999999999965;967.2117224462041;981.0287815063108;990.2078337330144;994.8010397920444;995.4873240281224;995.5719040812447;806.1978331560106;839.2237180607913;985.014985014985;986.585634033704;989.9059589339001;729.4674887332225;729.482067731911;729.7664747280872;730.6310313291807;731.6670914733784;15986.360545578164;16491.928024678356;16510.504684859472;16660.916695416567;16950.23723932426;14158.646033849132;14174.007819452643;14261.465172655793;14362.569122926328;14398.568042958623;11771.352575608338;11795.528178872835;11846.526138954432;12027.03783697302;12061.517539298418;3509.4209455439823;3561.679448849616;3605.1888325126897;3617.764845285048;3754.1740817020213;97709;119898.4005079969;122833;123582.9999999991;126697.09954350692;10206.591736330463;10447.373157610416;10586.57653693859;11047.668569942754;11049.558017679363;21179.576408472054;21226.36320910375;21364.14543418277;21504.892475537523;21783.891080544498;5732.914006289909;6139.355367686418;7364.858446940732;7373.741919032808;7487.812804679817;4129.8847764143;4149.294619914622;4209;4224.957750422518;4267.359896015622;3362.327534493111;3414;3419.7948123112196;3441;3456.5333679952882;442.5729171349663;573.1717667969743;574.0786038408295;579.5884921705637;590.2091197794975;620.8385819686823;654.8264709851893;664.7972368427676;666.4716179292778;693.719043787267;9533.666321678847;9930.851037234448;9943.303968722264;10098.394096354095;10267.691969240936;616.7902913009598;663.4307426047832;668.8963210702349;679.8980152977093;680.4080450008425;4040.959590404117;4051.372037334218;4138.172365526907;4168.645665118499;4181.644560212416;79395.60302198568;94885.10229795365;98616.50691746495;98746.5062674682;102779;71861.6406917962;75539;80122.39755204863;86074;90356.54821725849;69575;74871;76831.46337073228;79052.6047369771;79144.20855791484;10810.567577297135;10966.06788422993;11296.887031129583;11361.261518001304;11525.308481491138;47824.521754783396;49335.75332123316;49473.50526494689;50414.747926260134;51252.97494050173;66881.66559167173;68802;71193.6440317795;72041;73162;12544.121911466109;12720.942955189661;12978.935105324414;13018.80471792905;13036.608901732963;14456.927715361357;14694.412223510939;14806.48177313832;14938.925305373838;15111.94216404841;13333.933330333286;13712.725745484835;14131.576052718436;14146.575602731935;14236.501722439665;13390.732185356434;13905.72188556203;14101.224432656303;14258.073225240327;14541.27293635335;12951.870481295067;13170.802437963246;13287.20276783396;13438.395272212578;13450.327483625975;9341.673041443517;9565.808683826424;9679.274054445805;9957.502124893581;10035.34770239932;10417.270791044857;10477.790444191225;10636.361818290927;11558;11641.952224299854 15:41:20 INFO - PROCESS | 1637 | __end_tp_report 15:41:20 INFO - PROCESS | 1637 | __start_cc_report 15:41:20 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,411 15:41:20 INFO - PROCESS | 1637 | __end_cc_report 15:41:20 INFO - PROCESS | 1637 | __startTimestamp1490654480919__endTimestamp 15:41:20 INFO - PROCESS | 1637 | 15:41:20 INFO - PROCESS | 1637 | ------- Summary: start ------- 15:41:20 INFO - PROCESS | 1637 | Number of tests: 6 15:41:20 INFO - PROCESS | 1637 | 15:41:20 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5964.04 Median:6231.75 stddev:762.98 (12.2%) stddev-sans-first:718.53 15:41:20 INFO - PROCESS | 1637 | Values: 2610.1 3440.8 3501.6 3508.6 3512.8 6042.1 6050.1 6087.0 6111.9 6167.8 5535.5 6085.9 6102.5 6119.5 6136.0 3960.6 5398.5 5481.5 5563.0 5587.3 5525.6 5587.3 5593.2 5605.1 5607.7 4120.8 4611.3 5346.3 5591.5 5595.6 4443.0 5514.7 5529.2 5547.4 5554.4 4002.9 5332.1 5398.5 5500.0 5509.4 5764.1 5800.6 6026.7 6182.4 6209.9 6360.8 6374.0 6376.6 6403.7 6418.2 5923.5 6568.0 6603.5 6606.1 6606.5 5745.5 6231.8 6249.1 6271.4 6316.9 6405.6 6461.3 6586.1 6652.5 6663.6 6412.0 6425.7 6455.5 6463.6 6480.6 6468.4 6583.0 6607.2 6610.0 6619.7 6288.8 6324.7 6469.7 6503.3 6523.6 6400.6 6402.1 6439.8 6446.3 6460.8 4404.3 4911.2 5271.7 5285.5 5292.4 4585.0 4718.8 4732.0 4737.3 4745.0 4840.7 5987.0 6114.4 6137.6 6152.1 5785.0 6577.2 6635.8 6654.3 6657.7 5910.4 5929.9 6142.2 6226.7 6266.2 5814.4 5896.9 5909.4 5912.0 5968.7 6783.6 6838.7 6839.9 6847.8 6869.3 6286.1 6347.1 6577.6 6599.1 6675.8 5468.3 6645.5 6675.8 6682.7 6757.3 6428.2 6456.5 6458.6 6459.4 6489.8 5861.9 5889.6 6459.6 6508.1 6534.9 4924.2 6111.9 6210.8 6271.5 6315.2 6290.2 6342.8 6348.4 6349.3 6364.7 6193.0 6202.8 6296.0 6304.9 6310.5 6145.3 6208.1 6308.5 6314.4 6411.6 6263.7 6278.1 6307.6 6318.1 6319.6 15:41:20 INFO - PROCESS | 1637 | 15:41:20 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:58108.40 Median:33525.32 stddev:121978.77 (363.8%) stddev-sans-first:122244.85 15:41:20 INFO - PROCESS | 1637 | Values: 720.4 754.6 776.4 810.3 959.6 33696.2 37762.2 38449.6 38661.8 39696.6 38344.8 38475.8 38502.8 38542.2 38568.0 7797.8 7959.3 9348.4 9451.2 10787.5 10583.6 10591.9 10641.1 10642.3 10643.4 10631.2 10643.4 10670.0 10670.9 10682.9 8945.2 10595.2 10621.1 10627.5 10630.4 10399.3 10486.2 10489.7 10493.2 10526.0 33786.7 33796.5 33845.0 33944.5 34064.0 3675.7 3721.3 3746.2 3753.2 3790.6 24742.5 24809.5 25223.0 26151.5 26193.5 3000.3 3010.9 3118.6 3189.3 3220.5 350231.0 354378.0 355793.0 359074.0 359873.4 45656.3 45737.8 45763.3 45794.5 45863.9 46221.0 46552.0 46639.8 46662.5 46849.5 13704.0 13994.0 14025.0 14027.3 14060.0 11870.5 12110.3 12121.3 12133.0 12168.8 11417.3 11894.4 11940.1 11940.5 12007.9 4984.8 5379.9 5603.9 5621.4 5634.2 1089.0 1100.6 1121.5 1123.8 1167.4 21444.4 26909.6 27225.5 27286.0 27301.7 871.2 1007.6 1222.7 1234.2 1239.5 10985.8 11213.9 13166.5 13919.2 13974.9 511648.0 512170.0 513197.0 513786.0 515525.0 34088.8 41426.8 45496.5 47356.8 48012.5 1398.1 1423.7 1432.3 1434.2 1436.2 26679.5 27068.5 27085.6 27854.0 28003.2 40833.8 41018.2 41099.0 41115.6 41215.8 486222.6 487995.0 489661.0 491609.0 492375.0 30217.8 37974.6 39420.6 39558.4 39738.6 48279.3 48550.5 48939.0 49978.5 50214.0 32648.5 32667.7 32700.7 32795.5 33689.8 28647.9 32505.7 33199.8 33226.8 33673.8 32439.7 37394.7 39125.8 41072.4 41551.0 32188.7 33376.8 33752.5 34231.1 34611.7 21741.8 25390.6 26839.0 26914.0 27190.9 15:41:20 INFO - PROCESS | 1637 | 15:41:20 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:44394.09 Median:21998.73 stddev:128187.67 (582.7%) stddev-sans-first:128504.71 15:41:20 INFO - PROCESS | 1637 | Values: 404.6 406.6 420.2 434.7 448.3 18941.5 20078.4 21207.2 21219.7 21391.9 21867.9 22129.6 22264.0 22876.0 22955.1 5729.9 6264.1 6653.9 6691.5 6713.8 6387.0 6455.3 6601.2 6645.0 6662.7 6616.8 6620.0 6628.0 6639.7 7178.4 7514.6 7565.2 7608.3 7618.5 7633.4 7167.0 7376.1 7385.9 7425.3 7428.1 21165.0 21260.0 26144.5 26175.5 26296.7 3253.9 3258.2 3287.0 3294.6 3311.5 15506.2 15549.2 16044.4 16171.9 16198.5 1924.3 1940.9 2052.9 2053.3 2084.8 172836.4 175348.0 180878.0 181339.0 182462.1 16309.0 16616.7 20914.1 21107.8 21130.8 32556.8 32888.7 33006.7 33036.0 33066.0 10192.7 10415.8 10419.6 10553.6 10712.9 7094.5 7372.8 8618.5 8867.3 8872.0 9296.4 9623.6 9625.9 9701.6 9704.4 3856.2 3922.8 3941.7 3951.8 3956.4 896.0 913.8 915.6 946.4 957.5 21560.4 21647.7 21745.0 21746.8 21821.0 853.6 874.8 936.5 947.9 964.1 10721.0 11145.7 11163.6 11169.4 11186.5 743984.0 758753.0 766526.0 768185.0 781020.0 123677.4 169236.0 170398.1 174298.0 175900.0 1178.3 1225.1 1245.1 1248.7 1270.8 18979.5 19695.5 20007.4 20167.6 20331.7 52100.4 62312.7 63773.0 68606.7 69128.0 81749.4 82910.6 83121.7 89054.0 91340.0 11412.2 11670.4 11813.2 12060.3 12118.8 12978.4 13000.5 13304.3 13347.7 13443.7 9509.4 11322.0 11322.5 11324.5 11599.2 10171.5 10286.9 10423.4 10604.6 10740.0 9571.9 11384.6 11685.0 11751.6 11795.5 11683.6 11694.9 11916.2 11990.2 12009.6 11205.2 11267.5 11463.7 11533.5 11567.5 15:41:20 INFO - PROCESS | 1637 | 15:41:20 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:6759.28 Median:2437.57 stddev:4231.75 (173.6%) stddev-sans-first:4223.86 15:41:20 INFO - PROCESS | 1637 | Values: 1317.7 1360.9 1553.1 1555.7 1568.5 10698.4 11128.2 11303.1 11315.0 11458.9 11213.6 11353.7 11419.9 11433.8 11460.3 2429.7 2455.6 2459.2 2463.1 2464.6 2358.6 2409.6 2434.5 2440.7 2458.0 2373.0 2413.7 2420.5 2426.9 2442.3 2205.5 2349.5 2397.5 2416.7 2422.8 2304.6 2306.7 2319.1 2320.9 2328.5 10872.3 11276.0 11277.9 11279.4 11283.7 4985.3 5733.7 5917.3 5936.0 5936.6 3585.3 3603.6 3651.4 3679.2 3730.9 1182.4 1184.2 1207.4 1235.2 1236.9 13904.4 14387.1 15639.0 15783.6 15790.7 4273.8 4478.8 4601.1 4734.7 4757.1 11806.9 11815.2 11858.2 11865.8 11880.7 5770.8 5775.3 5775.4 5777.7 5796.5 5170.1 5376.8 5402.2 5411.3 5461.3 3782.5 3905.8 3927.3 3969.6 3984.0 882.7 1043.0 1048.4 1060.9 1065.1 926.8 933.7 942.4 944.7 990.8 6432.1 6495.6 6591.4 6681.2 6743.8 1032.1 1034.7 1054.0 1061.7 1072.2 3762.2 4142.2 4224.9 4251.3 4331.2 13359.6 13827.2 13891.9 13909.8 13932.6 10572.5 13621.0 13817.0 13823.5 13834.4 10063.8 10352.5 10364.3 10380.9 10407.2 5503.4 6496.5 6733.6 6775.3 6977.4 11985.0 12269.4 12418.4 12508.4 12656.7 11534.8 11837.3 12010.2 12890.2 12897.5 9163.3 9308.7 9388.4 9450.9 9476.6 9954.6 10265.2 10326.8 10425.9 10426.9 8877.7 8924.8 8929.1 8959.0 8968.7 8614.1 8643.8 8701.0 8716.4 8865.0 7814.8 7864.4 7864.9 7879.1 7951.7 4819.9 5850.1 5877.1 6064.5 6122.9 5961.8 5966.1 5997.5 6102.4 6124.0 15:41:20 INFO - PROCESS | 1637 | 15:41:20 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4667.58 Median:4971.99 stddev:1327.42 (26.7%) stddev-sans-first:1290.21 15:41:20 INFO - PROCESS | 1637 | Values: 309.7 325.2 372.1 372.8 384.5 4150.9 4587.8 5323.5 5440.0 5472.4 4060.2 4742.5 5218.9 5259.1 5308.2 3781.0 4197.2 4344.2 4385.4 4443.3 4298.5 4413.0 4413.1 4446.7 4450.1 3798.3 3918.9 4142.9 4206.2 4359.3 3693.2 3915.4 3952.4 4079.2 4334.0 3433.9 3725.5 3976.7 4011.4 4018.7 4759.9 5138.9 5147.7 5160.5 5242.3 4282.0 4649.1 4761.9 4852.0 5107.9 4743.1 5240.8 5374.2 5662.4 5711.7 4516.9 4525.3 4531.4 4537.3 4555.4 5494.5 5768.6 5773.3 5846.6 5932.0 3941.4 4225.3 4698.7 4706.7 4755.9 4525.0 4546.2 4627.9 4708.7 4805.3 4321.7 4498.6 4550.0 4592.5 5107.4 4722.7 5537.0 5747.0 5749.4 5782.5 1407.8 1466.9 1580.2 1585.9 1596.8 1023.9 1110.5 1183.2 1197.3 1228.8 5014.3 5200.9 5239.3 5242.4 5348.6 5884.3 5903.5 5922.7 5977.4 6018.0 4592.4 4825.7 4891.9 4936.8 5276.6 2693.3 2911.0 2913.4 3070.6 3121.6 5372.1 5807.3 5850.4 6028.7 6231.4 4960.6 5715.7 5835.9 6141.8 6155.5 5427.9 5880.0 6000.8 6074.8 6243.7 5018.3 5327.9 5520.1 5951.6 6015.8 5089.9 5173.6 5385.2 5497.5 5902.8 5661.3 5932.7 6190.3 6231.3 6241.8 4197.2 4215.6 4363.2 4605.0 4651.9 5126.9 5141.8 5385.3 5588.6 5766.0 4600.1 4820.8 4983.4 5470.8 5531.5 4754.0 5088.0 5266.0 5475.5 5766.5 4834.5 5096.7 5347.7 5427.9 5547.0 4851.5 4927.5 5492.8 5720.4 5736.0 5342.6 5378.6 5548.3 5673.0 5678.2 15:41:20 INFO - PROCESS | 1637 | 15:41:20 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:20444.81 Median:4134.03 stddev:29451.64 (712.4%) stddev-sans-first:29496.93 15:41:20 INFO - PROCESS | 1637 | Values: 695.6 704.8 763.2 766.6 851.9 15295.3 15878.9 16454.2 16468.6 16570.7 14931.9 15186.6 15214.5 15575.1 15577.6 939.4 959.7 960.8 962.6 964.9 899.5 951.6 953.8 967.0 967.2 981.0 990.2 994.8 995.5 995.6 806.2 839.2 985.0 986.6 989.9 729.5 729.5 729.8 730.6 731.7 15986.4 16491.9 16510.5 16660.9 16950.2 14158.6 14174.0 14261.5 14362.6 14398.6 11771.4 11795.5 11846.5 12027.0 12061.5 3509.4 3561.7 3605.2 3617.8 3754.2 97709.0 119898.4 122833.0 123583.0 126697.1 10206.6 10447.4 10586.6 11047.7 11049.6 21179.6 21226.4 21364.1 21504.9 21783.9 5732.9 6139.4 7364.9 7373.7 7487.8 4129.9 4149.3 4209.0 4225.0 4267.4 3362.3 3414.0 3419.8 3441.0 3456.5 442.6 573.2 574.1 579.6 590.2 620.8 654.8 664.8 666.5 693.7 9533.7 9930.9 9943.3 10098.4 10267.7 616.8 663.4 668.9 679.9 680.4 4041.0 4051.4 4138.2 4168.6 4181.6 79395.6 94885.1 98616.5 98746.5 102779.0 71861.6 75539.0 80122.4 86074.0 90356.5 69575.0 74871.0 76831.5 79052.6 79144.2 10810.6 10966.1 11296.9 11361.3 11525.3 47824.5 49335.8 49473.5 50414.7 51253.0 66881.7 68802.0 71193.6 72041.0 73162.0 12544.1 12720.9 12978.9 13018.8 13036.6 14456.9 14694.4 14806.5 14938.9 15111.9 13333.9 13712.7 14131.6 14146.6 14236.5 13390.7 13905.7 14101.2 14258.1 14541.3 12951.9 13170.8 13287.2 13438.4 13450.3 9341.7 9565.8 9679.3 9957.5 10035.3 10417.3 10477.8 10636.4 11558.0 11642.0 15:41:20 INFO - PROCESS | 1637 | -------- Summary: end -------- 15:41:20 INFO - PROCESS | 1637 | 15:41:21 INFO - TEST-INFO | 1637: exit 0 15:41:21 INFO - TEST-OK | dromaeo_css | took 1117625ms 15:41:21 INFO - TEST-START | kraken 15:41:21 INFO - operating with platform_type : mac_ 15:41:21 INFO - Initialising browser for kraken test... 15:41:21 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/tmpyVevbm/profile http://localhost:49242/getInfo.html) 15:41:24 INFO - PROCESS | 1650 | 2017-03-27 15:41:24.299 plugin-container[1651:10198] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a37, name = 'com.apple.tsm.portname' 15:41:24 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:41:24 INFO - PROCESS | 1650 | 2017-03-27 15:41:24.304 plugin-container[1651:10198] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c1b, name = 'com.apple.CFPasteboardClient' 15:41:24 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:41:24 INFO - PROCESS | 1650 | 2017-03-27 15:41:24.304 plugin-container[1651:10198] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:41:24 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 15:41:24 INFO - PROCESS | 1650 | colorDepth:24 15:41:24 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 15:41:24 INFO - PROCESS | 1650 | __metrics 15:41:24 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 15:41:25 INFO - TEST-INFO | 1650: exit 0 15:41:25 INFO - Browser initialized. 15:41:25 INFO - Running cycle 1/1 for kraken test... 15:41:25 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/tmpyVevbm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:41:27 INFO - PROCESS | 1652 | 2017-03-27 15:41:27.691 plugin-container[1653:10319] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 15:41:27 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:41:27 INFO - PROCESS | 1652 | 2017-03-27 15:41:27.692 plugin-container[1653:10319] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471b, name = 'com.apple.CFPasteboardClient' 15:41:27 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:41:27 INFO - PROCESS | 1652 | 2017-03-27 15:41:27.692 plugin-container[1653:10319] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:41:37 INFO - PROCESS | 1652 | RSS: Main: 220721152 15:41:37 INFO - PROCESS | 1652 | 15:41:40 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 15:41:41 INFO - PROCESS | 1652 | RSS: Main: 234995712 15:41:41 INFO - PROCESS | 1652 | 15:41:44 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 15:41:44 INFO - PROCESS | 1652 | RSS: Main: 242868224 15:41:44 INFO - PROCESS | 1652 | 15:41:48 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 15:41:48 INFO - PROCESS | 1652 | RSS: Main: 246747136 15:41:48 INFO - PROCESS | 1652 | 15:41:50 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 15:41:51 INFO - PROCESS | 1652 | RSS: Main: 247218176 15:41:51 INFO - PROCESS | 1652 | 15:41:53 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:41:54 INFO - PROCESS | 1652 | RSS: Main: 248455168 15:41:54 INFO - PROCESS | 1652 | 15:41:57 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 15:41:57 INFO - PROCESS | 1652 | RSS: Main: 252329984 15:41:57 INFO - PROCESS | 1652 | 15:42:00 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 15:42:01 INFO - PROCESS | 1652 | RSS: Main: 251670528 15:42:01 INFO - PROCESS | 1652 | 15:42:04 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 15:42:05 INFO - PROCESS | 1652 | RSS: Main: 251899904 15:42:05 INFO - PROCESS | 1652 | 15:42:06 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:42:07 INFO - PROCESS | 1652 | RSS: Main: 251314176 15:42:07 INFO - PROCESS | 1652 | 15:42:09 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:42:09 INFO - PROCESS | 1652 | RSS: Main: 251314176 15:42:09 INFO - PROCESS | 1652 | 15:42:12 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:42:12 INFO - PROCESS | 1652 | RSS: Main: 251289600 15:42:12 INFO - PROCESS | 1652 | 15:42:15 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:42:16 INFO - PROCESS | 1652 | RSS: Main: 251699200 15:42:16 INFO - PROCESS | 1652 | 15:42:19 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:42:20 INFO - PROCESS | 1652 | RSS: Main: 251461632 15:42:20 INFO - PROCESS | 1652 | 15:42:21 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 15:42:22 INFO - PROCESS | 1652 | RSS: Main: 253304832 15:42:22 INFO - PROCESS | 1652 | 15:42:22 INFO - PROCESS | 1652 | __start_tp_report 15:42:22 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 15:42:22 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 15:42:22 INFO - PROCESS | 1652 | |i|pagename|runs| 15:42:22 INFO - PROCESS | 1652 | |0;ai-astar;107;120;118;120;118;119;126;138;120;123 15:42:22 INFO - PROCESS | 1652 | |1;audio-beat-detection;141;111;129;152;141;138;142;118;131;109 15:42:22 INFO - PROCESS | 1652 | |2;audio-dft;203;196;198;199;197;196;277;252;202;197 15:42:22 INFO - PROCESS | 1652 | |3;audio-fft;82;60;64;80;64;81;52;69;97;86 15:42:22 INFO - PROCESS | 1652 | |4;audio-oscillator;91;72;85;73;70;95;114;71;71;84 15:42:22 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;91;91;91;125;137;138;92;93;91;91 15:42:22 INFO - PROCESS | 1652 | |6;imaging-darkroom;99;97;98;98;97;97;99;99;97;101 15:42:22 INFO - PROCESS | 1652 | |7;imaging-desaturate;96;124;92;90;91;91;107;114;90;92 15:42:22 INFO - PROCESS | 1652 | |8;json-parse-financial;70;78;102;106;86;69;67;69;72;71 15:42:22 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;57;63;57;58;63;61;59;58;58;62 15:42:22 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;86;89;88;84;87;95;103;83;86;89 15:42:22 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;166;153;133;147;148;143;175;150;204;184 15:42:22 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;165;166;168;167;169;197;222;192;165;183 15:42:22 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;65;65;65;67;66;65;64;65;69;74 15:42:22 INFO - PROCESS | 1652 | __end_tp_report 15:42:22 INFO - PROCESS | 1652 | __start_cc_report 15:42:22 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1006 15:42:22 INFO - PROCESS | 1652 | __end_cc_report 15:42:22 INFO - PROCESS | 1652 | __startTimestamp1490654542140__endTimestamp 15:42:22 INFO - PROCESS | 1652 | 15:42:22 INFO - PROCESS | 1652 | ------- Summary: start ------- 15:42:22 INFO - PROCESS | 1652 | Number of tests: 14 15:42:22 INFO - PROCESS | 1652 | 15:42:22 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:120.90 Median:120.00 stddev:7.74 (6.4%) stddev-sans-first:6.37 15:42:22 INFO - PROCESS | 1652 | Values: 107.0 120.0 118.0 120.0 118.0 119.0 126.0 138.0 120.0 123.0 15:42:22 INFO - PROCESS | 1652 | 15:42:22 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:131.20 Median:139.50 stddev:14.41 (10.3%) stddev-sans-first:14.84 15:42:22 INFO - PROCESS | 1652 | Values: 141.0 111.0 129.0 152.0 141.0 138.0 142.0 118.0 131.0 109.0 15:42:22 INFO - PROCESS | 1652 | 15:42:22 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:211.70 Median:200.50 stddev:28.54 (14.2%) stddev-sans-first:30.10 15:42:22 INFO - PROCESS | 1652 | Values: 203.0 196.0 198.0 199.0 197.0 196.0 277.0 252.0 202.0 197.0 15:42:22 INFO - PROCESS | 1652 | 15:42:22 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:73.50 Median:80.50 stddev:13.84 (17.2%) stddev-sans-first:14.34 15:42:22 INFO - PROCESS | 1652 | Values: 82.0 60.0 64.0 80.0 64.0 81.0 52.0 69.0 97.0 86.0 15:42:22 INFO - PROCESS | 1652 | 15:42:22 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:82.60 Median:78.50 stddev:14.34 (18.3%) stddev-sans-first:14.88 15:42:22 INFO - PROCESS | 1652 | Values: 91.0 72.0 85.0 73.0 70.0 95.0 114.0 71.0 71.0 84.0 15:42:22 INFO - PROCESS | 1652 | 15:42:22 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:104.00 Median:91.00 stddev:20.54 (22.6%) stddev-sans-first:21.24 15:42:22 INFO - PROCESS | 1652 | Values: 91.0 91.0 91.0 125.0 137.0 138.0 92.0 93.0 91.0 91.0 15:42:22 INFO - PROCESS | 1652 | 15:42:22 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:98.20 Median:98.00 stddev:1.32 (1.3%) stddev-sans-first:1.36 15:42:22 INFO - PROCESS | 1652 | Values: 99.0 97.0 98.0 98.0 97.0 97.0 99.0 99.0 97.0 101.0 15:42:22 INFO - PROCESS | 1652 | 15:42:22 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:98.70 Median:91.00 stddev:12.07 (13.3%) stddev-sans-first:12.76 15:42:22 INFO - PROCESS | 1652 | Values: 96.0 124.0 92.0 90.0 91.0 91.0 107.0 114.0 90.0 92.0 15:42:22 INFO - PROCESS | 1652 | 15:42:22 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:79.00 Median:70.50 stddev:14.32 (20.3%) stddev-sans-first:14.82 15:42:22 INFO - PROCESS | 1652 | Values: 70.0 78.0 102.0 106.0 86.0 69.0 67.0 69.0 72.0 71.0 15:42:22 INFO - PROCESS | 1652 | 15:42:22 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:60.00 stddev:2.41 (4.0%) stddev-sans-first:2.37 15:42:22 INFO - PROCESS | 1652 | Values: 57.0 63.0 57.0 58.0 63.0 61.0 59.0 58.0 58.0 62.0 15:42:22 INFO - PROCESS | 1652 | 15:42:22 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:89.00 Median:87.50 stddev:5.93 (6.8%) stddev-sans-first:6.18 15:42:22 INFO - PROCESS | 1652 | Values: 86.0 89.0 88.0 84.0 87.0 95.0 103.0 83.0 86.0 89.0 15:42:22 INFO - PROCESS | 1652 | 15:42:22 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:160.30 Median:159.50 stddev:21.74 (13.6%) stddev-sans-first:22.96 15:42:22 INFO - PROCESS | 1652 | Values: 166.0 153.0 133.0 147.0 148.0 143.0 175.0 150.0 204.0 184.0 15:42:22 INFO - PROCESS | 1652 | 15:42:22 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.40 Median:176.00 stddev:19.10 (10.9%) stddev-sans-first:19.53 15:42:22 INFO - PROCESS | 1652 | Values: 165.0 166.0 168.0 167.0 169.0 197.0 222.0 192.0 165.0 183.0 15:42:22 INFO - PROCESS | 1652 | 15:42:22 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:65.50 stddev:2.99 (4.6%) stddev-sans-first:3.12 15:42:22 INFO - PROCESS | 1652 | Values: 65.0 65.0 65.0 67.0 66.0 65.0 64.0 65.0 69.0 74.0 15:42:22 INFO - PROCESS | 1652 | -------- Summary: end -------- 15:42:22 INFO - PROCESS | 1652 | 15:42:23 INFO - TEST-INFO | 1652: exit 0 15:42:23 INFO - TEST-OK | kraken | took 61328ms 15:42:23 INFO - SUITE-END | took 1178s 15:42:23 INFO - Completed test suite (00:19:39) 15:42:23 INFO - javascript benchmark 15:42:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7725.298847969048, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2610.138654244099, 3440.8451619677103, 3501.597316308625, 3508.631593682663, 3512.8067956262394, 6042.063480160571, 6050.122732203828, 6086.965215913295, 6111.877762444748, 6167.7841275555365, 5535.501804837564, 6085.878282434349, 6102.542309326807, 6119.479844213236, 6136.048912418582, 3960.6435420812195, 5398.460153984609, 5481.493995299598, 5563.0, 5587.3015873015875, 5525.557955363582, 5587.3015873015875, 5593.188987596816, 5605.131204663284, 5607.719614019303, 4120.814563344642, 4611.30830375443, 5346.331708536433, 5591.524720398771, 5595.580331475136, 4442.955570444303, 5514.724263786815, 5529.170624406331, 5547.389787123413, 5554.361248456398, 4002.8598999035185, 5332.120200166968, 5398.460153984609, 5500.009998200322, 5509.449055094498, 5764.135379693038, 5800.564957628195, 6026.6986650667295, 6182.350853160398, 6209.9068513972325, 6360.77737279193, 6373.9681301593655, 6376.649284289362, 6403.7438502459845, 6418.1656384669695, 5923.466887980103, 6567.967160164217, 6603.537752357337, 6606.075149479077, 6606.471482281406, 5745.454181852759, 6231.750729970841, 6249.0626406039455, 6271.3728627136925, 6316.873662526816, 6405.647689377036, 6461.321561236003, 6586.1438013058, 6652.467802575782, 6663.566868153554, 6412.038194270804, 6425.74297028123, 6455.4835613150835, 6463.612183269019, 6480.57876238043, 6468.417842394207, 6583.012548117821, 6607.174103237096, 6609.975453804668, 6619.669016549154, 6288.779892703734, 6324.652144132025, 6469.708863101172, 6503.349665033459, 6523.608583485006, 6400.551961362753, 6402.135711678864, 6439.839004024937, 6446.258680251737, 6460.773872914519, 4404.339349097661, 4911.214205727067, 5271.71005594696, 5285.471452854761, 5292.391374991926, 4584.954150458453, 4718.764061796896, 4731.9589690268085, 4737.336772851803, 4745.0, 4840.741407234074, 5986.970065149733, 6114.388610881752, 6137.560853825989, 6152.107944348045, 5785.0, 6577.243616984012, 6635.767748128792, 6654.3345665434035, 6657.733690652417, 5910.438508341701, 5929.940700592939, 6142.232220972379, 6226.657533835682, 6266.185395898504, 5814.418558144152, 5896.882062358729, 5909.379515150933, 5911.970440147772, 5968.671723055187, 6783.646708317264, 6838.726450942106, 6839.89740153908, 6847.794566162824, 6869.347411995853, 6286.057091436322, 6347.111404403479, 6577.572457790228, 6599.076129341992, 6675.766348177791, 5468.26178465902, 6645.468362531083, 6675.766348177791, 6682.665866706742, 6757.256701762703, 6428.228612566522, 6456.451201647913, 6458.580192287486, 6459.354064593691, 6489.805305840832, 5861.882762344814, 5889.587728858939, 6459.644719540471, 6508.088867558451, 6534.934650653433, 4924.187509060965, 6111.938880611315, 6210.7515699373325, 6271.529635277281, 6315.179026726496, 6290.1508296381235, 6342.841428964312, 6348.396902294275, 6349.269833968968, 6364.681765911593, 6192.969035154795, 6202.81391558254, 6295.96852015737, 6304.873902521832, 6310.463610592968, 6145.3407579954055, 6208.0998255253635, 6308.495320374451, 6314.368563143649, 6411.647359395091, 6263.749450021948, 6278.058291256348, 6307.621542707452, 6318.115463834974, 6319.6208227506495], "value": 5913.797505292273, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [720.4056653261058, 754.5661244784249, 776.425445170574, 810.2505182705995, 959.6305422412373, 33696.15759606011, 37762.24475510488, 38449.61550384495, 38661.80669096651, 39696.60303396965, 38344.80827595858, 38475.807620961925, 38502.807485962534, 38542.22915541694, 38568.03579910503, 7797.805054873631, 7959.2836644701965, 9348.39235449697, 9451.196648284904, 10787.46062696866, 10583.629572964948, 10591.894081059168, 10641.095506881924, 10642.255042147053, 10643.414612196326, 10631.202659800507, 10643.361398316125, 10670.0, 10670.893291067068, 10682.893171068268, 8945.150210730002, 10595.152387808996, 10621.097206737437, 10627.468626568641, 10630.415327157003, 10399.32404393712, 10486.213533984981, 10489.737756556147, 10493.213009024277, 10526.000029997138, 33786.662133378595, 33796.49305260398, 33844.98465046052, 33944.49083263753, 34063.97808065762, 3675.650813172744, 3721.3301605710963, 3746.1758413149064, 3753.1555400035045, 3790.639889210521, 24742.505149897082, 24809.50380992388, 25223.0, 26151.476970460673, 26193.476130477473, 3000.294930691286, 3010.8795648174264, 3118.6257649082027, 3189.3302406494663, 3220.533022711717, 350231.0, 354378.0, 355793.0, 359074.0, 359873.4012659866, 45656.3151552727, 45737.77131114323, 45763.31355029677, 45794.542054579695, 45863.85340366451, 46221.0, 46552.0, 46639.76680116577, 46662.53337466582, 46849.53150468451, 13704.040717149705, 13994.0, 14025.0, 14027.298635068206, 14060.0, 11870.525178992917, 12110.333931633844, 12121.333326667118, 12133.029357651543, 12168.81746773799, 11417.314961102195, 11894.405279735978, 11940.10449216295, 11940.462679179458, 12007.939960300142, 4984.750762461862, 5379.919301210485, 5603.8879222416135, 5621.381648018714, 5634.211210430543, 1088.9618863339745, 1100.6422912553453, 1121.4673030310537, 1123.8482804821408, 1167.3521195736448, 21444.356669299945, 26909.596356054673, 27225.45549089007, 27286.0, 27301.726982730317, 871.2468599226414, 1007.6221416968637, 1222.6698791326291, 1234.2409418207737, 1239.535174309634, 10985.830029991215, 11213.887861121448, 13166.539171129156, 13919.234442105579, 13974.882009439221, 511648.0, 512170.0, 513197.0, 513786.0, 515525.0, 34088.82955585206, 41426.75719728413, 45496.54503454989, 47356.763216183695, 48012.5198748015, 1398.091240693537, 1423.7437261293067, 1432.3411230833933, 1434.1538492289808, 1436.1957303909844, 26679.466410672067, 27068.45863082688, 27085.59371609388, 27854.025109121085, 28003.159905202876, 40833.774986750446, 41018.17963640651, 41099.0, 41115.588844112375, 41215.7939210314, 486222.5688871533, 487995.0, 489661.0, 491609.0, 492375.0, 30217.848910755307, 37974.62025379711, 39420.60579394169, 39558.40662390009, 39738.60261397349, 48279.310224141984, 48550.5144948546, 48939.0, 49978.500214998836, 50214.0, 32648.51027234546, 32667.673323266463, 32700.672993269764, 32795.50806737949, 33689.831550842086, 28647.856760716062, 32505.674943250266, 33199.83400083081, 33226.83706070276, 33673.81120227275, 32439.675603243664, 37394.69118580837, 39125.80437097796, 41072.38391424191, 41551.0, 32188.678113219506, 33376.83311583426, 33752.49371259482, 34231.144221394665, 34611.653883460844, 21741.759004143198, 25390.619140713272, 26839.0, 26914.0, 27190.864045679646], "value": 18588.18746997678, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [404.633806405203, 406.56497547624036, 420.2225882118082, 434.704401007315, 448.3319853418408, 18941.526461838443, 20078.397648070564, 21207.151713931464, 21219.6817047744, 21391.893040534796, 21867.90660466978, 22129.55740885181, 22264.0, 22876.0, 22955.08179672811, 5729.914051289234, 6264.091706702526, 6653.900191497131, 6691.464682825386, 6713.798586042427, 6386.968065159667, 6455.32219116993, 6601.174853143357, 6644.970029645388, 6662.733490660367, 6616.768413105518, 6620.04009418634, 6628.0, 6639.701213445383, 7178.353948144666, 7514.586697731623, 7565.243475652445, 7608.315251627325, 7618.50479718822, 7633.389328853678, 7166.964165179141, 7376.077990251219, 7385.8522829543645, 7425.331720145213, 7428.108626964745, 21165.047572859257, 21260.04329805147, 26144.477110457683, 26175.476490470275, 26296.685165741637, 3253.869845206189, 3258.2343149360117, 3286.96713032869, 3294.5552350432627, 3311.536384906115, 15506.224688765516, 15549.222538873011, 16044.35822567096, 16171.91914040434, 16198.514044578682, 1924.3457224543583, 1940.8544359172977, 2052.917883284667, 2053.250563544296, 2084.80194381535, 172836.4074538883, 175348.0, 180877.9999999987, 181339.0, 182462.0876895607, 16309.0, 16616.66766664684, 20914.058867351006, 21107.78892211089, 21130.78869211319, 32556.837215813768, 32888.67111328904, 33006.669933300356, 33036.0, 33066.00801975944, 10192.745181370377, 10415.843762343571, 10419.635312764169, 10553.63062292816, 10712.89287107119, 7094.503384763017, 7372.815679608053, 8618.525981070976, 8867.290616750644, 8871.955640221757, 9296.442213467079, 9623.56693948774, 9625.903740962642, 9701.6119355225, 9704.417734935927, 3856.2094770571966, 3922.8038598069984, 3941.7240793144206, 3951.841926322915, 3956.3669812829808, 895.9507227102441, 913.794396260836, 915.6062892956093, 946.3517490519016, 957.5403806172936, 21560.353189404344, 21647.675284870424, 21745.021474033707, 21746.782532174475, 21821.0180541876, 853.628671527875, 874.7681864305964, 936.4989730494153, 947.8720372749729, 964.1081999150898, 10721.0, 11145.665630031112, 11163.609273675545, 11169.385683787306, 11186.496607652674, 743984.0, 758753.0, 766526.0, 768185.0, 781020.0, 123677.38161309136, 169236.0, 170398.14800925917, 174298.0, 175900.0, 1178.3048001679051, 1225.1301575881225, 1245.0786418050577, 1248.6691026877884, 1270.7776139175608, 18979.481641468927, 19695.507612309233, 20007.399778006682, 20167.59664806725, 20331.706345253628, 52100.436986890454, 62312.688436557524, 63773.0, 68606.65696671484, 69128.0, 81749.36501269822, 82910.58544707237, 83121.675132994, 89054.0, 91340.0, 11412.201145919706, 11670.416479175838, 11813.173077884465, 12060.336681482602, 12118.818217726917, 12978.415971281504, 13000.544980925622, 13304.268265245504, 13347.66630834237, 13443.731125377242, 9509.429434233969, 11321.981021708087, 11322.490487928228, 11324.547018119512, 11599.246049006788, 10171.54228059754, 10286.897131028594, 10423.426711530636, 10604.628837990633, 10739.979701928305, 9571.856422153534, 11384.601538946097, 11685.0, 11751.647450576496, 11795.469203886018, 11683.649490515298, 11694.941525292319, 11916.16586838913, 11990.160688751703, 12009.63971080834, 11205.159613028893, 11267.492962816528, 11463.656090317303, 11533.538658453568, 11567.479463424335], "value": 11671.93321187969, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1317.7232781115965, 1360.8503064662889, 1553.0603984589316, 1555.6733086051936, 1568.5372815019575, 10698.411587362692, 11128.221024528266, 11303.095752339814, 11315.0, 11458.942705286461, 11213.551457941672, 11353.659390218307, 11419.942900285487, 11433.828492572597, 11460.31238125713, 2429.7084349877996, 2455.607102863542, 2459.1638842793445, 2463.051725085846, 2464.6179842124498, 2358.5636657218383, 2409.6144616861307, 2434.47658753368, 2440.670509481224, 2457.987710061447, 2372.9644055339186, 2413.6862207913036, 2420.455397535558, 2426.8665223412704, 2442.2795275393714, 2205.514786746913, 2349.5066036132434, 2397.520495900818, 2416.709994800626, 2422.769836865495, 2304.6197377432873, 2306.7001289832383, 2319.130326127702, 2320.93037208884, 2328.4528135888054, 10872.347659140398, 11276.0, 11277.943610281978, 11279.436028198557, 11283.717907052389, 4985.277134815467, 5733.713314334308, 5917.349091599921, 5935.970320148414, 5936.584439089265, 3585.2829434113282, 3603.6126091454717, 3651.43402772568, 3679.1537946272238, 3730.9253814923823, 1182.3851597169423, 1184.2006645514243, 1207.3963018490754, 1235.1600911380258, 1236.8824961628632, 13904.374303156383, 14387.136771793726, 15638.98346607467, 15783.605409864731, 15790.684186316324, 4273.807678654468, 4478.768338706816, 4601.12578610063, 4734.715917044989, 4757.096151731229, 11806.940965295118, 11815.172937894265, 11858.16992810495, 11865.822012669816, 11880.702982425508, 5770.826875193751, 5775.27809023872, 5775.422457754191, 5777.740001699934, 5796.5072968797285, 5170.121079416508, 5376.784928602812, 5402.216678581583, 5411.323584551931, 5461.2627295315415, 3782.470454136423, 3905.843766249375, 3927.293087244266, 3969.622885825842, 3984.0438294809037, 882.7175303902818, 1042.9582816687248, 1048.3709774135457, 1060.9363438193734, 1065.0946695308926, 926.8321914387919, 933.73855320509, 942.3686130292721, 944.6882528765491, 990.8266053440619, 6432.131662225634, 6495.642739649365, 6591.373819487141, 6681.231658359254, 6743.797686069426, 1032.0866033560203, 1034.7102811212872, 1053.9776416875618, 1061.7080302916759, 1072.201210098466, 3762.2099359134886, 4142.212979533879, 4224.9155016899485, 4251.341042138473, 4331.177076355482, 13359.599212023655, 13827.170369777645, 13891.930540347234, 13909.817665498545, 13932.58202253934, 10572.471376431147, 13621.0, 13817.032807703565, 13823.51617693376, 13834.377453014638, 10063.849042264372, 10352.53413596375, 10364.274500785023, 10380.896191038144, 10407.219458540489, 5503.367112781922, 6496.480281577557, 6733.595984240766, 6775.322467753185, 6977.441804655717, 11985.0, 12269.386530673251, 12418.441170147184, 12508.374581270718, 12656.746865062463, 11534.82697759551, 11837.348945807718, 12010.219335743148, 12890.162139460905, 12897.48410063587, 9163.266938645025, 9308.674196403093, 9388.436693798394, 9450.858237126311, 9476.62588924616, 9954.601815927283, 10265.178785696977, 10326.793464130526, 10425.9478702606, 10426.895731042592, 8877.712731654003, 8924.821503569763, 8929.10708929102, 8958.969718482198, 8968.68609598661, 8614.138586141586, 8643.783905402164, 8701.0, 8716.43343182691, 8865.024847266666, 7814.765557033298, 7864.410169237444, 7864.921350786648, 7879.133295337623, 7951.721689740832, 4819.855404337875, 5850.0932355484965, 5877.118432235199, 6064.4541991221, 6122.938770612237, 5961.7615295387695, 5966.134910437875, 5997.520198384032, 6102.389761023862, 6124.0], "value": 5151.845353478441, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [309.74755574207035, 325.23731848814526, 372.11338513735336, 372.7912369073319, 384.47519136378844, 4150.9245377311345, 4587.79785702862, 5323.4828073998915, 5439.972800135999, 5472.398036216018, 4060.1879624075154, 4742.454617718963, 5218.895622087556, 5259.053370393328, 5308.177232528964, 3780.962190378102, 4197.208856110683, 4344.174606824709, 4385.42989411376, 4443.311286750559, 4298.505671847731, 4412.955870441287, 4413.073254616518, 4446.733196008234, 4450.109978004396, 3798.335291324021, 3918.864219000637, 4142.8757137285775, 4206.221848957937, 4359.28071868143, 3693.2244228712, 3915.3571339345826, 3952.359770695158, 4079.2261262341594, 4334.0, 3433.9484907726405, 3725.4598083277774, 3976.70174736893, 4011.4183443400834, 4018.738781979162, 4759.881002974918, 5138.922916156261, 5147.716875571842, 5160.483951604847, 5242.318498595197, 4281.978590107061, 4649.139909116824, 4761.880952976168, 4852.0213424991525, 5107.948920510785, 4743.0988112258565, 5240.764165612557, 5374.247605335257, 5662.405447428002, 5711.657300561938, 4516.88707782308, 4525.26665500963, 4531.365608814768, 4537.321191159259, 4555.407796986404, 5494.532964698045, 5768.567357448208, 5773.268019594105, 5846.561507886883, 5931.970340148272, 3941.428492868518, 4225.260579398593, 4698.694584851974, 4706.670533062721, 4755.881102972384, 4524.959259370363, 4546.181687296318, 4627.884302892455, 4708.670393072521, 4805.255185446262, 4321.7034889533015, 4498.617617502549, 4549.954500454953, 4592.471865735483, 5107.412647545506, 4722.669413141047, 5536.951328008662, 5747.0, 5749.367569567345, 5782.45066718661, 1407.7817938219591, 1466.8973171877867, 1580.1862041048869, 1585.8581821088799, 1596.7525033619806, 1023.8668973033458, 1110.5280255891375, 1183.1954271095738, 1197.2816310213802, 1228.846394200725, 5014.348134742533, 5200.8959820803375, 5239.318888544541, 5242.423333433319, 5348.57211423093, 5884.3226332019685, 5903.498202652822, 5922.703864806743, 5977.402259773988, 6017.969910150421, 4592.402987611589, 4825.734584597811, 4891.899322652446, 4936.777844996984, 5276.604254680914, 2693.3101650738613, 2911.005960916779, 2913.3539550154096, 3070.6161729783776, 3121.6119668453653, 5372.132064913256, 5807.299605683377, 5850.385709500441, 6028.728707208186, 6231.40801623845, 4960.561437183249, 5715.684658083121, 5835.912461313083, 6141.7543298268465, 6155.476254735537, 5427.891442171055, 5879.970600146972, 6000.819975400745, 6074.782704312894, 6243.687815609293, 5018.322526459027, 5327.893442131213, 5520.06158952987, 5951.55363347742, 6015.759369625166, 5089.898202035864, 5173.611979101658, 5385.165299378603, 5497.47773961473, 5902.763889444546, 5661.264035675501, 5932.733027013881, 6190.2571691397325, 6231.2522497300615, 6241.812745617639, 4197.244495990751, 4215.57844215576, 4363.192809330284, 4605.0, 4651.930221046755, 5126.948730512647, 5141.7686204120155, 5385.3268341457315, 5588.552915766648, 5766.019776638065, 4600.07998400328, 4820.806533027394, 4983.426905905867, 5470.835874923759, 5531.502164805187, 4754.0, 5087.974560127176, 5266.0, 5475.5072043516275, 5766.509846663081, 4834.468208496992, 5096.712620359921, 5347.679139251657, 5427.945720542744, 5547.006155567985, 4851.490593487633, 4927.452234391232, 5492.81904390542, 5720.399358067343, 5735.99620066495, 5342.649234336879, 5378.638271690686, 5548.306461692288, 5672.971635141798, 5678.233438485751], "value": 4272.537097321684, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [695.6174104242665, 704.7639040921288, 763.1529002806886, 766.5860435364904, 851.9020312664042, 15295.311710973005, 15878.920605396957, 16454.177291135424, 16468.588285292873, 16570.66858662829, 14931.925340373311, 15186.620334491616, 15214.467493637725, 15575.143367114833, 15577.610559736013, 939.3518472254123, 959.6737109382808, 960.7982323711994, 962.5764663548051, 964.8600952861831, 899.5187574647565, 951.5622813505796, 953.7949340891707, 966.9999999999965, 967.2117224462041, 981.0287815063108, 990.2078337330144, 994.8010397920444, 995.4873240281224, 995.5719040812447, 806.1978331560106, 839.2237180607913, 985.014985014985, 986.585634033704, 989.9059589339001, 729.4674887332225, 729.482067731911, 729.7664747280872, 730.6310313291807, 731.6670914733784, 15986.360545578164, 16491.928024678356, 16510.504684859472, 16660.916695416567, 16950.23723932426, 14158.646033849132, 14174.007819452643, 14261.465172655793, 14362.569122926328, 14398.568042958623, 11771.352575608338, 11795.528178872835, 11846.526138954432, 12027.03783697302, 12061.517539298418, 3509.4209455439823, 3561.679448849616, 3605.1888325126897, 3617.764845285048, 3754.1740817020213, 97709.0, 119898.4005079969, 122833.0, 123582.9999999991, 126697.09954350692, 10206.591736330463, 10447.373157610416, 10586.57653693859, 11047.668569942754, 11049.558017679363, 21179.576408472054, 21226.36320910375, 21364.14543418277, 21504.892475537523, 21783.891080544498, 5732.914006289909, 6139.355367686418, 7364.858446940732, 7373.741919032808, 7487.812804679817, 4129.8847764143, 4149.294619914622, 4209.0, 4224.957750422518, 4267.359896015622, 3362.327534493111, 3414.0, 3419.7948123112196, 3441.0, 3456.5333679952882, 442.5729171349663, 573.1717667969743, 574.0786038408295, 579.5884921705637, 590.2091197794975, 620.8385819686823, 654.8264709851893, 664.7972368427676, 666.4716179292778, 693.719043787267, 9533.666321678847, 9930.851037234448, 9943.303968722264, 10098.394096354095, 10267.691969240936, 616.7902913009598, 663.4307426047832, 668.8963210702349, 679.8980152977093, 680.4080450008425, 4040.959590404117, 4051.372037334218, 4138.172365526907, 4168.645665118499, 4181.644560212416, 79395.60302198568, 94885.10229795365, 98616.50691746495, 98746.5062674682, 102779.0, 71861.6406917962, 75539.0, 80122.39755204863, 86074.0, 90356.54821725849, 69575.0, 74871.0, 76831.46337073228, 79052.6047369771, 79144.20855791484, 10810.567577297135, 10966.06788422993, 11296.887031129583, 11361.261518001304, 11525.308481491138, 47824.521754783396, 49335.75332123316, 49473.50526494689, 50414.747926260134, 51252.97494050173, 66881.66559167173, 68802.0, 71193.6440317795, 72041.0, 73162.0, 12544.121911466109, 12720.942955189661, 12978.935105324414, 13018.80471792905, 13036.608901732963, 14456.927715361357, 14694.412223510939, 14806.48177313832, 14938.925305373838, 15111.94216404841, 13333.933330333286, 13712.725745484835, 14131.576052718436, 14146.575602731935, 14236.501722439665, 13390.732185356434, 13905.72188556203, 14101.224432656303, 14258.073225240327, 14541.27293635335, 12951.870481295067, 13170.802437963246, 13287.20276783396, 13438.395272212578, 13450.327483625975, 9341.673041443517, 9565.808683826424, 9679.274054445805, 9957.502124893581, 10035.34770239932, 10417.270791044857, 10477.790444191225, 10636.361818290927, 11558.0, 11641.952224299854], "value": 7525.865198926482, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1554.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 120.0, 118.0, 120.0, 118.0, 119.0, 126.0, 138.0, 120.0, 123.0], "value": 120.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 111.0, 129.0, 152.0, 141.0, 138.0, 142.0, 118.0, 131.0, 109.0], "value": 131.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 196.0, 198.0, 199.0, 197.0, 196.0, 277.0, 252.0, 202.0, 197.0], "value": 211.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 60.0, 64.0, 80.0, 64.0, 81.0, 52.0, 69.0, 97.0, 86.0], "value": 73.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 72.0, 85.0, 73.0, 70.0, 95.0, 114.0, 71.0, 71.0, 84.0], "value": 82.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 91.0, 125.0, 137.0, 138.0, 92.0, 93.0, 91.0, 91.0], "value": 104.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 97.0, 98.0, 98.0, 97.0, 97.0, 99.0, 99.0, 97.0, 101.0], "value": 98.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 124.0, 92.0, 90.0, 91.0, 91.0, 107.0, 114.0, 90.0, 92.0], "value": 98.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 78.0, 102.0, 106.0, 86.0, 69.0, 67.0, 69.0, 72.0, 71.0], "value": 79.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 63.0, 57.0, 58.0, 63.0, 61.0, 59.0, 58.0, 58.0, 62.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 89.0, 88.0, 84.0, 87.0, 95.0, 103.0, 83.0, 86.0, 89.0], "value": 89.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 153.0, 133.0, 147.0, 148.0, 143.0, 175.0, 150.0, 204.0, 184.0], "value": 160.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 168.0, 167.0, 169.0, 197.0, 222.0, 192.0, 165.0, 183.0], "value": 179.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 67.0, 66.0, 65.0, 64.0, 65.0, 69.0, 74.0], "value": 66.5, "unit": "score"}]}]} 15:42:23 INFO - Return code: 0 15:42:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:42:23 ERROR - # TBPL SUCCESS # 15:42:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:42:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:42:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:42:23 INFO - 2017-03-27 15:42:23.460 screenresolution[1657:10706] starting screenresolution argv=screenresolution get 15:42:23 INFO - 2017-03-27 15:42:23.463 screenresolution[1657:10706] Display 0: 1600x1200x32@60 15:42:23 INFO - 2017-03-27 15:42:23.469 screenresolution[1658:10709] starting screenresolution argv=screenresolution list 15:42:23 INFO - Available Modes on Display 0 15:42:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:42:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:42:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:42:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:42:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:42:23 INFO - Intel Iris: 15:42:23 INFO - Chipset Model: Intel Iris 15:42:23 INFO - Type: GPU 15:42:23 INFO - Bus: Built-In 15:42:23 INFO - VRAM (Dynamic, Max): 1536 MB 15:42:23 INFO - Vendor: Intel (0x8086) 15:42:23 INFO - Device ID: 0x0a2e 15:42:23 INFO - Revision ID: 0x0009 15:42:23 INFO - Displays: 15:42:23 INFO - Display: 15:42:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:42:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:42:23 INFO - Display Serial Number: 1600x1200 60 15:42:23 INFO - Main Display: Yes 15:42:23 INFO - Mirror: Off 15:42:23 INFO - Online: Yes 15:42:23 INFO - Rotation: Supported 15:42:23 INFO - Return code: 0 15:42:23 INFO - Running post-action listener: _resource_record_post_action 15:42:23 INFO - [mozharness: 2017-03-27 22:42:23.708065Z] Finished run-tests step (success) 15:42:23 INFO - Running post-run listener: _resource_record_post_run 15:42:23 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 44124672; Write bytes: 515512832; Read time: 547; Write time: 1845 15:42:23 INFO - TinderboxPrint: CPU usage
54.5% 15:42:23 INFO - TinderboxPrint: I/O read bytes / time
44,124,672 / 547 15:42:23 INFO - TinderboxPrint: I/O write bytes / time
515,512,832 / 1,845 15:42:23 INFO - TinderboxPrint: CPU idle
2,183.5 (45.5%) 15:42:23 INFO - TinderboxPrint: CPU system
966.9 (20.1%) 15:42:23 INFO - TinderboxPrint: CPU user
1,651.3 (34.4%) 15:42:23 INFO - TinderboxPrint: Swap in / out
252,256,256 / 0 15:42:23 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 200569344; Write bytes: 202749952; Read time: 15573; Write time: 419 15:42:23 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 43162112; Write bytes: 302858752; Read time: 529; Write time: 1391 15:42:24 INFO - Running post-run listener: _upload_blobber_files 15:42:24 INFO - Blob upload gear active. 15:42:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:42:24 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 15:42:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:42:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:42:24 INFO - (blobuploader) - INFO - Open directory for files ... 15:42:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:42:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:42:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:42:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:42:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:42:25 INFO - (blobuploader) - INFO - Done attempting. 15:42:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:42:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:42:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:42:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:42:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:42:26 INFO - (blobuploader) - INFO - Done attempting. 15:42:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:42:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:42:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:42:27 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:42:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:42:27 INFO - (blobuploader) - INFO - Done attempting. 15:42:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:42:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:42:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:42:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:42:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:42:29 INFO - (blobuploader) - INFO - Done attempting. 15:42:29 INFO - (blobuploader) - INFO - Iteration through files over. 15:42:29 INFO - Return code: 0 15:42:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:42:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:42:29 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/273708abd5fe38612517a10aff1817e52f7e7ec74400eaf09d29b86a0ed2f60353372bcd214f6758e1d7a3ab6b84f1967c93b8693c5c575f9c68240144ddfb68", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/65d4df7d5610c7b6d29ec15790a8e9887b46707befb4a5e4f99f6afc4aff241130700158d387f84dd8147f20ae8d5239a111ca22c40a3f2535c570133c5ed1fd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a3b44bcf3dd83474b281ad7ab6914aff62e889b728f2948db75d27137075d35e40fcd5d0d6e808c0b15982f2c51e5be332b6c7633e78b5bbff7f607297300acd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f77e37ac0b04015fd6c8eb6eba90eb74278b08f3825c189cb15c9857dd2e3d05738c93baeaebda361ccca7675499cdfc27ef35a16b5dc7c52a8207619497725d"} 15:42:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:42:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:42:29 INFO - Contents: 15:42:29 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/273708abd5fe38612517a10aff1817e52f7e7ec74400eaf09d29b86a0ed2f60353372bcd214f6758e1d7a3ab6b84f1967c93b8693c5c575f9c68240144ddfb68", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/65d4df7d5610c7b6d29ec15790a8e9887b46707befb4a5e4f99f6afc4aff241130700158d387f84dd8147f20ae8d5239a111ca22c40a3f2535c570133c5ed1fd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a3b44bcf3dd83474b281ad7ab6914aff62e889b728f2948db75d27137075d35e40fcd5d0d6e808c0b15982f2c51e5be332b6c7633e78b5bbff7f607297300acd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f77e37ac0b04015fd6c8eb6eba90eb74278b08f3825c189cb15c9857dd2e3d05738c93baeaebda361ccca7675499cdfc27ef35a16b5dc7c52a8207619497725d"} 15:42:29 INFO - Running post-run listener: copy_logs_to_upload_dir 15:42:29 INFO - Copying logs to upload dir... 15:42:29 INFO - mkdir: /builds/slave/test/build/upload/logs 15:42:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.994553 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-27 15:42:29.880722) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 15:42:29.884073) ========= 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.3gIxzuiGX4/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.OrwSIksOOv/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/273708abd5fe38612517a10aff1817e52f7e7ec74400eaf09d29b86a0ed2f60353372bcd214f6758e1d7a3ab6b84f1967c93b8693c5c575f9c68240144ddfb68", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/65d4df7d5610c7b6d29ec15790a8e9887b46707befb4a5e4f99f6afc4aff241130700158d387f84dd8147f20ae8d5239a111ca22c40a3f2535c570133c5ed1fd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a3b44bcf3dd83474b281ad7ab6914aff62e889b728f2948db75d27137075d35e40fcd5d0d6e808c0b15982f2c51e5be332b6c7633e78b5bbff7f607297300acd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f77e37ac0b04015fd6c8eb6eba90eb74278b08f3825c189cb15c9857dd2e3d05738c93baeaebda361ccca7675499cdfc27ef35a16b5dc7c52a8207619497725d"} build_url:https://queue.taskcluster.net/v1/task/X6ybCyeEQoaxwTyhMSJGUA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011214 build_url: 'https://queue.taskcluster.net/v1/task/X6ybCyeEQoaxwTyhMSJGUA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/273708abd5fe38612517a10aff1817e52f7e7ec74400eaf09d29b86a0ed2f60353372bcd214f6758e1d7a3ab6b84f1967c93b8693c5c575f9c68240144ddfb68", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/65d4df7d5610c7b6d29ec15790a8e9887b46707befb4a5e4f99f6afc4aff241130700158d387f84dd8147f20ae8d5239a111ca22c40a3f2535c570133c5ed1fd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a3b44bcf3dd83474b281ad7ab6914aff62e889b728f2948db75d27137075d35e40fcd5d0d6e808c0b15982f2c51e5be332b6c7633e78b5bbff7f607297300acd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f77e37ac0b04015fd6c8eb6eba90eb74278b08f3825c189cb15c9857dd2e3d05738c93baeaebda361ccca7675499cdfc27ef35a16b5dc7c52a8207619497725d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 15:42:29.918962) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 15:42:29.919281) ========= 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.3gIxzuiGX4/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.OrwSIksOOv/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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.005197 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 15:42:29.968442) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 15:42:29.968918) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 15:42:29.969386) ========= ========= Total master_lag: 0.19 =========