builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0129 starttime: 1491277910.15 results: success (0) buildid: 20170403194027 builduid: 5ecd0e90baf54ceeb895727c4a35af0e revision: 42024440f9452f6c71d86ee6cfd22ab4b677793b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-03 20:51:50.146668) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-03 20:51:50.147144) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-03 20:51:50.147487) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-03 20:51:50.176512) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 20:51:50.176947) ========= 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.HmrRupATDG/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.cmvejtSKf8/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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-04-03 20:51:50-- 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% 38.5M=0s 2017-04-03 20:51:50 (38.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.092716 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 20:51:50.294258) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 20:51:50.294631) ========= 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.HmrRupATDG/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.cmvejtSKf8/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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.029150 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 20:51:50.345799) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-03 20:51:50.346215) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 42024440f9452f6c71d86ee6cfd22ab4b677793b --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 42024440f9452f6c71d86ee6cfd22ab4b677793b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HmrRupATDG/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.cmvejtSKf8/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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-04-03 20:51:50,461 truncating revision to first 12 chars 2017-04-03 20:51:50,461 Setting DEBUG logging. 2017-04-03 20:51:50,461 attempt 1/10 2017-04-03 20:51:50,461 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/42024440f945?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-03 20:51:51,889 unpacking tar archive at: mozilla-beta-42024440f945/testing/mozharness/ program finished with exit code 0 elapsedTime=1.704602 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-03 20:51:52.092648) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-03 20:51:52.093023) ========= script_repo_revision: 42024440f9452f6c71d86ee6cfd22ab4b677793b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-03 20:51:52.093534) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-03 20:51:52.093851) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-03 20:51:52.114174) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-04-03 20:51:52.114649) ========= /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.HmrRupATDG/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.cmvejtSKf8/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 20:51:52 INFO - MultiFileLogger online at 20170403 20:51:52 in /builds/slave/test 20:51:52 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 20:51:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:51:52 INFO - {'append_to_log': False, 20:51:52 INFO - 'base_work_dir': '/builds/slave/test', 20:51:52 INFO - 'blob_upload_branch': 'Mozilla-Beta', 20:51:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:51:52 INFO - 'branch': 'Mozilla-Beta', 20:51:52 INFO - 'buildbot_json_path': 'buildprops.json', 20:51:52 INFO - 'config_files': ('talos/mac_config.py',), 20:51:52 INFO - 'default_actions': ('clobber', 20:51:52 INFO - 'read-buildbot-config', 20:51:52 INFO - 'download-and-extract', 20:51:52 INFO - 'populate-webroot', 20:51:52 INFO - 'create-virtualenv', 20:51:52 INFO - 'install', 20:51:52 INFO - 'run-tests'), 20:51:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:51:52 INFO - 'download_minidump_stackwalk': True, 20:51:52 INFO - 'download_symbols': 'ondemand', 20:51:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:51:52 INFO - 'tooltool.py': '/tools/tooltool.py', 20:51:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:51:52 INFO - '/tools/misc-python/virtualenv.py')}, 20:51:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:51:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:51:52 INFO - 'installer_path': 'installer.exe', 20:51:52 INFO - 'log_level': 'info', 20:51:52 INFO - 'log_name': 'talos', 20:51:52 INFO - 'log_to_console': True, 20:51:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:51:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:51:52 INFO - 'opt_config_files': (), 20:51:52 INFO - 'pip_index': False, 20:51:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:51:52 INFO - 'cmd': ('bash', 20:51:52 INFO - '-c', 20:51:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:51:52 INFO - 'enabled': True, 20:51:52 INFO - 'halt_on_failure': False, 20:51:52 INFO - 'name': 'check_screen_resolution'},), 20:51:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:51:52 INFO - 'cmd': ('bash', 20:51:52 INFO - '-c', 20:51:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:51:52 INFO - 'enabled': True, 20:51:52 INFO - 'halt_on_failure': False, 20:51:52 INFO - 'name': 'check_screen_resolution'},), 20:51:52 INFO - 'run_cmd_checks_enabled': True, 20:51:52 INFO - 'sps_profile': False, 20:51:52 INFO - 'sps_profile_interval': 0, 20:51:52 INFO - 'suite': 'dromaeojs-e10s', 20:51:52 INFO - 'system_bits': '32', 20:51:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:51:52 INFO - 'title': 't-yosemite-r7-0129', 20:51:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:51:52 INFO - 'use_talos_json': True, 20:51:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:51:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:51:52 INFO - 'work_dir': 'build'} 20:51:52 INFO - [mozharness: 2017-04-04 03:51:52.341077Z] Running clobber step. 20:51:52 INFO - Running pre-action listener: _resource_record_pre_action 20:51:52 INFO - Running main action method: clobber 20:51:52 INFO - rmtree: /builds/slave/test/build 20:51:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:51:54 INFO - Running post-action listener: _resource_record_post_action 20:51:54 INFO - [mozharness: 2017-04-04 03:51:54.818441Z] Finished clobber step (success) 20:51:54 INFO - [mozharness: 2017-04-04 03:51:54.818606Z] Running read-buildbot-config step. 20:51:54 INFO - Running pre-action listener: _resource_record_pre_action 20:51:54 INFO - Running main action method: read_buildbot_config 20:51:54 INFO - Using buildbot properties: 20:51:54 INFO - { 20:51:54 INFO - "project": "", 20:51:54 INFO - "product": "firefox", 20:51:54 INFO - "script_repo_revision": "production", 20:51:54 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 20:51:54 INFO - "repository": "", 20:51:54 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 20:51:54 INFO - "buildid": "20170403194027", 20:51:54 INFO - "pgo_build": "False", 20:51:54 INFO - "basedir": "/builds/slave/test", 20:51:54 INFO - "buildnumber": 3, 20:51:54 INFO - "slavename": "t-yosemite-r7-0129", 20:51:54 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 20:51:54 INFO - "platform": "yosemite_r7", 20:51:54 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 20:51:54 INFO - "branch": "mozilla-beta", 20:51:54 INFO - "repo_path": "releases/mozilla-beta", 20:51:54 INFO - "revision": "42024440f9452f6c71d86ee6cfd22ab4b677793b", 20:51:54 INFO - "stage_platform": "macosx64", 20:51:54 INFO - "builduid": "5ecd0e90baf54ceeb895727c4a35af0e", 20:51:54 INFO - "slavebuilddir": "test" 20:51:54 INFO - } 20:51:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/UrDvt7ceTcu0evu2vIspZQ/artifacts/public/build/firefox-53.0.en-US.mac.dmg. 20:51:54 INFO - Running post-action listener: _resource_record_post_action 20:51:54 INFO - [mozharness: 2017-04-04 03:51:54.822224Z] Finished read-buildbot-config step (success) 20:51:54 INFO - [mozharness: 2017-04-04 03:51:54.822316Z] Running download-and-extract step. 20:51:54 INFO - Running pre-action listener: _resource_record_pre_action 20:51:54 INFO - Running main action method: download_and_extract 20:51:54 INFO - mkdir: /builds/slave/test/build/tests 20:51:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:51:54 INFO - trying https://queue.taskcluster.net/v1/task/UrDvt7ceTcu0evu2vIspZQ/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json 20:51:54 INFO - Downloading https://queue.taskcluster.net/v1/task/UrDvt7ceTcu0evu2vIspZQ/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 20:51:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UrDvt7ceTcu0evu2vIspZQ/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 20:51:55 INFO - Downloaded 1165 bytes. 20:51:55 INFO - Reading from file /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 20:51:55 INFO - Using the following test package requirements: 20:51:55 INFO - {u'common': [u'firefox-53.0.en-US.mac.common.tests.zip'], 20:51:55 INFO - u'cppunittest': [u'firefox-53.0.en-US.mac.common.tests.zip', 20:51:55 INFO - u'firefox-53.0.en-US.mac.cppunittest.tests.zip'], 20:51:55 INFO - u'gtest': [u'firefox-53.0.en-US.mac.common.tests.zip', 20:51:55 INFO - u'firefox-53.0.en-US.mac.gtest.tests.zip'], 20:51:55 INFO - u'jittest': [u'firefox-53.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 20:51:55 INFO - u'mochitest': [u'firefox-53.0.en-US.mac.common.tests.zip', 20:51:55 INFO - u'firefox-53.0.en-US.mac.mochitest.tests.zip'], 20:51:55 INFO - u'mozbase': [u'firefox-53.0.en-US.mac.common.tests.zip'], 20:51:55 INFO - u'reftest': [u'firefox-53.0.en-US.mac.common.tests.zip', 20:51:55 INFO - u'firefox-53.0.en-US.mac.reftest.tests.zip'], 20:51:55 INFO - u'talos': [u'firefox-53.0.en-US.mac.common.tests.zip', 20:51:55 INFO - u'firefox-53.0.en-US.mac.talos.tests.zip'], 20:51:55 INFO - u'web-platform': [u'firefox-53.0.en-US.mac.common.tests.zip', 20:51:55 INFO - u'firefox-53.0.en-US.mac.web-platform.tests.zip'], 20:51:55 INFO - u'xpcshell': [u'firefox-53.0.en-US.mac.common.tests.zip', 20:51:55 INFO - u'firefox-53.0.en-US.mac.xpcshell.tests.zip']} 20:51:55 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip'] for test suite category: common 20:51:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UrDvt7ceTcu0evu2vIspZQ/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 20:51:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UrDvt7ceTcu0evu2vIspZQ/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 20:51:55 INFO - Fetch https://queue.taskcluster.net/v1/task/UrDvt7ceTcu0evu2vIspZQ/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 20:51:56 INFO - Expected file size: 16115103 20:51:56 INFO - Obtained file size: 16115103 20:52:03 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 20:52:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UrDvt7ceTcu0evu2vIspZQ/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 20:52:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UrDvt7ceTcu0evu2vIspZQ/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 20:52:03 INFO - Fetch https://queue.taskcluster.net/v1/task/UrDvt7ceTcu0evu2vIspZQ/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 20:52:04 INFO - Expected file size: 16115103 20:52:04 INFO - Obtained file size: 16115103 20:52:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UrDvt7ceTcu0evu2vIspZQ/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip 20:52:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UrDvt7ceTcu0evu2vIspZQ/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip'}, attempt #1 20:52:11 INFO - Fetch https://queue.taskcluster.net/v1/task/UrDvt7ceTcu0evu2vIspZQ/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip into memory 20:52:11 INFO - Expected file size: 14403663 20:52:11 INFO - Obtained file size: 14403663 20:52:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:52:12 INFO - trying https://queue.taskcluster.net/v1/task/UrDvt7ceTcu0evu2vIspZQ/artifacts/public/build/firefox-53.0.en-US.mac.dmg 20:52:12 INFO - Downloading https://queue.taskcluster.net/v1/task/UrDvt7ceTcu0evu2vIspZQ/artifacts/public/build/firefox-53.0.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0.en-US.mac.dmg 20:52:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UrDvt7ceTcu0evu2vIspZQ/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 20:52:13 INFO - Downloaded 58728405 bytes. 20:52:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UrDvt7ceTcu0evu2vIspZQ/artifacts/public/build/firefox-53.0.en-US.mac.dmg 20:52:13 INFO - mkdir: /builds/slave/test/properties 20:52:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:52:13 INFO - Writing to file /builds/slave/test/properties/build_url 20:52:13 INFO - Contents: 20:52:13 INFO - build_url:https://queue.taskcluster.net/v1/task/UrDvt7ceTcu0evu2vIspZQ/artifacts/public/build/firefox-53.0.en-US.mac.dmg 20:52:14 INFO - Running post-action listener: _resource_record_post_action 20:52:14 INFO - Running post-action listener: set_extra_try_arguments 20:52:14 INFO - [mozharness: 2017-04-04 03:52:14.631965Z] Finished download-and-extract step (success) 20:52:14 INFO - [mozharness: 2017-04-04 03:52:14.632101Z] Running populate-webroot step. 20:52:14 INFO - Running pre-action listener: _resource_record_pre_action 20:52:14 INFO - Running main action method: populate_webroot 20:52:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:52:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:52:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:52:14 INFO - '--tppagecycles', 20:52:14 INFO - '7'], 20:52:14 INFO - 'tests': ['tsvgm']}}, 20:52:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:52:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:52:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:52:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:52:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:52:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:52:14 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:52:14 INFO - 'talos_options': ['--disable-e10s'], 20:52:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:52:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:52:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:52:14 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:52:14 INFO - 'talos_options': ['--disable-e10s'], 20:52:14 INFO - 'tests': ['damp', 'tps']}, 20:52:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:52:14 INFO - 'tests': ['damp', 'tps']}, 20:52:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:52:14 INFO - 'tests': ['dromaeo_dom']}, 20:52:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:52:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:52:14 INFO - 'tests': ['basic_compositor_video']}, 20:52:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:52:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:52:14 INFO - 'tests': ['a11yr', 20:52:14 INFO - 'ts_paint', 20:52:14 INFO - 'tpaint', 20:52:14 INFO - 'sessionrestore', 20:52:14 INFO - 'sessionrestore_no_auto_restore', 20:52:14 INFO - 'tabpaint']}, 20:52:14 INFO - 'other-e10s': {'tests': ['a11yr', 20:52:14 INFO - 'ts_paint', 20:52:14 INFO - 'tpaint', 20:52:14 INFO - 'sessionrestore', 20:52:14 INFO - 'sessionrestore_no_auto_restore', 20:52:14 INFO - 'tabpaint']}, 20:52:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:52:14 INFO - 'ts_paint', 20:52:14 INFO - 'tpaint', 20:52:14 INFO - 'sessionrestore', 20:52:14 INFO - 'sessionrestore_no_auto_restore', 20:52:14 INFO - 'tabpaint']}, 20:52:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:52:14 INFO - 'ts_paint', 20:52:14 INFO - 'tpaint', 20:52:14 INFO - 'sessionrestore', 20:52:14 INFO - 'sessionrestore_no_auto_restore', 20:52:14 INFO - 'tabpaint']}, 20:52:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:52:14 INFO - 'tests': ['a11yr', 20:52:14 INFO - 'ts_paint', 20:52:14 INFO - 'tpaint', 20:52:14 INFO - 'sessionrestore', 20:52:14 INFO - 'sessionrestore_no_auto_restore', 20:52:14 INFO - 'tabpaint']}, 20:52:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:52:14 INFO - 'tests': ['a11yr', 20:52:14 INFO - 'ts_paint', 20:52:14 INFO - 'tpaint', 20:52:14 INFO - 'sessionrestore', 20:52:14 INFO - 'sessionrestore_no_auto_restore', 20:52:14 INFO - 'tabpaint']}, 20:52:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:52:14 INFO - 'tests': ['tsvgx', 20:52:14 INFO - 'tsvgr_opacity', 20:52:14 INFO - 'tart', 20:52:14 INFO - 'tscrollx', 20:52:14 INFO - 'cart', 20:52:14 INFO - 'tsvg_static']}, 20:52:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:52:14 INFO - 'tsvgr_opacity', 20:52:14 INFO - 'tart', 20:52:14 INFO - 'tscrollx', 20:52:14 INFO - 'cart', 20:52:14 INFO - 'tsvg_static']}, 20:52:14 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:52:14 INFO - 'talos_options': ['--disable-e10s'], 20:52:14 INFO - 'tests': ['tp5o']}, 20:52:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:52:14 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:52:14 INFO - 'talos_options': ['--disable-e10s', 20:52:14 INFO - '--xperf_path', 20:52:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:52:14 INFO - 'tests': ['tp5n']}, 20:52:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:52:14 INFO - 'talos_options': ['--xperf_path', 20:52:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:52:14 INFO - 'tests': ['tp5n']}}, 20:52:14 INFO - 'talos.zip': {'path': '', 20:52:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:52:14 INFO - Running post-action listener: _resource_record_post_action 20:52:14 INFO - [mozharness: 2017-04-04 03:52:14.652847Z] Finished populate-webroot step (success) 20:52:14 INFO - [mozharness: 2017-04-04 03:52:14.652950Z] Running create-virtualenv step. 20:52:14 INFO - Running pre-action listener: _resource_record_pre_action 20:52:14 INFO - Running main action method: create_virtualenv 20:52:14 INFO - Creating virtualenv /builds/slave/test/build/venv 20:52:14 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 20:52:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:52:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:52:14 INFO - Using real prefix '/tools/python27' 20:52:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:52:15 INFO - Installing distribute.............................................................................................................................................................................................done. 20:52:18 INFO - Installing pip.................done. 20:52:18 INFO - Return code: 0 20:52:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:52:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:52:18 INFO - Reading from file tmpfile_stdout 20:52:18 INFO - Output received: 20:52:18 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) 20:52:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:52:18 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')]} 20:52:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:52:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:52:18 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')]} 20:52:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:52:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:52:18 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 0x104b28b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b2b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9188dd5020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104906d30>, '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 0x10482ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048fbbe8>, '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.HmrRupATDG/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.cmvejtSKf8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 20:52:18 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 20:52:18 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 20:52:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HmrRupATDG/Render', 20:52:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:52:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:52:18 INFO - 'HOME': '/Users/cltbld', 20:52:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:52:18 INFO - 'LOGNAME': 'cltbld', 20:52:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:52:18 INFO - 'MOZ_NO_REMOTE': '1', 20:52:18 INFO - 'NO_EM_RESTART': '1', 20:52:18 INFO - 'PAGER': '/bin/cat', 20:52:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:52:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:52:18 INFO - 'PWD': '/builds/slave/test', 20:52:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:52:18 INFO - 'SHELL': '/bin/bash', 20:52:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cmvejtSKf8/Listeners', 20:52:18 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 20:52:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:52:18 INFO - 'USER': 'cltbld', 20:52:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:52:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:52:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:52:18 INFO - 'XPC_FLAGS': '0x0', 20:52:18 INFO - 'XPC_SERVICE_NAME': '0', 20:52:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:52:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:52:19 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 20:52:19 INFO - Cleaning up... 20:52:19 INFO - Return code: 0 20:52:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:52:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:52:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:52:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:52:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:52:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:52:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:52:19 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 0x104b28b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b2b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9188dd5020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104906d30>, '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 0x10482ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048fbbe8>, '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.HmrRupATDG/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.cmvejtSKf8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 20:52:19 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 20:52:19 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 20:52:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HmrRupATDG/Render', 20:52:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:52:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:52:19 INFO - 'HOME': '/Users/cltbld', 20:52:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:52:19 INFO - 'LOGNAME': 'cltbld', 20:52:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:52:19 INFO - 'MOZ_NO_REMOTE': '1', 20:52:19 INFO - 'NO_EM_RESTART': '1', 20:52:19 INFO - 'PAGER': '/bin/cat', 20:52:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:52:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:52:19 INFO - 'PWD': '/builds/slave/test', 20:52:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:52:19 INFO - 'SHELL': '/bin/bash', 20:52:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cmvejtSKf8/Listeners', 20:52:19 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 20:52:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:52:19 INFO - 'USER': 'cltbld', 20:52:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:52:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:52:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:52:19 INFO - 'XPC_FLAGS': '0x0', 20:52:19 INFO - 'XPC_SERVICE_NAME': '0', 20:52:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:52:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:52:19 INFO - Downloading/unpacking psutil>=3.1.1 20:52:19 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 20:52:19 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 20:52:19 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 20:52:19 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 20:52:19 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 20:52:19 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 20:52:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:52:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:52:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:52:22 INFO - Installing collected packages: psutil 20:52:22 INFO - Running setup.py install for psutil 20:52:23 INFO - building 'psutil._psutil_osx' extension 20:52:23 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 20:52:23 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 20:52:23 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 20:52:23 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 20:52:23 INFO - building 'psutil._psutil_posix' extension 20:52:23 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 20:52:23 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:52:23 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:52:23 INFO - ^ 20:52:24 INFO - 1 warning generated. 20:52:24 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 20:52:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:52:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:52:24 INFO - Successfully installed psutil 20:52:24 INFO - Cleaning up... 20:52:24 INFO - Return code: 0 20:52:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:52:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:52:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:52:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:52:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:52:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:52:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:52:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104b28b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b2b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9188dd5020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104906d30>, '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 0x10482ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048fbbe8>, '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.HmrRupATDG/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.cmvejtSKf8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 20:52:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:52:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:52:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HmrRupATDG/Render', 20:52:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:52:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:52:24 INFO - 'HOME': '/Users/cltbld', 20:52:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:52:24 INFO - 'LOGNAME': 'cltbld', 20:52:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:52:24 INFO - 'MOZ_NO_REMOTE': '1', 20:52:24 INFO - 'NO_EM_RESTART': '1', 20:52:24 INFO - 'PAGER': '/bin/cat', 20:52:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:52:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:52:24 INFO - 'PWD': '/builds/slave/test', 20:52:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:52:24 INFO - 'SHELL': '/bin/bash', 20:52:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cmvejtSKf8/Listeners', 20:52:24 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 20:52:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:52:24 INFO - 'USER': 'cltbld', 20:52:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:52:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:52:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:52:24 INFO - 'XPC_FLAGS': '0x0', 20:52:24 INFO - 'XPC_SERVICE_NAME': '0', 20:52:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:52:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:52:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:52: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 20:52: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 20:52: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 20:52: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 20:52: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 20:52: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 20:52:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:52:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:52:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:52:27 INFO - Installing collected packages: mozsystemmonitor 20:52:27 INFO - Running setup.py install for mozsystemmonitor 20:52:27 INFO - Successfully installed mozsystemmonitor 20:52:27 INFO - Cleaning up... 20:52:27 INFO - Return code: 0 20:52:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:52: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')]} 20:52:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:52:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:52: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')]} 20:52:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:52:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:52:27 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 0x104b28b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b2b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9188dd5020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104906d30>, '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 0x10482ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048fbbe8>, '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.HmrRupATDG/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.cmvejtSKf8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 20:52:27 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 20:52:27 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 20:52:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HmrRupATDG/Render', 20:52:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:52:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:52:27 INFO - 'HOME': '/Users/cltbld', 20:52:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:52:27 INFO - 'LOGNAME': 'cltbld', 20:52:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:52:27 INFO - 'MOZ_NO_REMOTE': '1', 20:52:27 INFO - 'NO_EM_RESTART': '1', 20:52:27 INFO - 'PAGER': '/bin/cat', 20:52:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:52:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:52:27 INFO - 'PWD': '/builds/slave/test', 20:52:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:52:27 INFO - 'SHELL': '/bin/bash', 20:52:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cmvejtSKf8/Listeners', 20:52:27 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 20:52:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:52:27 INFO - 'USER': 'cltbld', 20:52:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:52:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:52:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:52:27 INFO - 'XPC_FLAGS': '0x0', 20:52:27 INFO - 'XPC_SERVICE_NAME': '0', 20:52:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:52:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:52:27 INFO - Downloading/unpacking jsonschema==2.5.1 20:52:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:52:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:52:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:52:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:52:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:52:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:52:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:52:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:52:30 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 20:52:30 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 20:52:30 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 20:52:30 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 20:52:30 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 20:52:30 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 20:52:30 INFO - Downloading functools32-3.2.3-2.tar.gz 20:52:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:52:30 INFO - warning: no files found matching '*.txt' 20:52:30 INFO - no previously-included directories found matching 'build' 20:52:30 INFO - no previously-included directories found matching 'dist' 20:52:30 INFO - no previously-included directories found matching '.git*' 20:52:30 INFO - Installing collected packages: jsonschema, functools32 20:52:30 INFO - Running setup.py install for functools32 20:52:30 INFO - warning: no files found matching '*.txt' 20:52:30 INFO - no previously-included directories found matching 'build' 20:52:30 INFO - no previously-included directories found matching 'dist' 20:52:30 INFO - no previously-included directories found matching '.git*' 20:52:30 INFO - Successfully installed jsonschema functools32 20:52:30 INFO - Cleaning up... 20:52:30 INFO - Return code: 0 20:52:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:52:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:52:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:52:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:52:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:52:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:52:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:52:30 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 0x104b28b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b2b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9188dd5020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104906d30>, '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 0x10482ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048fbbe8>, '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.HmrRupATDG/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.cmvejtSKf8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 20:52:30 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 20:52:30 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 20:52:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HmrRupATDG/Render', 20:52:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:52:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:52:30 INFO - 'HOME': '/Users/cltbld', 20:52:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:52:30 INFO - 'LOGNAME': 'cltbld', 20:52:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:52:30 INFO - 'MOZ_NO_REMOTE': '1', 20:52:30 INFO - 'NO_EM_RESTART': '1', 20:52:30 INFO - 'PAGER': '/bin/cat', 20:52:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:52:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:52:30 INFO - 'PWD': '/builds/slave/test', 20:52:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:52:30 INFO - 'SHELL': '/bin/bash', 20:52:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cmvejtSKf8/Listeners', 20:52:30 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 20:52:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:52:30 INFO - 'USER': 'cltbld', 20:52:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:52:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:52:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:52:30 INFO - 'XPC_FLAGS': '0x0', 20:52:30 INFO - 'XPC_SERVICE_NAME': '0', 20:52:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:52:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:52:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:52:31 INFO - Cleaning up... 20:52:31 INFO - Return code: 0 20:52:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:52: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')]} 20:52:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:52:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:52: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')]} 20:52:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:52:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:52:31 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 0x104b28b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b2b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9188dd5020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104906d30>, '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 0x10482ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048fbbe8>, '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.HmrRupATDG/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.cmvejtSKf8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 20:52:31 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 20:52:31 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 20:52:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HmrRupATDG/Render', 20:52:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:52:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:52:31 INFO - 'HOME': '/Users/cltbld', 20:52:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:52:31 INFO - 'LOGNAME': 'cltbld', 20:52:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:52:31 INFO - 'MOZ_NO_REMOTE': '1', 20:52:31 INFO - 'NO_EM_RESTART': '1', 20:52:31 INFO - 'PAGER': '/bin/cat', 20:52:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:52:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:52:31 INFO - 'PWD': '/builds/slave/test', 20:52:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:52:31 INFO - 'SHELL': '/bin/bash', 20:52:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cmvejtSKf8/Listeners', 20:52:31 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 20:52:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:52:31 INFO - 'USER': 'cltbld', 20:52:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:52:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:52:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:52:31 INFO - 'XPC_FLAGS': '0x0', 20:52:31 INFO - 'XPC_SERVICE_NAME': '0', 20:52:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:52:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:52:31 INFO - Downloading/unpacking blobuploader==1.2.4 20:52:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:52:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:52:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:52:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:52:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:52:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:52:34 INFO - Downloading blobuploader-1.2.4.tar.gz 20:52:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:52:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:52:34 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 20:52:34 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 20:52:34 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 20:52:34 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 20:52:34 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 20:52:34 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 20:52:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:52:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:52:34 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 20:52:34 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 20:52:34 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 20:52:34 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 20:52:34 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 20:52:34 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 20:52:35 INFO - Downloading docopt-0.6.1.tar.gz 20:52:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:52:35 INFO - Installing collected packages: blobuploader, requests, docopt 20:52:35 INFO - Running setup.py install for blobuploader 20:52:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:52:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:52:35 INFO - Running setup.py install for requests 20:52:36 INFO - Running setup.py install for docopt 20:52:36 INFO - Successfully installed blobuploader requests docopt 20:52:36 INFO - Cleaning up... 20:52:36 INFO - Return code: 0 20:52:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:52: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')]} 20:52:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:52:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:52: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')]} 20:52:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:52:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:52:36 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 0x104b28b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b2b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9188dd5020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104906d30>, '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 0x10482ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048fbbe8>, '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.HmrRupATDG/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.cmvejtSKf8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 20:52:36 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 20:52:36 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 20:52:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HmrRupATDG/Render', 20:52:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:52:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:52:36 INFO - 'HOME': '/Users/cltbld', 20:52:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:52:36 INFO - 'LOGNAME': 'cltbld', 20:52:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:52:36 INFO - 'MOZ_NO_REMOTE': '1', 20:52:36 INFO - 'NO_EM_RESTART': '1', 20:52:36 INFO - 'PAGER': '/bin/cat', 20:52:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:52:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:52:36 INFO - 'PWD': '/builds/slave/test', 20:52:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:52:36 INFO - 'SHELL': '/bin/bash', 20:52:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cmvejtSKf8/Listeners', 20:52:36 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 20:52:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:52:36 INFO - 'USER': 'cltbld', 20:52:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:52:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:52:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:52:36 INFO - 'XPC_FLAGS': '0x0', 20:52:36 INFO - 'XPC_SERVICE_NAME': '0', 20:52:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:52:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:52:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:52:36 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-Z7t4On-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:52:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:52:36 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-FNE8ca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:52:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:52:36 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-OhzK_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:52:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:52:37 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-B1CBVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:52:37 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-k46Tlc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:52:37 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-2v2CxI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:52:37 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-ag1fvG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:52:37 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-TKTzeq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:52:37 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-igJg91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:52:37 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-heNOTI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:52:37 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-R3AIkG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:52:37 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-44odpM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:52:37 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-Xh8nPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:52:38 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-9r1GAc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:52:38 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-B5T6K_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:52:38 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-iPe6_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:52:38 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-o8iEIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:52:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:52:38 INFO - Running setup.py install for manifestparser 20:52:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:52:38 INFO - Running setup.py install for mozcrash 20:52:38 INFO - Running setup.py install for mozdebug 20:52:38 INFO - Running setup.py install for mozdevice 20:52:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:52:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:52:39 INFO - Running setup.py install for mozfile 20:52:39 INFO - Running setup.py install for mozhttpd 20:52:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:52:39 INFO - Running setup.py install for mozinfo 20:52:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:52:39 INFO - Running setup.py install for mozInstall 20:52:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:52:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:52:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:52:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:52:39 INFO - Running setup.py install for mozleak 20:52:39 INFO - Running setup.py install for mozlog 20:52:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:52:39 INFO - Running setup.py install for moznetwork 20:52:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:52:40 INFO - Running setup.py install for mozprocess 20:52:40 INFO - Running setup.py install for mozprofile 20:52:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:52:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:52:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:52:40 INFO - Running setup.py install for mozrunner 20:52:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:52:40 INFO - Running setup.py install for mozscreenshot 20:52:40 INFO - Running setup.py install for moztest 20:52:40 INFO - Running setup.py install for mozversion 20:52:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:52:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:52:41 INFO - Cleaning up... 20:52:41 INFO - Return code: 0 20:52:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:52:41 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')]} 20:52:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:52:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:52:41 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')]} 20:52:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:52:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:52:41 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 0x104b28b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b2b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9188dd5020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104906d30>, '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 0x10482ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048fbbe8>, '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.HmrRupATDG/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.cmvejtSKf8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 20:52:41 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 20:52:41 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 20:52:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HmrRupATDG/Render', 20:52:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:52:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:52:41 INFO - 'HOME': '/Users/cltbld', 20:52:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:52:41 INFO - 'LOGNAME': 'cltbld', 20:52:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:52:41 INFO - 'MOZ_NO_REMOTE': '1', 20:52:41 INFO - 'NO_EM_RESTART': '1', 20:52:41 INFO - 'PAGER': '/bin/cat', 20:52:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:52:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:52:41 INFO - 'PWD': '/builds/slave/test', 20:52:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:52:41 INFO - 'SHELL': '/bin/bash', 20:52:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cmvejtSKf8/Listeners', 20:52:41 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 20:52:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:52:41 INFO - 'USER': 'cltbld', 20:52:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:52:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:52:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:52:41 INFO - 'XPC_FLAGS': '0x0', 20:52:41 INFO - 'XPC_SERVICE_NAME': '0', 20:52:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:52:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:52:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:52:41 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-rtm9Ko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:52:41 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)) 20:52:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:52:41 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-_zsDft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:52:41 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)) 20:52:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:52:41 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-8jGbwF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:52:41 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)) 20:52:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:52:41 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-47W9fS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:52:41 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)) 20:52:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:52:41 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-wa0s7N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:52:41 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)) 20:52:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:52:41 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-Lfusym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:52:41 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)) 20:52:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:52:41 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-equxhO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:52:42 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)) 20:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:52:42 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-kQYRe7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:52:42 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)) 20:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:52:42 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-L7_tF4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:52:42 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)) 20:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:52:42 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-lcvqSu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:52:42 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)) 20:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:52:42 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-WXpPcI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:52:42 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)) 20:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:52:42 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-vBTsfy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:52:42 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)) 20:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:52:42 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-FWZuWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:52:42 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)) 20:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:52:42 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-11JOlO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:52:42 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)) 20:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:52:42 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-VllsHd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:52:42 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)) 20:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:52:42 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-sI_REY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:52:43 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)) 20:52:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:52:43 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-pTaBfm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:52:43 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)) 20:52:43 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)) 20:52:43 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)) 20:52:43 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)) 20:52:43 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)) 20:52:43 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)) 20:52:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:52:43 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 20:52:43 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 20:52:43 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 20:52:43 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 20:52:43 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 20:52:43 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 20:52:45 INFO - Downloading blessings-1.6.tar.gz 20:52:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:52:45 INFO - Installing collected packages: blessings 20:52:45 INFO - Running setup.py install for blessings 20:52:46 INFO - Successfully installed blessings 20:52:46 INFO - Cleaning up... 20:52:46 INFO - Return code: 0 20:52:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:52:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:52:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:52:46 INFO - Reading from file tmpfile_stdout 20:52:46 INFO - Current package versions: 20:52:46 INFO - blessings == 1.6 20:52:46 INFO - blobuploader == 1.2.4 20:52:46 INFO - docopt == 0.6.1 20:52:46 INFO - functools32 == 3.2.3-2 20:52:46 INFO - jsonschema == 2.5.1 20:52:46 INFO - manifestparser == 1.1 20:52:46 INFO - mozInstall == 1.12 20:52:46 INFO - mozcrash == 1.0 20:52:46 INFO - mozdebug == 0.1 20:52:46 INFO - mozdevice == 0.49 20:52:46 INFO - mozfile == 1.2 20:52:46 INFO - mozhttpd == 0.7 20:52:46 INFO - mozinfo == 0.9 20:52:46 INFO - mozleak == 0.1 20:52:46 INFO - mozlog == 3.4 20:52:46 INFO - moznetwork == 0.27 20:52:46 INFO - mozprocess == 0.24 20:52:46 INFO - mozprofile == 0.28 20:52:46 INFO - mozrunner == 6.13 20:52:46 INFO - mozscreenshot == 0.1 20:52:46 INFO - mozsystemmonitor == 0.3 20:52:46 INFO - moztest == 0.8 20:52:46 INFO - mozversion == 1.4 20:52:46 INFO - psutil == 3.1.1 20:52:46 INFO - requests == 1.2.3 20:52:46 INFO - wsgiref == 0.1.2 20:52:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:52:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:52:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:52:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:52:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:52:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:52:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:52:47 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 0x104b28b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b2b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9188dd5020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104906d30>, '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 0x10482ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048fbbe8>, '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.HmrRupATDG/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.cmvejtSKf8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 20:52:47 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 20:52:47 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 20:52:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HmrRupATDG/Render', 20:52:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:52:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:52:47 INFO - 'HOME': '/Users/cltbld', 20:52:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:52:47 INFO - 'LOGNAME': 'cltbld', 20:52:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:52:47 INFO - 'MOZ_NO_REMOTE': '1', 20:52:47 INFO - 'NO_EM_RESTART': '1', 20:52:47 INFO - 'PAGER': '/bin/cat', 20:52:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:52:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:52:47 INFO - 'PWD': '/builds/slave/test', 20:52:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:52:47 INFO - 'SHELL': '/bin/bash', 20:52:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cmvejtSKf8/Listeners', 20:52:47 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 20:52:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:52:47 INFO - 'USER': 'cltbld', 20:52:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:52:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:52:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:52:47 INFO - 'XPC_FLAGS': '0x0', 20:52:47 INFO - 'XPC_SERVICE_NAME': '0', 20:52:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:52:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:52:47 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)) 20:52:47 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)) 20:52:47 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)) 20:52:47 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)) 20:52:47 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)) 20:52:47 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)) 20:52:47 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)) 20:52:47 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)) 20:52:47 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)) 20:52:47 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)) 20:52:47 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)) 20:52:47 INFO - Cleaning up... 20:52:47 INFO - Return code: 0 20:52:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:52:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:52:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:52:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:52:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:52:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:52:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:52:47 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 0x104b28b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b2b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9188dd5020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104906d30>, '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 0x10482ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048fbbe8>, '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.HmrRupATDG/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.cmvejtSKf8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 20:52:47 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 20:52:47 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 20:52:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HmrRupATDG/Render', 20:52:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:52:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:52:47 INFO - 'HOME': '/Users/cltbld', 20:52:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:52:47 INFO - 'LOGNAME': 'cltbld', 20:52:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:52:47 INFO - 'MOZ_NO_REMOTE': '1', 20:52:47 INFO - 'NO_EM_RESTART': '1', 20:52:47 INFO - 'PAGER': '/bin/cat', 20:52:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:52:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:52:47 INFO - 'PWD': '/builds/slave/test', 20:52:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:52:47 INFO - 'SHELL': '/bin/bash', 20:52:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cmvejtSKf8/Listeners', 20:52:47 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 20:52:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:52:47 INFO - 'USER': 'cltbld', 20:52:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:52:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:52:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:52:47 INFO - 'XPC_FLAGS': '0x0', 20:52:47 INFO - 'XPC_SERVICE_NAME': '0', 20:52:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:52:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:52:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:52:48 INFO - Cleaning up... 20:52:48 INFO - Return code: 0 20:52:48 INFO - Running post-action listener: _resource_record_post_action 20:52:48 INFO - Running post-action listener: _start_resource_monitoring 20:52:48 INFO - Starting resource monitoring. 20:52:48 INFO - [mozharness: 2017-04-04 03:52:48.089172Z] Finished create-virtualenv step (success) 20:52:48 INFO - [mozharness: 2017-04-04 03:52:48.090097Z] Running install step. 20:52:48 INFO - Running pre-action listener: _resource_record_pre_action 20:52:48 INFO - Running main action method: install 20:52:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:52:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:52:48 INFO - Reading from file tmpfile_stdout 20:52:48 INFO - Detecting whether we're running mozinstall >=1.0... 20:52:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:52:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:52:48 INFO - Reading from file tmpfile_stdout 20:52:48 INFO - Output received: 20:52:48 INFO - Usage: mozinstall [options] installer 20:52:48 INFO - Options: 20:52:48 INFO - -h, --help show this help message and exit 20:52:48 INFO - -d DEST, --destination=DEST 20:52:48 INFO - Directory to install application into. [default: 20:52:48 INFO - "/builds/slave/test"] 20:52:48 INFO - --app=APP Application being installed. [default: firefox] 20:52:48 INFO - mkdir: /builds/slave/test/build/application 20:52:48 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'] 20:52:48 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 20:53:03 INFO - Reading from file tmpfile_stdout 20:53:03 INFO - Output received: 20:53:03 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 20:53:03 INFO - Running post-action listener: _resource_record_post_action 20:53:03 INFO - [mozharness: 2017-04-04 03:53:03.492215Z] Finished install step (success) 20:53:03 INFO - [mozharness: 2017-04-04 03:53:03.492342Z] Running run-tests step. 20:53:03 INFO - Running pre-action listener: _resource_record_pre_action 20:53:03 INFO - Running main action method: run_tests 20:53:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:53:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:53:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:53:03 INFO - 2017-04-03 20:53:03.506 screenresolution[1623:7399] starting screenresolution argv=screenresolution get 20:53:03 INFO - 2017-04-03 20:53:03.509 screenresolution[1623:7399] Display 0: 1600x1200x32@60 20:53:03 INFO - 2017-04-03 20:53:03.515 screenresolution[1624:7401] starting screenresolution argv=screenresolution list 20:53:03 INFO - Available Modes on Display 0 20:53:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:53:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:53:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:53:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:53:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:53:03 INFO - Intel Iris: 20:53:03 INFO - Chipset Model: Intel Iris 20:53:03 INFO - Type: GPU 20:53:03 INFO - Bus: Built-In 20:53:03 INFO - VRAM (Dynamic, Max): 1536 MB 20:53:03 INFO - Vendor: Intel (0x8086) 20:53:03 INFO - Device ID: 0x0a2e 20:53:03 INFO - Revision ID: 0x0009 20:53:03 INFO - Displays: 20:53:03 INFO - Display: 20:53:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:53:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:53:03 INFO - Display Serial Number: 1600x1200 60 20:53:03 INFO - Main Display: Yes 20:53:03 INFO - Mirror: Off 20:53:03 INFO - Online: Yes 20:53:03 INFO - Rotation: Supported 20:53:03 INFO - Return code: 0 20:53:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:53:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:53:03 INFO - Python 2.7.3 20:53:03 INFO - Return code: 0 20:53:03 INFO - grabbing minidump binary from tooltool 20:53:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:53:03 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 0x104906d30>, '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 0x10482ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048fbbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:53:03 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 20:53:03 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 20:53:03 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 20:53:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:53:03 INFO - Return code: 0 20:53:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:53:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:53:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:53:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:53:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:53:03 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/UrDvt7ceTcu0evu2vIspZQ/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0129', '--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 20:53:03 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/UrDvt7ceTcu0evu2vIspZQ/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0129 --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 20:53:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HmrRupATDG/Render', 20:53:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:53:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:53:03 INFO - 'HOME': '/Users/cltbld', 20:53:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:53:03 INFO - 'LOGNAME': 'cltbld', 20:53:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:53:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:53:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:53:03 INFO - 'MOZ_NO_REMOTE': '1', 20:53:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:53:03 INFO - 'NO_EM_RESTART': '1', 20:53:03 INFO - 'PAGER': '/bin/cat', 20:53:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:53:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:53:03 INFO - 'PWD': '/builds/slave/test', 20:53:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:53:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:53:03 INFO - 'SHELL': '/bin/bash', 20:53:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cmvejtSKf8/Listeners', 20:53:03 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 20:53:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:53:03 INFO - 'USER': 'cltbld', 20:53:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:53:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:53:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:53:03 INFO - 'XPC_FLAGS': '0x0', 20:53:03 INFO - 'XPC_SERVICE_NAME': '0', 20:53:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:53:03 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/UrDvt7ceTcu0evu2vIspZQ/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0129', '--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 20:53:04 INFO - mozversion application_buildid: 20170403194027 20:53:04 INFO - mozversion application_changeset: 42024440f9452f6c71d86ee6cfd22ab4b677793b 20:53:04 INFO - mozversion application_display_name: Firefox 20:53:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:53:04 INFO - mozversion application_name: Firefox 20:53:04 INFO - mozversion application_remotingname: firefox 20:53:04 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 20:53:04 INFO - mozversion application_vendor: Mozilla 20:53:04 INFO - mozversion application_version: 53.0 20:53:04 INFO - mozversion platform_buildid: 20170403194027 20:53:04 INFO - mozversion platform_changeset: 42024440f9452f6c71d86ee6cfd22ab4b677793b 20:53:04 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 20:53:04 INFO - mozversion platform_version: 53.0 20:53:04 INFO - using testdate: 1491277984 20:53:04 INFO - actual date: 1491277984 20:53:04 INFO - starting webserver on 'localhost:49254' 20:53:04 INFO - SUITE-START | Running 2 tests 20:53:04 INFO - TEST-START | dromaeo_css 20:53:04 INFO - operating with platform_type : mac_ 20:53:04 INFO - Initialising browser for dromaeo_css test... 20:53:04 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/tmpm2IOYq/profile http://localhost:49254/getInfo.html) 20:53:06 INFO - PROCESS | 1634 | 2017-04-03 20:53:06.971 plugin-container[1637:7566] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3b, name = 'com.apple.tsm.portname' 20:53:06 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:53:06 INFO - PROCESS | 1634 | 2017-04-03 20:53:06.971 plugin-container[1637:7566] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 20:53:06 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:53:06 INFO - PROCESS | 1634 | 2017-04-03 20:53:06.972 plugin-container[1637:7566] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:53:07 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 20:53:07 INFO - PROCESS | 1634 | colorDepth:24 20:53:07 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 20:53:07 INFO - PROCESS | 1634 | __metrics 20:53:07 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 20:53:07 INFO - TEST-INFO | 1634: exit 0 20:53:07 INFO - Browser initialized. 20:53:07 INFO - Running cycle 1/1 for dromaeo_css test... 20:53:07 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/tmpm2IOYq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:53:10 INFO - PROCESS | 1638 | 2017-04-03 20:53:10.470 plugin-container[1639:7692] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 20:53:10 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:53:10 INFO - PROCESS | 1638 | 2017-04-03 20:53:10.471 plugin-container[1639:7692] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 20:53:10 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:53:10 INFO - PROCESS | 1638 | 2017-04-03 20:53:10.471 plugin-container[1639:7692] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:53:20 INFO - PROCESS | 1638 | RSS: Main: 173219840 20:53:20 INFO - PROCESS | 1638 | 20:56:21 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49254/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49254/tests/dromaeo/cssquery-ext.html) 20:56:23 INFO - PROCESS | 1638 | RSS: Main: 191991808 20:56:23 INFO - PROCESS | 1638 | 20:59:25 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49254/tests/dromaeo/cssquery-ext.html (next: http://localhost:49254/tests/dromaeo/cssquery-jquery.html) 20:59:26 INFO - PROCESS | 1638 | RSS: Main: 201629696 20:59:26 INFO - PROCESS | 1638 | 21:02:28 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49254/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49254/tests/dromaeo/cssquery-mootools.html) 21:02:29 INFO - PROCESS | 1638 | RSS: Main: 205901824 21:02:29 INFO - PROCESS | 1638 | 21:05:30 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49254/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49254/tests/dromaeo/cssquery-prototype.html) 21:05:31 INFO - PROCESS | 1638 | RSS: Main: 209928192 21:05:31 INFO - PROCESS | 1638 | 21:08:34 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49254/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49254/tests/dromaeo/cssquery-yui.html) 21:08:34 INFO - PROCESS | 1638 | RSS: Main: 211165184 21:08:34 INFO - PROCESS | 1638 | 21:11:37 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49254/tests/dromaeo/cssquery-yui.html (next: http://localhost:49254/tests/dromaeo/cssquery-dojo.html) 21:11:37 INFO - PROCESS | 1638 | RSS: Main: 211382272 21:11:37 INFO - PROCESS | 1638 | 21:11:37 INFO - PROCESS | 1638 | __start_tp_report 21:11:37 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 21:11:37 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 21:11:37 INFO - PROCESS | 1638 | |i|pagename|runs| 21:11:37 INFO - PROCESS | 1638 | |0;dojo.html;3014.5400785492775;3580.462930560416;3582.3372676054946;3600.981995090024;3605;6089.330173680897;6100.420460056299;6111.02223644217;6120.908186377208;6141.416565426289;6065.15087887695;6093.573449858512;6144.109104179891;6166.568340216187;6177.567570270083;5452;5498.917516237259;5505.6696598204035;5532.087205611069;5572.749226284806;5328.120860058086;5522.834314970537;5538.280023596927;5555.972220138894;5559.471850174227;4575.130725162225;5234.6859188448625;5411.918821217685;5433.320834895638;5543.390227075019;5471.616986810925;5486.149646804732;5552.555795536347;5582.692951887645;5636.859078523029;5398.64908780928;5507.807226747084;5513.8345849624575;5524.640898341595;5535.833924982257;5952.047672372443;6081.543884208658;6157.630542167484;6245.781397651106;6246.875062498724;5211.687298762086;5217.739113044333;6228.470580000705;6238.344973777779;6340.651264180468;5372.7045012524295;6422.133012043408;6445.484361251089;6450.7097180626515;6462.0306953956815;5169.663971841818;5869.559783016291;6238.376162383725;6252.249730032379;6260.593061451037;6178.598391104564;6183.072539119168;6497.935020649733;6512.413882750575;6529.673516324165;6273.811785646438;6306.65313407767;6310.9368906311265;6320.115183874262;6346.619202847844;6438.356164383618;6444.903326450107;6448.806535803933;6452.387023232785;6463.806085817433;6170.876582468325;6192.628442293477;6227.626342419469;6242.469390101802;6284.528660350492;6298.622082675054;6332.461740752087;6335.366463353628;6349.52378571609;6375.585586936834;5191.532762051434;5206.947930520722;5213.035588416155;5216.58267338612;5239.1093514102695;4490.640748740093;4566.086782643484;4627.398438203012;4671.346011558385;4691.812327506861;5086.64393492452;6170.753169873155;6184.474319682788;6217.502599792006;6231.813045608639;6486.318936511692;6502.349765023459;6506.512011599149;6512.706928188243;6522.21733391996;5913.290405151323;6108.190302030417;6112.1995365628145;6133.202683651097;6144.310314663503;5775.364709881909;5776.768929242783;5776.942230577724;5800.071988481823;5807.128930660435;6586.8353291168105;6639.236487803868;6672.9656903180085;6687.465002799861;6688.598684078971;6258.374162583706;6270.6864656766065;6331.9050214247745;6358.395952384516;6432.195975503062;6564.573302735307;6582.407583317525;6586.0450234714735;6586.37429444202;6590.1103351046995;6337.334579869232;6338.714757835816;6349.14286571307;6353.7140828661895;6370.140031095742;6312.210973628296;6333.65164915934;6361.109444677656;6409.8397540060005;6434.356564343529;6132.875646117223;6282.685865706788;6304.6847657617855;6310.652914089769;6316.526260530387;5101.311322971352;6278.623282603058;6307.180066591313;6310.5898116622275;6310.6529140895855;6097.817065487865;6169;6191.597546159485;6218.253809542703;6247.375262473716;6134.620279381683;6185.752569897334;6225.657588832477;6289.559730818799;6297.4647154992335;5154.5103215194495;5884.911726324023;6155.692215389123;6209.4101060401;6235.532335074979 21:11:37 INFO - PROCESS | 1638 | |1;ext.html;505.030341743852;505.2270026858905;510.27286117282875;522.9137192363261;592.6325678079592;40923.386149207785;41757.373639395395;43013.92465188368;43116.70649880505;43275.7017289481;44487;44654.33018504725;44678.88302792432;44784.776076119655;45654.315185272164;9583.089606487372;9848.458334791583;9850.507474626276;11569.247998880088;11933.16467847251;11793.7641247175;11851.7629647407;11871;11901.988330991877;11905.642830715093;11894.82157767634;11914.46384912681;11946.283223006603;11960.222585531956;11961.2823230606;11605.013573846234;11713;11807.291562506234;11878.940605296919;11895.643130706092;11745.4714537846;11754;11759.764804703858;11829.645110646608;11842.23025503348;38314.42528362077;38482.42276365857;38587.8070609648;38650.613493864985;38708;4037.798110094513;4044.1709449563064;4059.5940405959464;4089.7955102245064;4140.358244472111;27860.442791144265;28049.85975070132;28519.429611407657;29597.81608735648;30373.848130759427;3008.4885569453245;3228.4027454920915;3525.048236976002;3526.8236588170744;3532.028692109665;383546;487463;497729;498850;499323.50338248076;59150.70424647849;59439.40560594425;59446.108308375406;59667;59667.403325967054;60497.69751151304;61225.08162377568;61813.690931545956;61904.69047654823;62130;16721.749173762404;16815.831841681673;16969;16991.235394407282;17017.149142542825;11942.761144777056;12242.877571224173;12914.612561623166;13219.537316194079;13268;18241;18737;18834.434966951012;18880.150393232336;18986.52533686669;8407.915920840713;8476.49141051539;8480.024797148284;8497.66009359631;8506;897.7261935109724;901.5537309032071;906.918377346042;933.3233405780754;943.7168849345169;26911;31158.844205778827;31703.84148079291;32178.83910580432;32503.34993300121;896.852019416802;1027.4605831938293;1101.9311268070119;1136.1592421608116;1230.0467137967967;12908.677283067998;14622.415103395957;14965.551033469013;15297;15306.923465382602;684369;733589;741724;743276.2836185893;984931;45403.77298113488;56159.71920140373;56414;56920;56958.7152064237;1452.3682198243798;1463.1367493179075;1471.8380978092616;1488.3302513868803;1489.6350394153285;26509.734902650725;28687.426251474437;29798;30390.544141837447;30601.693983059886;42465.36301955411;47153.2927006102;47827.28259076186;47962.760186198844;48112.5188748122;598311;626943;631518;634021.8298908476;634033;24035;24533.38666533351;26353.20940371792;26668.199954000636;26681.733182667926;43156.78421607872;43263.918402038944;44035.11929761451;45562.31656525088;45993.850153746425;30511.389772204875;30755.847090703435;31125;31208.843955780983;31302.06093817189;29942;30482.39035219239;31074.757009720262;31335.05994820159;31350.843245783624;33702.3259534803;35470.46794298036;36148.63851361558;36354;36441.90674279776;29831.850840745657;30411.695883040888;30847.38305233838;31494.370112598077;31562.526562101128;24572.877135614206;24689.382765431008;24751.133710320053;24968.750312496642;25175 21:11:37 INFO - PROCESS | 1638 | |2;jquery.html;381.3269579192725;398.83847041948;404.08273219791096;410.46434403103945;432.6906262022653;20624.484387890312;21014;21433.14267429305;21765.455863603416;21952;18589;18605.441836744885;22062.448438789037;22468.43828904278;22936.426589335275;5830.4169583041785;6163.815085547419;6177.814665560018;6180.629162250257;6201.224846894138;5901.114832775075;6047.667378294192;6052.425019623129;6113.755449782004;6138.14066030756;5967.16459695643;6061.878762424749;6074.240719910012;6074.939250607482;6080.391960803906;5764.365919748845;5987.760489580433;6029.69851507425;6066;6074.787382441637;5325.866853328698;5332.466753324676;5340.118880384732;5343.812966546152;5347.438518955492;25937.351566210808;26884.73115268842;26951.32621684454;27112;27210.591841122397;2803.6775770786417;2826.915192544227;2830.6178665880257;2833.107571115092;2834.107256214286;14002.649933751738;15609.531714048597;16351.59121021972;16439.26023328953;16635.6672866542;1910.8566857485744;1923.778765441978;1957.412776167144;2079.0852025109057;2089.937301880946;106430.46784766104;130680.34659826735;140433.29783351018;147167.26416367956;147839;12305.323207223688;12494.000479961582;12497.687557811127;12580.937095314648;12684.936575317064;16117.194140293173;16240.75638865418;16556.83443165553;16592.419265325654;16596.751048734037;9101.863472047924;9217.907820921839;9268.397554158959;9338.439693618404;9355.90644093564;7523.435742319348;8845.867311990325;9110.453372797654;9115.726528204164;9205.125513076247;3937.8621748239248;4305.978470107692;4335.631471324973;4895.583875370562;4904.190808516626;2120.35329224588;2135.370065830584;2162.5458653682904;2164.9567008659737;2183.748868880067;897.8832751742362;1058.867641544807;1073.4632683658172;1078.8975047370486;1079.384750692098;18299.268029278945;19208.807911920703;20788;22300.776992230196;22983.770162298497;1017.5014243020867;1070.2294348069377;1101.531848964187;1111.1777284809184;1120.5797825815318;11071.944640276746;11525.366104864146;11709;11716.297022178696;11720.003799676952;734438;760178;773920;791554;797104;137188.6281137176;148146;165886.1705691488;168510;170786;1005.1707341443192;1054.0882136951482;1078.9514471848786;1081.6538707613645;1083.9566417343376;19262.614747704687;19879;20315.898420508394;20790.27234046801;20826.37520874376;64272.67863660652;64393;64821.67589162024;64952;65058.67470662617;92156;94555;95509.52245238729;95540;96281.03718962721;5887.58786884931;6725.495587830835;8069.708846584518;8331;8962.641494340154;10030.498475076363;13045.282509462071;13129.34353282313;13448.93275533616;13604;7332.633368331455;9332.766680832761;11230.55077796879;11402;11892;10330.276880618285;10701.89298107009;10743.516541755796;10755.677329680122;11135.610253641084;10660.25378223517;11418.657440276806;11439.428028598702;11566.47950842231;12024.278543287432;8388.328933685412;8446.239838414573;8535.06114327411;8611.82776344482;8624.137586241326;11162.386068766296;11520.654380368602;11652.766944661229;11670.591529296435;11683.707907302385 21:11:37 INFO - PROCESS | 1638 | |3;mootools.html;1460.0363759918455;1473.0793254216107;1501.7897494350793;1502.2188461999767;1506.435086842434;7797.337226335754;8030.678772849094;8262.049864265604;8658.220760131588;8667.913320866797;8209.589520523965;8466.788330291745;8499.490030598168;8507.95746021269;8563;2377.2986968844148;2397.880105994702;2401.339631601315;2409.5060512594964;2412.9879350603223;2379.393254720044;2405.819563532742;2407.518496300738;2421.8183636227263;2433.4646377796857;2370.9644355334685;2384.308550520347;2384.7734465225776;2395.688560487134;2401.795847352977;1990.2536548794203;2359.5988681924027;2363.279199844047;2370.656254843055;2400.4958958618713;1979.772326182493;2326.8022218111482;2338.660894170353;2396.6884305040408;2425.1390756281444;6988.720451181946;7588.962055189744;8478.491290522588;8563.35774816885;8588.914110858874;5090.9236361454605;5213.817516386946;5230.320058392384;5263.078961181816;5274.208868669692;3150.771199232301;3512.4204506256438;3512.683858452726;3523.735719820998;3559.9110022249392;1179.6637958181877;1241.8326772833507;1277.993610031944;1308.725167714781;1309.9410526526328;9844.818621765435;10532.104771094466;11004.834927476095;11015;11019.614313499069;4488.753118578445;4741.952580474152;4766.976165119152;4785.1625965455905;4856.80572777092;9717.805643887083;9759.414435133915;9783.657571985088;9787.16809071237;9808.068233517804;5384.892302154013;5398.865028374322;5407.405185429599;5412.945870541244;5420.674759514442;4433.290673492225;4528.43394575678;4911.852644420673;4939.654224204343;4962.925556116661;3225.59680039995;3790.2419516096893;3883.3980732986433;3904.2581909437117;3935.9803200984384;1299.564645843634;1307.9149855259377;1316.6510874618234;1324.5959982205327;1332.4137379552965;910.3854897943861;928.252756530999;933.8272419602396;939.3143005605946;959.4003747657714;5626.521745651666;5672.319321681424;5734.541236701054;5776.277965254343;5854.151148083504;951.438651195784;983.1102851919024;983.3165949665057;994.39838897466;1012.7265638277623;3319.3195394943978;3709.406494960793;3878.1855810279594;3920.784356860343;3924.4309575111447;9828.655997040212;10077.69766906994;10088.848667269996;10127.848082278771;10137.2903896728;9801.803963920682;9839.065288797552;10058.899411005796;10059.044390782863;10098.091171794575;7817.374610031184;8133.349332053422;8143.633536490872;8173.591320433955;8181.713640022571;5523.585731070106;5564.221009058654;5589.5807814413265;5662.433756624305;5702.6863522505;9292.16370526656;9417.434953902513;9427.387219830689;9430.905690943002;9455.338126331091;9118.316126290421;9367.765805854908;9450.338476306868;9515.952420237854;9545.140937315675;6112.327643959072;6848.623325717133;6965.721371145098;7400.6669699864715;7550.433717471102;8058.677652894053;8282;8290.751277461686;8298.128696487023;8315.584220789058;5958;7017.719291228498;7177.820554485971;7183.102112235971;7239.601821899846;6030.698465076641;7008.7546935857;7014.2635023323855;7076.540024898365;7080.327368899946;6397.584157029778;6435.581687190318;6516.804495865132;6524.151860258136;6526.5105117117355;5055.51972562606;5074.5686616637995;5088.236764485208;5100.183970564692;5100.69395836251;4962.6774259673;4965.677230979974;5044.747762611928;5087.796488140581;5168.01807656544 21:11:37 INFO - PROCESS | 1638 | |4;prototype.html;336.00877411635673;354.81017655554274;375.47246119202515;380.9314323421783;382.2870346803211;4689.249720044793;5317.335333083364;5546.77812887484;5549.112142057272;5553.777848886045;4814.63890208234;4908.901821963558;5026.868954485239;5446.385734847276;5449.6730196188255;3708.295423869463;3876.8061596920184;3922.705797065217;4156.916861662765;4386;3721.2929543386804;3852.61305929865;4297.205017071852;4312.956870431302;4377.212101821687;3818;4331.978340108295;4367;4382.868513944587;4415.646748260131;4259.403683484315;4259.91480170398;4293.763842988635;4393.450818647669;4418.204723149848;3795.962040379616;3813.656770890633;3819.4652748615217;3834.559025712051;3864.942025869614;4984.327115839352;5410.375048717368;5411.783528658849;5450.31871016123;5475.288212532385;4472.217361961643;4551.908961820745;5049.267856160873;5053.620978426633;5058.974705126451;4672.742999135046;5128.871778205537;5890.852728681817;5916.881662366729;5947.345791962881;4264.87205383839;4284.635805956497;4308.858152589566;4309.491677912737;4313.972432956505;5121.718305493197;5187.948120518822;6065.63440258304;6162.414570615784;6266.803929995735;3645.8359373828243;4166.8749937501925;4241.512226094039;4291.592298731615;4347.282698354736;4394.406755088086;4406.5273747146675;4425.402570652921;4426.993570818415;4456.22016147173;4099.344104943195;4712.340272361872;4894.730789806595;5002.1496345621335;5060.746962651926;4676.649251306166;4934.605231581465;5876.7355469004;5977.6114552553945;6021.307549631761;1257.4278703190027;1441.978370324446;1442.725882082401;1461.2620626583507;1482.6589884326665;852.4927668037508;972.7179118055826;977.5277023664572;1029.4492446541065;1038.382162613244;4408.779561022;4718.41019872516;5234.528892399702;5300.231466437345;5316.73416329182;4608.631309495232;5366.6118577232755;5803;5804.952399989955;5813.360530341659;5081.441041485434;5130.974345128325;5182.507661772125;5203.453637368098;5335.493128152819;2779.9158328251997;2807.8315301081593;2921.13826421202;2931.9120426387244;3041.6654168041496;5088.949110508922;6007.918574656604;6127.824622259123;6137.5703700741415;6138.202033735675;4834.5890599299455;5496.422875598085;5609.49514543693;5770.336411312753;6072.969635151884;6026.939730602726;6041.456268935817;6075;6075.726592303268;6097.420745029126;5165.741712914264;5425.755840987244;5810.448007439287;5841.707914604338;5927.4665280125;4743.335932969456;5351.919721204107;5671.914921276267;5767.423257674199;5817.185594016925;5266.97366513165;5784.334801497882;6075.7569697213385;6122.785702500391;6316.842078948063;3668.3030224257304;4357.586029327335;4364.498082720401;4388.978055109704;4391.451068616423;5296.602754793329;5378.247045413724;5869.006806528787;5893.5874488785375;6134.78528251509;5257.710825904612;5421.512063914267;5744.931824543245;5821.76712931495;5855.970720146372;5627.099664053731;5847.742735311928;5847.795327163529;5886.23478947734;5988.784149864404;4860.975695121501;5236.6857988520815;5280.392754833243;5706.258186435737;5771.538276937918;5486.149646804752;5644.955683198621;5821.621594596338;5847.795327163529;5869.530437564899;4988.226824842155;5712.0860662293835;5741;5804.65172089676;5882.529367658086 21:11:37 INFO - PROCESS | 1638 | |5;yui.html;682.8054004608684;703.711478293899;772.1892013385946;778.9298963093324;868.5483548554749;13120.606381808537;13420.865791342083;16191.514254572367;16194.190290485458;16234.675306493864;14834.406623735038;14972.775408368883;14994.550163495085;15000.174990375524;15057.849421505782;694.4652617484534;716.4590733995826;847.8261956298975;853.1298075962515;871.2202578692066;860.5525126933991;869.9565021748919;876.1676407412951;876.2989608313324;879.0111124984393;769.3960241210651;928.1646518133666;932.3194068330123;935.242453612576;936.6487567162314;918.9127032931827;921.7280902133853;925.1303774452061;928.9071092890724;932.0493097041015;681.4344094401634;684.6953105867891;688.3770187979886;689.4691087862318;690.9481788865855;15858.682826343524;16052.51842444717;16203.513894583182;16390.590235244214;16770.74843877343;12394.3183124929;14843.332050057774;14980.475683351031;15013;15039.323230454656;11752.823707644393;11932.16474846761;11951.282923024559;11974.58088966882;12508.937455312665;3863.497745293122;3873.2640798248244;3921.627445392683;3922.607739226083;4054.5945405459515;119718.99999999913;120368;123376;125635.37182314122;125830.37084814609;10928.289661172157;11058.576569372315;11123.388213648164;11431.31412115259;11671.12466565011;21645.67531487029;21654.242101526685;22171.66742498864;22413.887930560242;22459;7574.394048476109;7584.772456826304;7631.503952243086;7650.158482566913;7651.502652327581;4429.867103986886;4443.400140980991;4523.95476045242;4556.749378784132;4588.38056862326;3704.3517384457614;3755.7370984030854;3772.245550889833;3795.905102372463;3819.598942111094;681.2271976745312;688.6763221286087;694.9409300209538;698.4586945117474;701.7754318618096;499.7825945713626;590.282806390238;707.4305184326666;714.2750108639735;730.82825535999;10242.129418999468;10728.89271107279;11063.668089957153;11078.335299882034;11079.944600276947;521.8173639226317;606.8634557224677;614.9016157414792;656.2485953583216;737.4616529933179;5033.421156566968;5098.827537035588;5173.896522069537;5247.1866860636665;5249.737513124329;109789.90210097957;110875;113129;113434.43282783534;113930.43034784772;73536;97430;99179.02462926124;99291.50354248182;100827.99172008333;83666.16333836583;84560;85722;86749.69875451873;87720.56139719389;9936.453495057842;10104.94947525287;10131;10198.082172604501;10232.437215953194;52501.47498524966;52761.68095797636;53754;54573.727131365675;54599;52656;66731;69447;71655;71923;11805.82291265649;12078.818817717565;12080.21478603888;12222;12524.624261272176;12140.635780926586;12274.14081014356;12643.718155834473;15342.539723808304;15491;11627.88372116268;14116.858831411555;14154.433822646979;14518.709625807214;14532.346044428237;13507;13862;13958.232297223343;14267.571972840831;14716.705665886837;13384.531541396005;13850.792238116637;14042.929785351007;14260.42958281657;14501.637459063608;10232.386056836614;10610.8408373876;10718.14254859625;10829.350238985686;10950.123990080934;9811.264155188532;10092.142167915516;10135.239857010902;10183.287169898036;11205.71985700364 21:11:37 INFO - PROCESS | 1638 | __end_tp_report 21:11:37 INFO - PROCESS | 1638 | __start_cc_report 21:11:37 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,378 21:11:37 INFO - PROCESS | 1638 | __end_cc_report 21:11:37 INFO - PROCESS | 1638 | __startTimestamp1491279097854__endTimestamp 21:11:37 INFO - PROCESS | 1638 | 21:11:37 INFO - PROCESS | 1638 | ------- Summary: start ------- 21:11:37 INFO - PROCESS | 1638 | Number of tests: 6 21:11:37 INFO - PROCESS | 1638 | 21:11:37 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5940.14 Median:6185.75 stddev:657.12 (10.6%) stddev-sans-first:617.75 21:11:37 INFO - PROCESS | 1638 | Values: 3014.5 3580.5 3582.3 3601.0 3605.0 6089.3 6100.4 6111.0 6120.9 6141.4 6065.2 6093.6 6144.1 6166.6 6177.6 5452.0 5498.9 5505.7 5532.1 5572.7 5328.1 5522.8 5538.3 5556.0 5559.5 4575.1 5234.7 5411.9 5433.3 5543.4 5471.6 5486.1 5552.6 5582.7 5636.9 5398.6 5507.8 5513.8 5524.6 5535.8 5952.0 6081.5 6157.6 6245.8 6246.9 5211.7 5217.7 6228.5 6238.3 6340.7 5372.7 6422.1 6445.5 6450.7 6462.0 5169.7 5869.6 6238.4 6252.2 6260.6 6178.6 6183.1 6497.9 6512.4 6529.7 6273.8 6306.7 6310.9 6320.1 6346.6 6438.4 6444.9 6448.8 6452.4 6463.8 6170.9 6192.6 6227.6 6242.5 6284.5 6298.6 6332.5 6335.4 6349.5 6375.6 5191.5 5206.9 5213.0 5216.6 5239.1 4490.6 4566.1 4627.4 4671.3 4691.8 5086.6 6170.8 6184.5 6217.5 6231.8 6486.3 6502.3 6506.5 6512.7 6522.2 5913.3 6108.2 6112.2 6133.2 6144.3 5775.4 5776.8 5776.9 5800.1 5807.1 6586.8 6639.2 6673.0 6687.5 6688.6 6258.4 6270.7 6331.9 6358.4 6432.2 6564.6 6582.4 6586.0 6586.4 6590.1 6337.3 6338.7 6349.1 6353.7 6370.1 6312.2 6333.7 6361.1 6409.8 6434.4 6132.9 6282.7 6304.7 6310.7 6316.5 5101.3 6278.6 6307.2 6310.6 6310.7 6097.8 6169.0 6191.6 6218.3 6247.4 6134.6 6185.8 6225.7 6289.6 6297.5 5154.5 5884.9 6155.7 6209.4 6235.5 21:11:37 INFO - PROCESS | 1638 | 21:11:37 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:74353.96 Median:17703.62 stddev:172341.14 (973.5%) stddev-sans-first:172735.40 21:11:37 INFO - PROCESS | 1638 | Values: 505.0 505.2 510.3 522.9 592.6 40923.4 41757.4 43013.9 43116.7 43275.7 44487.0 44654.3 44678.9 44784.8 45654.3 9583.1 9848.5 9850.5 11569.2 11933.2 11793.8 11851.8 11871.0 11902.0 11905.6 11894.8 11914.5 11946.3 11960.2 11961.3 11605.0 11713.0 11807.3 11878.9 11895.6 11745.5 11754.0 11759.8 11829.6 11842.2 38314.4 38482.4 38587.8 38650.6 38708.0 4037.8 4044.2 4059.6 4089.8 4140.4 27860.4 28049.9 28519.4 29597.8 30373.8 3008.5 3228.4 3525.0 3526.8 3532.0 383546.0 487463.0 497729.0 498850.0 499323.5 59150.7 59439.4 59446.1 59667.0 59667.4 60497.7 61225.1 61813.7 61904.7 62130.0 16721.7 16815.8 16969.0 16991.2 17017.1 11942.8 12242.9 12914.6 13219.5 13268.0 18241.0 18737.0 18834.4 18880.2 18986.5 8407.9 8476.5 8480.0 8497.7 8506.0 897.7 901.6 906.9 933.3 943.7 26911.0 31158.8 31703.8 32178.8 32503.3 896.9 1027.5 1101.9 1136.2 1230.0 12908.7 14622.4 14965.6 15297.0 15306.9 684369.0 733589.0 741724.0 743276.3 984931.0 45403.8 56159.7 56414.0 56920.0 56958.7 1452.4 1463.1 1471.8 1488.3 1489.6 26509.7 28687.4 29798.0 30390.5 30601.7 42465.4 47153.3 47827.3 47962.8 48112.5 598311.0 626943.0 631518.0 634021.8 634033.0 24035.0 24533.4 26353.2 26668.2 26681.7 43156.8 43263.9 44035.1 45562.3 45993.9 30511.4 30755.8 31125.0 31208.8 31302.1 29942.0 30482.4 31074.8 31335.1 31350.8 33702.3 35470.5 36148.6 36354.0 36441.9 29831.9 30411.7 30847.4 31494.4 31562.5 24572.9 24689.4 24751.1 24968.8 25175.0 21:11:37 INFO - PROCESS | 1638 | 21:11:37 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:42366.02 Median:12067.87 stddev:128606.40 (1065.7%) stddev-sans-first:128928.54 21:11:37 INFO - PROCESS | 1638 | Values: 381.3 398.8 404.1 410.5 432.7 20624.5 21014.0 21433.1 21765.5 21952.0 18589.0 18605.4 22062.4 22468.4 22936.4 5830.4 6163.8 6177.8 6180.6 6201.2 5901.1 6047.7 6052.4 6113.8 6138.1 5967.2 6061.9 6074.2 6074.9 6080.4 5764.4 5987.8 6029.7 6066.0 6074.8 5325.9 5332.5 5340.1 5343.8 5347.4 25937.4 26884.7 26951.3 27112.0 27210.6 2803.7 2826.9 2830.6 2833.1 2834.1 14002.6 15609.5 16351.6 16439.3 16635.7 1910.9 1923.8 1957.4 2079.1 2089.9 106430.5 130680.3 140433.3 147167.3 147839.0 12305.3 12494.0 12497.7 12580.9 12684.9 16117.2 16240.8 16556.8 16592.4 16596.8 9101.9 9217.9 9268.4 9338.4 9355.9 7523.4 8845.9 9110.5 9115.7 9205.1 3937.9 4306.0 4335.6 4895.6 4904.2 2120.4 2135.4 2162.5 2165.0 2183.7 897.9 1058.9 1073.5 1078.9 1079.4 18299.3 19208.8 20788.0 22300.8 22983.8 1017.5 1070.2 1101.5 1111.2 1120.6 11071.9 11525.4 11709.0 11716.3 11720.0 734438.0 760178.0 773920.0 791554.0 797104.0 137188.6 148146.0 165886.2 168510.0 170786.0 1005.2 1054.1 1079.0 1081.7 1084.0 19262.6 19879.0 20315.9 20790.3 20826.4 64272.7 64393.0 64821.7 64952.0 65058.7 92156.0 94555.0 95509.5 95540.0 96281.0 5887.6 6725.5 8069.7 8331.0 8962.6 10030.5 13045.3 13129.3 13448.9 13604.0 7332.6 9332.8 11230.6 11402.0 11892.0 10330.3 10701.9 10743.5 10755.7 11135.6 10660.3 11418.7 11439.4 11566.5 12024.3 8388.3 8446.2 8535.1 8611.8 8624.1 11162.4 11520.7 11652.8 11670.6 11683.7 21:11:37 INFO - PROCESS | 1638 | 21:11:37 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:5497.42 Median:5268.64 stddev:2972.66 (56.4%) stddev-sans-first:2965.51 21:11:37 INFO - PROCESS | 1638 | Values: 1460.0 1473.1 1501.8 1502.2 1506.4 7797.3 8030.7 8262.0 8658.2 8667.9 8209.6 8466.8 8499.5 8508.0 8563.0 2377.3 2397.9 2401.3 2409.5 2413.0 2379.4 2405.8 2407.5 2421.8 2433.5 2371.0 2384.3 2384.8 2395.7 2401.8 1990.3 2359.6 2363.3 2370.7 2400.5 1979.8 2326.8 2338.7 2396.7 2425.1 6988.7 7589.0 8478.5 8563.4 8588.9 5090.9 5213.8 5230.3 5263.1 5274.2 3150.8 3512.4 3512.7 3523.7 3559.9 1179.7 1241.8 1278.0 1308.7 1309.9 9844.8 10532.1 11004.8 11015.0 11019.6 4488.8 4742.0 4767.0 4785.2 4856.8 9717.8 9759.4 9783.7 9787.2 9808.1 5384.9 5398.9 5407.4 5412.9 5420.7 4433.3 4528.4 4911.9 4939.7 4962.9 3225.6 3790.2 3883.4 3904.3 3936.0 1299.6 1307.9 1316.7 1324.6 1332.4 910.4 928.3 933.8 939.3 959.4 5626.5 5672.3 5734.5 5776.3 5854.2 951.4 983.1 983.3 994.4 1012.7 3319.3 3709.4 3878.2 3920.8 3924.4 9828.7 10077.7 10088.8 10127.8 10137.3 9801.8 9839.1 10058.9 10059.0 10098.1 7817.4 8133.3 8143.6 8173.6 8181.7 5523.6 5564.2 5589.6 5662.4 5702.7 9292.2 9417.4 9427.4 9430.9 9455.3 9118.3 9367.8 9450.3 9516.0 9545.1 6112.3 6848.6 6965.7 7400.7 7550.4 8058.7 8282.0 8290.8 8298.1 8315.6 5958.0 7017.7 7177.8 7183.1 7239.6 6030.7 7008.8 7014.3 7076.5 7080.3 6397.6 6435.6 6516.8 6524.2 6526.5 5055.5 5074.6 5088.2 5100.2 5100.7 4962.7 4965.7 5044.7 5087.8 5168.0 21:11:37 INFO - PROCESS | 1638 | 21:11:37 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4709.83 Median:5129.92 stddev:1392.02 (27.1%) stddev-sans-first:1356.66 21:11:37 INFO - PROCESS | 1638 | Values: 336.0 354.8 375.5 380.9 382.3 4689.2 5317.3 5546.8 5549.1 5553.8 4814.6 4908.9 5026.9 5446.4 5449.7 3708.3 3876.8 3922.7 4156.9 4386.0 3721.3 3852.6 4297.2 4313.0 4377.2 3818.0 4332.0 4367.0 4382.9 4415.6 4259.4 4259.9 4293.8 4393.5 4418.2 3796.0 3813.7 3819.5 3834.6 3864.9 4984.3 5410.4 5411.8 5450.3 5475.3 4472.2 4551.9 5049.3 5053.6 5059.0 4672.7 5128.9 5890.9 5916.9 5947.3 4264.9 4284.6 4308.9 4309.5 4314.0 5121.7 5187.9 6065.6 6162.4 6266.8 3645.8 4166.9 4241.5 4291.6 4347.3 4394.4 4406.5 4425.4 4427.0 4456.2 4099.3 4712.3 4894.7 5002.1 5060.7 4676.6 4934.6 5876.7 5977.6 6021.3 1257.4 1442.0 1442.7 1461.3 1482.7 852.5 972.7 977.5 1029.4 1038.4 4408.8 4718.4 5234.5 5300.2 5316.7 4608.6 5366.6 5803.0 5805.0 5813.4 5081.4 5131.0 5182.5 5203.5 5335.5 2779.9 2807.8 2921.1 2931.9 3041.7 5088.9 6007.9 6127.8 6137.6 6138.2 4834.6 5496.4 5609.5 5770.3 6073.0 6026.9 6041.5 6075.0 6075.7 6097.4 5165.7 5425.8 5810.4 5841.7 5927.5 4743.3 5351.9 5671.9 5767.4 5817.2 5267.0 5784.3 6075.8 6122.8 6316.8 3668.3 4357.6 4364.5 4389.0 4391.5 5296.6 5378.2 5869.0 5893.6 6134.8 5257.7 5421.5 5744.9 5821.8 5856.0 5627.1 5847.7 5847.8 5886.2 5988.8 4861.0 5236.7 5280.4 5706.3 5771.5 5486.1 5645.0 5821.6 5847.8 5869.5 4988.2 5712.1 5741.0 5804.7 5882.5 21:11:37 INFO - PROCESS | 1638 | 21:11:37 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:21736.49 Median:3763.99 stddev:32387.00 (860.4%) stddev-sans-first:32439.27 21:11:37 INFO - PROCESS | 1638 | Values: 682.8 703.7 772.2 778.9 868.5 13120.6 13420.9 16191.5 16194.2 16234.7 14834.4 14972.8 14994.6 15000.2 15057.8 694.5 716.5 847.8 853.1 871.2 860.6 870.0 876.2 876.3 879.0 769.4 928.2 932.3 935.2 936.6 918.9 921.7 925.1 928.9 932.0 681.4 684.7 688.4 689.5 690.9 15858.7 16052.5 16203.5 16390.6 16770.7 12394.3 14843.3 14980.5 15013.0 15039.3 11752.8 11932.2 11951.3 11974.6 12508.9 3863.5 3873.3 3921.6 3922.6 4054.6 119719.0 120368.0 123376.0 125635.4 125830.4 10928.3 11058.6 11123.4 11431.3 11671.1 21645.7 21654.2 22171.7 22413.9 22459.0 7574.4 7584.8 7631.5 7650.2 7651.5 4429.9 4443.4 4524.0 4556.7 4588.4 3704.4 3755.7 3772.2 3795.9 3819.6 681.2 688.7 694.9 698.5 701.8 499.8 590.3 707.4 714.3 730.8 10242.1 10728.9 11063.7 11078.3 11079.9 521.8 606.9 614.9 656.2 737.5 5033.4 5098.8 5173.9 5247.2 5249.7 109789.9 110875.0 113129.0 113434.4 113930.4 73536.0 97430.0 99179.0 99291.5 100828.0 83666.2 84560.0 85722.0 86749.7 87720.6 9936.5 10104.9 10131.0 10198.1 10232.4 52501.5 52761.7 53754.0 54573.7 54599.0 52656.0 66731.0 69447.0 71655.0 71923.0 11805.8 12078.8 12080.2 12222.0 12524.6 12140.6 12274.1 12643.7 15342.5 15491.0 11627.9 14116.9 14154.4 14518.7 14532.3 13507.0 13862.0 13958.2 14267.6 14716.7 13384.5 13850.8 14042.9 14260.4 14501.6 10232.4 10610.8 10718.1 10829.4 10950.1 9811.3 10092.1 10135.2 10183.3 11205.7 21:11:37 INFO - PROCESS | 1638 | -------- Summary: end -------- 21:11:37 INFO - PROCESS | 1638 | 21:11:38 INFO - TEST-INFO | 1638: exit 0 21:11:38 INFO - TEST-OK | dromaeo_css | took 1114331ms 21:11:38 INFO - TEST-START | kraken 21:11:38 INFO - operating with platform_type : mac_ 21:11:38 INFO - Initialising browser for kraken test... 21:11:38 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/tmpdy1Mex/profile http://localhost:49254/getInfo.html) 21:11:41 INFO - PROCESS | 1649 | 2017-04-03 21:11:41.053 plugin-container[1651:9925] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8537, name = 'com.apple.tsm.portname' 21:11:41 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:11:41 INFO - PROCESS | 1649 | 2017-04-03 21:11:41.054 plugin-container[1651:9925] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 21:11:41 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:11:41 INFO - PROCESS | 1649 | 2017-04-03 21:11:41.054 plugin-container[1651:9925] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:11:41 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 21:11:41 INFO - PROCESS | 1649 | colorDepth:24 21:11:41 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 21:11:41 INFO - PROCESS | 1649 | __metrics 21:11:41 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 21:11:41 INFO - TEST-INFO | 1649: exit 0 21:11:41 INFO - Browser initialized. 21:11:41 INFO - Running cycle 1/1 for kraken test... 21:11:41 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/tmpdy1Mex/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:11:44 INFO - PROCESS | 1652 | 2017-04-03 21:11:44.000 plugin-container[1653:10049] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x894f, name = 'com.apple.tsm.portname' 21:11:44 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:11:44 INFO - PROCESS | 1652 | 2017-04-03 21:11:44.000 plugin-container[1653:10049] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x421b, name = 'com.apple.CFPasteboardClient' 21:11:44 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:11:44 INFO - PROCESS | 1652 | 2017-04-03 21:11:44.000 plugin-container[1653:10049] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:11:53 INFO - PROCESS | 1652 | RSS: Main: 173375488 21:11:53 INFO - PROCESS | 1652 | 21:11:56 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49254/tests/kraken/driver.html?testName=audio-beat-detection) 21:11:57 INFO - PROCESS | 1652 | RSS: Main: 186306560 21:11:57 INFO - PROCESS | 1652 | 21:12:00 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49254/tests/kraken/driver.html?testName=audio-dft) 21:12:00 INFO - PROCESS | 1652 | RSS: Main: 194093056 21:12:00 INFO - PROCESS | 1652 | 21:12:04 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49254/tests/kraken/driver.html?testName=audio-fft) 21:12:04 INFO - PROCESS | 1652 | RSS: Main: 195780608 21:12:04 INFO - PROCESS | 1652 | 21:12:06 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49254/tests/kraken/driver.html?testName=audio-oscillator) 21:12:07 INFO - PROCESS | 1652 | RSS: Main: 198488064 21:12:07 INFO - PROCESS | 1652 | 21:12:09 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49254/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:12:10 INFO - PROCESS | 1652 | RSS: Main: 198787072 21:12:10 INFO - PROCESS | 1652 | 21:12:13 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49254/tests/kraken/driver.html?testName=imaging-darkroom) 21:12:13 INFO - PROCESS | 1652 | RSS: Main: 202878976 21:12:13 INFO - PROCESS | 1652 | 21:12:16 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49254/tests/kraken/driver.html?testName=imaging-desaturate) 21:12:17 INFO - PROCESS | 1652 | RSS: Main: 202420224 21:12:17 INFO - PROCESS | 1652 | 21:12:20 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49254/tests/kraken/driver.html?testName=json-parse-financial) 21:12:21 INFO - PROCESS | 1652 | RSS: Main: 202301440 21:12:21 INFO - PROCESS | 1652 | 21:12:22 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49254/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:12:23 INFO - PROCESS | 1652 | RSS: Main: 201527296 21:12:23 INFO - PROCESS | 1652 | 21:12:25 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49254/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:12:26 INFO - PROCESS | 1652 | RSS: Main: 201535488 21:12:26 INFO - PROCESS | 1652 | 21:12:28 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49254/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:12:28 INFO - PROCESS | 1652 | RSS: Main: 201838592 21:12:28 INFO - PROCESS | 1652 | 21:12:31 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49254/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:12:32 INFO - PROCESS | 1652 | RSS: Main: 202321920 21:12:32 INFO - PROCESS | 1652 | 21:12:35 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49254/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:12:36 INFO - PROCESS | 1652 | RSS: Main: 201961472 21:12:36 INFO - PROCESS | 1652 | 21:12:37 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49254/tests/kraken/driver.html?testName=ai-astar) 21:12:38 INFO - PROCESS | 1652 | RSS: Main: 203739136 21:12:38 INFO - PROCESS | 1652 | 21:12:38 INFO - PROCESS | 1652 | __start_tp_report 21:12:38 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 21:12:38 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 21:12:38 INFO - PROCESS | 1652 | |i|pagename|runs| 21:12:38 INFO - PROCESS | 1652 | |0;ai-astar;119;119;119;118;117;118;116;117;119;117 21:12:38 INFO - PROCESS | 1652 | |1;audio-beat-detection;148;113;109;154;102;140;127;104;130;96 21:12:38 INFO - PROCESS | 1652 | |2;audio-dft;193;203;277;195;180;257;279;249;195;197 21:12:38 INFO - PROCESS | 1652 | |3;audio-fft;80;60;59;85;62;50;51;59;87;57 21:12:38 INFO - PROCESS | 1652 | |4;audio-oscillator;79;70;85;77;101;103;112;100;70;82 21:12:38 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;98;113;93;100;136;106;94;90;93;89 21:12:38 INFO - PROCESS | 1652 | |6;imaging-darkroom;107;99;98;96;152;97;98;98;98;122 21:12:38 INFO - PROCESS | 1652 | |7;imaging-desaturate;118;112;140;88;150;144;118;92;93;93 21:12:38 INFO - PROCESS | 1652 | |8;json-parse-financial;60;59;58;59;63;61;61;62;61;63 21:12:38 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;74;67;69;93;109;61;84;99;99;92 21:12:38 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;91;82;88;86;86;84;87;90;88;90 21:12:38 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;139;128;145;146;151;139;148;165;194;153 21:12:38 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;163;168;167;165;171;163;157;170;169;164 21:12:38 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;62;82;85;85;82;66;64;64;65;66 21:12:38 INFO - PROCESS | 1652 | __end_tp_report 21:12:38 INFO - PROCESS | 1652 | __start_cc_report 21:12:38 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,946 21:12:38 INFO - PROCESS | 1652 | __end_cc_report 21:12:38 INFO - PROCESS | 1652 | __startTimestamp1491279158019__endTimestamp 21:12:38 INFO - PROCESS | 1652 | 21:12:38 INFO - PROCESS | 1652 | ------- Summary: start ------- 21:12:38 INFO - PROCESS | 1652 | Number of tests: 14 21:12:38 INFO - PROCESS | 1652 | 21:12:38 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:117.90 Median:118.50 stddev:1.10 (0.9%) stddev-sans-first:1.09 21:12:38 INFO - PROCESS | 1652 | Values: 119.0 119.0 119.0 118.0 117.0 118.0 116.0 117.0 119.0 117.0 21:12:38 INFO - PROCESS | 1652 | 21:12:38 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:122.30 Median:135.00 stddev:20.45 (15.1%) stddev-sans-first:19.46 21:12:38 INFO - PROCESS | 1652 | Values: 148.0 113.0 109.0 154.0 102.0 140.0 127.0 104.0 130.0 96.0 21:12:38 INFO - PROCESS | 1652 | 21:12:38 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:222.50 Median:226.00 stddev:38.41 (17.0%) stddev-sans-first:39.22 21:12:38 INFO - PROCESS | 1652 | Values: 193.0 203.0 277.0 195.0 180.0 257.0 279.0 249.0 195.0 197.0 21:12:38 INFO - PROCESS | 1652 | 21:12:38 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:65.00 Median:61.00 stddev:13.74 (22.5%) stddev-sans-first:13.46 21:12:38 INFO - PROCESS | 1652 | Values: 80.0 60.0 59.0 85.0 62.0 50.0 51.0 59.0 87.0 57.0 21:12:38 INFO - PROCESS | 1652 | 21:12:38 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:87.90 Median:73.50 stddev:14.94 (20.3%) stddev-sans-first:15.50 21:12:38 INFO - PROCESS | 1652 | Values: 79.0 70.0 85.0 77.0 101.0 103.0 112.0 100.0 70.0 82.0 21:12:38 INFO - PROCESS | 1652 | 21:12:38 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:101.20 Median:91.50 stddev:14.32 (15.7%) stddev-sans-first:15.14 21:12:38 INFO - PROCESS | 1652 | Values: 98.0 113.0 93.0 100.0 136.0 106.0 94.0 90.0 93.0 89.0 21:12:38 INFO - PROCESS | 1652 | 21:12:38 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:106.50 Median:98.00 stddev:17.82 (18.2%) stddev-sans-first:18.90 21:12:38 INFO - PROCESS | 1652 | Values: 107.0 99.0 98.0 96.0 152.0 97.0 98.0 98.0 98.0 122.0 21:12:38 INFO - PROCESS | 1652 | 21:12:38 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:114.80 Median:119.00 stddev:23.44 (19.7%) stddev-sans-first:24.83 21:12:38 INFO - PROCESS | 1652 | Values: 118.0 112.0 140.0 88.0 150.0 144.0 118.0 92.0 93.0 93.0 21:12:38 INFO - PROCESS | 1652 | 21:12:38 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:60.70 Median:61.00 stddev:1.70 (2.8%) stddev-sans-first:1.79 21:12:38 INFO - PROCESS | 1652 | Values: 60.0 59.0 58.0 59.0 63.0 61.0 61.0 62.0 61.0 63.0 21:12:38 INFO - PROCESS | 1652 | 21:12:38 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:84.70 Median:88.00 stddev:16.19 (18.4%) stddev-sans-first:16.70 21:12:38 INFO - PROCESS | 1652 | Values: 74.0 67.0 69.0 93.0 109.0 61.0 84.0 99.0 99.0 92.0 21:12:38 INFO - PROCESS | 1652 | 21:12:38 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:88.00 stddev:2.82 (3.2%) stddev-sans-first:2.64 21:12:38 INFO - PROCESS | 1652 | Values: 91.0 82.0 88.0 86.0 86.0 84.0 87.0 90.0 88.0 90.0 21:12:38 INFO - PROCESS | 1652 | 21:12:38 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:150.80 Median:149.50 stddev:18.06 (12.1%) stddev-sans-first:18.64 21:12:38 INFO - PROCESS | 1652 | Values: 139.0 128.0 145.0 146.0 151.0 139.0 148.0 165.0 194.0 153.0 21:12:38 INFO - PROCESS | 1652 | 21:12:38 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.70 Median:167.50 stddev:4.19 (2.5%) stddev-sans-first:4.33 21:12:38 INFO - PROCESS | 1652 | Values: 163.0 168.0 167.0 165.0 171.0 163.0 157.0 170.0 169.0 164.0 21:12:38 INFO - PROCESS | 1652 | 21:12:38 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.10 Median:74.00 stddev:9.93 (13.4%) stddev-sans-first:9.83 21:12:38 INFO - PROCESS | 1652 | Values: 62.0 82.0 85.0 85.0 82.0 66.0 64.0 64.0 65.0 66.0 21:12:38 INFO - PROCESS | 1652 | -------- Summary: end -------- 21:12:38 INFO - PROCESS | 1652 | 21:12:38 INFO - TEST-INFO | 1652: exit 0 21:12:38 INFO - TEST-OK | kraken | took 60415ms 21:12:38 INFO - SUITE-END | took 1174s 21:12:39 INFO - Completed test suite (00:19:35) 21:12:39 INFO - javascript benchmark 21:12:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7542.099068358824, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3014.5400785492775, 3580.462930560416, 3582.3372676054946, 3600.981995090024, 3605.0, 6089.330173680897, 6100.420460056299, 6111.02223644217, 6120.908186377208, 6141.416565426289, 6065.15087887695, 6093.573449858512, 6144.109104179891, 6166.568340216187, 6177.567570270083, 5452.0, 5498.917516237259, 5505.6696598204035, 5532.087205611069, 5572.749226284806, 5328.120860058086, 5522.834314970537, 5538.280023596927, 5555.972220138894, 5559.471850174227, 4575.130725162225, 5234.6859188448625, 5411.918821217685, 5433.320834895638, 5543.390227075019, 5471.616986810925, 5486.149646804732, 5552.555795536347, 5582.692951887645, 5636.859078523029, 5398.64908780928, 5507.807226747084, 5513.8345849624575, 5524.640898341595, 5535.833924982257, 5952.047672372443, 6081.543884208658, 6157.630542167484, 6245.781397651106, 6246.875062498724, 5211.687298762086, 5217.739113044333, 6228.470580000705, 6238.344973777779, 6340.651264180468, 5372.7045012524295, 6422.133012043408, 6445.484361251089, 6450.7097180626515, 6462.0306953956815, 5169.663971841818, 5869.559783016291, 6238.376162383725, 6252.249730032379, 6260.593061451037, 6178.598391104564, 6183.072539119168, 6497.935020649733, 6512.413882750575, 6529.673516324165, 6273.811785646438, 6306.65313407767, 6310.9368906311265, 6320.115183874262, 6346.619202847844, 6438.356164383618, 6444.903326450107, 6448.806535803933, 6452.387023232785, 6463.806085817433, 6170.876582468325, 6192.628442293477, 6227.626342419469, 6242.469390101802, 6284.528660350492, 6298.622082675054, 6332.461740752087, 6335.366463353628, 6349.52378571609, 6375.585586936834, 5191.532762051434, 5206.947930520722, 5213.035588416155, 5216.58267338612, 5239.1093514102695, 4490.640748740093, 4566.086782643484, 4627.398438203012, 4671.346011558385, 4691.812327506861, 5086.64393492452, 6170.753169873155, 6184.474319682788, 6217.502599792006, 6231.813045608639, 6486.318936511692, 6502.349765023459, 6506.512011599149, 6512.706928188243, 6522.21733391996, 5913.290405151323, 6108.190302030417, 6112.1995365628145, 6133.202683651097, 6144.310314663503, 5775.364709881909, 5776.768929242783, 5776.942230577724, 5800.071988481823, 5807.128930660435, 6586.8353291168105, 6639.236487803868, 6672.9656903180085, 6687.465002799861, 6688.598684078971, 6258.374162583706, 6270.6864656766065, 6331.9050214247745, 6358.395952384516, 6432.195975503062, 6564.573302735307, 6582.407583317525, 6586.0450234714735, 6586.37429444202, 6590.1103351046995, 6337.334579869232, 6338.714757835816, 6349.14286571307, 6353.7140828661895, 6370.140031095742, 6312.210973628296, 6333.65164915934, 6361.109444677656, 6409.8397540060005, 6434.356564343529, 6132.875646117223, 6282.685865706788, 6304.6847657617855, 6310.652914089769, 6316.526260530387, 5101.311322971352, 6278.623282603058, 6307.180066591313, 6310.5898116622275, 6310.6529140895855, 6097.817065487865, 6169.0, 6191.597546159485, 6218.253809542703, 6247.375262473716, 6134.620279381683, 6185.752569897334, 6225.657588832477, 6289.559730818799, 6297.4647154992335, 5154.5103215194495, 5884.911726324023, 6155.692215389123, 6209.4101060401, 6235.532335074979], "value": 5900.9270388723535, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.030341743852, 505.2270026858905, 510.27286117282875, 522.9137192363261, 592.6325678079592, 40923.386149207785, 41757.373639395395, 43013.92465188368, 43116.70649880505, 43275.7017289481, 44487.0, 44654.33018504725, 44678.88302792432, 44784.776076119655, 45654.315185272164, 9583.089606487372, 9848.458334791583, 9850.507474626276, 11569.247998880088, 11933.16467847251, 11793.7641247175, 11851.7629647407, 11871.0, 11901.988330991877, 11905.642830715093, 11894.82157767634, 11914.46384912681, 11946.283223006603, 11960.222585531956, 11961.2823230606, 11605.013573846234, 11713.0, 11807.291562506234, 11878.940605296919, 11895.643130706092, 11745.4714537846, 11754.0, 11759.764804703858, 11829.645110646608, 11842.23025503348, 38314.42528362077, 38482.42276365857, 38587.8070609648, 38650.613493864985, 38708.0, 4037.798110094513, 4044.1709449563064, 4059.5940405959464, 4089.7955102245064, 4140.358244472111, 27860.442791144265, 28049.85975070132, 28519.429611407657, 29597.81608735648, 30373.848130759427, 3008.4885569453245, 3228.4027454920915, 3525.048236976002, 3526.8236588170744, 3532.028692109665, 383546.0, 487463.0, 497729.0, 498850.0, 499323.50338248076, 59150.70424647849, 59439.40560594425, 59446.108308375406, 59667.0, 59667.403325967054, 60497.69751151304, 61225.08162377568, 61813.690931545956, 61904.69047654823, 62130.0, 16721.749173762404, 16815.831841681673, 16969.0, 16991.235394407282, 17017.149142542825, 11942.761144777056, 12242.877571224173, 12914.612561623166, 13219.537316194079, 13268.0, 18241.0, 18737.0, 18834.434966951012, 18880.150393232336, 18986.52533686669, 8407.915920840713, 8476.49141051539, 8480.024797148284, 8497.66009359631, 8506.0, 897.7261935109724, 901.5537309032071, 906.918377346042, 933.3233405780754, 943.7168849345169, 26911.0, 31158.844205778827, 31703.84148079291, 32178.83910580432, 32503.34993300121, 896.852019416802, 1027.4605831938293, 1101.9311268070119, 1136.1592421608116, 1230.0467137967967, 12908.677283067998, 14622.415103395957, 14965.551033469013, 15297.0, 15306.923465382602, 684369.0, 733589.0, 741724.0, 743276.2836185893, 984931.0, 45403.77298113488, 56159.71920140373, 56414.0, 56920.0, 56958.7152064237, 1452.3682198243798, 1463.1367493179075, 1471.8380978092616, 1488.3302513868803, 1489.6350394153285, 26509.734902650725, 28687.426251474437, 29798.0, 30390.544141837447, 30601.693983059886, 42465.36301955411, 47153.2927006102, 47827.28259076186, 47962.760186198844, 48112.5188748122, 598311.0, 626943.0, 631518.0, 634021.8298908476, 634033.0, 24035.0, 24533.38666533351, 26353.20940371792, 26668.199954000636, 26681.733182667926, 43156.78421607872, 43263.918402038944, 44035.11929761451, 45562.31656525088, 45993.850153746425, 30511.389772204875, 30755.847090703435, 31125.0, 31208.843955780983, 31302.06093817189, 29942.0, 30482.39035219239, 31074.757009720262, 31335.05994820159, 31350.843245783624, 33702.3259534803, 35470.46794298036, 36148.63851361558, 36354.0, 36441.90674279776, 29831.850840745657, 30411.695883040888, 30847.38305233838, 31494.370112598077, 31562.526562101128, 24572.877135614206, 24689.382765431008, 24751.133710320053, 24968.750312496642, 25175.0], "value": 20368.841051021675, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.3269579192725, 398.83847041948, 404.08273219791096, 410.46434403103945, 432.6906262022653, 20624.484387890312, 21014.0, 21433.14267429305, 21765.455863603416, 21952.0, 18589.0, 18605.441836744885, 22062.448438789037, 22468.43828904278, 22936.426589335275, 5830.4169583041785, 6163.815085547419, 6177.814665560018, 6180.629162250257, 6201.224846894138, 5901.114832775075, 6047.667378294192, 6052.425019623129, 6113.755449782004, 6138.14066030756, 5967.16459695643, 6061.878762424749, 6074.240719910012, 6074.939250607482, 6080.391960803906, 5764.365919748845, 5987.760489580433, 6029.69851507425, 6066.0, 6074.787382441637, 5325.866853328698, 5332.466753324676, 5340.118880384732, 5343.812966546152, 5347.438518955492, 25937.351566210808, 26884.73115268842, 26951.32621684454, 27112.0, 27210.591841122397, 2803.6775770786417, 2826.915192544227, 2830.6178665880257, 2833.107571115092, 2834.107256214286, 14002.649933751738, 15609.531714048597, 16351.59121021972, 16439.26023328953, 16635.6672866542, 1910.8566857485744, 1923.778765441978, 1957.412776167144, 2079.0852025109057, 2089.937301880946, 106430.46784766104, 130680.34659826735, 140433.29783351018, 147167.26416367956, 147839.0, 12305.323207223688, 12494.000479961582, 12497.687557811127, 12580.937095314648, 12684.936575317064, 16117.194140293173, 16240.75638865418, 16556.83443165553, 16592.419265325654, 16596.751048734037, 9101.863472047924, 9217.907820921839, 9268.397554158959, 9338.439693618404, 9355.90644093564, 7523.435742319348, 8845.867311990325, 9110.453372797654, 9115.726528204164, 9205.125513076247, 3937.8621748239248, 4305.978470107692, 4335.631471324973, 4895.583875370562, 4904.190808516626, 2120.35329224588, 2135.370065830584, 2162.5458653682904, 2164.9567008659737, 2183.748868880067, 897.8832751742362, 1058.867641544807, 1073.4632683658172, 1078.8975047370486, 1079.384750692098, 18299.268029278945, 19208.807911920703, 20788.0, 22300.776992230196, 22983.770162298497, 1017.5014243020867, 1070.2294348069377, 1101.531848964187, 1111.1777284809184, 1120.5797825815318, 11071.944640276746, 11525.366104864146, 11709.0, 11716.297022178696, 11720.003799676952, 734438.0, 760178.0, 773920.0, 791554.0, 797104.0, 137188.6281137176, 148146.0, 165886.1705691488, 168510.0, 170786.0, 1005.1707341443192, 1054.0882136951482, 1078.9514471848786, 1081.6538707613645, 1083.9566417343376, 19262.614747704687, 19879.0, 20315.898420508394, 20790.27234046801, 20826.37520874376, 64272.67863660652, 64393.0, 64821.67589162024, 64952.0, 65058.67470662617, 92156.0, 94555.0, 95509.52245238729, 95540.0, 96281.03718962721, 5887.58786884931, 6725.495587830835, 8069.708846584518, 8331.0, 8962.641494340154, 10030.498475076363, 13045.282509462071, 13129.34353282313, 13448.93275533616, 13604.0, 7332.633368331455, 9332.766680832761, 11230.55077796879, 11402.0, 11892.0, 10330.276880618285, 10701.89298107009, 10743.516541755796, 10755.677329680122, 11135.610253641084, 10660.25378223517, 11418.657440276806, 11439.428028598702, 11566.47950842231, 12024.278543287432, 8388.328933685412, 8446.239838414573, 8535.06114327411, 8611.82776344482, 8624.137586241326, 11162.386068766296, 11520.654380368602, 11652.766944661229, 11670.591529296435, 11683.707907302385], "value": 10349.118122173762, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1460.0363759918455, 1473.0793254216107, 1501.7897494350793, 1502.2188461999767, 1506.435086842434, 7797.337226335754, 8030.678772849094, 8262.049864265604, 8658.220760131588, 8667.913320866797, 8209.589520523965, 8466.788330291745, 8499.490030598168, 8507.95746021269, 8563.0, 2377.2986968844148, 2397.880105994702, 2401.339631601315, 2409.5060512594964, 2412.9879350603223, 2379.393254720044, 2405.819563532742, 2407.518496300738, 2421.8183636227263, 2433.4646377796857, 2370.9644355334685, 2384.308550520347, 2384.7734465225776, 2395.688560487134, 2401.795847352977, 1990.2536548794203, 2359.5988681924027, 2363.279199844047, 2370.656254843055, 2400.4958958618713, 1979.772326182493, 2326.8022218111482, 2338.660894170353, 2396.6884305040408, 2425.1390756281444, 6988.720451181946, 7588.962055189744, 8478.491290522588, 8563.35774816885, 8588.914110858874, 5090.9236361454605, 5213.817516386946, 5230.320058392384, 5263.078961181816, 5274.208868669692, 3150.771199232301, 3512.4204506256438, 3512.683858452726, 3523.735719820998, 3559.9110022249392, 1179.6637958181877, 1241.8326772833507, 1277.993610031944, 1308.725167714781, 1309.9410526526328, 9844.818621765435, 10532.104771094466, 11004.834927476095, 11015.0, 11019.614313499069, 4488.753118578445, 4741.952580474152, 4766.976165119152, 4785.1625965455905, 4856.80572777092, 9717.805643887083, 9759.414435133915, 9783.657571985088, 9787.16809071237, 9808.068233517804, 5384.892302154013, 5398.865028374322, 5407.405185429599, 5412.945870541244, 5420.674759514442, 4433.290673492225, 4528.43394575678, 4911.852644420673, 4939.654224204343, 4962.925556116661, 3225.59680039995, 3790.2419516096893, 3883.3980732986433, 3904.2581909437117, 3935.9803200984384, 1299.564645843634, 1307.9149855259377, 1316.6510874618234, 1324.5959982205327, 1332.4137379552965, 910.3854897943861, 928.252756530999, 933.8272419602396, 939.3143005605946, 959.4003747657714, 5626.521745651666, 5672.319321681424, 5734.541236701054, 5776.277965254343, 5854.151148083504, 951.438651195784, 983.1102851919024, 983.3165949665057, 994.39838897466, 1012.7265638277623, 3319.3195394943978, 3709.406494960793, 3878.1855810279594, 3920.784356860343, 3924.4309575111447, 9828.655997040212, 10077.69766906994, 10088.848667269996, 10127.848082278771, 10137.2903896728, 9801.803963920682, 9839.065288797552, 10058.899411005796, 10059.044390782863, 10098.091171794575, 7817.374610031184, 8133.349332053422, 8143.633536490872, 8173.591320433955, 8181.713640022571, 5523.585731070106, 5564.221009058654, 5589.5807814413265, 5662.433756624305, 5702.6863522505, 9292.16370526656, 9417.434953902513, 9427.387219830689, 9430.905690943002, 9455.338126331091, 9118.316126290421, 9367.765805854908, 9450.338476306868, 9515.952420237854, 9545.140937315675, 6112.327643959072, 6848.623325717133, 6965.721371145098, 7400.6669699864715, 7550.433717471102, 8058.677652894053, 8282.0, 8290.751277461686, 8298.128696487023, 8315.584220789058, 5958.0, 7017.719291228498, 7177.820554485971, 7183.102112235971, 7239.601821899846, 6030.698465076641, 7008.7546935857, 7014.2635023323855, 7076.540024898365, 7080.327368899946, 6397.584157029778, 6435.581687190318, 6516.804495865132, 6524.151860258136, 6526.5105117117355, 5055.51972562606, 5074.5686616637995, 5088.236764485208, 5100.183970564692, 5100.69395836251, 4962.6774259673, 4965.677230979974, 5044.747762611928, 5087.796488140581, 5168.01807656544], "value": 4494.220071022798, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.00877411635673, 354.81017655554274, 375.47246119202515, 380.9314323421783, 382.2870346803211, 4689.249720044793, 5317.335333083364, 5546.77812887484, 5549.112142057272, 5553.777848886045, 4814.63890208234, 4908.901821963558, 5026.868954485239, 5446.385734847276, 5449.6730196188255, 3708.295423869463, 3876.8061596920184, 3922.705797065217, 4156.916861662765, 4386.0, 3721.2929543386804, 3852.61305929865, 4297.205017071852, 4312.956870431302, 4377.212101821687, 3818.0, 4331.978340108295, 4367.0, 4382.868513944587, 4415.646748260131, 4259.403683484315, 4259.91480170398, 4293.763842988635, 4393.450818647669, 4418.204723149848, 3795.962040379616, 3813.656770890633, 3819.4652748615217, 3834.559025712051, 3864.942025869614, 4984.327115839352, 5410.375048717368, 5411.783528658849, 5450.31871016123, 5475.288212532385, 4472.217361961643, 4551.908961820745, 5049.267856160873, 5053.620978426633, 5058.974705126451, 4672.742999135046, 5128.871778205537, 5890.852728681817, 5916.881662366729, 5947.345791962881, 4264.87205383839, 4284.635805956497, 4308.858152589566, 4309.491677912737, 4313.972432956505, 5121.718305493197, 5187.948120518822, 6065.63440258304, 6162.414570615784, 6266.803929995735, 3645.8359373828243, 4166.8749937501925, 4241.512226094039, 4291.592298731615, 4347.282698354736, 4394.406755088086, 4406.5273747146675, 4425.402570652921, 4426.993570818415, 4456.22016147173, 4099.344104943195, 4712.340272361872, 4894.730789806595, 5002.1496345621335, 5060.746962651926, 4676.649251306166, 4934.605231581465, 5876.7355469004, 5977.6114552553945, 6021.307549631761, 1257.4278703190027, 1441.978370324446, 1442.725882082401, 1461.2620626583507, 1482.6589884326665, 852.4927668037508, 972.7179118055826, 977.5277023664572, 1029.4492446541065, 1038.382162613244, 4408.779561022, 4718.41019872516, 5234.528892399702, 5300.231466437345, 5316.73416329182, 4608.631309495232, 5366.6118577232755, 5803.0, 5804.952399989955, 5813.360530341659, 5081.441041485434, 5130.974345128325, 5182.507661772125, 5203.453637368098, 5335.493128152819, 2779.9158328251997, 2807.8315301081593, 2921.13826421202, 2931.9120426387244, 3041.6654168041496, 5088.949110508922, 6007.918574656604, 6127.824622259123, 6137.5703700741415, 6138.202033735675, 4834.5890599299455, 5496.422875598085, 5609.49514543693, 5770.336411312753, 6072.969635151884, 6026.939730602726, 6041.456268935817, 6075.0, 6075.726592303268, 6097.420745029126, 5165.741712914264, 5425.755840987244, 5810.448007439287, 5841.707914604338, 5927.4665280125, 4743.335932969456, 5351.919721204107, 5671.914921276267, 5767.423257674199, 5817.185594016925, 5266.97366513165, 5784.334801497882, 6075.7569697213385, 6122.785702500391, 6316.842078948063, 3668.3030224257304, 4357.586029327335, 4364.498082720401, 4388.978055109704, 4391.451068616423, 5296.602754793329, 5378.247045413724, 5869.006806528787, 5893.5874488785375, 6134.78528251509, 5257.710825904612, 5421.512063914267, 5744.931824543245, 5821.76712931495, 5855.970720146372, 5627.099664053731, 5847.742735311928, 5847.795327163529, 5886.23478947734, 5988.784149864404, 4860.975695121501, 5236.6857988520815, 5280.392754833243, 5706.258186435737, 5771.538276937918, 5486.149646804752, 5644.955683198621, 5821.621594596338, 5847.795327163529, 5869.530437564899, 4988.226824842155, 5712.0860662293835, 5741.0, 5804.65172089676, 5882.529367658086], "value": 4283.864578277936, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [682.8054004608684, 703.711478293899, 772.1892013385946, 778.9298963093324, 868.5483548554749, 13120.606381808537, 13420.865791342083, 16191.514254572367, 16194.190290485458, 16234.675306493864, 14834.406623735038, 14972.775408368883, 14994.550163495085, 15000.174990375524, 15057.849421505782, 694.4652617484534, 716.4590733995826, 847.8261956298975, 853.1298075962515, 871.2202578692066, 860.5525126933991, 869.9565021748919, 876.1676407412951, 876.2989608313324, 879.0111124984393, 769.3960241210651, 928.1646518133666, 932.3194068330123, 935.242453612576, 936.6487567162314, 918.9127032931827, 921.7280902133853, 925.1303774452061, 928.9071092890724, 932.0493097041015, 681.4344094401634, 684.6953105867891, 688.3770187979886, 689.4691087862318, 690.9481788865855, 15858.682826343524, 16052.51842444717, 16203.513894583182, 16390.590235244214, 16770.74843877343, 12394.3183124929, 14843.332050057774, 14980.475683351031, 15013.0, 15039.323230454656, 11752.823707644393, 11932.16474846761, 11951.282923024559, 11974.58088966882, 12508.937455312665, 3863.497745293122, 3873.2640798248244, 3921.627445392683, 3922.607739226083, 4054.5945405459515, 119718.99999999913, 120368.0, 123376.0, 125635.37182314122, 125830.37084814609, 10928.289661172157, 11058.576569372315, 11123.388213648164, 11431.31412115259, 11671.12466565011, 21645.67531487029, 21654.242101526685, 22171.66742498864, 22413.887930560242, 22459.0, 7574.394048476109, 7584.772456826304, 7631.503952243086, 7650.158482566913, 7651.502652327581, 4429.867103986886, 4443.400140980991, 4523.95476045242, 4556.749378784132, 4588.38056862326, 3704.3517384457614, 3755.7370984030854, 3772.245550889833, 3795.905102372463, 3819.598942111094, 681.2271976745312, 688.6763221286087, 694.9409300209538, 698.4586945117474, 701.7754318618096, 499.7825945713626, 590.282806390238, 707.4305184326666, 714.2750108639735, 730.82825535999, 10242.129418999468, 10728.89271107279, 11063.668089957153, 11078.335299882034, 11079.944600276947, 521.8173639226317, 606.8634557224677, 614.9016157414792, 656.2485953583216, 737.4616529933179, 5033.421156566968, 5098.827537035588, 5173.896522069537, 5247.1866860636665, 5249.737513124329, 109789.90210097957, 110875.0, 113129.0, 113434.43282783534, 113930.43034784772, 73536.0, 97430.0, 99179.02462926124, 99291.50354248182, 100827.99172008333, 83666.16333836583, 84560.0, 85722.0, 86749.69875451873, 87720.56139719389, 9936.453495057842, 10104.94947525287, 10131.0, 10198.082172604501, 10232.437215953194, 52501.47498524966, 52761.68095797636, 53754.0, 54573.727131365675, 54599.0, 52656.0, 66731.0, 69447.0, 71655.0, 71923.0, 11805.82291265649, 12078.818817717565, 12080.21478603888, 12222.0, 12524.624261272176, 12140.635780926586, 12274.14081014356, 12643.718155834473, 15342.539723808304, 15491.0, 11627.88372116268, 14116.858831411555, 14154.433822646979, 14518.709625807214, 14532.346044428237, 13507.0, 13862.0, 13958.232297223343, 14267.571972840831, 14716.705665886837, 13384.531541396005, 13850.792238116637, 14042.929785351007, 14260.42958281657, 14501.637459063608, 10232.386056836614, 10610.8408373876, 10718.14254859625, 10829.350238985686, 10950.123990080934, 9811.264155188532, 10092.142167915516, 10135.239857010902, 10183.287169898036, 11205.71985700364], "value": 7684.7106114100625, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1559.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 119.0, 119.0, 118.0, 117.0, 118.0, 116.0, 117.0, 119.0, 117.0], "value": 117.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 113.0, 109.0, 154.0, 102.0, 140.0, 127.0, 104.0, 130.0, 96.0], "value": 122.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 203.0, 277.0, 195.0, 180.0, 257.0, 279.0, 249.0, 195.0, 197.0], "value": 222.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 60.0, 59.0, 85.0, 62.0, 50.0, 51.0, 59.0, 87.0, 57.0], "value": 65.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 70.0, 85.0, 77.0, 101.0, 103.0, 112.0, 100.0, 70.0, 82.0], "value": 87.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 113.0, 93.0, 100.0, 136.0, 106.0, 94.0, 90.0, 93.0, 89.0], "value": 101.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 99.0, 98.0, 96.0, 152.0, 97.0, 98.0, 98.0, 98.0, 122.0], "value": 106.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 112.0, 140.0, 88.0, 150.0, 144.0, 118.0, 92.0, 93.0, 93.0], "value": 114.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 58.0, 59.0, 63.0, 61.0, 61.0, 62.0, 61.0, 63.0], "value": 60.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 67.0, 69.0, 93.0, 109.0, 61.0, 84.0, 99.0, 99.0, 92.0], "value": 84.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 82.0, 88.0, 86.0, 86.0, 84.0, 87.0, 90.0, 88.0, 90.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 128.0, 145.0, 146.0, 151.0, 139.0, 148.0, 165.0, 194.0, 153.0], "value": 150.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 168.0, 167.0, 165.0, 171.0, 163.0, 157.0, 170.0, 169.0, 164.0], "value": 165.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 82.0, 85.0, 85.0, 82.0, 66.0, 64.0, 64.0, 65.0, 66.0], "value": 72.1, "unit": "score"}]}]} 21:12:39 INFO - Return code: 0 21:12:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:12:39 ERROR - # TBPL SUCCESS # 21:12:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:12:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:12:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:12:39 INFO - 2017-04-03 21:12:39.337 screenresolution[1657:10443] starting screenresolution argv=screenresolution get 21:12:39 INFO - 2017-04-03 21:12:39.339 screenresolution[1657:10443] Display 0: 1600x1200x32@60 21:12:39 INFO - 2017-04-03 21:12:39.345 screenresolution[1658:10446] starting screenresolution argv=screenresolution list 21:12:39 INFO - Available Modes on Display 0 21:12:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:12:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:12:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:12:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:12:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:12:39 INFO - Intel Iris: 21:12:39 INFO - Chipset Model: Intel Iris 21:12:39 INFO - Type: GPU 21:12:39 INFO - Bus: Built-In 21:12:39 INFO - VRAM (Dynamic, Max): 1536 MB 21:12:39 INFO - Vendor: Intel (0x8086) 21:12:39 INFO - Device ID: 0x0a2e 21:12:39 INFO - Revision ID: 0x0009 21:12:39 INFO - Displays: 21:12:39 INFO - Display: 21:12:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:12:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:12:39 INFO - Display Serial Number: 1600x1200 60 21:12:39 INFO - Main Display: Yes 21:12:39 INFO - Mirror: Off 21:12:39 INFO - Online: Yes 21:12:39 INFO - Rotation: Supported 21:12:39 INFO - Return code: 0 21:12:39 INFO - Running post-action listener: _resource_record_post_action 21:12:39 INFO - [mozharness: 2017-04-04 04:12:39.581401Z] Finished run-tests step (success) 21:12:39 INFO - Running post-run listener: _resource_record_post_run 21:12:39 INFO - Total resource usage - Wall time: 1191s; CPU: 54.0%; Read bytes: 40728576; Write bytes: 422993920; Read time: 391; Write time: 1476 21:12:39 INFO - TinderboxPrint: CPU usage
54.4% 21:12:39 INFO - TinderboxPrint: I/O read bytes / time
40,728,576 / 391 21:12:39 INFO - TinderboxPrint: I/O write bytes / time
422,993,920 / 1,476 21:12:39 INFO - TinderboxPrint: CPU idle
2,171.9 (45.6%) 21:12:39 INFO - TinderboxPrint: CPU system
959.3 (20.1%) 21:12:39 INFO - TinderboxPrint: CPU user
1,634.4 (34.3%) 21:12:39 INFO - TinderboxPrint: Swap in / out
162,729,984 / 0 21:12:39 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 119390720; Write bytes: 112754688; Read time: 10864; Write time: 227 21:12:39 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 38696960; Write bytes: 299286528; Read time: 369; Write time: 1218 21:12:40 INFO - Running post-run listener: _upload_blobber_files 21:12:40 INFO - Blob upload gear active. 21:12:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:12:40 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 21:12:40 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'] 21:12:40 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 21:12:40 INFO - (blobuploader) - INFO - Open directory for files ... 21:12:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:12:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:12:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:12:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:12:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:12:41 INFO - (blobuploader) - INFO - Done attempting. 21:12:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:12:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:12:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:12:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:12:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:12:42 INFO - (blobuploader) - INFO - Done attempting. 21:12:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:12:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:12:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:12:42 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:12:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:12:42 INFO - (blobuploader) - INFO - Done attempting. 21:12:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:12:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:12:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:12:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:12:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:12:45 INFO - (blobuploader) - INFO - Done attempting. 21:12:45 INFO - (blobuploader) - INFO - Iteration through files over. 21:12:45 INFO - Return code: 0 21:12:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:12:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:12:45 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c7f7e651492cb77e4f32e86b72a2aef7894ec43228b603782e12e8f0001fd3010e30c88368f130f00afcfdcfb669dd95cea6de26749dc4995282bbbccb935380", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b1e9cf419fada20f2f03f76e3e8a45b312cacc6d5d9a47614e05bf5bf24d6d924682c81acace467607a3e5689fe3ad79efbde8cc5e0ba9858c30e1a8fc5183ff", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/be5775005eac77c9512ee4675f34ad076edd8452fe28791a8201c819c8623217c05f023c11cfd92bb1cf0b29577e26360e72887ca913120bdb9cfc10666e4006", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ca832d83871443bf87f19556ea0e8f15d5f58c43326e4ea13150d9fc46ef6fa74301fa78bb069f20b3653e6dbf131068e072a283968dcf9a1194839d0dfa444a"} 21:12:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:12:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:12:45 INFO - Contents: 21:12:45 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c7f7e651492cb77e4f32e86b72a2aef7894ec43228b603782e12e8f0001fd3010e30c88368f130f00afcfdcfb669dd95cea6de26749dc4995282bbbccb935380", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b1e9cf419fada20f2f03f76e3e8a45b312cacc6d5d9a47614e05bf5bf24d6d924682c81acace467607a3e5689fe3ad79efbde8cc5e0ba9858c30e1a8fc5183ff", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/be5775005eac77c9512ee4675f34ad076edd8452fe28791a8201c819c8623217c05f023c11cfd92bb1cf0b29577e26360e72887ca913120bdb9cfc10666e4006", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ca832d83871443bf87f19556ea0e8f15d5f58c43326e4ea13150d9fc46ef6fa74301fa78bb069f20b3653e6dbf131068e072a283968dcf9a1194839d0dfa444a"} 21:12:45 INFO - Running post-run listener: copy_logs_to_upload_dir 21:12:45 INFO - Copying logs to upload dir... 21:12:45 INFO - mkdir: /builds/slave/test/build/upload/logs 21:12:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.004789 ========= master_lag: 0.25 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-04-03 21:12:45.367366) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-03 21:12:45.370898) ========= 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.HmrRupATDG/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.cmvejtSKf8/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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/c7f7e651492cb77e4f32e86b72a2aef7894ec43228b603782e12e8f0001fd3010e30c88368f130f00afcfdcfb669dd95cea6de26749dc4995282bbbccb935380", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b1e9cf419fada20f2f03f76e3e8a45b312cacc6d5d9a47614e05bf5bf24d6d924682c81acace467607a3e5689fe3ad79efbde8cc5e0ba9858c30e1a8fc5183ff", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/be5775005eac77c9512ee4675f34ad076edd8452fe28791a8201c819c8623217c05f023c11cfd92bb1cf0b29577e26360e72887ca913120bdb9cfc10666e4006", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ca832d83871443bf87f19556ea0e8f15d5f58c43326e4ea13150d9fc46ef6fa74301fa78bb069f20b3653e6dbf131068e072a283968dcf9a1194839d0dfa444a"} build_url:https://queue.taskcluster.net/v1/task/UrDvt7ceTcu0evu2vIspZQ/artifacts/public/build/firefox-53.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011398 build_url: 'https://queue.taskcluster.net/v1/task/UrDvt7ceTcu0evu2vIspZQ/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/c7f7e651492cb77e4f32e86b72a2aef7894ec43228b603782e12e8f0001fd3010e30c88368f130f00afcfdcfb669dd95cea6de26749dc4995282bbbccb935380", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b1e9cf419fada20f2f03f76e3e8a45b312cacc6d5d9a47614e05bf5bf24d6d924682c81acace467607a3e5689fe3ad79efbde8cc5e0ba9858c30e1a8fc5183ff", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/be5775005eac77c9512ee4675f34ad076edd8452fe28791a8201c819c8623217c05f023c11cfd92bb1cf0b29577e26360e72887ca913120bdb9cfc10666e4006", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ca832d83871443bf87f19556ea0e8f15d5f58c43326e4ea13150d9fc46ef6fa74301fa78bb069f20b3653e6dbf131068e072a283968dcf9a1194839d0dfa444a"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-03 21:12:45.407590) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 21:12:45.408224) ========= 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.HmrRupATDG/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.cmvejtSKf8/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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.005358 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 21:12:45.464092) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-03 21:12:45.464457) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-03 21:12:45.465096) ========= ========= Total master_lag: 0.41 =========