builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0223 starttime: 1490098685.39 results: success (0) buildid: 20170321033128 builduid: f45041a5208745b29e99bfac6f854e19 revision: ca4ae502156eaea6fffb296bb9c3b3930af8ab58 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-21 05:18:05.386644) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-21 05:18:05.387738) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-21 05:18:05.388014) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-21 05:18:05.414451) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 05:18:05.414834) ========= 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.Xx0DQ1RZ0O/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.3HOlwXSHfh/Listeners TMPDIR=/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-21 05:18:05-- 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% 173M=0s 2017-03-21 05:18:05 (173 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131813 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 05:18:05.571387) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 05:18:05.571705) ========= 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.Xx0DQ1RZ0O/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.3HOlwXSHfh/Listeners TMPDIR=/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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.037398 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 05:18:05.631804) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-21 05:18:05.632154) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ca4ae502156eaea6fffb296bb9c3b3930af8ab58 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev ca4ae502156eaea6fffb296bb9c3b3930af8ab58 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Xx0DQ1RZ0O/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.3HOlwXSHfh/Listeners TMPDIR=/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-21 05:18:05,962 truncating revision to first 12 chars 2017-03-21 05:18:05,962 Setting DEBUG logging. 2017-03-21 05:18:05,962 attempt 1/10 2017-03-21 05:18:05,962 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ca4ae502156e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-21 05:18:06,869 unpacking tar archive at: mozilla-central-ca4ae502156e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.187863 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-21 05:18:06.843683) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-21 05:18:06.843999) ========= script_repo_revision: ca4ae502156eaea6fffb296bb9c3b3930af8ab58 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-21 05:18:06.844384) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-21 05:18:06.844665) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-21 05:18:06.864875) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-21 05:18:06.865275) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Xx0DQ1RZ0O/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.3HOlwXSHfh/Listeners TMPDIR=/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:18:07 INFO - MultiFileLogger online at 20170321 05:18:07 in /builds/slave/test 05:18:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:18:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:18:07 INFO - {'append_to_log': False, 05:18:07 INFO - 'base_work_dir': '/builds/slave/test', 05:18:07 INFO - 'blob_upload_branch': 'Firefox', 05:18:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:18:07 INFO - 'branch': 'Firefox', 05:18:07 INFO - 'buildbot_json_path': 'buildprops.json', 05:18:07 INFO - 'config_files': ('talos/mac_config.py',), 05:18:07 INFO - 'default_actions': ('clobber', 05:18:07 INFO - 'read-buildbot-config', 05:18:07 INFO - 'download-and-extract', 05:18:07 INFO - 'populate-webroot', 05:18:07 INFO - 'create-virtualenv', 05:18:07 INFO - 'install', 05:18:07 INFO - 'run-tests'), 05:18:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:18:07 INFO - 'download_minidump_stackwalk': True, 05:18:07 INFO - 'download_symbols': 'ondemand', 05:18:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:18:07 INFO - 'tooltool.py': '/tools/tooltool.py', 05:18:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:18:07 INFO - '/tools/misc-python/virtualenv.py')}, 05:18:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:18:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:18:07 INFO - 'gecko_profile': False, 05:18:07 INFO - 'gecko_profile_interval': 0, 05:18:07 INFO - 'installer_path': 'installer.exe', 05:18:07 INFO - 'log_level': 'info', 05:18:07 INFO - 'log_name': 'talos', 05:18:07 INFO - 'log_to_console': True, 05:18:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:18:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:18:07 INFO - 'opt_config_files': (), 05:18:07 INFO - 'pip_index': False, 05:18:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:18:07 INFO - 'cmd': ('bash', 05:18:07 INFO - '-c', 05:18:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:18:07 INFO - 'enabled': True, 05:18:07 INFO - 'halt_on_failure': False, 05:18:07 INFO - 'name': 'check_screen_resolution'},), 05:18:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:18:07 INFO - 'cmd': ('bash', 05:18:07 INFO - '-c', 05:18:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:18:07 INFO - 'enabled': True, 05:18:07 INFO - 'halt_on_failure': False, 05:18:07 INFO - 'name': 'check_screen_resolution'},), 05:18:07 INFO - 'run_cmd_checks_enabled': True, 05:18:07 INFO - 'suite': 'dromaeojs-e10s', 05:18:07 INFO - 'system_bits': '32', 05:18:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:18:07 INFO - 'title': 't-yosemite-r7-0223', 05:18:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:18:07 INFO - 'use_talos_json': True, 05:18:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:18:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:18:07 INFO - 'work_dir': 'build'} 05:18:07 INFO - [mozharness: 2017-03-21 12:18:07.303120Z] Running clobber step. 05:18:07 INFO - Running pre-action listener: _resource_record_pre_action 05:18:07 INFO - Running main action method: clobber 05:18:07 INFO - rmtree: /builds/slave/test/build 05:18:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:18:09 INFO - Running post-action listener: _resource_record_post_action 05:18:09 INFO - [mozharness: 2017-03-21 12:18:09.770627Z] Finished clobber step (success) 05:18:09 INFO - [mozharness: 2017-03-21 12:18:09.770703Z] Running read-buildbot-config step. 05:18:09 INFO - Running pre-action listener: _resource_record_pre_action 05:18:09 INFO - Running main action method: read_buildbot_config 05:18:09 INFO - Using buildbot properties: 05:18:09 INFO - { 05:18:09 INFO - "project": "", 05:18:09 INFO - "product": "firefox", 05:18:09 INFO - "script_repo_revision": "production", 05:18:09 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:18:09 INFO - "repository": "", 05:18:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:18:09 INFO - "buildid": "20170321033128", 05:18:09 INFO - "pgo_build": "False", 05:18:09 INFO - "basedir": "/builds/slave/test", 05:18:09 INFO - "buildnumber": 129, 05:18:09 INFO - "slavename": "t-yosemite-r7-0223", 05:18:09 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 05:18:09 INFO - "platform": "yosemite_r7", 05:18:09 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:18:09 INFO - "branch": "mozilla-central", 05:18:09 INFO - "repo_path": "mozilla-central", 05:18:09 INFO - "revision": "ca4ae502156eaea6fffb296bb9c3b3930af8ab58", 05:18:09 INFO - "stage_platform": "macosx64", 05:18:09 INFO - "builduid": "f45041a5208745b29e99bfac6f854e19", 05:18:09 INFO - "slavebuilddir": "test" 05:18:09 INFO - } 05:18:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/C9lYO0JNRLuJjQlgcoCrag/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 05:18:09 INFO - Running post-action listener: _resource_record_post_action 05:18:09 INFO - [mozharness: 2017-03-21 12:18:09.776204Z] Finished read-buildbot-config step (success) 05:18:09 INFO - [mozharness: 2017-03-21 12:18:09.776390Z] Running download-and-extract step. 05:18:09 INFO - Running pre-action listener: _resource_record_pre_action 05:18:09 INFO - Running main action method: download_and_extract 05:18:09 INFO - mkdir: /builds/slave/test/build/tests 05:18:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:18:09 INFO - trying https://queue.taskcluster.net/v1/task/C9lYO0JNRLuJjQlgcoCrag/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 05:18:09 INFO - Downloading https://queue.taskcluster.net/v1/task/C9lYO0JNRLuJjQlgcoCrag/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:18:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/C9lYO0JNRLuJjQlgcoCrag/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 05:18:11 INFO - Downloaded 1325 bytes. 05:18:11 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:18:11 INFO - Using the following test package requirements: 05:18:11 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:18:11 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 05:18:11 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:18:11 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:18:11 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 05:18:11 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:18:11 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 05:18:11 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:18:11 INFO - u'jsshell-mac.zip'], 05:18:11 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:18:11 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 05:18:11 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:18:11 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:18:11 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 05:18:11 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:18:11 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 05:18:11 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:18:11 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 05:18:11 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:18:11 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 05:18:11 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:18:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C9lYO0JNRLuJjQlgcoCrag/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:18:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C9lYO0JNRLuJjQlgcoCrag/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:18:11 INFO - Fetch https://queue.taskcluster.net/v1/task/C9lYO0JNRLuJjQlgcoCrag/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:18:12 INFO - Expected file size: 52473714 05:18:12 INFO - Obtained file size: 52473714 05:18:28 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:18:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C9lYO0JNRLuJjQlgcoCrag/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:18:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C9lYO0JNRLuJjQlgcoCrag/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:18:28 INFO - Fetch https://queue.taskcluster.net/v1/task/C9lYO0JNRLuJjQlgcoCrag/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:18:30 INFO - Expected file size: 52473714 05:18:30 INFO - Obtained file size: 52473714 05:18:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C9lYO0JNRLuJjQlgcoCrag/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 05:18:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C9lYO0JNRLuJjQlgcoCrag/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:18:46 INFO - Fetch https://queue.taskcluster.net/v1/task/C9lYO0JNRLuJjQlgcoCrag/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 05:18:47 INFO - Expected file size: 14404171 05:18:47 INFO - Obtained file size: 14404171 05:18: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')]} 05:18:47 INFO - trying https://queue.taskcluster.net/v1/task/C9lYO0JNRLuJjQlgcoCrag/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:18:47 INFO - Downloading https://queue.taskcluster.net/v1/task/C9lYO0JNRLuJjQlgcoCrag/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 05:18:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/C9lYO0JNRLuJjQlgcoCrag/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 05:18:49 INFO - Downloaded 75236220 bytes. 05:18:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/C9lYO0JNRLuJjQlgcoCrag/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:18:49 INFO - mkdir: /builds/slave/test/properties 05:18:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:18:49 INFO - Writing to file /builds/slave/test/properties/build_url 05:18:49 INFO - Contents: 05:18:49 INFO - build_url:https://queue.taskcluster.net/v1/task/C9lYO0JNRLuJjQlgcoCrag/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:18:50 INFO - Running post-action listener: _resource_record_post_action 05:18:50 INFO - Running post-action listener: set_extra_try_arguments 05:18:50 INFO - [mozharness: 2017-03-21 12:18:50.580438Z] Finished download-and-extract step (success) 05:18:50 INFO - [mozharness: 2017-03-21 12:18:50.580565Z] Running populate-webroot step. 05:18:50 INFO - Running pre-action listener: _resource_record_pre_action 05:18:50 INFO - Running main action method: populate_webroot 05:18:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:18:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:18:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:18:50 INFO - '--tppagecycles', 05:18:50 INFO - '7'], 05:18:50 INFO - 'tests': ['tsvgm']}}, 05:18:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:18:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:18:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:18:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:18:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:18:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:18:50 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:18:50 INFO - 'talos_options': ['--disable-e10s'], 05:18:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:18:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:18:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:18:50 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:18:50 INFO - 'talos_options': ['--disable-e10s'], 05:18:50 INFO - 'tests': ['damp', 'tps']}, 05:18:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:18:50 INFO - 'tests': ['damp', 'tps']}, 05:18:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:18:50 INFO - 'tests': ['dromaeo_dom']}, 05:18:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:18:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:18:50 INFO - 'tests': ['basic_compositor_video']}, 05:18:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:18:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:18:50 INFO - 'tests': ['a11yr', 05:18:50 INFO - 'ts_paint', 05:18:50 INFO - 'tpaint', 05:18:50 INFO - 'sessionrestore', 05:18:50 INFO - 'sessionrestore_no_auto_restore', 05:18:50 INFO - 'tabpaint']}, 05:18:50 INFO - 'other-e10s': {'tests': ['a11yr', 05:18:50 INFO - 'ts_paint', 05:18:50 INFO - 'tpaint', 05:18:50 INFO - 'sessionrestore', 05:18:50 INFO - 'sessionrestore_no_auto_restore', 05:18:50 INFO - 'tabpaint']}, 05:18:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:18:50 INFO - 'ts_paint', 05:18:50 INFO - 'tpaint', 05:18:50 INFO - 'sessionrestore', 05:18:50 INFO - 'sessionrestore_no_auto_restore', 05:18:50 INFO - 'tabpaint']}, 05:18:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:18:50 INFO - 'ts_paint', 05:18:50 INFO - 'tpaint', 05:18:50 INFO - 'sessionrestore', 05:18:50 INFO - 'sessionrestore_no_auto_restore', 05:18:50 INFO - 'tabpaint']}, 05:18:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:18:50 INFO - 'tests': ['a11yr', 05:18:50 INFO - 'ts_paint', 05:18:50 INFO - 'tpaint', 05:18:50 INFO - 'sessionrestore', 05:18:50 INFO - 'sessionrestore_no_auto_restore', 05:18:50 INFO - 'tabpaint']}, 05:18:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:18:50 INFO - 'tests': ['a11yr', 05:18:50 INFO - 'ts_paint', 05:18:50 INFO - 'tpaint', 05:18:50 INFO - 'sessionrestore', 05:18:50 INFO - 'sessionrestore_no_auto_restore', 05:18:50 INFO - 'tabpaint']}, 05:18:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:18:50 INFO - 'tests': ['tsvgx', 05:18:50 INFO - 'tsvgr_opacity', 05:18:50 INFO - 'tart', 05:18:50 INFO - 'tscrollx', 05:18:50 INFO - 'cart', 05:18:50 INFO - 'tsvg_static']}, 05:18:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:18:50 INFO - 'tsvgr_opacity', 05:18:50 INFO - 'tart', 05:18:50 INFO - 'tscrollx', 05:18:50 INFO - 'cart', 05:18:50 INFO - 'tsvg_static']}, 05:18:50 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:18:50 INFO - 'talos_options': ['--disable-e10s'], 05:18:50 INFO - 'tests': ['tp5o']}, 05:18:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:18:50 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:18:50 INFO - 'talos_options': ['--disable-e10s', 05:18:50 INFO - '--xperf_path', 05:18:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:18:50 INFO - 'tests': ['tp5n']}, 05:18:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:18:50 INFO - 'talos_options': ['--xperf_path', 05:18:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:18:50 INFO - 'tests': ['tp5n']}}, 05:18:50 INFO - 'talos.zip': {'path': '', 05:18:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:18:50 INFO - Running post-action listener: _resource_record_post_action 05:18:50 INFO - [mozharness: 2017-03-21 12:18:50.599069Z] Finished populate-webroot step (success) 05:18:50 INFO - [mozharness: 2017-03-21 12:18:50.599175Z] Running create-virtualenv step. 05:18:50 INFO - Running pre-action listener: _resource_record_pre_action 05:18:50 INFO - Running main action method: create_virtualenv 05:18:50 INFO - Creating virtualenv /builds/slave/test/build/venv 05:18:50 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:18:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:18:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:18:50 INFO - Using real prefix '/tools/python27' 05:18:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:18:51 INFO - Installing distribute.............................................................................................................................................................................................done. 05:18:54 INFO - Installing pip.................done. 05:18:54 INFO - Return code: 0 05:18:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:18:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:18:54 INFO - Reading from file tmpfile_stdout 05:18:54 INFO - Output received: 05:18:54 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:18:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:18: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')]} 05:18:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18: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')]} 05:18:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f6c4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6c7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb079d71bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4a2d30>, '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 0x10f3c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f497be8>, '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.Xx0DQ1RZ0O/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.3HOlwXSHfh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:18:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:18:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xx0DQ1RZ0O/Render', 05:18:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:54 INFO - 'HOME': '/Users/cltbld', 05:18:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:54 INFO - 'LOGNAME': 'cltbld', 05:18:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:54 INFO - 'MOZ_NO_REMOTE': '1', 05:18:54 INFO - 'NO_EM_RESTART': '1', 05:18:54 INFO - 'PAGER': '/bin/cat', 05:18:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:54 INFO - 'PWD': '/builds/slave/test', 05:18:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:54 INFO - 'SHELL': '/bin/bash', 05:18:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HOlwXSHfh/Listeners', 05:18:54 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 05:18:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:54 INFO - 'USER': 'cltbld', 05:18:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:54 INFO - 'XPC_FLAGS': '0x0', 05:18:54 INFO - 'XPC_SERVICE_NAME': '0', 05:18:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:54 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:18:54 INFO - Cleaning up... 05:18:54 INFO - Return code: 0 05:18:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:18: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')]} 05:18:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18: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')]} 05:18:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f6c4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6c7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb079d71bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4a2d30>, '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 0x10f3c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f497be8>, '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.Xx0DQ1RZ0O/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.3HOlwXSHfh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:18:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:18:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xx0DQ1RZ0O/Render', 05:18:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:54 INFO - 'HOME': '/Users/cltbld', 05:18:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:54 INFO - 'LOGNAME': 'cltbld', 05:18:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:54 INFO - 'MOZ_NO_REMOTE': '1', 05:18:54 INFO - 'NO_EM_RESTART': '1', 05:18:54 INFO - 'PAGER': '/bin/cat', 05:18:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:54 INFO - 'PWD': '/builds/slave/test', 05:18:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:54 INFO - 'SHELL': '/bin/bash', 05:18:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HOlwXSHfh/Listeners', 05:18:54 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 05:18:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:54 INFO - 'USER': 'cltbld', 05:18:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:54 INFO - 'XPC_FLAGS': '0x0', 05:18:54 INFO - 'XPC_SERVICE_NAME': '0', 05:18:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:55 INFO - Downloading/unpacking psutil>=3.1.1 05:18:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:18:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:18:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:18:58 INFO - Installing collected packages: psutil 05:18:58 INFO - Running setup.py install for psutil 05:18:58 INFO - building 'psutil._psutil_osx' extension 05:18:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:18:59 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:18:59 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:18:59 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:18:59 INFO - building 'psutil._psutil_posix' extension 05:18:59 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:18:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:18:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:18:59 INFO - ^ 05:18:59 INFO - 1 warning generated. 05:18:59 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:18:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:18:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:18:59 INFO - Successfully installed psutil 05:18:59 INFO - Cleaning up... 05:18:59 INFO - Return code: 0 05:18:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:18:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:18:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:18:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:59 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 0x10f6c4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6c7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb079d71bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4a2d30>, '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 0x10f3c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f497be8>, '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.Xx0DQ1RZ0O/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.3HOlwXSHfh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:18:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:18:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xx0DQ1RZ0O/Render', 05:18:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:59 INFO - 'HOME': '/Users/cltbld', 05:18:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:59 INFO - 'LOGNAME': 'cltbld', 05:18:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:59 INFO - 'MOZ_NO_REMOTE': '1', 05:18:59 INFO - 'NO_EM_RESTART': '1', 05:18:59 INFO - 'PAGER': '/bin/cat', 05:18:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:59 INFO - 'PWD': '/builds/slave/test', 05:18:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:59 INFO - 'SHELL': '/bin/bash', 05:18:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HOlwXSHfh/Listeners', 05:18:59 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 05:18:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:59 INFO - 'USER': 'cltbld', 05:18:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:59 INFO - 'XPC_FLAGS': '0x0', 05:18:59 INFO - 'XPC_SERVICE_NAME': '0', 05:18:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:19:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:19:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:19:02 INFO - Installing collected packages: mozsystemmonitor 05:19:02 INFO - Running setup.py install for mozsystemmonitor 05:19:02 INFO - Successfully installed mozsystemmonitor 05:19:02 INFO - Cleaning up... 05:19:03 INFO - Return code: 0 05:19:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:19: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')]} 05:19:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19: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')]} 05:19:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:03 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 0x10f6c4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6c7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb079d71bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4a2d30>, '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 0x10f3c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f497be8>, '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.Xx0DQ1RZ0O/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.3HOlwXSHfh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:19:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:19:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xx0DQ1RZ0O/Render', 05:19:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:03 INFO - 'HOME': '/Users/cltbld', 05:19:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:03 INFO - 'LOGNAME': 'cltbld', 05:19:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:03 INFO - 'MOZ_NO_REMOTE': '1', 05:19:03 INFO - 'NO_EM_RESTART': '1', 05:19:03 INFO - 'PAGER': '/bin/cat', 05:19:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:03 INFO - 'PWD': '/builds/slave/test', 05:19:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:03 INFO - 'SHELL': '/bin/bash', 05:19:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HOlwXSHfh/Listeners', 05:19:03 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 05:19:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:03 INFO - 'USER': 'cltbld', 05:19:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:03 INFO - 'XPC_FLAGS': '0x0', 05:19:03 INFO - 'XPC_SERVICE_NAME': '0', 05:19:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:04 INFO - Downloading/unpacking jsonschema==2.5.1 05:19:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:19:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:19:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:07 INFO - Downloading functools32-3.2.3-2.tar.gz 05:19:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:19:07 INFO - warning: no files found matching '*.txt' 05:19:07 INFO - no previously-included directories found matching 'build' 05:19:07 INFO - no previously-included directories found matching 'dist' 05:19:07 INFO - no previously-included directories found matching '.git*' 05:19:07 INFO - Installing collected packages: jsonschema, functools32 05:19:07 INFO - Running setup.py install for functools32 05:19:07 INFO - warning: no files found matching '*.txt' 05:19:07 INFO - no previously-included directories found matching 'build' 05:19:07 INFO - no previously-included directories found matching 'dist' 05:19:07 INFO - no previously-included directories found matching '.git*' 05:19:07 INFO - Successfully installed jsonschema functools32 05:19:07 INFO - Cleaning up... 05:19:07 INFO - Return code: 0 05:19:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:19:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f6c4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6c7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb079d71bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4a2d30>, '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 0x10f3c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f497be8>, '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.Xx0DQ1RZ0O/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.3HOlwXSHfh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:19:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:19:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xx0DQ1RZ0O/Render', 05:19:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:07 INFO - 'HOME': '/Users/cltbld', 05:19:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:07 INFO - 'LOGNAME': 'cltbld', 05:19:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:07 INFO - 'MOZ_NO_REMOTE': '1', 05:19:07 INFO - 'NO_EM_RESTART': '1', 05:19:07 INFO - 'PAGER': '/bin/cat', 05:19:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:07 INFO - 'PWD': '/builds/slave/test', 05:19:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:07 INFO - 'SHELL': '/bin/bash', 05:19:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HOlwXSHfh/Listeners', 05:19:07 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 05:19:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:07 INFO - 'USER': 'cltbld', 05:19:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:07 INFO - 'XPC_FLAGS': '0x0', 05:19:07 INFO - 'XPC_SERVICE_NAME': '0', 05:19:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:19:08 INFO - Cleaning up... 05:19:08 INFO - Return code: 0 05:19:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:08 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 0x10f6c4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6c7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb079d71bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4a2d30>, '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 0x10f3c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f497be8>, '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.Xx0DQ1RZ0O/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.3HOlwXSHfh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:19:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:19:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xx0DQ1RZ0O/Render', 05:19:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:08 INFO - 'HOME': '/Users/cltbld', 05:19:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:08 INFO - 'LOGNAME': 'cltbld', 05:19:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:08 INFO - 'MOZ_NO_REMOTE': '1', 05:19:08 INFO - 'NO_EM_RESTART': '1', 05:19:08 INFO - 'PAGER': '/bin/cat', 05:19:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:08 INFO - 'PWD': '/builds/slave/test', 05:19:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:08 INFO - 'SHELL': '/bin/bash', 05:19:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HOlwXSHfh/Listeners', 05:19:08 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 05:19:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:08 INFO - 'USER': 'cltbld', 05:19:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:08 INFO - 'XPC_FLAGS': '0x0', 05:19:08 INFO - 'XPC_SERVICE_NAME': '0', 05:19:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:08 INFO - Downloading/unpacking blobuploader==1.2.4 05:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:11 INFO - Downloading blobuploader-1.2.4.tar.gz 05:19:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:19:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:19:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:12 INFO - Downloading docopt-0.6.1.tar.gz 05:19:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:19:12 INFO - Installing collected packages: blobuploader, requests, docopt 05:19:12 INFO - Running setup.py install for blobuploader 05:19:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:19:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:19:12 INFO - Running setup.py install for requests 05:19:13 INFO - Running setup.py install for docopt 05:19:13 INFO - Successfully installed blobuploader requests docopt 05:19:13 INFO - Cleaning up... 05:19:13 INFO - Return code: 0 05:19:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:19:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:13 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 0x10f6c4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6c7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb079d71bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4a2d30>, '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 0x10f3c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f497be8>, '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.Xx0DQ1RZ0O/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.3HOlwXSHfh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:19:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:19:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xx0DQ1RZ0O/Render', 05:19:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:13 INFO - 'HOME': '/Users/cltbld', 05:19:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:13 INFO - 'LOGNAME': 'cltbld', 05:19:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:13 INFO - 'MOZ_NO_REMOTE': '1', 05:19:13 INFO - 'NO_EM_RESTART': '1', 05:19:13 INFO - 'PAGER': '/bin/cat', 05:19:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:13 INFO - 'PWD': '/builds/slave/test', 05:19:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:13 INFO - 'SHELL': '/bin/bash', 05:19:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HOlwXSHfh/Listeners', 05:19:13 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 05:19:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:13 INFO - 'USER': 'cltbld', 05:19:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:13 INFO - 'XPC_FLAGS': '0x0', 05:19:13 INFO - 'XPC_SERVICE_NAME': '0', 05:19:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:19:13 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-QVsdmW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:19:13 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-3S4OQp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:19:13 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-ZzkLO1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:19:13 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-cCyRkx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:19:14 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-y4Uxyt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:19:14 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-EmD4QQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:19:14 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-XqO13O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:19:14 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-KlGuJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:19:14 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-lg6tg3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:19:14 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-2b7E8d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:19:14 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-46YBOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:19:14 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-zVdnHt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:19:14 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-k6FGse-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:19:14 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-jWNzUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:15 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-CtWRTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:19:15 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-uZDl0H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:19:15 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-mYRlml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:19:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:19:15 INFO - Running setup.py install for manifestparser 05:19:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:19:15 INFO - Running setup.py install for mozcrash 05:19:15 INFO - Running setup.py install for mozdebug 05:19:15 INFO - Running setup.py install for mozdevice 05:19:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:19:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:19:16 INFO - Running setup.py install for mozfile 05:19:16 INFO - Running setup.py install for mozhttpd 05:19:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:19:16 INFO - Running setup.py install for mozinfo 05:19:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:19:16 INFO - Running setup.py install for mozInstall 05:19:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:19:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:19:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:19:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:19:16 INFO - Running setup.py install for mozleak 05:19:16 INFO - Running setup.py install for mozlog 05:19:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:19:16 INFO - Running setup.py install for moznetwork 05:19:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:19:17 INFO - Running setup.py install for mozprocess 05:19:17 INFO - Running setup.py install for mozprofile 05:19:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:19:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:19:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:19:17 INFO - Running setup.py install for mozrunner 05:19:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:19:17 INFO - Running setup.py install for mozscreenshot 05:19:17 INFO - Running setup.py install for moztest 05:19:17 INFO - Running setup.py install for mozversion 05:19:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:19:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:19:17 INFO - Cleaning up... 05:19:17 INFO - Return code: 0 05:19:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:19:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:18 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 0x10f6c4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6c7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb079d71bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4a2d30>, '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 0x10f3c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f497be8>, '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.Xx0DQ1RZ0O/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.3HOlwXSHfh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:19:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:19:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xx0DQ1RZ0O/Render', 05:19:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:18 INFO - 'HOME': '/Users/cltbld', 05:19:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:18 INFO - 'LOGNAME': 'cltbld', 05:19:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:18 INFO - 'MOZ_NO_REMOTE': '1', 05:19:18 INFO - 'NO_EM_RESTART': '1', 05:19:18 INFO - 'PAGER': '/bin/cat', 05:19:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:18 INFO - 'PWD': '/builds/slave/test', 05:19:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:18 INFO - 'SHELL': '/bin/bash', 05:19:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HOlwXSHfh/Listeners', 05:19:18 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 05:19:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:18 INFO - 'USER': 'cltbld', 05:19:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:18 INFO - 'XPC_FLAGS': '0x0', 05:19:18 INFO - 'XPC_SERVICE_NAME': '0', 05:19:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:19:18 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-H0ys5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:19:18 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:19:18 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-itxHQv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:19:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:19:18 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-5IV6mN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:19:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:19:18 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-jlEalE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:19:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:19:18 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-i4jhjl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:19:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:19:18 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-iqN9fF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:19:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:19:18 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-Uxe7J9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:19:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:19:19 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-YpEvB6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:19:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:19:19 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-3ztNjt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:19:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:19:19 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-zg2v0D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:19:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:19:19 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-_RLykU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:19:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:19:19 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-sXo8HY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:19:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:19:19 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-WaeGUS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:19:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:19:19 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-VFefoW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:19:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:19 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-jyzTvo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:19:19 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-oSHCCN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:19:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:19:19 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-qtirJ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:19:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:19:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:22 INFO - Downloading blessings-1.6.tar.gz 05:19:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:19:22 INFO - Installing collected packages: blessings 05:19:22 INFO - Running setup.py install for blessings 05:19:23 INFO - Successfully installed blessings 05:19:23 INFO - Cleaning up... 05:19:23 INFO - Return code: 0 05:19:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:19:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:19:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:19:23 INFO - Reading from file tmpfile_stdout 05:19:23 INFO - Current package versions: 05:19:23 INFO - blessings == 1.6 05:19:23 INFO - blobuploader == 1.2.4 05:19:23 INFO - docopt == 0.6.1 05:19:23 INFO - functools32 == 3.2.3-2 05:19:23 INFO - jsonschema == 2.5.1 05:19:23 INFO - manifestparser == 1.1 05:19:23 INFO - mozInstall == 1.12 05:19:23 INFO - mozcrash == 1.0 05:19:23 INFO - mozdebug == 0.1 05:19:23 INFO - mozdevice == 0.49 05:19:23 INFO - mozfile == 1.2 05:19:23 INFO - mozhttpd == 0.7 05:19:23 INFO - mozinfo == 0.9 05:19:23 INFO - mozleak == 0.1 05:19:23 INFO - mozlog == 3.4 05:19:23 INFO - moznetwork == 0.27 05:19:23 INFO - mozprocess == 0.25 05:19:23 INFO - mozprofile == 0.28 05:19:23 INFO - mozrunner == 6.13 05:19:23 INFO - mozscreenshot == 0.1 05:19:23 INFO - mozsystemmonitor == 0.3 05:19:23 INFO - moztest == 0.8 05:19:23 INFO - mozversion == 1.4 05:19:23 INFO - psutil == 3.1.1 05:19:23 INFO - requests == 1.2.3 05:19:23 INFO - wsgiref == 0.1.2 05:19:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:19:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:23 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 0x10f6c4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6c7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb079d71bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4a2d30>, '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 0x10f3c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f497be8>, '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.Xx0DQ1RZ0O/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.3HOlwXSHfh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:19:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:19:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xx0DQ1RZ0O/Render', 05:19:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:23 INFO - 'HOME': '/Users/cltbld', 05:19:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:23 INFO - 'LOGNAME': 'cltbld', 05:19:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:23 INFO - 'MOZ_NO_REMOTE': '1', 05:19:23 INFO - 'NO_EM_RESTART': '1', 05:19:23 INFO - 'PAGER': '/bin/cat', 05:19:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:23 INFO - 'PWD': '/builds/slave/test', 05:19:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:23 INFO - 'SHELL': '/bin/bash', 05:19:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HOlwXSHfh/Listeners', 05:19:23 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 05:19:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:23 INFO - 'USER': 'cltbld', 05:19:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:23 INFO - 'XPC_FLAGS': '0x0', 05:19:23 INFO - 'XPC_SERVICE_NAME': '0', 05:19:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:19:23 INFO - Cleaning up... 05:19:23 INFO - Return code: 0 05:19:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:19:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:23 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 0x10f6c4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6c7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb079d71bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4a2d30>, '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 0x10f3c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f497be8>, '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.Xx0DQ1RZ0O/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.3HOlwXSHfh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:19:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:19:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xx0DQ1RZ0O/Render', 05:19:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:23 INFO - 'HOME': '/Users/cltbld', 05:19:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:23 INFO - 'LOGNAME': 'cltbld', 05:19:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:23 INFO - 'MOZ_NO_REMOTE': '1', 05:19:23 INFO - 'NO_EM_RESTART': '1', 05:19:23 INFO - 'PAGER': '/bin/cat', 05:19:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:23 INFO - 'PWD': '/builds/slave/test', 05:19:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:23 INFO - 'SHELL': '/bin/bash', 05:19:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HOlwXSHfh/Listeners', 05:19:23 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 05:19:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:23 INFO - 'USER': 'cltbld', 05:19:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:23 INFO - 'XPC_FLAGS': '0x0', 05:19:23 INFO - 'XPC_SERVICE_NAME': '0', 05:19:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:19:23 INFO - Cleaning up... 05:19:23 INFO - Return code: 0 05:19:23 INFO - Running post-action listener: _resource_record_post_action 05:19:23 INFO - Running post-action listener: _start_resource_monitoring 05:19:23 INFO - Starting resource monitoring. 05:19:23 INFO - [mozharness: 2017-03-21 12:19:23.952348Z] Finished create-virtualenv step (success) 05:19:23 INFO - [mozharness: 2017-03-21 12:19:23.953285Z] Running install step. 05:19:23 INFO - Running pre-action listener: _resource_record_pre_action 05:19:23 INFO - Running main action method: install 05:19:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:19:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:19:24 INFO - Reading from file tmpfile_stdout 05:19:24 INFO - Detecting whether we're running mozinstall >=1.0... 05:19:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:19:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:19:24 INFO - Reading from file tmpfile_stdout 05:19:24 INFO - Output received: 05:19:24 INFO - Usage: mozinstall [options] installer 05:19:24 INFO - Options: 05:19:24 INFO - -h, --help show this help message and exit 05:19:24 INFO - -d DEST, --destination=DEST 05:19:24 INFO - Directory to install application into. [default: 05:19:24 INFO - "/builds/slave/test"] 05:19:24 INFO - --app=APP Application being installed. [default: firefox] 05:19:24 INFO - mkdir: /builds/slave/test/build/application 05:19:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:19:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:19:45 INFO - Reading from file tmpfile_stdout 05:19:45 INFO - Output received: 05:19:45 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:19:45 INFO - Running post-action listener: _resource_record_post_action 05:19:45 INFO - [mozharness: 2017-03-21 12:19:45.804995Z] Finished install step (success) 05:19:45 INFO - [mozharness: 2017-03-21 12:19:45.805091Z] Running run-tests step. 05:19:45 INFO - Running pre-action listener: _resource_record_pre_action 05:19:45 INFO - Running main action method: run_tests 05:19:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:19:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:19:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:19:45 INFO - 2017-03-21 05:19:45.817 screenresolution[1629:7476] starting screenresolution argv=screenresolution get 05:19:45 INFO - 2017-03-21 05:19:45.819 screenresolution[1629:7476] Display 0: 1600x1200x32@60 05:19:45 INFO - 2017-03-21 05:19:45.825 screenresolution[1630:7479] starting screenresolution argv=screenresolution list 05:19:45 INFO - Available Modes on Display 0 05:19:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:19:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:19:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:19:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:19:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:19:46 INFO - Intel Iris: 05:19:46 INFO - Chipset Model: Intel Iris 05:19:46 INFO - Type: GPU 05:19:46 INFO - Bus: Built-In 05:19:46 INFO - VRAM (Dynamic, Max): 1536 MB 05:19:46 INFO - Vendor: Intel (0x8086) 05:19:46 INFO - Device ID: 0x0a2e 05:19:46 INFO - Revision ID: 0x0009 05:19:46 INFO - Displays: 05:19:46 INFO - Display: 05:19:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:19:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:19:46 INFO - Display Serial Number: 1600x1200 60 05:19:46 INFO - Main Display: Yes 05:19:46 INFO - Mirror: Off 05:19:46 INFO - Online: Yes 05:19:46 INFO - Rotation: Supported 05:19:46 INFO - Return code: 0 05:19:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:19:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:19:46 INFO - Python 2.7.3 05:19:46 INFO - Return code: 0 05:19:46 INFO - grabbing minidump binary from tooltool 05:19: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')]} 05:19:46 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 0x10f4a2d30>, '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 0x10f3c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f497be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:19:46 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:19:46 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:19:46 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:19:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:19:46 INFO - Return code: 0 05:19:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:19:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:19:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:19:46 INFO - ENV: RUST_BACKTRACE is now 1 05:19:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:19:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:19:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C9lYO0JNRLuJjQlgcoCrag/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0223', '--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 05:19:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/C9lYO0JNRLuJjQlgcoCrag/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0223 --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 05:19:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xx0DQ1RZ0O/Render', 05:19:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:46 INFO - 'HOME': '/Users/cltbld', 05:19:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:46 INFO - 'LOGNAME': 'cltbld', 05:19:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:19:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:19:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:46 INFO - 'MOZ_NO_REMOTE': '1', 05:19:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:19:46 INFO - 'NO_EM_RESTART': '1', 05:19:46 INFO - 'PAGER': '/bin/cat', 05:19:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:46 INFO - 'PWD': '/builds/slave/test', 05:19:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:19:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:46 INFO - 'RUST_BACKTRACE': '1', 05:19:46 INFO - 'SHELL': '/bin/bash', 05:19:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HOlwXSHfh/Listeners', 05:19:46 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 05:19:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:46 INFO - 'USER': 'cltbld', 05:19:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:46 INFO - 'XPC_FLAGS': '0x0', 05:19:46 INFO - 'XPC_SERVICE_NAME': '0', 05:19:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C9lYO0JNRLuJjQlgcoCrag/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0223', '--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 05:19:46 INFO - mozversion application_buildid: 20170321033128 05:19:46 INFO - mozversion application_changeset: ca4ae502156eaea6fffb296bb9c3b3930af8ab58 05:19:46 INFO - mozversion application_display_name: Nightly 05:19:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:19:46 INFO - mozversion application_name: Firefox 05:19:46 INFO - mozversion application_remotingname: firefox 05:19:46 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:19:46 INFO - mozversion application_vendor: Mozilla 05:19:46 INFO - mozversion application_version: 55.0a1 05:19:46 INFO - mozversion platform_buildid: 20170321033128 05:19:46 INFO - mozversion platform_changeset: ca4ae502156eaea6fffb296bb9c3b3930af8ab58 05:19:46 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:19:46 INFO - mozversion platform_version: 55.0a1 05:19:46 INFO - using testdate: 1490098786 05:19:46 INFO - actual date: 1490098786 05:19:46 INFO - starting webserver on 'localhost:49251' 05:19:46 INFO - SUITE-START | Running 2 tests 05:19:46 INFO - TEST-START | dromaeo_css 05:19:46 INFO - operating with platform_type : mac_ 05:19:46 INFO - Initialising browser for dromaeo_css test... 05:19:46 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/tmpI6bNaL/profile http://localhost:49251/getInfo.html) 05:19:49 INFO - PID 1640 | 2017-03-21 05:19:49.192 plugin-container[1642:7663] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 05:19:49 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:19:49 INFO - PID 1640 | 2017-03-21 05:19:49.195 plugin-container[1642:7663] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 05:19:49 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:19:49 INFO - PID 1640 | 2017-03-21 05:19:49.195 plugin-container[1642:7663] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:19:49 INFO - PID 1640 | __metrics Screen width/height:1600/1200 05:19:49 INFO - PID 1640 | colorDepth:24 05:19:49 INFO - PID 1640 | Browser inner width/height: 1024/689 05:19:49 INFO - PID 1640 | __metrics 05:19:49 ERROR - PID 1640 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 05:19:50 INFO - TEST-INFO | 1640: exit 0 05:19:50 INFO - Browser initialized. 05:19:50 INFO - Running cycle 1/1 for dromaeo_css test... 05:19:50 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/tmpI6bNaL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:19:52 INFO - PID 1643 | 2017-03-21 05:19:52.808 plugin-container[1644:7798] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 05:19:52 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:19:52 INFO - PID 1643 | 2017-03-21 05:19:52.809 plugin-container[1644:7798] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471f, name = 'com.apple.CFPasteboardClient' 05:19:52 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:19:52 INFO - PID 1643 | 2017-03-21 05:19:52.809 plugin-container[1644:7798] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:20:02 INFO - PID 1643 | RSS: Main: 227454976 05:20:02 INFO - PID 1643 | 05:23:06 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49251/tests/dromaeo/cssquery-ext.html) 05:23:08 INFO - PID 1643 | RSS: Main: 250621952 05:23:08 INFO - PID 1643 | 05:24:50 INFO - PID 1643 | ************************* 05:24:50 INFO - PID 1643 | A coding exception was thrown and uncaught in a Task. 05:24:50 INFO - PID 1643 | 05:24:50 ERROR - PID 1643 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:24:50 INFO - PID 1643 | Full stack: 05:24:50 INFO - PID 1643 | ************************* 05:26:10 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-ext.html (next: http://localhost:49251/tests/dromaeo/cssquery-jquery.html) 05:26:11 INFO - PID 1643 | RSS: Main: 258752512 05:26:11 INFO - PID 1643 | 05:29:13 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49251/tests/dromaeo/cssquery-mootools.html) 05:29:13 INFO - PID 1643 | RSS: Main: 271560704 05:29:13 INFO - PID 1643 | 05:32:15 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49251/tests/dromaeo/cssquery-prototype.html) 05:32:16 INFO - PID 1643 | RSS: Main: 263872512 05:32:16 INFO - PID 1643 | 05:35:19 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49251/tests/dromaeo/cssquery-yui.html) 05:35:19 INFO - PID 1643 | RSS: Main: 273997824 05:35:19 INFO - PID 1643 | 05:38:23 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-yui.html (next: http://localhost:49251/tests/dromaeo/cssquery-dojo.html) 05:38:23 INFO - PID 1643 | RSS: Main: 267956224 05:38:23 INFO - PID 1643 | 05:38:23 INFO - PID 1643 | __start_tp_report 05:38:23 INFO - PID 1643 | _x_x_mozilla_page_load 05:38:23 INFO - PID 1643 | _x_x_mozilla_page_load_details 05:38:23 INFO - PID 1643 | |i|pagename|runs| 05:38:23 INFO - PID 1643 | |0;dojo.html;3541.6635419635136;3542.7874327540367;3564.429691249399;3568.9107772305674;3593.4609808528717;6027.517798576115;6071.514278857692;6110.052941794023;6123;6145.754169833207;6010.459058684717;6084.787032453865;6111.358307377728;6120.357362476942;6122.632642041481;4618.930716039262;5435.266239057736;5530.861728456801;5557.083081291582;5626.493615574597;5441.7279136043235;5511.696856672881;5562.833115006536;5576;5597.048501754691;5420.647657902263;5450.264214331094;5529.806456773994;5551.555875529948;5580.414056524068;5457.808976685797;5462.890742185174;5480.616356855022;5490.835274941758;5523.337199536041;5403.324584426947;5404.946035523075;5422.6475279106735;5460.097942997751;5518.944810551883;5977.1333156692035;5986.910196347058;6015.2781666200335;6018.578699491038;6027.939720602782;6284;6393.93606063938;6485.092087107809;6487.870242595169;6498.3826536478955;6548.312427195171;6557.540972131953;6562.67186640666;6576.704048317837;6584.11114499546;5137.255098010767;6180.690965451709;6187.721552530102;6196.1635179250225;6228.128062071313;6430.485561155097;6583.506237032242;6651.501137414713;6679.098321726505;6699.9950007499265;5217.843464696065;6245.219347581552;6309.905351419732;6382.266039405527;6397.040443933354;6482.481401487869;6568.441682457044;6575.901361479581;6595.868082638321;6598.47212223021;6458.063580780855;6467.288598254188;6469.288378278386;6491.318411566812;6503.28463868974;6242.875142497124;6259.749610015549;6293.37066293367;6295.842603934938;6390.7443702251385;5275.076861549213;5281.339832520935;5285.920711189336;5311.814086506954;5320.707361095177;4680.017196388796;4684.718916864999;4700.012997270543;4702.788374523155;4720.95279047212;5534.114541673393;6155.953487907066;6240.875182496325;6305.905411418832;6307.684615769193;6539.47684185264;6545.934540654533;6563.737450501926;6586.5389422739945;6590.7693230736695;6213.254409470802;6261.092141639437;6264;6271.341509141565;6279.120923070774;5657.9434205658235;5831.241938547962;5834.649921004753;5835.79574714883;5841.532677385799;6542.34576542342;6725.226598941184;6800;6848.794536163923;6881.827954301182;5877.823665290048;6562.5406221564035;6571.408573228432;6578.079068930258;6598.90101648466;6811.386975172259;6832.658367081725;6892.689828957809;6906.8618627626165;6916.412104970933;5777.91333129995;6254.437100660963;6278.497720182283;6428.19647544057;6465.321141280097;6253.937460625518;6565.409113179837;6603.471722262304;6605.075289459383;6609.603423794395;6207.96896015517;6252.343503932204;6286.7799627014665;6365.140706004815;6374.394432528902;5670.4329567044615;6138.97087834436;6343.4608058313715;6351.364863513611;6382.457491113121;5622;5787.652740835563;6252.812415627538;6271.717772700148;6281.403266689657;6178.907316390348;6261.499080073674;6308.747650093945;6382.6170429774365;6386.680665966776;6221.222347206599;6257.56197066201;6262.1546091278915;6268.592541484789;6290.182276304235 05:38:23 INFO - PID 1643 | |1;ext.html;721.8195451137216;800.1718221640604;800.487687879757;801.7995501124723;822.7819627798633;38622;39211.80394098026;39218.215635687266;39229.60770392295;39335.426582936685;38145.42781858274;38643;38650.420243696295;38706.41940370897;38717.806410967976;2660.7472290132455;2792.371716363812;2874.252694299487;2882.452334056533;2889.6387951506063;2856.2430955796626;2883.0485939639975;2891.696371880954;2892.7975041747086;2898.855057247129;2867.283179205199;2887.1482912540746;2888.8411137387434;2893.175444998176;2893.334533057398;2852.6434195725533;2864.1264414353614;2892.913212603625;2893.971060289391;2903.7096290371005;2838.6593608766875;2845.1037923054173;2854.8287102773693;2864.455753406846;2866.4123854609757;38140.61859381398;38485.61514384848;38498.80750596257;38564.80717596394;38589.22821543582;2929.9121026369244;2949.749271311941;2957.0833041757123;2978.4043191361816;2980.493316136262;28138.85930570334;28918.855405722836;28982.855085724437;29089.41821163565;29868.253293667614;2761.226856480189;2786.679531853822;2789.191134570972;2797.37059161689;2818.957715634267;242201;246215;274477.6276118607;327309;329132.35433822917;47890.52109478861;48084.519154808004;48255;48287.5171248283;48323.75838120787;50192.247116293285;50209.24686129711;51308;51479.74260128675;51520.22719659208;14559.708805823824;14579.343929523196;14663.266836658338;14684.559463216121;14689.412423503154;11864.584739534248;12073.758524829454;12075.215111017756;12081.939590302169;12093.576724814766;13860.683235092853;14017;14068.43726250959;14185.858141418661;14237.288135593179;5833.970830145907;6138.570300079041;6141.539384546177;6161.907571386433;6178.752849886044;958.4153666263574;971.7036303927229;982.3712823792779;1005.3867141043958;1047.5024363427463;21449.356519304445;21536.56926861454;23128.88435557845;24414.511709765706;24565.263042108767;903.5662881816634;1092.431935393591;1130.6494986554194;1142.5886680794906;1143.834144049125;11918.404079795975;12597.622071337692;12617.17988330774;12643.178193417398;12654.050946179073;402163;413539;478100;478106;481436;45379.546204538194;57334.56663583444;57855.71072144612;57858.132128018115;58143;1361.809346691464;1483.9554813355617;1510.18450036981;1555.0125670199504;1556.1830039229494;23990.248271058677;24187.032518699056;24278.757212428358;24375.14686985947;24489.632655509824;35526.28947420985;37486.62513374831;39411.802940985115;39442.802785985885;39674.801625991684;408396;445327;445883;446308;446744;25535.36161595975;26626.467470650867;27016.32459188536;31650.841745791124;34534.96395108151;40332.59667403406;40737.1852562953;40886.59113408828;41012.38481422721;41732.79133604313;34698.181091346;34763.60945562251;35051.82474087613;35084.473732893515;35276.29447411089;33755.493667595496;33785.32429351448;33903;34879.302413952086;34933;35174.26751214035;35557.82221088878;35860.82069589636;35982.46026309555;36082.11343092599;21521;28716.712832871402;29305.41389172162;31021.670902697766;31196.376072477968;23868.761312386654;23878.403039924142;24075.398115047265;24368.75631243736;24487.755122448547 05:38:23 INFO - PID 1643 | |2;jquery.html;403.07494300580163;406.41070447850603;415.6550063447338;429.2745260509738;433.84381622615854;19304.90347548262;20864.68702969455;21484.677729834064;21642.567148657017;21725;21466.677999830015;22659.77340226597;23514.648535146476;23710.88144559279;23839;4149.502059752834;4365.148795984785;4373.868783936487;4377.4528183977;4377.978110109445;3939.5666476687543;4138.420621113047;4265.7440553566885;4344.695871289011;4352.325389564622;4032.6975476839207;4298.312270036795;4320.783960801948;4324.027093903878;4326.632236259922;3737.859952714421;4269.167512335096;4281.486221653406;4308.913821723548;4315.719478233905;3854.1328201154515;3869.342211823997;3922.5292964844116;3950.150717595731;3971.4241434992055;26624.60063099055;26828.865855670792;27247;28133.29666758327;28417.85791071052;1908.551490399755;2000.829929455998;2020.4342784020498;2029.5636438165866;2053.5482193917464;15829.525114246591;16143.515694529184;16189.757153642704;16351.182440877908;16710.08094554799;1620.9189540523046;1627.702864437885;1642.7289497232941;1649.6205872649357;1658.5107393319;125057;141259;161891.5716264257;162295.94260143474;163597;12011.399430028463;12301.815472767916;12350.938245308716;12364.505419783109;12557.497700092077;13287.933560332136;15766.76349854753;16029.438969636007;16031.358745650277;16041.358345666044;8829.16122968317;8900;9035.819283614292;9098.590563424661;9154.267658587298;8654.023936236688;8702.782430439163;8771.868421973675;8899.555022248862;8937.553122343857;7759.418043646749;7876.763697088982;7900.841983160304;7943.086545047278;7960.283574478325;1784.5895444047942;2003.9899800500905;2104.5790841831695;2106.8103870651407;2310.22607426514;788.4874831359548;844.8056946902246;910.6249563159545;931.231733819588;941.9434833909987;20594.073266703035;20728;21017.68473472899;21027.948602570115;21287.574248514942;972.2125078686287;972.8929817720045;1009.2884516415909;1009.6869970309227;1014.9797004059877;7704.730334438268;10611.84082238767;10832.295900766583;11021.173411994132;11036.337819730841;693283;695010;695331.2801231942;710916;711504;91604;135695;143688;144217.27891360686;148989;1120.4341807387218;1132.9773404532027;1199.1366216324232;1229.342301868496;1254.6047994881583;18780.906095469436;20775.58448831045;22049.889750551145;22081.779182208615;22166.445838854157;41518.58481415268;62873.68563157155;63452.68273658602;63900.738269114176;64315.74894878844;78169;78799.21200787919;80181.59909200417;80694;82498.58750706208;8409.03296120954;8609.665501847223;8740.344474164336;8858.645654174019;8963.596638151168;13776.65558361049;13803.309834508435;13978.161310321413;14137.43450261978;14167.64580885486;11297.71755706114;11558.017568506772;11827.40862956866;11959.402029898645;12056.879431205576;10306.866244712926;10522.736931576772;11515.69686062774;11651.41742912834;11779.823302650295;11723.531058757555;11833.053355731692;11992.40037998114;12101.636950891487;12153.635390938285;7301.269873012656;7735.535867847947;8308.750737477885;8532.189442003;8755.124487551195;11292.548298067984;11714.355710436008;11993.160478766402;12009.63971080869;12078.154529182873 05:38:23 INFO - PID 1643 | |3;mootools.html;1314;1390.9234992075437;1457.4753088887999;1472.2344380921913;1497.648052707614;10113.69658910233;10431.217658675592;10522.368657880532;10572.312799668036;10683;10407.895921040787;10557.20820938429;10557.260991730582;10577.841332380001;10582.259241853073;2381.6546600742927;2408.51829634073;2418.9274321770376;2420.2739178246543;2422.39440139965;2367.2779802160335;2402.0631953538136;2408.9882249455272;2415.577273977056;2416.6012607919674;2359.6932398788135;2383.618621020637;2392.1627430399394;2401.0995876546294;2405.122130422392;1986.682130859063;2343.953120937572;2373.1100837186054;2374.6319320505263;2377.4294169399304;2332.696749422581;2381.833271670984;2402.3153401280724;2409.0363854458183;2428.781409673138;10480;10521.316114452537;10527.94736026315;10534.736631584195;10536.789264214673;5377.650452720561;6071.301800292935;6323.336049714806;6330.366963303679;6338.556301058928;3836.251930873481;3985.2826491231567;4017.4174744662155;4024.557298697141;4058.6753059755147;1198.922070065443;1545.9072455652583;1575.550967974122;1592.2277695317762;1657.1714142928536;11372.48823802931;11916.285022898654;13273.402696878664;14252.786208206886;14310.57068287953;5150.253213284052;5299.44355842631;5299.523042926078;5318.9202161967605;5327.254184414185;9879.753006174904;10649.603839136133;11114.944425277983;11242.49408776607;11303.378314192634;5875.265591801025;6208.348123447064;6215.285242197115;6215.875682486325;6217.720202590895;4871;5349.304590403222;5627.099664053813;5732.914006289909;5761.732418867842;3616.294822509585;3941.664958478504;4205.831766729358;4222.662187025031;4262.893427664333;1211.5759484180467;1269.9682507937375;1271.6947932496319;1276.8978481721626;1294.70869054462;606.6299557270062;754.4077898849376;757.8446418484198;768.8045089885283;816.2368185746227;5782.277215348082;6274.717637706314;6438.356164383618;6468.902966455507;6486.091947127406;568.8407245971134;569.7578529125105;579.9478046975793;589.6702934881814;592.4283066840519;3092.659807421182;3246.269589342426;3435.690787829057;3766.0584853786554;3779.263043706451;13083.149595276276;13522.459101636021;13530.864691353157;13533.661658458617;13572.321383930765;13021;13203.07578469498;13317.667058323426;13509.59471215865;13558;9795.363301385387;10017.449040302854;10102.949485252526;10105.494725263707;10109.39343639384;6343.270523889627;6377.7130029147875;6409.8397540060005;6414.604156750687;6454.225492940877;11819.82270265964;11907.583234587095;11950.103742219195;12106.213096148722;12189.878101218874;11086.279391839827;12030.398480076135;12135.878641213474;12156.331401772988;12260.386980650754;7901.17037711032;8269.751907442786;8636.740897773077;8917.242034427147;8927.732168034969;9668.613255469703;9788.706338809847;9885.802283954425;10047.598096076075;10048.643433136433;7003.859922801617;8807.207351338155;8807.91192088071;9041.819163616823;9074.500902450429;8631.784205394664;8685.652573896974;8732.388732788644;8801.779955501164;8825;5810.796622118206;7565;7719.575423351769;7725.111612164673;7732.9947106878;5226.398964119175;5665.461781130786;5760.423957604206;5927.762889484372;5956.523478121653;5400.702961337164;5725.627834190931;5909.5863289569315;5995.61028533144;6030.638161710312 05:38:23 INFO - PID 1643 | |4;prototype.html;343.5173581118526;399.32713377958123;400.2934820042625;401.9738716983397;407.64942149751226;5561.4705955862055;5575.916361254584;6066.848328791777;6188.298217990049;6196.845078873031;5378;5459.290292262011;5718.914216286748;5851.3270973838025;6053.455189032986;3629.8185090745487;4260.914781704364;4276.97861510692;4674.883127921795;4695.67130300879;3518.771279866813;4133.904515303432;4714.996126646179;4716.764161791897;4741.857744267678;3871.322518559255;4540.931886021713;4691.179043667362;4703.858884233478;4725.763711814413;3400.2009527760956;4528.6150677192145;4623.9768801156115;4675.976620116895;4699.412573428322;4145.839165033795;4146.854860079883;4157.750534967881;4160.292750232467;4180.770057646828;5067.543921047123;5269.446708095633;5751.223584816038;5996.4603185713495;5996.8500787480225;4055.71609987301;4293.29160688486;5120.900430823344;5436.037359638966;5495.093309603911;5301.6818990860675;6150.538709596798;6476.255230648489;6499.480041596661;6537.836554086139;3803.619638036202;4285;4419.690621656486;4574.153781550391;4641.489436162019;6444.226692796848;6748.7975360738765;6863.176418829722;6881.243063263037;6923.896141557881;4314.784260786948;4398.3842262083335;4620.907581848344;4780.11567859947;4795.017021510582;4458.7324760514475;4835.540623640749;4859.562639362474;4870.732109733999;4899.1426500362295;5279.762410691451;5448.83653490396;5448.863778405572;5492.780288788404;5500.257465242221;6501.254084518234;6502.479801615859;6529.902051469232;6547.345265473415;6581.407673309425;1500.647347873249;1569.7017566662298;1651.6614094110678;1666.3667806233555;1669.0403018264547;989.980200395988;1116.9553217871357;1136.9602063927723;1153.9653810385703;1162.9825552616717;5370.62405631602;5438.91841622376;6093.451589356979;6274.592151510137;6312.589681670676;5683.261176047087;6365.267994180728;6485.54601177913;6710.731570737116;6721.4958878084335;5883.940620782691;5901.911471327933;6220.626762394271;6239.688015599202;6256.900625989803;3527.659489394065;3532.2691880978136;3535.5680949215607;3543.8936831895085;3559.18138828071;6160.661163635953;6531.902021469682;6730.495212859055;7028.2620324866175;7130.967674212824;6294.464970477469;6784.694688739019;6871.415929645936;6908.7891874600655;6922.58464492122;5353.9732301338245;6739.157605299338;6992.160940687048;7137.607431591208;7145.714171433188;4912.975435122801;5643.830685079454;5677.545796336366;6101.786437474667;6622.1722284714415;4487.775611219491;6565.868682626417;6588.472922166119;6604.735810567524;6627.4698024158915;6178.351273116258;6411.070394792822;7042.6126563039525;7202.279772022755;7208.27917208275;3889.727719059752;4417.8232870684815;4435.689501734848;4464.754438505784;4468.664850136318;6168.537359698131;6532.608043517404;6772;6796.553240833197;6798.76204332846;6242.720242350398;6509.381608747161;6537.346265373425;6555.164553924908;6631.403173714389;6347.334119669842;6494.545381823227;6506.121673574007;6594.70323835419;6629.2376376715365;6311.715972781328;6400.55196136266;6411.96794016027;6420.3773283236;6422.197225346832;6366.872662546816;6538.542302038812;6544.004078755835;6558.967205163944;6574.474042076718;6336.6376229110965;6376.171097757262;6384.872302554016;6395.20059992501;6428.100065990671 05:38:23 INFO - PID 1643 | |5;yui.html;628.8427893026743;699.1094558780387;721.455301247558;725.9382952449041;750.4859171467543;15050.473233436833;15101.62245943852;15635.296411661475;15836.445724399648;15872.285747141363;14345.42618295267;14633.12201267922;14725.116493010426;14759.335829887654;15175.08949463033;920.5535315372056;930.3348106104135;932.3147486597345;933.4446004627235;933.9066093390675;904.0507467159489;942.0108885670053;950.9191718703919;959.3524371049549;963.2919803944095;992.3004281981222;999.4053538144792;1005.316384858296;1006.0442579549457;1006.4615430744553;872.8734333521636;996.3424140067557;1001.4892404873494;1001.7295330260862;1002.879654441468;637.9904301435482;735.3896266099179;743.4424181863602;745.8396444764347;745.9813504662372;14118.50585229512;14967.727743141846;16505.504834854975;16568.917155414267;16571.66856662872;13777.242251676154;14600.050996685182;14706.044107133002;14786.556403307917;14887.330070146761;11517.654470365902;11661.125415593864;12015.459304331325;12056.216345937486;12306;2993.670696223414;3008.0197286318826;3106.0216031949863;3177.06276648387;3196.6004249468815;90977;100070;106784;107263;107381;11028.60984537228;11170.776584468427;11247.212695111264;11333.26333788301;11795.174337796443;24603.753962460145;24649.01403943822;24873;24878.12926547598;24931.376715582257;6777.661116944133;7226.241244669339;7746.41901857363;7820.413468989735;7930.167332430127;4062.6749860011128;4115.609017143366;4132.91734165315;4165;4165.4584903962295;3784.751032159381;3840.385538313912;3854.6530812226474;3873.3802591585213;3875.5155605549307;784.4391260248835;785.4266385538589;805.6052534258172;812.6546217857505;813.5281536708695;500.1123006663202;535.6250624562822;549.8755045931001;576.8038866785313;599.637219482207;9272.119148680864;10064.44645544502;10144.28989970695;10206.234532410099;10316.27786054969;520.6017396691534;536.1261144334787;543.1662398218795;564.1453198404421;570.3925319534765;4481.641468682497;4659.487456379796;4734.479207287196;4844.363222385285;4882.902341953141;106058;108587;109018.90981090246;109226.45386773015;110146.44926775315;70666.64666676633;74987.62506187434;78260;79045;79255.69675942091;76470;76957.61521192358;77196;77211;77866;10533.559073672744;10775.299605525615;10786.73033174177;10880.347179169275;10892.727681808019;36163;36478.27043459169;36950.44574331441;37138;37423.62576374201;44903;44978.87552811206;45044;45118;45743.313850292936;13171.953005142203;13324;13497.190168589916;13571.38928748189;13593.45626174942;14263.290183494342;14403.567892963229;14437.927810361301;14500.927495362455;14860.925695371454;13232.801507977196;13299.867001329863;13420.396082176521;13469.979503074617;13631.459355717772;12221.572244971383;13290.0032497565;13376.665583360495;13381.264030478416;14290.356933938207;12786.926210050524;13054.738905222031;13731.588052358446;13734.65663358424;13818.792718109438;9546.618135274512;10908.94545527299;10964.396958167377;11086.722831929266;11554.826677599674;9630.807383852423;10546.367217966947;10861.636539864103;11270.492827822603;11498.425078745862 05:38:23 INFO - PID 1643 | __end_tp_report 05:38:23 INFO - PID 1643 | __start_cc_report 05:38:23 INFO - PID 1643 | _x_x_mozilla_cycle_collect,427 05:38:23 INFO - PID 1643 | __end_cc_report 05:38:23 INFO - PID 1643 | __startTimestamp1490099903399__endTimestamp 05:38:23 INFO - PID 1643 | 05:38:23 INFO - PID 1643 | ------- Summary: start ------- 05:38:23 INFO - PID 1643 | Number of tests: 6 05:38:23 INFO - PID 1643 | 05:38:23 INFO - PID 1643 | [#0] dojo.html Cycles:165 Average:6023.03 Median:6252.81 stddev:669.00 (10.7%) stddev-sans-first:642.10 05:38:23 INFO - PID 1643 | Values: 3541.7 3542.8 3564.4 3568.9 3593.5 6027.5 6071.5 6110.1 6123.0 6145.8 6010.5 6084.8 6111.4 6120.4 6122.6 4618.9 5435.3 5530.9 5557.1 5626.5 5441.7 5511.7 5562.8 5576.0 5597.0 5420.6 5450.3 5529.8 5551.6 5580.4 5457.8 5462.9 5480.6 5490.8 5523.3 5403.3 5404.9 5422.6 5460.1 5518.9 5977.1 5986.9 6015.3 6018.6 6027.9 6284.0 6393.9 6485.1 6487.9 6498.4 6548.3 6557.5 6562.7 6576.7 6584.1 5137.3 6180.7 6187.7 6196.2 6228.1 6430.5 6583.5 6651.5 6679.1 6700.0 5217.8 6245.2 6309.9 6382.3 6397.0 6482.5 6568.4 6575.9 6595.9 6598.5 6458.1 6467.3 6469.3 6491.3 6503.3 6242.9 6259.7 6293.4 6295.8 6390.7 5275.1 5281.3 5285.9 5311.8 5320.7 4680.0 4684.7 4700.0 4702.8 4721.0 5534.1 6156.0 6240.9 6305.9 6307.7 6539.5 6545.9 6563.7 6586.5 6590.8 6213.3 6261.1 6264.0 6271.3 6279.1 5657.9 5831.2 5834.6 5835.8 5841.5 6542.3 6725.2 6800.0 6848.8 6881.8 5877.8 6562.5 6571.4 6578.1 6598.9 6811.4 6832.7 6892.7 6906.9 6916.4 5777.9 6254.4 6278.5 6428.2 6465.3 6253.9 6565.4 6603.5 6605.1 6609.6 6208.0 6252.3 6286.8 6365.1 6374.4 5670.4 6139.0 6343.5 6351.4 6382.5 5622.0 5787.7 6252.8 6271.7 6281.4 6178.9 6261.5 6308.7 6382.6 6386.7 6221.2 6257.6 6262.2 6268.6 6290.2 05:38:23 INFO - PID 1643 | 05:38:23 INFO - PID 1643 | [#1] ext.html Cycles:180 Average:51929.21 Median:2953.42 stddev:106514.28 (3606.5%) stddev-sans-first:106743.70 05:38:23 INFO - PID 1643 | Values: 721.8 800.2 800.5 801.8 822.8 38622.0 39211.8 39218.2 39229.6 39335.4 38145.4 38643.0 38650.4 38706.4 38717.8 2660.7 2792.4 2874.3 2882.5 2889.6 2856.2 2883.0 2891.7 2892.8 2898.9 2867.3 2887.1 2888.8 2893.2 2893.3 2852.6 2864.1 2892.9 2894.0 2903.7 2838.7 2845.1 2854.8 2864.5 2866.4 38140.6 38485.6 38498.8 38564.8 38589.2 2929.9 2949.7 2957.1 2978.4 2980.5 28138.9 28918.9 28982.9 29089.4 29868.3 2761.2 2786.7 2789.2 2797.4 2819.0 242201.0 246215.0 274477.6 327309.0 329132.4 47890.5 48084.5 48255.0 48287.5 48323.8 50192.2 50209.2 51308.0 51479.7 51520.2 14559.7 14579.3 14663.3 14684.6 14689.4 11864.6 12073.8 12075.2 12081.9 12093.6 13860.7 14017.0 14068.4 14185.9 14237.3 5834.0 6138.6 6141.5 6161.9 6178.8 958.4 971.7 982.4 1005.4 1047.5 21449.4 21536.6 23128.9 24414.5 24565.3 903.6 1092.4 1130.6 1142.6 1143.8 11918.4 12597.6 12617.2 12643.2 12654.1 402163.0 413539.0 478100.0 478106.0 481436.0 45379.5 57334.6 57855.7 57858.1 58143.0 1361.8 1484.0 1510.2 1555.0 1556.2 23990.2 24187.0 24278.8 24375.1 24489.6 35526.3 37486.6 39411.8 39442.8 39674.8 408396.0 445327.0 445883.0 446308.0 446744.0 25535.4 26626.5 27016.3 31650.8 34535.0 40332.6 40737.2 40886.6 41012.4 41732.8 34698.2 34763.6 35051.8 35084.5 35276.3 33755.5 33785.3 33903.0 34879.3 34933.0 35174.3 35557.8 35860.8 35982.5 36082.1 21521.0 28716.7 29305.4 31021.7 31196.4 23868.8 23878.4 24075.4 24368.8 24487.8 05:38:23 INFO - PID 1643 | 05:38:23 INFO - PID 1643 | [#2] jquery.html Cycles:180 Average:39589.84 Median:22413.11 stddev:117109.93 (522.5%) stddev-sans-first:117401.49 05:38:23 INFO - PID 1643 | Values: 403.1 406.4 415.7 429.3 433.8 19304.9 20864.7 21484.7 21642.6 21725.0 21466.7 22659.8 23514.6 23710.9 23839.0 4149.5 4365.1 4373.9 4377.5 4378.0 3939.6 4138.4 4265.7 4344.7 4352.3 4032.7 4298.3 4320.8 4324.0 4326.6 3737.9 4269.2 4281.5 4308.9 4315.7 3854.1 3869.3 3922.5 3950.2 3971.4 26624.6 26828.9 27247.0 28133.3 28417.9 1908.6 2000.8 2020.4 2029.6 2053.5 15829.5 16143.5 16189.8 16351.2 16710.1 1620.9 1627.7 1642.7 1649.6 1658.5 125057.0 141259.0 161891.6 162295.9 163597.0 12011.4 12301.8 12350.9 12364.5 12557.5 13287.9 15766.8 16029.4 16031.4 16041.4 8829.2 8900.0 9035.8 9098.6 9154.3 8654.0 8702.8 8771.9 8899.6 8937.6 7759.4 7876.8 7900.8 7943.1 7960.3 1784.6 2004.0 2104.6 2106.8 2310.2 788.5 844.8 910.6 931.2 941.9 20594.1 20728.0 21017.7 21027.9 21287.6 972.2 972.9 1009.3 1009.7 1015.0 7704.7 10611.8 10832.3 11021.2 11036.3 693283.0 695010.0 695331.3 710916.0 711504.0 91604.0 135695.0 143688.0 144217.3 148989.0 1120.4 1133.0 1199.1 1229.3 1254.6 18780.9 20775.6 22049.9 22081.8 22166.4 41518.6 62873.7 63452.7 63900.7 64315.7 78169.0 78799.2 80181.6 80694.0 82498.6 8409.0 8609.7 8740.3 8858.6 8963.6 13776.7 13803.3 13978.2 14137.4 14167.6 11297.7 11558.0 11827.4 11959.4 12056.9 10306.9 10522.7 11515.7 11651.4 11779.8 11723.5 11833.1 11992.4 12101.6 12153.6 7301.3 7735.5 8308.8 8532.2 8755.1 11292.5 11714.4 11993.2 12009.6 12078.2 05:38:23 INFO - PID 1643 | 05:38:23 INFO - PID 1643 | [#3] mootools.html Cycles:180 Average:6533.90 Median:3169.46 stddev:3964.98 (125.1%) stddev-sans-first:3956.70 05:38:23 INFO - PID 1643 | Values: 1314.0 1390.9 1457.5 1472.2 1497.6 10113.7 10431.2 10522.4 10572.3 10683.0 10407.9 10557.2 10557.3 10577.8 10582.3 2381.7 2408.5 2418.9 2420.3 2422.4 2367.3 2402.1 2409.0 2415.6 2416.6 2359.7 2383.6 2392.2 2401.1 2405.1 1986.7 2344.0 2373.1 2374.6 2377.4 2332.7 2381.8 2402.3 2409.0 2428.8 10480.0 10521.3 10527.9 10534.7 10536.8 5377.7 6071.3 6323.3 6330.4 6338.6 3836.3 3985.3 4017.4 4024.6 4058.7 1198.9 1545.9 1575.6 1592.2 1657.2 11372.5 11916.3 13273.4 14252.8 14310.6 5150.3 5299.4 5299.5 5318.9 5327.3 9879.8 10649.6 11114.9 11242.5 11303.4 5875.3 6208.3 6215.3 6215.9 6217.7 4871.0 5349.3 5627.1 5732.9 5761.7 3616.3 3941.7 4205.8 4222.7 4262.9 1211.6 1270.0 1271.7 1276.9 1294.7 606.6 754.4 757.8 768.8 816.2 5782.3 6274.7 6438.4 6468.9 6486.1 568.8 569.8 579.9 589.7 592.4 3092.7 3246.3 3435.7 3766.1 3779.3 13083.1 13522.5 13530.9 13533.7 13572.3 13021.0 13203.1 13317.7 13509.6 13558.0 9795.4 10017.4 10102.9 10105.5 10109.4 6343.3 6377.7 6409.8 6414.6 6454.2 11819.8 11907.6 11950.1 12106.2 12189.9 11086.3 12030.4 12135.9 12156.3 12260.4 7901.2 8269.8 8636.7 8917.2 8927.7 9668.6 9788.7 9885.8 10047.6 10048.6 7003.9 8807.2 8807.9 9041.8 9074.5 8631.8 8685.7 8732.4 8801.8 8825.0 5810.8 7565.0 7719.6 7725.1 7733.0 5226.4 5665.5 5760.4 5927.8 5956.5 5400.7 5725.6 5909.6 5995.6 6030.6 05:38:23 INFO - PID 1643 | 05:38:23 INFO - PID 1643 | [#4] prototype.html Cycles:180 Average:5269.27 Median:5801.28 stddev:1628.83 (28.1%) stddev-sans-first:1590.89 05:38:23 INFO - PID 1643 | Values: 343.5 399.3 400.3 402.0 407.6 5561.5 5575.9 6066.8 6188.3 6196.8 5378.0 5459.3 5718.9 5851.3 6053.5 3629.8 4260.9 4277.0 4674.9 4695.7 3518.8 4133.9 4715.0 4716.8 4741.9 3871.3 4540.9 4691.2 4703.9 4725.8 3400.2 4528.6 4624.0 4676.0 4699.4 4145.8 4146.9 4157.8 4160.3 4180.8 5067.5 5269.4 5751.2 5996.5 5996.9 4055.7 4293.3 5120.9 5436.0 5495.1 5301.7 6150.5 6476.3 6499.5 6537.8 3803.6 4285.0 4419.7 4574.2 4641.5 6444.2 6748.8 6863.2 6881.2 6923.9 4314.8 4398.4 4620.9 4780.1 4795.0 4458.7 4835.5 4859.6 4870.7 4899.1 5279.8 5448.8 5448.9 5492.8 5500.3 6501.3 6502.5 6529.9 6547.3 6581.4 1500.6 1569.7 1651.7 1666.4 1669.0 990.0 1117.0 1137.0 1154.0 1163.0 5370.6 5438.9 6093.5 6274.6 6312.6 5683.3 6365.3 6485.5 6710.7 6721.5 5883.9 5901.9 6220.6 6239.7 6256.9 3527.7 3532.3 3535.6 3543.9 3559.2 6160.7 6531.9 6730.5 7028.3 7131.0 6294.5 6784.7 6871.4 6908.8 6922.6 5354.0 6739.2 6992.2 7137.6 7145.7 4913.0 5643.8 5677.5 6101.8 6622.2 4487.8 6565.9 6588.5 6604.7 6627.5 6178.4 6411.1 7042.6 7202.3 7208.3 3889.7 4417.8 4435.7 4464.8 4468.7 6168.5 6532.6 6772.0 6796.6 6798.8 6242.7 6509.4 6537.3 6555.2 6631.4 6347.3 6494.5 6506.1 6594.7 6629.2 6311.7 6400.6 6412.0 6420.4 6422.2 6366.9 6538.5 6544.0 6559.0 6574.5 6336.6 6376.2 6384.9 6395.2 6428.1 05:38:23 INFO - PID 1643 | 05:38:23 INFO - PID 1643 | [#5] yui.html Cycles:180 Average:19336.16 Median:24904.75 stddev:27660.96 (111.1%) stddev-sans-first:27702.89 05:38:23 INFO - PID 1643 | Values: 628.8 699.1 721.5 725.9 750.5 15050.5 15101.6 15635.3 15836.4 15872.3 14345.4 14633.1 14725.1 14759.3 15175.1 920.6 930.3 932.3 933.4 933.9 904.1 942.0 950.9 959.4 963.3 992.3 999.4 1005.3 1006.0 1006.5 872.9 996.3 1001.5 1001.7 1002.9 638.0 735.4 743.4 745.8 746.0 14118.5 14967.7 16505.5 16568.9 16571.7 13777.2 14600.1 14706.0 14786.6 14887.3 11517.7 11661.1 12015.5 12056.2 12306.0 2993.7 3008.0 3106.0 3177.1 3196.6 90977.0 100070.0 106784.0 107263.0 107381.0 11028.6 11170.8 11247.2 11333.3 11795.2 24603.8 24649.0 24873.0 24878.1 24931.4 6777.7 7226.2 7746.4 7820.4 7930.2 4062.7 4115.6 4132.9 4165.0 4165.5 3784.8 3840.4 3854.7 3873.4 3875.5 784.4 785.4 805.6 812.7 813.5 500.1 535.6 549.9 576.8 599.6 9272.1 10064.4 10144.3 10206.2 10316.3 520.6 536.1 543.2 564.1 570.4 4481.6 4659.5 4734.5 4844.4 4882.9 106058.0 108587.0 109018.9 109226.5 110146.4 70666.6 74987.6 78260.0 79045.0 79255.7 76470.0 76957.6 77196.0 77211.0 77866.0 10533.6 10775.3 10786.7 10880.3 10892.7 36163.0 36478.3 36950.4 37138.0 37423.6 44903.0 44978.9 45044.0 45118.0 45743.3 13172.0 13324.0 13497.2 13571.4 13593.5 14263.3 14403.6 14437.9 14500.9 14860.9 13232.8 13299.9 13420.4 13470.0 13631.5 12221.6 13290.0 13376.7 13381.3 14290.4 12786.9 13054.7 13731.6 13734.7 13818.8 9546.6 10908.9 10964.4 11086.7 11554.8 9630.8 10546.4 10861.6 11270.5 11498.4 05:38:23 INFO - PID 1643 | -------- Summary: end -------- 05:38:23 INFO - PID 1643 | 05:38:24 INFO - TEST-INFO | 1643: exit 0 05:38:24 INFO - TEST-OK | dromaeo_css | took 1117743ms 05:38:24 INFO - TEST-START | kraken 05:38:24 INFO - operating with platform_type : mac_ 05:38:24 INFO - Initialising browser for kraken test... 05:38:24 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/tmp22prMH/profile http://localhost:49251/getInfo.html) 05:38:26 INFO - PID 1656 | 2017-03-21 05:38:26.864 plugin-container[1657:10297] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.tsm.portname' 05:38:26 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:38:26 INFO - PID 1656 | 2017-03-21 05:38:26.867 plugin-container[1657:10297] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e13, name = 'com.apple.CFPasteboardClient' 05:38:26 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:38:26 INFO - PID 1656 | 2017-03-21 05:38:26.867 plugin-container[1657:10297] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:38:26 INFO - PID 1656 | __metrics Screen width/height:1600/1200 05:38:26 INFO - PID 1656 | colorDepth:24 05:38:26 INFO - PID 1656 | Browser inner width/height: 1024/689 05:38:26 INFO - PID 1656 | __metrics 05:38:27 ERROR - PID 1656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 05:38:27 INFO - TEST-INFO | 1656: exit 0 05:38:27 INFO - Browser initialized. 05:38:27 INFO - Running cycle 1/1 for kraken test... 05:38:27 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/tmp22prMH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:38:30 INFO - PID 1658 | 2017-03-21 05:38:30.303 plugin-container[1659:10421] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3b, name = 'com.apple.tsm.portname' 05:38:30 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:38:30 INFO - PID 1658 | 2017-03-21 05:38:30.304 plugin-container[1659:10421] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e13, name = 'com.apple.CFPasteboardClient' 05:38:30 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:38:30 INFO - PID 1658 | 2017-03-21 05:38:30.304 plugin-container[1659:10421] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:38:40 INFO - PID 1658 | RSS: Main: 228171776 05:38:40 INFO - PID 1658 | 05:38:43 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-beat-detection) 05:38:44 INFO - PID 1658 | RSS: Main: 242311168 05:38:44 INFO - PID 1658 | 05:38:46 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-dft) 05:38:47 INFO - PID 1658 | RSS: Main: 250851328 05:38:47 INFO - PID 1658 | 05:38:50 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-fft) 05:38:51 INFO - PID 1658 | RSS: Main: 253583360 05:38:51 INFO - PID 1658 | 05:38:53 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-oscillator) 05:38:54 INFO - PID 1658 | RSS: Main: 255651840 05:38:54 INFO - PID 1658 | 05:38:56 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:38:56 INFO - PID 1658 | RSS: Main: 256331776 05:38:56 INFO - PID 1658 | 05:39:00 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-darkroom) 05:39:00 INFO - PID 1658 | RSS: Main: 261238784 05:39:00 INFO - PID 1658 | 05:39:03 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-desaturate) 05:39:04 INFO - PID 1658 | RSS: Main: 261799936 05:39:04 INFO - PID 1658 | 05:39:07 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49251/tests/kraken/driver.html?testName=json-parse-financial) 05:39:08 INFO - PID 1658 | RSS: Main: 258998272 05:39:08 INFO - PID 1658 | 05:39:10 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49251/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:39:10 INFO - PID 1658 | RSS: Main: 260300800 05:39:10 INFO - PID 1658 | 05:39:12 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:39:13 INFO - PID 1658 | RSS: Main: 260636672 05:39:13 INFO - PID 1658 | 05:39:15 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:39:16 INFO - PID 1658 | RSS: Main: 260751360 05:39:16 INFO - PID 1658 | 05:39:19 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:39:20 INFO - PID 1658 | RSS: Main: 261107712 05:39:20 INFO - PID 1658 | 05:39:23 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:39:23 INFO - PID 1658 | RSS: Main: 260853760 05:39:23 INFO - PID 1658 | 05:39:25 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49251/tests/kraken/driver.html?testName=ai-astar) 05:39:25 INFO - PID 1658 | RSS: Main: 263905280 05:39:25 INFO - PID 1658 | 05:39:25 INFO - PID 1658 | __start_tp_report 05:39:25 INFO - PID 1658 | _x_x_mozilla_page_load 05:39:25 INFO - PID 1658 | _x_x_mozilla_page_load_details 05:39:25 INFO - PID 1658 | |i|pagename|runs| 05:39:25 INFO - PID 1658 | |0;ai-astar;141;173;164;114;114;132;146;151;98;166 05:39:25 INFO - PID 1658 | |1;audio-beat-detection;157;148;143;157;117;116;119;135;172;117 05:39:25 INFO - PID 1658 | |2;audio-dft;204;242;298;197;196;199;198;199;194;198 05:39:25 INFO - PID 1658 | |3;audio-fft;82;57;63;88;59;66;62;63;78;61 05:39:25 INFO - PID 1658 | |4;audio-oscillator;90;72;85;73;71;58;102;103;105;115 05:39:25 INFO - PID 1658 | |5;imaging-gaussian-blur;102;87;92;88;105;111;131;108;135;133 05:39:25 INFO - PID 1658 | |6;imaging-darkroom;97;97;98;98;96;98;100;98;98;121 05:39:25 INFO - PID 1658 | |7;imaging-desaturate;147;146;134;96;143;109;140;145;91;91 05:39:25 INFO - PID 1658 | |8;json-parse-financial;69;67;67;68;86;99;98;97;75;68 05:39:25 INFO - PID 1658 | |9;json-stringify-tinderbox;60;58;67;66;58;58;57;57;58;59 05:39:25 INFO - PID 1658 | |10;stanford-crypto-aes;84;85;97;74;99;100;107;105;82;85 05:39:25 INFO - PID 1658 | |11;stanford-crypto-ccm;141;143;139;140;144;142;162;157;204;160 05:39:25 INFO - PID 1658 | |12;stanford-crypto-pbkdf2;189;185;190;187;193;188;189;171;193;190 05:39:25 INFO - PID 1658 | |13;stanford-crypto-sha256-iterative;71;71;72;93;97;75;75;83;93;95 05:39:25 INFO - PID 1658 | __end_tp_report 05:39:25 INFO - PID 1658 | __start_cc_report 05:39:25 INFO - PID 1658 | _x_x_mozilla_cycle_collect,1007 05:39:25 INFO - PID 1658 | __end_cc_report 05:39:25 INFO - PID 1658 | __startTimestamp1490099965845__endTimestamp 05:39:25 INFO - PID 1658 | 05:39:25 INFO - PID 1658 | ------- Summary: start ------- 05:39:25 INFO - PID 1658 | Number of tests: 14 05:39:25 INFO - PID 1658 | 05:39:25 INFO - PID 1658 | [#0] ai-astar Cycles:10 Average:139.90 Median:157.50 stddev:25.12 (15.9%) stddev-sans-first:26.64 05:39:25 INFO - PID 1658 | Values: 141.0 173.0 164.0 114.0 114.0 132.0 146.0 151.0 98.0 166.0 05:39:25 INFO - PID 1658 | 05:39:25 INFO - PID 1658 | [#1] audio-beat-detection Cycles:10 Average:138.10 Median:145.50 stddev:20.38 (14.0%) stddev-sans-first:20.44 05:39:25 INFO - PID 1658 | Values: 157.0 148.0 143.0 157.0 117.0 116.0 119.0 135.0 172.0 117.0 05:39:25 INFO - PID 1658 | 05:39:25 INFO - PID 1658 | [#2] audio-dft Cycles:10 Average:212.50 Median:199.00 stddev:33.15 (16.7%) stddev-sans-first:35.02 05:39:25 INFO - PID 1658 | Values: 204.0 242.0 298.0 197.0 196.0 199.0 198.0 199.0 194.0 198.0 05:39:25 INFO - PID 1658 | 05:39:25 INFO - PID 1658 | [#3] audio-fft Cycles:10 Average:67.90 Median:64.50 stddev:10.73 (16.6%) stddev-sans-first:10.10 05:39:25 INFO - PID 1658 | Values: 82.0 57.0 63.0 88.0 59.0 66.0 62.0 63.0 78.0 61.0 05:39:25 INFO - PID 1658 | 05:39:25 INFO - PID 1658 | [#4] audio-oscillator Cycles:10 Average:87.40 Median:71.50 stddev:18.61 (26.0%) stddev-sans-first:19.72 05:39:25 INFO - PID 1658 | Values: 90.0 72.0 85.0 73.0 71.0 58.0 102.0 103.0 105.0 115.0 05:39:25 INFO - PID 1658 | 05:39:25 INFO - PID 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:109.20 Median:134.00 stddev:18.32 (13.7%) stddev-sans-first:19.24 05:39:25 INFO - PID 1658 | Values: 102.0 87.0 92.0 88.0 105.0 111.0 131.0 108.0 135.0 133.0 05:39:25 INFO - PID 1658 | 05:39:25 INFO - PID 1658 | [#6] imaging-darkroom Cycles:10 Average:100.10 Median:98.00 stddev:7.42 (7.6%) stddev-sans-first:7.78 05:39:25 INFO - PID 1658 | Values: 97.0 97.0 98.0 98.0 96.0 98.0 100.0 98.0 98.0 121.0 05:39:25 INFO - PID 1658 | 05:39:25 INFO - PID 1658 | [#7] imaging-desaturate Cycles:10 Average:124.20 Median:146.50 stddev:24.40 (16.7%) stddev-sans-first:24.44 05:39:25 INFO - PID 1658 | Values: 147.0 146.0 134.0 96.0 143.0 109.0 140.0 145.0 91.0 91.0 05:39:25 INFO - PID 1658 | 05:39:25 INFO - PID 1658 | [#8] json-parse-financial Cycles:10 Average:79.40 Median:80.50 stddev:14.06 (17.5%) stddev-sans-first:14.40 05:39:25 INFO - PID 1658 | Values: 69.0 67.0 67.0 68.0 86.0 99.0 98.0 97.0 75.0 68.0 05:39:25 INFO - PID 1658 | 05:39:25 INFO - PID 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:58.50 stddev:3.65 (6.2%) stddev-sans-first:3.87 05:39:25 INFO - PID 1658 | Values: 60.0 58.0 67.0 66.0 58.0 58.0 57.0 57.0 58.0 59.0 05:39:25 INFO - PID 1658 | 05:39:25 INFO - PID 1658 | [#10] stanford-crypto-aes Cycles:10 Average:91.80 Median:84.50 stddev:11.14 (13.2%) stddev-sans-first:11.46 05:39:25 INFO - PID 1658 | Values: 84.0 85.0 97.0 74.0 99.0 100.0 107.0 105.0 82.0 85.0 05:39:25 INFO - PID 1658 | 05:39:25 INFO - PID 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:153.20 Median:150.50 stddev:19.88 (13.2%) stddev-sans-first:20.59 05:39:25 INFO - PID 1658 | Values: 141.0 143.0 139.0 140.0 144.0 142.0 162.0 157.0 204.0 160.0 05:39:25 INFO - PID 1658 | 05:39:25 INFO - PID 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.50 Median:189.50 stddev:6.29 (3.3%) stddev-sans-first:6.65 05:39:25 INFO - PID 1658 | Values: 189.0 185.0 190.0 187.0 193.0 188.0 189.0 171.0 193.0 190.0 05:39:25 INFO - PID 1658 | 05:39:25 INFO - PID 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:82.50 Median:88.00 stddev:10.93 (12.4%) stddev-sans-first:10.77 05:39:25 INFO - PID 1658 | Values: 71.0 71.0 72.0 93.0 97.0 75.0 75.0 83.0 93.0 95.0 05:39:25 INFO - PID 1658 | -------- Summary: end -------- 05:39:25 INFO - PID 1658 | 05:39:26 INFO - TEST-INFO | 1658: exit 0 05:39:26 INFO - TEST-OK | kraken | took 62608ms 05:39:26 INFO - SUITE-END | took 1180s 05:39:26 INFO - Completed test suite (00:19:40) 05:39:26 INFO - javascript benchmark 05:39:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7353.281120017843, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3541.6635419635136, 3542.7874327540367, 3564.429691249399, 3568.9107772305674, 3593.4609808528717, 6027.517798576115, 6071.514278857692, 6110.052941794023, 6123.0, 6145.754169833207, 6010.459058684717, 6084.787032453865, 6111.358307377728, 6120.357362476942, 6122.632642041481, 4618.930716039262, 5435.266239057736, 5530.861728456801, 5557.083081291582, 5626.493615574597, 5441.7279136043235, 5511.696856672881, 5562.833115006536, 5576.0, 5597.048501754691, 5420.647657902263, 5450.264214331094, 5529.806456773994, 5551.555875529948, 5580.414056524068, 5457.808976685797, 5462.890742185174, 5480.616356855022, 5490.835274941758, 5523.337199536041, 5403.324584426947, 5404.946035523075, 5422.6475279106735, 5460.097942997751, 5518.944810551883, 5977.1333156692035, 5986.910196347058, 6015.2781666200335, 6018.578699491038, 6027.939720602782, 6284.0, 6393.93606063938, 6485.092087107809, 6487.870242595169, 6498.3826536478955, 6548.312427195171, 6557.540972131953, 6562.67186640666, 6576.704048317837, 6584.11114499546, 5137.255098010767, 6180.690965451709, 6187.721552530102, 6196.1635179250225, 6228.128062071313, 6430.485561155097, 6583.506237032242, 6651.501137414713, 6679.098321726505, 6699.9950007499265, 5217.843464696065, 6245.219347581552, 6309.905351419732, 6382.266039405527, 6397.040443933354, 6482.481401487869, 6568.441682457044, 6575.901361479581, 6595.868082638321, 6598.47212223021, 6458.063580780855, 6467.288598254188, 6469.288378278386, 6491.318411566812, 6503.28463868974, 6242.875142497124, 6259.749610015549, 6293.37066293367, 6295.842603934938, 6390.7443702251385, 5275.076861549213, 5281.339832520935, 5285.920711189336, 5311.814086506954, 5320.707361095177, 4680.017196388796, 4684.718916864999, 4700.012997270543, 4702.788374523155, 4720.95279047212, 5534.114541673393, 6155.953487907066, 6240.875182496325, 6305.905411418832, 6307.684615769193, 6539.47684185264, 6545.934540654533, 6563.737450501926, 6586.5389422739945, 6590.7693230736695, 6213.254409470802, 6261.092141639437, 6264.0, 6271.341509141565, 6279.120923070774, 5657.9434205658235, 5831.241938547962, 5834.649921004753, 5835.79574714883, 5841.532677385799, 6542.34576542342, 6725.226598941184, 6800.0, 6848.794536163923, 6881.827954301182, 5877.823665290048, 6562.5406221564035, 6571.408573228432, 6578.079068930258, 6598.90101648466, 6811.386975172259, 6832.658367081725, 6892.689828957809, 6906.8618627626165, 6916.412104970933, 5777.91333129995, 6254.437100660963, 6278.497720182283, 6428.19647544057, 6465.321141280097, 6253.937460625518, 6565.409113179837, 6603.471722262304, 6605.075289459383, 6609.603423794395, 6207.96896015517, 6252.343503932204, 6286.7799627014665, 6365.140706004815, 6374.394432528902, 5670.4329567044615, 6138.97087834436, 6343.4608058313715, 6351.364863513611, 6382.457491113121, 5622.0, 5787.652740835563, 6252.812415627538, 6271.717772700148, 6281.403266689657, 6178.907316390348, 6261.499080073674, 6308.747650093945, 6382.6170429774365, 6386.680665966776, 6221.222347206599, 6257.56197066201, 6262.1546091278915, 6268.592541484789, 6290.182276304235], "value": 5982.050386855146, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [721.8195451137216, 800.1718221640604, 800.487687879757, 801.7995501124723, 822.7819627798633, 38622.0, 39211.80394098026, 39218.215635687266, 39229.60770392295, 39335.426582936685, 38145.42781858274, 38643.0, 38650.420243696295, 38706.41940370897, 38717.806410967976, 2660.7472290132455, 2792.371716363812, 2874.252694299487, 2882.452334056533, 2889.6387951506063, 2856.2430955796626, 2883.0485939639975, 2891.696371880954, 2892.7975041747086, 2898.855057247129, 2867.283179205199, 2887.1482912540746, 2888.8411137387434, 2893.175444998176, 2893.334533057398, 2852.6434195725533, 2864.1264414353614, 2892.913212603625, 2893.971060289391, 2903.7096290371005, 2838.6593608766875, 2845.1037923054173, 2854.8287102773693, 2864.455753406846, 2866.4123854609757, 38140.61859381398, 38485.61514384848, 38498.80750596257, 38564.80717596394, 38589.22821543582, 2929.9121026369244, 2949.749271311941, 2957.0833041757123, 2978.4043191361816, 2980.493316136262, 28138.85930570334, 28918.855405722836, 28982.855085724437, 29089.41821163565, 29868.253293667614, 2761.226856480189, 2786.679531853822, 2789.191134570972, 2797.37059161689, 2818.957715634267, 242201.0, 246215.0, 274477.6276118607, 327309.0, 329132.35433822917, 47890.52109478861, 48084.519154808004, 48255.0, 48287.5171248283, 48323.75838120787, 50192.247116293285, 50209.24686129711, 51308.0, 51479.74260128675, 51520.22719659208, 14559.708805823824, 14579.343929523196, 14663.266836658338, 14684.559463216121, 14689.412423503154, 11864.584739534248, 12073.758524829454, 12075.215111017756, 12081.939590302169, 12093.576724814766, 13860.683235092853, 14017.0, 14068.43726250959, 14185.858141418661, 14237.288135593179, 5833.970830145907, 6138.570300079041, 6141.539384546177, 6161.907571386433, 6178.752849886044, 958.4153666263574, 971.7036303927229, 982.3712823792779, 1005.3867141043958, 1047.5024363427463, 21449.356519304445, 21536.56926861454, 23128.88435557845, 24414.511709765706, 24565.263042108767, 903.5662881816634, 1092.431935393591, 1130.6494986554194, 1142.5886680794906, 1143.834144049125, 11918.404079795975, 12597.622071337692, 12617.17988330774, 12643.178193417398, 12654.050946179073, 402163.0, 413539.0, 478100.0, 478106.0, 481436.0, 45379.546204538194, 57334.56663583444, 57855.71072144612, 57858.132128018115, 58143.0, 1361.809346691464, 1483.9554813355617, 1510.18450036981, 1555.0125670199504, 1556.1830039229494, 23990.248271058677, 24187.032518699056, 24278.757212428358, 24375.14686985947, 24489.632655509824, 35526.28947420985, 37486.62513374831, 39411.802940985115, 39442.802785985885, 39674.801625991684, 408396.0, 445327.0, 445883.0, 446308.0, 446744.0, 25535.36161595975, 26626.467470650867, 27016.32459188536, 31650.841745791124, 34534.96395108151, 40332.59667403406, 40737.1852562953, 40886.59113408828, 41012.38481422721, 41732.79133604313, 34698.181091346, 34763.60945562251, 35051.82474087613, 35084.473732893515, 35276.29447411089, 33755.493667595496, 33785.32429351448, 33903.0, 34879.302413952086, 34933.0, 35174.26751214035, 35557.82221088878, 35860.82069589636, 35982.46026309555, 36082.11343092599, 21521.0, 28716.712832871402, 29305.41389172162, 31021.670902697766, 31196.376072477968, 23868.761312386654, 23878.403039924142, 24075.398115047265, 24368.75631243736, 24487.755122448547], "value": 15216.671935674029, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [403.07494300580163, 406.41070447850603, 415.6550063447338, 429.2745260509738, 433.84381622615854, 19304.90347548262, 20864.68702969455, 21484.677729834064, 21642.567148657017, 21725.0, 21466.677999830015, 22659.77340226597, 23514.648535146476, 23710.88144559279, 23839.0, 4149.502059752834, 4365.148795984785, 4373.868783936487, 4377.4528183977, 4377.978110109445, 3939.5666476687543, 4138.420621113047, 4265.7440553566885, 4344.695871289011, 4352.325389564622, 4032.6975476839207, 4298.312270036795, 4320.783960801948, 4324.027093903878, 4326.632236259922, 3737.859952714421, 4269.167512335096, 4281.486221653406, 4308.913821723548, 4315.719478233905, 3854.1328201154515, 3869.342211823997, 3922.5292964844116, 3950.150717595731, 3971.4241434992055, 26624.60063099055, 26828.865855670792, 27247.0, 28133.29666758327, 28417.85791071052, 1908.551490399755, 2000.829929455998, 2020.4342784020498, 2029.5636438165866, 2053.5482193917464, 15829.525114246591, 16143.515694529184, 16189.757153642704, 16351.182440877908, 16710.08094554799, 1620.9189540523046, 1627.702864437885, 1642.7289497232941, 1649.6205872649357, 1658.5107393319, 125057.0, 141259.0, 161891.5716264257, 162295.94260143474, 163597.0, 12011.399430028463, 12301.815472767916, 12350.938245308716, 12364.505419783109, 12557.497700092077, 13287.933560332136, 15766.76349854753, 16029.438969636007, 16031.358745650277, 16041.358345666044, 8829.16122968317, 8900.0, 9035.819283614292, 9098.590563424661, 9154.267658587298, 8654.023936236688, 8702.782430439163, 8771.868421973675, 8899.555022248862, 8937.553122343857, 7759.418043646749, 7876.763697088982, 7900.841983160304, 7943.086545047278, 7960.283574478325, 1784.5895444047942, 2003.9899800500905, 2104.5790841831695, 2106.8103870651407, 2310.22607426514, 788.4874831359548, 844.8056946902246, 910.6249563159545, 931.231733819588, 941.9434833909987, 20594.073266703035, 20728.0, 21017.68473472899, 21027.948602570115, 21287.574248514942, 972.2125078686287, 972.8929817720045, 1009.2884516415909, 1009.6869970309227, 1014.9797004059877, 7704.730334438268, 10611.84082238767, 10832.295900766583, 11021.173411994132, 11036.337819730841, 693283.0, 695010.0, 695331.2801231942, 710916.0, 711504.0, 91604.0, 135695.0, 143688.0, 144217.27891360686, 148989.0, 1120.4341807387218, 1132.9773404532027, 1199.1366216324232, 1229.342301868496, 1254.6047994881583, 18780.906095469436, 20775.58448831045, 22049.889750551145, 22081.779182208615, 22166.445838854157, 41518.58481415268, 62873.68563157155, 63452.68273658602, 63900.738269114176, 64315.74894878844, 78169.0, 78799.21200787919, 80181.59909200417, 80694.0, 82498.58750706208, 8409.03296120954, 8609.665501847223, 8740.344474164336, 8858.645654174019, 8963.596638151168, 13776.65558361049, 13803.309834508435, 13978.161310321413, 14137.43450261978, 14167.64580885486, 11297.71755706114, 11558.017568506772, 11827.40862956866, 11959.402029898645, 12056.879431205576, 10306.866244712926, 10522.736931576772, 11515.69686062774, 11651.41742912834, 11779.823302650295, 11723.531058757555, 11833.053355731692, 11992.40037998114, 12101.636950891487, 12153.635390938285, 7301.269873012656, 7735.535867847947, 8308.750737477885, 8532.189442003, 8755.124487551195, 11292.548298067984, 11714.355710436008, 11993.160478766402, 12009.63971080869, 12078.154529182873], "value": 9828.037044678576, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1314.0, 1390.9234992075437, 1457.4753088887999, 1472.2344380921913, 1497.648052707614, 10113.69658910233, 10431.217658675592, 10522.368657880532, 10572.312799668036, 10683.0, 10407.895921040787, 10557.20820938429, 10557.260991730582, 10577.841332380001, 10582.259241853073, 2381.6546600742927, 2408.51829634073, 2418.9274321770376, 2420.2739178246543, 2422.39440139965, 2367.2779802160335, 2402.0631953538136, 2408.9882249455272, 2415.577273977056, 2416.6012607919674, 2359.6932398788135, 2383.618621020637, 2392.1627430399394, 2401.0995876546294, 2405.122130422392, 1986.682130859063, 2343.953120937572, 2373.1100837186054, 2374.6319320505263, 2377.4294169399304, 2332.696749422581, 2381.833271670984, 2402.3153401280724, 2409.0363854458183, 2428.781409673138, 10480.0, 10521.316114452537, 10527.94736026315, 10534.736631584195, 10536.789264214673, 5377.650452720561, 6071.301800292935, 6323.336049714806, 6330.366963303679, 6338.556301058928, 3836.251930873481, 3985.2826491231567, 4017.4174744662155, 4024.557298697141, 4058.6753059755147, 1198.922070065443, 1545.9072455652583, 1575.550967974122, 1592.2277695317762, 1657.1714142928536, 11372.48823802931, 11916.285022898654, 13273.402696878664, 14252.786208206886, 14310.57068287953, 5150.253213284052, 5299.44355842631, 5299.523042926078, 5318.9202161967605, 5327.254184414185, 9879.753006174904, 10649.603839136133, 11114.944425277983, 11242.49408776607, 11303.378314192634, 5875.265591801025, 6208.348123447064, 6215.285242197115, 6215.875682486325, 6217.720202590895, 4871.0, 5349.304590403222, 5627.099664053813, 5732.914006289909, 5761.732418867842, 3616.294822509585, 3941.664958478504, 4205.831766729358, 4222.662187025031, 4262.893427664333, 1211.5759484180467, 1269.9682507937375, 1271.6947932496319, 1276.8978481721626, 1294.70869054462, 606.6299557270062, 754.4077898849376, 757.8446418484198, 768.8045089885283, 816.2368185746227, 5782.277215348082, 6274.717637706314, 6438.356164383618, 6468.902966455507, 6486.091947127406, 568.8407245971134, 569.7578529125105, 579.9478046975793, 589.6702934881814, 592.4283066840519, 3092.659807421182, 3246.269589342426, 3435.690787829057, 3766.0584853786554, 3779.263043706451, 13083.149595276276, 13522.459101636021, 13530.864691353157, 13533.661658458617, 13572.321383930765, 13021.0, 13203.07578469498, 13317.667058323426, 13509.59471215865, 13558.0, 9795.363301385387, 10017.449040302854, 10102.949485252526, 10105.494725263707, 10109.39343639384, 6343.270523889627, 6377.7130029147875, 6409.8397540060005, 6414.604156750687, 6454.225492940877, 11819.82270265964, 11907.583234587095, 11950.103742219195, 12106.213096148722, 12189.878101218874, 11086.279391839827, 12030.398480076135, 12135.878641213474, 12156.331401772988, 12260.386980650754, 7901.17037711032, 8269.751907442786, 8636.740897773077, 8917.242034427147, 8927.732168034969, 9668.613255469703, 9788.706338809847, 9885.802283954425, 10047.598096076075, 10048.643433136433, 7003.859922801617, 8807.207351338155, 8807.91192088071, 9041.819163616823, 9074.500902450429, 8631.784205394664, 8685.652573896974, 8732.388732788644, 8801.779955501164, 8825.0, 5810.796622118206, 7565.0, 7719.575423351769, 7725.111612164673, 7732.9947106878, 5226.398964119175, 5665.461781130786, 5760.423957604206, 5927.762889484372, 5956.523478121653, 5400.702961337164, 5725.627834190931, 5909.5863289569315, 5995.61028533144, 6030.638161710312], "value": 4994.165718524738, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.5173581118526, 399.32713377958123, 400.2934820042625, 401.9738716983397, 407.64942149751226, 5561.4705955862055, 5575.916361254584, 6066.848328791777, 6188.298217990049, 6196.845078873031, 5378.0, 5459.290292262011, 5718.914216286748, 5851.3270973838025, 6053.455189032986, 3629.8185090745487, 4260.914781704364, 4276.97861510692, 4674.883127921795, 4695.67130300879, 3518.771279866813, 4133.904515303432, 4714.996126646179, 4716.764161791897, 4741.857744267678, 3871.322518559255, 4540.931886021713, 4691.179043667362, 4703.858884233478, 4725.763711814413, 3400.2009527760956, 4528.6150677192145, 4623.9768801156115, 4675.976620116895, 4699.412573428322, 4145.839165033795, 4146.854860079883, 4157.750534967881, 4160.292750232467, 4180.770057646828, 5067.543921047123, 5269.446708095633, 5751.223584816038, 5996.4603185713495, 5996.8500787480225, 4055.71609987301, 4293.29160688486, 5120.900430823344, 5436.037359638966, 5495.093309603911, 5301.6818990860675, 6150.538709596798, 6476.255230648489, 6499.480041596661, 6537.836554086139, 3803.619638036202, 4285.0, 4419.690621656486, 4574.153781550391, 4641.489436162019, 6444.226692796848, 6748.7975360738765, 6863.176418829722, 6881.243063263037, 6923.896141557881, 4314.784260786948, 4398.3842262083335, 4620.907581848344, 4780.11567859947, 4795.017021510582, 4458.7324760514475, 4835.540623640749, 4859.562639362474, 4870.732109733999, 4899.1426500362295, 5279.762410691451, 5448.83653490396, 5448.863778405572, 5492.780288788404, 5500.257465242221, 6501.254084518234, 6502.479801615859, 6529.902051469232, 6547.345265473415, 6581.407673309425, 1500.647347873249, 1569.7017566662298, 1651.6614094110678, 1666.3667806233555, 1669.0403018264547, 989.980200395988, 1116.9553217871357, 1136.9602063927723, 1153.9653810385703, 1162.9825552616717, 5370.62405631602, 5438.91841622376, 6093.451589356979, 6274.592151510137, 6312.589681670676, 5683.261176047087, 6365.267994180728, 6485.54601177913, 6710.731570737116, 6721.4958878084335, 5883.940620782691, 5901.911471327933, 6220.626762394271, 6239.688015599202, 6256.900625989803, 3527.659489394065, 3532.2691880978136, 3535.5680949215607, 3543.8936831895085, 3559.18138828071, 6160.661163635953, 6531.902021469682, 6730.495212859055, 7028.2620324866175, 7130.967674212824, 6294.464970477469, 6784.694688739019, 6871.415929645936, 6908.7891874600655, 6922.58464492122, 5353.9732301338245, 6739.157605299338, 6992.160940687048, 7137.607431591208, 7145.714171433188, 4912.975435122801, 5643.830685079454, 5677.545796336366, 6101.786437474667, 6622.1722284714415, 4487.775611219491, 6565.868682626417, 6588.472922166119, 6604.735810567524, 6627.4698024158915, 6178.351273116258, 6411.070394792822, 7042.6126563039525, 7202.279772022755, 7208.27917208275, 3889.727719059752, 4417.8232870684815, 4435.689501734848, 4464.754438505784, 4468.664850136318, 6168.537359698131, 6532.608043517404, 6772.0, 6796.553240833197, 6798.76204332846, 6242.720242350398, 6509.381608747161, 6537.346265373425, 6555.164553924908, 6631.403173714389, 6347.334119669842, 6494.545381823227, 6506.121673574007, 6594.70323835419, 6629.2376376715365, 6311.715972781328, 6400.55196136266, 6411.96794016027, 6420.3773283236, 6422.197225346832, 6366.872662546816, 6538.542302038812, 6544.004078755835, 6558.967205163944, 6574.474042076718, 6336.6376229110965, 6376.171097757262, 6384.872302554016, 6395.20059992501, 6428.100065990671], "value": 4773.937707056598, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [628.8427893026743, 699.1094558780387, 721.455301247558, 725.9382952449041, 750.4859171467543, 15050.473233436833, 15101.62245943852, 15635.296411661475, 15836.445724399648, 15872.285747141363, 14345.42618295267, 14633.12201267922, 14725.116493010426, 14759.335829887654, 15175.08949463033, 920.5535315372056, 930.3348106104135, 932.3147486597345, 933.4446004627235, 933.9066093390675, 904.0507467159489, 942.0108885670053, 950.9191718703919, 959.3524371049549, 963.2919803944095, 992.3004281981222, 999.4053538144792, 1005.316384858296, 1006.0442579549457, 1006.4615430744553, 872.8734333521636, 996.3424140067557, 1001.4892404873494, 1001.7295330260862, 1002.879654441468, 637.9904301435482, 735.3896266099179, 743.4424181863602, 745.8396444764347, 745.9813504662372, 14118.50585229512, 14967.727743141846, 16505.504834854975, 16568.917155414267, 16571.66856662872, 13777.242251676154, 14600.050996685182, 14706.044107133002, 14786.556403307917, 14887.330070146761, 11517.654470365902, 11661.125415593864, 12015.459304331325, 12056.216345937486, 12306.0, 2993.670696223414, 3008.0197286318826, 3106.0216031949863, 3177.06276648387, 3196.6004249468815, 90977.0, 100070.0, 106784.0, 107263.0, 107381.0, 11028.60984537228, 11170.776584468427, 11247.212695111264, 11333.26333788301, 11795.174337796443, 24603.753962460145, 24649.01403943822, 24873.0, 24878.12926547598, 24931.376715582257, 6777.661116944133, 7226.241244669339, 7746.41901857363, 7820.413468989735, 7930.167332430127, 4062.6749860011128, 4115.609017143366, 4132.91734165315, 4165.0, 4165.4584903962295, 3784.751032159381, 3840.385538313912, 3854.6530812226474, 3873.3802591585213, 3875.5155605549307, 784.4391260248835, 785.4266385538589, 805.6052534258172, 812.6546217857505, 813.5281536708695, 500.1123006663202, 535.6250624562822, 549.8755045931001, 576.8038866785313, 599.637219482207, 9272.119148680864, 10064.44645544502, 10144.28989970695, 10206.234532410099, 10316.27786054969, 520.6017396691534, 536.1261144334787, 543.1662398218795, 564.1453198404421, 570.3925319534765, 4481.641468682497, 4659.487456379796, 4734.479207287196, 4844.363222385285, 4882.902341953141, 106058.0, 108587.0, 109018.90981090246, 109226.45386773015, 110146.44926775315, 70666.64666676633, 74987.62506187434, 78260.0, 79045.0, 79255.69675942091, 76470.0, 76957.61521192358, 77196.0, 77211.0, 77866.0, 10533.559073672744, 10775.299605525615, 10786.73033174177, 10880.347179169275, 10892.727681808019, 36163.0, 36478.27043459169, 36950.44574331441, 37138.0, 37423.62576374201, 44903.0, 44978.87552811206, 45044.0, 45118.0, 45743.313850292936, 13171.953005142203, 13324.0, 13497.190168589916, 13571.38928748189, 13593.45626174942, 14263.290183494342, 14403.567892963229, 14437.927810361301, 14500.927495362455, 14860.925695371454, 13232.801507977196, 13299.867001329863, 13420.396082176521, 13469.979503074617, 13631.459355717772, 12221.572244971383, 13290.0032497565, 13376.665583360495, 13381.264030478416, 14290.356933938207, 12786.926210050524, 13054.738905222031, 13731.588052358446, 13734.65663358424, 13818.792718109438, 9546.618135274512, 10908.94545527299, 10964.396958167377, 11086.722831929266, 11554.826677599674, 9630.807383852423, 10546.367217966947, 10861.636539864103, 11270.492827822603, 11498.425078745862], "value": 7411.110768194435, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1633.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 173.0, 164.0, 114.0, 114.0, 132.0, 146.0, 151.0, 98.0, 166.0], "value": 139.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 148.0, 143.0, 157.0, 117.0, 116.0, 119.0, 135.0, 172.0, 117.0], "value": 138.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 242.0, 298.0, 197.0, 196.0, 199.0, 198.0, 199.0, 194.0, 198.0], "value": 212.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 57.0, 63.0, 88.0, 59.0, 66.0, 62.0, 63.0, 78.0, 61.0], "value": 67.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 72.0, 85.0, 73.0, 71.0, 58.0, 102.0, 103.0, 105.0, 115.0], "value": 87.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 87.0, 92.0, 88.0, 105.0, 111.0, 131.0, 108.0, 135.0, 133.0], "value": 109.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 98.0, 98.0, 96.0, 98.0, 100.0, 98.0, 98.0, 121.0], "value": 100.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 134.0, 96.0, 143.0, 109.0, 140.0, 145.0, 91.0, 91.0], "value": 124.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 67.0, 68.0, 86.0, 99.0, 98.0, 97.0, 75.0, 68.0], "value": 79.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 58.0, 67.0, 66.0, 58.0, 58.0, 57.0, 57.0, 58.0, 59.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 97.0, 74.0, 99.0, 100.0, 107.0, 105.0, 82.0, 85.0], "value": 91.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 143.0, 139.0, 140.0, 144.0, 142.0, 162.0, 157.0, 204.0, 160.0], "value": 153.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 185.0, 190.0, 187.0, 193.0, 188.0, 189.0, 171.0, 193.0, 190.0], "value": 187.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 72.0, 93.0, 97.0, 75.0, 75.0, 83.0, 93.0, 95.0], "value": 82.5, "unit": "score"}]}]} 05:39:27 INFO - Return code: 0 05:39:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:39:27 ERROR - # TBPL SUCCESS # 05:39:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:39:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:39:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:39:27 INFO - 2017-03-21 05:39:27.167 screenresolution[1663:10808] starting screenresolution argv=screenresolution get 05:39:27 INFO - 2017-03-21 05:39:27.170 screenresolution[1663:10808] Display 0: 1600x1200x32@60 05:39:27 INFO - 2017-03-21 05:39:27.176 screenresolution[1664:10811] starting screenresolution argv=screenresolution list 05:39:27 INFO - Available Modes on Display 0 05:39:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:39:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:39:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:39:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:39:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:39:27 INFO - Intel Iris: 05:39:27 INFO - Chipset Model: Intel Iris 05:39:27 INFO - Type: GPU 05:39:27 INFO - Bus: Built-In 05:39:27 INFO - VRAM (Dynamic, Max): 1536 MB 05:39:27 INFO - Vendor: Intel (0x8086) 05:39:27 INFO - Device ID: 0x0a2e 05:39:27 INFO - Revision ID: 0x0009 05:39:27 INFO - Displays: 05:39:27 INFO - Display: 05:39:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:39:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:39:27 INFO - Display Serial Number: 1600x1200 60 05:39:27 INFO - Main Display: Yes 05:39:27 INFO - Mirror: Off 05:39:27 INFO - Online: Yes 05:39:27 INFO - Rotation: Supported 05:39:27 INFO - Return code: 0 05:39:27 INFO - Running post-action listener: _resource_record_post_action 05:39:27 INFO - [mozharness: 2017-03-21 12:39:27.410831Z] Finished run-tests step (success) 05:39:27 INFO - Running post-run listener: _resource_record_post_run 05:39:27 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 39004672; Write bytes: 514001408; Read time: 248; Write time: 1616 05:39:27 INFO - TinderboxPrint: CPU usage
54.1% 05:39:27 INFO - TinderboxPrint: I/O read bytes / time
39,004,672 / 248 05:39:27 INFO - TinderboxPrint: I/O write bytes / time
514,001,408 / 1,616 05:39:27 INFO - TinderboxPrint: CPU idle
2,206.4 (45.9%) 05:39:27 INFO - TinderboxPrint: CPU system
969.9 (20.2%) 05:39:27 INFO - TinderboxPrint: CPU user
1,633.4 (34.0%) 05:39:27 INFO - TinderboxPrint: Swap in / out
249,868,288 / 0 05:39:27 INFO - install - Wall time: 22s; CPU: 55.0%; Read bytes: 202414592; Write bytes: 203621888; Read time: 16279; Write time: 442 05:39:27 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 38046208; Write bytes: 300438528; Read time: 234; Write time: 1144 05:39:27 INFO - Running post-run listener: _upload_blobber_files 05:39:27 INFO - Blob upload gear active. 05:39:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:39:27 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:39:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:39:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:39:28 INFO - (blobuploader) - INFO - Open directory for files ... 05:39:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:39:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:39:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:39:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:39:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:39:29 INFO - (blobuploader) - INFO - Done attempting. 05:39:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:39:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:39:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:39:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:39:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:39:30 INFO - (blobuploader) - INFO - Done attempting. 05:39:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:39:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:39:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:39:31 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:39:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:39:31 INFO - (blobuploader) - INFO - Done attempting. 05:39:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:39:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:39:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:39:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:39:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:39:34 INFO - (blobuploader) - INFO - Done attempting. 05:39:34 INFO - (blobuploader) - INFO - Iteration through files over. 05:39:34 INFO - Return code: 0 05:39:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:39:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:39:34 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99b041c9f19dd4e1d407c31a95e029b0c957dcd80c001c9f5e4781150994e08a436c800e0fcf3c9071e3673221b5aecffa1d94561679c6aab6ecfff983cfb035", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94044cb7211a7aa77e33823caaef1ef5f918a3bbb16aecbd8c5451ed9f4da6da7800229839ae605f9829b27337fcf6283005622c95241d6d215a6915e9ba84fd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea2a5f515ba4bde32810385fc3d3e4de0b537b620f01f43505b2771dce834f234222a1198f60eed7a025dff3a541188ba8705b38788c2b657b761a0cdf5f588e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/952bb192e1ae1d387ec1d3703cca469a035558f5c16bcb4ec630ef2d13f2b27128f314735a15a6e8e7ebd6bfab54da07bbac1364757717b10e3dc0b0b9bf0b5d"} 05:39:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:39:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:39:34 INFO - Contents: 05:39:34 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99b041c9f19dd4e1d407c31a95e029b0c957dcd80c001c9f5e4781150994e08a436c800e0fcf3c9071e3673221b5aecffa1d94561679c6aab6ecfff983cfb035", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94044cb7211a7aa77e33823caaef1ef5f918a3bbb16aecbd8c5451ed9f4da6da7800229839ae605f9829b27337fcf6283005622c95241d6d215a6915e9ba84fd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea2a5f515ba4bde32810385fc3d3e4de0b537b620f01f43505b2771dce834f234222a1198f60eed7a025dff3a541188ba8705b38788c2b657b761a0cdf5f588e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/952bb192e1ae1d387ec1d3703cca469a035558f5c16bcb4ec630ef2d13f2b27128f314735a15a6e8e7ebd6bfab54da07bbac1364757717b10e3dc0b0b9bf0b5d"} 05:39:34 INFO - Running post-run listener: copy_logs_to_upload_dir 05:39:34 INFO - Copying logs to upload dir... 05:39:34 INFO - mkdir: /builds/slave/test/build/upload/logs 05:39:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.938765 ========= master_lag: -0.09 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-21 05:39:33.709760) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-21 05:39:33.713670) ========= 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.Xx0DQ1RZ0O/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.3HOlwXSHfh/Listeners TMPDIR=/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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/Firefox/sha512/99b041c9f19dd4e1d407c31a95e029b0c957dcd80c001c9f5e4781150994e08a436c800e0fcf3c9071e3673221b5aecffa1d94561679c6aab6ecfff983cfb035", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94044cb7211a7aa77e33823caaef1ef5f918a3bbb16aecbd8c5451ed9f4da6da7800229839ae605f9829b27337fcf6283005622c95241d6d215a6915e9ba84fd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea2a5f515ba4bde32810385fc3d3e4de0b537b620f01f43505b2771dce834f234222a1198f60eed7a025dff3a541188ba8705b38788c2b657b761a0cdf5f588e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/952bb192e1ae1d387ec1d3703cca469a035558f5c16bcb4ec630ef2d13f2b27128f314735a15a6e8e7ebd6bfab54da07bbac1364757717b10e3dc0b0b9bf0b5d"} build_url:https://queue.taskcluster.net/v1/task/C9lYO0JNRLuJjQlgcoCrag/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011015 build_url: 'https://queue.taskcluster.net/v1/task/C9lYO0JNRLuJjQlgcoCrag/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99b041c9f19dd4e1d407c31a95e029b0c957dcd80c001c9f5e4781150994e08a436c800e0fcf3c9071e3673221b5aecffa1d94561679c6aab6ecfff983cfb035", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94044cb7211a7aa77e33823caaef1ef5f918a3bbb16aecbd8c5451ed9f4da6da7800229839ae605f9829b27337fcf6283005622c95241d6d215a6915e9ba84fd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea2a5f515ba4bde32810385fc3d3e4de0b537b620f01f43505b2771dce834f234222a1198f60eed7a025dff3a541188ba8705b38788c2b657b761a0cdf5f588e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/952bb192e1ae1d387ec1d3703cca469a035558f5c16bcb4ec630ef2d13f2b27128f314735a15a6e8e7ebd6bfab54da07bbac1364757717b10e3dc0b0b9bf0b5d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-21 05:39:33.748585) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 05:39:33.748955) ========= 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.Xx0DQ1RZ0O/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.3HOlwXSHfh/Listeners TMPDIR=/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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.005125 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 05:39:33.798622) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-21 05:39:33.798973) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-21 05:39:33.799347) ========= ========= Total master_lag: 0.05 =========