builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0379 starttime: 1490180237.77 results: success (0) buildid: 20170322030136 builduid: 512059dfe16745b1af3bb9605b753b15 revision: c5cc62e792d9f0bbd84e380da79102cc3efcfe08 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-22 03:57:17.767778) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-22 03:57:17.768203) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-22 03:57:17.768481) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-22 03:57:17.795339) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 03:57:17.795779) ========= 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.Tfen0ohELu/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.eU62nKFO00/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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-22 03:57:17-- 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% 145M=0s 2017-03-22 03:57:17 (145 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.094065 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 03:57:17.914029) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 03:57:17.914375) ========= 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.Tfen0ohELu/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.eU62nKFO00/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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.026653 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 03:57:17.963462) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-22 03:57:17.963824) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev c5cc62e792d9f0bbd84e380da79102cc3efcfe08 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev c5cc62e792d9f0bbd84e380da79102cc3efcfe08 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Tfen0ohELu/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.eU62nKFO00/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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-22 03:57:18,071 truncating revision to first 12 chars 2017-03-22 03:57:18,072 Setting DEBUG logging. 2017-03-22 03:57:18,072 attempt 1/10 2017-03-22 03:57:18,072 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/c5cc62e792d9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-22 03:57:19,289 unpacking tar archive at: mozilla-beta-c5cc62e792d9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.496551 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-22 03:57:19.485156) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-22 03:57:19.485477) ========= script_repo_revision: c5cc62e792d9f0bbd84e380da79102cc3efcfe08 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-22 03:57:19.485881) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-22 03:57:19.486166) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-22 03:57:19.506357) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-03-22 03:57:19.506830) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Tfen0ohELu/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.eU62nKFO00/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 03:57:19 INFO - MultiFileLogger online at 20170322 03:57:19 in /builds/slave/test 03:57:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 03:57:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:57:19 INFO - {'append_to_log': False, 03:57:19 INFO - 'base_work_dir': '/builds/slave/test', 03:57:19 INFO - 'blob_upload_branch': 'Mozilla-Beta', 03:57:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:57:19 INFO - 'branch': 'Mozilla-Beta', 03:57:19 INFO - 'buildbot_json_path': 'buildprops.json', 03:57:19 INFO - 'config_files': ('talos/mac_config.py',), 03:57:19 INFO - 'default_actions': ('clobber', 03:57:19 INFO - 'read-buildbot-config', 03:57:19 INFO - 'download-and-extract', 03:57:19 INFO - 'populate-webroot', 03:57:19 INFO - 'create-virtualenv', 03:57:19 INFO - 'install', 03:57:19 INFO - 'run-tests'), 03:57:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:57:19 INFO - 'download_minidump_stackwalk': True, 03:57:19 INFO - 'download_symbols': 'ondemand', 03:57:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:57:19 INFO - 'tooltool.py': '/tools/tooltool.py', 03:57:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:57:19 INFO - '/tools/misc-python/virtualenv.py')}, 03:57:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:57:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:57:19 INFO - 'installer_path': 'installer.exe', 03:57:19 INFO - 'log_level': 'info', 03:57:19 INFO - 'log_name': 'talos', 03:57:19 INFO - 'log_to_console': True, 03:57:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:57:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:57:19 INFO - 'opt_config_files': (), 03:57:19 INFO - 'pip_index': False, 03:57:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:57:19 INFO - 'cmd': ('bash', 03:57:19 INFO - '-c', 03:57:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:57:19 INFO - 'enabled': True, 03:57:19 INFO - 'halt_on_failure': False, 03:57:19 INFO - 'name': 'check_screen_resolution'},), 03:57:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:57:19 INFO - 'cmd': ('bash', 03:57:19 INFO - '-c', 03:57:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:57:19 INFO - 'enabled': True, 03:57:19 INFO - 'halt_on_failure': False, 03:57:19 INFO - 'name': 'check_screen_resolution'},), 03:57:19 INFO - 'run_cmd_checks_enabled': True, 03:57:19 INFO - 'sps_profile': False, 03:57:19 INFO - 'sps_profile_interval': 0, 03:57:19 INFO - 'suite': 'dromaeojs-e10s', 03:57:19 INFO - 'system_bits': '32', 03:57:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:57:19 INFO - 'title': 't-yosemite-r7-0379', 03:57:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:57:19 INFO - 'use_talos_json': True, 03:57:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:57:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:57:19 INFO - 'work_dir': 'build'} 03:57:19 INFO - [mozharness: 2017-03-22 10:57:19.721511Z] Running clobber step. 03:57:19 INFO - Running pre-action listener: _resource_record_pre_action 03:57:19 INFO - Running main action method: clobber 03:57:19 INFO - rmtree: /builds/slave/test/build 03:57:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:57:22 INFO - Running post-action listener: _resource_record_post_action 03:57:22 INFO - [mozharness: 2017-03-22 10:57:22.130400Z] Finished clobber step (success) 03:57:22 INFO - [mozharness: 2017-03-22 10:57:22.130591Z] Running read-buildbot-config step. 03:57:22 INFO - Running pre-action listener: _resource_record_pre_action 03:57:22 INFO - Running main action method: read_buildbot_config 03:57:22 INFO - Using buildbot properties: 03:57:22 INFO - { 03:57:22 INFO - "project": "", 03:57:22 INFO - "product": "firefox", 03:57:22 INFO - "script_repo_revision": "production", 03:57:22 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 03:57:22 INFO - "repository": "", 03:57:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 03:57:22 INFO - "buildid": "20170322030136", 03:57:22 INFO - "pgo_build": "False", 03:57:22 INFO - "basedir": "/builds/slave/test", 03:57:22 INFO - "buildnumber": 3, 03:57:22 INFO - "slavename": "t-yosemite-r7-0379", 03:57:22 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 03:57:22 INFO - "platform": "yosemite_r7", 03:57:22 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 03:57:22 INFO - "branch": "mozilla-beta", 03:57:22 INFO - "repo_path": "releases/mozilla-beta", 03:57:22 INFO - "revision": "c5cc62e792d9f0bbd84e380da79102cc3efcfe08", 03:57:22 INFO - "stage_platform": "macosx64", 03:57:22 INFO - "builduid": "512059dfe16745b1af3bb9605b753b15", 03:57:22 INFO - "slavebuilddir": "test" 03:57:22 INFO - } 03:57:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZmR-3WC5ScyNH_t4rNLUSg/artifacts/public/build/firefox-53.0.en-US.mac.dmg. 03:57:22 INFO - Running post-action listener: _resource_record_post_action 03:57:22 INFO - [mozharness: 2017-03-22 10:57:22.133811Z] Finished read-buildbot-config step (success) 03:57:22 INFO - [mozharness: 2017-03-22 10:57:22.133945Z] Running download-and-extract step. 03:57:22 INFO - Running pre-action listener: _resource_record_pre_action 03:57:22 INFO - Running main action method: download_and_extract 03:57:22 INFO - mkdir: /builds/slave/test/build/tests 03:57:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:22 INFO - trying https://queue.taskcluster.net/v1/task/ZmR-3WC5ScyNH_t4rNLUSg/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json 03:57:22 INFO - Downloading https://queue.taskcluster.net/v1/task/ZmR-3WC5ScyNH_t4rNLUSg/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 03:57:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZmR-3WC5ScyNH_t4rNLUSg/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json'}, attempt #1 03:57:23 INFO - Downloaded 1165 bytes. 03:57:23 INFO - Reading from file /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 03:57:23 INFO - Using the following test package requirements: 03:57:23 INFO - {u'common': [u'firefox-53.0.en-US.mac.common.tests.zip'], 03:57:23 INFO - u'cppunittest': [u'firefox-53.0.en-US.mac.common.tests.zip', 03:57:23 INFO - u'firefox-53.0.en-US.mac.cppunittest.tests.zip'], 03:57:23 INFO - u'gtest': [u'firefox-53.0.en-US.mac.common.tests.zip', 03:57:23 INFO - u'firefox-53.0.en-US.mac.gtest.tests.zip'], 03:57:23 INFO - u'jittest': [u'firefox-53.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 03:57:23 INFO - u'mochitest': [u'firefox-53.0.en-US.mac.common.tests.zip', 03:57:23 INFO - u'firefox-53.0.en-US.mac.mochitest.tests.zip'], 03:57:23 INFO - u'mozbase': [u'firefox-53.0.en-US.mac.common.tests.zip'], 03:57:23 INFO - u'reftest': [u'firefox-53.0.en-US.mac.common.tests.zip', 03:57:23 INFO - u'firefox-53.0.en-US.mac.reftest.tests.zip'], 03:57:23 INFO - u'talos': [u'firefox-53.0.en-US.mac.common.tests.zip', 03:57:23 INFO - u'firefox-53.0.en-US.mac.talos.tests.zip'], 03:57:23 INFO - u'web-platform': [u'firefox-53.0.en-US.mac.common.tests.zip', 03:57:23 INFO - u'firefox-53.0.en-US.mac.web-platform.tests.zip'], 03:57:23 INFO - u'xpcshell': [u'firefox-53.0.en-US.mac.common.tests.zip', 03:57:23 INFO - u'firefox-53.0.en-US.mac.xpcshell.tests.zip']} 03:57:23 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip'] for test suite category: common 03:57:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZmR-3WC5ScyNH_t4rNLUSg/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 03:57:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZmR-3WC5ScyNH_t4rNLUSg/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 03:57:23 INFO - Fetch https://queue.taskcluster.net/v1/task/ZmR-3WC5ScyNH_t4rNLUSg/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 03:57:24 INFO - Expected file size: 16108179 03:57:24 INFO - Obtained file size: 16108179 03:57:31 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip', u'firefox-53.0.en-US.mac.talos.tests.zip'] for test suite category: talos 03:57:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZmR-3WC5ScyNH_t4rNLUSg/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 03:57:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZmR-3WC5ScyNH_t4rNLUSg/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 03:57:31 INFO - Fetch https://queue.taskcluster.net/v1/task/ZmR-3WC5ScyNH_t4rNLUSg/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 03:57:31 INFO - Expected file size: 16108179 03:57:31 INFO - Obtained file size: 16108179 03:57:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZmR-3WC5ScyNH_t4rNLUSg/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip 03:57:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZmR-3WC5ScyNH_t4rNLUSg/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip'}, attempt #1 03:57:38 INFO - Fetch https://queue.taskcluster.net/v1/task/ZmR-3WC5ScyNH_t4rNLUSg/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip into memory 03:57:39 INFO - Expected file size: 14403663 03:57:39 INFO - Obtained file size: 14403663 03:57:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:39 INFO - trying https://queue.taskcluster.net/v1/task/ZmR-3WC5ScyNH_t4rNLUSg/artifacts/public/build/firefox-53.0.en-US.mac.dmg 03:57:39 INFO - Downloading https://queue.taskcluster.net/v1/task/ZmR-3WC5ScyNH_t4rNLUSg/artifacts/public/build/firefox-53.0.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0.en-US.mac.dmg 03:57:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZmR-3WC5ScyNH_t4rNLUSg/artifacts/public/build/firefox-53.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg'}, attempt #1 03:57:41 INFO - Downloaded 58737622 bytes. 03:57:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZmR-3WC5ScyNH_t4rNLUSg/artifacts/public/build/firefox-53.0.en-US.mac.dmg 03:57:41 INFO - mkdir: /builds/slave/test/properties 03:57:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:57:41 INFO - Writing to file /builds/slave/test/properties/build_url 03:57:41 INFO - Contents: 03:57:41 INFO - build_url:https://queue.taskcluster.net/v1/task/ZmR-3WC5ScyNH_t4rNLUSg/artifacts/public/build/firefox-53.0.en-US.mac.dmg 03:57:42 INFO - Running post-action listener: _resource_record_post_action 03:57:42 INFO - Running post-action listener: set_extra_try_arguments 03:57:42 INFO - [mozharness: 2017-03-22 10:57:42.431226Z] Finished download-and-extract step (success) 03:57:42 INFO - [mozharness: 2017-03-22 10:57:42.431367Z] Running populate-webroot step. 03:57:42 INFO - Running pre-action listener: _resource_record_pre_action 03:57:42 INFO - Running main action method: populate_webroot 03:57:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:57:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:57:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:57:42 INFO - '--tppagecycles', 03:57:42 INFO - '7'], 03:57:42 INFO - 'tests': ['tsvgm']}}, 03:57:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:57:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:57:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:57:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:57:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:57:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:57:42 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:57:42 INFO - 'talos_options': ['--disable-e10s'], 03:57:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:57:42 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:57:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:57:42 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:57:42 INFO - 'talos_options': ['--disable-e10s'], 03:57:42 INFO - 'tests': ['damp', 'tps']}, 03:57:42 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:57:42 INFO - 'tests': ['damp', 'tps']}, 03:57:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:57:42 INFO - 'tests': ['dromaeo_dom']}, 03:57:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:57:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:57:42 INFO - 'tests': ['basic_compositor_video']}, 03:57:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:57:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:57:42 INFO - 'tests': ['a11yr', 03:57:42 INFO - 'ts_paint', 03:57:42 INFO - 'tpaint', 03:57:42 INFO - 'sessionrestore', 03:57:42 INFO - 'sessionrestore_no_auto_restore', 03:57:42 INFO - 'tabpaint']}, 03:57:42 INFO - 'other-e10s': {'tests': ['a11yr', 03:57:42 INFO - 'ts_paint', 03:57:42 INFO - 'tpaint', 03:57:42 INFO - 'sessionrestore', 03:57:42 INFO - 'sessionrestore_no_auto_restore', 03:57:42 INFO - 'tabpaint']}, 03:57:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:57:42 INFO - 'ts_paint', 03:57:42 INFO - 'tpaint', 03:57:42 INFO - 'sessionrestore', 03:57:42 INFO - 'sessionrestore_no_auto_restore', 03:57:42 INFO - 'tabpaint']}, 03:57:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:57:42 INFO - 'ts_paint', 03:57:42 INFO - 'tpaint', 03:57:42 INFO - 'sessionrestore', 03:57:42 INFO - 'sessionrestore_no_auto_restore', 03:57:42 INFO - 'tabpaint']}, 03:57:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:57:42 INFO - 'tests': ['a11yr', 03:57:42 INFO - 'ts_paint', 03:57:42 INFO - 'tpaint', 03:57:42 INFO - 'sessionrestore', 03:57:42 INFO - 'sessionrestore_no_auto_restore', 03:57:42 INFO - 'tabpaint']}, 03:57:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:57:42 INFO - 'tests': ['a11yr', 03:57:42 INFO - 'ts_paint', 03:57:42 INFO - 'tpaint', 03:57:42 INFO - 'sessionrestore', 03:57:42 INFO - 'sessionrestore_no_auto_restore', 03:57:42 INFO - 'tabpaint']}, 03:57:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:57:42 INFO - 'tests': ['tsvgx', 03:57:42 INFO - 'tsvgr_opacity', 03:57:42 INFO - 'tart', 03:57:42 INFO - 'tscrollx', 03:57:42 INFO - 'cart', 03:57:42 INFO - 'tsvg_static']}, 03:57:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:57:42 INFO - 'tsvgr_opacity', 03:57:42 INFO - 'tart', 03:57:42 INFO - 'tscrollx', 03:57:42 INFO - 'cart', 03:57:42 INFO - 'tsvg_static']}, 03:57:42 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:57:42 INFO - 'talos_options': ['--disable-e10s'], 03:57:42 INFO - 'tests': ['tp5o']}, 03:57:42 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:57:42 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:57:42 INFO - 'talos_options': ['--disable-e10s', 03:57:42 INFO - '--xperf_path', 03:57:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:57:42 INFO - 'tests': ['tp5n']}, 03:57:42 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:57:42 INFO - 'talos_options': ['--xperf_path', 03:57:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:57:42 INFO - 'tests': ['tp5n']}}, 03:57:42 INFO - 'talos.zip': {'path': '', 03:57:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:57:42 INFO - Running post-action listener: _resource_record_post_action 03:57:42 INFO - [mozharness: 2017-03-22 10:57:42.449597Z] Finished populate-webroot step (success) 03:57:42 INFO - [mozharness: 2017-03-22 10:57:42.449701Z] Running create-virtualenv step. 03:57:42 INFO - Running pre-action listener: _resource_record_pre_action 03:57:42 INFO - Running main action method: create_virtualenv 03:57:42 INFO - Creating virtualenv /builds/slave/test/build/venv 03:57:42 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 03:57:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:57:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:57:42 INFO - Using real prefix '/tools/python27' 03:57:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:57:43 INFO - Installing distribute.............................................................................................................................................................................................done. 03:57:46 INFO - Installing pip.................done. 03:57:46 INFO - Return code: 0 03:57:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:57:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:57:46 INFO - Reading from file tmpfile_stdout 03:57:46 INFO - Output received: 03:57:46 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) 03:57:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:57:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:46 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 0x10149cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10149f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff219565aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10127ad30>, '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 0x1011a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10126fbe8>, '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.Tfen0ohELu/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.eU62nKFO00/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 03:57:46 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 03:57:46 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 03:57:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tfen0ohELu/Render', 03:57:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:46 INFO - 'HOME': '/Users/cltbld', 03:57:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:46 INFO - 'LOGNAME': 'cltbld', 03:57:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:46 INFO - 'MOZ_NO_REMOTE': '1', 03:57:46 INFO - 'NO_EM_RESTART': '1', 03:57:46 INFO - 'PAGER': '/bin/cat', 03:57:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:46 INFO - 'PWD': '/builds/slave/test', 03:57:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:46 INFO - 'SHELL': '/bin/bash', 03:57:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eU62nKFO00/Listeners', 03:57:46 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 03:57:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:46 INFO - 'USER': 'cltbld', 03:57:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:46 INFO - 'XPC_FLAGS': '0x0', 03:57:46 INFO - 'XPC_SERVICE_NAME': '0', 03:57:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:46 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 03:57:46 INFO - Cleaning up... 03:57:46 INFO - Return code: 0 03:57:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:57:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:46 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 0x10149cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10149f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff219565aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10127ad30>, '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 0x1011a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10126fbe8>, '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.Tfen0ohELu/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.eU62nKFO00/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 03:57:46 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 03:57:46 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 03:57:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tfen0ohELu/Render', 03:57:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:46 INFO - 'HOME': '/Users/cltbld', 03:57:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:46 INFO - 'LOGNAME': 'cltbld', 03:57:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:46 INFO - 'MOZ_NO_REMOTE': '1', 03:57:46 INFO - 'NO_EM_RESTART': '1', 03:57:46 INFO - 'PAGER': '/bin/cat', 03:57:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:46 INFO - 'PWD': '/builds/slave/test', 03:57:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:46 INFO - 'SHELL': '/bin/bash', 03:57:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eU62nKFO00/Listeners', 03:57:46 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 03:57:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:46 INFO - 'USER': 'cltbld', 03:57:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:46 INFO - 'XPC_FLAGS': '0x0', 03:57:46 INFO - 'XPC_SERVICE_NAME': '0', 03:57:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:46 INFO - Downloading/unpacking psutil>=3.1.1 03:57:46 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 03:57:46 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 03:57:46 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 03:57:46 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 03:57:46 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 03:57:46 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 03:57:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:57:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:57:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:57:50 INFO - Installing collected packages: psutil 03:57:50 INFO - Running setup.py install for psutil 03:57:50 INFO - building 'psutil._psutil_osx' extension 03:57:50 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 03:57:51 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 03:57:51 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 03:57:51 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 03:57:51 INFO - building 'psutil._psutil_posix' extension 03:57:51 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 03:57:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:57:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:57:51 INFO - ^ 03:57:51 INFO - 1 warning generated. 03:57:51 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 03:57:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:57:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:57:51 INFO - Successfully installed psutil 03:57:51 INFO - Cleaning up... 03:57:51 INFO - Return code: 0 03:57:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:57:51 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')]} 03:57:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:51 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')]} 03:57:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:51 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 0x10149cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10149f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff219565aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10127ad30>, '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 0x1011a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10126fbe8>, '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.Tfen0ohELu/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.eU62nKFO00/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 03:57:51 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 03:57:51 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 03:57:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tfen0ohELu/Render', 03:57:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:51 INFO - 'HOME': '/Users/cltbld', 03:57:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:51 INFO - 'LOGNAME': 'cltbld', 03:57:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:51 INFO - 'MOZ_NO_REMOTE': '1', 03:57:51 INFO - 'NO_EM_RESTART': '1', 03:57:51 INFO - 'PAGER': '/bin/cat', 03:57:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:51 INFO - 'PWD': '/builds/slave/test', 03:57:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:51 INFO - 'SHELL': '/bin/bash', 03:57:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eU62nKFO00/Listeners', 03:57:51 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 03:57:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:51 INFO - 'USER': 'cltbld', 03:57:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:51 INFO - 'XPC_FLAGS': '0x0', 03:57:51 INFO - 'XPC_SERVICE_NAME': '0', 03:57:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:57:51 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 03:57:51 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 03:57:51 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 03:57:51 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 03:57:51 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 03:57:51 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 03:57:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:57:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:57:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:57:54 INFO - Installing collected packages: mozsystemmonitor 03:57:54 INFO - Running setup.py install for mozsystemmonitor 03:57:54 INFO - Successfully installed mozsystemmonitor 03:57:54 INFO - Cleaning up... 03:57:54 INFO - Return code: 0 03:57:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:57:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:54 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 0x10149cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10149f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff219565aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10127ad30>, '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 0x1011a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10126fbe8>, '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.Tfen0ohELu/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.eU62nKFO00/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 03:57:54 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 03:57:54 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 03:57:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tfen0ohELu/Render', 03:57:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:54 INFO - 'HOME': '/Users/cltbld', 03:57:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:54 INFO - 'LOGNAME': 'cltbld', 03:57:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:54 INFO - 'MOZ_NO_REMOTE': '1', 03:57:54 INFO - 'NO_EM_RESTART': '1', 03:57:54 INFO - 'PAGER': '/bin/cat', 03:57:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:54 INFO - 'PWD': '/builds/slave/test', 03:57:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:54 INFO - 'SHELL': '/bin/bash', 03:57:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eU62nKFO00/Listeners', 03:57:54 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 03:57:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:54 INFO - 'USER': 'cltbld', 03:57:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:54 INFO - 'XPC_FLAGS': '0x0', 03:57:54 INFO - 'XPC_SERVICE_NAME': '0', 03:57:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:55 INFO - Downloading/unpacking jsonschema==2.5.1 03:57:55 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 03:57:55 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 03:57:55 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 03:57:55 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 03:57:55 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 03:57:55 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 03:57:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:57:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:57:57 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 03:57:57 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 03:57:57 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 03:57:57 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 03:57:57 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 03:57:57 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 03:57:58 INFO - Downloading functools32-3.2.3-2.tar.gz 03:57:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:57:58 INFO - warning: no files found matching '*.txt' 03:57:58 INFO - no previously-included directories found matching 'build' 03:57:58 INFO - no previously-included directories found matching 'dist' 03:57:58 INFO - no previously-included directories found matching '.git*' 03:57:58 INFO - Installing collected packages: jsonschema, functools32 03:57:58 INFO - Running setup.py install for functools32 03:57:58 INFO - warning: no files found matching '*.txt' 03:57:58 INFO - no previously-included directories found matching 'build' 03:57:58 INFO - no previously-included directories found matching 'dist' 03:57:58 INFO - no previously-included directories found matching '.git*' 03:57:58 INFO - Successfully installed jsonschema functools32 03:57:58 INFO - Cleaning up... 03:57:58 INFO - Return code: 0 03:57:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:57:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10149cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10149f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff219565aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10127ad30>, '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 0x1011a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10126fbe8>, '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.Tfen0ohELu/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.eU62nKFO00/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 03:57:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:57:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:57:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tfen0ohELu/Render', 03:57:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:58 INFO - 'HOME': '/Users/cltbld', 03:57:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:58 INFO - 'LOGNAME': 'cltbld', 03:57:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:58 INFO - 'MOZ_NO_REMOTE': '1', 03:57:58 INFO - 'NO_EM_RESTART': '1', 03:57:58 INFO - 'PAGER': '/bin/cat', 03:57:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:58 INFO - 'PWD': '/builds/slave/test', 03:57:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:58 INFO - 'SHELL': '/bin/bash', 03:57:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eU62nKFO00/Listeners', 03:57:58 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 03:57:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:58 INFO - 'USER': 'cltbld', 03:57:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:58 INFO - 'XPC_FLAGS': '0x0', 03:57:58 INFO - 'XPC_SERVICE_NAME': '0', 03:57:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:57:58 INFO - Cleaning up... 03:57:58 INFO - Return code: 0 03:57:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:57:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10149cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10149f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff219565aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10127ad30>, '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 0x1011a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10126fbe8>, '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.Tfen0ohELu/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.eU62nKFO00/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 03:57:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:57:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:57:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tfen0ohELu/Render', 03:57:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:58 INFO - 'HOME': '/Users/cltbld', 03:57:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:58 INFO - 'LOGNAME': 'cltbld', 03:57:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:58 INFO - 'MOZ_NO_REMOTE': '1', 03:57:58 INFO - 'NO_EM_RESTART': '1', 03:57:58 INFO - 'PAGER': '/bin/cat', 03:57:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:58 INFO - 'PWD': '/builds/slave/test', 03:57:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:58 INFO - 'SHELL': '/bin/bash', 03:57:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eU62nKFO00/Listeners', 03:57:58 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 03:57:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:58 INFO - 'USER': 'cltbld', 03:57:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:58 INFO - 'XPC_FLAGS': '0x0', 03:57:58 INFO - 'XPC_SERVICE_NAME': '0', 03:57:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:58 INFO - Downloading/unpacking blobuploader==1.2.4 03:57:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:57:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:57:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:57:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:57:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:57:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:01 INFO - Downloading blobuploader-1.2.4.tar.gz 03:58:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:58:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:58:01 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 03:58:01 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 03:58:01 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 03:58:01 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 03:58:01 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 03:58:01 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 03:58:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:58:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:58:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:58:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:58:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:02 INFO - Downloading docopt-0.6.1.tar.gz 03:58:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:58:02 INFO - Installing collected packages: blobuploader, requests, docopt 03:58:02 INFO - Running setup.py install for blobuploader 03:58:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:58:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:58:03 INFO - Running setup.py install for requests 03:58:03 INFO - Running setup.py install for docopt 03:58:03 INFO - Successfully installed blobuploader requests docopt 03:58:03 INFO - Cleaning up... 03:58:03 INFO - Return code: 0 03:58:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:58:03 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')]} 03:58:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:58:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:58:03 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')]} 03:58:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:58:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:58:03 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 0x10149cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10149f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff219565aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10127ad30>, '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 0x1011a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10126fbe8>, '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.Tfen0ohELu/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.eU62nKFO00/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 03:58:03 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 03:58:03 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 03:58:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tfen0ohELu/Render', 03:58:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:58:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:58:03 INFO - 'HOME': '/Users/cltbld', 03:58:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:58:03 INFO - 'LOGNAME': 'cltbld', 03:58:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:58:03 INFO - 'MOZ_NO_REMOTE': '1', 03:58:03 INFO - 'NO_EM_RESTART': '1', 03:58:03 INFO - 'PAGER': '/bin/cat', 03:58:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:58:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:58:03 INFO - 'PWD': '/builds/slave/test', 03:58:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:58:03 INFO - 'SHELL': '/bin/bash', 03:58:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eU62nKFO00/Listeners', 03:58:03 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 03:58:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:58:03 INFO - 'USER': 'cltbld', 03:58:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:58:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:58:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:58:03 INFO - 'XPC_FLAGS': '0x0', 03:58:03 INFO - 'XPC_SERVICE_NAME': '0', 03:58:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:58:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:58:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:58:04 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-KZnsjj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:58:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:58:04 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-gVmS3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:58:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:58:04 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-xflyOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:58:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:58:04 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-VnS5un-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:58:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:58:04 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-IXOLfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:58:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:58:04 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-IYN30z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:58:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:58:04 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-zD7Vue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:58:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:58:04 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-v0sAIS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:58:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:58:04 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-wQIMvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:58:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:58:05 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-dKeVEa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:58:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:58:05 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-7xMTnq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:58:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:58:05 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-CfJT1L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:58:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:58:05 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-Oa4ARH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:58:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:58:05 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-yKVgw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:58:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:58:05 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-TQiPun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:58:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:58:05 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-npo_EJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:58:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:58:05 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-FwR6f7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:58:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:58:05 INFO - Running setup.py install for manifestparser 03:58:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:58:05 INFO - Running setup.py install for mozcrash 03:58:06 INFO - Running setup.py install for mozdebug 03:58:06 INFO - Running setup.py install for mozdevice 03:58:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:58:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:58:06 INFO - Running setup.py install for mozfile 03:58:06 INFO - Running setup.py install for mozhttpd 03:58:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:58:06 INFO - Running setup.py install for mozinfo 03:58:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:58:06 INFO - Running setup.py install for mozInstall 03:58:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:58:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:58:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:58:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:58:07 INFO - Running setup.py install for mozleak 03:58:07 INFO - Running setup.py install for mozlog 03:58:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:58:07 INFO - Running setup.py install for moznetwork 03:58:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:58:07 INFO - Running setup.py install for mozprocess 03:58:07 INFO - Running setup.py install for mozprofile 03:58:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:58:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:58:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:58:07 INFO - Running setup.py install for mozrunner 03:58:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:58:07 INFO - Running setup.py install for mozscreenshot 03:58:08 INFO - Running setup.py install for moztest 03:58:08 INFO - Running setup.py install for mozversion 03:58:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:58:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:58:08 INFO - Cleaning up... 03:58:08 INFO - Return code: 0 03:58:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:58:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:58:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:58:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:58:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:58:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:58:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:58:08 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 0x10149cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10149f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff219565aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10127ad30>, '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 0x1011a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10126fbe8>, '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.Tfen0ohELu/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.eU62nKFO00/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 03:58:08 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 03:58:08 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 03:58:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tfen0ohELu/Render', 03:58:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:58:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:58:08 INFO - 'HOME': '/Users/cltbld', 03:58:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:58:08 INFO - 'LOGNAME': 'cltbld', 03:58:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:58:08 INFO - 'MOZ_NO_REMOTE': '1', 03:58:08 INFO - 'NO_EM_RESTART': '1', 03:58:08 INFO - 'PAGER': '/bin/cat', 03:58:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:58:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:58:08 INFO - 'PWD': '/builds/slave/test', 03:58:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:58:08 INFO - 'SHELL': '/bin/bash', 03:58:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eU62nKFO00/Listeners', 03:58:08 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 03:58:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:58:08 INFO - 'USER': 'cltbld', 03:58:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:58:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:58:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:58:08 INFO - 'XPC_FLAGS': '0x0', 03:58:08 INFO - 'XPC_SERVICE_NAME': '0', 03:58:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:58:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:58:08 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-_OLXxc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:58:08 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)) 03:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:58:08 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-HNhBtK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:58:08 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)) 03:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:58:08 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-1T1hFo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:58:09 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)) 03:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:58:09 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-Sop_uh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:58:09 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)) 03:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:58:09 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-ZrZK8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:58:09 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)) 03:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:58:09 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-jnmVxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:58:09 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)) 03:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:58:09 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-0hggjH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:58:09 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)) 03:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:58:09 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-vNKho5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:58:09 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)) 03:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:58:09 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-NN3EM6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:58:09 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)) 03:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:58:09 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-qeRG8l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:58:09 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)) 03:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:58:09 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-ATlBf8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:58:09 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)) 03:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:58:09 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-BncBcN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:58:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 03:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:58:09 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-mR1Q6x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:58:10 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)) 03:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:58:10 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-_4Gzo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:58:10 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)) 03:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:58:10 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-fdpN_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:58:10 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)) 03:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:58:10 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-5O5PgY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:58:10 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)) 03:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:58:10 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-jf9Vno-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:58:10 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)) 03:58:10 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)) 03:58:10 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)) 03:58:10 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)) 03:58:10 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)) 03:58:10 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)) 03:58:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:58:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:58:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:58:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:13 INFO - Downloading blessings-1.6.tar.gz 03:58:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:58:13 INFO - Installing collected packages: blessings 03:58:13 INFO - Running setup.py install for blessings 03:58:13 INFO - Successfully installed blessings 03:58:13 INFO - Cleaning up... 03:58:13 INFO - Return code: 0 03:58:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:58:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:58:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:58:13 INFO - Reading from file tmpfile_stdout 03:58:13 INFO - Current package versions: 03:58:13 INFO - blessings == 1.6 03:58:13 INFO - blobuploader == 1.2.4 03:58:13 INFO - docopt == 0.6.1 03:58:13 INFO - functools32 == 3.2.3-2 03:58:13 INFO - jsonschema == 2.5.1 03:58:13 INFO - manifestparser == 1.1 03:58:13 INFO - mozInstall == 1.12 03:58:13 INFO - mozcrash == 1.0 03:58:13 INFO - mozdebug == 0.1 03:58:13 INFO - mozdevice == 0.49 03:58:13 INFO - mozfile == 1.2 03:58:13 INFO - mozhttpd == 0.7 03:58:13 INFO - mozinfo == 0.9 03:58:13 INFO - mozleak == 0.1 03:58:13 INFO - mozlog == 3.4 03:58:13 INFO - moznetwork == 0.27 03:58:13 INFO - mozprocess == 0.24 03:58:13 INFO - mozprofile == 0.28 03:58:13 INFO - mozrunner == 6.13 03:58:13 INFO - mozscreenshot == 0.1 03:58:13 INFO - mozsystemmonitor == 0.3 03:58:13 INFO - moztest == 0.8 03:58:13 INFO - mozversion == 1.4 03:58:13 INFO - psutil == 3.1.1 03:58:13 INFO - requests == 1.2.3 03:58:13 INFO - wsgiref == 0.1.2 03:58:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:58:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:58:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:58:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:58:14 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')]} 03:58:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:58:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:58:14 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 0x10149cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10149f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff219565aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10127ad30>, '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 0x1011a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10126fbe8>, '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.Tfen0ohELu/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.eU62nKFO00/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 03:58:14 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 03:58:14 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 03:58:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tfen0ohELu/Render', 03:58:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:58:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:58:14 INFO - 'HOME': '/Users/cltbld', 03:58:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:58:14 INFO - 'LOGNAME': 'cltbld', 03:58:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:58:14 INFO - 'MOZ_NO_REMOTE': '1', 03:58:14 INFO - 'NO_EM_RESTART': '1', 03:58:14 INFO - 'PAGER': '/bin/cat', 03:58:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:58:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:58:14 INFO - 'PWD': '/builds/slave/test', 03:58:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:58:14 INFO - 'SHELL': '/bin/bash', 03:58:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eU62nKFO00/Listeners', 03:58:14 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 03:58:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:58:14 INFO - 'USER': 'cltbld', 03:58:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:58:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:58:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:58:14 INFO - 'XPC_FLAGS': '0x0', 03:58:14 INFO - 'XPC_SERVICE_NAME': '0', 03:58:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:58:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:58:15 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)) 03:58:15 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)) 03:58:15 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)) 03:58:15 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)) 03:58:15 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)) 03:58:15 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)) 03:58:15 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)) 03:58:15 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)) 03:58:15 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)) 03:58:15 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)) 03:58:15 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)) 03:58:15 INFO - Cleaning up... 03:58:15 INFO - Return code: 0 03:58:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:58:15 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')]} 03:58:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:58:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:58:15 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')]} 03:58:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:58:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:58:15 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 0x10149cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10149f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff219565aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10127ad30>, '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 0x1011a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10126fbe8>, '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.Tfen0ohELu/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.eU62nKFO00/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 03:58:15 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 03:58:15 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 03:58:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tfen0ohELu/Render', 03:58:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:58:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:58:15 INFO - 'HOME': '/Users/cltbld', 03:58:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:58:15 INFO - 'LOGNAME': 'cltbld', 03:58:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:58:15 INFO - 'MOZ_NO_REMOTE': '1', 03:58:15 INFO - 'NO_EM_RESTART': '1', 03:58:15 INFO - 'PAGER': '/bin/cat', 03:58:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:58:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:58:15 INFO - 'PWD': '/builds/slave/test', 03:58:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:58:15 INFO - 'SHELL': '/bin/bash', 03:58:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eU62nKFO00/Listeners', 03:58:15 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 03:58:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:58:15 INFO - 'USER': 'cltbld', 03:58:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:58:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:58:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:58:15 INFO - 'XPC_FLAGS': '0x0', 03:58:15 INFO - 'XPC_SERVICE_NAME': '0', 03:58:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:58:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:58:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:58:15 INFO - Cleaning up... 03:58:15 INFO - Return code: 0 03:58:15 INFO - Running post-action listener: _resource_record_post_action 03:58:15 INFO - Running post-action listener: _start_resource_monitoring 03:58:15 INFO - Starting resource monitoring. 03:58:15 INFO - [mozharness: 2017-03-22 10:58:15.801505Z] Finished create-virtualenv step (success) 03:58:15 INFO - [mozharness: 2017-03-22 10:58:15.802505Z] Running install step. 03:58:15 INFO - Running pre-action listener: _resource_record_pre_action 03:58:15 INFO - Running main action method: install 03:58:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:58:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:58:16 INFO - Reading from file tmpfile_stdout 03:58:16 INFO - Detecting whether we're running mozinstall >=1.0... 03:58:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:58:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:58:16 INFO - Reading from file tmpfile_stdout 03:58:16 INFO - Output received: 03:58:16 INFO - Usage: mozinstall [options] installer 03:58:16 INFO - Options: 03:58:16 INFO - -h, --help show this help message and exit 03:58:16 INFO - -d DEST, --destination=DEST 03:58:16 INFO - Directory to install application into. [default: 03:58:16 INFO - "/builds/slave/test"] 03:58:16 INFO - --app=APP Application being installed. [default: firefox] 03:58:16 INFO - mkdir: /builds/slave/test/build/application 03:58:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:58:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0.en-US.mac.dmg --destination /builds/slave/test/build/application 03:58:30 INFO - Reading from file tmpfile_stdout 03:58:30 INFO - Output received: 03:58:30 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 03:58:30 INFO - Running post-action listener: _resource_record_post_action 03:58:30 INFO - [mozharness: 2017-03-22 10:58:30.720416Z] Finished install step (success) 03:58:30 INFO - [mozharness: 2017-03-22 10:58:30.720551Z] Running run-tests step. 03:58:30 INFO - Running pre-action listener: _resource_record_pre_action 03:58:30 INFO - Running main action method: run_tests 03:58:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:58:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:58:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:58:30 INFO - 2017-03-22 03:58:30.734 screenresolution[1623:7238] starting screenresolution argv=screenresolution get 03:58:30 INFO - 2017-03-22 03:58:30.737 screenresolution[1623:7238] Display 0: 1600x1200x32@60 03:58:30 INFO - 2017-03-22 03:58:30.743 screenresolution[1624:7240] starting screenresolution argv=screenresolution list 03:58:30 INFO - Available Modes on Display 0 03:58:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:58:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:58:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:58:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:58:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:58:30 INFO - Intel Iris: 03:58:30 INFO - Chipset Model: Intel Iris 03:58:30 INFO - Type: GPU 03:58:30 INFO - Bus: Built-In 03:58:30 INFO - VRAM (Dynamic, Max): 1536 MB 03:58:30 INFO - Vendor: Intel (0x8086) 03:58:30 INFO - Device ID: 0x0a2e 03:58:30 INFO - Revision ID: 0x0009 03:58:30 INFO - Displays: 03:58:30 INFO - Display: 03:58:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:58:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:58:30 INFO - Display Serial Number: 1600x1200 60 03:58:30 INFO - Main Display: Yes 03:58:30 INFO - Mirror: Off 03:58:30 INFO - Online: Yes 03:58:30 INFO - Rotation: Supported 03:58:30 INFO - Return code: 0 03:58:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:58:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:58:30 INFO - Python 2.7.3 03:58:30 INFO - Return code: 0 03:58:30 INFO - grabbing minidump binary from tooltool 03:58:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:58:30 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 0x10127ad30>, '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 0x1011a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10126fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:58:30 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 03:58:30 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 03:58:30 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 03:58:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:58:31 INFO - Return code: 0 03:58:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:58:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:58:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:58:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:58:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:58:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZmR-3WC5ScyNH_t4rNLUSg/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0379', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:58:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZmR-3WC5ScyNH_t4rNLUSg/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0379 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:58:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tfen0ohELu/Render', 03:58:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:58:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:58:31 INFO - 'HOME': '/Users/cltbld', 03:58:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:58:31 INFO - 'LOGNAME': 'cltbld', 03:58:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:58:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:58:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:58:31 INFO - 'MOZ_NO_REMOTE': '1', 03:58:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:58:31 INFO - 'NO_EM_RESTART': '1', 03:58:31 INFO - 'PAGER': '/bin/cat', 03:58:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:58:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:58:31 INFO - 'PWD': '/builds/slave/test', 03:58:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:58:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:58:31 INFO - 'SHELL': '/bin/bash', 03:58:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eU62nKFO00/Listeners', 03:58:31 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 03:58:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:58:31 INFO - 'USER': 'cltbld', 03:58:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:58:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:58:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:58:31 INFO - 'XPC_FLAGS': '0x0', 03:58:31 INFO - 'XPC_SERVICE_NAME': '0', 03:58:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:58:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZmR-3WC5ScyNH_t4rNLUSg/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0379', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:58:31 INFO - mozversion application_buildid: 20170322030136 03:58:31 INFO - mozversion application_changeset: c5cc62e792d9f0bbd84e380da79102cc3efcfe08 03:58:31 INFO - mozversion application_display_name: Firefox 03:58:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:58:31 INFO - mozversion application_name: Firefox 03:58:31 INFO - mozversion application_remotingname: firefox 03:58:31 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 03:58:31 INFO - mozversion application_vendor: Mozilla 03:58:31 INFO - mozversion application_version: 53.0 03:58:31 INFO - mozversion platform_buildid: 20170322030136 03:58:31 INFO - mozversion platform_changeset: c5cc62e792d9f0bbd84e380da79102cc3efcfe08 03:58:31 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 03:58:31 INFO - mozversion platform_version: 53.0 03:58:31 INFO - using testdate: 1490180311 03:58:31 INFO - actual date: 1490180311 03:58:31 INFO - starting webserver on 'localhost:49242' 03:58:31 INFO - SUITE-START | Running 2 tests 03:58:31 INFO - TEST-START | dromaeo_css 03:58:31 INFO - operating with platform_type : mac_ 03:58:31 INFO - Initialising browser for dromaeo_css test... 03:58:31 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpXHu65L/profile http://localhost:49242/getInfo.html) 03:58:34 INFO - PROCESS | 1634 | 2017-03-22 03:58:34.264 plugin-container[1637:7403] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e4b, name = 'com.apple.tsm.portname' 03:58:34 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:58:34 INFO - PROCESS | 1634 | 2017-03-22 03:58:34.265 plugin-container[1637:7403] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x491f, name = 'com.apple.CFPasteboardClient' 03:58:34 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:58:34 INFO - PROCESS | 1634 | 2017-03-22 03:58:34.265 plugin-container[1637:7403] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:58:34 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 03:58:34 INFO - PROCESS | 1634 | colorDepth:24 03:58:34 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 03:58:34 INFO - PROCESS | 1634 | __metrics 03:58:34 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 03:58:35 INFO - TEST-INFO | 1634: exit 0 03:58:35 INFO - Browser initialized. 03:58:35 INFO - Running cycle 1/1 for dromaeo_css test... 03:58:35 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpXHu65L/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:58:37 INFO - PROCESS | 1638 | 2017-03-22 03:58:37.540 plugin-container[1639:7526] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 03:58:37 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:58:37 INFO - PROCESS | 1638 | 2017-03-22 03:58:37.541 plugin-container[1639:7526] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 03:58:37 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:58:37 INFO - PROCESS | 1638 | 2017-03-22 03:58:37.541 plugin-container[1639:7526] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:58:47 INFO - PROCESS | 1638 | RSS: Main: 173133824 03:58:47 INFO - PROCESS | 1638 | 04:01:48 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 04:01:50 INFO - PROCESS | 1638 | RSS: Main: 191594496 04:01:50 INFO - PROCESS | 1638 | 04:04:52 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 04:04:53 INFO - PROCESS | 1638 | RSS: Main: 201248768 04:04:53 INFO - PROCESS | 1638 | 04:07:55 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 04:07:55 INFO - PROCESS | 1638 | RSS: Main: 203210752 04:07:55 INFO - PROCESS | 1638 | 04:10:57 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 04:10:58 INFO - PROCESS | 1638 | RSS: Main: 206438400 04:10:58 INFO - PROCESS | 1638 | 04:14:01 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 04:14:01 INFO - PROCESS | 1638 | RSS: Main: 207568896 04:14:01 INFO - PROCESS | 1638 | 04:17:04 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 04:17:04 INFO - PROCESS | 1638 | RSS: Main: 212250624 04:17:04 INFO - PROCESS | 1638 | 04:17:04 INFO - PROCESS | 1638 | __start_tp_report 04:17:04 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 04:17:04 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 04:17:04 INFO - PROCESS | 1638 | |i|pagename|runs| 04:17:04 INFO - PROCESS | 1638 | |0;dojo.html;3519.87680431185;3523.559555055618;3541.3271478419097;3548.92902141957;3560.359135355635;5904.350521442643;5971.4625683688455;5976.491998180149;5976.581639285252;5983.970080149598;4877.585405240558;5918.230630018103;5918.378570250126;5949.643021418718;5963.165156878041;5286.524212820846;5349.197620356938;5385.649932754359;5399.811006614769;5401.594880383967;3862.995621138496;5158.329417175762;5405.108157154064;5428.701421421821;5445.646033007862;5271.077561426754;5288.101022826128;5385.542228910547;5400.864978375553;5417.45825417457;5309.522143007129;5323.920141197885;5371.623986320959;5400.567954563626;5453.427390124019;5245.291885595472;5355.6251062425645;5364.168553874156;5370.865728356784;5419.105847535152;5934.910976335358;5939;6080.0575910733905;6091.786787462417;6093.11649810775;6262.561620686553;6271.8745625087695;6295.8740825183695;6297.023961286008;6314.87370252597;6335.04974253861;6344.6510441925675;6348.968255158741;6358.904616430757;6366.363363663643;6030.7889223876955;6038;6038.758449662008;6043.546733994968;6068.605540639845;6226.657533835682;6308.179936608211;6325.734853029412;6346.238451385863;6409.647469389135;6234.875302493925;6300.905486417708;6318.873622527523;6321.893668607975;6338.461230795434;6323.146375239376;6334.303226645157;6357.96821015892;6359.3640635936035;6366.745330186741;6235.971064774352;6259.655718935503;6272.749090036439;6294.748210071546;6348.682565871688;6243.406876346739;6276.968615156895;6341.143945567382;6342.682865856688;6347.047942808524;5079.415867175248;5082;5085.389753229636;5089.262057001788;5109.616778741609;4428.645708343389;4435.733855968652;4560.338750881104;4565.246734288871;4581.312803079498;5064.392272927273;6078.574499784972;6085.726142323606;6158.076288556752;6166;5311.389190243094;6330.588511746721;6333.461655759312;6342.556021078481;6351.55539112267;5933.643981361046;5958.404159584094;6073.8177854663545;6081.57428979976;6171.537134714825;4829.937413768966;5514.145307477347;5596.860078497989;5612.831615051555;5619.494245517923;6503.349665033459;6525.314841941623;6533;6535.150430444107;6555.70499327541;6218.440340369389;6263.906041409474;6312.926802443504;6347.0479428086155;6351.301356850835;6451.741930322923;6560.44236239925;6564.015397690194;6571.080048793268;6586.80239592813;6298.905516417349;6313.810585682437;6317.368263173646;6331.841703957253;6345.17512723343;6241.344658810759;6267.373262673696;6287.559870808999;6314.273858506331;6331.113644089924;6259.749610015549;6270.717817698306;6272.498200143887;6343;6365;4830.3237546744185;6307.96846015767;6308.621482710869;6343.0485427186295;6356.459700925473;5011;6072.787452439144;6179.62922224668;6189.35011823752;6247.406496382836;5919.556033297606;6074.544409169418;6187.84530386744;6210.161628180138;6321.557490975588;6137.355577664281;6144.986077297196;6180.536459765445;6186.721597528032;6195.535334849814 04:17:04 INFO - PROCESS | 1638 | |1;ext.html;448.64108713029566;461.303431817955;472.6738550400224;481.17958880109416;535.7080391186803;41380.96547586308;43714.34428483567;43737.562624373786;43754.78122609382;43874.99999999996;45229.773851130696;45644.315335269996;45680.54319456805;45747.77126114373;45898.54101458984;9610.08704173106;9828.164606008499;9836.213102951746;9838.606455741761;11577.015953643908;11517.251378660401;11776.941115294454;11782.410879456036;11783.234089784137;11813.7637247255;11728.12039097067;11819.822702659467;11851.170418070738;11856.687945850563;11861.703457413547;11649.883501164964;11723.296602203853;11758.412079395996;11779.764404711901;11793.528258869635;11681.591144309994;11704.47329870158;11723.531058757639;11818.409079545989;11838.052955763518;38877.80561097205;39048;39075.41386879199;39080.02299942496;39154.80422597897;4073.3075377185655;4099.754014759094;4139.668826493903;4145.067359844041;4176.519700234481;29430.70569294301;29763.40473190546;30028;30554.236144096354;31385.84307078473;3126.1715645353997;3162.9525557116663;3280.1963518937773;3307.4046671599103;3317.3637830457988;440190;508691;510543;513744;515185;61537.69231153816;61673.07490387648;61830;61874.0718889217;62017.379826202065;64253;64266;64514.677426612565;64556.70886582336;64949.67525162344;16499.6700065998;17220.82779172217;17298.962062276303;17307.3942412015;17336.3065477382;12171.33057681836;12457.937710311391;13041.543545975845;13218.669533261746;13322.200667959953;19105.522361940784;19145.04274786255;19172.71240931387;19189.32837350686;19221.711674324895;8233.8353232935;8324.084350721416;8337.833243335099;8350.08149103598;8364.916350836414;777.3858651665234;878.4904755241944;890.3055616619048;890.5102193793388;919.8804155459748;29433.85283073571;30539.2365190872;31819.522707159413;32002.839985800387;32287;957.1146689312358;1109.6670998700358;1112.3270421395102;1118.8601424821898;1255.4036832504487;16241.83758162427;16326;16639.6672066558;16660.833391666172;16679.0826504541;243598;247254;248306;248615;249001;64554;65267.67366163139;65331.67334163299;65533.67233163804;65571;1451.2018389885732;1482.5922871210503;1487.3530014443536;1498.5354540092605;1508.2835653064706;29756.55365169481;30961.535576966813;31133.91031313893;31401;31533.36933261276;55553.16670249869;55733.44266557445;55852;55910.72044639914;55917.44082559285;564311;567717;568760;570029;571256;27795.861020694767;28329.433411331247;28898;28928.42143157167;28962;49761.25358119559;50294;50682;50717.746411267704;51828;40036.798896032;40193.79903100583;41115;42325.730228093205;42403.363949540166;36457.270854583294;37170.62829371672;41491.74203598865;42180.36729449122;42318.57681423269;36850.44724329191;37521.62478375291;37896.242075158894;37954.81022594869;38007.429888552244;32236.516452253705;33721.3255734879;34089;34262.48606270858;34272.82863585666;25279.620805688297;25365.731713413887;25369.492610148063;25618.359541011625;25804.225873223833 04:17:04 INFO - PROCESS | 1638 | |2;jquery.html;344.24781851654154;349.54908168462686;371.5745471435207;397.1025482409753;432.1637631183658;20121.29575464859;20967.580648387022;21893.343199703995;22082;22543.661845072318;12677.492900284;12781.680457988556;13754.381052852621;21178.25876094337;22497.55004899901;4597.60920321773;4712.293156026589;5274.393444753854;5478.506934375905;5493.835184944458;5302.443243459439;5437.891242175154;5461.344638643368;5468.207109969052;5484.478974497416;5199.220116982445;5449.01917654822;5456;5458.836234912959;5466.34403871534;5388.892222155535;5395.352557693082;5423.7830486780485;5428.96849598575;5448.101063324586;5145.176771716508;5349.117395629716;5351.197320401932;5361.329833770778;5371.435999220103;13911.513097041556;14137.363818628186;17302.480925572254;26972.92108315665;27166.049188278317;2576.4589436218416;2637.7626013658864;2734.357426004887;2740.1231605886123;2748.876300566479;9478.857817132748;9848.113669769755;10434.37393756377;10571.312864663772;10597.417142057184;1848.2884089625456;1895.4503194073702;1903.7049257364993;1923.519120219945;1928.7781905080956;114394.42802786017;139140;139994;142949.2852535741;146053;10197.235207359477;11773.17587768865;11838.94080529609;12447.315397653036;12471.989562688646;16155.757663635055;16397.42609008679;16439.506814795575;16442.506724798277;16452.506424807274;7754.418418618626;8902.821943561223;9191.816163676689;9250.537473126451;9293.163615274523;8974.86537701935;8986.46081235115;8988.011318754932;9124.680636177702;9154.588043538057;4923.90152196954;4964.106460837084;4970.826021089245;5009.974950125226;5012.348394708664;2065.4216819290623;2100.705901173837;2117.502386939068;2120.81973032294;2120.879120879121;1004.7739258666887;1020.9224228694571;1023.8652409725805;1025.4308858583559;1052;22527.436814079778;22530.54938901213;22927.770722292564;22980.310590682308;23047.769522304898;1040.334186120868;1040.4121671255716;1068.2522234435926;1070.593174593665;1074.1514203779084;11239.482669839466;11777.705557361134;11817.822732659017;11822.34977076252;11827.940860295816;774534;782019;782335;790850;798560;135155.6484435143;155661.72432729224;158040;163171;165924.17037914734;957.9377340472847;962.4514027004681;979.7305740921161;986.1272773595276;994.6966175316453;19192.808071919102;19427.028648567797;19728.704069439256;19756;19805.306814262007;48922.755386224264;60971.475713107524;61922;63244.68377658082;63729.72540549256;67151;85155;86223.56888215519;87142;99516;5613.943860561505;6227.034809604518;7262.96368518154;7305.634718263959;9105.908940910771;12732.809007865075;13454.46182152703;13536.729265414833;13729.107608005448;13842.65393365174;11557.422128893353;11649;11767.352795596324;11809.881901180877;11817.704557386134;11632.011279041377;11792.174547781573;11796.056315494912;11838.28970261787;11843.940780296389;11580.710482238012;11602.099475131217;11730.413479325829;12060.095492838247;12179.086568507575;7289.562626242442;8565.314774817874;8888.6000129993;9012.684556040507;9071.274298056273;10021.649242276486;11903.583374581847;11979.401029948642;12024.519019239133;12297.139200255897 04:17:04 INFO - PROCESS | 1638 | |3;mootools.html;1450.2893582144752;1467.948621798237;1468.1704836767226;1470.9779353309702;1480.5928369698336;7735.071791385028;7993.560354180518;8333.208345207204;8718.258947989429;8742.082081381459;8107;8413.78965525862;8425.325973922087;8513.574321283942;8546.188112129354;2368.4315764216594;2386.0336563691644;2401.771831675988;2419.11702228687;2431.088341871798;2405.482821193443;2418.5525677749583;2432.914847980321;2440.2069327468553;2446.498467814094;2385.2844146755992;2388.629762386824;2409.722881868581;2417.2748175547354;2427.3082171581104;2373.738888722239;2376.192094687806;2392.4138586046356;2400.3159099656514;2400.7839294463497;2345.519168570439;2386.72552656445;2407.0612461139995;2417.709874815016;2418.093215044358;8584.785380365478;8614;8633.395662303641;8634.956825215897;8647.783805404852;5228.738563071831;5383.138697808371;5389.110796718537;5405.405405405402;5416.0792665247;3037.58992536007;3250.447423937936;3440.9655903440894;3503.1242189452637;3598.316319899237;1225.4730465899659;1238.5355491690616;1260.9180403273758;1292.4184117147227;1319.597522755559;10899.400532970683;10924.726881827939;10967.396793176373;10967.50646220922;10995.120390368751;4290.678199135077;4306.310990241546;4808.230683090758;4847.418309802776;4867.221244600918;7944.642491087915;9704.175145112604;9721.368111072758;9725.221982241404;9764.902350976541;5344.545713614386;5354.330708661418;5373.301470808769;5385.434529374437;5388.461153884658;3893.766374017568;4424.292113261927;4453.844115455943;4880.609551235963;4916.434610019894;3952.2490726761825;4014.8996275093355;4052.65552428041;4091.5499295077516;4092.8362865485046;1139.882914743556;1313.934303284832;1315.3817705678316;1327.707904261061;1344.8520662727092;938.8216238914584;951.110711484764;958.8993155718689;963.2967933408511;1002.2232769603615;5022.472640372708;5768.74040668171;5798;5831.008728516163;5956.374580669054;589.9351071382144;591.7189335065809;594.1414655822296;594.6699581732094;599.6012651586645;3819.618038196158;3825.885223443301;3881.6118388160958;3916.412538119305;3919.470871432377;9850.950745246228;10081.596736130472;10236.129928955974;10257.846132308021;10277.94861025705;10146.13757830578;10150.289479736348;10164.33931794431;10185.643502477378;10329.74175645615;7798.415118865994;8002.679892804223;8047.195280471906;8058.919410805817;8066.233707797867;5658.17956396312;5686.147077938343;5691.77232910679;5794.101914203306;5831.504322132666;9233.169014788702;9327.58025888823;9371.297152713438;9467.242620590474;9467.952660236655;9048.773780655536;9434.386764860263;9454.669086581936;9516.524173791422;9531.237501000041;6748.932510675027;6937.89593156093;7098.432125430057;7283.599402032939;7404.296591823768;7891.408144389079;8104.797380065545;8113.797155071171;8149.348052155695;8153.592320383838;7170.39051680613;7318.231585683427;7379.446541509516;7409.592472414069;7435.219301973431;7122.786316410516;7151.642417879189;7193.820154496179;7316.048913641405;7316.853662926818;6606.372394622503;6652.7006284716335;6699.631520266432;6701.430378417889;6709.396154346132;5053.823116190915;5092.312537807348;5123.7190702324415;5250.291210686509;5280.155175171954;5168.483151684802;5201.323827902348;5244.475552444877;5260.921086183634;5267.894642107213 04:17:04 INFO - PROCESS | 1638 | |4;prototype.html;321.3813409187312;356.3016487684139;369.8816378758797;373.5386797305328;378.96589306962375;5184.403793563742;5506.476884695958;5551.139573366129;5583.916241256379;5628.720368250854;4440.800163992628;4855.349181278365;5512.669239845612;5536.280283563141;5556.527695145907;3829.463875057494;4017.5178978522617;4242.875637955936;4367.192069467143;4370.978145109286;4204.747715137086;4375.665422046274;4375.7155784873885;4411.845585404511;4418.823247070113;3577.996322059655;3786.734928555002;3826.559945606249;4232.640225580814;4303.698741088125;3648.543932008499;3713.904398202537;3989.40158976153;4325.783710814447;4332.28517294646;3875.341191997339;3891.3384724596885;3906.199229158016;3913.8043097844993;3922.3135951208424;5014.799408023675;5317.548008419289;5374.811881584164;5392.0294347017525;5418.078926582491;4332.523422423531;4616.238320677118;4772.565991344404;4808.951910480885;4826.34844296019;5389.622726409153;5849.444302791228;5874.5006674432725;5900.793472228452;5901.498372638331;3322.601287845474;3535.717142628609;3928.2339943710986;3943.9885658314465;3981.686043605603;6082.939170608326;6145;6165.5992360496875;6190.566660333779;6201.05449911293;4040.575739547364;4060.309747342959;4065.2682517146604;4072.1041370898356;4095.610916962884;3432.6567343265974;3499.9850043487104;3719.497867787868;4084.4281800547947;4091.263572556909;4239.851605193865;4764.523547645208;4766.713997160182;4776.677107217974;4780.904381912343;5174.611904107207;5313.123334649817;5511.311086114236;5651.660900345993;5678.914816277759;1108.8412608823637;1134.4384529657873;1238.8911923828302;1263.8372697118673;1322.3322222277689;758.8238230742327;771.7568965775763;806.9072056713436;823.4853216739538;874.4272501511521;4896.314515967767;4956.753326571102;5362.65142765719;5397.0285348636835;5414.40441551429;4610.216263235191;5231.790728370898;5294.973525132426;5546.225207933423;5821.359650438449;4322.913541729211;4896.212882297936;5219.947800521946;5309.522143007148;5372.301600791872;2442.4626582152255;2776.4447110577967;3076.769242306791;3080.969190308113;3084.892028778982;5417.539509141767;5582.441755824385;5635.970281248927;6035;6174.5060395168275;5196.558292545101;5210.635255532076;5877.764889404376;5890.322612899486;5966.032588328712;5428.592855535771;5744.759543778014;6008.699565021731;6028.849278767978;6103.664298463538;5409.756560954688;5514.61397702157;5767.875264323417;5868.295804503487;5878.412158784087;4961.8511444656715;5468.91796623059;5692.80451105256;5695;5706.514946229617;5209.76556054986;5604.8879022418505;6007.669578173068;6105.358937311696;6152.415520525543;3370.528126062304;3779;3841.116543195025;3848.9037774055873;4049.372347286175;5228.346456692913;6020;6020.531582051513;6029.804203772008;6070.878582428415;5292.20616907467;5381.528913850622;5550.888982220414;5648.237487939075;5735.082386818089;4982.754311422144;5146.173108546413;5893.5874488785375;5903.675297858659;5999.220101386792;4326.183003138753;5418.295621569171;5448.809291674773;5709.942900571107;5720.685362305113;5683.834813863231;5840.678762668094;5856.94143058564;5887.144734803531;5902.956474814766;5270.262163297218;5353.678779273256;5608.6073974823;5754.97863621602;5828.737706803289 04:17:04 INFO - PROCESS | 1638 | |5;yui.html;750.2272659161063;754.532190042174;798.7124635131352;840.7156505124198;865.1478293880526;15175.54473365798;16344.346226150954;17563.297468101293;18123.093845307747;18167.091645417742;14472.710545789105;17471.650566988654;17523.299068037264;17529.298828046896;17550.736738948926;699.4439420660561;874.2132081126989;903.223228023899;905.6105874473974;906.6871929184421;740.7999840043185;783.2324322164283;884.4339622641514;895.4985208283382;902.2781774580342;911.3802614222326;935.508857849631;938.9154976052154;947.0766003146946;952.5713428956963;913.1188403190953;927.4945154890567;940.0458534587399;941.9529023548829;943.4527973775193;699.402011280353;706.5407485134704;706.759701701424;707.7133760826873;708.7979925721145;15747.291371888292;16252.593685157966;16265.349386024545;16585.668286634198;16647.41734039315;14392.333093328658;14447.277636118151;14600.561983140522;14689.265536723122;14707.218790726456;10269.640562580274;10418.739531511697;11676.708082297926;11739.884710952445;12109.878901210963;3719.088823238324;3742.1393079591576;3864.3044252034624;3959.9010024749323;3990.541087774885;107188;111737.44131279373;112946.99999999919;113547;115911;11169.329840209613;11227.157963152633;11403.372814495116;11556.88443115575;11579.942100289612;23509.294721158392;23790.64314035291;23836.284911452683;23875.40311492192;24185.51628967411;6837.760678376233;8011.479253848481;8067.596620168968;8084.595770211466;8203.261706446328;4534.841280555164;4542.772861356985;4617.284320930261;4620.145273124483;4644.140833945731;3776.546814382292;3847.846086156578;3876.5541962674088;3883.262180185756;3883.339832228528;700.1493185779302;713.6075158662715;714.9428045756327;726.5495392856357;728.4354625165539;654.1855390039336;655.7672026430548;674.8616533610598;690.1235431002599;713.5896859305919;9553.665621703207;9721.9027809721;10758;10998.230123891251;11075.833862492069;574.8189320364072;576.8471355090904;636;638.7604648256904;647.4755448087064;5077.213031980049;5137.5376216139975;5141.263128635756;5190.558802501754;5223.764930578057;112002.43998779955;112474;115035.42482287536;116269.67460650908;117385;81236.18763812286;92286;100791.49604252078;101110;101817.49091254496;88093.11906880977;88726;88931.55534222287;90088.74778130469;90712;8865.68970086044;9328.906710932804;9774.462404567817;10081.848772268275;10199.0820826126;56088.43911560995;56291;56854;57451.1382329257;57865.71067144637;70304.64847675934;71045.93431098641;72362.63818680873;73413;76813;11739.589114380955;11745.47145378443;11956.103292252941;11975.461104250155;12105.33420661872;12903.806442903551;12977.545785897826;13137.080404371602;13478.137577298647;13596.456141754616;12273.324967126893;12661.366931653196;12703.80944285855;12825.871741282468;12940.611781646188;12154.392280385768;12660.98712103048;12673.683157921127;12755.93622031884;12981.675458113623;11837.467313971065;12348.135630505778;12621.495140194289;12666.683332916751;12763.936180319039;8746.868796968178;10135.847962280424;10596.364218146937;10601.522931467947;10859.968303011201;8062.8199531728105;9183.943846457743;9751.902480975099;10297.623778113482;10447.529861156418 04:17:04 INFO - PROCESS | 1638 | __end_tp_report 04:17:04 INFO - PROCESS | 1638 | __start_cc_report 04:17:04 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,382 04:17:04 INFO - PROCESS | 1638 | __end_cc_report 04:17:04 INFO - PROCESS | 1638 | __startTimestamp1490181424718__endTimestamp 04:17:04 INFO - PROCESS | 1638 | 04:17:04 INFO - PROCESS | 1638 | ------- Summary: start ------- 04:17:04 INFO - PROCESS | 1638 | Number of tests: 6 04:17:04 INFO - PROCESS | 1638 | 04:17:04 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5869.71 Median:6171.54 stddev:672.21 (10.9%) stddev-sans-first:648.51 04:17:04 INFO - PROCESS | 1638 | Values: 3519.9 3523.6 3541.3 3548.9 3560.4 5904.4 5971.5 5976.5 5976.6 5984.0 4877.6 5918.2 5918.4 5949.6 5963.2 5286.5 5349.2 5385.6 5399.8 5401.6 3863.0 5158.3 5405.1 5428.7 5445.6 5271.1 5288.1 5385.5 5400.9 5417.5 5309.5 5323.9 5371.6 5400.6 5453.4 5245.3 5355.6 5364.2 5370.9 5419.1 5934.9 5939.0 6080.1 6091.8 6093.1 6262.6 6271.9 6295.9 6297.0 6314.9 6335.0 6344.7 6349.0 6358.9 6366.4 6030.8 6038.0 6038.8 6043.5 6068.6 6226.7 6308.2 6325.7 6346.2 6409.6 6234.9 6300.9 6318.9 6321.9 6338.5 6323.1 6334.3 6358.0 6359.4 6366.7 6236.0 6259.7 6272.7 6294.7 6348.7 6243.4 6277.0 6341.1 6342.7 6347.0 5079.4 5082.0 5085.4 5089.3 5109.6 4428.6 4435.7 4560.3 4565.2 4581.3 5064.4 6078.6 6085.7 6158.1 6166.0 5311.4 6330.6 6333.5 6342.6 6351.6 5933.6 5958.4 6073.8 6081.6 6171.5 4829.9 5514.1 5596.9 5612.8 5619.5 6503.3 6525.3 6533.0 6535.2 6555.7 6218.4 6263.9 6312.9 6347.0 6351.3 6451.7 6560.4 6564.0 6571.1 6586.8 6298.9 6313.8 6317.4 6331.8 6345.2 6241.3 6267.4 6287.6 6314.3 6331.1 6259.7 6270.7 6272.5 6343.0 6365.0 4830.3 6308.0 6308.6 6343.0 6356.5 5011.0 6072.8 6179.6 6189.4 6247.4 5919.6 6074.5 6187.8 6210.2 6321.6 6137.4 6145.0 6180.5 6186.7 6195.5 04:17:04 INFO - PROCESS | 1638 | 04:17:04 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:60579.74 Median:36653.86 stddev:122372.54 (333.9%) stddev-sans-first:122632.55 04:17:04 INFO - PROCESS | 1638 | Values: 448.6 461.3 472.7 481.2 535.7 41381.0 43714.3 43737.6 43754.8 43875.0 45229.8 45644.3 45680.5 45747.8 45898.5 9610.1 9828.2 9836.2 9838.6 11577.0 11517.3 11776.9 11782.4 11783.2 11813.8 11728.1 11819.8 11851.2 11856.7 11861.7 11649.9 11723.3 11758.4 11779.8 11793.5 11681.6 11704.5 11723.5 11818.4 11838.1 38877.8 39048.0 39075.4 39080.0 39154.8 4073.3 4099.8 4139.7 4145.1 4176.5 29430.7 29763.4 30028.0 30554.2 31385.8 3126.2 3163.0 3280.2 3307.4 3317.4 440190.0 508691.0 510543.0 513744.0 515185.0 61537.7 61673.1 61830.0 61874.1 62017.4 64253.0 64266.0 64514.7 64556.7 64949.7 16499.7 17220.8 17299.0 17307.4 17336.3 12171.3 12457.9 13041.5 13218.7 13322.2 19105.5 19145.0 19172.7 19189.3 19221.7 8233.8 8324.1 8337.8 8350.1 8364.9 777.4 878.5 890.3 890.5 919.9 29433.9 30539.2 31819.5 32002.8 32287.0 957.1 1109.7 1112.3 1118.9 1255.4 16241.8 16326.0 16639.7 16660.8 16679.1 243598.0 247254.0 248306.0 248615.0 249001.0 64554.0 65267.7 65331.7 65533.7 65571.0 1451.2 1482.6 1487.4 1498.5 1508.3 29756.6 30961.5 31133.9 31401.0 31533.4 55553.2 55733.4 55852.0 55910.7 55917.4 564311.0 567717.0 568760.0 570029.0 571256.0 27795.9 28329.4 28898.0 28928.4 28962.0 49761.3 50294.0 50682.0 50717.7 51828.0 40036.8 40193.8 41115.0 42325.7 42403.4 36457.3 37170.6 41491.7 42180.4 42318.6 36850.4 37521.6 37896.2 37954.8 38007.4 32236.5 33721.3 34089.0 34262.5 34272.8 25279.6 25365.7 25369.5 25618.4 25804.2 04:17:04 INFO - PROCESS | 1638 | 04:17:04 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:41909.94 Median:22528.99 stddev:130755.43 (580.4%) stddev-sans-first:131084.98 04:17:04 INFO - PROCESS | 1638 | Values: 344.2 349.5 371.6 397.1 432.2 20121.3 20967.6 21893.3 22082.0 22543.7 12677.5 12781.7 13754.4 21178.3 22497.6 4597.6 4712.3 5274.4 5478.5 5493.8 5302.4 5437.9 5461.3 5468.2 5484.5 5199.2 5449.0 5456.0 5458.8 5466.3 5388.9 5395.4 5423.8 5429.0 5448.1 5145.2 5349.1 5351.2 5361.3 5371.4 13911.5 14137.4 17302.5 26972.9 27166.0 2576.5 2637.8 2734.4 2740.1 2748.9 9478.9 9848.1 10434.4 10571.3 10597.4 1848.3 1895.5 1903.7 1923.5 1928.8 114394.4 139140.0 139994.0 142949.3 146053.0 10197.2 11773.2 11838.9 12447.3 12472.0 16155.8 16397.4 16439.5 16442.5 16452.5 7754.4 8902.8 9191.8 9250.5 9293.2 8974.9 8986.5 8988.0 9124.7 9154.6 4923.9 4964.1 4970.8 5010.0 5012.3 2065.4 2100.7 2117.5 2120.8 2120.9 1004.8 1020.9 1023.9 1025.4 1052.0 22527.4 22530.5 22927.8 22980.3 23047.8 1040.3 1040.4 1068.3 1070.6 1074.2 11239.5 11777.7 11817.8 11822.3 11827.9 774534.0 782019.0 782335.0 790850.0 798560.0 135155.6 155661.7 158040.0 163171.0 165924.2 957.9 962.5 979.7 986.1 994.7 19192.8 19427.0 19728.7 19756.0 19805.3 48922.8 60971.5 61922.0 63244.7 63729.7 67151.0 85155.0 86223.6 87142.0 99516.0 5613.9 6227.0 7263.0 7305.6 9105.9 12732.8 13454.5 13536.7 13729.1 13842.7 11557.4 11649.0 11767.4 11809.9 11817.7 11632.0 11792.2 11796.1 11838.3 11843.9 11580.7 11602.1 11730.4 12060.1 12179.1 7289.6 8565.3 8888.6 9012.7 9071.3 10021.6 11903.6 11979.4 12024.5 12297.1 04:17:04 INFO - PROCESS | 1638 | 04:17:04 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:5547.60 Median:5274.02 stddev:3011.74 (57.1%) stddev-sans-first:3004.45 04:17:04 INFO - PROCESS | 1638 | Values: 1450.3 1467.9 1468.2 1471.0 1480.6 7735.1 7993.6 8333.2 8718.3 8742.1 8107.0 8413.8 8425.3 8513.6 8546.2 2368.4 2386.0 2401.8 2419.1 2431.1 2405.5 2418.6 2432.9 2440.2 2446.5 2385.3 2388.6 2409.7 2417.3 2427.3 2373.7 2376.2 2392.4 2400.3 2400.8 2345.5 2386.7 2407.1 2417.7 2418.1 8584.8 8614.0 8633.4 8635.0 8647.8 5228.7 5383.1 5389.1 5405.4 5416.1 3037.6 3250.4 3441.0 3503.1 3598.3 1225.5 1238.5 1260.9 1292.4 1319.6 10899.4 10924.7 10967.4 10967.5 10995.1 4290.7 4306.3 4808.2 4847.4 4867.2 7944.6 9704.2 9721.4 9725.2 9764.9 5344.5 5354.3 5373.3 5385.4 5388.5 3893.8 4424.3 4453.8 4880.6 4916.4 3952.2 4014.9 4052.7 4091.5 4092.8 1139.9 1313.9 1315.4 1327.7 1344.9 938.8 951.1 958.9 963.3 1002.2 5022.5 5768.7 5798.0 5831.0 5956.4 589.9 591.7 594.1 594.7 599.6 3819.6 3825.9 3881.6 3916.4 3919.5 9851.0 10081.6 10236.1 10257.8 10277.9 10146.1 10150.3 10164.3 10185.6 10329.7 7798.4 8002.7 8047.2 8058.9 8066.2 5658.2 5686.1 5691.8 5794.1 5831.5 9233.2 9327.6 9371.3 9467.2 9468.0 9048.8 9434.4 9454.7 9516.5 9531.2 6748.9 6937.9 7098.4 7283.6 7404.3 7891.4 8104.8 8113.8 8149.3 8153.6 7170.4 7318.2 7379.4 7409.6 7435.2 7122.8 7151.6 7193.8 7316.0 7316.9 6606.4 6652.7 6699.6 6701.4 6709.4 5053.8 5092.3 5123.7 5250.3 5280.2 5168.5 5201.3 5244.5 5260.9 5267.9 04:17:04 INFO - PROCESS | 1638 | 04:17:04 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4644.95 Median:5281.23 stddev:1439.30 (27.3%) stddev-sans-first:1406.27 04:17:04 INFO - PROCESS | 1638 | Values: 321.4 356.3 369.9 373.5 379.0 5184.4 5506.5 5551.1 5583.9 5628.7 4440.8 4855.3 5512.7 5536.3 5556.5 3829.5 4017.5 4242.9 4367.2 4371.0 4204.7 4375.7 4375.7 4411.8 4418.8 3578.0 3786.7 3826.6 4232.6 4303.7 3648.5 3713.9 3989.4 4325.8 4332.3 3875.3 3891.3 3906.2 3913.8 3922.3 5014.8 5317.5 5374.8 5392.0 5418.1 4332.5 4616.2 4772.6 4809.0 4826.3 5389.6 5849.4 5874.5 5900.8 5901.5 3322.6 3535.7 3928.2 3944.0 3981.7 6082.9 6145.0 6165.6 6190.6 6201.1 4040.6 4060.3 4065.3 4072.1 4095.6 3432.7 3500.0 3719.5 4084.4 4091.3 4239.9 4764.5 4766.7 4776.7 4780.9 5174.6 5313.1 5511.3 5651.7 5678.9 1108.8 1134.4 1238.9 1263.8 1322.3 758.8 771.8 806.9 823.5 874.4 4896.3 4956.8 5362.7 5397.0 5414.4 4610.2 5231.8 5295.0 5546.2 5821.4 4322.9 4896.2 5219.9 5309.5 5372.3 2442.5 2776.4 3076.8 3081.0 3084.9 5417.5 5582.4 5636.0 6035.0 6174.5 5196.6 5210.6 5877.8 5890.3 5966.0 5428.6 5744.8 6008.7 6028.8 6103.7 5409.8 5514.6 5767.9 5868.3 5878.4 4961.9 5468.9 5692.8 5695.0 5706.5 5209.8 5604.9 6007.7 6105.4 6152.4 3370.5 3779.0 3841.1 3848.9 4049.4 5228.3 6020.0 6020.5 6029.8 6070.9 5292.2 5381.5 5550.9 5648.2 5735.1 4982.8 5146.2 5893.6 5903.7 5999.2 4326.2 5418.3 5448.8 5709.9 5720.7 5683.8 5840.7 5856.9 5887.1 5903.0 5270.3 5353.7 5608.6 5755.0 5828.7 04:17:04 INFO - PROCESS | 1638 | 04:17:04 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:21882.33 Median:3883.30 stddev:32335.11 (832.7%) stddev-sans-first:32386.89 04:17:04 INFO - PROCESS | 1638 | Values: 750.2 754.5 798.7 840.7 865.1 15175.5 16344.3 17563.3 18123.1 18167.1 14472.7 17471.7 17523.3 17529.3 17550.7 699.4 874.2 903.2 905.6 906.7 740.8 783.2 884.4 895.5 902.3 911.4 935.5 938.9 947.1 952.6 913.1 927.5 940.0 942.0 943.5 699.4 706.5 706.8 707.7 708.8 15747.3 16252.6 16265.3 16585.7 16647.4 14392.3 14447.3 14600.6 14689.3 14707.2 10269.6 10418.7 11676.7 11739.9 12109.9 3719.1 3742.1 3864.3 3959.9 3990.5 107188.0 111737.4 112947.0 113547.0 115911.0 11169.3 11227.2 11403.4 11556.9 11579.9 23509.3 23790.6 23836.3 23875.4 24185.5 6837.8 8011.5 8067.6 8084.6 8203.3 4534.8 4542.8 4617.3 4620.1 4644.1 3776.5 3847.8 3876.6 3883.3 3883.3 700.1 713.6 714.9 726.5 728.4 654.2 655.8 674.9 690.1 713.6 9553.7 9721.9 10758.0 10998.2 11075.8 574.8 576.8 636.0 638.8 647.5 5077.2 5137.5 5141.3 5190.6 5223.8 112002.4 112474.0 115035.4 116269.7 117385.0 81236.2 92286.0 100791.5 101110.0 101817.5 88093.1 88726.0 88931.6 90088.7 90712.0 8865.7 9328.9 9774.5 10081.8 10199.1 56088.4 56291.0 56854.0 57451.1 57865.7 70304.6 71045.9 72362.6 73413.0 76813.0 11739.6 11745.5 11956.1 11975.5 12105.3 12903.8 12977.5 13137.1 13478.1 13596.5 12273.3 12661.4 12703.8 12825.9 12940.6 12154.4 12661.0 12673.7 12755.9 12981.7 11837.5 12348.1 12621.5 12666.7 12763.9 8746.9 10135.8 10596.4 10601.5 10860.0 8062.8 9183.9 9751.9 10297.6 10447.5 04:17:04 INFO - PROCESS | 1638 | -------- Summary: end -------- 04:17:04 INFO - PROCESS | 1638 | 04:17:05 INFO - TEST-INFO | 1638: exit 0 04:17:05 INFO - TEST-OK | dromaeo_css | took 1114046ms 04:17:05 INFO - TEST-START | kraken 04:17:05 INFO - operating with platform_type : mac_ 04:17:05 INFO - Initialising browser for kraken test... 04:17:05 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpdI2P9X/profile http://localhost:49242/getInfo.html) 04:17:08 INFO - PROCESS | 1650 | 2017-03-22 04:17:08.035 plugin-container[1652:9767] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 04:17:08 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:17:08 INFO - PROCESS | 1650 | 2017-03-22 04:17:08.036 plugin-container[1652:9767] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0b, name = 'com.apple.CFPasteboardClient' 04:17:08 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:17:08 INFO - PROCESS | 1650 | 2017-03-22 04:17:08.036 plugin-container[1652:9767] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:17:08 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 04:17:08 INFO - PROCESS | 1650 | colorDepth:24 04:17:08 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 04:17:08 INFO - PROCESS | 1650 | __metrics 04:17:08 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 04:17:08 INFO - TEST-INFO | 1650: exit 0 04:17:08 INFO - Browser initialized. 04:17:08 INFO - Running cycle 1/1 for kraken test... 04:17:08 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpdI2P9X/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:17:11 INFO - PROCESS | 1653 | 2017-03-22 04:17:11.134 plugin-container[1654:9895] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4247, name = 'com.apple.tsm.portname' 04:17:11 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:17:11 INFO - PROCESS | 1653 | 2017-03-22 04:17:11.134 plugin-container[1654:9895] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b03, name = 'com.apple.CFPasteboardClient' 04:17:11 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:17:11 INFO - PROCESS | 1653 | 2017-03-22 04:17:11.134 plugin-container[1654:9895] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:17:20 INFO - PROCESS | 1653 | RSS: Main: 173133824 04:17:20 INFO - PROCESS | 1653 | 04:17:24 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 04:17:24 INFO - PROCESS | 1653 | RSS: Main: 185999360 04:17:24 INFO - PROCESS | 1653 | 04:17:27 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 04:17:27 INFO - PROCESS | 1653 | RSS: Main: 193978368 04:17:27 INFO - PROCESS | 1653 | 04:17:31 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 04:17:31 INFO - PROCESS | 1653 | RSS: Main: 197529600 04:17:31 INFO - PROCESS | 1653 | 04:17:33 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 04:17:34 INFO - PROCESS | 1653 | RSS: Main: 198012928 04:17:34 INFO - PROCESS | 1653 | 04:17:36 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:17:36 INFO - PROCESS | 1653 | RSS: Main: 199241728 04:17:36 INFO - PROCESS | 1653 | 04:17:39 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 04:17:40 INFO - PROCESS | 1653 | RSS: Main: 203517952 04:17:40 INFO - PROCESS | 1653 | 04:17:43 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 04:17:44 INFO - PROCESS | 1653 | RSS: Main: 204046336 04:17:44 INFO - PROCESS | 1653 | 04:17:47 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 04:17:47 INFO - PROCESS | 1653 | RSS: Main: 203362304 04:17:47 INFO - PROCESS | 1653 | 04:17:49 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:17:49 INFO - PROCESS | 1653 | RSS: Main: 202113024 04:17:49 INFO - PROCESS | 1653 | 04:17:51 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:17:52 INFO - PROCESS | 1653 | RSS: Main: 202067968 04:17:52 INFO - PROCESS | 1653 | 04:17:54 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:17:55 INFO - PROCESS | 1653 | RSS: Main: 202006528 04:17:55 INFO - PROCESS | 1653 | 04:17:58 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:17:58 INFO - PROCESS | 1653 | RSS: Main: 202194944 04:17:58 INFO - PROCESS | 1653 | 04:18:01 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:18:02 INFO - PROCESS | 1653 | RSS: Main: 202567680 04:18:02 INFO - PROCESS | 1653 | 04:18:04 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 04:18:04 INFO - PROCESS | 1653 | RSS: Main: 204132352 04:18:04 INFO - PROCESS | 1653 | 04:18:04 INFO - PROCESS | 1653 | __start_tp_report 04:18:04 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 04:18:04 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 04:18:04 INFO - PROCESS | 1653 | |i|pagename|runs| 04:18:04 INFO - PROCESS | 1653 | |0;ai-astar;107;119;133;176;124;134;177;162;121;118 04:18:04 INFO - PROCESS | 1653 | |1;audio-beat-detection;142;112;111;149;109;122;106;110;130;112 04:18:04 INFO - PROCESS | 1653 | |2;audio-dft;196;199;194;199;194;194;196;201;200;191 04:18:04 INFO - PROCESS | 1653 | |3;audio-fft;82;60;58;84;60;61;60;59;71;57 04:18:04 INFO - PROCESS | 1653 | |4;audio-oscillator;88;70;87;103;88;70;80;69;70;81 04:18:04 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;88;86;88;85;89;85;89;112;121;133 04:18:04 INFO - PROCESS | 1653 | |6;imaging-darkroom;99;155;92;151;153;120;94;125;99;121 04:18:04 INFO - PROCESS | 1653 | |7;imaging-desaturate;92;94;92;91;93;93;93;100;130;93 04:18:04 INFO - PROCESS | 1653 | |8;json-parse-financial;60;58;74;76;60;62;61;62;63;62 04:18:04 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;69;75;73;81;76;70;68;68;73;72 04:18:04 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;81;82;83;88;85;84;85;87;93;105 04:18:04 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;149;148;138;155;123;145;148;155;183;155 04:18:04 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;150;168;148;226;230;228;163;161;222;227 04:18:04 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;65;65;67;64;80;87;61;66;85;83 04:18:04 INFO - PROCESS | 1653 | __end_tp_report 04:18:04 INFO - PROCESS | 1653 | __start_cc_report 04:18:04 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,931 04:18:04 INFO - PROCESS | 1653 | __end_cc_report 04:18:04 INFO - PROCESS | 1653 | __startTimestamp1490181484542__endTimestamp 04:18:04 INFO - PROCESS | 1653 | 04:18:04 INFO - PROCESS | 1653 | ------- Summary: start ------- 04:18:04 INFO - PROCESS | 1653 | Number of tests: 14 04:18:04 INFO - PROCESS | 1653 | 04:18:04 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:137.10 Median:133.50 stddev:25.34 (19.0%) stddev-sans-first:24.43 04:18:04 INFO - PROCESS | 1653 | Values: 107.0 119.0 133.0 176.0 124.0 134.0 177.0 162.0 121.0 118.0 04:18:04 INFO - PROCESS | 1653 | 04:18:04 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:120.30 Median:117.00 stddev:15.11 (12.9%) stddev-sans-first:13.83 04:18:04 INFO - PROCESS | 1653 | Values: 142.0 112.0 111.0 149.0 109.0 122.0 106.0 110.0 130.0 112.0 04:18:04 INFO - PROCESS | 1653 | 04:18:04 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:196.40 Median:197.50 stddev:3.24 (1.6%) stddev-sans-first:3.43 04:18:04 INFO - PROCESS | 1653 | Values: 196.0 199.0 194.0 199.0 194.0 194.0 196.0 201.0 200.0 191.0 04:18:04 INFO - PROCESS | 1653 | 04:18:04 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:65.20 Median:60.50 stddev:10.14 (16.8%) stddev-sans-first:8.75 04:18:04 INFO - PROCESS | 1653 | Values: 82.0 60.0 58.0 84.0 60.0 61.0 60.0 59.0 71.0 57.0 04:18:04 INFO - PROCESS | 1653 | 04:18:04 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:80.60 Median:80.50 stddev:11.18 (13.9%) stddev-sans-first:11.53 04:18:04 INFO - PROCESS | 1653 | Values: 88.0 70.0 87.0 103.0 88.0 70.0 80.0 69.0 70.0 81.0 04:18:04 INFO - PROCESS | 1653 | 04:18:04 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:97.60 Median:87.00 stddev:17.61 (20.2%) stddev-sans-first:18.34 04:18:04 INFO - PROCESS | 1653 | Values: 88.0 86.0 88.0 85.0 89.0 85.0 89.0 112.0 121.0 133.0 04:18:04 INFO - PROCESS | 1653 | 04:18:04 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:120.90 Median:123.50 stddev:25.02 (20.3%) stddev-sans-first:25.25 04:18:04 INFO - PROCESS | 1653 | Values: 99.0 155.0 92.0 151.0 153.0 120.0 94.0 125.0 99.0 121.0 04:18:04 INFO - PROCESS | 1653 | 04:18:04 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:97.10 Median:93.00 stddev:11.82 (12.7%) stddev-sans-first:12.39 04:18:04 INFO - PROCESS | 1653 | Values: 92.0 94.0 92.0 91.0 93.0 93.0 93.0 100.0 130.0 93.0 04:18:04 INFO - PROCESS | 1653 | 04:18:04 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:63.80 Median:62.00 stddev:6.09 (9.8%) stddev-sans-first:6.30 04:18:04 INFO - PROCESS | 1653 | Values: 60.0 58.0 74.0 76.0 60.0 62.0 61.0 62.0 63.0 62.0 04:18:04 INFO - PROCESS | 1653 | 04:18:04 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:72.50 Median:73.00 stddev:4.09 (5.6%) stddev-sans-first:4.14 04:18:04 INFO - PROCESS | 1653 | Values: 69.0 75.0 73.0 81.0 76.0 70.0 68.0 68.0 73.0 72.0 04:18:04 INFO - PROCESS | 1653 | 04:18:04 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:87.30 Median:85.00 stddev:7.10 (8.4%) stddev-sans-first:7.16 04:18:04 INFO - PROCESS | 1653 | Values: 81.0 82.0 83.0 88.0 85.0 84.0 85.0 87.0 93.0 105.0 04:18:04 INFO - PROCESS | 1653 | 04:18:04 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:149.90 Median:152.00 stddev:15.17 (10.0%) stddev-sans-first:16.09 04:18:04 INFO - PROCESS | 1653 | Values: 149.0 148.0 138.0 155.0 123.0 145.0 148.0 155.0 183.0 155.0 04:18:04 INFO - PROCESS | 1653 | 04:18:04 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.30 Median:224.00 stddev:36.66 (16.4%) stddev-sans-first:35.55 04:18:04 INFO - PROCESS | 1653 | Values: 150.0 168.0 148.0 226.0 230.0 228.0 163.0 161.0 222.0 227.0 04:18:04 INFO - PROCESS | 1653 | 04:18:04 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.30 Median:73.50 stddev:10.12 (13.8%) stddev-sans-first:10.39 04:18:04 INFO - PROCESS | 1653 | Values: 65.0 65.0 67.0 64.0 80.0 87.0 61.0 66.0 85.0 83.0 04:18:04 INFO - PROCESS | 1653 | -------- Summary: end -------- 04:18:04 INFO - PROCESS | 1653 | 04:18:05 INFO - TEST-INFO | 1653: exit 0 04:18:05 INFO - TEST-OK | kraken | took 59976ms 04:18:05 INFO - SUITE-END | took 1174s 04:18:05 INFO - Completed test suite (00:19:34) 04:18:05 INFO - javascript benchmark 04:18:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7454.983155089421, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3519.87680431185, 3523.559555055618, 3541.3271478419097, 3548.92902141957, 3560.359135355635, 5904.350521442643, 5971.4625683688455, 5976.491998180149, 5976.581639285252, 5983.970080149598, 4877.585405240558, 5918.230630018103, 5918.378570250126, 5949.643021418718, 5963.165156878041, 5286.524212820846, 5349.197620356938, 5385.649932754359, 5399.811006614769, 5401.594880383967, 3862.995621138496, 5158.329417175762, 5405.108157154064, 5428.701421421821, 5445.646033007862, 5271.077561426754, 5288.101022826128, 5385.542228910547, 5400.864978375553, 5417.45825417457, 5309.522143007129, 5323.920141197885, 5371.623986320959, 5400.567954563626, 5453.427390124019, 5245.291885595472, 5355.6251062425645, 5364.168553874156, 5370.865728356784, 5419.105847535152, 5934.910976335358, 5939.0, 6080.0575910733905, 6091.786787462417, 6093.11649810775, 6262.561620686553, 6271.8745625087695, 6295.8740825183695, 6297.023961286008, 6314.87370252597, 6335.04974253861, 6344.6510441925675, 6348.968255158741, 6358.904616430757, 6366.363363663643, 6030.7889223876955, 6038.0, 6038.758449662008, 6043.546733994968, 6068.605540639845, 6226.657533835682, 6308.179936608211, 6325.734853029412, 6346.238451385863, 6409.647469389135, 6234.875302493925, 6300.905486417708, 6318.873622527523, 6321.893668607975, 6338.461230795434, 6323.146375239376, 6334.303226645157, 6357.96821015892, 6359.3640635936035, 6366.745330186741, 6235.971064774352, 6259.655718935503, 6272.749090036439, 6294.748210071546, 6348.682565871688, 6243.406876346739, 6276.968615156895, 6341.143945567382, 6342.682865856688, 6347.047942808524, 5079.415867175248, 5082.0, 5085.389753229636, 5089.262057001788, 5109.616778741609, 4428.645708343389, 4435.733855968652, 4560.338750881104, 4565.246734288871, 4581.312803079498, 5064.392272927273, 6078.574499784972, 6085.726142323606, 6158.076288556752, 6166.0, 5311.389190243094, 6330.588511746721, 6333.461655759312, 6342.556021078481, 6351.55539112267, 5933.643981361046, 5958.404159584094, 6073.8177854663545, 6081.57428979976, 6171.537134714825, 4829.937413768966, 5514.145307477347, 5596.860078497989, 5612.831615051555, 5619.494245517923, 6503.349665033459, 6525.314841941623, 6533.0, 6535.150430444107, 6555.70499327541, 6218.440340369389, 6263.906041409474, 6312.926802443504, 6347.0479428086155, 6351.301356850835, 6451.741930322923, 6560.44236239925, 6564.015397690194, 6571.080048793268, 6586.80239592813, 6298.905516417349, 6313.810585682437, 6317.368263173646, 6331.841703957253, 6345.17512723343, 6241.344658810759, 6267.373262673696, 6287.559870808999, 6314.273858506331, 6331.113644089924, 6259.749610015549, 6270.717817698306, 6272.498200143887, 6343.0, 6365.0, 4830.3237546744185, 6307.96846015767, 6308.621482710869, 6343.0485427186295, 6356.459700925473, 5011.0, 6072.787452439144, 6179.62922224668, 6189.35011823752, 6247.406496382836, 5919.556033297606, 6074.544409169418, 6187.84530386744, 6210.161628180138, 6321.557490975588, 6137.355577664281, 6144.986077297196, 6180.536459765445, 6186.721597528032, 6195.535334849814], "value": 5829.520983588635, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [448.64108713029566, 461.303431817955, 472.6738550400224, 481.17958880109416, 535.7080391186803, 41380.96547586308, 43714.34428483567, 43737.562624373786, 43754.78122609382, 43874.99999999996, 45229.773851130696, 45644.315335269996, 45680.54319456805, 45747.77126114373, 45898.54101458984, 9610.08704173106, 9828.164606008499, 9836.213102951746, 9838.606455741761, 11577.015953643908, 11517.251378660401, 11776.941115294454, 11782.410879456036, 11783.234089784137, 11813.7637247255, 11728.12039097067, 11819.822702659467, 11851.170418070738, 11856.687945850563, 11861.703457413547, 11649.883501164964, 11723.296602203853, 11758.412079395996, 11779.764404711901, 11793.528258869635, 11681.591144309994, 11704.47329870158, 11723.531058757639, 11818.409079545989, 11838.052955763518, 38877.80561097205, 39048.0, 39075.41386879199, 39080.02299942496, 39154.80422597897, 4073.3075377185655, 4099.754014759094, 4139.668826493903, 4145.067359844041, 4176.519700234481, 29430.70569294301, 29763.40473190546, 30028.0, 30554.236144096354, 31385.84307078473, 3126.1715645353997, 3162.9525557116663, 3280.1963518937773, 3307.4046671599103, 3317.3637830457988, 440190.0, 508691.0, 510543.0, 513744.0, 515185.0, 61537.69231153816, 61673.07490387648, 61830.0, 61874.0718889217, 62017.379826202065, 64253.0, 64266.0, 64514.677426612565, 64556.70886582336, 64949.67525162344, 16499.6700065998, 17220.82779172217, 17298.962062276303, 17307.3942412015, 17336.3065477382, 12171.33057681836, 12457.937710311391, 13041.543545975845, 13218.669533261746, 13322.200667959953, 19105.522361940784, 19145.04274786255, 19172.71240931387, 19189.32837350686, 19221.711674324895, 8233.8353232935, 8324.084350721416, 8337.833243335099, 8350.08149103598, 8364.916350836414, 777.3858651665234, 878.4904755241944, 890.3055616619048, 890.5102193793388, 919.8804155459748, 29433.85283073571, 30539.2365190872, 31819.522707159413, 32002.839985800387, 32287.0, 957.1146689312358, 1109.6670998700358, 1112.3270421395102, 1118.8601424821898, 1255.4036832504487, 16241.83758162427, 16326.0, 16639.6672066558, 16660.833391666172, 16679.0826504541, 243598.0, 247254.0, 248306.0, 248615.0, 249001.0, 64554.0, 65267.67366163139, 65331.67334163299, 65533.67233163804, 65571.0, 1451.2018389885732, 1482.5922871210503, 1487.3530014443536, 1498.5354540092605, 1508.2835653064706, 29756.55365169481, 30961.535576966813, 31133.91031313893, 31401.0, 31533.36933261276, 55553.16670249869, 55733.44266557445, 55852.0, 55910.72044639914, 55917.44082559285, 564311.0, 567717.0, 568760.0, 570029.0, 571256.0, 27795.861020694767, 28329.433411331247, 28898.0, 28928.42143157167, 28962.0, 49761.25358119559, 50294.0, 50682.0, 50717.746411267704, 51828.0, 40036.798896032, 40193.79903100583, 41115.0, 42325.730228093205, 42403.363949540166, 36457.270854583294, 37170.62829371672, 41491.74203598865, 42180.36729449122, 42318.57681423269, 36850.44724329191, 37521.62478375291, 37896.242075158894, 37954.81022594869, 38007.429888552244, 32236.516452253705, 33721.3255734879, 34089.0, 34262.48606270858, 34272.82863585666, 25279.620805688297, 25365.731713413887, 25369.492610148063, 25618.359541011625, 25804.225873223833], "value": 20554.224803703004, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.24781851654154, 349.54908168462686, 371.5745471435207, 397.1025482409753, 432.1637631183658, 20121.29575464859, 20967.580648387022, 21893.343199703995, 22082.0, 22543.661845072318, 12677.492900284, 12781.680457988556, 13754.381052852621, 21178.25876094337, 22497.55004899901, 4597.60920321773, 4712.293156026589, 5274.393444753854, 5478.506934375905, 5493.835184944458, 5302.443243459439, 5437.891242175154, 5461.344638643368, 5468.207109969052, 5484.478974497416, 5199.220116982445, 5449.01917654822, 5456.0, 5458.836234912959, 5466.34403871534, 5388.892222155535, 5395.352557693082, 5423.7830486780485, 5428.96849598575, 5448.101063324586, 5145.176771716508, 5349.117395629716, 5351.197320401932, 5361.329833770778, 5371.435999220103, 13911.513097041556, 14137.363818628186, 17302.480925572254, 26972.92108315665, 27166.049188278317, 2576.4589436218416, 2637.7626013658864, 2734.357426004887, 2740.1231605886123, 2748.876300566479, 9478.857817132748, 9848.113669769755, 10434.37393756377, 10571.312864663772, 10597.417142057184, 1848.2884089625456, 1895.4503194073702, 1903.7049257364993, 1923.519120219945, 1928.7781905080956, 114394.42802786017, 139140.0, 139994.0, 142949.2852535741, 146053.0, 10197.235207359477, 11773.17587768865, 11838.94080529609, 12447.315397653036, 12471.989562688646, 16155.757663635055, 16397.42609008679, 16439.506814795575, 16442.506724798277, 16452.506424807274, 7754.418418618626, 8902.821943561223, 9191.816163676689, 9250.537473126451, 9293.163615274523, 8974.86537701935, 8986.46081235115, 8988.011318754932, 9124.680636177702, 9154.588043538057, 4923.90152196954, 4964.106460837084, 4970.826021089245, 5009.974950125226, 5012.348394708664, 2065.4216819290623, 2100.705901173837, 2117.502386939068, 2120.81973032294, 2120.879120879121, 1004.7739258666887, 1020.9224228694571, 1023.8652409725805, 1025.4308858583559, 1052.0, 22527.436814079778, 22530.54938901213, 22927.770722292564, 22980.310590682308, 23047.769522304898, 1040.334186120868, 1040.4121671255716, 1068.2522234435926, 1070.593174593665, 1074.1514203779084, 11239.482669839466, 11777.705557361134, 11817.822732659017, 11822.34977076252, 11827.940860295816, 774534.0, 782019.0, 782335.0, 790850.0, 798560.0, 135155.6484435143, 155661.72432729224, 158040.0, 163171.0, 165924.17037914734, 957.9377340472847, 962.4514027004681, 979.7305740921161, 986.1272773595276, 994.6966175316453, 19192.808071919102, 19427.028648567797, 19728.704069439256, 19756.0, 19805.306814262007, 48922.755386224264, 60971.475713107524, 61922.0, 63244.68377658082, 63729.72540549256, 67151.0, 85155.0, 86223.56888215519, 87142.0, 99516.0, 5613.943860561505, 6227.034809604518, 7262.96368518154, 7305.634718263959, 9105.908940910771, 12732.809007865075, 13454.46182152703, 13536.729265414833, 13729.107608005448, 13842.65393365174, 11557.422128893353, 11649.0, 11767.352795596324, 11809.881901180877, 11817.704557386134, 11632.011279041377, 11792.174547781573, 11796.056315494912, 11838.28970261787, 11843.940780296389, 11580.710482238012, 11602.099475131217, 11730.413479325829, 12060.095492838247, 12179.086568507575, 7289.562626242442, 8565.314774817874, 8888.6000129993, 9012.684556040507, 9071.274298056273, 10021.649242276486, 11903.583374581847, 11979.401029948642, 12024.519019239133, 12297.139200255897], "value": 9909.027952557813, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1450.2893582144752, 1467.948621798237, 1468.1704836767226, 1470.9779353309702, 1480.5928369698336, 7735.071791385028, 7993.560354180518, 8333.208345207204, 8718.258947989429, 8742.082081381459, 8107.0, 8413.78965525862, 8425.325973922087, 8513.574321283942, 8546.188112129354, 2368.4315764216594, 2386.0336563691644, 2401.771831675988, 2419.11702228687, 2431.088341871798, 2405.482821193443, 2418.5525677749583, 2432.914847980321, 2440.2069327468553, 2446.498467814094, 2385.2844146755992, 2388.629762386824, 2409.722881868581, 2417.2748175547354, 2427.3082171581104, 2373.738888722239, 2376.192094687806, 2392.4138586046356, 2400.3159099656514, 2400.7839294463497, 2345.519168570439, 2386.72552656445, 2407.0612461139995, 2417.709874815016, 2418.093215044358, 8584.785380365478, 8614.0, 8633.395662303641, 8634.956825215897, 8647.783805404852, 5228.738563071831, 5383.138697808371, 5389.110796718537, 5405.405405405402, 5416.0792665247, 3037.58992536007, 3250.447423937936, 3440.9655903440894, 3503.1242189452637, 3598.316319899237, 1225.4730465899659, 1238.5355491690616, 1260.9180403273758, 1292.4184117147227, 1319.597522755559, 10899.400532970683, 10924.726881827939, 10967.396793176373, 10967.50646220922, 10995.120390368751, 4290.678199135077, 4306.310990241546, 4808.230683090758, 4847.418309802776, 4867.221244600918, 7944.642491087915, 9704.175145112604, 9721.368111072758, 9725.221982241404, 9764.902350976541, 5344.545713614386, 5354.330708661418, 5373.301470808769, 5385.434529374437, 5388.461153884658, 3893.766374017568, 4424.292113261927, 4453.844115455943, 4880.609551235963, 4916.434610019894, 3952.2490726761825, 4014.8996275093355, 4052.65552428041, 4091.5499295077516, 4092.8362865485046, 1139.882914743556, 1313.934303284832, 1315.3817705678316, 1327.707904261061, 1344.8520662727092, 938.8216238914584, 951.110711484764, 958.8993155718689, 963.2967933408511, 1002.2232769603615, 5022.472640372708, 5768.74040668171, 5798.0, 5831.008728516163, 5956.374580669054, 589.9351071382144, 591.7189335065809, 594.1414655822296, 594.6699581732094, 599.6012651586645, 3819.618038196158, 3825.885223443301, 3881.6118388160958, 3916.412538119305, 3919.470871432377, 9850.950745246228, 10081.596736130472, 10236.129928955974, 10257.846132308021, 10277.94861025705, 10146.13757830578, 10150.289479736348, 10164.33931794431, 10185.643502477378, 10329.74175645615, 7798.415118865994, 8002.679892804223, 8047.195280471906, 8058.919410805817, 8066.233707797867, 5658.17956396312, 5686.147077938343, 5691.77232910679, 5794.101914203306, 5831.504322132666, 9233.169014788702, 9327.58025888823, 9371.297152713438, 9467.242620590474, 9467.952660236655, 9048.773780655536, 9434.386764860263, 9454.669086581936, 9516.524173791422, 9531.237501000041, 6748.932510675027, 6937.89593156093, 7098.432125430057, 7283.599402032939, 7404.296591823768, 7891.408144389079, 8104.797380065545, 8113.797155071171, 8149.348052155695, 8153.592320383838, 7170.39051680613, 7318.231585683427, 7379.446541509516, 7409.592472414069, 7435.219301973431, 7122.786316410516, 7151.642417879189, 7193.820154496179, 7316.048913641405, 7316.853662926818, 6606.372394622503, 6652.7006284716335, 6699.631520266432, 6701.430378417889, 6709.396154346132, 5053.823116190915, 5092.312537807348, 5123.7190702324415, 5250.291210686509, 5280.155175171954, 5168.483151684802, 5201.323827902348, 5244.475552444877, 5260.921086183634, 5267.894642107213], "value": 4480.2210826556, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.3813409187312, 356.3016487684139, 369.8816378758797, 373.5386797305328, 378.96589306962375, 5184.403793563742, 5506.476884695958, 5551.139573366129, 5583.916241256379, 5628.720368250854, 4440.800163992628, 4855.349181278365, 5512.669239845612, 5536.280283563141, 5556.527695145907, 3829.463875057494, 4017.5178978522617, 4242.875637955936, 4367.192069467143, 4370.978145109286, 4204.747715137086, 4375.665422046274, 4375.7155784873885, 4411.845585404511, 4418.823247070113, 3577.996322059655, 3786.734928555002, 3826.559945606249, 4232.640225580814, 4303.698741088125, 3648.543932008499, 3713.904398202537, 3989.40158976153, 4325.783710814447, 4332.28517294646, 3875.341191997339, 3891.3384724596885, 3906.199229158016, 3913.8043097844993, 3922.3135951208424, 5014.799408023675, 5317.548008419289, 5374.811881584164, 5392.0294347017525, 5418.078926582491, 4332.523422423531, 4616.238320677118, 4772.565991344404, 4808.951910480885, 4826.34844296019, 5389.622726409153, 5849.444302791228, 5874.5006674432725, 5900.793472228452, 5901.498372638331, 3322.601287845474, 3535.717142628609, 3928.2339943710986, 3943.9885658314465, 3981.686043605603, 6082.939170608326, 6145.0, 6165.5992360496875, 6190.566660333779, 6201.05449911293, 4040.575739547364, 4060.309747342959, 4065.2682517146604, 4072.1041370898356, 4095.610916962884, 3432.6567343265974, 3499.9850043487104, 3719.497867787868, 4084.4281800547947, 4091.263572556909, 4239.851605193865, 4764.523547645208, 4766.713997160182, 4776.677107217974, 4780.904381912343, 5174.611904107207, 5313.123334649817, 5511.311086114236, 5651.660900345993, 5678.914816277759, 1108.8412608823637, 1134.4384529657873, 1238.8911923828302, 1263.8372697118673, 1322.3322222277689, 758.8238230742327, 771.7568965775763, 806.9072056713436, 823.4853216739538, 874.4272501511521, 4896.314515967767, 4956.753326571102, 5362.65142765719, 5397.0285348636835, 5414.40441551429, 4610.216263235191, 5231.790728370898, 5294.973525132426, 5546.225207933423, 5821.359650438449, 4322.913541729211, 4896.212882297936, 5219.947800521946, 5309.522143007148, 5372.301600791872, 2442.4626582152255, 2776.4447110577967, 3076.769242306791, 3080.969190308113, 3084.892028778982, 5417.539509141767, 5582.441755824385, 5635.970281248927, 6035.0, 6174.5060395168275, 5196.558292545101, 5210.635255532076, 5877.764889404376, 5890.322612899486, 5966.032588328712, 5428.592855535771, 5744.759543778014, 6008.699565021731, 6028.849278767978, 6103.664298463538, 5409.756560954688, 5514.61397702157, 5767.875264323417, 5868.295804503487, 5878.412158784087, 4961.8511444656715, 5468.91796623059, 5692.80451105256, 5695.0, 5706.514946229617, 5209.76556054986, 5604.8879022418505, 6007.669578173068, 6105.358937311696, 6152.415520525543, 3370.528126062304, 3779.0, 3841.116543195025, 3848.9037774055873, 4049.372347286175, 5228.346456692913, 6020.0, 6020.531582051513, 6029.804203772008, 6070.878582428415, 5292.20616907467, 5381.528913850622, 5550.888982220414, 5648.237487939075, 5735.082386818089, 4982.754311422144, 5146.173108546413, 5893.5874488785375, 5903.675297858659, 5999.220101386792, 4326.183003138753, 5418.295621569171, 5448.809291674773, 5709.942900571107, 5720.685362305113, 5683.834813863231, 5840.678762668094, 5856.94143058564, 5887.144734803531, 5902.956474814766, 5270.262163297218, 5353.678779273256, 5608.6073974823, 5754.97863621602, 5828.737706803289], "value": 4184.050624739216, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [750.2272659161063, 754.532190042174, 798.7124635131352, 840.7156505124198, 865.1478293880526, 15175.54473365798, 16344.346226150954, 17563.297468101293, 18123.093845307747, 18167.091645417742, 14472.710545789105, 17471.650566988654, 17523.299068037264, 17529.298828046896, 17550.736738948926, 699.4439420660561, 874.2132081126989, 903.223228023899, 905.6105874473974, 906.6871929184421, 740.7999840043185, 783.2324322164283, 884.4339622641514, 895.4985208283382, 902.2781774580342, 911.3802614222326, 935.508857849631, 938.9154976052154, 947.0766003146946, 952.5713428956963, 913.1188403190953, 927.4945154890567, 940.0458534587399, 941.9529023548829, 943.4527973775193, 699.402011280353, 706.5407485134704, 706.759701701424, 707.7133760826873, 708.7979925721145, 15747.291371888292, 16252.593685157966, 16265.349386024545, 16585.668286634198, 16647.41734039315, 14392.333093328658, 14447.277636118151, 14600.561983140522, 14689.265536723122, 14707.218790726456, 10269.640562580274, 10418.739531511697, 11676.708082297926, 11739.884710952445, 12109.878901210963, 3719.088823238324, 3742.1393079591576, 3864.3044252034624, 3959.9010024749323, 3990.541087774885, 107188.0, 111737.44131279373, 112946.99999999919, 113547.0, 115911.0, 11169.329840209613, 11227.157963152633, 11403.372814495116, 11556.88443115575, 11579.942100289612, 23509.294721158392, 23790.64314035291, 23836.284911452683, 23875.40311492192, 24185.51628967411, 6837.760678376233, 8011.479253848481, 8067.596620168968, 8084.595770211466, 8203.261706446328, 4534.841280555164, 4542.772861356985, 4617.284320930261, 4620.145273124483, 4644.140833945731, 3776.546814382292, 3847.846086156578, 3876.5541962674088, 3883.262180185756, 3883.339832228528, 700.1493185779302, 713.6075158662715, 714.9428045756327, 726.5495392856357, 728.4354625165539, 654.1855390039336, 655.7672026430548, 674.8616533610598, 690.1235431002599, 713.5896859305919, 9553.665621703207, 9721.9027809721, 10758.0, 10998.230123891251, 11075.833862492069, 574.8189320364072, 576.8471355090904, 636.0, 638.7604648256904, 647.4755448087064, 5077.213031980049, 5137.5376216139975, 5141.263128635756, 5190.558802501754, 5223.764930578057, 112002.43998779955, 112474.0, 115035.42482287536, 116269.67460650908, 117385.0, 81236.18763812286, 92286.0, 100791.49604252078, 101110.0, 101817.49091254496, 88093.11906880977, 88726.0, 88931.55534222287, 90088.74778130469, 90712.0, 8865.68970086044, 9328.906710932804, 9774.462404567817, 10081.848772268275, 10199.0820826126, 56088.43911560995, 56291.0, 56854.0, 57451.1382329257, 57865.71067144637, 70304.64847675934, 71045.93431098641, 72362.63818680873, 73413.0, 76813.0, 11739.589114380955, 11745.47145378443, 11956.103292252941, 11975.461104250155, 12105.33420661872, 12903.806442903551, 12977.545785897826, 13137.080404371602, 13478.137577298647, 13596.456141754616, 12273.324967126893, 12661.366931653196, 12703.80944285855, 12825.871741282468, 12940.611781646188, 12154.392280385768, 12660.98712103048, 12673.683157921127, 12755.93622031884, 12981.675458113623, 11837.467313971065, 12348.135630505778, 12621.495140194289, 12666.683332916751, 12763.936180319039, 8746.868796968178, 10135.847962280424, 10596.364218146937, 10601.522931467947, 10859.968303011201, 8062.8199531728105, 9183.943846457743, 9751.902480975099, 10297.623778113482, 10447.529861156418], "value": 7712.082020860848, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1553.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 119.0, 133.0, 176.0, 124.0, 134.0, 177.0, 162.0, 121.0, 118.0], "value": 137.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 112.0, 111.0, 149.0, 109.0, 122.0, 106.0, 110.0, 130.0, 112.0], "value": 120.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 199.0, 194.0, 199.0, 194.0, 194.0, 196.0, 201.0, 200.0, 191.0], "value": 196.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 60.0, 58.0, 84.0, 60.0, 61.0, 60.0, 59.0, 71.0, 57.0], "value": 65.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 87.0, 103.0, 88.0, 70.0, 80.0, 69.0, 70.0, 81.0], "value": 80.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 88.0, 85.0, 89.0, 85.0, 89.0, 112.0, 121.0, 133.0], "value": 97.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 155.0, 92.0, 151.0, 153.0, 120.0, 94.0, 125.0, 99.0, 121.0], "value": 120.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 92.0, 91.0, 93.0, 93.0, 93.0, 100.0, 130.0, 93.0], "value": 97.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 58.0, 74.0, 76.0, 60.0, 62.0, 61.0, 62.0, 63.0, 62.0], "value": 63.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 75.0, 73.0, 81.0, 76.0, 70.0, 68.0, 68.0, 73.0, 72.0], "value": 72.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 83.0, 88.0, 85.0, 84.0, 85.0, 87.0, 93.0, 105.0], "value": 87.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 138.0, 155.0, 123.0, 145.0, 148.0, 155.0, 183.0, 155.0], "value": 149.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 168.0, 148.0, 226.0, 230.0, 228.0, 163.0, 161.0, 222.0, 227.0], "value": 192.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 67.0, 64.0, 80.0, 87.0, 61.0, 66.0, 85.0, 83.0], "value": 72.3, "unit": "score"}]}]} 04:18:05 INFO - Return code: 0 04:18:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:18:05 ERROR - # TBPL SUCCESS # 04:18:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:18:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:18:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:18:05 INFO - 2017-03-22 04:18:05.823 screenresolution[1658:10267] starting screenresolution argv=screenresolution get 04:18:05 INFO - 2017-03-22 04:18:05.826 screenresolution[1658:10267] Display 0: 1600x1200x32@60 04:18:05 INFO - 2017-03-22 04:18:05.832 screenresolution[1659:10270] starting screenresolution argv=screenresolution list 04:18:05 INFO - Available Modes on Display 0 04:18:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:18:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:18:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:18:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:18:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:18:06 INFO - Intel Iris: 04:18:06 INFO - Chipset Model: Intel Iris 04:18:06 INFO - Type: GPU 04:18:06 INFO - Bus: Built-In 04:18:06 INFO - VRAM (Dynamic, Max): 1536 MB 04:18:06 INFO - Vendor: Intel (0x8086) 04:18:06 INFO - Device ID: 0x0a2e 04:18:06 INFO - Revision ID: 0x0009 04:18:06 INFO - Displays: 04:18:06 INFO - Display: 04:18:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:18:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:18:06 INFO - Display Serial Number: 1600x1200 60 04:18:06 INFO - Main Display: Yes 04:18:06 INFO - Mirror: Off 04:18:06 INFO - Online: Yes 04:18:06 INFO - Rotation: Supported 04:18:06 INFO - Return code: 0 04:18:06 INFO - Running post-action listener: _resource_record_post_action 04:18:06 INFO - [mozharness: 2017-03-22 11:18:06.068205Z] Finished run-tests step (success) 04:18:06 INFO - Running post-run listener: _resource_record_post_run 04:18:06 INFO - Total resource usage - Wall time: 1189s; CPU: 54.0%; Read bytes: 39413760; Write bytes: 426770432; Read time: 245; Write time: 1423 04:18:06 INFO - TinderboxPrint: CPU usage
54.4% 04:18:06 INFO - TinderboxPrint: I/O read bytes / time
39,413,760 / 245 04:18:06 INFO - TinderboxPrint: I/O write bytes / time
426,770,432 / 1,423 04:18:06 INFO - TinderboxPrint: CPU idle
2,167.7 (45.6%) 04:18:06 INFO - TinderboxPrint: CPU system
960.1 (20.2%) 04:18:06 INFO - TinderboxPrint: CPU user
1,630.0 (34.3%) 04:18:06 INFO - TinderboxPrint: Swap in / out
161,779,712 / 0 04:18:06 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 112073216; Write bytes: 104411136; Read time: 9978; Write time: 225 04:18:06 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 38492160; Write bytes: 300111872; Read time: 233; Write time: 1126 04:18:06 INFO - Running post-run listener: _upload_blobber_files 04:18:06 INFO - Blob upload gear active. 04:18:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:18:06 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 04:18:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:18:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:18:06 INFO - (blobuploader) - INFO - Open directory for files ... 04:18:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:18:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:18:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:18:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:18:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:18:08 INFO - (blobuploader) - INFO - Done attempting. 04:18:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:18:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:18:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:18:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:18:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:18:09 INFO - (blobuploader) - INFO - Done attempting. 04:18:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:18:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:18:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:18:10 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:18:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:18:10 INFO - (blobuploader) - INFO - Done attempting. 04:18:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:18:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:18:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:18:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:18:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:18:12 INFO - (blobuploader) - INFO - Done attempting. 04:18:12 INFO - (blobuploader) - INFO - Iteration through files over. 04:18:12 INFO - Return code: 0 04:18:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:18:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:18:12 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/846eb2c45074e32045dc83a5a9c92255a40a0b5744f50f1cc56f0475f3b72d2f0fe3223190579117b8bc190bdb8aa9b4645efbb387be579c5c34a6922d766cd3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bc322ac7db079340a95a3eafc1bdacfde7af7b8f28f76338b9c43d23ace5e3303dabf40a5ebd7da8b35f40ce5e376e8cb836156a7521f851524c354564a6963c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/25a2b7c858e2a14f6b3eab9d64bb7c79f8107e00ce37603eb111318aa521a09dab21aa79b78ba384bef43a46d2390d7c34afc19ba62896a7709cf3b063447314", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0c23e317df25717cc0b4762525ebc30384cd507441ba24e839d2f0ef8c05596339991142615184097ac070e687d75d50f12ca90d537ac951cfb2f9d807c1eaaf"} 04:18:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:18:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:18:12 INFO - Contents: 04:18:12 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/846eb2c45074e32045dc83a5a9c92255a40a0b5744f50f1cc56f0475f3b72d2f0fe3223190579117b8bc190bdb8aa9b4645efbb387be579c5c34a6922d766cd3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bc322ac7db079340a95a3eafc1bdacfde7af7b8f28f76338b9c43d23ace5e3303dabf40a5ebd7da8b35f40ce5e376e8cb836156a7521f851524c354564a6963c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/25a2b7c858e2a14f6b3eab9d64bb7c79f8107e00ce37603eb111318aa521a09dab21aa79b78ba384bef43a46d2390d7c34afc19ba62896a7709cf3b063447314", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0c23e317df25717cc0b4762525ebc30384cd507441ba24e839d2f0ef8c05596339991142615184097ac070e687d75d50f12ca90d537ac951cfb2f9d807c1eaaf"} 04:18:12 INFO - Running post-run listener: copy_logs_to_upload_dir 04:18:12 INFO - Copying logs to upload dir... 04:18:12 INFO - mkdir: /builds/slave/test/build/upload/logs 04:18:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.999303 ========= master_lag: 0.55 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-03-22 04:18:13.057823) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-22 04:18:13.061397) ========= 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.Tfen0ohELu/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.eU62nKFO00/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/846eb2c45074e32045dc83a5a9c92255a40a0b5744f50f1cc56f0475f3b72d2f0fe3223190579117b8bc190bdb8aa9b4645efbb387be579c5c34a6922d766cd3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bc322ac7db079340a95a3eafc1bdacfde7af7b8f28f76338b9c43d23ace5e3303dabf40a5ebd7da8b35f40ce5e376e8cb836156a7521f851524c354564a6963c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/25a2b7c858e2a14f6b3eab9d64bb7c79f8107e00ce37603eb111318aa521a09dab21aa79b78ba384bef43a46d2390d7c34afc19ba62896a7709cf3b063447314", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0c23e317df25717cc0b4762525ebc30384cd507441ba24e839d2f0ef8c05596339991142615184097ac070e687d75d50f12ca90d537ac951cfb2f9d807c1eaaf"} build_url:https://queue.taskcluster.net/v1/task/ZmR-3WC5ScyNH_t4rNLUSg/artifacts/public/build/firefox-53.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011148 build_url: 'https://queue.taskcluster.net/v1/task/ZmR-3WC5ScyNH_t4rNLUSg/artifacts/public/build/firefox-53.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/846eb2c45074e32045dc83a5a9c92255a40a0b5744f50f1cc56f0475f3b72d2f0fe3223190579117b8bc190bdb8aa9b4645efbb387be579c5c34a6922d766cd3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bc322ac7db079340a95a3eafc1bdacfde7af7b8f28f76338b9c43d23ace5e3303dabf40a5ebd7da8b35f40ce5e376e8cb836156a7521f851524c354564a6963c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/25a2b7c858e2a14f6b3eab9d64bb7c79f8107e00ce37603eb111318aa521a09dab21aa79b78ba384bef43a46d2390d7c34afc19ba62896a7709cf3b063447314", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0c23e317df25717cc0b4762525ebc30384cd507441ba24e839d2f0ef8c05596339991142615184097ac070e687d75d50f12ca90d537ac951cfb2f9d807c1eaaf"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-22 04:18:13.102682) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 04:18:13.103225) ========= 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.Tfen0ohELu/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.eU62nKFO00/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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.005088 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 04:18:13.152738) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-22 04:18:13.153134) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-22 04:18:13.153480) ========= ========= Total master_lag: 0.70 =========