builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0098 starttime: 1490398121.29 results: success (0) buildid: 20170324150515 builduid: 24b73268045145ccbf0c76fdef4382d9 revision: b961126da5f0273d07a771adfbf143a66853e147 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-24 16:28:41.285787) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-24 16:28:41.286175) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-24 16:28:41.286436) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-24 16:28:41.313820) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 16:28:41.314129) ========= 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.tllbQ0Keg1/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.PkMJa2Xpe0/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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-24 16:28:41-- 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% 56.1M=0s 2017-03-24 16:28:41 (56.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.166605 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 16:28:41.504351) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 16:28:41.504650) ========= 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.tllbQ0Keg1/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.PkMJa2Xpe0/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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.027529 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 16:28:41.553518) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-24 16:28:41.553859) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev b961126da5f0273d07a771adfbf143a66853e147 --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 b961126da5f0273d07a771adfbf143a66853e147 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tllbQ0Keg1/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.PkMJa2Xpe0/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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-24 16:28:41,605 truncating revision to first 12 chars 2017-03-24 16:28:41,605 Setting DEBUG logging. 2017-03-24 16:28:41,605 attempt 1/10 2017-03-24 16:28:41,606 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/b961126da5f0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-24 16:28:42,376 unpacking tar archive at: mozilla-beta-b961126da5f0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.049501 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-24 16:28:42.626706) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-24 16:28:42.627003) ========= script_repo_revision: b961126da5f0273d07a771adfbf143a66853e147 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-24 16:28:42.627384) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-24 16:28:42.627647) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-24 16:28:42.644767) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-03-24 16:28:42.645029) ========= /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.tllbQ0Keg1/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.PkMJa2Xpe0/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 16:28:42 INFO - MultiFileLogger online at 20170324 16:28:42 in /builds/slave/test 16:28:42 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 16:28:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:28:42 INFO - {'append_to_log': False, 16:28:42 INFO - 'base_work_dir': '/builds/slave/test', 16:28:42 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:28:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:28:42 INFO - 'branch': 'Mozilla-Beta', 16:28:42 INFO - 'buildbot_json_path': 'buildprops.json', 16:28:42 INFO - 'config_files': ('talos/mac_config.py',), 16:28:42 INFO - 'default_actions': ('clobber', 16:28:42 INFO - 'read-buildbot-config', 16:28:42 INFO - 'download-and-extract', 16:28:42 INFO - 'populate-webroot', 16:28:42 INFO - 'create-virtualenv', 16:28:42 INFO - 'install', 16:28:42 INFO - 'run-tests'), 16:28:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:28:42 INFO - 'download_minidump_stackwalk': True, 16:28:42 INFO - 'download_symbols': 'ondemand', 16:28:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:28:42 INFO - 'tooltool.py': '/tools/tooltool.py', 16:28:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:28:42 INFO - '/tools/misc-python/virtualenv.py')}, 16:28:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:28:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:28:42 INFO - 'installer_path': 'installer.exe', 16:28:42 INFO - 'log_level': 'info', 16:28:42 INFO - 'log_name': 'talos', 16:28:42 INFO - 'log_to_console': True, 16:28:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:28:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:28:42 INFO - 'opt_config_files': (), 16:28:42 INFO - 'pip_index': False, 16:28:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:28:42 INFO - 'cmd': ('bash', 16:28:42 INFO - '-c', 16:28:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:28:42 INFO - 'enabled': True, 16:28:42 INFO - 'halt_on_failure': False, 16:28:42 INFO - 'name': 'check_screen_resolution'},), 16:28:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:28:42 INFO - 'cmd': ('bash', 16:28:42 INFO - '-c', 16:28:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:28:42 INFO - 'enabled': True, 16:28:42 INFO - 'halt_on_failure': False, 16:28:42 INFO - 'name': 'check_screen_resolution'},), 16:28:42 INFO - 'run_cmd_checks_enabled': True, 16:28:42 INFO - 'sps_profile': False, 16:28:42 INFO - 'sps_profile_interval': 0, 16:28:42 INFO - 'suite': 'dromaeojs-e10s', 16:28:42 INFO - 'system_bits': '32', 16:28:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:28:42 INFO - 'title': 't-yosemite-r7-0098', 16:28:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:28:42 INFO - 'use_talos_json': True, 16:28:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:28:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:28:42 INFO - 'work_dir': 'build'} 16:28:42 INFO - [mozharness: 2017-03-24 23:28:42.809689Z] Running clobber step. 16:28:42 INFO - Running pre-action listener: _resource_record_pre_action 16:28:42 INFO - Running main action method: clobber 16:28:42 INFO - rmtree: /builds/slave/test/build 16:28:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:28:45 INFO - Running post-action listener: _resource_record_post_action 16:28:45 INFO - [mozharness: 2017-03-24 23:28:45.336363Z] Finished clobber step (success) 16:28:45 INFO - [mozharness: 2017-03-24 23:28:45.336526Z] Running read-buildbot-config step. 16:28:45 INFO - Running pre-action listener: _resource_record_pre_action 16:28:45 INFO - Running main action method: read_buildbot_config 16:28:45 INFO - Using buildbot properties: 16:28:45 INFO - { 16:28:45 INFO - "project": "", 16:28:45 INFO - "product": "firefox", 16:28:45 INFO - "script_repo_revision": "production", 16:28:45 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 16:28:45 INFO - "repository": "", 16:28:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 16:28:45 INFO - "buildid": "20170324150515", 16:28:45 INFO - "pgo_build": "False", 16:28:45 INFO - "basedir": "/builds/slave/test", 16:28:45 INFO - "buildnumber": 37, 16:28:45 INFO - "slavename": "t-yosemite-r7-0098", 16:28:45 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 16:28:45 INFO - "platform": "yosemite_r7", 16:28:45 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 16:28:45 INFO - "branch": "mozilla-beta", 16:28:45 INFO - "repo_path": "releases/mozilla-beta", 16:28:45 INFO - "revision": "b961126da5f0273d07a771adfbf143a66853e147", 16:28:45 INFO - "stage_platform": "macosx64", 16:28:45 INFO - "builduid": "24b73268045145ccbf0c76fdef4382d9", 16:28:45 INFO - "slavebuilddir": "test" 16:28:45 INFO - } 16:28:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/Jda4TLv3SwyV5Mzkrklklw/artifacts/public/build/firefox-53.0.en-US.mac.dmg. 16:28:45 INFO - Running post-action listener: _resource_record_post_action 16:28:45 INFO - [mozharness: 2017-03-24 23:28:45.339907Z] Finished read-buildbot-config step (success) 16:28:45 INFO - [mozharness: 2017-03-24 23:28:45.340038Z] Running download-and-extract step. 16:28:45 INFO - Running pre-action listener: _resource_record_pre_action 16:28:45 INFO - Running main action method: download_and_extract 16:28:45 INFO - mkdir: /builds/slave/test/build/tests 16:28:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:28:45 INFO - trying https://queue.taskcluster.net/v1/task/Jda4TLv3SwyV5Mzkrklklw/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json 16:28:45 INFO - Downloading https://queue.taskcluster.net/v1/task/Jda4TLv3SwyV5Mzkrklklw/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 16:28:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Jda4TLv3SwyV5Mzkrklklw/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 16:28:46 INFO - Downloaded 1165 bytes. 16:28:46 INFO - Reading from file /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 16:28:46 INFO - Using the following test package requirements: 16:28:46 INFO - {u'common': [u'firefox-53.0.en-US.mac.common.tests.zip'], 16:28:46 INFO - u'cppunittest': [u'firefox-53.0.en-US.mac.common.tests.zip', 16:28:46 INFO - u'firefox-53.0.en-US.mac.cppunittest.tests.zip'], 16:28:46 INFO - u'gtest': [u'firefox-53.0.en-US.mac.common.tests.zip', 16:28:46 INFO - u'firefox-53.0.en-US.mac.gtest.tests.zip'], 16:28:46 INFO - u'jittest': [u'firefox-53.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 16:28:46 INFO - u'mochitest': [u'firefox-53.0.en-US.mac.common.tests.zip', 16:28:46 INFO - u'firefox-53.0.en-US.mac.mochitest.tests.zip'], 16:28:46 INFO - u'mozbase': [u'firefox-53.0.en-US.mac.common.tests.zip'], 16:28:46 INFO - u'reftest': [u'firefox-53.0.en-US.mac.common.tests.zip', 16:28:46 INFO - u'firefox-53.0.en-US.mac.reftest.tests.zip'], 16:28:46 INFO - u'talos': [u'firefox-53.0.en-US.mac.common.tests.zip', 16:28:46 INFO - u'firefox-53.0.en-US.mac.talos.tests.zip'], 16:28:46 INFO - u'web-platform': [u'firefox-53.0.en-US.mac.common.tests.zip', 16:28:46 INFO - u'firefox-53.0.en-US.mac.web-platform.tests.zip'], 16:28:46 INFO - u'xpcshell': [u'firefox-53.0.en-US.mac.common.tests.zip', 16:28:46 INFO - u'firefox-53.0.en-US.mac.xpcshell.tests.zip']} 16:28:46 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip'] for test suite category: common 16:28:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Jda4TLv3SwyV5Mzkrklklw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 16:28:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Jda4TLv3SwyV5Mzkrklklw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 16:28:46 INFO - Fetch https://queue.taskcluster.net/v1/task/Jda4TLv3SwyV5Mzkrklklw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 16:28:47 INFO - Expected file size: 16108483 16:28:47 INFO - Obtained file size: 16108483 16:28:54 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 16:28:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Jda4TLv3SwyV5Mzkrklklw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 16:28:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Jda4TLv3SwyV5Mzkrklklw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 16:28:54 INFO - Fetch https://queue.taskcluster.net/v1/task/Jda4TLv3SwyV5Mzkrklklw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 16:28:55 INFO - Expected file size: 16108483 16:28:55 INFO - Obtained file size: 16108483 16:29:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Jda4TLv3SwyV5Mzkrklklw/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip 16:29:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Jda4TLv3SwyV5Mzkrklklw/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip'}, attempt #1 16:29:01 INFO - Fetch https://queue.taskcluster.net/v1/task/Jda4TLv3SwyV5Mzkrklklw/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip into memory 16:29:02 INFO - Expected file size: 14403663 16:29:02 INFO - Obtained file size: 14403663 16:29:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:02 INFO - trying https://queue.taskcluster.net/v1/task/Jda4TLv3SwyV5Mzkrklklw/artifacts/public/build/firefox-53.0.en-US.mac.dmg 16:29:02 INFO - Downloading https://queue.taskcluster.net/v1/task/Jda4TLv3SwyV5Mzkrklklw/artifacts/public/build/firefox-53.0.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0.en-US.mac.dmg 16:29:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Jda4TLv3SwyV5Mzkrklklw/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 16:29:04 INFO - Downloaded 58674251 bytes. 16:29:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Jda4TLv3SwyV5Mzkrklklw/artifacts/public/build/firefox-53.0.en-US.mac.dmg 16:29:04 INFO - mkdir: /builds/slave/test/properties 16:29:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:29:04 INFO - Writing to file /builds/slave/test/properties/build_url 16:29:04 INFO - Contents: 16:29:04 INFO - build_url:https://queue.taskcluster.net/v1/task/Jda4TLv3SwyV5Mzkrklklw/artifacts/public/build/firefox-53.0.en-US.mac.dmg 16:29:05 INFO - Running post-action listener: _resource_record_post_action 16:29:05 INFO - Running post-action listener: set_extra_try_arguments 16:29:05 INFO - [mozharness: 2017-03-24 23:29:05.135885Z] Finished download-and-extract step (success) 16:29:05 INFO - [mozharness: 2017-03-24 23:29:05.136011Z] Running populate-webroot step. 16:29:05 INFO - Running pre-action listener: _resource_record_pre_action 16:29:05 INFO - Running main action method: populate_webroot 16:29:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:29:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:29:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:29:05 INFO - '--tppagecycles', 16:29:05 INFO - '7'], 16:29:05 INFO - 'tests': ['tsvgm']}}, 16:29:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:29:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:29:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:29:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:29:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:29:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:29:05 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:29:05 INFO - 'talos_options': ['--disable-e10s'], 16:29:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:29:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:29:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:29:05 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:29:05 INFO - 'talos_options': ['--disable-e10s'], 16:29:05 INFO - 'tests': ['damp', 'tps']}, 16:29:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:29:05 INFO - 'tests': ['damp', 'tps']}, 16:29:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:29:05 INFO - 'tests': ['dromaeo_dom']}, 16:29:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:29:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:29:05 INFO - 'tests': ['basic_compositor_video']}, 16:29:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:29:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:29:05 INFO - 'tests': ['a11yr', 16:29:05 INFO - 'ts_paint', 16:29:05 INFO - 'tpaint', 16:29:05 INFO - 'sessionrestore', 16:29:05 INFO - 'sessionrestore_no_auto_restore', 16:29:05 INFO - 'tabpaint']}, 16:29:05 INFO - 'other-e10s': {'tests': ['a11yr', 16:29:05 INFO - 'ts_paint', 16:29:05 INFO - 'tpaint', 16:29:05 INFO - 'sessionrestore', 16:29:05 INFO - 'sessionrestore_no_auto_restore', 16:29:05 INFO - 'tabpaint']}, 16:29:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:29:05 INFO - 'ts_paint', 16:29:05 INFO - 'tpaint', 16:29:05 INFO - 'sessionrestore', 16:29:05 INFO - 'sessionrestore_no_auto_restore', 16:29:05 INFO - 'tabpaint']}, 16:29:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:29:05 INFO - 'ts_paint', 16:29:05 INFO - 'tpaint', 16:29:05 INFO - 'sessionrestore', 16:29:05 INFO - 'sessionrestore_no_auto_restore', 16:29:05 INFO - 'tabpaint']}, 16:29:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:29:05 INFO - 'tests': ['a11yr', 16:29:05 INFO - 'ts_paint', 16:29:05 INFO - 'tpaint', 16:29:05 INFO - 'sessionrestore', 16:29:05 INFO - 'sessionrestore_no_auto_restore', 16:29:05 INFO - 'tabpaint']}, 16:29:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:29:05 INFO - 'tests': ['a11yr', 16:29:05 INFO - 'ts_paint', 16:29:05 INFO - 'tpaint', 16:29:05 INFO - 'sessionrestore', 16:29:05 INFO - 'sessionrestore_no_auto_restore', 16:29:05 INFO - 'tabpaint']}, 16:29:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:29:05 INFO - 'tests': ['tsvgx', 16:29:05 INFO - 'tsvgr_opacity', 16:29:05 INFO - 'tart', 16:29:05 INFO - 'tscrollx', 16:29:05 INFO - 'cart', 16:29:05 INFO - 'tsvg_static']}, 16:29:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:29:05 INFO - 'tsvgr_opacity', 16:29:05 INFO - 'tart', 16:29:05 INFO - 'tscrollx', 16:29:05 INFO - 'cart', 16:29:05 INFO - 'tsvg_static']}, 16:29:05 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:29:05 INFO - 'talos_options': ['--disable-e10s'], 16:29:05 INFO - 'tests': ['tp5o']}, 16:29:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:29:05 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:29:05 INFO - 'talos_options': ['--disable-e10s', 16:29:05 INFO - '--xperf_path', 16:29:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:29:05 INFO - 'tests': ['tp5n']}, 16:29:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:29:05 INFO - 'talos_options': ['--xperf_path', 16:29:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:29:05 INFO - 'tests': ['tp5n']}}, 16:29:05 INFO - 'talos.zip': {'path': '', 16:29:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:29:05 INFO - Running post-action listener: _resource_record_post_action 16:29:05 INFO - [mozharness: 2017-03-24 23:29:05.154230Z] Finished populate-webroot step (success) 16:29:05 INFO - [mozharness: 2017-03-24 23:29:05.154320Z] Running create-virtualenv step. 16:29:05 INFO - Running pre-action listener: _resource_record_pre_action 16:29:05 INFO - Running main action method: create_virtualenv 16:29:05 INFO - Creating virtualenv /builds/slave/test/build/venv 16:29:05 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:29:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:29:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:29:05 INFO - Using real prefix '/tools/python27' 16:29:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:29:06 INFO - Installing distribute.............................................................................................................................................................................................done. 16:29:08 INFO - Installing pip.................done. 16:29:08 INFO - Return code: 0 16:29:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:29:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:29:09 INFO - Reading from file tmpfile_stdout 16:29:09 INFO - Output received: 16:29:09 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 16:29:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:09 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 0x10d0c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc024a79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cea6d30>, '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 0x10cdcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce9bbe8>, '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.tllbQ0Keg1/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.PkMJa2Xpe0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 16:29:09 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 16:29:09 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 16:29:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tllbQ0Keg1/Render', 16:29:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:09 INFO - 'HOME': '/Users/cltbld', 16:29:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:09 INFO - 'LOGNAME': 'cltbld', 16:29:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:09 INFO - 'MOZ_NO_REMOTE': '1', 16:29:09 INFO - 'NO_EM_RESTART': '1', 16:29:09 INFO - 'PAGER': '/bin/cat', 16:29:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:09 INFO - 'PWD': '/builds/slave/test', 16:29:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:09 INFO - 'SHELL': '/bin/bash', 16:29:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PkMJa2Xpe0/Listeners', 16:29:09 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 16:29:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:09 INFO - 'USER': 'cltbld', 16:29:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:09 INFO - 'XPC_FLAGS': '0x0', 16:29:09 INFO - 'XPC_SERVICE_NAME': '0', 16:29:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:09 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 16:29:09 INFO - Cleaning up... 16:29:09 INFO - Return code: 0 16:29:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:09 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 0x10d0c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc024a79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cea6d30>, '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 0x10cdcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce9bbe8>, '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.tllbQ0Keg1/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.PkMJa2Xpe0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 16:29:09 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 16:29:09 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 16:29:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tllbQ0Keg1/Render', 16:29:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:09 INFO - 'HOME': '/Users/cltbld', 16:29:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:09 INFO - 'LOGNAME': 'cltbld', 16:29:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:09 INFO - 'MOZ_NO_REMOTE': '1', 16:29:09 INFO - 'NO_EM_RESTART': '1', 16:29:09 INFO - 'PAGER': '/bin/cat', 16:29:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:09 INFO - 'PWD': '/builds/slave/test', 16:29:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:09 INFO - 'SHELL': '/bin/bash', 16:29:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PkMJa2Xpe0/Listeners', 16:29:09 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 16:29:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:09 INFO - 'USER': 'cltbld', 16:29:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:09 INFO - 'XPC_FLAGS': '0x0', 16:29:09 INFO - 'XPC_SERVICE_NAME': '0', 16:29:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:09 INFO - Downloading/unpacking psutil>=3.1.1 16:29:09 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 16:29:09 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 16:29:09 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 16:29:09 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 16:29:09 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 16:29:09 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 16:29:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:29:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:29:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:29:13 INFO - Installing collected packages: psutil 16:29:13 INFO - Running setup.py install for psutil 16:29:13 INFO - building 'psutil._psutil_osx' extension 16:29:13 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 16:29:14 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 16:29:14 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 16:29:14 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 16:29:14 INFO - building 'psutil._psutil_posix' extension 16:29:14 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 16:29:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:29:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:29:14 INFO - ^ 16:29:14 INFO - 1 warning generated. 16:29:14 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 16:29:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:29:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:29:14 INFO - Successfully installed psutil 16:29:14 INFO - Cleaning up... 16:29:14 INFO - Return code: 0 16:29:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:29: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')]} 16:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29: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')]} 16:29:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:14 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 0x10d0c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc024a79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cea6d30>, '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 0x10cdcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce9bbe8>, '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.tllbQ0Keg1/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.PkMJa2Xpe0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 16:29:14 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 16:29:14 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 16:29:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tllbQ0Keg1/Render', 16:29:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:14 INFO - 'HOME': '/Users/cltbld', 16:29:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:14 INFO - 'LOGNAME': 'cltbld', 16:29:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:14 INFO - 'MOZ_NO_REMOTE': '1', 16:29:14 INFO - 'NO_EM_RESTART': '1', 16:29:14 INFO - 'PAGER': '/bin/cat', 16:29:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:14 INFO - 'PWD': '/builds/slave/test', 16:29:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:14 INFO - 'SHELL': '/bin/bash', 16:29:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PkMJa2Xpe0/Listeners', 16:29:14 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 16:29:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:14 INFO - 'USER': 'cltbld', 16:29:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:14 INFO - 'XPC_FLAGS': '0x0', 16:29:14 INFO - 'XPC_SERVICE_NAME': '0', 16:29:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:29:15 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 16:29:15 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 16:29:15 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 16:29:15 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 16:29:15 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 16:29:15 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 16:29:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:29:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:29:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:29:17 INFO - Installing collected packages: mozsystemmonitor 16:29:17 INFO - Running setup.py install for mozsystemmonitor 16:29:17 INFO - Successfully installed mozsystemmonitor 16:29:17 INFO - Cleaning up... 16:29:17 INFO - Return code: 0 16:29:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:29:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d0c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc024a79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cea6d30>, '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 0x10cdcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce9bbe8>, '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.tllbQ0Keg1/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.PkMJa2Xpe0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 16:29:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:29:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:29:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tllbQ0Keg1/Render', 16:29:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:17 INFO - 'HOME': '/Users/cltbld', 16:29:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:17 INFO - 'LOGNAME': 'cltbld', 16:29:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:17 INFO - 'MOZ_NO_REMOTE': '1', 16:29:17 INFO - 'NO_EM_RESTART': '1', 16:29:17 INFO - 'PAGER': '/bin/cat', 16:29:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:17 INFO - 'PWD': '/builds/slave/test', 16:29:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:17 INFO - 'SHELL': '/bin/bash', 16:29:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PkMJa2Xpe0/Listeners', 16:29:17 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 16:29:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:17 INFO - 'USER': 'cltbld', 16:29:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:17 INFO - 'XPC_FLAGS': '0x0', 16:29:17 INFO - 'XPC_SERVICE_NAME': '0', 16:29:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:18 INFO - Downloading/unpacking jsonschema==2.5.1 16:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:29:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:21 INFO - Downloading functools32-3.2.3-2.tar.gz 16:29:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:29:21 INFO - warning: no files found matching '*.txt' 16:29:21 INFO - no previously-included directories found matching 'build' 16:29:21 INFO - no previously-included directories found matching 'dist' 16:29:21 INFO - no previously-included directories found matching '.git*' 16:29:21 INFO - Installing collected packages: jsonschema, functools32 16:29:21 INFO - Running setup.py install for functools32 16:29:21 INFO - warning: no files found matching '*.txt' 16:29:21 INFO - no previously-included directories found matching 'build' 16:29:21 INFO - no previously-included directories found matching 'dist' 16:29:21 INFO - no previously-included directories found matching '.git*' 16:29:21 INFO - Successfully installed jsonschema functools32 16:29:21 INFO - Cleaning up... 16:29:21 INFO - Return code: 0 16:29:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:29:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d0c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc024a79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cea6d30>, '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 0x10cdcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce9bbe8>, '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.tllbQ0Keg1/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.PkMJa2Xpe0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 16:29:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:29:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:29:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tllbQ0Keg1/Render', 16:29:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:21 INFO - 'HOME': '/Users/cltbld', 16:29:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:21 INFO - 'LOGNAME': 'cltbld', 16:29:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:21 INFO - 'MOZ_NO_REMOTE': '1', 16:29:21 INFO - 'NO_EM_RESTART': '1', 16:29:21 INFO - 'PAGER': '/bin/cat', 16:29:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:21 INFO - 'PWD': '/builds/slave/test', 16:29:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:21 INFO - 'SHELL': '/bin/bash', 16:29:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PkMJa2Xpe0/Listeners', 16:29:21 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 16:29:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:21 INFO - 'USER': 'cltbld', 16:29:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:21 INFO - 'XPC_FLAGS': '0x0', 16:29:21 INFO - 'XPC_SERVICE_NAME': '0', 16:29:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:29:21 INFO - Cleaning up... 16:29:21 INFO - Return code: 0 16:29:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:29:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d0c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc024a79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cea6d30>, '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 0x10cdcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce9bbe8>, '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.tllbQ0Keg1/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.PkMJa2Xpe0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 16:29:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:29:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:29:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tllbQ0Keg1/Render', 16:29:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:21 INFO - 'HOME': '/Users/cltbld', 16:29:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:21 INFO - 'LOGNAME': 'cltbld', 16:29:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:21 INFO - 'MOZ_NO_REMOTE': '1', 16:29:21 INFO - 'NO_EM_RESTART': '1', 16:29:21 INFO - 'PAGER': '/bin/cat', 16:29:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:21 INFO - 'PWD': '/builds/slave/test', 16:29:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:21 INFO - 'SHELL': '/bin/bash', 16:29:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PkMJa2Xpe0/Listeners', 16:29:21 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 16:29:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:21 INFO - 'USER': 'cltbld', 16:29:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:21 INFO - 'XPC_FLAGS': '0x0', 16:29:21 INFO - 'XPC_SERVICE_NAME': '0', 16:29:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:22 INFO - Downloading/unpacking blobuploader==1.2.4 16:29:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:24 INFO - Downloading blobuploader-1.2.4.tar.gz 16:29:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:29:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:29:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:29:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:25 INFO - Downloading docopt-0.6.1.tar.gz 16:29:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:29:26 INFO - Installing collected packages: blobuploader, requests, docopt 16:29:26 INFO - Running setup.py install for blobuploader 16:29:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:29:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:29:26 INFO - Running setup.py install for requests 16:29:26 INFO - Running setup.py install for docopt 16:29:26 INFO - Successfully installed blobuploader requests docopt 16:29:26 INFO - Cleaning up... 16:29:27 INFO - Return code: 0 16:29:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:29:27 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')]} 16:29:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:27 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')]} 16:29:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:27 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 0x10d0c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc024a79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cea6d30>, '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 0x10cdcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce9bbe8>, '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.tllbQ0Keg1/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.PkMJa2Xpe0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 16:29:27 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 16:29:27 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 16:29:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tllbQ0Keg1/Render', 16:29:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:27 INFO - 'HOME': '/Users/cltbld', 16:29:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:27 INFO - 'LOGNAME': 'cltbld', 16:29:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:27 INFO - 'MOZ_NO_REMOTE': '1', 16:29:27 INFO - 'NO_EM_RESTART': '1', 16:29:27 INFO - 'PAGER': '/bin/cat', 16:29:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:27 INFO - 'PWD': '/builds/slave/test', 16:29:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:27 INFO - 'SHELL': '/bin/bash', 16:29:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PkMJa2Xpe0/Listeners', 16:29:27 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 16:29:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:27 INFO - 'USER': 'cltbld', 16:29:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:27 INFO - 'XPC_FLAGS': '0x0', 16:29:27 INFO - 'XPC_SERVICE_NAME': '0', 16:29:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:29:27 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-pOqH43-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:29:27 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-8aHczb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:29:27 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-wp66rK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:29:27 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-h2BBuZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:29:27 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-rL5TOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:29:27 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-4RmiGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:29:27 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-hM3u4K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:29:28 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-JKUrPe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:29:28 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-ogWV7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:29:28 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-b7TkBp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:29:28 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-308giL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:29:28 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-iMUH4a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:29:28 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-YnWsRQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:29:28 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-k3PloL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:28 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-kd_2dJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:29:28 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-UFVyX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:29:28 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-XlzSIn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:29:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:29:29 INFO - Running setup.py install for manifestparser 16:29:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:29:29 INFO - Running setup.py install for mozcrash 16:29:29 INFO - Running setup.py install for mozdebug 16:29:29 INFO - Running setup.py install for mozdevice 16:29:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:29:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:29:29 INFO - Running setup.py install for mozfile 16:29:29 INFO - Running setup.py install for mozhttpd 16:29:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:29:29 INFO - Running setup.py install for mozinfo 16:29:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:29:30 INFO - Running setup.py install for mozInstall 16:29:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:29:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:29:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:29:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:29:30 INFO - Running setup.py install for mozleak 16:29:30 INFO - Running setup.py install for mozlog 16:29:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:29:30 INFO - Running setup.py install for moznetwork 16:29:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:29:30 INFO - Running setup.py install for mozprocess 16:29:30 INFO - Running setup.py install for mozprofile 16:29:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:29:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:29:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:29:30 INFO - Running setup.py install for mozrunner 16:29:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:29:31 INFO - Running setup.py install for mozscreenshot 16:29:31 INFO - Running setup.py install for moztest 16:29:31 INFO - Running setup.py install for mozversion 16:29:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:29:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:29:31 INFO - Cleaning up... 16:29:31 INFO - Return code: 0 16:29:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:29:31 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')]} 16:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:31 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')]} 16:29:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:31 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 0x10d0c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc024a79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cea6d30>, '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 0x10cdcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce9bbe8>, '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.tllbQ0Keg1/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.PkMJa2Xpe0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 16:29:31 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 16:29:31 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 16:29:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tllbQ0Keg1/Render', 16:29:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:31 INFO - 'HOME': '/Users/cltbld', 16:29:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:31 INFO - 'LOGNAME': 'cltbld', 16:29:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:31 INFO - 'MOZ_NO_REMOTE': '1', 16:29:31 INFO - 'NO_EM_RESTART': '1', 16:29:31 INFO - 'PAGER': '/bin/cat', 16:29:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:31 INFO - 'PWD': '/builds/slave/test', 16:29:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:31 INFO - 'SHELL': '/bin/bash', 16:29:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PkMJa2Xpe0/Listeners', 16:29:31 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 16:29:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:31 INFO - 'USER': 'cltbld', 16:29:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:31 INFO - 'XPC_FLAGS': '0x0', 16:29:31 INFO - 'XPC_SERVICE_NAME': '0', 16:29:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:29:31 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-MfPk92-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:29:32 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)) 16:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:29:32 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-t6SvZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:29:32 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)) 16:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:29:32 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-GpiCeS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:29:32 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)) 16:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:29:32 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-xa6RCR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:29:32 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)) 16:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:29:32 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-E1B8AU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:29:32 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)) 16:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:29:32 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-kU9gqx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:29:32 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)) 16:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:29:32 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-KcjPJX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:29:32 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)) 16:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:29:32 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-Zr6GHg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:29:32 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)) 16:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:29:32 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-bQ9p_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:29:32 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)) 16:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:29:32 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-6wd3bm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:29:32 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)) 16:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:29:32 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-Ts_ELR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:29:33 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)) 16:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:29:33 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-ku9CJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:29:33 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)) 16:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:29:33 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-Rr8Paz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:29:33 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)) 16:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:29:33 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-SL3jFI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:29:33 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)) 16:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:33 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-CQkOtu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:33 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)) 16:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:29:33 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-J7sYbt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:29:33 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)) 16:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:29:33 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-n9_BPM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:29:33 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)) 16:29:33 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)) 16:29:33 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)) 16:29:33 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)) 16:29:33 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)) 16:29:33 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)) 16:29:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:29:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:36 INFO - Downloading blessings-1.6.tar.gz 16:29:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:29:36 INFO - Installing collected packages: blessings 16:29:36 INFO - Running setup.py install for blessings 16:29:36 INFO - Successfully installed blessings 16:29:36 INFO - Cleaning up... 16:29:36 INFO - Return code: 0 16:29:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:29:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:29:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:29:36 INFO - Reading from file tmpfile_stdout 16:29:36 INFO - Current package versions: 16:29:36 INFO - blessings == 1.6 16:29:36 INFO - blobuploader == 1.2.4 16:29:36 INFO - docopt == 0.6.1 16:29:36 INFO - functools32 == 3.2.3-2 16:29:36 INFO - jsonschema == 2.5.1 16:29:36 INFO - manifestparser == 1.1 16:29:36 INFO - mozInstall == 1.12 16:29:36 INFO - mozcrash == 1.0 16:29:36 INFO - mozdebug == 0.1 16:29:36 INFO - mozdevice == 0.49 16:29:36 INFO - mozfile == 1.2 16:29:36 INFO - mozhttpd == 0.7 16:29:36 INFO - mozinfo == 0.9 16:29:36 INFO - mozleak == 0.1 16:29:36 INFO - mozlog == 3.4 16:29:36 INFO - moznetwork == 0.27 16:29:36 INFO - mozprocess == 0.24 16:29:36 INFO - mozprofile == 0.28 16:29:36 INFO - mozrunner == 6.13 16:29:36 INFO - mozscreenshot == 0.1 16:29:36 INFO - mozsystemmonitor == 0.3 16:29:36 INFO - moztest == 0.8 16:29:36 INFO - mozversion == 1.4 16:29:36 INFO - psutil == 3.1.1 16:29:36 INFO - requests == 1.2.3 16:29:36 INFO - wsgiref == 0.1.2 16:29:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:29:36 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')]} 16:29:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:36 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')]} 16:29:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:36 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 0x10d0c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc024a79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cea6d30>, '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 0x10cdcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce9bbe8>, '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.tllbQ0Keg1/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.PkMJa2Xpe0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 16:29:36 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 16:29:36 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 16:29:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tllbQ0Keg1/Render', 16:29:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:36 INFO - 'HOME': '/Users/cltbld', 16:29:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:36 INFO - 'LOGNAME': 'cltbld', 16:29:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:36 INFO - 'MOZ_NO_REMOTE': '1', 16:29:36 INFO - 'NO_EM_RESTART': '1', 16:29:36 INFO - 'PAGER': '/bin/cat', 16:29:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:36 INFO - 'PWD': '/builds/slave/test', 16:29:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:36 INFO - 'SHELL': '/bin/bash', 16:29:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PkMJa2Xpe0/Listeners', 16:29:36 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 16:29:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:36 INFO - 'USER': 'cltbld', 16:29:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:36 INFO - 'XPC_FLAGS': '0x0', 16:29:36 INFO - 'XPC_SERVICE_NAME': '0', 16:29:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:37 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)) 16:29:37 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)) 16:29:37 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)) 16:29:37 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)) 16:29:37 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)) 16:29:37 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)) 16:29:37 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)) 16:29:37 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)) 16:29:37 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)) 16:29:37 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)) 16:29:37 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)) 16:29:37 INFO - Cleaning up... 16:29:37 INFO - Return code: 0 16:29:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d0c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc024a79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cea6d30>, '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 0x10cdcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce9bbe8>, '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.tllbQ0Keg1/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.PkMJa2Xpe0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 16:29:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:29:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:29:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tllbQ0Keg1/Render', 16:29:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:37 INFO - 'HOME': '/Users/cltbld', 16:29:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:37 INFO - 'LOGNAME': 'cltbld', 16:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:37 INFO - 'MOZ_NO_REMOTE': '1', 16:29:37 INFO - 'NO_EM_RESTART': '1', 16:29:37 INFO - 'PAGER': '/bin/cat', 16:29:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:37 INFO - 'PWD': '/builds/slave/test', 16:29:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:37 INFO - 'SHELL': '/bin/bash', 16:29:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PkMJa2Xpe0/Listeners', 16:29:37 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 16:29:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:37 INFO - 'USER': 'cltbld', 16:29:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:37 INFO - 'XPC_FLAGS': '0x0', 16:29:37 INFO - 'XPC_SERVICE_NAME': '0', 16:29:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:29:37 INFO - Cleaning up... 16:29:37 INFO - Return code: 0 16:29:37 INFO - Running post-action listener: _resource_record_post_action 16:29:37 INFO - Running post-action listener: _start_resource_monitoring 16:29:37 INFO - Starting resource monitoring. 16:29:37 INFO - [mozharness: 2017-03-24 23:29:37.527953Z] Finished create-virtualenv step (success) 16:29:37 INFO - [mozharness: 2017-03-24 23:29:37.528899Z] Running install step. 16:29:37 INFO - Running pre-action listener: _resource_record_pre_action 16:29:37 INFO - Running main action method: install 16:29:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:29:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:29:37 INFO - Reading from file tmpfile_stdout 16:29:37 INFO - Detecting whether we're running mozinstall >=1.0... 16:29:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:29:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:29:37 INFO - Reading from file tmpfile_stdout 16:29:37 INFO - Output received: 16:29:37 INFO - Usage: mozinstall [options] installer 16:29:37 INFO - Options: 16:29:37 INFO - -h, --help show this help message and exit 16:29:37 INFO - -d DEST, --destination=DEST 16:29:37 INFO - Directory to install application into. [default: 16:29:37 INFO - "/builds/slave/test"] 16:29:37 INFO - --app=APP Application being installed. [default: firefox] 16:29:37 INFO - mkdir: /builds/slave/test/build/application 16:29:37 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'] 16:29:37 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 16:29:52 INFO - Reading from file tmpfile_stdout 16:29:52 INFO - Output received: 16:29:52 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 16:29:52 INFO - Running post-action listener: _resource_record_post_action 16:29:52 INFO - [mozharness: 2017-03-24 23:29:52.435693Z] Finished install step (success) 16:29:52 INFO - [mozharness: 2017-03-24 23:29:52.435781Z] Running run-tests step. 16:29:52 INFO - Running pre-action listener: _resource_record_pre_action 16:29:52 INFO - Running main action method: run_tests 16:29:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:29:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:29:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:29:52 INFO - 2017-03-24 16:29:52.448 screenresolution[1629:8246] starting screenresolution argv=screenresolution get 16:29:52 INFO - 2017-03-24 16:29:52.450 screenresolution[1629:8246] Display 0: 1600x1200x32@60 16:29:52 INFO - 2017-03-24 16:29:52.455 screenresolution[1630:8248] starting screenresolution argv=screenresolution list 16:29:52 INFO - Available Modes on Display 0 16:29:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:29:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:29:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:29:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:29:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:29:52 INFO - Intel Iris: 16:29:52 INFO - Chipset Model: Intel Iris 16:29:52 INFO - Type: GPU 16:29:52 INFO - Bus: Built-In 16:29:52 INFO - VRAM (Dynamic, Max): 1536 MB 16:29:52 INFO - Vendor: Intel (0x8086) 16:29:52 INFO - Device ID: 0x0a2e 16:29:52 INFO - Revision ID: 0x0009 16:29:52 INFO - Displays: 16:29:52 INFO - Display: 16:29:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:29:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:29:52 INFO - Display Serial Number: 1600x1200 60 16:29:52 INFO - Main Display: Yes 16:29:52 INFO - Mirror: Off 16:29:52 INFO - Online: Yes 16:29:52 INFO - Rotation: Supported 16:29:52 INFO - Return code: 0 16:29:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:29:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:29:52 INFO - Python 2.7.3 16:29:52 INFO - Return code: 0 16:29:52 INFO - grabbing minidump binary from tooltool 16:29:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:52 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 0x10cea6d30>, '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 0x10cdcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce9bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:29:52 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 16:29:52 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 16:29:52 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 16:29:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:29:52 INFO - Return code: 0 16:29:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:29:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:29:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:29:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:29:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:29:52 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/Jda4TLv3SwyV5Mzkrklklw/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0098', '--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 16:29:52 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/Jda4TLv3SwyV5Mzkrklklw/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0098 --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 16:29:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tllbQ0Keg1/Render', 16:29:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:52 INFO - 'HOME': '/Users/cltbld', 16:29:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:52 INFO - 'LOGNAME': 'cltbld', 16:29:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:29:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:29:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:52 INFO - 'MOZ_NO_REMOTE': '1', 16:29:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:29:52 INFO - 'NO_EM_RESTART': '1', 16:29:52 INFO - 'PAGER': '/bin/cat', 16:29:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:52 INFO - 'PWD': '/builds/slave/test', 16:29:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:29:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:52 INFO - 'SHELL': '/bin/bash', 16:29:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PkMJa2Xpe0/Listeners', 16:29:52 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 16:29:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:52 INFO - 'USER': 'cltbld', 16:29:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:52 INFO - 'XPC_FLAGS': '0x0', 16:29:52 INFO - 'XPC_SERVICE_NAME': '0', 16:29:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:52 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/Jda4TLv3SwyV5Mzkrklklw/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0098', '--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 16:29:53 INFO - mozversion application_buildid: 20170324150515 16:29:53 INFO - mozversion application_changeset: b961126da5f0273d07a771adfbf143a66853e147 16:29:53 INFO - mozversion application_display_name: Firefox 16:29:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:29:53 INFO - mozversion application_name: Firefox 16:29:53 INFO - mozversion application_remotingname: firefox 16:29:53 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:29:53 INFO - mozversion application_vendor: Mozilla 16:29:53 INFO - mozversion application_version: 53.0 16:29:53 INFO - mozversion platform_buildid: 20170324150515 16:29:53 INFO - mozversion platform_changeset: b961126da5f0273d07a771adfbf143a66853e147 16:29:53 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:29:53 INFO - mozversion platform_version: 53.0 16:29:53 INFO - using testdate: 1490398193 16:29:53 INFO - actual date: 1490398193 16:29:53 INFO - starting webserver on 'localhost:49240' 16:29:53 INFO - SUITE-START | Running 2 tests 16:29:53 INFO - TEST-START | dromaeo_css 16:29:53 INFO - operating with platform_type : mac_ 16:29:53 INFO - Initialising browser for dromaeo_css test... 16:29:53 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmpvKowUQ/profile http://localhost:49240/getInfo.html) 16:29:55 INFO - PROCESS | 1640 | 2017-03-24 16:29:55.933 plugin-container[1643:8424] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b43, name = 'com.apple.tsm.portname' 16:29:55 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:29:55 INFO - PROCESS | 1640 | 2017-03-24 16:29:55.933 plugin-container[1643:8424] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c17, name = 'com.apple.CFPasteboardClient' 16:29:55 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:29:55 INFO - PROCESS | 1640 | 2017-03-24 16:29:55.933 plugin-container[1643:8424] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:29:55 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 16:29:55 INFO - PROCESS | 1640 | colorDepth:24 16:29:55 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 16:29:55 INFO - PROCESS | 1640 | __metrics 16:29:56 ERROR - PROCESS | 1640 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 16:29:56 INFO - TEST-INFO | 1640: exit 0 16:29:56 INFO - Browser initialized. 16:29:56 INFO - Running cycle 1/1 for dromaeo_css test... 16:29:56 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmpvKowUQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:29:59 INFO - PROCESS | 1645 | 2017-03-24 16:29:59.098 plugin-container[1646:8568] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 16:29:59 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:29:59 INFO - PROCESS | 1645 | 2017-03-24 16:29:59.099 plugin-container[1646:8568] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 16:29:59 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:29:59 INFO - PROCESS | 1645 | 2017-03-24 16:29:59.099 plugin-container[1646:8568] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:30:08 INFO - PROCESS | 1645 | RSS: Main: 172810240 16:30:08 INFO - PROCESS | 1645 | 16:33:10 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 16:33:12 INFO - PROCESS | 1645 | RSS: Main: 188665856 16:33:12 INFO - PROCESS | 1645 | 16:36:14 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 16:36:15 INFO - PROCESS | 1645 | RSS: Main: 203014144 16:36:15 INFO - PROCESS | 1645 | 16:39:17 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 16:39:18 INFO - PROCESS | 1645 | RSS: Main: 205672448 16:39:18 INFO - PROCESS | 1645 | 16:42:19 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 16:42:20 INFO - PROCESS | 1645 | RSS: Main: 208748544 16:42:20 INFO - PROCESS | 1645 | 16:45:23 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 16:45:23 INFO - PROCESS | 1645 | RSS: Main: 209944576 16:45:23 INFO - PROCESS | 1645 | 16:48:26 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 16:48:26 INFO - PROCESS | 1645 | RSS: Main: 210186240 16:48:26 INFO - PROCESS | 1645 | 16:48:26 INFO - PROCESS | 1645 | __start_tp_report 16:48:26 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 16:48:26 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 16:48:26 INFO - PROCESS | 1645 | |i|pagename|runs| 16:48:26 INFO - PROCESS | 1645 | |0;dojo.html;3556.5020897074396;3564.714822814175;3588.264405796812;3595.928081438373;3617.2765446910607;5335.4664533546675;5358.713908661913;5602.915956260655;5673.6879471629045;6101.420365065323;5460.890782184354;5700.543956483481;6052.878942421149;6111.694415279229;6128.662923539204;5385.919211211835;5435.2934118564535;5438.782448702047;5479.808206712766;5492.47821456963;4546.8408605698805;5488.643238189525;5489.862753431156;5510.889782204354;5553.416891226424;4801.855944321676;5353.678779273256;5384.703841288728;5479.205515200293;5502.669839809604;4587.931181032287;5367.0339338918975;5397.595180361469;5443.646162999393;5512.84230311635;4615.30770384445;5292.311999440087;5422.8915421691345;5429.592780541436;5477.835664930059;4477.574630410106;4831.95168048322;5861.20873682056;6129.754809807602;6144.293406258293;6207.31719510858;6250.624962502219;6272.65500397478;6307.7699848529555;6410.647414392207;5605.134808613047;6389.616623002588;6475.676216189172;6476.222853257639;6492.772752953624;6198.597091189058;6205.596636218631;6211.378862113843;6222.439980401831;6226.470749986212;6383;6386.521010924199;6464.288928217892;6467.579607325509;6503;6277.686115694197;6314.842128946813;6331.715072821734;6336.461400780893;6354.3963323484195;6434.903476447857;6437.452816510649;6457.741690332334;6468.579542329733;6470.741170353133;6199.194104766441;6217.906731399033;6240.282367527792;6242.15730876335;6302.747890084345;6304.936950630527;6307.9369206308265;6321.589096708699;6337.144485494399;6354.491640668735;5175;5209.661372010807;5209.6874187548865;5225.581953443715;5244.685318880879;4555.772211389417;4601.378813860086;4601.815927362868;4668.97665511677;4692.366530518405;6151.0773383992755;6183.443490085914;6224.439800417894;6236.656983865841;6242.469390101892;6505.349465053457;6508.707108180143;6514;6529.934700652932;6537.34626537352;5143.742812859342;6081.17904082952;6112.807455516588;6152.2924863640355;6190.597611155351;5692.629979051431;5739.7704091836695;5742.942570574324;5747.453991870766;5763.164341170507;6673.866522669617;6680.265170831205;6688.097106890507;6712.95949127886;6725.8654826903185;6341.587796793194;6342.746290148342;6364;6423.293437721753;6482.935170648233;5597.63615365;6077.392260774064;6671.833204169935;6707.765228216989;6725.9663701681175;6407.807765767035;6421.743130274736;6423.550351475352;6444.3233460487845;6701.966490167518;6266.122742815918;6317.273513546001;6329.335419780857;6466.06242094903;6487.113234610553;5739.5695322850115;5787.334456537552;6159.784407545893;6303.842403939938;6319.146915166394;6273.62358258506;6304.747810087545;6317.147185130132;6333.936660633335;6338.714757835816;6101.694915254308;6137.714085983886;6188.8762224754355;6225.346338634559;6297;6170.50635949114;6173.135760993554;6221.222347206599;6281.371862813682;6302.905456418249;5260.815871444481;6232.750689972531;6241.500679945684;6248.750049998131;6253.187085678833 16:48:26 INFO - PROCESS | 1645 | |1;ext.html;458.4086528378392;469.97650117494123;491.9311296418502;521.7208793295588;534.4521865088283;32378.838105809467;43134.13731725363;43143.92140196493;43149.568504314906;43442.56557434425;44675.10649787002;45105.54894451055;45117.646470605854;45208.64374068775;45229;9511.048895110485;9756.07317304855;9789.95105024474;11386;11821.7635647287;11646.534138634444;11702.180847340689;11764.882351176508;11771.058315334752;11772.705682357924;11676;11725.648230553097;11773.941130294379;11790;11804.114691398137;11684.182107252453;11691.532338706442;11762.059035277158;11767.58813441534;11767.646970590897;11725.706857328634;11765.235259708177;11831.112666549958;11832.053435725122;11832.763344733057;38863.61136388628;39112.80443597792;39173.41239881404;39330.41004384936;39424.60575394238;4062.6546743526837;4068.4507591475067;4085.040015596362;4142.855000074983;4153.210889930903;29349.70650293491;29419.264518386997;29532.852335738186;30718;31262.531062034086;3201.2157021529642;3359.2945481448924;3377.493375993596;3523.8942831714835;3525.012996361023;468597;469165;469220;471770.64114679204;473262;62074;62382;62531.062034069524;62636;62655.6867215661;55630.72184639051;62655.6867215661;62789.05816412757;64927.350726493074;65687.68624627481;15173.696526069416;17290.394836180923;17388.91305543464;17409.65180696379;17453.563660908578;12216.816747748791;12542.1847579907;13122.14706044104;13371.866281337258;13418.798718019238;18007.784318320853;19261.422157335302;19287.614247714966;19437.66494037367;19511.707324390147;6903.275156108637;7171.784846454615;7774.378049756006;8220.38347123968;8274.958625206835;736.8931504931755;766.7354762606892;905.7916679163698;924.912133347331;945.5603144537686;31662.525062124085;32208.38958052135;32316;32410.351792964007;32580.34839303248;933.4492649336923;1050.0182329521922;1104.364990130678;1162.3026184289358;1275.0691994843817;15307.923460382626;16071.598210044842;16743.413980510624;18018.099095045196;18050.548736281435;581861;653353;654198;655541;658055;68613;68943.65528172327;68994.31005689979;69256.30743692498;69391;1420.6235347632885;1467.1637166814812;1531.8314985351603;1547.3578464936925;1574.2679653960734;32243;33467.33065338728;33555;33759.98720038403;33809.64468268303;48357.79105522249;58844.52888677646;58854.411455884896;58858.41141588529;58942.70528647329;523521;607524;609181;611182.9440852767;611225;38381;39046.609533904295;45628.771856140505;47006;47069.52930470651;43358.132837343706;49737;50084;51040.5803898875;51749.74125129501;38646.2270754589;40022.1995560093;42219;42506.08722607428;43300.35049474197;41792.791036044626;42159;42230.78884605557;42450.575494244666;42459.4404416206;37239.441408378356;37969.86090417292;38080.238395232496;41129.76610701684;41372;32690.346193076482;34665.35305737619;34772.82613587017;34778.13054673653;34948;23521.41196470102;24753.87623061873;25886.61170082488;26188.73811261863;26531.86734066317 16:48:26 INFO - PROCESS | 1645 | |2;jquery.html;396.4232042378338;397.8249570189117;397.91643754811486;409.74391005621476;424.7600105940144;21139.15443382265;21664.133434662635;22146.778532214674;22211;22342.329730108082;22513.662295065587;22995.42511437215;23034.769652303472;23179.768202317973;23355.299341019752;5899.498542623872;6211;6214.285357183937;6227.501799856001;6235.688215589225;4927.753612319388;6150.938490615104;6163.568550201488;6176.752929882799;6219.564630475868;6160.291566469846;6243.531735119862;6252.468540174091;6266.749330026793;6272.372762723737;5846.269216347957;6127.938720612804;6173.043178307392;6185.381461853824;6247.656378899159;5943.4056594340655;6029.758809647609;6138.386161383871;6150.66171360575;6153.1693221415435;13704.177749335071;13827.170369777745;14002.859971400258;26825.731742682518;27012.594811077848;2686.1270087221733;2724.101046654599;2816.6479190101236;2913.475574396608;2959.2306000439826;10179.643712470028;10219.79560408795;10390.584376624925;10588.417637029961;10923.071538919201;1856.2853301478892;1903.4289713086157;1918.3285849952406;1926.1621194780175;1932.3623204342534;135787.32106339405;138454;138954.30522847423;141694;163196.3680363193;10922.399268040175;12182.84262995014;12326.198797078161;12417.62747117588;12563.434645440977;15857.969231999883;16254.918725406535;16366.672666546841;16369.42707005249;16400.425985090464;9107.95446022779;9270.212031977222;9311.255099592016;9394.765130871783;9442.527873606292;7995.920550725577;9038.502882341534;9222.446653200695;9291.907080929239;9438.622455101719;4891.559759621651;4906.362172917497;4996.250562415594;5011.273365362002;5028.823991160292;2051.271798511537;2080.1887263967064;2081.6149012432447;2084.842912183722;2090.6968489568926;1007.6322142417964;1021.5096753558186;1045.8954104589632;1060.7984482948216;1096.2271598523023;22010.186319386317;22596;23203.955821988053;23531.294061178192;24111.51776964451;1090.1061129873585;1112.7385064509995;1118.972025699364;1127.5546159267044;1131.0725205331548;11318.886811131948;11350.48922798476;11402.486888090056;11847.703807404712;12076.698082548006;685098;726486;772549;782568;795960;150246;153759;158021.2098939521;166146;168968;902.0077914294355;982.9580644516835;1001.9098281154585;1024.416082832778;1120.7534342444812;20310.390688279374;20740;21183.268107140473;21224.893875530524;21251.787482124982;54868.72565637146;57667.711661441426;61566;61954;66003.33996660163;70366;70808.64595676989;87828.5608571953;89491;91044;5863.120531920246;6892.724291028303;8746.300295976434;8938.731838044869;8945.686900958435;11803.645890623295;12066.879331206575;12233.632991010283;13674.658133546343;14032.22822744759;11288.830667540158;11482.827757583476;11737.47181376823;11781.76436471283;11829.881701182878;9511.334206605472;11475.655730328104;11577.131715121568;11884.524619015488;11914.40427978615;9504.85941687002;11673.533058677558;11970.820437693616;12048.939755301168;12062.155649104478;8512.659493620185;8518.531480768617;8522.360822938428;8816.691415800417;8843.778905527413;9559.761005974906;9641.180499657608;11687.590934317599;11700.180987330805;11942.208668699765 16:48:26 INFO - PROCESS | 1645 | |3;mootools.html;1328.3889410871;1438.0591147134019;1475.6827282134338;1487.6578386970996;1495.8504149585042;7761.961190194041;8030.397720170981;8204.712835050774;8650.653973841047;8652.394332396734;8195.385346099036;8447.873281900776;8473.02560205575;8592.699255526042;8604.698835540741;2393.2222027840976;2432.0879670123704;2444.364465239033;2449.938751531217;2453.3621258472836;1992.9835783750318;2020.55547779489;2408.084927727461;2440.5728997425467;2472.4931389065287;1920.9135588898464;2099.9080478151354;2373.76262373763;2390.486045500217;2404.6873906392143;2376.738558758535;2382.0828980842325;2396.9760302396926;2397.6763136976547;2414.2515820095737;2396.125414223813;2406.6871306730186;2409.180878501318;2414.2395145529104;2452.3623857796924;8663.176998185163;8693.087225841322;8701.782455438664;8733.606987685569;8736.825263494695;5011.799528018875;5327.041132596131;5353.866153346158;5370.033393989081;5373.274607927958;3372.426687463137;3595.7842529448317;3648.3615367310613;3654.5979942206336;3670.229251857113;1135.6025391113126;1153.9423028848523;1279.3027799849106;1321.9471221151239;1328.8139660447546;11134.27627204229;11136.61021864239;11163.106951443864;11178.776424471547;11179.049780768726;4641.3296571611945;4747.169245382044;4828.37231159954;4857.927131093036;4878.756062196876;9719.854202186973;9747.9025209747;9748.951255243821;9770.609175633037;9818.26363022776;5405.864853378697;5438.700871452029;5440.537554307849;5447.863803404946;5457.317835270591;4454.888627784267;4515.909681806345;4886.95113048865;4912.361393018885;4954.479779623088;3904.2972264992004;4042.7776472293717;4052.513698356157;4056.472658554369;4140.544540100586;1314.4479318685983;1319.9868001320056;1322.418136020148;1323.7815760399426;1329.2556168545646;932.6455946740195;936.6674830435097;949.8005418861978;966.4877614864133;1003.1623594298842;5651.9152212716845;5845.532357411397;5879.853003674857;6008.008678568075;6111.480524155408;586.2320360327899;589.1222079102107;593.1429084972174;599.5713065158433;602.7016626769777;3890.9416358754643;3901.648851603369;3950.150717595731;3975.741576797499;4010.258102251093;9870.21038316933;10229.43738094412;10244;10245.84631230532;10258.794824103476;10171.135453486537;10186.592536298613;10188.847167292497;10197.541110650038;10213.591456341663;7532.585707786015;7809.68761249555;7895.447318687751;7985.640646171052;8026.237507436784;5885.205497257815;5956.285245770535;5995.640261584319;6024.156618073385;6154.5384096192065;9426.434413935185;9428.481433521221;9537.52312384392;9540.52297385114;9569.13877750978;9441.103095205945;9473.24214062887;9522.476263805556;9588.568514417007;9690.418574885529;6867.759628412981;7068.081149450539;7227.2772722729405;7300.56196628204;7490.625468726651;8062.193780621891;8261.958690206511;8269.255766981001;8283;8299.004119505698;7188.640567971685;7285.635718214174;7342.8898566520475;7382.187959324363;7449.6275186241555;7165.856682866418;7207.207207207098;7264.818379540554;7339.082614673166;7416.44376671741;6505.674716264262;6633.038209459698;6642.76750313756;6643.2360278568585;6660.400563949268;4818.855434336975;4864.537868902448;4919.262110683426;4943.9505604944925;4951.034548263054;5057.165567681292;5066.847994560169;5146.794128234828;5219.556337711337;5337.172738225581 16:48:26 INFO - PROCESS | 1645 | |4;prototype.html;342.5392846621295;374.561762737597;381.57645014034415;381.86634677862736;388.0821856309828;4787.760611969401;4945.357103576539;4967.752571551317;5305.363356397228;5561.777528898844;5196.272521846944;5286.894262114755;5570.6657600543895;5602.24369710089;5611.438856114387;3481.354170830162;4356.542563030884;4362.498312694049;4367.366731823884;4374.825006999717;3440.6731360520835;4135.421041054255;4360.912781744363;4364.978175109136;4377.190219809346;4127.195196936598;4142.668586513072;4144.958550414487;4164.437800896885;4198.349255865346;3593.119685677013;3860.903477413059;4117.888170194061;4161.438205842233;4172.3310675729645;3588.5693716753894;3646.5259516262718;3647.726420518445;3649.7810131391934;3656.926861462756;4872.366592343008;4973.383650313663;5278.9208161877605;5289.735513224324;5427.810026649048;4062.370332598452;4233.767142807144;4316.482022157361;4843.539863712942;4881.755912204376;5098.59211263098;5497.697626630534;5739.540836733051;5856.590038697293;5884.6469211847425;3283.819389933553;4001.799910004488;4012.799360031987;4019.256437559032;4031.012401961538;5268.736563171864;5280.442269530459;6150.8769824603705;6194.845128871769;6214.830436477384;4071.3892916062828;4090.3638544582404;4094.324436468009;4104.835806567764;4108.938365924513;3957.88126356204;4111.7944102794745;4112.642827866796;4140.91718165641;4149.97925010373;3910.511186101737;4814.133455977888;4825.5174482551465;4833.752305178109;4899.58353539946;5239.633225674243;5721.456461636138;5733.8566535836935;5810.576408779876;6041.758329666764;1134.3704244144415;1153.5847095045776;1196.4077781498213;1228.8402507674125;1242.428482897877;854.9441439821815;874.7098030405101;905.1943770044666;913.6299798581585;916.9633214671472;4787.563730880722;5292.967871265067;5348.827914226909;5357.812476563302;5380.488853558905;4800.231962885921;5500.3949565547755;5519;5823.359430462646;5875.266796295104;4639.9768001160455;4915.778789954461;4922.975385123052;4942.876428089255;5317.308749862493;2555.961660575093;2567.525007873512;2597.545429549821;3016.9547456788164;3039.6656367799524;5725.366331487562;5767.394423585463;5850.795222167204;5862.824115276549;5913.2608423947;5121.103806833789;5368.3158420789605;5485.862853428616;5514.628709654887;5943;5419.647722897999;5461.061323230276;5920.881582368328;5936.881262374728;5977.432143946318;4464.437446893561;4711.528847115261;5366.39008297517;5565.465929134947;5735.44402470471;4764.856434455775;5130.743462826918;5150.4592017837585;5631.267935168402;5727;4962.022786328174;5528.612997090326;5893.5874488785375;6002.0996850472775;6004.8799024020145;3770.3024940386117;3820.2741479118877;3827.4929920901855;3832.8884623458885;3859.286032084073;5731.398203188772;5802.100674395476;6012.248468941382;6069.51443884497;6077.817665470043;4958.479359667327;5697.772089116389;5783.334916484658;5869.918292896264;5873.735681894411;4926.8768280793265;5172.818951336685;5852.736626851887;5860.38465961068;5866.3547009828035;4857.878553036202;5553.389127196086;5768.807853015827;5793.362730099601;5841.386654401226;5285.2822832578795;5982.602786951674;5992.310884248367;6010.669413182142;6027.517798576016;5217.81737639196;5410.540104091197;5588.6646801192055;5853.502452291603;5877.001819381431 16:48:26 INFO - PROCESS | 1645 | |5;yui.html;728.963551822409;731.6268702961488;781.8905353250545;868.7046404222565;878.5431575580698;15179.924100379496;15904.84095159048;16162.434314798955;16282.267297971604;16416;14951.700965980674;15095.245237738096;15157.317920693567;15183.240837958085;15189.848101518981;883.3683916000059;892.4199270474211;896.9416987895765;898.640543782489;900.6217388696763;701.6947627781906;719.0293104309167;870.1863757386832;880.8986966498838;887.3167660901103;940.1022023967129;942.5428667096487;942.575840871607;942.7171848445439;945.5130607737021;939.3565407695751;942.2320808541017;942.5004747483845;944.2823454174808;944.5607792376509;701.242657929435;701.5860642221063;703.7220297982319;703.7677566403074;704.246456291768;15176.937614366998;15297.158656273901;15482.30329635169;16296.185190740534;16588.170591470498;15014.099154050682;15027.323770430357;15113.622159445991;15116.773248401283;15138.167400792952;10921.945390273077;11908.225965312313;11913.22564033335;11971.820422693667;12545.498180072786;3301.4882693182353;3914.451976723261;3916.255911376858;3933.5279766427925;4061.451704019949;104346;109576;111592;112252;113063.43468282688;11465.140114491447;11474;11523.48144333507;11528.538858445569;11963.40182990847;23952.52094958126;24219.273421797374;24368.025278988996;24384.39039024003;24395.02419903184;8121.147279535564;8127.309178719756;8161.347092232607;8195.590220489072;8365.916340836635;3961.187956468917;4355.2813785725;4439.4228750262255;4524.615407690383;4579.313103034505;3717.70258379329;3777.867774627875;3793.412021136728;3816.885493435201;3822.7132965027736;689.8447849233883;696.7352406085756;700.4641449291296;714.2785786355818;715.2632788228134;697.5640224859463;709.6097146569366;711.9572825630479;720.5748608321115;738.2138023005589;8893;10428.270021098602;10475.161987041018;10592.152627789912;10927.562897484171;591.8757061017235;611.3031144495279;628.5725706519611;668.6589839182052;737.2516895351223;5106.310648062538;5196.740162991911;5321.281626980385;5326.041312563698;5328.8401347959625;72271;82378.35243295101;91420.54289728642;101324;102447.97552024534;64234.07297781074;66461;75187.62406188043;86861.5656921724;88443;61904.38095619076;63112.368876311564;63314.467421303554;71515.6424217886;72022.63988680128;9966.551505182138;10041.196704263788;10067.748306292402;10093.19254459656;10116.08955194036;51695.48304517057;51851;52557;53126.734366327924;54182;46147.76926115348;67370.66314668395;68717;69243;70493.647531762;12424.378781061092;12505.437255323714;12627.11610187278;12743.299118548568;12744.235345879277;13179.538716144889;13192;13212.141210821266;13451.192928424325;14015.50945716895;12061.51753929833;12557.874421256036;12845.807312890127;13647.249401283025;14770.778438323649;12611;13947.651308717363;14311.856881431053;14402.991790574559;14741.484048058266;12975.805362919753;13639.181649101085;14351.354189061309;14396.784048239075;14484.420623175376;9308.394954327947;10848.837267440837;11375.544978201111;11551.306921584732;11902.583409580624;9397.248220142235;10460.476976151314;11197.384143872165;11435.371054592077;11598.130140239347 16:48:26 INFO - PROCESS | 1645 | __end_tp_report 16:48:26 INFO - PROCESS | 1645 | __start_cc_report 16:48:26 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,381 16:48:26 INFO - PROCESS | 1645 | __end_cc_report 16:48:26 INFO - PROCESS | 1645 | __startTimestamp1490399306488__endTimestamp 16:48:26 INFO - PROCESS | 1645 | 16:48:26 INFO - PROCESS | 1645 | ------- Summary: start ------- 16:48:26 INFO - PROCESS | 1645 | Number of tests: 6 16:48:26 INFO - PROCESS | 1645 | 16:48:26 INFO - PROCESS | 1645 | [#0] dojo.html Cycles:165 Average:5912.34 Median:6207.32 stddev:682.73 (11.0%) stddev-sans-first:659.33 16:48:26 INFO - PROCESS | 1645 | Values: 3556.5 3564.7 3588.3 3595.9 3617.3 5335.5 5358.7 5602.9 5673.7 6101.4 5460.9 5700.5 6052.9 6111.7 6128.7 5385.9 5435.3 5438.8 5479.8 5492.5 4546.8 5488.6 5489.9 5510.9 5553.4 4801.9 5353.7 5384.7 5479.2 5502.7 4587.9 5367.0 5397.6 5443.6 5512.8 4615.3 5292.3 5422.9 5429.6 5477.8 4477.6 4832.0 5861.2 6129.8 6144.3 6207.3 6250.6 6272.7 6307.8 6410.6 5605.1 6389.6 6475.7 6476.2 6492.8 6198.6 6205.6 6211.4 6222.4 6226.5 6383.0 6386.5 6464.3 6467.6 6503.0 6277.7 6314.8 6331.7 6336.5 6354.4 6434.9 6437.5 6457.7 6468.6 6470.7 6199.2 6217.9 6240.3 6242.2 6302.7 6304.9 6307.9 6321.6 6337.1 6354.5 5175.0 5209.7 5209.7 5225.6 5244.7 4555.8 4601.4 4601.8 4669.0 4692.4 6151.1 6183.4 6224.4 6236.7 6242.5 6505.3 6508.7 6514.0 6529.9 6537.3 5143.7 6081.2 6112.8 6152.3 6190.6 5692.6 5739.8 5742.9 5747.5 5763.2 6673.9 6680.3 6688.1 6713.0 6725.9 6341.6 6342.7 6364.0 6423.3 6482.9 5597.6 6077.4 6671.8 6707.8 6726.0 6407.8 6421.7 6423.6 6444.3 6702.0 6266.1 6317.3 6329.3 6466.1 6487.1 5739.6 5787.3 6159.8 6303.8 6319.1 6273.6 6304.7 6317.1 6333.9 6338.7 6101.7 6137.7 6188.9 6225.3 6297.0 6170.5 6173.1 6221.2 6281.4 6302.9 5260.8 6232.8 6241.5 6248.8 6253.2 16:48:26 INFO - PROCESS | 1645 | 16:48:26 INFO - PROCESS | 1645 | [#1] ext.html Cycles:180 Average:72016.60 Median:39251.91 stddev:152746.72 (389.1%) stddev-sans-first:153080.73 16:48:26 INFO - PROCESS | 1645 | Values: 458.4 470.0 491.9 521.7 534.5 32378.8 43134.1 43143.9 43149.6 43442.6 44675.1 45105.5 45117.6 45208.6 45229.0 9511.0 9756.1 9790.0 11386.0 11821.8 11646.5 11702.2 11764.9 11771.1 11772.7 11676.0 11725.6 11773.9 11790.0 11804.1 11684.2 11691.5 11762.1 11767.6 11767.6 11725.7 11765.2 11831.1 11832.1 11832.8 38863.6 39112.8 39173.4 39330.4 39424.6 4062.7 4068.5 4085.0 4142.9 4153.2 29349.7 29419.3 29532.9 30718.0 31262.5 3201.2 3359.3 3377.5 3523.9 3525.0 468597.0 469165.0 469220.0 471770.6 473262.0 62074.0 62382.0 62531.1 62636.0 62655.7 55630.7 62655.7 62789.1 64927.4 65687.7 15173.7 17290.4 17388.9 17409.7 17453.6 12216.8 12542.2 13122.1 13371.9 13418.8 18007.8 19261.4 19287.6 19437.7 19511.7 6903.3 7171.8 7774.4 8220.4 8275.0 736.9 766.7 905.8 924.9 945.6 31662.5 32208.4 32316.0 32410.4 32580.3 933.4 1050.0 1104.4 1162.3 1275.1 15307.9 16071.6 16743.4 18018.1 18050.5 581861.0 653353.0 654198.0 655541.0 658055.0 68613.0 68943.7 68994.3 69256.3 69391.0 1420.6 1467.2 1531.8 1547.4 1574.3 32243.0 33467.3 33555.0 33760.0 33809.6 48357.8 58844.5 58854.4 58858.4 58942.7 523521.0 607524.0 609181.0 611182.9 611225.0 38381.0 39046.6 45628.8 47006.0 47069.5 43358.1 49737.0 50084.0 51040.6 51749.7 38646.2 40022.2 42219.0 42506.1 43300.4 41792.8 42159.0 42230.8 42450.6 42459.4 37239.4 37969.9 38080.2 41129.8 41372.0 32690.3 34665.4 34772.8 34778.1 34948.0 23521.4 24753.9 25886.6 26188.7 26531.9 16:48:26 INFO - PROCESS | 1645 | 16:48:26 INFO - PROCESS | 1645 | [#2] jquery.html Cycles:180 Average:41528.40 Median:23015.10 stddev:125828.23 (546.7%) stddev-sans-first:126143.31 16:48:26 INFO - PROCESS | 1645 | Values: 396.4 397.8 397.9 409.7 424.8 21139.2 21664.1 22146.8 22211.0 22342.3 22513.7 22995.4 23034.8 23179.8 23355.3 5899.5 6211.0 6214.3 6227.5 6235.7 4927.8 6150.9 6163.6 6176.8 6219.6 6160.3 6243.5 6252.5 6266.7 6272.4 5846.3 6127.9 6173.0 6185.4 6247.7 5943.4 6029.8 6138.4 6150.7 6153.2 13704.2 13827.2 14002.9 26825.7 27012.6 2686.1 2724.1 2816.6 2913.5 2959.2 10179.6 10219.8 10390.6 10588.4 10923.1 1856.3 1903.4 1918.3 1926.2 1932.4 135787.3 138454.0 138954.3 141694.0 163196.4 10922.4 12182.8 12326.2 12417.6 12563.4 15858.0 16254.9 16366.7 16369.4 16400.4 9108.0 9270.2 9311.3 9394.8 9442.5 7995.9 9038.5 9222.4 9291.9 9438.6 4891.6 4906.4 4996.3 5011.3 5028.8 2051.3 2080.2 2081.6 2084.8 2090.7 1007.6 1021.5 1045.9 1060.8 1096.2 22010.2 22596.0 23204.0 23531.3 24111.5 1090.1 1112.7 1119.0 1127.6 1131.1 11318.9 11350.5 11402.5 11847.7 12076.7 685098.0 726486.0 772549.0 782568.0 795960.0 150246.0 153759.0 158021.2 166146.0 168968.0 902.0 983.0 1001.9 1024.4 1120.8 20310.4 20740.0 21183.3 21224.9 21251.8 54868.7 57667.7 61566.0 61954.0 66003.3 70366.0 70808.6 87828.6 89491.0 91044.0 5863.1 6892.7 8746.3 8938.7 8945.7 11803.6 12066.9 12233.6 13674.7 14032.2 11288.8 11482.8 11737.5 11781.8 11829.9 9511.3 11475.7 11577.1 11884.5 11914.4 9504.9 11673.5 11970.8 12048.9 12062.2 8512.7 8518.5 8522.4 8816.7 8843.8 9559.8 9641.2 11687.6 11700.2 11942.2 16:48:26 INFO - PROCESS | 1645 | 16:48:26 INFO - PROCESS | 1645 | [#3] mootools.html Cycles:180 Average:5593.54 Median:5332.11 stddev:3051.75 (57.2%) stddev-sans-first:3043.47 16:48:26 INFO - PROCESS | 1645 | Values: 1328.4 1438.1 1475.7 1487.7 1495.9 7762.0 8030.4 8204.7 8650.7 8652.4 8195.4 8447.9 8473.0 8592.7 8604.7 2393.2 2432.1 2444.4 2449.9 2453.4 1993.0 2020.6 2408.1 2440.6 2472.5 1920.9 2099.9 2373.8 2390.5 2404.7 2376.7 2382.1 2397.0 2397.7 2414.3 2396.1 2406.7 2409.2 2414.2 2452.4 8663.2 8693.1 8701.8 8733.6 8736.8 5011.8 5327.0 5353.9 5370.0 5373.3 3372.4 3595.8 3648.4 3654.6 3670.2 1135.6 1153.9 1279.3 1321.9 1328.8 11134.3 11136.6 11163.1 11178.8 11179.0 4641.3 4747.2 4828.4 4857.9 4878.8 9719.9 9747.9 9749.0 9770.6 9818.3 5405.9 5438.7 5440.5 5447.9 5457.3 4454.9 4515.9 4887.0 4912.4 4954.5 3904.3 4042.8 4052.5 4056.5 4140.5 1314.4 1320.0 1322.4 1323.8 1329.3 932.6 936.7 949.8 966.5 1003.2 5651.9 5845.5 5879.9 6008.0 6111.5 586.2 589.1 593.1 599.6 602.7 3890.9 3901.6 3950.2 3975.7 4010.3 9870.2 10229.4 10244.0 10245.8 10258.8 10171.1 10186.6 10188.8 10197.5 10213.6 7532.6 7809.7 7895.4 7985.6 8026.2 5885.2 5956.3 5995.6 6024.2 6154.5 9426.4 9428.5 9537.5 9540.5 9569.1 9441.1 9473.2 9522.5 9588.6 9690.4 6867.8 7068.1 7227.3 7300.6 7490.6 8062.2 8262.0 8269.3 8283.0 8299.0 7188.6 7285.6 7342.9 7382.2 7449.6 7165.9 7207.2 7264.8 7339.1 7416.4 6505.7 6633.0 6642.8 6643.2 6660.4 4818.9 4864.5 4919.3 4944.0 4951.0 5057.2 5066.8 5146.8 5219.6 5337.2 16:48:26 INFO - PROCESS | 1645 | 16:48:26 INFO - PROCESS | 1645 | [#4] prototype.html Cycles:180 Average:4616.36 Median:5140.60 stddev:1418.81 (27.6%) stddev-sans-first:1386.05 16:48:26 INFO - PROCESS | 1645 | Values: 342.5 374.6 381.6 381.9 388.1 4787.8 4945.4 4967.8 5305.4 5561.8 5196.3 5286.9 5570.7 5602.2 5611.4 3481.4 4356.5 4362.5 4367.4 4374.8 3440.7 4135.4 4360.9 4365.0 4377.2 4127.2 4142.7 4145.0 4164.4 4198.3 3593.1 3860.9 4117.9 4161.4 4172.3 3588.6 3646.5 3647.7 3649.8 3656.9 4872.4 4973.4 5278.9 5289.7 5427.8 4062.4 4233.8 4316.5 4843.5 4881.8 5098.6 5497.7 5739.5 5856.6 5884.6 3283.8 4001.8 4012.8 4019.3 4031.0 5268.7 5280.4 6150.9 6194.8 6214.8 4071.4 4090.4 4094.3 4104.8 4108.9 3957.9 4111.8 4112.6 4140.9 4150.0 3910.5 4814.1 4825.5 4833.8 4899.6 5239.6 5721.5 5733.9 5810.6 6041.8 1134.4 1153.6 1196.4 1228.8 1242.4 854.9 874.7 905.2 913.6 917.0 4787.6 5293.0 5348.8 5357.8 5380.5 4800.2 5500.4 5519.0 5823.4 5875.3 4640.0 4915.8 4923.0 4942.9 5317.3 2556.0 2567.5 2597.5 3017.0 3039.7 5725.4 5767.4 5850.8 5862.8 5913.3 5121.1 5368.3 5485.9 5514.6 5943.0 5419.6 5461.1 5920.9 5936.9 5977.4 4464.4 4711.5 5366.4 5565.5 5735.4 4764.9 5130.7 5150.5 5631.3 5727.0 4962.0 5528.6 5893.6 6002.1 6004.9 3770.3 3820.3 3827.5 3832.9 3859.3 5731.4 5802.1 6012.2 6069.5 6077.8 4958.5 5697.8 5783.3 5869.9 5873.7 4926.9 5172.8 5852.7 5860.4 5866.4 4857.9 5553.4 5768.8 5793.4 5841.4 5285.3 5982.6 5992.3 6010.7 6027.5 5217.8 5410.5 5588.7 5853.5 5877.0 16:48:26 INFO - PROCESS | 1645 | 16:48:26 INFO - PROCESS | 1645 | [#5] yui.html Cycles:180 Average:19760.54 Median:3924.89 stddev:27455.05 (699.5%) stddev-sans-first:27494.88 16:48:26 INFO - PROCESS | 1645 | Values: 729.0 731.6 781.9 868.7 878.5 15179.9 15904.8 16162.4 16282.3 16416.0 14951.7 15095.2 15157.3 15183.2 15189.8 883.4 892.4 896.9 898.6 900.6 701.7 719.0 870.2 880.9 887.3 940.1 942.5 942.6 942.7 945.5 939.4 942.2 942.5 944.3 944.6 701.2 701.6 703.7 703.8 704.2 15176.9 15297.2 15482.3 16296.2 16588.2 15014.1 15027.3 15113.6 15116.8 15138.2 10921.9 11908.2 11913.2 11971.8 12545.5 3301.5 3914.5 3916.3 3933.5 4061.5 104346.0 109576.0 111592.0 112252.0 113063.4 11465.1 11474.0 11523.5 11528.5 11963.4 23952.5 24219.3 24368.0 24384.4 24395.0 8121.1 8127.3 8161.3 8195.6 8365.9 3961.2 4355.3 4439.4 4524.6 4579.3 3717.7 3777.9 3793.4 3816.9 3822.7 689.8 696.7 700.5 714.3 715.3 697.6 709.6 712.0 720.6 738.2 8893.0 10428.3 10475.2 10592.2 10927.6 591.9 611.3 628.6 668.7 737.3 5106.3 5196.7 5321.3 5326.0 5328.8 72271.0 82378.4 91420.5 101324.0 102448.0 64234.1 66461.0 75187.6 86861.6 88443.0 61904.4 63112.4 63314.5 71515.6 72022.6 9966.6 10041.2 10067.7 10093.2 10116.1 51695.5 51851.0 52557.0 53126.7 54182.0 46147.8 67370.7 68717.0 69243.0 70493.6 12424.4 12505.4 12627.1 12743.3 12744.2 13179.5 13192.0 13212.1 13451.2 14015.5 12061.5 12557.9 12845.8 13647.2 14770.8 12611.0 13947.7 14311.9 14403.0 14741.5 12975.8 13639.2 14351.4 14396.8 14484.4 9308.4 10848.8 11375.5 11551.3 11902.6 9397.2 10460.5 11197.4 11435.4 11598.1 16:48:26 INFO - PROCESS | 1645 | -------- Summary: end -------- 16:48:26 INFO - PROCESS | 1645 | 16:48:27 INFO - TEST-INFO | 1645: exit 0 16:48:27 INFO - TEST-OK | dromaeo_css | took 1114018ms 16:48:27 INFO - TEST-START | kraken 16:48:27 INFO - operating with platform_type : mac_ 16:48:27 INFO - Initialising browser for kraken test... 16:48:27 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmp3HRO30/profile http://localhost:49240/getInfo.html) 16:48:29 INFO - PROCESS | 1654 | 2017-03-24 16:48:29.783 plugin-container[1656:10699] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x884b, name = 'com.apple.tsm.portname' 16:48:29 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:48:29 INFO - PROCESS | 1654 | 2017-03-24 16:48:29.784 plugin-container[1656:10699] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x430f, name = 'com.apple.CFPasteboardClient' 16:48:29 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:48:29 INFO - PROCESS | 1654 | 2017-03-24 16:48:29.784 plugin-container[1656:10699] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:48:29 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 16:48:29 INFO - PROCESS | 1654 | colorDepth:24 16:48:29 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 16:48:29 INFO - PROCESS | 1654 | __metrics 16:48:29 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 16:48:30 INFO - TEST-INFO | 1654: exit 0 16:48:30 INFO - Browser initialized. 16:48:30 INFO - Running cycle 1/1 for kraken test... 16:48:30 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmp3HRO30/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:48:33 INFO - PROCESS | 1657 | 2017-03-24 16:48:33.024 plugin-container[1658:10822] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8537, name = 'com.apple.tsm.portname' 16:48:33 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:48:33 INFO - PROCESS | 1657 | 2017-03-24 16:48:33.024 plugin-container[1658:10822] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4427, name = 'com.apple.CFPasteboardClient' 16:48:33 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:48:33 INFO - PROCESS | 1657 | 2017-03-24 16:48:33.024 plugin-container[1658:10822] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:48:42 INFO - PROCESS | 1657 | RSS: Main: 172797952 16:48:42 INFO - PROCESS | 1657 | 16:48:45 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 16:48:46 INFO - PROCESS | 1657 | RSS: Main: 185516032 16:48:46 INFO - PROCESS | 1657 | 16:48:49 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 16:48:49 INFO - PROCESS | 1657 | RSS: Main: 193605632 16:48:49 INFO - PROCESS | 1657 | 16:48:52 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 16:48:53 INFO - PROCESS | 1657 | RSS: Main: 197128192 16:48:53 INFO - PROCESS | 1657 | 16:48:55 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 16:48:56 INFO - PROCESS | 1657 | RSS: Main: 198684672 16:48:56 INFO - PROCESS | 1657 | 16:48:58 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:48:58 INFO - PROCESS | 1657 | RSS: Main: 198918144 16:48:58 INFO - PROCESS | 1657 | 16:49:01 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 16:49:02 INFO - PROCESS | 1657 | RSS: Main: 203837440 16:49:02 INFO - PROCESS | 1657 | 16:49:05 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 16:49:06 INFO - PROCESS | 1657 | RSS: Main: 202694656 16:49:06 INFO - PROCESS | 1657 | 16:49:09 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 16:49:10 INFO - PROCESS | 1657 | RSS: Main: 202969088 16:49:10 INFO - PROCESS | 1657 | 16:49:11 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:49:12 INFO - PROCESS | 1657 | RSS: Main: 201695232 16:49:12 INFO - PROCESS | 1657 | 16:49:14 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:49:14 INFO - PROCESS | 1657 | RSS: Main: 201764864 16:49:14 INFO - PROCESS | 1657 | 16:49:16 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:49:17 INFO - PROCESS | 1657 | RSS: Main: 201719808 16:49:17 INFO - PROCESS | 1657 | 16:49:20 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:49:21 INFO - PROCESS | 1657 | RSS: Main: 201998336 16:49:21 INFO - PROCESS | 1657 | 16:49:23 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:49:24 INFO - PROCESS | 1657 | RSS: Main: 201924608 16:49:24 INFO - PROCESS | 1657 | 16:49:26 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 16:49:26 INFO - PROCESS | 1657 | RSS: Main: 203776000 16:49:26 INFO - PROCESS | 1657 | 16:49:26 INFO - PROCESS | 1657 | __start_tp_report 16:49:26 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 16:49:26 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 16:49:26 INFO - PROCESS | 1657 | |i|pagename|runs| 16:49:26 INFO - PROCESS | 1657 | |0;ai-astar;107;150;121;118;118;117;118;118;118;118 16:49:26 INFO - PROCESS | 1657 | |1;audio-beat-detection;139;110;110;143;109;128;138;128;126;111 16:49:26 INFO - PROCESS | 1657 | |2;audio-dft;193;199;194;200;193;195;197;193;193;193 16:49:26 INFO - PROCESS | 1657 | |3;audio-fft;76;59;57;87;58;59;59;59;72;60 16:49:26 INFO - PROCESS | 1657 | |4;audio-oscillator;85;70;82;96;53;64;97;95;98;106 16:49:26 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;135;86;89;85;89;102;133;111;89;107 16:49:26 INFO - PROCESS | 1657 | |6;imaging-darkroom;115;169;157;101;122;173;159;98;133;113 16:49:26 INFO - PROCESS | 1657 | |7;imaging-desaturate;92;98;107;92;105;145;88;142;145;118 16:49:26 INFO - PROCESS | 1657 | |8;json-parse-financial;56;59;58;59;61;60;61;61;61;61 16:49:26 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;67;74;70;82;79;98;70;71;77;72 16:49:26 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;80;86;83;80;82;84;79;88;89;91 16:49:26 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;136;135;133;131;132;132;146;157;194;135 16:49:26 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;165;167;164;174;165;165;163;164;166;191 16:49:26 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;65;67;64;66;68;67;65;63;64;66 16:49:26 INFO - PROCESS | 1657 | __end_tp_report 16:49:26 INFO - PROCESS | 1657 | __start_cc_report 16:49:26 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,926 16:49:26 INFO - PROCESS | 1657 | __end_cc_report 16:49:26 INFO - PROCESS | 1657 | __startTimestamp1490399366456__endTimestamp 16:49:26 INFO - PROCESS | 1657 | 16:49:26 INFO - PROCESS | 1657 | ------- Summary: start ------- 16:49:26 INFO - PROCESS | 1657 | Number of tests: 14 16:49:26 INFO - PROCESS | 1657 | 16:49:26 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:120.30 Median:118.00 stddev:11.07 (9.4%) stddev-sans-first:10.64 16:49:26 INFO - PROCESS | 1657 | Values: 107.0 150.0 121.0 118.0 118.0 117.0 118.0 118.0 118.0 118.0 16:49:26 INFO - PROCESS | 1657 | 16:49:26 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:124.20 Median:128.00 stddev:13.35 (10.4%) stddev-sans-first:13.04 16:49:26 INFO - PROCESS | 1657 | Values: 139.0 110.0 110.0 143.0 109.0 128.0 138.0 128.0 126.0 111.0 16:49:26 INFO - PROCESS | 1657 | 16:49:26 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:195.00 Median:194.50 stddev:2.71 (1.4%) stddev-sans-first:2.77 16:49:26 INFO - PROCESS | 1657 | Values: 193.0 199.0 194.0 200.0 193.0 195.0 197.0 193.0 193.0 193.0 16:49:26 INFO - PROCESS | 1657 | 16:49:26 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:64.60 Median:59.50 stddev:10.19 (17.1%) stddev-sans-first:9.94 16:49:26 INFO - PROCESS | 1657 | Values: 76.0 59.0 57.0 87.0 58.0 59.0 59.0 59.0 72.0 60.0 16:49:26 INFO - PROCESS | 1657 | 16:49:26 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:84.60 Median:90.00 stddev:17.23 (19.1%) stddev-sans-first:18.28 16:49:26 INFO - PROCESS | 1657 | Values: 85.0 70.0 82.0 96.0 53.0 64.0 97.0 95.0 98.0 106.0 16:49:26 INFO - PROCESS | 1657 | 16:49:26 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:102.60 Median:85.50 stddev:18.87 (22.1%) stddev-sans-first:15.96 16:49:26 INFO - PROCESS | 1657 | Values: 135.0 86.0 89.0 85.0 89.0 102.0 133.0 111.0 89.0 107.0 16:49:26 INFO - PROCESS | 1657 | 16:49:26 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:134.00 Median:158.00 stddev:28.35 (17.9%) stddev-sans-first:29.22 16:49:26 INFO - PROCESS | 1657 | Values: 115.0 169.0 157.0 101.0 122.0 173.0 159.0 98.0 133.0 113.0 16:49:26 INFO - PROCESS | 1657 | 16:49:26 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:113.20 Median:116.50 stddev:22.96 (19.7%) stddev-sans-first:23.04 16:49:26 INFO - PROCESS | 1657 | Values: 92.0 98.0 107.0 92.0 105.0 145.0 88.0 142.0 145.0 118.0 16:49:26 INFO - PROCESS | 1657 | 16:49:26 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:59.70 Median:61.00 stddev:1.70 (2.8%) stddev-sans-first:1.17 16:49:26 INFO - PROCESS | 1657 | Values: 56.0 59.0 58.0 59.0 61.0 60.0 61.0 61.0 61.0 61.0 16:49:26 INFO - PROCESS | 1657 | 16:49:26 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:76.00 Median:75.50 stddev:8.99 (11.9%) stddev-sans-first:8.93 16:49:26 INFO - PROCESS | 1657 | Values: 67.0 74.0 70.0 82.0 79.0 98.0 70.0 71.0 77.0 72.0 16:49:26 INFO - PROCESS | 1657 | 16:49:26 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:85.00 stddev:4.16 (4.9%) stddev-sans-first:4.12 16:49:26 INFO - PROCESS | 1657 | Values: 80.0 86.0 83.0 80.0 82.0 84.0 79.0 88.0 89.0 91.0 16:49:26 INFO - PROCESS | 1657 | 16:49:26 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:143.10 Median:135.50 stddev:19.63 (14.5%) stddev-sans-first:20.65 16:49:26 INFO - PROCESS | 1657 | Values: 136.0 135.0 133.0 131.0 132.0 132.0 146.0 157.0 194.0 135.0 16:49:26 INFO - PROCESS | 1657 | 16:49:26 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.40 Median:165.50 stddev:8.51 (5.1%) stddev-sans-first:8.94 16:49:26 INFO - PROCESS | 1657 | Values: 165.0 167.0 164.0 174.0 165.0 165.0 163.0 164.0 166.0 191.0 16:49:26 INFO - PROCESS | 1657 | 16:49:26 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:66.00 stddev:1.58 (2.4%) stddev-sans-first:1.67 16:49:26 INFO - PROCESS | 1657 | Values: 65.0 67.0 64.0 66.0 68.0 67.0 65.0 63.0 64.0 66.0 16:49:26 INFO - PROCESS | 1657 | -------- Summary: end -------- 16:49:26 INFO - PROCESS | 1657 | 16:49:27 INFO - TEST-INFO | 1657: exit 0 16:49:27 INFO - TEST-OK | kraken | took 60214ms 16:49:27 INFO - SUITE-END | took 1174s 16:49:27 INFO - Completed test suite (00:19:34) 16:49:27 INFO - javascript benchmark 16:49:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7549.59874740571, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3556.5020897074396, 3564.714822814175, 3588.264405796812, 3595.928081438373, 3617.2765446910607, 5335.4664533546675, 5358.713908661913, 5602.915956260655, 5673.6879471629045, 6101.420365065323, 5460.890782184354, 5700.543956483481, 6052.878942421149, 6111.694415279229, 6128.662923539204, 5385.919211211835, 5435.2934118564535, 5438.782448702047, 5479.808206712766, 5492.47821456963, 4546.8408605698805, 5488.643238189525, 5489.862753431156, 5510.889782204354, 5553.416891226424, 4801.855944321676, 5353.678779273256, 5384.703841288728, 5479.205515200293, 5502.669839809604, 4587.931181032287, 5367.0339338918975, 5397.595180361469, 5443.646162999393, 5512.84230311635, 4615.30770384445, 5292.311999440087, 5422.8915421691345, 5429.592780541436, 5477.835664930059, 4477.574630410106, 4831.95168048322, 5861.20873682056, 6129.754809807602, 6144.293406258293, 6207.31719510858, 6250.624962502219, 6272.65500397478, 6307.7699848529555, 6410.647414392207, 5605.134808613047, 6389.616623002588, 6475.676216189172, 6476.222853257639, 6492.772752953624, 6198.597091189058, 6205.596636218631, 6211.378862113843, 6222.439980401831, 6226.470749986212, 6383.0, 6386.521010924199, 6464.288928217892, 6467.579607325509, 6503.0, 6277.686115694197, 6314.842128946813, 6331.715072821734, 6336.461400780893, 6354.3963323484195, 6434.903476447857, 6437.452816510649, 6457.741690332334, 6468.579542329733, 6470.741170353133, 6199.194104766441, 6217.906731399033, 6240.282367527792, 6242.15730876335, 6302.747890084345, 6304.936950630527, 6307.9369206308265, 6321.589096708699, 6337.144485494399, 6354.491640668735, 5175.0, 5209.661372010807, 5209.6874187548865, 5225.581953443715, 5244.685318880879, 4555.772211389417, 4601.378813860086, 4601.815927362868, 4668.97665511677, 4692.366530518405, 6151.0773383992755, 6183.443490085914, 6224.439800417894, 6236.656983865841, 6242.469390101892, 6505.349465053457, 6508.707108180143, 6514.0, 6529.934700652932, 6537.34626537352, 5143.742812859342, 6081.17904082952, 6112.807455516588, 6152.2924863640355, 6190.597611155351, 5692.629979051431, 5739.7704091836695, 5742.942570574324, 5747.453991870766, 5763.164341170507, 6673.866522669617, 6680.265170831205, 6688.097106890507, 6712.95949127886, 6725.8654826903185, 6341.587796793194, 6342.746290148342, 6364.0, 6423.293437721753, 6482.935170648233, 5597.63615365, 6077.392260774064, 6671.833204169935, 6707.765228216989, 6725.9663701681175, 6407.807765767035, 6421.743130274736, 6423.550351475352, 6444.3233460487845, 6701.966490167518, 6266.122742815918, 6317.273513546001, 6329.335419780857, 6466.06242094903, 6487.113234610553, 5739.5695322850115, 5787.334456537552, 6159.784407545893, 6303.842403939938, 6319.146915166394, 6273.62358258506, 6304.747810087545, 6317.147185130132, 6333.936660633335, 6338.714757835816, 6101.694915254308, 6137.714085983886, 6188.8762224754355, 6225.346338634559, 6297.0, 6170.50635949114, 6173.135760993554, 6221.222347206599, 6281.371862813682, 6302.905456418249, 5260.815871444481, 6232.750689972531, 6241.500679945684, 6248.750049998131, 6253.187085678833], "value": 5872.0255073821245, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [458.4086528378392, 469.97650117494123, 491.9311296418502, 521.7208793295588, 534.4521865088283, 32378.838105809467, 43134.13731725363, 43143.92140196493, 43149.568504314906, 43442.56557434425, 44675.10649787002, 45105.54894451055, 45117.646470605854, 45208.64374068775, 45229.0, 9511.048895110485, 9756.07317304855, 9789.95105024474, 11386.0, 11821.7635647287, 11646.534138634444, 11702.180847340689, 11764.882351176508, 11771.058315334752, 11772.705682357924, 11676.0, 11725.648230553097, 11773.941130294379, 11790.0, 11804.114691398137, 11684.182107252453, 11691.532338706442, 11762.059035277158, 11767.58813441534, 11767.646970590897, 11725.706857328634, 11765.235259708177, 11831.112666549958, 11832.053435725122, 11832.763344733057, 38863.61136388628, 39112.80443597792, 39173.41239881404, 39330.41004384936, 39424.60575394238, 4062.6546743526837, 4068.4507591475067, 4085.040015596362, 4142.855000074983, 4153.210889930903, 29349.70650293491, 29419.264518386997, 29532.852335738186, 30718.0, 31262.531062034086, 3201.2157021529642, 3359.2945481448924, 3377.493375993596, 3523.8942831714835, 3525.012996361023, 468597.0, 469165.0, 469220.0, 471770.64114679204, 473262.0, 62074.0, 62382.0, 62531.062034069524, 62636.0, 62655.6867215661, 55630.72184639051, 62655.6867215661, 62789.05816412757, 64927.350726493074, 65687.68624627481, 15173.696526069416, 17290.394836180923, 17388.91305543464, 17409.65180696379, 17453.563660908578, 12216.816747748791, 12542.1847579907, 13122.14706044104, 13371.866281337258, 13418.798718019238, 18007.784318320853, 19261.422157335302, 19287.614247714966, 19437.66494037367, 19511.707324390147, 6903.275156108637, 7171.784846454615, 7774.378049756006, 8220.38347123968, 8274.958625206835, 736.8931504931755, 766.7354762606892, 905.7916679163698, 924.912133347331, 945.5603144537686, 31662.525062124085, 32208.38958052135, 32316.0, 32410.351792964007, 32580.34839303248, 933.4492649336923, 1050.0182329521922, 1104.364990130678, 1162.3026184289358, 1275.0691994843817, 15307.923460382626, 16071.598210044842, 16743.413980510624, 18018.099095045196, 18050.548736281435, 581861.0, 653353.0, 654198.0, 655541.0, 658055.0, 68613.0, 68943.65528172327, 68994.31005689979, 69256.30743692498, 69391.0, 1420.6235347632885, 1467.1637166814812, 1531.8314985351603, 1547.3578464936925, 1574.2679653960734, 32243.0, 33467.33065338728, 33555.0, 33759.98720038403, 33809.64468268303, 48357.79105522249, 58844.52888677646, 58854.411455884896, 58858.41141588529, 58942.70528647329, 523521.0, 607524.0, 609181.0, 611182.9440852767, 611225.0, 38381.0, 39046.609533904295, 45628.771856140505, 47006.0, 47069.52930470651, 43358.132837343706, 49737.0, 50084.0, 51040.5803898875, 51749.74125129501, 38646.2270754589, 40022.1995560093, 42219.0, 42506.08722607428, 43300.35049474197, 41792.791036044626, 42159.0, 42230.78884605557, 42450.575494244666, 42459.4404416206, 37239.441408378356, 37969.86090417292, 38080.238395232496, 41129.76610701684, 41372.0, 32690.346193076482, 34665.35305737619, 34772.82613587017, 34778.13054673653, 34948.0, 23521.41196470102, 24753.87623061873, 25886.61170082488, 26188.73811261863, 26531.86734066317], "value": 21459.726071218436, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.4232042378338, 397.8249570189117, 397.91643754811486, 409.74391005621476, 424.7600105940144, 21139.15443382265, 21664.133434662635, 22146.778532214674, 22211.0, 22342.329730108082, 22513.662295065587, 22995.42511437215, 23034.769652303472, 23179.768202317973, 23355.299341019752, 5899.498542623872, 6211.0, 6214.285357183937, 6227.501799856001, 6235.688215589225, 4927.753612319388, 6150.938490615104, 6163.568550201488, 6176.752929882799, 6219.564630475868, 6160.291566469846, 6243.531735119862, 6252.468540174091, 6266.749330026793, 6272.372762723737, 5846.269216347957, 6127.938720612804, 6173.043178307392, 6185.381461853824, 6247.656378899159, 5943.4056594340655, 6029.758809647609, 6138.386161383871, 6150.66171360575, 6153.1693221415435, 13704.177749335071, 13827.170369777745, 14002.859971400258, 26825.731742682518, 27012.594811077848, 2686.1270087221733, 2724.101046654599, 2816.6479190101236, 2913.475574396608, 2959.2306000439826, 10179.643712470028, 10219.79560408795, 10390.584376624925, 10588.417637029961, 10923.071538919201, 1856.2853301478892, 1903.4289713086157, 1918.3285849952406, 1926.1621194780175, 1932.3623204342534, 135787.32106339405, 138454.0, 138954.30522847423, 141694.0, 163196.3680363193, 10922.399268040175, 12182.84262995014, 12326.198797078161, 12417.62747117588, 12563.434645440977, 15857.969231999883, 16254.918725406535, 16366.672666546841, 16369.42707005249, 16400.425985090464, 9107.95446022779, 9270.212031977222, 9311.255099592016, 9394.765130871783, 9442.527873606292, 7995.920550725577, 9038.502882341534, 9222.446653200695, 9291.907080929239, 9438.622455101719, 4891.559759621651, 4906.362172917497, 4996.250562415594, 5011.273365362002, 5028.823991160292, 2051.271798511537, 2080.1887263967064, 2081.6149012432447, 2084.842912183722, 2090.6968489568926, 1007.6322142417964, 1021.5096753558186, 1045.8954104589632, 1060.7984482948216, 1096.2271598523023, 22010.186319386317, 22596.0, 23203.955821988053, 23531.294061178192, 24111.51776964451, 1090.1061129873585, 1112.7385064509995, 1118.972025699364, 1127.5546159267044, 1131.0725205331548, 11318.886811131948, 11350.48922798476, 11402.486888090056, 11847.703807404712, 12076.698082548006, 685098.0, 726486.0, 772549.0, 782568.0, 795960.0, 150246.0, 153759.0, 158021.2098939521, 166146.0, 168968.0, 902.0077914294355, 982.9580644516835, 1001.9098281154585, 1024.416082832778, 1120.7534342444812, 20310.390688279374, 20740.0, 21183.268107140473, 21224.893875530524, 21251.787482124982, 54868.72565637146, 57667.711661441426, 61566.0, 61954.0, 66003.33996660163, 70366.0, 70808.64595676989, 87828.5608571953, 89491.0, 91044.0, 5863.120531920246, 6892.724291028303, 8746.300295976434, 8938.731838044869, 8945.686900958435, 11803.645890623295, 12066.879331206575, 12233.632991010283, 13674.658133546343, 14032.22822744759, 11288.830667540158, 11482.827757583476, 11737.47181376823, 11781.76436471283, 11829.881701182878, 9511.334206605472, 11475.655730328104, 11577.131715121568, 11884.524619015488, 11914.40427978615, 9504.85941687002, 11673.533058677558, 11970.820437693616, 12048.939755301168, 12062.155649104478, 8512.659493620185, 8518.531480768617, 8522.360822938428, 8816.691415800417, 8843.778905527413, 9559.761005974906, 9641.180499657608, 11687.590934317599, 11700.180987330805, 11942.208668699765], "value": 10261.549337697897, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1328.3889410871, 1438.0591147134019, 1475.6827282134338, 1487.6578386970996, 1495.8504149585042, 7761.961190194041, 8030.397720170981, 8204.712835050774, 8650.653973841047, 8652.394332396734, 8195.385346099036, 8447.873281900776, 8473.02560205575, 8592.699255526042, 8604.698835540741, 2393.2222027840976, 2432.0879670123704, 2444.364465239033, 2449.938751531217, 2453.3621258472836, 1992.9835783750318, 2020.55547779489, 2408.084927727461, 2440.5728997425467, 2472.4931389065287, 1920.9135588898464, 2099.9080478151354, 2373.76262373763, 2390.486045500217, 2404.6873906392143, 2376.738558758535, 2382.0828980842325, 2396.9760302396926, 2397.6763136976547, 2414.2515820095737, 2396.125414223813, 2406.6871306730186, 2409.180878501318, 2414.2395145529104, 2452.3623857796924, 8663.176998185163, 8693.087225841322, 8701.782455438664, 8733.606987685569, 8736.825263494695, 5011.799528018875, 5327.041132596131, 5353.866153346158, 5370.033393989081, 5373.274607927958, 3372.426687463137, 3595.7842529448317, 3648.3615367310613, 3654.5979942206336, 3670.229251857113, 1135.6025391113126, 1153.9423028848523, 1279.3027799849106, 1321.9471221151239, 1328.8139660447546, 11134.27627204229, 11136.61021864239, 11163.106951443864, 11178.776424471547, 11179.049780768726, 4641.3296571611945, 4747.169245382044, 4828.37231159954, 4857.927131093036, 4878.756062196876, 9719.854202186973, 9747.9025209747, 9748.951255243821, 9770.609175633037, 9818.26363022776, 5405.864853378697, 5438.700871452029, 5440.537554307849, 5447.863803404946, 5457.317835270591, 4454.888627784267, 4515.909681806345, 4886.95113048865, 4912.361393018885, 4954.479779623088, 3904.2972264992004, 4042.7776472293717, 4052.513698356157, 4056.472658554369, 4140.544540100586, 1314.4479318685983, 1319.9868001320056, 1322.418136020148, 1323.7815760399426, 1329.2556168545646, 932.6455946740195, 936.6674830435097, 949.8005418861978, 966.4877614864133, 1003.1623594298842, 5651.9152212716845, 5845.532357411397, 5879.853003674857, 6008.008678568075, 6111.480524155408, 586.2320360327899, 589.1222079102107, 593.1429084972174, 599.5713065158433, 602.7016626769777, 3890.9416358754643, 3901.648851603369, 3950.150717595731, 3975.741576797499, 4010.258102251093, 9870.21038316933, 10229.43738094412, 10244.0, 10245.84631230532, 10258.794824103476, 10171.135453486537, 10186.592536298613, 10188.847167292497, 10197.541110650038, 10213.591456341663, 7532.585707786015, 7809.68761249555, 7895.447318687751, 7985.640646171052, 8026.237507436784, 5885.205497257815, 5956.285245770535, 5995.640261584319, 6024.156618073385, 6154.5384096192065, 9426.434413935185, 9428.481433521221, 9537.52312384392, 9540.52297385114, 9569.13877750978, 9441.103095205945, 9473.24214062887, 9522.476263805556, 9588.568514417007, 9690.418574885529, 6867.759628412981, 7068.081149450539, 7227.2772722729405, 7300.56196628204, 7490.625468726651, 8062.193780621891, 8261.958690206511, 8269.255766981001, 8283.0, 8299.004119505698, 7188.640567971685, 7285.635718214174, 7342.8898566520475, 7382.187959324363, 7449.6275186241555, 7165.856682866418, 7207.207207207098, 7264.818379540554, 7339.082614673166, 7416.44376671741, 6505.674716264262, 6633.038209459698, 6642.76750313756, 6643.2360278568585, 6660.400563949268, 4818.855434336975, 4864.537868902448, 4919.262110683426, 4943.9505604944925, 4951.034548263054, 5057.165567681292, 5066.847994560169, 5146.794128234828, 5219.556337711337, 5337.172738225581], "value": 4501.492704607328, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.5392846621295, 374.561762737597, 381.57645014034415, 381.86634677862736, 388.0821856309828, 4787.760611969401, 4945.357103576539, 4967.752571551317, 5305.363356397228, 5561.777528898844, 5196.272521846944, 5286.894262114755, 5570.6657600543895, 5602.24369710089, 5611.438856114387, 3481.354170830162, 4356.542563030884, 4362.498312694049, 4367.366731823884, 4374.825006999717, 3440.6731360520835, 4135.421041054255, 4360.912781744363, 4364.978175109136, 4377.190219809346, 4127.195196936598, 4142.668586513072, 4144.958550414487, 4164.437800896885, 4198.349255865346, 3593.119685677013, 3860.903477413059, 4117.888170194061, 4161.438205842233, 4172.3310675729645, 3588.5693716753894, 3646.5259516262718, 3647.726420518445, 3649.7810131391934, 3656.926861462756, 4872.366592343008, 4973.383650313663, 5278.9208161877605, 5289.735513224324, 5427.810026649048, 4062.370332598452, 4233.767142807144, 4316.482022157361, 4843.539863712942, 4881.755912204376, 5098.59211263098, 5497.697626630534, 5739.540836733051, 5856.590038697293, 5884.6469211847425, 3283.819389933553, 4001.799910004488, 4012.799360031987, 4019.256437559032, 4031.012401961538, 5268.736563171864, 5280.442269530459, 6150.8769824603705, 6194.845128871769, 6214.830436477384, 4071.3892916062828, 4090.3638544582404, 4094.324436468009, 4104.835806567764, 4108.938365924513, 3957.88126356204, 4111.7944102794745, 4112.642827866796, 4140.91718165641, 4149.97925010373, 3910.511186101737, 4814.133455977888, 4825.5174482551465, 4833.752305178109, 4899.58353539946, 5239.633225674243, 5721.456461636138, 5733.8566535836935, 5810.576408779876, 6041.758329666764, 1134.3704244144415, 1153.5847095045776, 1196.4077781498213, 1228.8402507674125, 1242.428482897877, 854.9441439821815, 874.7098030405101, 905.1943770044666, 913.6299798581585, 916.9633214671472, 4787.563730880722, 5292.967871265067, 5348.827914226909, 5357.812476563302, 5380.488853558905, 4800.231962885921, 5500.3949565547755, 5519.0, 5823.359430462646, 5875.266796295104, 4639.9768001160455, 4915.778789954461, 4922.975385123052, 4942.876428089255, 5317.308749862493, 2555.961660575093, 2567.525007873512, 2597.545429549821, 3016.9547456788164, 3039.6656367799524, 5725.366331487562, 5767.394423585463, 5850.795222167204, 5862.824115276549, 5913.2608423947, 5121.103806833789, 5368.3158420789605, 5485.862853428616, 5514.628709654887, 5943.0, 5419.647722897999, 5461.061323230276, 5920.881582368328, 5936.881262374728, 5977.432143946318, 4464.437446893561, 4711.528847115261, 5366.39008297517, 5565.465929134947, 5735.44402470471, 4764.856434455775, 5130.743462826918, 5150.4592017837585, 5631.267935168402, 5727.0, 4962.022786328174, 5528.612997090326, 5893.5874488785375, 6002.0996850472775, 6004.8799024020145, 3770.3024940386117, 3820.2741479118877, 3827.4929920901855, 3832.8884623458885, 3859.286032084073, 5731.398203188772, 5802.100674395476, 6012.248468941382, 6069.51443884497, 6077.817665470043, 4958.479359667327, 5697.772089116389, 5783.334916484658, 5869.918292896264, 5873.735681894411, 4926.8768280793265, 5172.818951336685, 5852.736626851887, 5860.38465961068, 5866.3547009828035, 4857.878553036202, 5553.389127196086, 5768.807853015827, 5793.362730099601, 5841.386654401226, 5285.2822832578795, 5982.602786951674, 5992.310884248367, 6010.669413182142, 6027.517798576016, 5217.81737639196, 5410.540104091197, 5588.6646801192055, 5853.502452291603, 5877.001819381431], "value": 4173.370221034226, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [728.963551822409, 731.6268702961488, 781.8905353250545, 868.7046404222565, 878.5431575580698, 15179.924100379496, 15904.84095159048, 16162.434314798955, 16282.267297971604, 16416.0, 14951.700965980674, 15095.245237738096, 15157.317920693567, 15183.240837958085, 15189.848101518981, 883.3683916000059, 892.4199270474211, 896.9416987895765, 898.640543782489, 900.6217388696763, 701.6947627781906, 719.0293104309167, 870.1863757386832, 880.8986966498838, 887.3167660901103, 940.1022023967129, 942.5428667096487, 942.575840871607, 942.7171848445439, 945.5130607737021, 939.3565407695751, 942.2320808541017, 942.5004747483845, 944.2823454174808, 944.5607792376509, 701.242657929435, 701.5860642221063, 703.7220297982319, 703.7677566403074, 704.246456291768, 15176.937614366998, 15297.158656273901, 15482.30329635169, 16296.185190740534, 16588.170591470498, 15014.099154050682, 15027.323770430357, 15113.622159445991, 15116.773248401283, 15138.167400792952, 10921.945390273077, 11908.225965312313, 11913.22564033335, 11971.820422693667, 12545.498180072786, 3301.4882693182353, 3914.451976723261, 3916.255911376858, 3933.5279766427925, 4061.451704019949, 104346.0, 109576.0, 111592.0, 112252.0, 113063.43468282688, 11465.140114491447, 11474.0, 11523.48144333507, 11528.538858445569, 11963.40182990847, 23952.52094958126, 24219.273421797374, 24368.025278988996, 24384.39039024003, 24395.02419903184, 8121.147279535564, 8127.309178719756, 8161.347092232607, 8195.590220489072, 8365.916340836635, 3961.187956468917, 4355.2813785725, 4439.4228750262255, 4524.615407690383, 4579.313103034505, 3717.70258379329, 3777.867774627875, 3793.412021136728, 3816.885493435201, 3822.7132965027736, 689.8447849233883, 696.7352406085756, 700.4641449291296, 714.2785786355818, 715.2632788228134, 697.5640224859463, 709.6097146569366, 711.9572825630479, 720.5748608321115, 738.2138023005589, 8893.0, 10428.270021098602, 10475.161987041018, 10592.152627789912, 10927.562897484171, 591.8757061017235, 611.3031144495279, 628.5725706519611, 668.6589839182052, 737.2516895351223, 5106.310648062538, 5196.740162991911, 5321.281626980385, 5326.041312563698, 5328.8401347959625, 72271.0, 82378.35243295101, 91420.54289728642, 101324.0, 102447.97552024534, 64234.07297781074, 66461.0, 75187.62406188043, 86861.5656921724, 88443.0, 61904.38095619076, 63112.368876311564, 63314.467421303554, 71515.6424217886, 72022.63988680128, 9966.551505182138, 10041.196704263788, 10067.748306292402, 10093.19254459656, 10116.08955194036, 51695.48304517057, 51851.0, 52557.0, 53126.734366327924, 54182.0, 46147.76926115348, 67370.66314668395, 68717.0, 69243.0, 70493.647531762, 12424.378781061092, 12505.437255323714, 12627.11610187278, 12743.299118548568, 12744.235345879277, 13179.538716144889, 13192.0, 13212.141210821266, 13451.192928424325, 14015.50945716895, 12061.51753929833, 12557.874421256036, 12845.807312890127, 13647.249401283025, 14770.778438323649, 12611.0, 13947.651308717363, 14311.856881431053, 14402.991790574559, 14741.484048058266, 12975.805362919753, 13639.181649101085, 14351.354189061309, 14396.784048239075, 14484.420623175376, 9308.394954327947, 10848.837267440837, 11375.544978201111, 11551.306921584732, 11902.583409580624, 9397.248220142235, 10460.476976151314, 11197.384143872165, 11435.371054592077, 11598.130140239347], "value": 7621.243391175602, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1535.4000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 150.0, 121.0, 118.0, 118.0, 117.0, 118.0, 118.0, 118.0, 118.0], "value": 120.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 110.0, 110.0, 143.0, 109.0, 128.0, 138.0, 128.0, 126.0, 111.0], "value": 124.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 199.0, 194.0, 200.0, 193.0, 195.0, 197.0, 193.0, 193.0, 193.0], "value": 195.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 59.0, 57.0, 87.0, 58.0, 59.0, 59.0, 59.0, 72.0, 60.0], "value": 64.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 70.0, 82.0, 96.0, 53.0, 64.0, 97.0, 95.0, 98.0, 106.0], "value": 84.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 86.0, 89.0, 85.0, 89.0, 102.0, 133.0, 111.0, 89.0, 107.0], "value": 102.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 169.0, 157.0, 101.0, 122.0, 173.0, 159.0, 98.0, 133.0, 113.0], "value": 134.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 98.0, 107.0, 92.0, 105.0, 145.0, 88.0, 142.0, 145.0, 118.0], "value": 113.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 59.0, 58.0, 59.0, 61.0, 60.0, 61.0, 61.0, 61.0, 61.0], "value": 59.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 74.0, 70.0, 82.0, 79.0, 98.0, 70.0, 71.0, 77.0, 72.0], "value": 76.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 86.0, 83.0, 80.0, 82.0, 84.0, 79.0, 88.0, 89.0, 91.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 135.0, 133.0, 131.0, 132.0, 132.0, 146.0, 157.0, 194.0, 135.0], "value": 143.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 167.0, 164.0, 174.0, 165.0, 165.0, 163.0, 164.0, 166.0, 191.0], "value": 168.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 64.0, 66.0, 68.0, 67.0, 65.0, 63.0, 64.0, 66.0], "value": 65.5, "unit": "score"}]}]} 16:49:27 INFO - Return code: 0 16:49:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:49:27 ERROR - # TBPL SUCCESS # 16:49:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:49:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:49:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:49:27 INFO - 2017-03-24 16:49:27.794 screenresolution[1662:11205] starting screenresolution argv=screenresolution get 16:49:27 INFO - 2017-03-24 16:49:27.797 screenresolution[1662:11205] Display 0: 1600x1200x32@60 16:49:27 INFO - 2017-03-24 16:49:27.803 screenresolution[1663:11208] starting screenresolution argv=screenresolution list 16:49:27 INFO - Available Modes on Display 0 16:49:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:49:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:49:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:49:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:49:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:49:28 INFO - Intel Iris: 16:49:28 INFO - Chipset Model: Intel Iris 16:49:28 INFO - Type: GPU 16:49:28 INFO - Bus: Built-In 16:49:28 INFO - VRAM (Dynamic, Max): 1536 MB 16:49:28 INFO - Vendor: Intel (0x8086) 16:49:28 INFO - Device ID: 0x0a2e 16:49:28 INFO - Revision ID: 0x0009 16:49:28 INFO - Displays: 16:49:28 INFO - Display: 16:49:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:49:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:49:28 INFO - Display Serial Number: 1600x1200 60 16:49:28 INFO - Main Display: Yes 16:49:28 INFO - Mirror: Off 16:49:28 INFO - Online: Yes 16:49:28 INFO - Rotation: Supported 16:49:28 INFO - Return code: 0 16:49:28 INFO - Running post-action listener: _resource_record_post_action 16:49:28 INFO - [mozharness: 2017-03-24 23:49:28.035931Z] Finished run-tests step (success) 16:49:28 INFO - Running post-run listener: _resource_record_post_run 16:49:28 INFO - Total resource usage - Wall time: 1190s; CPU: 54.0%; Read bytes: 35278848; Write bytes: 426514432; Read time: 325; Write time: 1411 16:49:28 INFO - TinderboxPrint: CPU usage
54.4% 16:49:28 INFO - TinderboxPrint: I/O read bytes / time
35,278,848 / 325 16:49:28 INFO - TinderboxPrint: I/O write bytes / time
426,514,432 / 1,411 16:49:28 INFO - TinderboxPrint: CPU idle
2,170.1 (45.6%) 16:49:28 INFO - TinderboxPrint: CPU system
962.2 (20.2%) 16:49:28 INFO - TinderboxPrint: CPU user
1,629.3 (34.2%) 16:49:28 INFO - TinderboxPrint: Swap in / out
153,829,376 / 0 16:49:28 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 111858176; Write bytes: 104173568; Read time: 9986; Write time: 200 16:49:28 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 34344960; Write bytes: 303958016; Read time: 311; Write time: 1158 16:49:28 INFO - Running post-run listener: _upload_blobber_files 16:49:28 INFO - Blob upload gear active. 16:49:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:49:28 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 16:49:28 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'] 16:49:28 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 16:49:28 INFO - (blobuploader) - INFO - Open directory for files ... 16:49:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:49:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:49:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:49:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:49:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:49:29 INFO - (blobuploader) - INFO - Done attempting. 16:49:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:49:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:49:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:49:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:49:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:49:30 INFO - (blobuploader) - INFO - Done attempting. 16:49:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:49:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:49:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:49:31 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:49:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:49:31 INFO - (blobuploader) - INFO - Done attempting. 16:49:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:49:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:49:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:49:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:49:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:49:33 INFO - (blobuploader) - INFO - Done attempting. 16:49:33 INFO - (blobuploader) - INFO - Iteration through files over. 16:49:33 INFO - Return code: 0 16:49:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:49:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:49:33 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d06f0f71b0ae3e2a03605f3538041d6650ffa96c35407c19bb2aa399bf365793597ce2592311fce6f40e677561bb463461f4f38f1eb9c790e93551690fcb417d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8fec1bbfad32add0783cc1415711c3826f1b774b254f5a6fe13444f0f4f34d9d3c3b8c43b86648361f8169b5a79ac008bb191f8277d924955d5c8db11743483b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/58ce9e72a09794f6e86b814f97e4337dad6677595be414261f5416c4f552a163771c6959ab3234ea591d3463f4a4395dc689619a3c0d7f1cc8e98901cee1b7a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e73682b48db50942da97c05f8b6d0860ba010ff3c11a4246a1657bfd90f85149966954e83d6fb74c38a35eeb8331f1b19063eac04a58cb0cd88cbaa82f3fa63b"} 16:49:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:49:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:49:33 INFO - Contents: 16:49:33 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d06f0f71b0ae3e2a03605f3538041d6650ffa96c35407c19bb2aa399bf365793597ce2592311fce6f40e677561bb463461f4f38f1eb9c790e93551690fcb417d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8fec1bbfad32add0783cc1415711c3826f1b774b254f5a6fe13444f0f4f34d9d3c3b8c43b86648361f8169b5a79ac008bb191f8277d924955d5c8db11743483b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/58ce9e72a09794f6e86b814f97e4337dad6677595be414261f5416c4f552a163771c6959ab3234ea591d3463f4a4395dc689619a3c0d7f1cc8e98901cee1b7a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e73682b48db50942da97c05f8b6d0860ba010ff3c11a4246a1657bfd90f85149966954e83d6fb74c38a35eeb8331f1b19063eac04a58cb0cd88cbaa82f3fa63b"} 16:49:33 INFO - Running post-run listener: copy_logs_to_upload_dir 16:49:33 INFO - Copying logs to upload dir... 16:49:33 INFO - mkdir: /builds/slave/test/build/upload/logs 16:49:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.819844 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-03-24 16:49:33.521001) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-24 16:49:33.524856) ========= 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.tllbQ0Keg1/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.PkMJa2Xpe0/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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/d06f0f71b0ae3e2a03605f3538041d6650ffa96c35407c19bb2aa399bf365793597ce2592311fce6f40e677561bb463461f4f38f1eb9c790e93551690fcb417d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8fec1bbfad32add0783cc1415711c3826f1b774b254f5a6fe13444f0f4f34d9d3c3b8c43b86648361f8169b5a79ac008bb191f8277d924955d5c8db11743483b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/58ce9e72a09794f6e86b814f97e4337dad6677595be414261f5416c4f552a163771c6959ab3234ea591d3463f4a4395dc689619a3c0d7f1cc8e98901cee1b7a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e73682b48db50942da97c05f8b6d0860ba010ff3c11a4246a1657bfd90f85149966954e83d6fb74c38a35eeb8331f1b19063eac04a58cb0cd88cbaa82f3fa63b"} build_url:https://queue.taskcluster.net/v1/task/Jda4TLv3SwyV5Mzkrklklw/artifacts/public/build/firefox-53.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011193 build_url: 'https://queue.taskcluster.net/v1/task/Jda4TLv3SwyV5Mzkrklklw/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/d06f0f71b0ae3e2a03605f3538041d6650ffa96c35407c19bb2aa399bf365793597ce2592311fce6f40e677561bb463461f4f38f1eb9c790e93551690fcb417d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8fec1bbfad32add0783cc1415711c3826f1b774b254f5a6fe13444f0f4f34d9d3c3b8c43b86648361f8169b5a79ac008bb191f8277d924955d5c8db11743483b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/58ce9e72a09794f6e86b814f97e4337dad6677595be414261f5416c4f552a163771c6959ab3234ea591d3463f4a4395dc689619a3c0d7f1cc8e98901cee1b7a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e73682b48db50942da97c05f8b6d0860ba010ff3c11a4246a1657bfd90f85149966954e83d6fb74c38a35eeb8331f1b19063eac04a58cb0cd88cbaa82f3fa63b"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-24 16:49:33.564180) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 16:49:33.564508) ========= 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.tllbQ0Keg1/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.PkMJa2Xpe0/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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.005311 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 16:49:33.612900) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-24 16:49:33.613225) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-24 16:49:33.613548) ========= ========= Total master_lag: 0.20 =========