builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0070 starttime: 1490616192.28 results: success (0) buildid: 20170327035616 builduid: 9bf79d2dbc6248f599d3f8a2a0611133 revision: 7ac8812719a11344a4cbcb5b1f6d55b5a68de0e6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 05:03:12.277739) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 05:03:12.278332) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 05:03:12.278699) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 05:03:13.179327) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-27 05:03:13.179813) ========= 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.BDtFuPi0Wl/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.g9l7TO4D1B/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 05:03:13-- 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% 155M=0s 2017-03-27 05:03:15 (155 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=2.238651 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-27 05:03:15.444047) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 05:03:15.444560) ========= 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.BDtFuPi0Wl/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.g9l7TO4D1B/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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.020637 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 05:03:15.496185) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-03-27 05:03:15.496759) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7ac8812719a11344a4cbcb5b1f6d55b5a68de0e6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 7ac8812719a11344a4cbcb5b1f6d55b5a68de0e6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.BDtFuPi0Wl/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.g9l7TO4D1B/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 05:03:15,586 truncating revision to first 12 chars 2017-03-27 05:03:15,586 Setting DEBUG logging. 2017-03-27 05:03:15,586 attempt 1/10 2017-03-27 05:03:15,586 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7ac8812719a1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-27 05:03:18,346 unpacking tar archive at: mozilla-central-7ac8812719a1/testing/mozharness/ program finished with exit code 0 elapsedTime=3.020298 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-03-27 05:03:18.542336) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 05:03:18.542708) ========= script_repo_revision: 7ac8812719a11344a4cbcb5b1f6d55b5a68de0e6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 05:03:18.543142) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 05:03:18.543467) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 05:03:18.563960) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 37 secs) (at 2017-03-27 05:03:18.564375) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.BDtFuPi0Wl/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.g9l7TO4D1B/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 05:03:18 INFO - MultiFileLogger online at 20170327 05:03:18 in /builds/slave/test 05:03:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:03:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:03:18 INFO - {'append_to_log': False, 05:03:18 INFO - 'base_work_dir': '/builds/slave/test', 05:03:18 INFO - 'blob_upload_branch': 'Firefox', 05:03:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:03:18 INFO - 'branch': 'Firefox', 05:03:18 INFO - 'buildbot_json_path': 'buildprops.json', 05:03:18 INFO - 'config_files': ('talos/mac_config.py',), 05:03:18 INFO - 'default_actions': ('clobber', 05:03:18 INFO - 'read-buildbot-config', 05:03:18 INFO - 'download-and-extract', 05:03:18 INFO - 'populate-webroot', 05:03:18 INFO - 'create-virtualenv', 05:03:18 INFO - 'install', 05:03:18 INFO - 'run-tests'), 05:03:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:03:18 INFO - 'download_minidump_stackwalk': True, 05:03:18 INFO - 'download_symbols': 'ondemand', 05:03:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:03:18 INFO - 'tooltool.py': '/tools/tooltool.py', 05:03:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:03:18 INFO - '/tools/misc-python/virtualenv.py')}, 05:03:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:03:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:03:18 INFO - 'gecko_profile': False, 05:03:18 INFO - 'gecko_profile_interval': 0, 05:03:18 INFO - 'installer_path': 'installer.exe', 05:03:18 INFO - 'log_level': 'info', 05:03:18 INFO - 'log_name': 'talos', 05:03:18 INFO - 'log_to_console': True, 05:03:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:03:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:03:18 INFO - 'opt_config_files': (), 05:03:18 INFO - 'pip_index': False, 05:03:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:03:18 INFO - 'cmd': ('bash', 05:03:18 INFO - '-c', 05:03:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:03:18 INFO - 'enabled': True, 05:03:18 INFO - 'halt_on_failure': False, 05:03:18 INFO - 'name': 'check_screen_resolution'},), 05:03:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:03:18 INFO - 'cmd': ('bash', 05:03:18 INFO - '-c', 05:03:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:03:18 INFO - 'enabled': True, 05:03:18 INFO - 'halt_on_failure': False, 05:03:18 INFO - 'name': 'check_screen_resolution'},), 05:03:18 INFO - 'run_cmd_checks_enabled': True, 05:03:18 INFO - 'suite': 'dromaeojs', 05:03:18 INFO - 'system_bits': '32', 05:03:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:03:18 INFO - 'title': 't-yosemite-r7-0070', 05:03:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:03:18 INFO - 'use_talos_json': True, 05:03:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:03:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:03:18 INFO - 'work_dir': 'build'} 05:03:18 INFO - [mozharness: 2017-03-27 12:03:18.779577Z] Running clobber step. 05:03:18 INFO - Running pre-action listener: _resource_record_pre_action 05:03:18 INFO - Running main action method: clobber 05:03:18 INFO - rmtree: /builds/slave/test/build 05:03:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:03:19 INFO - Running post-action listener: _resource_record_post_action 05:03:19 INFO - [mozharness: 2017-03-27 12:03:19.483162Z] Finished clobber step (success) 05:03:19 INFO - [mozharness: 2017-03-27 12:03:19.483433Z] Running read-buildbot-config step. 05:03:19 INFO - Running pre-action listener: _resource_record_pre_action 05:03:19 INFO - Running main action method: read_buildbot_config 05:03:19 INFO - Using buildbot properties: 05:03:19 INFO - { 05:03:19 INFO - "project": "", 05:03:19 INFO - "product": "firefox", 05:03:19 INFO - "script_repo_revision": "production", 05:03:19 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:03:19 INFO - "repository": "", 05:03:19 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:03:19 INFO - "buildid": "20170327035616", 05:03:19 INFO - "pgo_build": "False", 05:03:19 INFO - "basedir": "/builds/slave/test", 05:03:19 INFO - "buildnumber": 16, 05:03:19 INFO - "slavename": "t-yosemite-r7-0070", 05:03:19 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 05:03:19 INFO - "platform": "yosemite_r7", 05:03:19 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:03:19 INFO - "branch": "mozilla-central", 05:03:19 INFO - "repo_path": "mozilla-central", 05:03:19 INFO - "revision": "7ac8812719a11344a4cbcb5b1f6d55b5a68de0e6", 05:03:19 INFO - "stage_platform": "macosx64", 05:03:19 INFO - "builduid": "9bf79d2dbc6248f599d3f8a2a0611133", 05:03:19 INFO - "slavebuilddir": "test" 05:03:19 INFO - } 05:03:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/FTPLurtWSHuX5XvYx52hqA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 05:03:19 INFO - Running post-action listener: _resource_record_post_action 05:03:19 INFO - [mozharness: 2017-03-27 12:03:19.489360Z] Finished read-buildbot-config step (success) 05:03:19 INFO - [mozharness: 2017-03-27 12:03:19.489553Z] Running download-and-extract step. 05:03:19 INFO - Running pre-action listener: _resource_record_pre_action 05:03:19 INFO - Running main action method: download_and_extract 05:03:19 INFO - mkdir: /builds/slave/test/build/tests 05:03:19 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')]} 05:03:19 INFO - trying https://queue.taskcluster.net/v1/task/FTPLurtWSHuX5XvYx52hqA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 05:03:19 INFO - Downloading https://queue.taskcluster.net/v1/task/FTPLurtWSHuX5XvYx52hqA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:03:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FTPLurtWSHuX5XvYx52hqA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 05:03:22 INFO - Downloaded 1325 bytes. 05:03:22 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:03:22 INFO - Using the following test package requirements: 05:03:22 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:03:22 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 05:03:22 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:03:22 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:03:22 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 05:03:22 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:03:22 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 05:03:22 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:03:22 INFO - u'jsshell-mac.zip'], 05:03:22 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:03:22 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 05:03:22 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:03:22 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:03:22 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 05:03:22 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:03:22 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 05:03:22 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:03:22 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 05:03:22 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:03:22 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 05:03:22 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:03:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FTPLurtWSHuX5XvYx52hqA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:03:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FTPLurtWSHuX5XvYx52hqA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:03:22 INFO - Fetch https://queue.taskcluster.net/v1/task/FTPLurtWSHuX5XvYx52hqA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:03:24 INFO - Expected file size: 52411985 05:03:24 INFO - Obtained file size: 52411985 05:03:41 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:03:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FTPLurtWSHuX5XvYx52hqA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:03:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FTPLurtWSHuX5XvYx52hqA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:03:41 INFO - Fetch https://queue.taskcluster.net/v1/task/FTPLurtWSHuX5XvYx52hqA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:03:43 INFO - Expected file size: 52411985 05:03:43 INFO - Obtained file size: 52411985 05:03:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FTPLurtWSHuX5XvYx52hqA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 05:03:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FTPLurtWSHuX5XvYx52hqA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:03:59 INFO - Fetch https://queue.taskcluster.net/v1/task/FTPLurtWSHuX5XvYx52hqA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 05:04:01 INFO - Expected file size: 14404171 05:04:01 INFO - Obtained file size: 14404171 05:04: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')]} 05:04:01 INFO - trying https://queue.taskcluster.net/v1/task/FTPLurtWSHuX5XvYx52hqA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:04:01 INFO - Downloading https://queue.taskcluster.net/v1/task/FTPLurtWSHuX5XvYx52hqA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 05:04:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FTPLurtWSHuX5XvYx52hqA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 05:04:04 INFO - Downloaded 75546890 bytes. 05:04:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FTPLurtWSHuX5XvYx52hqA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:04:04 INFO - mkdir: /builds/slave/test/properties 05:04:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:04:04 INFO - Writing to file /builds/slave/test/properties/build_url 05:04:04 INFO - Contents: 05:04:04 INFO - build_url:https://queue.taskcluster.net/v1/task/FTPLurtWSHuX5XvYx52hqA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:04:05 INFO - Running post-action listener: _resource_record_post_action 05:04:05 INFO - Running post-action listener: set_extra_try_arguments 05:04:05 INFO - [mozharness: 2017-03-27 12:04:05.617973Z] Finished download-and-extract step (success) 05:04:05 INFO - [mozharness: 2017-03-27 12:04:05.618100Z] Running populate-webroot step. 05:04:05 INFO - Running pre-action listener: _resource_record_pre_action 05:04:05 INFO - Running main action method: populate_webroot 05:04:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:04:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:04:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:04:05 INFO - '--tppagecycles', 05:04:05 INFO - '7'], 05:04:05 INFO - 'tests': ['tsvgm']}}, 05:04:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:04:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:04:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:04:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:04:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:04:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:04:05 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:04:05 INFO - 'talos_options': ['--disable-e10s'], 05:04:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:04:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:04:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:04:05 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:04:05 INFO - 'talos_options': ['--disable-e10s'], 05:04:05 INFO - 'tests': ['damp', 'tps']}, 05:04:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:04:05 INFO - 'tests': ['damp', 'tps']}, 05:04:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:04:05 INFO - 'tests': ['dromaeo_dom']}, 05:04:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:04:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:04:05 INFO - 'tests': ['basic_compositor_video']}, 05:04:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:04:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:04:05 INFO - 'tests': ['a11yr', 05:04:05 INFO - 'ts_paint', 05:04:05 INFO - 'tpaint', 05:04:05 INFO - 'sessionrestore', 05:04:05 INFO - 'sessionrestore_no_auto_restore', 05:04:05 INFO - 'tabpaint']}, 05:04:05 INFO - 'other-e10s': {'tests': ['a11yr', 05:04:05 INFO - 'ts_paint', 05:04:05 INFO - 'tpaint', 05:04:05 INFO - 'sessionrestore', 05:04:05 INFO - 'sessionrestore_no_auto_restore', 05:04:05 INFO - 'tabpaint']}, 05:04:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:04:05 INFO - 'ts_paint', 05:04:05 INFO - 'tpaint', 05:04:05 INFO - 'sessionrestore', 05:04:05 INFO - 'sessionrestore_no_auto_restore', 05:04:05 INFO - 'tabpaint']}, 05:04:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:04:05 INFO - 'ts_paint', 05:04:05 INFO - 'tpaint', 05:04:05 INFO - 'sessionrestore', 05:04:05 INFO - 'sessionrestore_no_auto_restore', 05:04:05 INFO - 'tabpaint']}, 05:04:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:04:05 INFO - 'tests': ['a11yr', 05:04:05 INFO - 'ts_paint', 05:04:05 INFO - 'tpaint', 05:04:05 INFO - 'sessionrestore', 05:04:05 INFO - 'sessionrestore_no_auto_restore', 05:04:05 INFO - 'tabpaint']}, 05:04:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:04:05 INFO - 'tests': ['a11yr', 05:04:05 INFO - 'ts_paint', 05:04:05 INFO - 'tpaint', 05:04:05 INFO - 'sessionrestore', 05:04:05 INFO - 'sessionrestore_no_auto_restore', 05:04:05 INFO - 'tabpaint']}, 05:04:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:04:05 INFO - 'tests': ['tsvgx', 05:04:05 INFO - 'tsvgr_opacity', 05:04:05 INFO - 'tart', 05:04:05 INFO - 'tscrollx', 05:04:05 INFO - 'cart', 05:04:05 INFO - 'tsvg_static']}, 05:04:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:04:05 INFO - 'tsvgr_opacity', 05:04:05 INFO - 'tart', 05:04:05 INFO - 'tscrollx', 05:04:05 INFO - 'cart', 05:04:05 INFO - 'tsvg_static']}, 05:04:05 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:04:05 INFO - 'talos_options': ['--disable-e10s'], 05:04:05 INFO - 'tests': ['tp5o']}, 05:04:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:04:05 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:04:05 INFO - 'talos_options': ['--disable-e10s', 05:04:05 INFO - '--xperf_path', 05:04:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:04:05 INFO - 'tests': ['tp5n']}, 05:04:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:04:05 INFO - 'talos_options': ['--xperf_path', 05:04:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:04:05 INFO - 'tests': ['tp5n']}}, 05:04:05 INFO - 'talos.zip': {'path': '', 05:04:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:04:05 INFO - Running post-action listener: _resource_record_post_action 05:04:05 INFO - [mozharness: 2017-03-27 12:04:05.636682Z] Finished populate-webroot step (success) 05:04:05 INFO - [mozharness: 2017-03-27 12:04:05.636813Z] Running create-virtualenv step. 05:04:05 INFO - Running pre-action listener: _resource_record_pre_action 05:04:05 INFO - Running main action method: create_virtualenv 05:04:05 INFO - Creating virtualenv /builds/slave/test/build/venv 05:04:05 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:04:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:04:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:04:05 INFO - Using real prefix '/tools/python27' 05:04:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:04:06 INFO - Installing distribute.............................................................................................................................................................................................done. 05:04:09 INFO - Installing pip.................done. 05:04:09 INFO - Return code: 0 05:04:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:04:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:04:09 INFO - Reading from file tmpfile_stdout 05:04:09 INFO - Output received: 05:04:09 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:04:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:04:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:10 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')]} 05:04:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103180b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103183768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4b85792d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f5ed30>, '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 0x102e85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f53be8>, '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.BDtFuPi0Wl/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.g9l7TO4D1B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 05:04:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:04:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:04:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BDtFuPi0Wl/Render', 05:04:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:11 INFO - 'HOME': '/Users/cltbld', 05:04:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:11 INFO - 'LOGNAME': 'cltbld', 05:04:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:11 INFO - 'MOZ_NO_REMOTE': '1', 05:04:11 INFO - 'NO_EM_RESTART': '1', 05:04:11 INFO - 'PAGER': '/bin/cat', 05:04:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:11 INFO - 'PWD': '/builds/slave/test', 05:04:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:11 INFO - 'SHELL': '/bin/bash', 05:04:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g9l7TO4D1B/Listeners', 05:04:11 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 05:04:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:11 INFO - 'USER': 'cltbld', 05:04:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:11 INFO - 'XPC_FLAGS': '0x0', 05:04:11 INFO - 'XPC_SERVICE_NAME': '0', 05:04:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:11 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 05:04:11 INFO - Cleaning up... 05:04:11 INFO - Return code: 0 05:04:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:04:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103180b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103183768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4b85792d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f5ed30>, '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 0x102e85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f53be8>, '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.BDtFuPi0Wl/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.g9l7TO4D1B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 05:04:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:04:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:04:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BDtFuPi0Wl/Render', 05:04:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:11 INFO - 'HOME': '/Users/cltbld', 05:04:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:11 INFO - 'LOGNAME': 'cltbld', 05:04:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:11 INFO - 'MOZ_NO_REMOTE': '1', 05:04:11 INFO - 'NO_EM_RESTART': '1', 05:04:11 INFO - 'PAGER': '/bin/cat', 05:04:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:11 INFO - 'PWD': '/builds/slave/test', 05:04:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:11 INFO - 'SHELL': '/bin/bash', 05:04:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g9l7TO4D1B/Listeners', 05:04:11 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 05:04:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:11 INFO - 'USER': 'cltbld', 05:04:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:11 INFO - 'XPC_FLAGS': '0x0', 05:04:11 INFO - 'XPC_SERVICE_NAME': '0', 05:04:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:12 INFO - Downloading/unpacking psutil>=3.1.1 05:04:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:04:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:04:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:04:17 INFO - Installing collected packages: psutil 05:04:17 INFO - Running setup.py install for psutil 05:04:17 INFO - building 'psutil._psutil_osx' extension 05:04:17 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 05:04:17 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 05:04:17 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 05:04:17 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 05:04:17 INFO - building 'psutil._psutil_posix' extension 05:04:17 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 05:04:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:04:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:04:17 INFO - ^ 05:04:17 INFO - 1 warning generated. 05:04:17 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 05:04:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:04:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:04:17 INFO - Successfully installed psutil 05:04:17 INFO - Cleaning up... 05:04:17 INFO - Return code: 0 05:04:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:04:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103180b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103183768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4b85792d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f5ed30>, '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 0x102e85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f53be8>, '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.BDtFuPi0Wl/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.g9l7TO4D1B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 05:04:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:04:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:04:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BDtFuPi0Wl/Render', 05:04:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:17 INFO - 'HOME': '/Users/cltbld', 05:04:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:17 INFO - 'LOGNAME': 'cltbld', 05:04:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:17 INFO - 'MOZ_NO_REMOTE': '1', 05:04:17 INFO - 'NO_EM_RESTART': '1', 05:04:17 INFO - 'PAGER': '/bin/cat', 05:04:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:17 INFO - 'PWD': '/builds/slave/test', 05:04:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:17 INFO - 'SHELL': '/bin/bash', 05:04:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g9l7TO4D1B/Listeners', 05:04:17 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 05:04:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:17 INFO - 'USER': 'cltbld', 05:04:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:18 INFO - 'XPC_FLAGS': '0x0', 05:04:18 INFO - 'XPC_SERVICE_NAME': '0', 05:04:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:04: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 05:04: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 05:04: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 05:04: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 05:04: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 05:04: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 05:04:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:04:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:04:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:04:21 INFO - Installing collected packages: mozsystemmonitor 05:04:21 INFO - Running setup.py install for mozsystemmonitor 05:04:21 INFO - Successfully installed mozsystemmonitor 05:04:21 INFO - Cleaning up... 05:04:21 INFO - Return code: 0 05:04:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:04:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103180b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103183768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4b85792d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f5ed30>, '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 0x102e85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f53be8>, '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.BDtFuPi0Wl/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.g9l7TO4D1B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 05:04:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:04:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:04:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BDtFuPi0Wl/Render', 05:04:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:21 INFO - 'HOME': '/Users/cltbld', 05:04:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:21 INFO - 'LOGNAME': 'cltbld', 05:04:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:21 INFO - 'MOZ_NO_REMOTE': '1', 05:04:21 INFO - 'NO_EM_RESTART': '1', 05:04:21 INFO - 'PAGER': '/bin/cat', 05:04:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:21 INFO - 'PWD': '/builds/slave/test', 05:04:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:21 INFO - 'SHELL': '/bin/bash', 05:04:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g9l7TO4D1B/Listeners', 05:04:21 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 05:04:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:21 INFO - 'USER': 'cltbld', 05:04:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:21 INFO - 'XPC_FLAGS': '0x0', 05:04:21 INFO - 'XPC_SERVICE_NAME': '0', 05:04:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:22 INFO - Downloading/unpacking jsonschema==2.5.1 05:04:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:04:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:04:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:25 INFO - Downloading functools32-3.2.3-2.tar.gz 05:04:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:04:25 INFO - warning: no files found matching '*.txt' 05:04:25 INFO - no previously-included directories found matching 'build' 05:04:25 INFO - no previously-included directories found matching 'dist' 05:04:25 INFO - no previously-included directories found matching '.git*' 05:04:25 INFO - Installing collected packages: jsonschema, functools32 05:04:25 INFO - Running setup.py install for functools32 05:04:25 INFO - warning: no files found matching '*.txt' 05:04:25 INFO - no previously-included directories found matching 'build' 05:04:25 INFO - no previously-included directories found matching 'dist' 05:04:25 INFO - no previously-included directories found matching '.git*' 05:04:25 INFO - Successfully installed jsonschema functools32 05:04:25 INFO - Cleaning up... 05:04:26 INFO - Return code: 0 05:04:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:04:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103180b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103183768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4b85792d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f5ed30>, '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 0x102e85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f53be8>, '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.BDtFuPi0Wl/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.g9l7TO4D1B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 05:04:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:04:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:04:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BDtFuPi0Wl/Render', 05:04:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:26 INFO - 'HOME': '/Users/cltbld', 05:04:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:26 INFO - 'LOGNAME': 'cltbld', 05:04:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:26 INFO - 'MOZ_NO_REMOTE': '1', 05:04:26 INFO - 'NO_EM_RESTART': '1', 05:04:26 INFO - 'PAGER': '/bin/cat', 05:04:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:26 INFO - 'PWD': '/builds/slave/test', 05:04:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:26 INFO - 'SHELL': '/bin/bash', 05:04:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g9l7TO4D1B/Listeners', 05:04:26 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 05:04:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:26 INFO - 'USER': 'cltbld', 05:04:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:26 INFO - 'XPC_FLAGS': '0x0', 05:04:26 INFO - 'XPC_SERVICE_NAME': '0', 05:04:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:04:26 INFO - Cleaning up... 05:04:26 INFO - Return code: 0 05:04:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:04:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103180b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103183768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4b85792d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f5ed30>, '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 0x102e85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f53be8>, '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.BDtFuPi0Wl/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.g9l7TO4D1B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 05:04:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:04:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:04:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BDtFuPi0Wl/Render', 05:04:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:26 INFO - 'HOME': '/Users/cltbld', 05:04:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:26 INFO - 'LOGNAME': 'cltbld', 05:04:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:26 INFO - 'MOZ_NO_REMOTE': '1', 05:04:26 INFO - 'NO_EM_RESTART': '1', 05:04:26 INFO - 'PAGER': '/bin/cat', 05:04:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:26 INFO - 'PWD': '/builds/slave/test', 05:04:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:26 INFO - 'SHELL': '/bin/bash', 05:04:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g9l7TO4D1B/Listeners', 05:04:26 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 05:04:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:26 INFO - 'USER': 'cltbld', 05:04:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:26 INFO - 'XPC_FLAGS': '0x0', 05:04:26 INFO - 'XPC_SERVICE_NAME': '0', 05:04:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:26 INFO - Downloading/unpacking blobuploader==1.2.4 05:04:26 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 05:04:26 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 05:04:26 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 05:04:26 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 05:04:26 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 05:04:26 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 05:04:31 INFO - Downloading blobuploader-1.2.4.tar.gz 05:04:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:04:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:04:31 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 05:04:31 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 05:04:31 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 05:04:31 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 05:04:31 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 05:04:31 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 05:04:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:04:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:04:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:32 INFO - Downloading docopt-0.6.1.tar.gz 05:04:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:04:32 INFO - Installing collected packages: blobuploader, requests, docopt 05:04:32 INFO - Running setup.py install for blobuploader 05:04:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:04:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:04:32 INFO - Running setup.py install for requests 05:04:33 INFO - Running setup.py install for docopt 05:04:33 INFO - Successfully installed blobuploader requests docopt 05:04:33 INFO - Cleaning up... 05:04:33 INFO - Return code: 0 05:04:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:04:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:33 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 0x103180b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103183768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4b85792d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f5ed30>, '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 0x102e85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f53be8>, '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.BDtFuPi0Wl/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.g9l7TO4D1B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 05:04:33 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 05:04:33 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 05:04:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BDtFuPi0Wl/Render', 05:04:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:33 INFO - 'HOME': '/Users/cltbld', 05:04:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:33 INFO - 'LOGNAME': 'cltbld', 05:04:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:33 INFO - 'MOZ_NO_REMOTE': '1', 05:04:33 INFO - 'NO_EM_RESTART': '1', 05:04:33 INFO - 'PAGER': '/bin/cat', 05:04:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:33 INFO - 'PWD': '/builds/slave/test', 05:04:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:33 INFO - 'SHELL': '/bin/bash', 05:04:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g9l7TO4D1B/Listeners', 05:04:33 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 05:04:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:33 INFO - 'USER': 'cltbld', 05:04:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:33 INFO - 'XPC_FLAGS': '0x0', 05:04:33 INFO - 'XPC_SERVICE_NAME': '0', 05:04:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:04:33 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-_2lnqg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:04:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:04:34 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-mfBIaT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:04:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:04:34 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-SF32Qx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:04:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:04:34 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-GU8KkV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:04:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:04:34 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-_6hsnR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:04:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:04:34 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-OApyTa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:04:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:04:34 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-N7tJqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:04:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:04:34 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-4ZFLrQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:04:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:04:34 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-i7EnMr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:04:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:04:34 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-YNYNYm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:04:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:04:34 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-In0N73-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:04:35 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-hsYdde-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:04:35 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-EMPTmN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:04:35 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-AQ_i0H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:35 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-ScotHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:04:35 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-iLtIj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:04:35 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-QKt6RF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:04:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:04:35 INFO - Running setup.py install for manifestparser 05:04:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:04:35 INFO - Running setup.py install for mozcrash 05:04:36 INFO - Running setup.py install for mozdebug 05:04:36 INFO - Running setup.py install for mozdevice 05:04:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:04:36 INFO - Running setup.py install for mozfile 05:04:36 INFO - Running setup.py install for mozhttpd 05:04:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:04:36 INFO - Running setup.py install for mozinfo 05:04:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:04:36 INFO - Running setup.py install for mozInstall 05:04:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:04:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:04:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:04:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:04:36 INFO - Running setup.py install for mozleak 05:04:37 INFO - Running setup.py install for mozlog 05:04:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:04:37 INFO - Running setup.py install for moznetwork 05:04:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:04:37 INFO - Running setup.py install for mozprocess 05:04:37 INFO - Running setup.py install for mozprofile 05:04:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:04:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:04:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:04:37 INFO - Running setup.py install for mozrunner 05:04:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:04:37 INFO - Running setup.py install for mozscreenshot 05:04:38 INFO - Running setup.py install for moztest 05:04:38 INFO - Running setup.py install for mozversion 05:04:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:04:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:04:38 INFO - Cleaning up... 05:04:38 INFO - Return code: 0 05:04:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:04:38 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')]} 05:04:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:38 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')]} 05:04:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:38 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 0x103180b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103183768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4b85792d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f5ed30>, '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 0x102e85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f53be8>, '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.BDtFuPi0Wl/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.g9l7TO4D1B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 05:04:38 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 05:04:38 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 05:04:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BDtFuPi0Wl/Render', 05:04:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:38 INFO - 'HOME': '/Users/cltbld', 05:04:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:38 INFO - 'LOGNAME': 'cltbld', 05:04:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:38 INFO - 'MOZ_NO_REMOTE': '1', 05:04:38 INFO - 'NO_EM_RESTART': '1', 05:04:38 INFO - 'PAGER': '/bin/cat', 05:04:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:38 INFO - 'PWD': '/builds/slave/test', 05:04:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:38 INFO - 'SHELL': '/bin/bash', 05:04:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g9l7TO4D1B/Listeners', 05:04:38 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 05:04:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:38 INFO - 'USER': 'cltbld', 05:04:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:38 INFO - 'XPC_FLAGS': '0x0', 05:04:38 INFO - 'XPC_SERVICE_NAME': '0', 05:04:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:04:38 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-fMX5gU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:04:38 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)) 05:04:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:04:38 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-otLJIb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:04:38 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)) 05:04:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:04:38 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-wXR2q0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:04:38 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)) 05:04:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:04:38 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-JQYVh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:04:39 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)) 05:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:04:39 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-y6kBk1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:04:39 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)) 05:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:04:39 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-kB2TWY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:04:39 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)) 05:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:04:39 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-rvpXF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:04:39 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)) 05:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:04:39 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-JYovbd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:04:39 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)) 05:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:04:39 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-hoYCO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:04:39 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)) 05:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:04:39 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-wEeUkg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:04:39 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)) 05:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:04:39 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-YU7o54-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:04:39 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)) 05:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:04:39 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-9_bgJv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:04:39 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)) 05:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:04:39 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-Yr_Sf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:04:40 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)) 05:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:04:40 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-swYr2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:04:40 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)) 05:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:40 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-2Q_Lgj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:40 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)) 05:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:04:40 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-WSnLMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:04:40 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)) 05:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:04:40 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-DQ0jN1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:04:40 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)) 05:04:40 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)) 05:04:40 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)) 05:04:40 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)) 05:04:40 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)) 05:04:40 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)) 05:04:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:04:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:44 INFO - Downloading blessings-1.6.tar.gz 05:04:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:04:44 INFO - Installing collected packages: blessings 05:04:44 INFO - Running setup.py install for blessings 05:04:44 INFO - Successfully installed blessings 05:04:44 INFO - Cleaning up... 05:04:44 INFO - Return code: 0 05:04:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:04:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:04:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:04:44 INFO - Reading from file tmpfile_stdout 05:04:44 INFO - Current package versions: 05:04:44 INFO - blessings == 1.6 05:04:44 INFO - blobuploader == 1.2.4 05:04:44 INFO - docopt == 0.6.1 05:04:44 INFO - functools32 == 3.2.3-2 05:04:44 INFO - jsonschema == 2.5.1 05:04:44 INFO - manifestparser == 1.1 05:04:44 INFO - mozInstall == 1.12 05:04:44 INFO - mozcrash == 1.0 05:04:44 INFO - mozdebug == 0.1 05:04:44 INFO - mozdevice == 0.49 05:04:44 INFO - mozfile == 1.2 05:04:44 INFO - mozhttpd == 0.7 05:04:44 INFO - mozinfo == 0.9 05:04:44 INFO - mozleak == 0.1 05:04:44 INFO - mozlog == 3.4 05:04:44 INFO - moznetwork == 0.27 05:04:44 INFO - mozprocess == 0.25 05:04:44 INFO - mozprofile == 0.28 05:04:44 INFO - mozrunner == 6.13 05:04:44 INFO - mozscreenshot == 0.1 05:04:44 INFO - mozsystemmonitor == 0.3 05:04:44 INFO - moztest == 0.8 05:04:44 INFO - mozversion == 1.4 05:04:44 INFO - psutil == 3.1.1 05:04:44 INFO - requests == 1.2.3 05:04:44 INFO - wsgiref == 0.1.2 05:04:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:04:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:44 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 0x103180b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103183768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4b85792d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f5ed30>, '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 0x102e85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f53be8>, '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.BDtFuPi0Wl/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.g9l7TO4D1B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 05:04:44 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 05:04:44 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 05:04:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BDtFuPi0Wl/Render', 05:04:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:44 INFO - 'HOME': '/Users/cltbld', 05:04:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:44 INFO - 'LOGNAME': 'cltbld', 05:04:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:44 INFO - 'MOZ_NO_REMOTE': '1', 05:04:44 INFO - 'NO_EM_RESTART': '1', 05:04:44 INFO - 'PAGER': '/bin/cat', 05:04:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:44 INFO - 'PWD': '/builds/slave/test', 05:04:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:44 INFO - 'SHELL': '/bin/bash', 05:04:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g9l7TO4D1B/Listeners', 05:04:44 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 05:04:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:44 INFO - 'USER': 'cltbld', 05:04:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:44 INFO - 'XPC_FLAGS': '0x0', 05:04:44 INFO - 'XPC_SERVICE_NAME': '0', 05:04:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:45 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)) 05:04:45 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)) 05:04:45 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)) 05:04:45 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)) 05:04:45 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)) 05:04:45 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)) 05:04:45 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)) 05:04:45 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)) 05:04:45 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)) 05:04:45 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)) 05:04:45 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)) 05:04:45 INFO - Cleaning up... 05:04:45 INFO - Return code: 0 05:04:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:04:45 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')]} 05:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:45 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')]} 05:04:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:45 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 0x103180b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103183768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4b85792d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f5ed30>, '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 0x102e85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f53be8>, '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.BDtFuPi0Wl/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.g9l7TO4D1B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 05:04:45 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 05:04:45 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 05:04:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BDtFuPi0Wl/Render', 05:04:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:45 INFO - 'HOME': '/Users/cltbld', 05:04:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:45 INFO - 'LOGNAME': 'cltbld', 05:04:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:45 INFO - 'MOZ_NO_REMOTE': '1', 05:04:45 INFO - 'NO_EM_RESTART': '1', 05:04:45 INFO - 'PAGER': '/bin/cat', 05:04:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:45 INFO - 'PWD': '/builds/slave/test', 05:04:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:45 INFO - 'SHELL': '/bin/bash', 05:04:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g9l7TO4D1B/Listeners', 05:04:45 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 05:04:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:45 INFO - 'USER': 'cltbld', 05:04:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:45 INFO - 'XPC_FLAGS': '0x0', 05:04:45 INFO - 'XPC_SERVICE_NAME': '0', 05:04:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:04:45 INFO - Cleaning up... 05:04:45 INFO - Return code: 0 05:04:45 INFO - Running post-action listener: _resource_record_post_action 05:04:45 INFO - Running post-action listener: _start_resource_monitoring 05:04:45 INFO - Starting resource monitoring. 05:04:45 INFO - [mozharness: 2017-03-27 12:04:45.421707Z] Finished create-virtualenv step (success) 05:04:45 INFO - [mozharness: 2017-03-27 12:04:45.422691Z] Running install step. 05:04:45 INFO - Running pre-action listener: _resource_record_pre_action 05:04:45 INFO - Running main action method: install 05:04:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:04:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:04:45 INFO - Reading from file tmpfile_stdout 05:04:45 INFO - Detecting whether we're running mozinstall >=1.0... 05:04:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:04:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:04:45 INFO - Reading from file tmpfile_stdout 05:04:45 INFO - Output received: 05:04:45 INFO - Usage: mozinstall [options] installer 05:04:45 INFO - Options: 05:04:45 INFO - -h, --help show this help message and exit 05:04:45 INFO - -d DEST, --destination=DEST 05:04:45 INFO - Directory to install application into. [default: 05:04:45 INFO - "/builds/slave/test"] 05:04:45 INFO - --app=APP Application being installed. [default: firefox] 05:04:45 INFO - mkdir: /builds/slave/test/build/application 05:04:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:04:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:05:06 INFO - Reading from file tmpfile_stdout 05:05:06 INFO - Output received: 05:05:06 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:05:06 INFO - Running post-action listener: _resource_record_post_action 05:05:06 INFO - [mozharness: 2017-03-27 12:05:06.181248Z] Finished install step (success) 05:05:06 INFO - [mozharness: 2017-03-27 12:05:06.181381Z] Running run-tests step. 05:05:06 INFO - Running pre-action listener: _resource_record_pre_action 05:05:06 INFO - Running main action method: run_tests 05:05:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:05:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:05:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:05:06 INFO - 2017-03-27 05:05:06.196 screenresolution[6613:121662] starting screenresolution argv=screenresolution get 05:05:06 INFO - 2017-03-27 05:05:06.199 screenresolution[6613:121662] Display 0: 1600x1200x32@60 05:05:06 INFO - 2017-03-27 05:05:06.204 screenresolution[6614:121665] starting screenresolution argv=screenresolution list 05:05:06 INFO - Available Modes on Display 0 05:05:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:05:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:05:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:05:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:05:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:05:06 INFO - Intel Iris: 05:05:06 INFO - Chipset Model: Intel Iris 05:05:06 INFO - Type: GPU 05:05:06 INFO - Bus: Built-In 05:05:06 INFO - VRAM (Dynamic, Max): 1536 MB 05:05:06 INFO - Vendor: Intel (0x8086) 05:05:06 INFO - Device ID: 0x0a2e 05:05:06 INFO - Revision ID: 0x0009 05:05:06 INFO - Displays: 05:05:06 INFO - Display: 05:05:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:05:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:05:06 INFO - Display Serial Number: 1600x1200 60 05:05:06 INFO - Main Display: Yes 05:05:06 INFO - Mirror: Off 05:05:06 INFO - Online: Yes 05:05:06 INFO - Rotation: Supported 05:05:06 INFO - Return code: 0 05:05:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:05:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:05:06 INFO - Python 2.7.3 05:05:06 INFO - Return code: 0 05:05:06 INFO - grabbing minidump binary from tooltool 05:05: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')]} 05:05:06 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 0x102f5ed30>, '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 0x102e85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f53be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:05:06 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 05:05:06 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 05:05:06 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 05:05:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:05:06 INFO - Return code: 0 05:05:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:05:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:05:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:05:06 INFO - ENV: RUST_BACKTRACE is now 1 05:05:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:05:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:05:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FTPLurtWSHuX5XvYx52hqA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0070', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:05:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FTPLurtWSHuX5XvYx52hqA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0070 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:05:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BDtFuPi0Wl/Render', 05:05:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:06 INFO - 'HOME': '/Users/cltbld', 05:05:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:06 INFO - 'LOGNAME': 'cltbld', 05:05:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:05:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:05:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:06 INFO - 'MOZ_NO_REMOTE': '1', 05:05:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:05:06 INFO - 'NO_EM_RESTART': '1', 05:05:06 INFO - 'PAGER': '/bin/cat', 05:05:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:06 INFO - 'PWD': '/builds/slave/test', 05:05:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:05:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:06 INFO - 'RUST_BACKTRACE': '1', 05:05:06 INFO - 'SHELL': '/bin/bash', 05:05:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g9l7TO4D1B/Listeners', 05:05:06 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 05:05:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:06 INFO - 'USER': 'cltbld', 05:05:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:06 INFO - 'XPC_FLAGS': '0x0', 05:05:06 INFO - 'XPC_SERVICE_NAME': '0', 05:05:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FTPLurtWSHuX5XvYx52hqA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0070', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:05:06 INFO - mozversion application_buildid: 20170327035616 05:05:06 INFO - mozversion application_changeset: 7ac8812719a11344a4cbcb5b1f6d55b5a68de0e6 05:05:06 INFO - mozversion application_display_name: Nightly 05:05:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:05:06 INFO - mozversion application_name: Firefox 05:05:06 INFO - mozversion application_remotingname: firefox 05:05:06 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:05:06 INFO - mozversion application_vendor: Mozilla 05:05:06 INFO - mozversion application_version: 55.0a1 05:05:06 INFO - mozversion platform_buildid: 20170327035616 05:05:06 INFO - mozversion platform_changeset: 7ac8812719a11344a4cbcb5b1f6d55b5a68de0e6 05:05:06 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:05:06 INFO - mozversion platform_version: 55.0a1 05:05:06 INFO - using testdate: 1490616306 05:05:06 INFO - actual date: 1490616306 05:05:06 INFO - starting webserver on 'localhost:58091' 05:05:06 INFO - SUITE-START | Running 2 tests 05:05:06 INFO - TEST-START | dromaeo_css 05:05:06 INFO - operating with platform_type : mac_ 05:05:06 INFO - Initialising browser for dromaeo_css test... 05:05:06 INFO - TEST-INFO | started process 6624 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmp8bUpuO/profile http://localhost:58091/getInfo.html) 05:05:09 INFO - PID 6624 | __metrics Screen width/height:1600/1200 05:05:09 INFO - PID 6624 | colorDepth:24 05:05:09 INFO - PID 6624 | Browser inner width/height: 1024/689 05:05:09 INFO - PID 6624 | __metrics 05:05:09 ERROR - PID 6624 | 1490616309401 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 05:05:09 INFO - PID 6624 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:05:09 INFO - PID 6624 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:05:10 INFO - TEST-INFO | 6624: exit 0 05:05:10 INFO - Browser initialized. 05:05:10 INFO - Running cycle 1/1 for dromaeo_css test... 05:05:10 INFO - TEST-INFO | started process 6627 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmp8bUpuO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:05:22 INFO - PID 6627 | RSS: Main: 233553920 05:05:22 INFO - PID 6627 | 05:08:28 INFO - PID 6627 | Cycle 1(1): loaded http://localhost:58091/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58091/tests/dromaeo/cssquery-ext.html) 05:08:30 INFO - PID 6627 | RSS: Main: 385175552 05:08:30 INFO - PID 6627 | 05:10:12 INFO - PID 6627 | ************************* 05:10:12 INFO - PID 6627 | A coding exception was thrown and uncaught in a Task. 05:10:12 INFO - PID 6627 | 05:10:12 ERROR - PID 6627 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:10:12 INFO - PID 6627 | Full stack: 05:10:12 INFO - PID 6627 | ************************* 05:10:12 INFO - PID 6627 | ************************* 05:10:12 INFO - PID 6627 | A coding exception was thrown and uncaught in a Task. 05:10:12 INFO - PID 6627 | 05:10:12 ERROR - PID 6627 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:10:12 INFO - PID 6627 | Full stack: 05:10:12 INFO - PID 6627 | ************************* 05:10:12 INFO - PID 6627 | ************************* 05:10:12 INFO - PID 6627 | A coding exception was thrown and uncaught in a Task. 05:10:12 INFO - PID 6627 | 05:10:12 ERROR - PID 6627 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:10:12 INFO - PID 6627 | Full stack: 05:10:12 INFO - PID 6627 | ************************* 05:10:12 INFO - PID 6627 | ************************* 05:10:12 INFO - PID 6627 | A coding exception was thrown and uncaught in a Task. 05:10:12 INFO - PID 6627 | 05:10:12 ERROR - PID 6627 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:10:12 INFO - PID 6627 | Full stack: 05:10:12 INFO - PID 6627 | ************************* 05:11:33 INFO - PID 6627 | Cycle 1(1): loaded http://localhost:58091/tests/dromaeo/cssquery-ext.html (next: http://localhost:58091/tests/dromaeo/cssquery-jquery.html) 05:11:33 INFO - PID 6627 | RSS: Main: 369676288 05:11:33 INFO - PID 6627 | 05:14:36 INFO - PID 6627 | Cycle 1(1): loaded http://localhost:58091/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58091/tests/dromaeo/cssquery-mootools.html) 05:14:36 INFO - PID 6627 | RSS: Main: 354877440 05:14:36 INFO - PID 6627 | 05:17:39 INFO - PID 6627 | Cycle 1(1): loaded http://localhost:58091/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58091/tests/dromaeo/cssquery-prototype.html) 05:17:40 INFO - PID 6627 | RSS: Main: 338071552 05:17:40 INFO - PID 6627 | 05:20:43 INFO - PID 6627 | Cycle 1(1): loaded http://localhost:58091/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58091/tests/dromaeo/cssquery-yui.html) 05:20:44 INFO - PID 6627 | RSS: Main: 336797696 05:20:44 INFO - PID 6627 | 05:23:47 INFO - PID 6627 | Cycle 1(1): loaded http://localhost:58091/tests/dromaeo/cssquery-yui.html (next: http://localhost:58091/tests/dromaeo/cssquery-dojo.html) 05:23:47 INFO - PID 6627 | RSS: Main: 372367360 05:23:47 INFO - PID 6627 | 05:23:47 INFO - PID 6627 | __start_tp_report 05:23:47 INFO - PID 6627 | _x_x_mozilla_page_load 05:23:47 INFO - PID 6627 | _x_x_mozilla_page_load_details 05:23:47 INFO - PID 6627 | |i|pagename|runs| 05:23:47 INFO - PID 6627 | |0;dojo.html;3210.2134976930647;3384.306217225469;3406.5522153084935;3423.733218709078;3442.6041005284383;4739.5023522530155;6013.579049466539;6054.273487181534;6066.1810655561485;6187.969060154693;5988.580799344048;6051.697415129237;6078.726457309421;6082.300535438426;6089.238845144357;4292.463442069741;5350.8394748157425;5380.973095134538;5457.808976685817;5475.123980163175;5519.917201241985;5532.75102620383;5543.805966791163;5586.469285417899;5592.0773072443;5578.995780759462;5579.832605021856;5583.609147359686;5584.553235741131;5587.636803607773;5538.806141785059;5575.972120139414;5576.107822748381;5584.301962254718;5587.55299576037;5371.113766228567;5460.6177567570285;5474.835754927359;5488.670679759187;5569.220309156722;6189.2572891252885;6198.659073750942;6199.411055949723;6203.689815509207;6204.13142160098;6284.18305620271;6495.6752162391695;6496.350364963466;6510.381513756186;6554.3117972613145;5276.393214780311;6019.264388304252;6256.81120587087;6450.870982580323;6495.577787443801;5336.626436149471;6163.660998645073;6187.84530386735;6253.749850005949;6254.87490250197;6786.219584747719;6788.456923446112;6790.5925644461495;6794.93205067943;6807.353301436356;5747.051736463521;6202.162708034448;6246.500279977591;6450.903236451457;6459.580127291711;6308.369163083655;6321.304656487782;6509.414152726277;6529.444997175198;6679.998000299896;6546.050822630692;6556.639384833785;6560;6564.277929427759;6569.737210511622;6462.3537646235;6546.410823025951;6549.803505894831;6560.803175904731;6572.802815915435;5192.9740351298005;5298.761555730001;5304.92042619361;5317.787288508416;5334.546563542142;4690.835820746326;4704.153252414599;4712.104700106968;4742.715437073786;4760.976195119002;6148.231471066117;6158.784442544489;6162.291336496271;6165.815025549241;6165.938340616536;6308.558400911892;6401.4238718514625;6533.705983230766;6558.081868538408;6591.934080659228;6139.216166136355;6141.117811339906;6143.815685529441;6151.2618485781995;6159.476444502178;5786.07422812348;5820;5851.502622277154;5854.975379308604;5863.501602363762;6454.870902581828;6548.148740663683;6632.303608121078;6725.260221375743;6729.831754206184;5608.551315894801;6502.252240992158;6554.672266386567;6622.735090596322;6634.237062737654;6688.765893193715;6711.228208756055;6713.160854893138;6713.429358504582;6719.697613607302;5403.5136837684795;6132.41742034506;6443.7422503101225;6501.2523559791225;6508.056331831953;6336.651484168231;6399.072134540559;6425.1968503937005;6434.64609446485;6475.190601174853;6360.586561873463;6363.5227357947415;6374.26695929974;6382.712777925098;6383.39357761012;6145.907811382922;6189.288231853395;6263.498920086291;6305.8108256752375;6353.7140828661895;6256.843578910563;6257.78097766576;6261.843453913689;6278.780242691484;6280.026595877646;6217.906731398942;6264;6271.216097987752;6359.8092057238355;6372.490200784019;6223.502119830493;6239.812805615838;6266.310705822447;6267;6272.40412160844 05:23:47 INFO - PID 6627 | |1;ext.html;748.8352562436265;788.7042359115331;809.5587904591997;1135.2393896089613;1151.0446329546478;27921.581176282347;36717.265654686926;37296.62703372969;37299;37344.813275933586;39066;39334.819955401385;39548.638365738945;39549.81350559481;39553.802230988804;6038.215032045828;6185.134081228632;8390.874136887951;8563.314934805201;8577.656893724243;6167;6252.437280644741;6263;6272.278687950876;8374.58127093646;8567.914320856775;8568.914310856906;8635.309175265995;8637.697680581181;8661.566921653925;7385.815354616124;8520.40357174998;8613.526256055915;8620.008698999602;8636.697715579987;8394.958025209835;8467.618957146944;8481.236688697985;8483.830323393497;8494.320454363637;34278.82860585681;37501.31244094022;39054.60945390538;39368.01579960495;39383;3007.6090108286003;3019.124453908364;3031.602461182512;3041.376517813843;3048.3446059097178;27998.880044798185;28490.86036558535;28579.714202858122;28660.856695716597;29604.8519757402;2424.260600516842;2752.4357506711076;2806.5509518476947;2815.0569559197693;2866.498362786504;327304;330954;331167;331569;331706.3414682935;49989.750256243155;50122.749386252835;50135.74932125316;50157.74921125371;50229.24197653065;52726.94546109057;52785.7360713194;52807.98324159199;52850.18647948722;52871.73564132232;14375.28123593816;14823.703525929637;14833.258337083103;14835.480758173411;14857.405703771945;9970.800583988279;12055.638330850088;12060.216085954386;12085.637430877088;12114.273143611412;11145.275557088764;11218.943905280421;11238.325700458;13012.47950082005;13259.469621215236;4656.278276867091;4707.176244157259;4710.505396933341;5147.639665223398;5344.9732751336;942.8491441369348;973.1193270090579;980.9705708828747;984.3364713633936;990.4305024610877;24185.637215441784;24518.01927922899;24521.509569808502;24550;24588.631170532455;1063.9042486176281;1091.252492042953;1116.1405717597404;1118.848955391028;1133.9603113890973;11649.41752912351;11782.234154779911;12308.953738932112;12460.501579936883;12486;450498;453138;453531;453898;454253;56679.14981275284;56753;56766;56813.43186568164;57122;1444.9349779259749;1460.5545308680742;1500.4973333933037;1506.374854435419;1524.3978628441703;22395.552088957804;23948.760512394652;28157.71842281551;28190.85904570464;28918.987835425658;47766.52233477621;49924.75188120326;51728.48271517387;51772;51924.74037629788;348877;349325;349374;349506;350328;32875.34249315048;32894.835525822215;32947;32961.83519082389;34235.65764342325;43017.78491107524;43301.56698432975;43381.3492797602;44762.55237447584;44822;29315.12054638364;30260;30353;30375.544366834958;30632.387352252383;29522.770195187648;29545.704542954296;30211.09366719002;30267.848660757438;30730.005099336944;29136.854315728287;36476;36828.81585592055;36894.81552592327;37041.25917481689;22695.546089077794;29866.850665747403;30437.695623043484;31105;31262.53106203454;22489.77510224877;23955.161569345688;24246.75753242445;24294.392640184138;24424.389390265387 05:23:47 INFO - PID 6627 | |2;jquery.html;359.4141549274682;391.38747859599744;392.86642541535883;418.5960548071111;448.16641047651365;13286.069975101735;19401.417957461283;21452.249171279007;22399.776002239974;22413.103475860968;21818.563628727417;22149.446263843412;22254.99852506637;22296.554068918613;22320.441988950286;4900.901981960359;5848.444397782215;6265.530085243602;6349.841253968664;6362.268339140988;6112.083187521863;6223.066540018988;6279.089532017854;6280.842978925518;6291.968540157292;6023.18686977259;6248.031555108965;6265.154204182445;6274.937250627504;6276.089966954789;6074.362191969868;6143.1092491588915;6170.6297622142365;6174.629522228658;6224.097505861648;5492.340919089735;5521.696306703129;5526.171074338881;5529.115341545334;5535.972320138373;26402.339941501425;26533;27253.727462725317;27421.17736467909;27798;1926.9325573604856;1938.5928954919484;1973.2107157137116;1976.9505762355911;2050.0979568989746;15228.619284517865;16047.27787249565;16291.674166516721;16329.510114696579;16466.588335291715;1511.4332125452954;1638.189096397291;1668.6329007618426;1689.6113893804493;1695.4998275508754;155145;160356.99107522288;161896.19051904784;164640.17679911642;165727.9999999988;11656.552124053875;11709.824352634718;11902.226355286879;11936.462859171359;11978.25043978895;16045.037297761939;16054.598635034217;16073.517794466185;16088.75866861998;16102.516924492284;8157.918420815716;8381.790455238546;8390.748277551684;8447.95776021116;8509.574521274035;7726.34326082278;7767.18444563324;7946.7615971520945;8021.358291336679;8033;7422;7545.811354716176;7586;7612.3148916597775;7615.581143037186;1742.8954262744276;2067.6071546406133;2068.469332693796;2075.8339332853334;2232.475368288451;878.8505953987838;896.7085697148453;896.8475359188953;915.1269489965958;930.5626355612986;20004.89997550032;20030.298939537046;20205;20259.392218233475;20371;825.2284114353099;904.6200595749801;928.3223247029706;960.2653969713175;966.7099870039101;9260.39807412516;10154.949225253826;10496.370217786956;10558.788824223473;10864.456777161111;414637;514704;577357;577500;628322;113509;130091;163052;164011;169098;1177.3406892140429;1221.6518292286655;1254.899608031337;1257.6541451100838;1259.6913756129807;18421.631567368844;20000.399988000383;20068.59862802765;22327;22408.215712449986;57120.71439642775;60294.69852650708;64535.67732161309;65784.34215657783;66020.33979660142;80822.59588702019;82752.17247827444;83871.58064209639;85922;87165;11891.881081189078;12123.333216673167;12432.937835310766;12741.235525868478;12840.678983025498;13529;13556.051076424556;13810.171389716648;14008.719825603635;14066.015378923377;11638.88361116378;11651.242669226813;11851.288922664668;12003.219790713576;12072.698182545506;11031.338119712844;11116.722081948015;11305.43472826339;11372.757699786544;11408.258463199865;11722;11905.16663833558;11971.221870578385;12052;12097.33464659417;12198.634040978784;12274.693132671755;12463.31451770125;12533.623991280276;12803.487860485477;11533.480993355486;11760.88239117598;11994.220375675553;12010.63968080959;12028.518859245532 05:23:47 INFO - PID 6627 | |3;mootools.html;1190.4821402689831;1338.8058731483934;1353.2827601371278;1353.3368649361817;1358.0493654441893;10348;10803.513841877115;10815.675529734111;10821.188410869197;10826.1880358973;9898.307118501707;10197.694069177918;10260.486975651225;10260.692179234617;10523.947380263107;2404.2546810488716;2451.2891261534132;2452.497238066201;2453.116877923946;2457.5453541094867;2084.9270275540357;2252.526969336441;2374.157174203159;2411.915582954597;2454.3373289211904;2055.2909246310073;2138.2516119358256;2139.871607703543;2368.8578685278853;2451.4239153798844;2437.756224377557;2460.692413448319;2462.3105530451503;2465.297390243772;2465.9506809863883;1889.5087277307998;2398.9698890649797;2447.8776061197045;2461.5815311397105;2466.075221791828;10739.301945373603;10880.782384352347;10884.23810333277;10889.346639201673;11089.112870970303;6202.069689546559;6452.387023232785;6452.903206451907;6467.094606755058;6473.546851720382;3676.669099781006;3791.6018818023986;3894.201688653819;4038.434619153321;4120.96975756061;876.3471213945651;1172.4196522721309;1200.8258802473679;1221.3709939381226;1230.267990545624;15323.999999999889;15440.227988600638;15515.922420387938;15531.06813591188;15540.844591554052;5116.56509196715;5352.143657014937;5443.985725281671;5460.918086228709;5472.179173124063;11482.827757583644;11490.712732181595;11501.827472587918;11505.13711471643;11528.481218345194;6319.399657032666;6326;6326.49388048955;6328.5886417383645;6328.71520781566;5613.915791263134;5808.3901190375245;5959.344472108065;6020.247469066367;6042.244719410074;4056;4188.60208280219;4212.115455754326;4250.234957707642;4253.957460425418;1057.4976885979252;1235.3508066730835;1244.7137158453606;1262.9621111366673;1266.4870727355435;740.2227660955974;742.4913933955258;768.8923550702906;775.4145620056821;805.3718099882101;5756.683382414008;5878.941210587925;6032.638041717511;6087.54343424245;6088.990633091069;556.3740791609439;578.5776383240258;593.1814096546824;594.5184400635499;597.1729155120103;3737.050442204313;4212.49450065994;4224.936625950613;4380.737155770664;4402.207602631493;13293.867061329263;14030.508932187324;14061.929690351688;14082.295885205698;14101.153930764187;13599.728005440034;13605.31973401326;13651.112677676118;14010.719785604231;14034.508792192224;10091.49542522871;10165.542550585242;10204.897951020543;10338.896611033944;10366;5634.6337488063145;5680.40355762639;6511.967440162769;6520.413162815369;6598.306725923059;12075.033997280021;12152.513899443924;12249.69375765613;12531.87468125307;12544.93727531393;11398.658040258806;11653.184277100521;12589.559365422167;12784.936075319936;12854.357282136043;8140.023197216372;8205.753827385188;8276.213759692819;8337.83324333522;8341.20758527939;9961.651342202988;9984.850227246743;10167;10179.592816287563;10210.642627508001;6759.459243260429;8510;8615.095414981588;8726.869096963424;8730.34522410809;8156.662239486999;8211.425200235926;8361.66553337884;8407.327413806759;8484.703035393732;7614.695412183451;7706.845863082819;7838.764837054898;7839.137694853675;7850.136484986761;5629;5956.195913551788;6002.459778619946;6016.639001659914;6071.392860713893;5159.380874295109;5902.380250073852;6123.142760013513;6206.689665516797;6240.875182496234 05:23:47 INFO - PID 6627 | |4;prototype.html;336.1679842390083;377.8998565380174;393.88971088095343;399.24741861590894;401.57232547337094;5007.774650140744;5677.943220567793;5787.681677507735;5931.9406805931985;6012.969935150329;5772.538196944245;5772.913406298909;5847.619904706191;5892.175095486636;5909.704514774266;3856.614338566149;4295.720778149426;4424.646028317743;4441.733495990251;4444.200043992072;4433.379326894237;4444.22226110431;4446.7776611169475;4466.642668586505;4469.418975533176;4401.229784787665;4429.06989532197;4436.689431739779;4439.977800111011;4449.998750281177;4254.510731265913;4433.113377324548;4437.334399840033;4449.66627502937;4469.508354081048;3186.279409119073;3225.080851957181;3388.356212319676;3585.695215906651;3812.370958791796;4966.541561926833;5708.257926469532;5767.279090113736;5783.21926539916;5784.971075144597;3586.4620306954166;4260.786960651955;4767.594754445876;5101.4695591322825;5133.640645154841;5278.736063196825;5280.445553216934;6235.438810507031;6265.561410701207;6326.272478664966;4270.974966008136;4277.486701595828;4282.794360904137;4284.550122237151;4315.223259813232;4931.211006238985;5482.506574408322;5798.347470970753;5985.491233245223;6784.423324017464;3507.7544571880235;3536;3727.981360093182;4056.3915412688334;4175.561566035522;4028.3756017817286;4380.408644832907;4381.846635367747;4386.342048692658;4411.382406463098;4270.850520231777;4783.808647654125;4808.519148085164;4892.363992681;5133.897322053538;5691.089425691869;6116.908246376308;6262.937370626327;6274.988005757262;6283.59156654816;1317.4795955597676;1476.6825132596543;1495.6410461489165;1503.8195416550084;1508.3212554350366;949.1789601994225;992.528548939248;995.3928103856641;1004.3873237325225;1009.4801177393648;5085.389753229636;5378.731063446812;5712.714364281769;5739.253896993364;5798.652080875161;6130.448259656652;6140.723667434976;6462.579932304385;6486.967565162238;6490.513211509156;5504.4770746778995;5669.688167150764;5856.531477481792;5895.823125306248;5910.408959104141;2899.666538348075;3406.284680217182;3438.449848024304;3453.9827300863335;3454.360943225461;5738.569607279471;6301.464375528039;6456;6685.899711504331;6695.497837662195;5590.972045139748;5738;6391.712372943229;6719.462443004548;6835.897461538081;6189.195404597373;6594.40650341462;6615.536912416084;6635.701393437307;6647.401733843881;6459.547831651739;6489.286178520265;6507.869842603216;6515.804525864231;6559.868802623825;5445.564354851523;5627.52166065889;5785.22309291534;6144.754209831557;6451.903221451776;6305.662808877049;6508.349165083454;6595.703193356407;6596.736130554916;6631.237407698177;3270.296886169485;3589.712822974208;3873.8378486454517;3957.8522228553397;4234.4495215623;5896.410358964241;6283.403076707706;6394.26465956421;6509.934900650933;6512.283648798723;6242.469390101892;6278.6860656966055;6280.9685951569945;6286.748530058747;6301.590396624205;6263.68681565929;6269.435750782452;6288.182536270256;6298.921355952616;6319.115323854572;5802.941970580409;6079.544034197364;6135.300467794156;6137.3862613738265;6157.907631385443;5442.857000030038;5712.714364281686;6082.574219804571;6247.875042499216;6275.717592708431;5849.678267695147;6062.848428789315;6077.5745697802495;6121.8469538260115;6165.797669454413 05:23:47 INFO - PID 6627 | |5;yui.html;714.8748968930436;726.2410780734132;726.8073960400492;735.5917465806475;746.6751962896138;14714.926425367872;14796.038257513253;15444.459443919452;15450.5364839055;15454.845451545496;14506.492272770454;14583;14929.85070149301;14962.775558366606;15011.174385408798;932.6129656192688;957.4255446731939;962.8555716642489;965.4496936746058;966.7969726357474;915.8671992561075;954.2270760683869;962.8796400449944;963.6001059560274;963.9903600964005;1007.3301254665676;1009.328796350426;1010.4745532323187;1010.5755582655303;1013.027493606139;1004.6634377483551;1004.678502879079;1005.0452070533023;1006.5219020965056;1008.3193844155165;743.7843025522592;744.5569885917924;746.5632604926125;747.2340850628095;747.3050063440998;16129.919350403174;16618.25217865199;16688.58278543034;16723.247453864482;17003.829961700347;14480.348384322731;14563.781543276858;14580.987574713014;14631.195284259362;14744;11510.251833630788;11594.362310072942;11676.649700508999;11850.940745296304;12138.635840924786;2635.420207554335;3017.3908735074247;3045.482268014443;3047.9847600762077;3130.8904188353526;103734.48132759288;108360;108453;112031;112075.43962280218;10187.694369168938;10689.251752377415;11259.549618015351;11336.886631133582;11395.715107122223;24590.877045614656;24984;25004.999800007798;25194.87402563012;25227.62158567623;7547.471676982668;7626.9618651906385;7638.732644357421;7660.310572048543;7719.035120609924;3960.9009774755286;4001.7198796083994;4001.9399709004388;4034.1504818013996;4087.89780255496;4075.3275709507598;4168.249715051259;4192.685548583869;4193.706440549132;4198.6221240087925;771.0468904097294;800.3277247112453;815.9469634473741;862.6894318045498;891.6166048599035;524.9002689488985;550.6778534557249;555.6082014553517;570.1931766550367;596.341043147316;8992.955035224782;10177.33847299938;10324.483775811179;10477.9476102619;10730.312934144915;534.9812756553501;535.2399592578548;577.3504807092022;602.7106988645475;610.7353035641025;4279.768774575238;4410.977945110254;4628.143793398232;4926.3842019747535;5198.92201616976;101887;105000;105875.47062264639;107050.3942440864;109133.45433272782;92641.5367923156;92952.53523732338;93497.532512337;95838;95873;45024;63870;79377.20622793928;80131.3973720534;80479;8093.3929955252415;9511.714648560554;9745.853812192965;11023.503942322775;11044.226904116957;28407.573886392132;37365.626343736214;38532.80733596314;38768;39454.60545394509;38054;49163.75418122886;49591.76020599514;50504.74747626238;51359;13288.734225315633;13404.798928015893;13445.462181513021;13911.860881391056;13975.580732578039;14229.50196743109;14264.45050855867;14470;14472.638184045483;14514.782278265624;13240.139390939557;13321.067525273525;13338.799918001432;13479.258640774851;13545.72908541804;11170.88829111731;13098.672533186365;13203.339833008118;13284.53504127351;13812.240326782123;12991.350432478528;13007.055550034931;13345.532906348231;13780;13899.930500347433;9485;9678.612855485702;9712.75718107053;9969;10450.947745261225;8782.09700427958;9728.75678108053;9933.453660048477;9980.301378903407;11162.33026018409 05:23:47 INFO - PID 6627 | __end_tp_report 05:23:47 INFO - PID 6627 | __start_cc_report 05:23:47 INFO - PID 6627 | _x_x_mozilla_cycle_collect,1732 05:23:47 INFO - PID 6627 | __end_cc_report 05:23:47 INFO - PID 6627 | __startTimestamp1490617427924__endTimestamp 05:23:47 INFO - PID 6627 | 05:23:47 INFO - PID 6627 | ------- Summary: start ------- 05:23:47 INFO - PID 6627 | Number of tests: 6 05:23:47 INFO - PID 6627 | 05:23:47 INFO - PID 6627 | [#0] dojo.html Cycles:165 Average:6012.32 Median:6217.91 stddev:689.61 (11.1%) stddev-sans-first:655.76 05:23:47 INFO - PID 6627 | Values: 3210.2 3384.3 3406.6 3423.7 3442.6 4739.5 6013.6 6054.3 6066.2 6188.0 5988.6 6051.7 6078.7 6082.3 6089.2 4292.5 5350.8 5381.0 5457.8 5475.1 5519.9 5532.8 5543.8 5586.5 5592.1 5579.0 5579.8 5583.6 5584.6 5587.6 5538.8 5576.0 5576.1 5584.3 5587.6 5371.1 5460.6 5474.8 5488.7 5569.2 6189.3 6198.7 6199.4 6203.7 6204.1 6284.2 6495.7 6496.4 6510.4 6554.3 5276.4 6019.3 6256.8 6450.9 6495.6 5336.6 6163.7 6187.8 6253.7 6254.9 6786.2 6788.5 6790.6 6794.9 6807.4 5747.1 6202.2 6246.5 6450.9 6459.6 6308.4 6321.3 6509.4 6529.4 6680.0 6546.1 6556.6 6560.0 6564.3 6569.7 6462.4 6546.4 6549.8 6560.8 6572.8 5193.0 5298.8 5304.9 5317.8 5334.5 4690.8 4704.2 4712.1 4742.7 4761.0 6148.2 6158.8 6162.3 6165.8 6165.9 6308.6 6401.4 6533.7 6558.1 6591.9 6139.2 6141.1 6143.8 6151.3 6159.5 5786.1 5820.0 5851.5 5855.0 5863.5 6454.9 6548.1 6632.3 6725.3 6729.8 5608.6 6502.3 6554.7 6622.7 6634.2 6688.8 6711.2 6713.2 6713.4 6719.7 5403.5 6132.4 6443.7 6501.3 6508.1 6336.7 6399.1 6425.2 6434.6 6475.2 6360.6 6363.5 6374.3 6382.7 6383.4 6145.9 6189.3 6263.5 6305.8 6353.7 6256.8 6257.8 6261.8 6278.8 6280.0 6217.9 6264.0 6271.2 6359.8 6372.5 6223.5 6239.8 6266.3 6267.0 6272.4 05:23:47 INFO - PID 6627 | 05:23:47 INFO - PID 6627 | [#1] ext.html Cycles:180 Average:51845.15 Median:349349.50 stddev:101098.08 (28.9%) stddev-sans-first:101308.89 05:23:47 INFO - PID 6627 | Values: 748.8 788.7 809.6 1135.2 1151.0 27921.6 36717.3 37296.6 37299.0 37344.8 39066.0 39334.8 39548.6 39549.8 39553.8 6038.2 6185.1 8390.9 8563.3 8577.7 6167.0 6252.4 6263.0 6272.3 8374.6 8567.9 8568.9 8635.3 8637.7 8661.6 7385.8 8520.4 8613.5 8620.0 8636.7 8395.0 8467.6 8481.2 8483.8 8494.3 34278.8 37501.3 39054.6 39368.0 39383.0 3007.6 3019.1 3031.6 3041.4 3048.3 27998.9 28490.9 28579.7 28660.9 29604.9 2424.3 2752.4 2806.6 2815.1 2866.5 327304.0 330954.0 331167.0 331569.0 331706.3 49989.8 50122.7 50135.7 50157.7 50229.2 52726.9 52785.7 52808.0 52850.2 52871.7 14375.3 14823.7 14833.3 14835.5 14857.4 9970.8 12055.6 12060.2 12085.6 12114.3 11145.3 11218.9 11238.3 13012.5 13259.5 4656.3 4707.2 4710.5 5147.6 5345.0 942.8 973.1 981.0 984.3 990.4 24185.6 24518.0 24521.5 24550.0 24588.6 1063.9 1091.3 1116.1 1118.8 1134.0 11649.4 11782.2 12309.0 12460.5 12486.0 450498.0 453138.0 453531.0 453898.0 454253.0 56679.1 56753.0 56766.0 56813.4 57122.0 1444.9 1460.6 1500.5 1506.4 1524.4 22395.6 23948.8 28157.7 28190.9 28919.0 47766.5 49924.8 51728.5 51772.0 51924.7 348877.0 349325.0 349374.0 349506.0 350328.0 32875.3 32894.8 32947.0 32961.8 34235.7 43017.8 43301.6 43381.3 44762.6 44822.0 29315.1 30260.0 30353.0 30375.5 30632.4 29522.8 29545.7 30211.1 30267.8 30730.0 29136.9 36476.0 36828.8 36894.8 37041.3 22695.5 29866.9 30437.7 31105.0 31262.5 22489.8 23955.2 24246.8 24294.4 24424.4 05:23:47 INFO - PID 6627 | 05:23:47 INFO - PID 6627 | [#2] jquery.html Cycles:180 Average:36432.11 Median:2068.04 stddev:93965.30 (4543.7%) stddev-sans-first:94189.86 05:23:47 INFO - PID 6627 | Values: 359.4 391.4 392.9 418.6 448.2 13286.1 19401.4 21452.2 22399.8 22413.1 21818.6 22149.4 22255.0 22296.6 22320.4 4900.9 5848.4 6265.5 6349.8 6362.3 6112.1 6223.1 6279.1 6280.8 6292.0 6023.2 6248.0 6265.2 6274.9 6276.1 6074.4 6143.1 6170.6 6174.6 6224.1 5492.3 5521.7 5526.2 5529.1 5536.0 26402.3 26533.0 27253.7 27421.2 27798.0 1926.9 1938.6 1973.2 1977.0 2050.1 15228.6 16047.3 16291.7 16329.5 16466.6 1511.4 1638.2 1668.6 1689.6 1695.5 155145.0 160357.0 161896.2 164640.2 165728.0 11656.6 11709.8 11902.2 11936.5 11978.3 16045.0 16054.6 16073.5 16088.8 16102.5 8157.9 8381.8 8390.7 8448.0 8509.6 7726.3 7767.2 7946.8 8021.4 8033.0 7422.0 7545.8 7586.0 7612.3 7615.6 1742.9 2067.6 2068.5 2075.8 2232.5 878.9 896.7 896.8 915.1 930.6 20004.9 20030.3 20205.0 20259.4 20371.0 825.2 904.6 928.3 960.3 966.7 9260.4 10154.9 10496.4 10558.8 10864.5 414637.0 514704.0 577357.0 577500.0 628322.0 113509.0 130091.0 163052.0 164011.0 169098.0 1177.3 1221.7 1254.9 1257.7 1259.7 18421.6 20000.4 20068.6 22327.0 22408.2 57120.7 60294.7 64535.7 65784.3 66020.3 80822.6 82752.2 83871.6 85922.0 87165.0 11891.9 12123.3 12432.9 12741.2 12840.7 13529.0 13556.1 13810.2 14008.7 14066.0 11638.9 11651.2 11851.3 12003.2 12072.7 11031.3 11116.7 11305.4 11372.8 11408.3 11722.0 11905.2 11971.2 12052.0 12097.3 12198.6 12274.7 12463.3 12533.6 12803.5 11533.5 11760.9 11994.2 12010.6 12028.5 05:23:47 INFO - PID 6627 | 05:23:47 INFO - PID 6627 | [#3] mootools.html Cycles:180 Average:6698.43 Median:3071.37 stddev:4186.24 (136.3%) stddev-sans-first:4177.52 05:23:47 INFO - PID 6627 | Values: 1190.5 1338.8 1353.3 1353.3 1358.0 10348.0 10803.5 10815.7 10821.2 10826.2 9898.3 10197.7 10260.5 10260.7 10523.9 2404.3 2451.3 2452.5 2453.1 2457.5 2084.9 2252.5 2374.2 2411.9 2454.3 2055.3 2138.3 2139.9 2368.9 2451.4 2437.8 2460.7 2462.3 2465.3 2466.0 1889.5 2399.0 2447.9 2461.6 2466.1 10739.3 10880.8 10884.2 10889.3 11089.1 6202.1 6452.4 6452.9 6467.1 6473.5 3676.7 3791.6 3894.2 4038.4 4121.0 876.3 1172.4 1200.8 1221.4 1230.3 15324.0 15440.2 15515.9 15531.1 15540.8 5116.6 5352.1 5444.0 5460.9 5472.2 11482.8 11490.7 11501.8 11505.1 11528.5 6319.4 6326.0 6326.5 6328.6 6328.7 5613.9 5808.4 5959.3 6020.2 6042.2 4056.0 4188.6 4212.1 4250.2 4254.0 1057.5 1235.4 1244.7 1263.0 1266.5 740.2 742.5 768.9 775.4 805.4 5756.7 5878.9 6032.6 6087.5 6089.0 556.4 578.6 593.2 594.5 597.2 3737.1 4212.5 4224.9 4380.7 4402.2 13293.9 14030.5 14061.9 14082.3 14101.2 13599.7 13605.3 13651.1 14010.7 14034.5 10091.5 10165.5 10204.9 10338.9 10366.0 5634.6 5680.4 6512.0 6520.4 6598.3 12075.0 12152.5 12249.7 12531.9 12544.9 11398.7 11653.2 12589.6 12784.9 12854.4 8140.0 8205.8 8276.2 8337.8 8341.2 9961.7 9984.9 10167.0 10179.6 10210.6 6759.5 8510.0 8615.1 8726.9 8730.3 8156.7 8211.4 8361.7 8407.3 8484.7 7614.7 7706.8 7838.8 7839.1 7850.1 5629.0 5956.2 6002.5 6016.6 6071.4 5159.4 5902.4 6123.1 6206.7 6240.9 05:23:47 INFO - PID 6627 | 05:23:47 INFO - PID 6627 | [#4] prototype.html Cycles:180 Average:5009.72 Median:5725.36 stddev:1582.04 (27.6%) stddev-sans-first:1547.10 05:23:47 INFO - PID 6627 | Values: 336.2 377.9 393.9 399.2 401.6 5007.8 5677.9 5787.7 5931.9 6013.0 5772.5 5772.9 5847.6 5892.2 5909.7 3856.6 4295.7 4424.6 4441.7 4444.2 4433.4 4444.2 4446.8 4466.6 4469.4 4401.2 4429.1 4436.7 4440.0 4450.0 4254.5 4433.1 4437.3 4449.7 4469.5 3186.3 3225.1 3388.4 3585.7 3812.4 4966.5 5708.3 5767.3 5783.2 5785.0 3586.5 4260.8 4767.6 5101.5 5133.6 5278.7 5280.4 6235.4 6265.6 6326.3 4271.0 4277.5 4282.8 4284.6 4315.2 4931.2 5482.5 5798.3 5985.5 6784.4 3507.8 3536.0 3728.0 4056.4 4175.6 4028.4 4380.4 4381.8 4386.3 4411.4 4270.9 4783.8 4808.5 4892.4 5133.9 5691.1 6116.9 6262.9 6275.0 6283.6 1317.5 1476.7 1495.6 1503.8 1508.3 949.2 992.5 995.4 1004.4 1009.5 5085.4 5378.7 5712.7 5739.3 5798.7 6130.4 6140.7 6462.6 6487.0 6490.5 5504.5 5669.7 5856.5 5895.8 5910.4 2899.7 3406.3 3438.4 3454.0 3454.4 5738.6 6301.5 6456.0 6685.9 6695.5 5591.0 5738.0 6391.7 6719.5 6835.9 6189.2 6594.4 6615.5 6635.7 6647.4 6459.5 6489.3 6507.9 6515.8 6559.9 5445.6 5627.5 5785.2 6144.8 6451.9 6305.7 6508.3 6595.7 6596.7 6631.2 3270.3 3589.7 3873.8 3957.9 4234.4 5896.4 6283.4 6394.3 6509.9 6512.3 6242.5 6278.7 6281.0 6286.7 6301.6 6263.7 6269.4 6288.2 6298.9 6319.1 5802.9 6079.5 6135.3 6137.4 6157.9 5442.9 5712.7 6082.6 6247.9 6275.7 5849.7 6062.8 6077.6 6121.8 6165.8 05:23:47 INFO - PID 6627 | 05:23:47 INFO - PID 6627 | [#5] yui.html Cycles:180 Average:19727.13 Median:3031.44 stddev:28906.28 (953.6%) stddev-sans-first:28952.12 05:23:47 INFO - PID 6627 | Values: 714.9 726.2 726.8 735.6 746.7 14714.9 14796.0 15444.5 15450.5 15454.8 14506.5 14583.0 14929.9 14962.8 15011.2 932.6 957.4 962.9 965.4 966.8 915.9 954.2 962.9 963.6 964.0 1007.3 1009.3 1010.5 1010.6 1013.0 1004.7 1004.7 1005.0 1006.5 1008.3 743.8 744.6 746.6 747.2 747.3 16129.9 16618.3 16688.6 16723.2 17003.8 14480.3 14563.8 14581.0 14631.2 14744.0 11510.3 11594.4 11676.6 11850.9 12138.6 2635.4 3017.4 3045.5 3048.0 3130.9 103734.5 108360.0 108453.0 112031.0 112075.4 10187.7 10689.3 11259.5 11336.9 11395.7 24590.9 24984.0 25005.0 25194.9 25227.6 7547.5 7627.0 7638.7 7660.3 7719.0 3960.9 4001.7 4001.9 4034.2 4087.9 4075.3 4168.2 4192.7 4193.7 4198.6 771.0 800.3 815.9 862.7 891.6 524.9 550.7 555.6 570.2 596.3 8993.0 10177.3 10324.5 10477.9 10730.3 535.0 535.2 577.4 602.7 610.7 4279.8 4411.0 4628.1 4926.4 5198.9 101887.0 105000.0 105875.5 107050.4 109133.5 92641.5 92952.5 93497.5 95838.0 95873.0 45024.0 63870.0 79377.2 80131.4 80479.0 8093.4 9511.7 9745.9 11023.5 11044.2 28407.6 37365.6 38532.8 38768.0 39454.6 38054.0 49163.8 49591.8 50504.7 51359.0 13288.7 13404.8 13445.5 13911.9 13975.6 14229.5 14264.5 14470.0 14472.6 14514.8 13240.1 13321.1 13338.8 13479.3 13545.7 11170.9 13098.7 13203.3 13284.5 13812.2 12991.4 13007.1 13345.5 13780.0 13899.9 9485.0 9678.6 9712.8 9969.0 10450.9 8782.1 9728.8 9933.5 9980.3 11162.3 05:23:47 INFO - PID 6627 | -------- Summary: end -------- 05:23:47 INFO - PID 6627 | 05:23:48 INFO - TEST-INFO | 6627: exit 0 05:23:48 INFO - TEST-OK | dromaeo_css | took 1121907ms 05:23:48 INFO - TEST-START | kraken 05:23:48 INFO - operating with platform_type : mac_ 05:23:48 INFO - Initialising browser for kraken test... 05:23:48 INFO - TEST-INFO | started process 6630 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmpLXf0sZ/profile http://localhost:58091/getInfo.html) 05:23:51 INFO - PID 6630 | __metrics Screen width/height:1600/1200 05:23:51 INFO - PID 6630 | colorDepth:24 05:23:51 INFO - PID 6630 | Browser inner width/height: 1024/689 05:23:51 INFO - PID 6630 | __metrics 05:23:51 ERROR - PID 6630 | 1490617431235 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 05:23:51 INFO - PID 6630 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:23:51 INFO - PID 6630 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:23:52 INFO - TEST-INFO | 6630: exit 0 05:23:52 INFO - Browser initialized. 05:23:52 INFO - Running cycle 1/1 for kraken test... 05:23:52 INFO - TEST-INFO | started process 6631 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmpLXf0sZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:24:04 INFO - PID 6631 | RSS: Main: 233836544 05:24:04 INFO - PID 6631 | 05:24:07 INFO - PID 6631 | Cycle 1(1): loaded http://localhost:58091/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58091/tests/kraken/driver.html?testName=audio-beat-detection) 05:24:08 INFO - PID 6631 | RSS: Main: 284860416 05:24:08 INFO - PID 6631 | 05:24:10 INFO - PID 6631 | Cycle 1(1): loaded http://localhost:58091/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58091/tests/kraken/driver.html?testName=audio-dft) 05:24:11 INFO - PID 6631 | RSS: Main: 287145984 05:24:11 INFO - PID 6631 | 05:24:14 INFO - PID 6631 | Cycle 1(1): loaded http://localhost:58091/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58091/tests/kraken/driver.html?testName=audio-fft) 05:24:15 INFO - PID 6631 | RSS: Main: 378269696 05:24:15 INFO - PID 6631 | 05:24:17 INFO - PID 6631 | Cycle 1(1): loaded http://localhost:58091/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58091/tests/kraken/driver.html?testName=audio-oscillator) 05:24:18 INFO - PID 6631 | RSS: Main: 283529216 05:24:18 INFO - PID 6631 | 05:24:20 INFO - PID 6631 | Cycle 1(1): loaded http://localhost:58091/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58091/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:24:20 INFO - PID 6631 | RSS: Main: 287350784 05:24:20 INFO - PID 6631 | 05:24:23 INFO - PID 6631 | Cycle 1(1): loaded http://localhost:58091/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58091/tests/kraken/driver.html?testName=imaging-darkroom) 05:24:24 INFO - PID 6631 | RSS: Main: 329846784 05:24:24 INFO - PID 6631 | 05:24:27 INFO - PID 6631 | Cycle 1(1): loaded http://localhost:58091/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58091/tests/kraken/driver.html?testName=imaging-desaturate) 05:24:27 INFO - PID 6631 | RSS: Main: 335998976 05:24:27 INFO - PID 6631 | 05:24:31 INFO - PID 6631 | Cycle 1(1): loaded http://localhost:58091/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58091/tests/kraken/driver.html?testName=json-parse-financial) 05:24:31 INFO - PID 6631 | RSS: Main: 340590592 05:24:31 INFO - PID 6631 | 05:24:33 INFO - PID 6631 | Cycle 1(1): loaded http://localhost:58091/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58091/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:24:34 INFO - PID 6631 | RSS: Main: 304132096 05:24:34 INFO - PID 6631 | 05:24:35 INFO - PID 6631 | Cycle 1(1): loaded http://localhost:58091/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58091/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:24:36 INFO - PID 6631 | RSS: Main: 305618944 05:24:36 INFO - PID 6631 | 05:24:38 INFO - PID 6631 | Cycle 1(1): loaded http://localhost:58091/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58091/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:24:39 INFO - PID 6631 | RSS: Main: 307576832 05:24:39 INFO - PID 6631 | 05:24:42 INFO - PID 6631 | Cycle 1(1): loaded http://localhost:58091/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58091/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:24:43 INFO - PID 6631 | RSS: Main: 328585216 05:24:43 INFO - PID 6631 | 05:24:46 INFO - PID 6631 | Cycle 1(1): loaded http://localhost:58091/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58091/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:24:46 INFO - PID 6631 | RSS: Main: 321921024 05:24:46 INFO - PID 6631 | 05:24:48 INFO - PID 6631 | Cycle 1(1): loaded http://localhost:58091/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58091/tests/kraken/driver.html?testName=ai-astar) 05:24:48 INFO - PID 6631 | RSS: Main: 383406080 05:24:48 INFO - PID 6631 | 05:24:48 INFO - PID 6631 | __start_tp_report 05:24:48 INFO - PID 6631 | _x_x_mozilla_page_load 05:24:48 INFO - PID 6631 | _x_x_mozilla_page_load_details 05:24:48 INFO - PID 6631 | |i|pagename|runs| 05:24:48 INFO - PID 6631 | |0;ai-astar;113;111;112;113;112;117;161;111;113;112 05:24:48 INFO - PID 6631 | |1;audio-beat-detection;143;110;111;159;164;110;109;108;130;110 05:24:48 INFO - PID 6631 | |2;audio-dft;189;185;187;187;196;234;266;200;183;184 05:24:48 INFO - PID 6631 | |3;audio-fft;81;62;62;91;60;60;58;57;74;58 05:24:48 INFO - PID 6631 | |4;audio-oscillator;89;71;87;69;69;69;83;68;70;85 05:24:48 INFO - PID 6631 | |5;imaging-gaussian-blur;86;87;87;86;86;85;86;86;87;86 05:24:48 INFO - PID 6631 | |6;imaging-darkroom;93;98;94;94;94;95;95;95;94;93 05:24:48 INFO - PID 6631 | |7;imaging-desaturate;85;135;129;130;97;140;128;83;86;85 05:24:48 INFO - PID 6631 | |8;json-parse-financial;64;63;62;60;62;63;61;62;62;62 05:24:48 INFO - PID 6631 | |9;json-stringify-tinderbox;55;64;66;57;61;60;58;58;58;60 05:24:48 INFO - PID 6631 | |10;stanford-crypto-aes;83;83;82;84;84;83;81;83;81;83 05:24:48 INFO - PID 6631 | |11;stanford-crypto-ccm;138;144;136;141;145;144;147;138;176;143 05:24:48 INFO - PID 6631 | |12;stanford-crypto-pbkdf2;172;184;187;183;188;187;183;189;186;224 05:24:48 INFO - PID 6631 | |13;stanford-crypto-sha256-iterative;67;67;67;68;67;68;67;67;68;67 05:24:48 INFO - PID 6631 | __end_tp_report 05:24:48 INFO - PID 6631 | __start_cc_report 05:24:48 INFO - PID 6631 | _x_x_mozilla_cycle_collect,2113 05:24:48 INFO - PID 6631 | __end_cc_report 05:24:48 INFO - PID 6631 | __startTimestamp1490617488903__endTimestamp 05:24:48 INFO - PID 6631 | 05:24:48 INFO - PID 6631 | ------- Summary: start ------- 05:24:48 INFO - PID 6631 | Number of tests: 14 05:24:48 INFO - PID 6631 | 05:24:48 INFO - PID 6631 | [#0] ai-astar Cycles:10 Average:117.50 Median:113.00 stddev:15.38 (13.6%) stddev-sans-first:16.22 05:24:48 INFO - PID 6631 | Values: 113.0 111.0 112.0 113.0 112.0 117.0 161.0 111.0 113.0 112.0 05:24:48 INFO - PID 6631 | 05:24:48 INFO - PID 6631 | [#1] audio-beat-detection Cycles:10 Average:125.40 Median:120.50 stddev:22.21 (18.4%) stddev-sans-first:22.63 05:24:48 INFO - PID 6631 | Values: 143.0 110.0 111.0 159.0 164.0 110.0 109.0 108.0 130.0 110.0 05:24:48 INFO - PID 6631 | 05:24:48 INFO - PID 6631 | [#2] audio-dft Cycles:10 Average:201.10 Median:192.50 stddev:27.38 (14.2%) stddev-sans-first:28.68 05:24:48 INFO - PID 6631 | Values: 189.0 185.0 187.0 187.0 196.0 234.0 266.0 200.0 183.0 184.0 05:24:48 INFO - PID 6631 | 05:24:48 INFO - PID 6631 | [#3] audio-fft Cycles:10 Average:66.30 Median:62.00 stddev:11.67 (18.8%) stddev-sans-first:11.10 05:24:48 INFO - PID 6631 | Values: 81.0 62.0 62.0 91.0 60.0 60.0 58.0 57.0 74.0 58.0 05:24:48 INFO - PID 6631 | 05:24:48 INFO - PID 6631 | [#4] audio-oscillator Cycles:10 Average:76.00 Median:77.00 stddev:8.77 (11.4%) stddev-sans-first:7.94 05:24:48 INFO - PID 6631 | Values: 89.0 71.0 87.0 69.0 69.0 69.0 83.0 68.0 70.0 85.0 05:24:48 INFO - PID 6631 | 05:24:48 INFO - PID 6631 | [#5] imaging-gaussian-blur Cycles:10 Average:86.20 Median:86.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 05:24:48 INFO - PID 6631 | Values: 86.0 87.0 87.0 86.0 86.0 85.0 86.0 86.0 87.0 86.0 05:24:48 INFO - PID 6631 | 05:24:48 INFO - PID 6631 | [#6] imaging-darkroom Cycles:10 Average:94.50 Median:94.50 stddev:1.43 (1.5%) stddev-sans-first:1.41 05:24:48 INFO - PID 6631 | Values: 93.0 98.0 94.0 94.0 94.0 95.0 95.0 95.0 94.0 93.0 05:24:48 INFO - PID 6631 | 05:24:48 INFO - PID 6631 | [#7] imaging-desaturate Cycles:10 Average:109.80 Median:84.00 stddev:24.34 (29.0%) stddev-sans-first:24.11 05:24:48 INFO - PID 6631 | Values: 85.0 135.0 129.0 130.0 97.0 140.0 128.0 83.0 86.0 85.0 05:24:48 INFO - PID 6631 | 05:24:48 INFO - PID 6631 | [#8] json-parse-financial Cycles:10 Average:62.10 Median:62.00 stddev:1.10 (1.8%) stddev-sans-first:0.93 05:24:48 INFO - PID 6631 | Values: 64.0 63.0 62.0 60.0 62.0 63.0 61.0 62.0 62.0 62.0 05:24:48 INFO - PID 6631 | 05:24:48 INFO - PID 6631 | [#9] json-stringify-tinderbox Cycles:10 Average:59.70 Median:60.00 stddev:3.30 (5.5%) stddev-sans-first:3.03 05:24:48 INFO - PID 6631 | Values: 55.0 64.0 66.0 57.0 61.0 60.0 58.0 58.0 58.0 60.0 05:24:48 INFO - PID 6631 | 05:24:48 INFO - PID 6631 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:83.00 stddev:1.06 (1.3%) stddev-sans-first:1.12 05:24:48 INFO - PID 6631 | Values: 83.0 83.0 82.0 84.0 84.0 83.0 81.0 83.0 81.0 83.0 05:24:48 INFO - PID 6631 | 05:24:48 INFO - PID 6631 | [#11] stanford-crypto-ccm Cycles:10 Average:145.20 Median:144.00 stddev:11.38 (7.9%) stddev-sans-first:11.77 05:24:48 INFO - PID 6631 | Values: 138.0 144.0 136.0 141.0 145.0 144.0 147.0 138.0 176.0 143.0 05:24:48 INFO - PID 6631 | 05:24:48 INFO - PID 6631 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.30 Median:187.00 stddev:13.43 (7.2%) stddev-sans-first:12.89 05:24:48 INFO - PID 6631 | Values: 172.0 184.0 187.0 183.0 188.0 187.0 183.0 189.0 186.0 224.0 05:24:48 INFO - PID 6631 | 05:24:48 INFO - PID 6631 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.00 stddev:0.48 (0.7%) stddev-sans-first:0.50 05:24:48 INFO - PID 6631 | Values: 67.0 67.0 67.0 68.0 67.0 68.0 67.0 67.0 68.0 67.0 05:24:48 INFO - PID 6631 | -------- Summary: end -------- 05:24:48 INFO - PID 6631 | 05:24:49 INFO - TEST-INFO | 6631: exit 0 05:24:49 INFO - TEST-OK | kraken | took 61191ms 05:24:49 INFO - SUITE-END | took 1183s 05:24:50 INFO - Completed test suite (00:19:43) 05:24:50 INFO - javascript benchmark 05:24:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7548.495913219592, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3210.2134976930647, 3384.306217225469, 3406.5522153084935, 3423.733218709078, 3442.6041005284383, 4739.5023522530155, 6013.579049466539, 6054.273487181534, 6066.1810655561485, 6187.969060154693, 5988.580799344048, 6051.697415129237, 6078.726457309421, 6082.300535438426, 6089.238845144357, 4292.463442069741, 5350.8394748157425, 5380.973095134538, 5457.808976685817, 5475.123980163175, 5519.917201241985, 5532.75102620383, 5543.805966791163, 5586.469285417899, 5592.0773072443, 5578.995780759462, 5579.832605021856, 5583.609147359686, 5584.553235741131, 5587.636803607773, 5538.806141785059, 5575.972120139414, 5576.107822748381, 5584.301962254718, 5587.55299576037, 5371.113766228567, 5460.6177567570285, 5474.835754927359, 5488.670679759187, 5569.220309156722, 6189.2572891252885, 6198.659073750942, 6199.411055949723, 6203.689815509207, 6204.13142160098, 6284.18305620271, 6495.6752162391695, 6496.350364963466, 6510.381513756186, 6554.3117972613145, 5276.393214780311, 6019.264388304252, 6256.81120587087, 6450.870982580323, 6495.577787443801, 5336.626436149471, 6163.660998645073, 6187.84530386735, 6253.749850005949, 6254.87490250197, 6786.219584747719, 6788.456923446112, 6790.5925644461495, 6794.93205067943, 6807.353301436356, 5747.051736463521, 6202.162708034448, 6246.500279977591, 6450.903236451457, 6459.580127291711, 6308.369163083655, 6321.304656487782, 6509.414152726277, 6529.444997175198, 6679.998000299896, 6546.050822630692, 6556.639384833785, 6560.0, 6564.277929427759, 6569.737210511622, 6462.3537646235, 6546.410823025951, 6549.803505894831, 6560.803175904731, 6572.802815915435, 5192.9740351298005, 5298.761555730001, 5304.92042619361, 5317.787288508416, 5334.546563542142, 4690.835820746326, 4704.153252414599, 4712.104700106968, 4742.715437073786, 4760.976195119002, 6148.231471066117, 6158.784442544489, 6162.291336496271, 6165.815025549241, 6165.938340616536, 6308.558400911892, 6401.4238718514625, 6533.705983230766, 6558.081868538408, 6591.934080659228, 6139.216166136355, 6141.117811339906, 6143.815685529441, 6151.2618485781995, 6159.476444502178, 5786.07422812348, 5820.0, 5851.502622277154, 5854.975379308604, 5863.501602363762, 6454.870902581828, 6548.148740663683, 6632.303608121078, 6725.260221375743, 6729.831754206184, 5608.551315894801, 6502.252240992158, 6554.672266386567, 6622.735090596322, 6634.237062737654, 6688.765893193715, 6711.228208756055, 6713.160854893138, 6713.429358504582, 6719.697613607302, 5403.5136837684795, 6132.41742034506, 6443.7422503101225, 6501.2523559791225, 6508.056331831953, 6336.651484168231, 6399.072134540559, 6425.1968503937005, 6434.64609446485, 6475.190601174853, 6360.586561873463, 6363.5227357947415, 6374.26695929974, 6382.712777925098, 6383.39357761012, 6145.907811382922, 6189.288231853395, 6263.498920086291, 6305.8108256752375, 6353.7140828661895, 6256.843578910563, 6257.78097766576, 6261.843453913689, 6278.780242691484, 6280.026595877646, 6217.906731398942, 6264.0, 6271.216097987752, 6359.8092057238355, 6372.490200784019, 6223.502119830493, 6239.812805615838, 6266.310705822447, 6267.0, 6272.40412160844], "value": 5968.046772126138, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [748.8352562436265, 788.7042359115331, 809.5587904591997, 1135.2393896089613, 1151.0446329546478, 27921.581176282347, 36717.265654686926, 37296.62703372969, 37299.0, 37344.813275933586, 39066.0, 39334.819955401385, 39548.638365738945, 39549.81350559481, 39553.802230988804, 6038.215032045828, 6185.134081228632, 8390.874136887951, 8563.314934805201, 8577.656893724243, 6167.0, 6252.437280644741, 6263.0, 6272.278687950876, 8374.58127093646, 8567.914320856775, 8568.914310856906, 8635.309175265995, 8637.697680581181, 8661.566921653925, 7385.815354616124, 8520.40357174998, 8613.526256055915, 8620.008698999602, 8636.697715579987, 8394.958025209835, 8467.618957146944, 8481.236688697985, 8483.830323393497, 8494.320454363637, 34278.82860585681, 37501.31244094022, 39054.60945390538, 39368.01579960495, 39383.0, 3007.6090108286003, 3019.124453908364, 3031.602461182512, 3041.376517813843, 3048.3446059097178, 27998.880044798185, 28490.86036558535, 28579.714202858122, 28660.856695716597, 29604.8519757402, 2424.260600516842, 2752.4357506711076, 2806.5509518476947, 2815.0569559197693, 2866.498362786504, 327304.0, 330954.0, 331167.0, 331569.0, 331706.3414682935, 49989.750256243155, 50122.749386252835, 50135.74932125316, 50157.74921125371, 50229.24197653065, 52726.94546109057, 52785.7360713194, 52807.98324159199, 52850.18647948722, 52871.73564132232, 14375.28123593816, 14823.703525929637, 14833.258337083103, 14835.480758173411, 14857.405703771945, 9970.800583988279, 12055.638330850088, 12060.216085954386, 12085.637430877088, 12114.273143611412, 11145.275557088764, 11218.943905280421, 11238.325700458, 13012.47950082005, 13259.469621215236, 4656.278276867091, 4707.176244157259, 4710.505396933341, 5147.639665223398, 5344.9732751336, 942.8491441369348, 973.1193270090579, 980.9705708828747, 984.3364713633936, 990.4305024610877, 24185.637215441784, 24518.01927922899, 24521.509569808502, 24550.0, 24588.631170532455, 1063.9042486176281, 1091.252492042953, 1116.1405717597404, 1118.848955391028, 1133.9603113890973, 11649.41752912351, 11782.234154779911, 12308.953738932112, 12460.501579936883, 12486.0, 450498.0, 453138.0, 453531.0, 453898.0, 454253.0, 56679.14981275284, 56753.0, 56766.0, 56813.43186568164, 57122.0, 1444.9349779259749, 1460.5545308680742, 1500.4973333933037, 1506.374854435419, 1524.3978628441703, 22395.552088957804, 23948.760512394652, 28157.71842281551, 28190.85904570464, 28918.987835425658, 47766.52233477621, 49924.75188120326, 51728.48271517387, 51772.0, 51924.74037629788, 348877.0, 349325.0, 349374.0, 349506.0, 350328.0, 32875.34249315048, 32894.835525822215, 32947.0, 32961.83519082389, 34235.65764342325, 43017.78491107524, 43301.56698432975, 43381.3492797602, 44762.55237447584, 44822.0, 29315.12054638364, 30260.0, 30353.0, 30375.544366834958, 30632.387352252383, 29522.770195187648, 29545.704542954296, 30211.09366719002, 30267.848660757438, 30730.005099336944, 29136.854315728287, 36476.0, 36828.81585592055, 36894.81552592327, 37041.25917481689, 22695.546089077794, 29866.850665747403, 30437.695623043484, 31105.0, 31262.53106203454, 22489.77510224877, 23955.161569345688, 24246.75753242445, 24294.392640184138, 24424.389390265387], "value": 17518.39223008458, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.4141549274682, 391.38747859599744, 392.86642541535883, 418.5960548071111, 448.16641047651365, 13286.069975101735, 19401.417957461283, 21452.249171279007, 22399.776002239974, 22413.103475860968, 21818.563628727417, 22149.446263843412, 22254.99852506637, 22296.554068918613, 22320.441988950286, 4900.901981960359, 5848.444397782215, 6265.530085243602, 6349.841253968664, 6362.268339140988, 6112.083187521863, 6223.066540018988, 6279.089532017854, 6280.842978925518, 6291.968540157292, 6023.18686977259, 6248.031555108965, 6265.154204182445, 6274.937250627504, 6276.089966954789, 6074.362191969868, 6143.1092491588915, 6170.6297622142365, 6174.629522228658, 6224.097505861648, 5492.340919089735, 5521.696306703129, 5526.171074338881, 5529.115341545334, 5535.972320138373, 26402.339941501425, 26533.0, 27253.727462725317, 27421.17736467909, 27798.0, 1926.9325573604856, 1938.5928954919484, 1973.2107157137116, 1976.9505762355911, 2050.0979568989746, 15228.619284517865, 16047.27787249565, 16291.674166516721, 16329.510114696579, 16466.588335291715, 1511.4332125452954, 1638.189096397291, 1668.6329007618426, 1689.6113893804493, 1695.4998275508754, 155145.0, 160356.99107522288, 161896.19051904784, 164640.17679911642, 165727.9999999988, 11656.552124053875, 11709.824352634718, 11902.226355286879, 11936.462859171359, 11978.25043978895, 16045.037297761939, 16054.598635034217, 16073.517794466185, 16088.75866861998, 16102.516924492284, 8157.918420815716, 8381.790455238546, 8390.748277551684, 8447.95776021116, 8509.574521274035, 7726.34326082278, 7767.18444563324, 7946.7615971520945, 8021.358291336679, 8033.0, 7422.0, 7545.811354716176, 7586.0, 7612.3148916597775, 7615.581143037186, 1742.8954262744276, 2067.6071546406133, 2068.469332693796, 2075.8339332853334, 2232.475368288451, 878.8505953987838, 896.7085697148453, 896.8475359188953, 915.1269489965958, 930.5626355612986, 20004.89997550032, 20030.298939537046, 20205.0, 20259.392218233475, 20371.0, 825.2284114353099, 904.6200595749801, 928.3223247029706, 960.2653969713175, 966.7099870039101, 9260.39807412516, 10154.949225253826, 10496.370217786956, 10558.788824223473, 10864.456777161111, 414637.0, 514704.0, 577357.0, 577500.0, 628322.0, 113509.0, 130091.0, 163052.0, 164011.0, 169098.0, 1177.3406892140429, 1221.6518292286655, 1254.899608031337, 1257.6541451100838, 1259.6913756129807, 18421.631567368844, 20000.399988000383, 20068.59862802765, 22327.0, 22408.215712449986, 57120.71439642775, 60294.69852650708, 64535.67732161309, 65784.34215657783, 66020.33979660142, 80822.59588702019, 82752.17247827444, 83871.58064209639, 85922.0, 87165.0, 11891.881081189078, 12123.333216673167, 12432.937835310766, 12741.235525868478, 12840.678983025498, 13529.0, 13556.051076424556, 13810.171389716648, 14008.719825603635, 14066.015378923377, 11638.88361116378, 11651.242669226813, 11851.288922664668, 12003.219790713576, 12072.698182545506, 11031.338119712844, 11116.722081948015, 11305.43472826339, 11372.757699786544, 11408.258463199865, 11722.0, 11905.16663833558, 11971.221870578385, 12052.0, 12097.33464659417, 12198.634040978784, 12274.693132671755, 12463.31451770125, 12533.623991280276, 12803.487860485477, 11533.480993355486, 11760.88239117598, 11994.220375675553, 12010.63968080959, 12028.518859245532], "value": 10431.589284345779, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1190.4821402689831, 1338.8058731483934, 1353.2827601371278, 1353.3368649361817, 1358.0493654441893, 10348.0, 10803.513841877115, 10815.675529734111, 10821.188410869197, 10826.1880358973, 9898.307118501707, 10197.694069177918, 10260.486975651225, 10260.692179234617, 10523.947380263107, 2404.2546810488716, 2451.2891261534132, 2452.497238066201, 2453.116877923946, 2457.5453541094867, 2084.9270275540357, 2252.526969336441, 2374.157174203159, 2411.915582954597, 2454.3373289211904, 2055.2909246310073, 2138.2516119358256, 2139.871607703543, 2368.8578685278853, 2451.4239153798844, 2437.756224377557, 2460.692413448319, 2462.3105530451503, 2465.297390243772, 2465.9506809863883, 1889.5087277307998, 2398.9698890649797, 2447.8776061197045, 2461.5815311397105, 2466.075221791828, 10739.301945373603, 10880.782384352347, 10884.23810333277, 10889.346639201673, 11089.112870970303, 6202.069689546559, 6452.387023232785, 6452.903206451907, 6467.094606755058, 6473.546851720382, 3676.669099781006, 3791.6018818023986, 3894.201688653819, 4038.434619153321, 4120.96975756061, 876.3471213945651, 1172.4196522721309, 1200.8258802473679, 1221.3709939381226, 1230.267990545624, 15323.999999999889, 15440.227988600638, 15515.922420387938, 15531.06813591188, 15540.844591554052, 5116.56509196715, 5352.143657014937, 5443.985725281671, 5460.918086228709, 5472.179173124063, 11482.827757583644, 11490.712732181595, 11501.827472587918, 11505.13711471643, 11528.481218345194, 6319.399657032666, 6326.0, 6326.49388048955, 6328.5886417383645, 6328.71520781566, 5613.915791263134, 5808.3901190375245, 5959.344472108065, 6020.247469066367, 6042.244719410074, 4056.0, 4188.60208280219, 4212.115455754326, 4250.234957707642, 4253.957460425418, 1057.4976885979252, 1235.3508066730835, 1244.7137158453606, 1262.9621111366673, 1266.4870727355435, 740.2227660955974, 742.4913933955258, 768.8923550702906, 775.4145620056821, 805.3718099882101, 5756.683382414008, 5878.941210587925, 6032.638041717511, 6087.54343424245, 6088.990633091069, 556.3740791609439, 578.5776383240258, 593.1814096546824, 594.5184400635499, 597.1729155120103, 3737.050442204313, 4212.49450065994, 4224.936625950613, 4380.737155770664, 4402.207602631493, 13293.867061329263, 14030.508932187324, 14061.929690351688, 14082.295885205698, 14101.153930764187, 13599.728005440034, 13605.31973401326, 13651.112677676118, 14010.719785604231, 14034.508792192224, 10091.49542522871, 10165.542550585242, 10204.897951020543, 10338.896611033944, 10366.0, 5634.6337488063145, 5680.40355762639, 6511.967440162769, 6520.413162815369, 6598.306725923059, 12075.033997280021, 12152.513899443924, 12249.69375765613, 12531.87468125307, 12544.93727531393, 11398.658040258806, 11653.184277100521, 12589.559365422167, 12784.936075319936, 12854.357282136043, 8140.023197216372, 8205.753827385188, 8276.213759692819, 8337.83324333522, 8341.20758527939, 9961.651342202988, 9984.850227246743, 10167.0, 10179.592816287563, 10210.642627508001, 6759.459243260429, 8510.0, 8615.095414981588, 8726.869096963424, 8730.34522410809, 8156.662239486999, 8211.425200235926, 8361.66553337884, 8407.327413806759, 8484.703035393732, 7614.695412183451, 7706.845863082819, 7838.764837054898, 7839.137694853675, 7850.136484986761, 5629.0, 5956.195913551788, 6002.459778619946, 6016.639001659914, 6071.392860713893, 5159.380874295109, 5902.380250073852, 6123.142760013513, 6206.689665516797, 6240.875182496234], "value": 5037.59749899074, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.1679842390083, 377.8998565380174, 393.88971088095343, 399.24741861590894, 401.57232547337094, 5007.774650140744, 5677.943220567793, 5787.681677507735, 5931.9406805931985, 6012.969935150329, 5772.538196944245, 5772.913406298909, 5847.619904706191, 5892.175095486636, 5909.704514774266, 3856.614338566149, 4295.720778149426, 4424.646028317743, 4441.733495990251, 4444.200043992072, 4433.379326894237, 4444.22226110431, 4446.7776611169475, 4466.642668586505, 4469.418975533176, 4401.229784787665, 4429.06989532197, 4436.689431739779, 4439.977800111011, 4449.998750281177, 4254.510731265913, 4433.113377324548, 4437.334399840033, 4449.66627502937, 4469.508354081048, 3186.279409119073, 3225.080851957181, 3388.356212319676, 3585.695215906651, 3812.370958791796, 4966.541561926833, 5708.257926469532, 5767.279090113736, 5783.21926539916, 5784.971075144597, 3586.4620306954166, 4260.786960651955, 4767.594754445876, 5101.4695591322825, 5133.640645154841, 5278.736063196825, 5280.445553216934, 6235.438810507031, 6265.561410701207, 6326.272478664966, 4270.974966008136, 4277.486701595828, 4282.794360904137, 4284.550122237151, 4315.223259813232, 4931.211006238985, 5482.506574408322, 5798.347470970753, 5985.491233245223, 6784.423324017464, 3507.7544571880235, 3536.0, 3727.981360093182, 4056.3915412688334, 4175.561566035522, 4028.3756017817286, 4380.408644832907, 4381.846635367747, 4386.342048692658, 4411.382406463098, 4270.850520231777, 4783.808647654125, 4808.519148085164, 4892.363992681, 5133.897322053538, 5691.089425691869, 6116.908246376308, 6262.937370626327, 6274.988005757262, 6283.59156654816, 1317.4795955597676, 1476.6825132596543, 1495.6410461489165, 1503.8195416550084, 1508.3212554350366, 949.1789601994225, 992.528548939248, 995.3928103856641, 1004.3873237325225, 1009.4801177393648, 5085.389753229636, 5378.731063446812, 5712.714364281769, 5739.253896993364, 5798.652080875161, 6130.448259656652, 6140.723667434976, 6462.579932304385, 6486.967565162238, 6490.513211509156, 5504.4770746778995, 5669.688167150764, 5856.531477481792, 5895.823125306248, 5910.408959104141, 2899.666538348075, 3406.284680217182, 3438.449848024304, 3453.9827300863335, 3454.360943225461, 5738.569607279471, 6301.464375528039, 6456.0, 6685.899711504331, 6695.497837662195, 5590.972045139748, 5738.0, 6391.712372943229, 6719.462443004548, 6835.897461538081, 6189.195404597373, 6594.40650341462, 6615.536912416084, 6635.701393437307, 6647.401733843881, 6459.547831651739, 6489.286178520265, 6507.869842603216, 6515.804525864231, 6559.868802623825, 5445.564354851523, 5627.52166065889, 5785.22309291534, 6144.754209831557, 6451.903221451776, 6305.662808877049, 6508.349165083454, 6595.703193356407, 6596.736130554916, 6631.237407698177, 3270.296886169485, 3589.712822974208, 3873.8378486454517, 3957.8522228553397, 4234.4495215623, 5896.410358964241, 6283.403076707706, 6394.26465956421, 6509.934900650933, 6512.283648798723, 6242.469390101892, 6278.6860656966055, 6280.9685951569945, 6286.748530058747, 6301.590396624205, 6263.68681565929, 6269.435750782452, 6288.182536270256, 6298.921355952616, 6319.115323854572, 5802.941970580409, 6079.544034197364, 6135.300467794156, 6137.3862613738265, 6157.907631385443, 5442.857000030038, 5712.714364281686, 6082.574219804571, 6247.875042499216, 6275.717592708431, 5849.678267695147, 6062.848428789315, 6077.5745697802495, 6121.8469538260115, 6165.797669454413], "value": 4520.126014399104, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [714.8748968930436, 726.2410780734132, 726.8073960400492, 735.5917465806475, 746.6751962896138, 14714.926425367872, 14796.038257513253, 15444.459443919452, 15450.5364839055, 15454.845451545496, 14506.492272770454, 14583.0, 14929.85070149301, 14962.775558366606, 15011.174385408798, 932.6129656192688, 957.4255446731939, 962.8555716642489, 965.4496936746058, 966.7969726357474, 915.8671992561075, 954.2270760683869, 962.8796400449944, 963.6001059560274, 963.9903600964005, 1007.3301254665676, 1009.328796350426, 1010.4745532323187, 1010.5755582655303, 1013.027493606139, 1004.6634377483551, 1004.678502879079, 1005.0452070533023, 1006.5219020965056, 1008.3193844155165, 743.7843025522592, 744.5569885917924, 746.5632604926125, 747.2340850628095, 747.3050063440998, 16129.919350403174, 16618.25217865199, 16688.58278543034, 16723.247453864482, 17003.829961700347, 14480.348384322731, 14563.781543276858, 14580.987574713014, 14631.195284259362, 14744.0, 11510.251833630788, 11594.362310072942, 11676.649700508999, 11850.940745296304, 12138.635840924786, 2635.420207554335, 3017.3908735074247, 3045.482268014443, 3047.9847600762077, 3130.8904188353526, 103734.48132759288, 108360.0, 108453.0, 112031.0, 112075.43962280218, 10187.694369168938, 10689.251752377415, 11259.549618015351, 11336.886631133582, 11395.715107122223, 24590.877045614656, 24984.0, 25004.999800007798, 25194.87402563012, 25227.62158567623, 7547.471676982668, 7626.9618651906385, 7638.732644357421, 7660.310572048543, 7719.035120609924, 3960.9009774755286, 4001.7198796083994, 4001.9399709004388, 4034.1504818013996, 4087.89780255496, 4075.3275709507598, 4168.249715051259, 4192.685548583869, 4193.706440549132, 4198.6221240087925, 771.0468904097294, 800.3277247112453, 815.9469634473741, 862.6894318045498, 891.6166048599035, 524.9002689488985, 550.6778534557249, 555.6082014553517, 570.1931766550367, 596.341043147316, 8992.955035224782, 10177.33847299938, 10324.483775811179, 10477.9476102619, 10730.312934144915, 534.9812756553501, 535.2399592578548, 577.3504807092022, 602.7106988645475, 610.7353035641025, 4279.768774575238, 4410.977945110254, 4628.143793398232, 4926.3842019747535, 5198.92201616976, 101887.0, 105000.0, 105875.47062264639, 107050.3942440864, 109133.45433272782, 92641.5367923156, 92952.53523732338, 93497.532512337, 95838.0, 95873.0, 45024.0, 63870.0, 79377.20622793928, 80131.3973720534, 80479.0, 8093.3929955252415, 9511.714648560554, 9745.853812192965, 11023.503942322775, 11044.226904116957, 28407.573886392132, 37365.626343736214, 38532.80733596314, 38768.0, 39454.60545394509, 38054.0, 49163.75418122886, 49591.76020599514, 50504.74747626238, 51359.0, 13288.734225315633, 13404.798928015893, 13445.462181513021, 13911.860881391056, 13975.580732578039, 14229.50196743109, 14264.45050855867, 14470.0, 14472.638184045483, 14514.782278265624, 13240.139390939557, 13321.067525273525, 13338.799918001432, 13479.258640774851, 13545.72908541804, 11170.88829111731, 13098.672533186365, 13203.339833008118, 13284.53504127351, 13812.240326782123, 12991.350432478528, 13007.055550034931, 13345.532906348231, 13780.0, 13899.930500347433, 9485.0, 9678.612855485702, 9712.75718107053, 9969.0, 10450.947745261225, 8782.09700427958, 9728.75678108053, 9933.453660048477, 9980.301378903407, 11162.33026018409], "value": 7448.6405689921885, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1482.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 111.0, 112.0, 113.0, 112.0, 117.0, 161.0, 111.0, 113.0, 112.0], "value": 117.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 110.0, 111.0, 159.0, 164.0, 110.0, 109.0, 108.0, 130.0, 110.0], "value": 125.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 185.0, 187.0, 187.0, 196.0, 234.0, 266.0, 200.0, 183.0, 184.0], "value": 201.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 62.0, 62.0, 91.0, 60.0, 60.0, 58.0, 57.0, 74.0, 58.0], "value": 66.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 87.0, 69.0, 69.0, 69.0, 83.0, 68.0, 70.0, 85.0], "value": 76.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 87.0, 86.0, 86.0, 85.0, 86.0, 86.0, 87.0, 86.0], "value": 86.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 98.0, 94.0, 94.0, 94.0, 95.0, 95.0, 95.0, 94.0, 93.0], "value": 94.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 135.0, 129.0, 130.0, 97.0, 140.0, 128.0, 83.0, 86.0, 85.0], "value": 109.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 62.0, 60.0, 62.0, 63.0, 61.0, 62.0, 62.0, 62.0], "value": 62.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 64.0, 66.0, 57.0, 61.0, 60.0, 58.0, 58.0, 58.0, 60.0], "value": 59.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 82.0, 84.0, 84.0, 83.0, 81.0, 83.0, 81.0, 83.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 144.0, 136.0, 141.0, 145.0, 144.0, 147.0, 138.0, 176.0, 143.0], "value": 145.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 184.0, 187.0, 183.0, 188.0, 187.0, 183.0, 189.0, 186.0, 224.0], "value": 188.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 68.0, 67.0, 68.0, 67.0, 67.0, 68.0, 67.0], "value": 67.3, "unit": "score"}]}]} 05:24:50 INFO - Return code: 0 05:24:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:24:50 ERROR - # TBPL SUCCESS # 05:24:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:24:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:24:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:24:50 INFO - 2017-03-27 05:24:50.245 screenresolution[6635:123797] starting screenresolution argv=screenresolution get 05:24:50 INFO - 2017-03-27 05:24:50.248 screenresolution[6635:123797] Display 0: 1600x1200x32@60 05:24:50 INFO - 2017-03-27 05:24:50.253 screenresolution[6636:123800] starting screenresolution argv=screenresolution list 05:24:50 INFO - Available Modes on Display 0 05:24:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:24:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:24:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:24:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:24:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:24:50 INFO - Intel Iris: 05:24:50 INFO - Chipset Model: Intel Iris 05:24:50 INFO - Type: GPU 05:24:50 INFO - Bus: Built-In 05:24:50 INFO - VRAM (Dynamic, Max): 1536 MB 05:24:50 INFO - Vendor: Intel (0x8086) 05:24:50 INFO - Device ID: 0x0a2e 05:24:50 INFO - Revision ID: 0x0009 05:24:50 INFO - Displays: 05:24:50 INFO - Display: 05:24:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:24:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:24:50 INFO - Display Serial Number: 1600x1200 60 05:24:50 INFO - Main Display: Yes 05:24:50 INFO - Mirror: Off 05:24:50 INFO - Online: Yes 05:24:50 INFO - Rotation: Supported 05:24:50 INFO - Return code: 0 05:24:50 INFO - Running post-action listener: _resource_record_post_action 05:24:50 INFO - [mozharness: 2017-03-27 12:24:50.486358Z] Finished run-tests step (success) 05:24:50 INFO - Running post-run listener: _resource_record_post_run 05:24:50 INFO - Total resource usage - Wall time: 1204s; CPU: 53.0%; Read bytes: 1305600; Write bytes: 514211328; Read time: 17; Write time: 1899 05:24:50 INFO - TinderboxPrint: CPU usage
53.4% 05:24:50 INFO - TinderboxPrint: I/O read bytes / time
1,305,600 / 17 05:24:50 INFO - TinderboxPrint: I/O write bytes / time
514,211,328 / 1,899 05:24:50 INFO - TinderboxPrint: CPU idle
2,246.2 (46.6%) 05:24:50 INFO - TinderboxPrint: CPU system
964.3 (20.0%) 05:24:50 INFO - TinderboxPrint: CPU user
1,607.2 (33.4%) 05:24:50 INFO - TinderboxPrint: Swap in / out
212,877,312 / 0 05:24:50 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203614720; Write bytes: 205527040; Read time: 15626; Write time: 502 05:24:50 INFO - run-tests - Wall time: 1184s; CPU: 53.0%; Read bytes: 1305600; Write bytes: 298718720; Read time: 17; Write time: 1363 05:24:51 INFO - Running post-run listener: _upload_blobber_files 05:24:51 INFO - Blob upload gear active. 05:24:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:24:51 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 05:24:51 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:24:51 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:24:51 INFO - (blobuploader) - INFO - Open directory for files ... 05:24:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:24:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:24:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:24:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:24:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:24:52 INFO - (blobuploader) - INFO - Done attempting. 05:24:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:24:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:24:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:24:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:24:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:24:53 INFO - (blobuploader) - INFO - Done attempting. 05:24:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:24:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:24:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:24:54 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:24:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:24:54 INFO - (blobuploader) - INFO - Done attempting. 05:24:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:24:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:24:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:24:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:24:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:24:56 INFO - (blobuploader) - INFO - Done attempting. 05:24:56 INFO - (blobuploader) - INFO - Iteration through files over. 05:24:56 INFO - Return code: 0 05:24:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:24:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:24:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce6debce5d10096ef22e6f3cc580c79b7367f9f42387f9699cf996714564560db306babd4f2cc73482671f3e71e478d90b4bc6c6828c8cba153798d0876dfa31", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94903e039bccc4b5a7a992b19a658e50fd6794ec03011b557998b05c5cc59e271d5edadf52462f72c4259e9275b09932d03a2737d1fa479592f50e1c73f49ba5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7700a726a25881639882146e27b5fb6b8e0c5ef10f250d24b705d30757a9cc8d40fae0fa1a599aac32494b58e843972e9a61b467f47d6d2614ddedebf8bf65ed", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8493ddc6b313264119212076412374bba2cf47aa07e7168d53ba57c7bc090b589642e2d5022fb4813a21c0e8d75a3f29fd28e24648f20c518f088e77d76d6e95"} 05:24:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:24:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:24:56 INFO - Contents: 05:24:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce6debce5d10096ef22e6f3cc580c79b7367f9f42387f9699cf996714564560db306babd4f2cc73482671f3e71e478d90b4bc6c6828c8cba153798d0876dfa31", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94903e039bccc4b5a7a992b19a658e50fd6794ec03011b557998b05c5cc59e271d5edadf52462f72c4259e9275b09932d03a2737d1fa479592f50e1c73f49ba5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7700a726a25881639882146e27b5fb6b8e0c5ef10f250d24b705d30757a9cc8d40fae0fa1a599aac32494b58e843972e9a61b467f47d6d2614ddedebf8bf65ed", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8493ddc6b313264119212076412374bba2cf47aa07e7168d53ba57c7bc090b589642e2d5022fb4813a21c0e8d75a3f29fd28e24648f20c518f088e77d76d6e95"} 05:24:56 INFO - Running post-run listener: copy_logs_to_upload_dir 05:24:56 INFO - Copying logs to upload dir... 05:24:56 INFO - mkdir: /builds/slave/test/build/upload/logs 05:24:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1297.622280 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 37 secs) (at 2017-03-27 05:24:56.230207) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 05:24:56.235753) ========= 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.BDtFuPi0Wl/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.g9l7TO4D1B/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce6debce5d10096ef22e6f3cc580c79b7367f9f42387f9699cf996714564560db306babd4f2cc73482671f3e71e478d90b4bc6c6828c8cba153798d0876dfa31", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94903e039bccc4b5a7a992b19a658e50fd6794ec03011b557998b05c5cc59e271d5edadf52462f72c4259e9275b09932d03a2737d1fa479592f50e1c73f49ba5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7700a726a25881639882146e27b5fb6b8e0c5ef10f250d24b705d30757a9cc8d40fae0fa1a599aac32494b58e843972e9a61b467f47d6d2614ddedebf8bf65ed", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8493ddc6b313264119212076412374bba2cf47aa07e7168d53ba57c7bc090b589642e2d5022fb4813a21c0e8d75a3f29fd28e24648f20c518f088e77d76d6e95"} build_url:https://queue.taskcluster.net/v1/task/FTPLurtWSHuX5XvYx52hqA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011594 build_url: 'https://queue.taskcluster.net/v1/task/FTPLurtWSHuX5XvYx52hqA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce6debce5d10096ef22e6f3cc580c79b7367f9f42387f9699cf996714564560db306babd4f2cc73482671f3e71e478d90b4bc6c6828c8cba153798d0876dfa31", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94903e039bccc4b5a7a992b19a658e50fd6794ec03011b557998b05c5cc59e271d5edadf52462f72c4259e9275b09932d03a2737d1fa479592f50e1c73f49ba5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7700a726a25881639882146e27b5fb6b8e0c5ef10f250d24b705d30757a9cc8d40fae0fa1a599aac32494b58e843972e9a61b467f47d6d2614ddedebf8bf65ed", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8493ddc6b313264119212076412374bba2cf47aa07e7168d53ba57c7bc090b589642e2d5022fb4813a21c0e8d75a3f29fd28e24648f20c518f088e77d76d6e95"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 05:24:56.274901) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 05:24:56.275260) ========= 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.BDtFuPi0Wl/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.g9l7TO4D1B/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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.005535 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 05:24:56.324317) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 05:24:56.324687) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 05:24:56.325078) ========= ========= Total master_lag: 0.20 =========