builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0141 starttime: 1479953783.42 results: success (0) buildid: 20161123150736 builduid: 63ab215d18974ae997fd70b326b24b52 revision: 116d83d90ce4ca5cb214e3e33c5da6735d59b848 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-23 18:16:23.425583) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-23 18:16:23.426596) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-23 18:16:23.427260) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-23 18:16:23.456089) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 18:16:23.456769) ========= 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.4XwuIxIBNU/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.YLz4vNpzIc/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 --2016-11-23 18:16:23-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.0M=0.001s 2016-11-23 18:16:23 (15.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.117428 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 18:16:23.601287) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 18:16:23.601636) ========= 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.4XwuIxIBNU/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.YLz4vNpzIc/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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.034366 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 18:16:23.661892) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-23 18:16:23.662664) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 116d83d90ce4ca5cb214e3e33c5da6735d59b848 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 116d83d90ce4ca5cb214e3e33c5da6735d59b848 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4XwuIxIBNU/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.YLz4vNpzIc/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 2016-11-23 18:16:23,755 truncating revision to first 12 chars 2016-11-23 18:16:23,756 Setting DEBUG logging. 2016-11-23 18:16:23,756 attempt 1/10 2016-11-23 18:16:23,756 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/116d83d90ce4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-23 18:16:24,712 unpacking tar archive at: mozilla-aurora-116d83d90ce4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.222372 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-23 18:16:24.914655) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-23 18:16:24.915405) ========= script_repo_revision: 116d83d90ce4ca5cb214e3e33c5da6735d59b848 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-23 18:16:24.916367) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-23 18:16:24.917056) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-23 18:16:24.939297) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-11-23 18:16:24.939960) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4XwuIxIBNU/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.YLz4vNpzIc/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 18:16:25 INFO - MultiFileLogger online at 20161123 18:16:25 in /builds/slave/test 18:16:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 18:16:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:16:25 INFO - {'append_to_log': False, 18:16:25 INFO - 'base_work_dir': '/builds/slave/test', 18:16:25 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 18:16:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:16:25 INFO - 'branch': 'Mozilla-Aurora', 18:16:25 INFO - 'buildbot_json_path': 'buildprops.json', 18:16:25 INFO - 'config_files': ('talos/mac_config.py',), 18:16:25 INFO - 'default_actions': ('clobber', 18:16:25 INFO - 'read-buildbot-config', 18:16:25 INFO - 'download-and-extract', 18:16:25 INFO - 'populate-webroot', 18:16:25 INFO - 'create-virtualenv', 18:16:25 INFO - 'install', 18:16:25 INFO - 'run-tests'), 18:16:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:16:25 INFO - 'download_minidump_stackwalk': True, 18:16:25 INFO - 'download_symbols': 'ondemand', 18:16:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:16:25 INFO - 'tooltool.py': '/tools/tooltool.py', 18:16:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:16:25 INFO - '/tools/misc-python/virtualenv.py')}, 18:16:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:16:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:16:25 INFO - 'installer_path': 'installer.exe', 18:16:25 INFO - 'log_level': 'info', 18:16:25 INFO - 'log_name': 'talos', 18:16:25 INFO - 'log_to_console': True, 18:16:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:16:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:16:25 INFO - 'opt_config_files': (), 18:16:25 INFO - 'pip_index': False, 18:16:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:16:25 INFO - 'cmd': ('bash', 18:16:25 INFO - '-c', 18:16:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:16:25 INFO - 'enabled': True, 18:16:25 INFO - 'halt_on_failure': False, 18:16:25 INFO - 'name': 'check_screen_resolution'},), 18:16:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:16:25 INFO - 'cmd': ('bash', 18:16:25 INFO - '-c', 18:16:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:16:25 INFO - 'enabled': True, 18:16:25 INFO - 'halt_on_failure': False, 18:16:25 INFO - 'name': 'check_screen_resolution'},), 18:16:25 INFO - 'run_cmd_checks_enabled': True, 18:16:25 INFO - 'sps_profile': False, 18:16:25 INFO - 'sps_profile_interval': 0, 18:16:25 INFO - 'suite': 'dromaeojs-e10s', 18:16:25 INFO - 'system_bits': '32', 18:16:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:16:25 INFO - 'title': 't-yosemite-r7-0141', 18:16:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:16:25 INFO - 'use_talos_json': True, 18:16:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:16:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:16:25 INFO - 'work_dir': 'build'} 18:16:25 INFO - [mozharness: 2016-11-24 02:16:25.162580Z] Running clobber step. 18:16:25 INFO - Running pre-action listener: _resource_record_pre_action 18:16:25 INFO - Running main action method: clobber 18:16:25 INFO - rmtree: /builds/slave/test/build 18:16:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:16:27 INFO - Running post-action listener: _resource_record_post_action 18:16:27 INFO - [mozharness: 2016-11-24 02:16:27.561690Z] Finished clobber step (success) 18:16:27 INFO - [mozharness: 2016-11-24 02:16:27.561855Z] Running read-buildbot-config step. 18:16:27 INFO - Running pre-action listener: _resource_record_pre_action 18:16:27 INFO - Running main action method: read_buildbot_config 18:16:27 INFO - Using buildbot properties: 18:16:27 INFO - { 18:16:27 INFO - "project": "", 18:16:27 INFO - "product": "firefox", 18:16:27 INFO - "script_repo_revision": "production", 18:16:27 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 18:16:27 INFO - "repository": "", 18:16:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 18:16:27 INFO - "buildid": "20161123150736", 18:16:27 INFO - "pgo_build": "False", 18:16:27 INFO - "basedir": "/builds/slave/test", 18:16:27 INFO - "buildnumber": 459, 18:16:27 INFO - "slavename": "t-yosemite-r7-0141", 18:16:27 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 18:16:27 INFO - "platform": "yosemite_r7", 18:16:27 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 18:16:27 INFO - "branch": "mozilla-aurora", 18:16:27 INFO - "repo_path": "releases/mozilla-aurora", 18:16:27 INFO - "revision": "116d83d90ce4ca5cb214e3e33c5da6735d59b848", 18:16:27 INFO - "stage_platform": "macosx64", 18:16:27 INFO - "builduid": "63ab215d18974ae997fd70b326b24b52", 18:16:27 INFO - "slavebuilddir": "test" 18:16:27 INFO - } 18:16:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 18:16:27 INFO - Running post-action listener: _resource_record_post_action 18:16:27 INFO - [mozharness: 2016-11-24 02:16:27.565151Z] Finished read-buildbot-config step (success) 18:16:27 INFO - [mozharness: 2016-11-24 02:16:27.565284Z] Running download-and-extract step. 18:16:27 INFO - Running pre-action listener: _resource_record_pre_action 18:16:27 INFO - Running main action method: download_and_extract 18:16:27 INFO - mkdir: /builds/slave/test/build/tests 18:16:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:27 INFO - trying https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 18:16:27 INFO - Downloading https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 18:16:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 18:16:28 INFO - Downloaded 1199 bytes. 18:16:28 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 18:16:28 INFO - Using the following test package requirements: 18:16:28 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 18:16:28 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:16:28 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 18:16:28 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:16:28 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 18:16:28 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:16:28 INFO - u'jsshell-mac.zip'], 18:16:28 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:16:28 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 18:16:28 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 18:16:28 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:16:28 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 18:16:28 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:16:28 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 18:16:28 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:16:28 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 18:16:28 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:16:28 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 18:16:28 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 18:16:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 18:16:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:16:28 INFO - Fetch https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 18:16:29 INFO - Http code: 200 18:16:29 INFO - via: 1.1 9baadab4e4d95afe7af7bcba342b29c6.cloudfront.net (CloudFront) 18:16:29 INFO - x-amz-cf-id: QkdHdICTIs8aBCLd8IA2z9Bf3InZtuYid5fAcCdmwnSbjf264UxflA== 18:16:29 INFO - x-amz-version-id: aaQI8Ou79JdQHhNzoiN3dw86FVF5GyxI 18:16:29 INFO - Expected file size: 16278314 18:16:29 INFO - Obtained file size: 16278314 18:16:36 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 18:16:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 18:16:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:16:36 INFO - Fetch https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 18:16:37 INFO - Http code: 200 18:16:37 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 18:16:37 INFO - x-amz-cf-id: GL1_8AIWA0OKoMij_zmOFIHj35LwONmrGsCz4fJDHR4XCjXcPL8Iyg== 18:16:37 INFO - x-amz-version-id: aaQI8Ou79JdQHhNzoiN3dw86FVF5GyxI 18:16:37 INFO - Expected file size: 16278314 18:16:37 INFO - Obtained file size: 16278314 18:16:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 18:16:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 18:16:43 INFO - Fetch https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 18:16:44 INFO - Http code: 200 18:16:44 INFO - via: 1.1 7f9e159e8ac7593f5d3d995abc6d2db3.cloudfront.net (CloudFront) 18:16:44 INFO - x-amz-cf-id: gU70vgPrl4-fcqr-Eq-kccWi0UM6fjZxOp3J_53v1-ylYlviJtvXbg== 18:16:44 INFO - x-amz-version-id: JaZpp6qEjLS9DGJRP507u1nx6Z9rTYtJ 18:16:44 INFO - Expected file size: 14409038 18:16:44 INFO - Obtained file size: 14409038 18:16:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:45 INFO - trying https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 18:16:45 INFO - Downloading https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 18:16:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 18:16:47 INFO - Downloaded 109683609 bytes. 18:16:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 18:16:47 INFO - mkdir: /builds/slave/test/properties 18:16:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:16:47 INFO - Writing to file /builds/slave/test/properties/build_url 18:16:47 INFO - Contents: 18:16:47 INFO - build_url:https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 18:16:48 INFO - Running post-action listener: _resource_record_post_action 18:16:48 INFO - Running post-action listener: set_extra_try_arguments 18:16:48 INFO - [mozharness: 2016-11-24 02:16:48.496539Z] Finished download-and-extract step (success) 18:16:48 INFO - [mozharness: 2016-11-24 02:16:48.496661Z] Running populate-webroot step. 18:16:48 INFO - Running pre-action listener: _resource_record_pre_action 18:16:48 INFO - Running main action method: populate_webroot 18:16:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:16:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:16:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:16:48 INFO - '--tppagecycles', 18:16:48 INFO - '7'], 18:16:48 INFO - 'tests': ['tsvgm']}}, 18:16:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:16:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:16:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:16:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:16:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:16:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:16:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:16:48 INFO - 'talos_options': ['--disable-e10s'], 18:16:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:16:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:16:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:16:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:16:48 INFO - 'talos_options': ['--disable-e10s'], 18:16:48 INFO - 'tests': ['damp', 'tps']}, 18:16:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:16:48 INFO - 'tests': ['damp', 'tps']}, 18:16:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:16:48 INFO - 'tests': ['dromaeo_dom']}, 18:16:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:16:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:16:48 INFO - 'tests': ['basic_compositor_video']}, 18:16:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:16:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:16:48 INFO - 'tests': ['a11yr', 18:16:48 INFO - 'ts_paint', 18:16:48 INFO - 'tpaint', 18:16:48 INFO - 'sessionrestore', 18:16:48 INFO - 'sessionrestore_no_auto_restore', 18:16:48 INFO - 'tabpaint']}, 18:16:48 INFO - 'other-e10s': {'tests': ['a11yr', 18:16:48 INFO - 'ts_paint', 18:16:48 INFO - 'tpaint', 18:16:48 INFO - 'sessionrestore', 18:16:48 INFO - 'sessionrestore_no_auto_restore', 18:16:48 INFO - 'tabpaint']}, 18:16:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:16:48 INFO - 'ts_paint', 18:16:48 INFO - 'tpaint', 18:16:48 INFO - 'sessionrestore', 18:16:48 INFO - 'sessionrestore_no_auto_restore', 18:16:48 INFO - 'tabpaint']}, 18:16:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:16:48 INFO - 'ts_paint', 18:16:48 INFO - 'tpaint', 18:16:48 INFO - 'sessionrestore', 18:16:48 INFO - 'sessionrestore_no_auto_restore', 18:16:48 INFO - 'tabpaint']}, 18:16:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:16:48 INFO - 'tests': ['a11yr', 18:16:48 INFO - 'ts_paint', 18:16:48 INFO - 'tpaint', 18:16:48 INFO - 'sessionrestore', 18:16:48 INFO - 'sessionrestore_no_auto_restore', 18:16:48 INFO - 'tabpaint']}, 18:16:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:16:48 INFO - 'tests': ['a11yr', 18:16:48 INFO - 'ts_paint', 18:16:48 INFO - 'tpaint', 18:16:48 INFO - 'sessionrestore', 18:16:48 INFO - 'sessionrestore_no_auto_restore', 18:16:48 INFO - 'tabpaint']}, 18:16:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:16:48 INFO - 'tests': ['tsvgx', 18:16:48 INFO - 'tsvgr_opacity', 18:16:48 INFO - 'tart', 18:16:48 INFO - 'tscrollx', 18:16:48 INFO - 'cart']}, 18:16:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:16:48 INFO - 'tsvgr_opacity', 18:16:48 INFO - 'tart', 18:16:48 INFO - 'tscrollx', 18:16:48 INFO - 'cart']}, 18:16:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:16:48 INFO - 'talos_options': ['--disable-e10s'], 18:16:48 INFO - 'tests': ['tp5o']}, 18:16:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:16:48 INFO - 'tests': ['tp5o']}, 18:16:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:16:48 INFO - 'talos_options': ['--disable-e10s', 18:16:48 INFO - '--xperf_path', 18:16:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:16:48 INFO - 'tests': ['tp5n']}, 18:16:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:16:48 INFO - 'talos_options': ['--xperf_path', 18:16:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:16:48 INFO - 'tests': ['tp5n']}}, 18:16:48 INFO - 'talos.zip': {'path': '', 18:16:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:16:48 INFO - Running post-action listener: _resource_record_post_action 18:16:48 INFO - [mozharness: 2016-11-24 02:16:48.515680Z] Finished populate-webroot step (success) 18:16:48 INFO - [mozharness: 2016-11-24 02:16:48.515786Z] Running create-virtualenv step. 18:16:48 INFO - Running pre-action listener: _resource_record_pre_action 18:16:48 INFO - Running main action method: create_virtualenv 18:16:48 INFO - Creating virtualenv /builds/slave/test/build/venv 18:16:48 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 18:16:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:16:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:16:48 INFO - Using real prefix '/tools/python27' 18:16:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:16:49 INFO - Installing distribute.............................................................................................................................................................................................done. 18:16:52 INFO - Installing pip.................done. 18:16:52 INFO - Return code: 0 18:16:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:16:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:16:52 INFO - Reading from file tmpfile_stdout 18:16:52 INFO - Output received: 18:16:52 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) 18:16:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:52 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 0x105a58490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa51bdc4240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10586cd30>, '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 0x1056d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105864b20>, '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.4XwuIxIBNU/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.YLz4vNpzIc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 18:16:52 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 18:16:52 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 18:16:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4XwuIxIBNU/Render', 18:16:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:16:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:16:52 INFO - 'HOME': '/Users/cltbld', 18:16:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:16:52 INFO - 'LOGNAME': 'cltbld', 18:16:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:52 INFO - 'MOZ_NO_REMOTE': '1', 18:16:52 INFO - 'NO_EM_RESTART': '1', 18:16:52 INFO - 'PAGER': '/bin/cat', 18:16:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:16:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:52 INFO - 'PWD': '/builds/slave/test', 18:16:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:16:52 INFO - 'SHELL': '/bin/bash', 18:16:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YLz4vNpzIc/Listeners', 18:16:52 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 18:16:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:16:52 INFO - 'USER': 'cltbld', 18:16:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:16:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:16:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:52 INFO - 'XPC_FLAGS': '0x0', 18:16:52 INFO - 'XPC_SERVICE_NAME': '0', 18:16:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:16:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:52 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 18:16:52 INFO - Cleaning up... 18:16:52 INFO - Return code: 0 18:16:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:52 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 0x105a58490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa51bdc4240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10586cd30>, '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 0x1056d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105864b20>, '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.4XwuIxIBNU/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.YLz4vNpzIc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 18:16:52 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 18:16:52 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 18:16:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4XwuIxIBNU/Render', 18:16:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:16:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:16:52 INFO - 'HOME': '/Users/cltbld', 18:16:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:16:52 INFO - 'LOGNAME': 'cltbld', 18:16:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:52 INFO - 'MOZ_NO_REMOTE': '1', 18:16:52 INFO - 'NO_EM_RESTART': '1', 18:16:52 INFO - 'PAGER': '/bin/cat', 18:16:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:16:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:52 INFO - 'PWD': '/builds/slave/test', 18:16:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:16:52 INFO - 'SHELL': '/bin/bash', 18:16:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YLz4vNpzIc/Listeners', 18:16:52 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 18:16:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:16:52 INFO - 'USER': 'cltbld', 18:16:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:16:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:16:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:52 INFO - 'XPC_FLAGS': '0x0', 18:16:52 INFO - 'XPC_SERVICE_NAME': '0', 18:16:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:16:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:52 INFO - Downloading/unpacking psutil>=3.1.1 18:16:52 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 18:16:52 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 18:16:52 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 18:16:53 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 18:16:53 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 18:16:53 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 18:16:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:16:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:16:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:16:56 INFO - Installing collected packages: psutil 18:16:56 INFO - Running setup.py install for psutil 18:16:56 INFO - building 'psutil._psutil_osx' extension 18:16:56 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 18:16:57 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 18:16:57 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 18:16:57 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 18:16:57 INFO - building 'psutil._psutil_posix' extension 18:16:57 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 18:16:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:16:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:16:57 INFO - ^ 18:16:57 INFO - 1 warning generated. 18:16:57 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 18:16:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:16:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:16:57 INFO - Successfully installed psutil 18:16:57 INFO - Cleaning up... 18:16:57 INFO - Return code: 0 18:16:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:16:57 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')]} 18:16:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:57 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')]} 18:16:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:57 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 0x105a58490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa51bdc4240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10586cd30>, '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 0x1056d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105864b20>, '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.4XwuIxIBNU/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.YLz4vNpzIc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 18:16:57 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 18:16:57 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 18:16:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4XwuIxIBNU/Render', 18:16:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:16:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:16:57 INFO - 'HOME': '/Users/cltbld', 18:16:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:16:57 INFO - 'LOGNAME': 'cltbld', 18:16:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:57 INFO - 'MOZ_NO_REMOTE': '1', 18:16:57 INFO - 'NO_EM_RESTART': '1', 18:16:57 INFO - 'PAGER': '/bin/cat', 18:16:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:16:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:57 INFO - 'PWD': '/builds/slave/test', 18:16:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:16:57 INFO - 'SHELL': '/bin/bash', 18:16:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YLz4vNpzIc/Listeners', 18:16:57 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 18:16:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:16:57 INFO - 'USER': 'cltbld', 18:16:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:16:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:16:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:57 INFO - 'XPC_FLAGS': '0x0', 18:16:57 INFO - 'XPC_SERVICE_NAME': '0', 18:16:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:16:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:16:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:16:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:16:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:17:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:17:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:17:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:17:00 INFO - Installing collected packages: mozsystemmonitor 18:17:00 INFO - Running setup.py install for mozsystemmonitor 18:17:00 INFO - Successfully installed mozsystemmonitor 18:17:00 INFO - Cleaning up... 18:17:00 INFO - Return code: 0 18:17:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:17:00 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')]} 18:17:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:00 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')]} 18:17:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:00 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 0x105a58490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa51bdc4240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10586cd30>, '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 0x1056d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105864b20>, '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.4XwuIxIBNU/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.YLz4vNpzIc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 18:17:00 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 18:17:00 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 18:17:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4XwuIxIBNU/Render', 18:17:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:17:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:17:00 INFO - 'HOME': '/Users/cltbld', 18:17:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:17:00 INFO - 'LOGNAME': 'cltbld', 18:17:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:00 INFO - 'MOZ_NO_REMOTE': '1', 18:17:00 INFO - 'NO_EM_RESTART': '1', 18:17:00 INFO - 'PAGER': '/bin/cat', 18:17:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:17:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:00 INFO - 'PWD': '/builds/slave/test', 18:17:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:17:00 INFO - 'SHELL': '/bin/bash', 18:17:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YLz4vNpzIc/Listeners', 18:17:00 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 18:17:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:17:00 INFO - 'USER': 'cltbld', 18:17:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:17:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:17:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:00 INFO - 'XPC_FLAGS': '0x0', 18:17:00 INFO - 'XPC_SERVICE_NAME': '0', 18:17:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:17:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:01 INFO - Downloading/unpacking jsonschema==2.5.1 18:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:17:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:17:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:17:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:17:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:17:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:17:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:17:03 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 18:17:03 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 18:17:03 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 18:17:03 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 18:17:03 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 18:17:03 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 18:17:04 INFO - Downloading functools32-3.2.3-2.tar.gz 18:17:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:17:04 INFO - warning: no files found matching '*.txt' 18:17:04 INFO - no previously-included directories found matching 'build' 18:17:04 INFO - no previously-included directories found matching 'dist' 18:17:04 INFO - no previously-included directories found matching '.git*' 18:17:04 INFO - Installing collected packages: jsonschema, functools32 18:17:04 INFO - Running setup.py install for functools32 18:17:04 INFO - warning: no files found matching '*.txt' 18:17:04 INFO - no previously-included directories found matching 'build' 18:17:04 INFO - no previously-included directories found matching 'dist' 18:17:04 INFO - no previously-included directories found matching '.git*' 18:17:04 INFO - Successfully installed jsonschema functools32 18:17:04 INFO - Cleaning up... 18:17:04 INFO - Return code: 0 18:17:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:17:04 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')]} 18:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:04 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')]} 18:17:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:04 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 0x105a58490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa51bdc4240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10586cd30>, '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 0x1056d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105864b20>, '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.4XwuIxIBNU/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.YLz4vNpzIc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 18:17:04 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 18:17:04 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 18:17:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4XwuIxIBNU/Render', 18:17:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:17:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:17:04 INFO - 'HOME': '/Users/cltbld', 18:17:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:17:04 INFO - 'LOGNAME': 'cltbld', 18:17:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:04 INFO - 'MOZ_NO_REMOTE': '1', 18:17:04 INFO - 'NO_EM_RESTART': '1', 18:17:04 INFO - 'PAGER': '/bin/cat', 18:17:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:17:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:04 INFO - 'PWD': '/builds/slave/test', 18:17:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:17:04 INFO - 'SHELL': '/bin/bash', 18:17:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YLz4vNpzIc/Listeners', 18:17:04 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 18:17:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:17:04 INFO - 'USER': 'cltbld', 18:17:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:17:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:17:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:04 INFO - 'XPC_FLAGS': '0x0', 18:17:04 INFO - 'XPC_SERVICE_NAME': '0', 18:17:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:17:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:17:04 INFO - Cleaning up... 18:17:04 INFO - Return code: 0 18:17:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:17:04 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')]} 18:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:04 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')]} 18:17:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:04 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 0x105a58490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa51bdc4240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10586cd30>, '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 0x1056d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105864b20>, '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.4XwuIxIBNU/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.YLz4vNpzIc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 18:17:04 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 18:17:04 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 18:17:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4XwuIxIBNU/Render', 18:17:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:17:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:17:04 INFO - 'HOME': '/Users/cltbld', 18:17:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:17:04 INFO - 'LOGNAME': 'cltbld', 18:17:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:04 INFO - 'MOZ_NO_REMOTE': '1', 18:17:04 INFO - 'NO_EM_RESTART': '1', 18:17:04 INFO - 'PAGER': '/bin/cat', 18:17:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:17:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:04 INFO - 'PWD': '/builds/slave/test', 18:17:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:17:04 INFO - 'SHELL': '/bin/bash', 18:17:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YLz4vNpzIc/Listeners', 18:17:04 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 18:17:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:17:04 INFO - 'USER': 'cltbld', 18:17:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:17:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:17:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:04 INFO - 'XPC_FLAGS': '0x0', 18:17:04 INFO - 'XPC_SERVICE_NAME': '0', 18:17:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:17:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:04 INFO - Downloading/unpacking blobuploader==1.2.4 18:17: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 18:17: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 18:17: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 18:17: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 18:17: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 18:17: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 18:17:07 INFO - Downloading blobuploader-1.2.4.tar.gz 18:17:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:17:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:17: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 18:17: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 18:17: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 18:17: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 18:17: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 18:17: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 18:17:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:17:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:17: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 18:17: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 18:17: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 18:17: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 18:17: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 18:17: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 18:17:08 INFO - Downloading docopt-0.6.1.tar.gz 18:17:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:17:08 INFO - Installing collected packages: blobuploader, requests, docopt 18:17:08 INFO - Running setup.py install for blobuploader 18:17:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:17:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:17:09 INFO - Running setup.py install for requests 18:17:09 INFO - Running setup.py install for docopt 18:17:09 INFO - Successfully installed blobuploader requests docopt 18:17:09 INFO - Cleaning up... 18:17:09 INFO - Return code: 0 18:17:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:17: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')]} 18:17:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17: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')]} 18:17:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:09 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 0x105a58490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa51bdc4240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10586cd30>, '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 0x1056d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105864b20>, '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.4XwuIxIBNU/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.YLz4vNpzIc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 18:17:09 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 18:17:09 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 18:17:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4XwuIxIBNU/Render', 18:17:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:17:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:17:09 INFO - 'HOME': '/Users/cltbld', 18:17:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:17:09 INFO - 'LOGNAME': 'cltbld', 18:17:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:09 INFO - 'MOZ_NO_REMOTE': '1', 18:17:09 INFO - 'NO_EM_RESTART': '1', 18:17:09 INFO - 'PAGER': '/bin/cat', 18:17:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:17:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:09 INFO - 'PWD': '/builds/slave/test', 18:17:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:17:09 INFO - 'SHELL': '/bin/bash', 18:17:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YLz4vNpzIc/Listeners', 18:17:09 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 18:17:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:17:09 INFO - 'USER': 'cltbld', 18:17:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:17:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:17:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:09 INFO - 'XPC_FLAGS': '0x0', 18:17:09 INFO - 'XPC_SERVICE_NAME': '0', 18:17:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:17:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:17:10 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-S7VQRX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:17:10 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-E9a6Tm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:17:10 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-3UoiRU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:17:10 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-Ppe4ms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:17:10 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-SrQNgB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:17:10 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-pKMTRg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:17:10 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-c8qbCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:17:10 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-1na5x_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:17:10 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-9q3Zvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:17:10 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-d_rQLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:17:11 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-bZDPQc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:17:11 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-HdI95t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:17:11 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-a7pyJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:17:11 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-an9vxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:17:11 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-6OwelF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:17:11 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-kpPaMw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:17:11 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-Lh80Rb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:17:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:17:11 INFO - Running setup.py install for manifestparser 18:17:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:17:11 INFO - Running setup.py install for mozcrash 18:17:12 INFO - Running setup.py install for mozdebug 18:17:12 INFO - Running setup.py install for mozdevice 18:17:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:17:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:17:12 INFO - Running setup.py install for mozfile 18:17:12 INFO - Running setup.py install for mozhttpd 18:17:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:17:12 INFO - Running setup.py install for mozinfo 18:17:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:17:12 INFO - Running setup.py install for mozInstall 18:17:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:17:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:17:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:17:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:17:12 INFO - Running setup.py install for mozleak 18:17:13 INFO - Running setup.py install for mozlog 18:17:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:17:13 INFO - Running setup.py install for moznetwork 18:17:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:17:13 INFO - Running setup.py install for mozprocess 18:17:13 INFO - Running setup.py install for mozprofile 18:17:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:17:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:17:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:17:13 INFO - Running setup.py install for mozrunner 18:17:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:17:13 INFO - Running setup.py install for mozscreenshot 18:17:14 INFO - Running setup.py install for moztest 18:17:14 INFO - Running setup.py install for mozversion 18:17:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:17:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:17:14 INFO - Cleaning up... 18:17:14 INFO - Return code: 0 18:17:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:17:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:14 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 0x105a58490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa51bdc4240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10586cd30>, '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 0x1056d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105864b20>, '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.4XwuIxIBNU/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.YLz4vNpzIc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 18:17:14 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 18:17:14 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 18:17:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4XwuIxIBNU/Render', 18:17:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:17:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:17:14 INFO - 'HOME': '/Users/cltbld', 18:17:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:17:14 INFO - 'LOGNAME': 'cltbld', 18:17:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:14 INFO - 'MOZ_NO_REMOTE': '1', 18:17:14 INFO - 'NO_EM_RESTART': '1', 18:17:14 INFO - 'PAGER': '/bin/cat', 18:17:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:17:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:14 INFO - 'PWD': '/builds/slave/test', 18:17:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:17:14 INFO - 'SHELL': '/bin/bash', 18:17:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YLz4vNpzIc/Listeners', 18:17:14 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 18:17:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:17:14 INFO - 'USER': 'cltbld', 18:17:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:17:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:17:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:14 INFO - 'XPC_FLAGS': '0x0', 18:17:14 INFO - 'XPC_SERVICE_NAME': '0', 18:17:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:17:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:17:14 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-rKuGD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:17:14 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)) 18:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:17:14 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-CWtMod-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:17:14 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)) 18:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:17:14 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-L_PVqc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:17:14 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)) 18:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:17:14 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-rHIMDM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 18:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:17:15 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-iFUJIf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:17:15 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)) 18:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:17:15 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-XiM0er-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:17:15 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)) 18:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:17:15 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-Acl6xk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:17:15 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)) 18:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:17:15 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-iyLMpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:17:15 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)) 18:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:17:15 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-5Vtk8c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:17:15 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)) 18:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:17:15 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-ZY24uM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:17:15 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)) 18:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:17:15 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-3gPBdl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:17:15 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)) 18:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:17:15 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-yYoFE0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 18:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:17:15 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-HxBeQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:17:16 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)) 18:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:17:16 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-F9ahdO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:17:16 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)) 18:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:17:16 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-_H76u7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:17:16 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)) 18:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:17:16 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-JLpX0r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 18:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:17:16 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-mxjPBe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:17:16 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)) 18:17:16 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)) 18:17:16 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)) 18:17:16 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)) 18:17:16 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:17:16 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:17:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:17:16 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 18:17:16 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 18:17:16 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 18:17:16 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 18:17:16 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 18:17:16 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 18:17:18 INFO - Downloading blessings-1.6.tar.gz 18:17:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:17:19 INFO - Installing collected packages: blessings 18:17:19 INFO - Running setup.py install for blessings 18:17:19 INFO - Successfully installed blessings 18:17:19 INFO - Cleaning up... 18:17:19 INFO - Return code: 0 18:17:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:17:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:17:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:17:19 INFO - Reading from file tmpfile_stdout 18:17:19 INFO - Current package versions: 18:17:19 INFO - blessings == 1.6 18:17:19 INFO - blobuploader == 1.2.4 18:17:19 INFO - docopt == 0.6.1 18:17:19 INFO - functools32 == 3.2.3-2 18:17:19 INFO - jsonschema == 2.5.1 18:17:19 INFO - manifestparser == 1.1 18:17:19 INFO - mozInstall == 1.12 18:17:19 INFO - mozcrash == 1.0 18:17:19 INFO - mozdebug == 0.1 18:17:19 INFO - mozdevice == 0.48 18:17:19 INFO - mozfile == 1.2 18:17:19 INFO - mozhttpd == 0.7 18:17:19 INFO - mozinfo == 0.9 18:17:19 INFO - mozleak == 0.1 18:17:19 INFO - mozlog == 3.4 18:17:19 INFO - moznetwork == 0.27 18:17:19 INFO - mozprocess == 0.23 18:17:19 INFO - mozprofile == 0.28 18:17:19 INFO - mozrunner == 6.13 18:17:19 INFO - mozscreenshot == 0.1 18:17:19 INFO - mozsystemmonitor == 0.3 18:17:19 INFO - moztest == 0.7 18:17:19 INFO - mozversion == 1.4 18:17:19 INFO - psutil == 3.1.1 18:17:19 INFO - requests == 1.2.3 18:17:19 INFO - wsgiref == 0.1.2 18:17:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:17:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:20 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')]} 18:17:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:20 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 0x105a58490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa51bdc4240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10586cd30>, '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 0x1056d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105864b20>, '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.4XwuIxIBNU/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.YLz4vNpzIc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 18:17:20 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 18:17:20 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 18:17:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4XwuIxIBNU/Render', 18:17:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:17:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:17:20 INFO - 'HOME': '/Users/cltbld', 18:17:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:17:20 INFO - 'LOGNAME': 'cltbld', 18:17:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:20 INFO - 'MOZ_NO_REMOTE': '1', 18:17:20 INFO - 'NO_EM_RESTART': '1', 18:17:20 INFO - 'PAGER': '/bin/cat', 18:17:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:17:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:20 INFO - 'PWD': '/builds/slave/test', 18:17:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:17:20 INFO - 'SHELL': '/bin/bash', 18:17:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YLz4vNpzIc/Listeners', 18:17:20 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 18:17:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:17:20 INFO - 'USER': 'cltbld', 18:17:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:17:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:17:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:20 INFO - 'XPC_FLAGS': '0x0', 18:17:20 INFO - 'XPC_SERVICE_NAME': '0', 18:17:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:17:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:20 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)) 18:17:20 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)) 18:17:20 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)) 18:17:20 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)) 18:17:20 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)) 18:17:20 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)) 18:17:20 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)) 18:17:20 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)) 18:17:20 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)) 18:17:20 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)) 18:17:20 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)) 18:17:20 INFO - Cleaning up... 18:17:20 INFO - Return code: 0 18:17:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:17:20 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')]} 18:17:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:20 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')]} 18:17:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105a58490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa51bdc4240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10586cd30>, '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 0x1056d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105864b20>, '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.4XwuIxIBNU/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.YLz4vNpzIc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 18:17:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:17:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:17:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4XwuIxIBNU/Render', 18:17:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:17:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:17:21 INFO - 'HOME': '/Users/cltbld', 18:17:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:17:21 INFO - 'LOGNAME': 'cltbld', 18:17:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:21 INFO - 'MOZ_NO_REMOTE': '1', 18:17:21 INFO - 'NO_EM_RESTART': '1', 18:17:21 INFO - 'PAGER': '/bin/cat', 18:17:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:17:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:21 INFO - 'PWD': '/builds/slave/test', 18:17:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:17:21 INFO - 'SHELL': '/bin/bash', 18:17:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YLz4vNpzIc/Listeners', 18:17:21 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 18:17:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:17:21 INFO - 'USER': 'cltbld', 18:17:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:17:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:17:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:21 INFO - 'XPC_FLAGS': '0x0', 18:17:21 INFO - 'XPC_SERVICE_NAME': '0', 18:17:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:17:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:17:21 INFO - Cleaning up... 18:17:21 INFO - Return code: 0 18:17:21 INFO - Running post-action listener: _resource_record_post_action 18:17:21 INFO - Running post-action listener: _start_resource_monitoring 18:17:21 INFO - Starting resource monitoring. 18:17:21 INFO - [mozharness: 2016-11-24 02:17:21.364099Z] Finished create-virtualenv step (success) 18:17:21 INFO - [mozharness: 2016-11-24 02:17:21.365089Z] Running install step. 18:17:21 INFO - Running pre-action listener: _resource_record_pre_action 18:17:21 INFO - Running main action method: install 18:17:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:17:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:17:21 INFO - Reading from file tmpfile_stdout 18:17:21 INFO - Detecting whether we're running mozinstall >=1.0... 18:17:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:17:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:17:21 INFO - Reading from file tmpfile_stdout 18:17:21 INFO - Output received: 18:17:21 INFO - Usage: mozinstall [options] installer 18:17:21 INFO - Options: 18:17:21 INFO - -h, --help show this help message and exit 18:17:21 INFO - -d DEST, --destination=DEST 18:17:21 INFO - Directory to install application into. [default: 18:17:21 INFO - "/builds/slave/test"] 18:17:21 INFO - --app=APP Application being installed. [default: firefox] 18:17:21 INFO - mkdir: /builds/slave/test/build/application 18:17:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:17:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 18:17:52 INFO - Reading from file tmpfile_stdout 18:17:52 INFO - Output received: 18:17:52 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 18:17:52 INFO - Running post-action listener: _resource_record_post_action 18:17:52 INFO - [mozharness: 2016-11-24 02:17:52.726795Z] Finished install step (success) 18:17:52 INFO - [mozharness: 2016-11-24 02:17:52.726959Z] Running run-tests step. 18:17:52 INFO - Running pre-action listener: _resource_record_pre_action 18:17:52 INFO - Running main action method: run_tests 18:17:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:17:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:17:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:17:52 INFO - 2016-11-23 18:17:52.741 screenresolution[1630:7352] starting screenresolution argv=screenresolution get 18:17:52 INFO - 2016-11-23 18:17:52.744 screenresolution[1630:7352] Display 0: 1600x1200x32@60 18:17:52 INFO - 2016-11-23 18:17:52.749 screenresolution[1631:7354] starting screenresolution argv=screenresolution list 18:17:52 INFO - Available Modes on Display 0 18:17:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:17:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:17:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:17:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:17:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:17:52 INFO - Intel Iris: 18:17:52 INFO - Chipset Model: Intel Iris 18:17:52 INFO - Type: GPU 18:17:52 INFO - Bus: Built-In 18:17:52 INFO - VRAM (Dynamic, Max): 1536 MB 18:17:52 INFO - Vendor: Intel (0x8086) 18:17:52 INFO - Device ID: 0x0a2e 18:17:52 INFO - Revision ID: 0x0009 18:17:52 INFO - Displays: 18:17:52 INFO - Display: 18:17:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:17:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:17:52 INFO - Display Serial Number: 1600x1200 60 18:17:52 INFO - Main Display: Yes 18:17:52 INFO - Mirror: Off 18:17:52 INFO - Online: Yes 18:17:52 INFO - Rotation: Supported 18:17:52 INFO - Return code: 0 18:17:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:17:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:17:52 INFO - Python 2.7.3 18:17:52 INFO - Return code: 0 18:17:52 INFO - grabbing minidump binary from tooltool 18:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:52 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10586cd30>, '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 0x1056d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105864b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:17:52 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:17:52 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 18:17:52 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:17:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:17:53 INFO - Return code: 0 18:17:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:17:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:17:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:17:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:17:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:17:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0141', '--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 18:17:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0141 --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 18:17:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4XwuIxIBNU/Render', 18:17:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:17:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:17:53 INFO - 'HOME': '/Users/cltbld', 18:17:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:17:53 INFO - 'LOGNAME': 'cltbld', 18:17:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:17:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:17:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:53 INFO - 'MOZ_NO_REMOTE': '1', 18:17:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:17:53 INFO - 'NO_EM_RESTART': '1', 18:17:53 INFO - 'PAGER': '/bin/cat', 18:17:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:17:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:53 INFO - 'PWD': '/builds/slave/test', 18:17:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:17:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:17:53 INFO - 'SHELL': '/bin/bash', 18:17:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YLz4vNpzIc/Listeners', 18:17:53 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 18:17:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:17:53 INFO - 'USER': 'cltbld', 18:17:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:17:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:17:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:53 INFO - 'XPC_FLAGS': '0x0', 18:17:53 INFO - 'XPC_SERVICE_NAME': '0', 18:17:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:17:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0141', '--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 18:17:53 INFO - mozversion application_buildid: 20161123150736 18:17:53 INFO - mozversion application_changeset: 116d83d90ce4ca5cb214e3e33c5da6735d59b848 18:17:53 INFO - mozversion application_display_name: FirefoxDeveloperEdition 18:17:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:17:53 INFO - mozversion application_name: Firefox 18:17:53 INFO - mozversion application_remotingname: firefox-dev 18:17:53 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:17:53 INFO - mozversion application_vendor: Mozilla 18:17:53 INFO - mozversion application_version: 52.0a2 18:17:53 INFO - mozversion platform_buildid: 20161123150736 18:17:53 INFO - mozversion platform_changeset: 116d83d90ce4ca5cb214e3e33c5da6735d59b848 18:17:53 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:17:53 INFO - mozversion platform_version: 52.0a2 18:17:53 INFO - using testdate: 1479953873 18:17:53 INFO - actual date: 1479953873 18:17:53 INFO - starting webserver on 'localhost:49241' 18:17:53 INFO - SUITE-START | Running 2 tests 18:17:53 INFO - TEST-START | dromaeo_css 18:17:53 INFO - operating with platform_type : mac_ 18:17:53 INFO - Initialising browser for dromaeo_css test... 18:17:53 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmpaBLKE7/profile http://localhost:49241/getInfo.html) 18:17:55 ERROR - PROCESS | 1641 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5373: TypeError: parent.firstChild is null 18:17:56 INFO - PROCESS | 1641 | __metrics Screen width/height:1600/1200 18:17:56 INFO - PROCESS | 1641 | colorDepth:24 18:17:56 INFO - PROCESS | 1641 | Browser inner width/height: 1024/689 18:17:56 INFO - PROCESS | 1641 | __metrics 18:17:56 ERROR - PROCESS | 1641 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 18:17:57 INFO - TEST-INFO | 1641: exit 0 18:17:57 INFO - Browser initialized. 18:17:57 INFO - Running cycle 1/1 for dromaeo_css test... 18:17:57 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmpaBLKE7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:18:09 INFO - PROCESS | 1644 | RSS: Main: 230006784 18:18:09 INFO - PROCESS | 1644 | 18:18:59 INFO - PROCESS | 1644 | 1479953939181 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 18:21:12 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 18:21:14 INFO - PROCESS | 1644 | RSS: Main: 233836544 18:21:14 INFO - PROCESS | 1644 | 18:24:16 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 18:24:17 INFO - PROCESS | 1644 | RSS: Main: 243314688 18:24:17 INFO - PROCESS | 1644 | 18:27:19 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 18:27:20 INFO - PROCESS | 1644 | RSS: Main: 246931456 18:27:20 INFO - PROCESS | 1644 | 18:30:21 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 18:30:22 INFO - PROCESS | 1644 | RSS: Main: 246542336 18:30:22 INFO - PROCESS | 1644 | 18:33:25 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 18:33:25 INFO - PROCESS | 1644 | RSS: Main: 249999360 18:33:25 INFO - PROCESS | 1644 | 18:36:28 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 18:36:28 INFO - PROCESS | 1644 | RSS: Main: 251367424 18:36:28 INFO - PROCESS | 1644 | 18:36:28 INFO - PROCESS | 1644 | __start_tp_report 18:36:28 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 18:36:28 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 18:36:28 INFO - PROCESS | 1644 | |i|pagename|runs| 18:36:28 INFO - PROCESS | 1644 | |0;dojo.html;3132.545780861775;3449.1894404814866;3455.2398472336076;3460;3483.721302295816;4783.425988881339;5847.8538036549;5982.34194238634;6031.035034394497;6057.878842423154;5968.462838344548;5979.252593425822;6036.12476190952;6046.274447066358;6084.908726369097;5342.679439233649;5426.782928682867;5451.318585176853;5456.536194423459;5470.835874923759;4005.4592629995013;5485.725713714318;5508.394076651565;5510.476504732064;5528.115501519757;5305.257263983045;5317.075608415375;5506.119020956648;5511.366192887809;5532.972335138339;5330.973345133269;5351.919721204185;5402.189671549259;5424.430434804368;5491.725413729338;5285.577153827723;5369.7583608737705;5387.838364849061;5396.892062158774;5419.349678038621;5819.418058194189;5951.166836642873;5965.955957707427;6000.309964354112;6028.608140470856;6283.088952101965;6291.748330066792;6295.811125666238;6326.082718005863;6386.201724784402;6381.010943303749;6395.232572091332;6402.359764023607;6406.167198264196;6412.551121421502;6125.173101631267;6135.969320153371;6146.78486252979;6165.722542485554;6171;6265.404786545362;6310;6334.3032266450655;6344.873102537924;6378;6322.9051564226575;6367;6382.648954307558;6403.103565500834;6408.775692850728;6370.936290637127;6400.7119679614525;6404.391582799627;6406.423421892052;6435.485161187094;6159.19930409044;6234.875302494015;6242.875142497124;6252.74989000444;6304.873902521924;6266.997280435108;6294.622322660655;6327.430531252117;6346.30190679025;6364.268109167412;5053.418856831438;5093.8726531836255;5116.2069879168785;5120.155174396183;5123.590112790967;4479.7984090716;4500.572445617661;4547.3179023146795;4616.722996620214;4623.329617205486;5839.970800145972;5979.222701048836;6001;6044.788432404933;6111.8472038198515;6368.617882927039;6370.458511026614;6374;6387.489000879918;6405.3594640535575;5892.793752218652;5939.2575928009;5997.760089596367;6015.639061656315;6022.18700475439;5022.422421421583;5587.078132108238;5627.296587926509;5655.349634792052;5677.630953987978;6234.657093859882;6298.968505157628;6393.041043843367;6398.648074355955;6408.679566021773;6246.843828904313;6290.811275661737;6322.841928951813;6386.87226255463;6404.615723056631;6400.423961843457;6407.839804004937;6408.839779005562;6414.583052101599;6575.80272591823;6193.102000210034;6328.525360597881;6350.587211831401;6356.809295721136;6366.4588509977175;6142.078688196806;6282.371762823681;6312.179416675803;6332.208473940757;6383.9361606383345;6214.782482613087;6310.5267104968225;6332.873342533031;6338.11266422692;6357.650329231937;6315.715792789428;6325.905111423424;6332.0818481320875;6350.555461117862;6367.299597044229;5094.847154585368;5907.49786268172;6045.183900173596;6100.54245931548;6120.751898419022;6235.937640623536;6275.184226050585;6301.306856245901;6305.243370795534;6327.810165695037;6174.8147555573405;6200.968995155175;6201.162843016135;6208.441240288395;6223.37766223374 18:36:28 INFO - PROCESS | 1644 | |1;ext.html;424.8406847432213;472.9594891239274;493.55086870947446;535.3896557923965;576.8722148200269;36281.2743745125;38798;38836.22327553454;38854.22291554167;38857.611423885784;39444.40833387501;40227.59772402275;40320.193596128134;40424.787256382355;40475.39286910699;8196.918030819705;8353.289970352527;8934.508602026903;9199.632014719436;9250.35247532673;9214.170724634781;9325.974142844281;9333.160015598594;9352.111549402796;9352.579133938954;9279.025702301262;9335.906640933572;9339.953300233523;9353.76615584609;9363.48500832454;9236.122568355995;9339.252859771203;9345.439273643604;9346.11211934872;9356.719298421058;9073.77315567116;9146.176844083999;9251.16739493442;9255.259579233645;9282.675106371313;38541.80729096365;38606;38722.41916371257;38966.41550376718;39111.80444097761;3121.562981182636;3215.8552865121123;3249.2851572653976;3277.1807048237943;3294.5222942673176;27999.720002799913;28017.57973630397;28114.578281325797;28259.71740282612;28281.292967675767;3147.1974646465233;3168.4930411134337;3276.6231883333244;3333.949990750141;3348.196432856121;509743;514591;515123;516890;517043.41478292743;60290;60580.091298630556;60605.090923636184;60619.696901516094;60823;62825;62876.37123628797;63185;63235.6838215806;63378;16032.519024429286;16133.354665813471;16227.18864056816;16249.837501625068;16256.59358516023;12454.377281135907;12478.43847026886;12617.558385456648;12620.242785432903;12646.051546134076;15550;17853;18368.897866128074;18378.08109594515;18385.448436546925;7793.688252469951;7945.880811787828;8017.478863873829;8021.27808497238;8044.678212871419;849.6091797773094;940.6472572785206;965.7392504024015;1010.9797804043878;1029.063552167524;24312;27376.315592109957;28103.43793124126;28820.135395938156;29313.267168320963;832.8584140696096;908.5184852028435;1011.7167193185772;1033.214756784834;1111;9981.750456238507;13559.796603050961;13975.672311130425;14765.852341476662;15104.773428398583;691243;703516.4824175846;703766;705072;705618;53577.732111339974;53826.46173538293;53842.73078634582;53865.461345386044;53903.46096539063;1061.506399524225;1214.6538236602526;1252.2486508095253;1341.704824938512;1358.1850889466043;28731.42537149287;29804;30227;30660;30933;47537.76231118822;52641.7367913158;54338.45661543334;54568;58219.4178058231;605272;628952;649881;656400;656494;40426.97865106792;40913.977150571474;41349.79325103355;41420.378694320214;41544.792276038424;44965.77517112524;45028;45109.548904511845;45274;45722.85692857705;35618.28763424769;36802.8159859199;37991.05022374352;38841;39942.400863986484;36935.82730368968;37521;37819.62180378161;38420.807895960344;38820.223595527365;33626.66373336333;35986.280274393845;36961.26077478489;37176.62823371732;38451.807740961114;31349.529757053206;33668;33709.651613935166;33767.83116084404;33879.153021174665;24729;24972.50054998926;25175.49649007046;25273.494530109663;25609.871950640874 18:36:28 INFO - PROCESS | 1644 | |2;jquery.html;370.98330575124135;375.56246972277296;375.95864454909974;383.93473109571363;401.3898873711958;18563.257469701213;19195.520111997208;19370.806291937075;19674.016299185037;19900.905450200233;19033.619327613473;19073.046347682593;19544.31594894179;19544.804551954476;19744.407667769952;5193.896122077556;5412.431694672072;5596.2165296858475;5605.5515558755205;5992.970035149818;6037.75848966043;6142.600730952496;6145.600535965148;6147.077938309245;6156.076588511737;5162.767675454595;5844.707764611752;6014.188084608566;6094.512439004891;6102.938970610281;6009.278886533601;6030.396960303979;6037.698115094227;6060.757569697184;6064.696765161724;5185.688858668492;5225.973870130663;5308.70802105884;5344.171653393731;5344.652597581145;12668.4299206535;12942.741145177139;12949.54676586315;13087.345632718327;13133.802992955114;2068.7414073240843;2163.1888041984257;2271.5684020036306;2287.874166920819;2309.2725791375665;8407.621657025387;9768.755781105458;9912.058354456316;10265.948670256601;10292.742681433025;1529.5334922848526;1810.36637176987;1886.6321067391864;1922.9711554326695;1976.8319692826128;106185;117598;128292;136768.999999999;137616.31191843978;11266.098712103012;11437.656870293904;11730.648080557597;11759.47082381295;11947.581834635746;14657.34041968114;14665.120092794466;14687.192204428646;14689.853101469062;14739.631509212355;8341.45780524264;8544.572771361407;8586.055533891267;8661.956690216508;8697.695580654647;6966.303369662993;8182.1817818217705;8484.490930544187;8512.74461766148;8622.525761083201;4441.422615060086;4600.5169457207185;4604.447466303998;4622.422197225347;4622.930656040162;1929.411529483521;1967.331107423483;1974.5712773288935;1979.139074502596;1992.2329902987165;811.8213992921548;825.2737590919952;863.218786997768;870.7344260000634;891.8350105230553;20181.39455816328;20579.382618521468;20644.69032964507;20737.94828692361;20835.270765523135;885.1812073831809;886;911.129870973219;943.7451887990204;943.8395472769646;10274.075333220046;10872.510737016853;10878.891211087788;10905.618303359344;11037.724056898642;362832.1858390744;628524;636042.8197858981;638606;638792;82354.76467852987;83818.5809070963;129327;132669;140782;945.2863088368375;984.8227319082633;1006.2201793610011;1009.6769033909079;1011.4891979550428;16372.345106195618;17858.231974044043;18490.798098123578;19025.80974190296;19218.942958137435;43757.34363984606;55139.96730179879;56269.43730562642;57588;58193;84165.57917210375;86345.56827215824;86935;88146.55926720325;88301;7896.763097107096;7925.365970722213;8007.399445041528;8201.671933122609;8285.3785966054;8432;11316.49075791575;12171.2697238166;12312.938435307766;12475.126741128033;8466.703665371682;10228.795424091626;10562;10649.520771565452;11745.236559623598;10409.1672666185;10441.895581044397;10530;10675.946620266848;10884.45577721095;10746.313421644589;10848;10904;10994;11013;7022.613756243456;7431.071116110486;7436.92563074384;7943.165967573322;7999.360051195774;10274.434906080238;10733.892661073289;10749.838752418564;10805.675829725122;11143.161378372477 18:36:28 INFO - PROCESS | 1644 | |3;mootools.html;1462.839087700353;1471.337897945924;1620.7811945387377;1659.1289572974179;1735.5227312489055;7615.390768738502;7914.722984695537;8112.107668156498;8556.486610803357;8597.226249637524;6850.24647288799;8299.211574900375;8423.199796019384;8475.194856488639;8506.787330316745;2315.2822624986266;2357.7760112789256;2358.9528209435803;2359.3629719975597;2365.550545396378;2359.846609970355;2381.9761802382013;2406.68713067301;2410.7227668818045;2413.4811015631635;2344.460774021976;2352.223766157164;2358.1864256831363;2371.4782747795543;2377.6909001829736;2259.9209027684033;2283.132409684326;2286.2683941138844;2292.6904867842795;2355.7997570206553;1854.619802940394;1981.890141520753;2373.4066483379156;2381.2261015169966;2386.642003699459;8451;8481.95759021207;8502.06477287498;8567.314614830799;8599;5173.793048278064;5326.094563924142;5342.198670199462;5348;5372.838814835562;3146.961502704125;3345.800815781742;3355.76509644325;3689.5203623529037;3693.0398096495105;861.7113267055544;873.6505397840851;1084.4252546150553;1102.0687519046394;1117.1956191542076;11176.161787865862;11186.888131118665;11280.830787538194;11286.774264514746;11386.772264554662;4374.58441448062;4461.57615026572;4545.272756358967;4691.296305554194;4828;9624;9650.986646402167;9673.129418352382;9677.90322096784;9695.563699633534;5225.268462415265;5255.921161182585;5268.920966185434;5273.209018647157;5277.788888444419;4312.7627980461375;4338.956610433918;4821.614270858323;4852.053849499384;4854.805807767721;3805.7906815125434;3875.8255878485534;3914.8434062637743;3937.2322397132857;3965.72239943207;1083.4041277297456;1271.5613113475836;1279.2132838304376;1282.3588205897051;1283.1980012492193;819.8606236939735;909.1136142261374;915.158054589779;921.4793641592479;921.6958403726754;5837.88324233513;5846.532277417797;5868.530517558585;5931.288245410578;6088.817335479854;448.7195502810743;454.51367037270467;456.86979210924727;457.2501098198956;463.99081996657134;3580.98209508956;3872.186840763471;3934.6065393461;4043.0094626816617;4060.7360521566;10155.593776249016;10441.530131144116;10516.526756295985;10535;10538.473076346152;10094.242931780145;10153.340032897986;10302.33034852732;10455.320404173704;10473.738156546147;7771.533707977426;7890.132085470594;7915.44591878563;8033.19668033192;8035.598220088972;5627.352854421631;5710.685912274865;5758.193852860519;5774.884502310014;5785.3636100028125;9291.442513449214;9398.530073496435;9494.05059494045;9528.809423811346;9614.990426005448;9653.758656033375;9764.414135151914;9806.313558050868;9865.556049977911;9871.358361706752;6728;6951.756688515878;7214.2785721427435;7326.780196594111;7404.9633051371775;7870.331021863206;8170;8190.180981901762;8190.631421585924;8198.754037378889;5645.661260324394;7133.464990125865;7271.709131634887;7292.598907060163;7305.671244793891;7058.505904586629;7085.326893945067;7098.114843551515;7140;7193.136823581203;6840.9315906842285;6857.348551887562;6951.895721564281;6956.0609317741455;6973.686184121828;4919.557239848287;4948.35671362735;4965.503449655005;4979.103761322996;4990.426100998432;4813.309499795218;4913.70517768935;4924.778384972757;5010.3737032870895;5085.796568137233 18:36:28 INFO - PROCESS | 1644 | |4;prototype.html;291.99562006569903;300.78493876878025;301.458881308052;307.81992534367396;310.8647738233868;4687.531246875311;5268.789248430058;5566.805161819332;5568.192612071252;5570.2480165177685;4063.3701776224657;4556;4849.781759820808;4917.3607431033915;5466.890662186754;3280.6555311692287;3533.3463309287736;4137.793110344478;4158.189153115144;4160.604742549468;3300.7359411247044;3488.406970814955;3864.787436690981;4167.9583204167875;4169.60388763067;3336.816475093869;3590.9765716770726;3794.9810250948844;4164.791760411982;4175.728577642459;4123.463949686551;4184.8744537663915;4186.0581369192;4188.078622703;4188.141431006637;3032.1358412852233;3645.5078564393734;3647.434647629595;3686.00477870976;3690.612485689018;4524.547545245482;4604.447466304032;5108.770105345268;5276.393214780311;5315.495027972336;3776.7733935963656;4213.820130363503;4662.230731929228;4798.184308667535;4802.255650374198;5652.293463317085;5784.652920824764;5793.7392817323325;5818.283606336141;5824.184614154022;3075.369549242422;3814.8855534334016;3894.92889455402;3964.7621142731527;4026.71812973092;5696.088625819849;5982.8504287392725;6058.39416058395;6081.483073938721;6109.022556391001;3337.18239031434;3833.099221682902;3947.684185265172;3959.7822119783114;3960.128771670228;3620.873269435557;3665.303592317451;3758.624137586274;3815.828287727059;4030.6573941215324;4047.6761859051207;4063.8577649782706;4405.968538787038;4566.109608626286;4701.553352431513;5848.5321174305955;5855;5866.6480011199465;5890.558208134407;5897.262842144732;1065.5950738719391;1151.205668089016;1260.426505939795;1279.6992706713916;1304.8564657887625;869.2046777198792;889.3729920406098;891.4473026723473;891.987594080723;895.7312806158127;4412.933805992912;5021.548060674557;5158.819441319611;5161.845144645667;5213.881033983187;5115.0844018908865;5291.439025487491;5555.278113482731;5572.192032155314;5624.409437009137;4571.405717256735;4615.284760673212;5036.64743467961;5158.632962265003;5190.091733946544;2924.8976285829895;2939.3974235281717;2993.476141675198;2999.460097182528;3021.416688731147;4783.4499032611;5144.8713782155;5547.667139971614;5807.854803629859;5820.563457740602;4801.831935882227;5518.172274158908;5745.0198009418755;5782.855428614318;5784.640609456775;4694.0611877624615;4903.632227583017;5744.913826292609;5747.597668163188;5764.982256210293;5209.661372010807;5473.67157970523;5612.102063669793;5632.113242063954;5635.619273278073;4993.675411098267;5012.649114561946;5498.890022199614;5522.2544956431975;5541.3904470507405;5831.854203644943;5837.409992854279;6159.29168145669;6186.938130618816;6215.689215539295;3534.3461459630053;3726.4721464199547;3736.495573097706;3753.4745135681574;4039.6768258539832;5038.874028149326;5606.159076138449;5744.396838331915;6002.639841609517;6008.639481631116;5241.659292145999;5626.943730562805;5761.448455719934;5826.970865145647;5872.677002764889;4955.132851751001;5802.854928626818;5840.065589505829;5841.620294680833;5900.675462849585;5590.888182236251;5666.433356664301;5679.088634846035;5741.593309639165;5763.9135412968;4855.465898751205;4998.300237966615;5505.394406615189;5877.794277200277;5905.35041145465;5454.863628409322;5630.83212963004;5712.285964254468;5773.364929857628;5774.335951365647 18:36:28 INFO - PROCESS | 1644 | |5;yui.html;645.6475815489591;647.7409036385442;731.1664702239448;753.4650398217266;807.9394045446589;11378.37418941958;14749.188794616291;14894.404223831047;14900.552983410487;14909.403623855047;12089.093318001142;13378.66553336167;13469.595912122628;14402.567922962302;14911.552653420387;661.1009027722317;674.2818897873761;812.6140083460339;853.9402241843073;861.3927181337174;845.8731190321439;848.9830203395928;852.3820230333021;853.7182729699184;854.5940678177878;761.4517547365888;808.1393316118315;877.7761670773943;881.9338549608805;885.2165833237599;863.7365603490932;865.7316231968078;870.3298460185654;873.6767396063466;874.6282829797311;628.5411649495894;640.4204195203301;647.4626060369882;648.099142192353;649.1431310669917;13024.674383140497;13101.672458188526;14011.369488373048;14378.856211437856;14639;10903.83644245337;10923.726906827313;10925.836112458319;11115.666530004031;11180.944095279552;11041.171912106623;11350.829737553944;11390.316581005083;11587.188896777228;11601.709957251052;3551.5383000210068;3576.266865292635;3584.5698516177963;3614.060344310472;3672.761270517408;87090;91409.54295228548;92222;92306;93168.06831931662;10231.232657550714;10268.948655256676;10359.326643768281;10535.736606584744;10623;20270.69593956092;21405.78594214038;21926.671099933516;22060.669089963663;22097.116115355206;7768.145503994556;7791.649375778104;7878.72424465141;7916.852056451898;8070.878936815952;4019.9459177850867;4086.959130408658;4145.626893579592;4176.269152898291;4243.872683819491;3462.082548124749;3579.266250418658;3581.4448760442174;3589.964100359015;3591.3894637911617;641.5477088652489;662.453475882399;668.2281964331205;669.9631520266432;682.3006418421159;595.3153873046032;598.5480961873844;603.9124326972565;639.3031595560861;643.2088531106765;8960.283177345797;9190.816183676288;9323.580438880266;9358.859617105749;9363.250939924927;567.4325674325675;596.7881402102191;615.1295916278506;633.7655067625009;661.5534514202894;4410;4477.350784136282;4524.457065152137;4549.226631472658;4626.21354369758;115577;116410;116970;117138;120926.39536802436;66069;81337.59331203306;86743.56628216819;88380.5580972091;92134;65623.34376656268;85342;85840.99999999875;87533;88657.55671221603;9501;9860.359076659994;9895.752606184902;9902.603895844373;9909.851352229578;45356;55608.721956389956;56042.15936761034;56763;56972.43027569837;70393.24016899618;74332.88500672387;75354.62322688352;78639.42721145431;80362;11804.468798904241;12412.317322547347;12428.502859885502;12556.246625202517;12709.497904396536;14012.719745605234;14301.998860079695;14389.841523771518;14392.06451580644;14400.639984000065;12804.871951280367;12989.545365912525;13063.020273479337;13111.672208194872;13170;12197.146199765932;12559.748805024032;12857.357132143168;12890.871091288967;13175.07774455779;9881.654142104991;10236.641717539851;10528.210384221062;11223.71940701489;12374.195677280948;11002.284851484628;11054.723631909266;11417;11465.770684586429;11477.196596238184;9399.577019034297;11274.492647831032;11306.679449346018;11611.361375124448;11645.010174135294 18:36:28 INFO - PROCESS | 1644 | __end_tp_report 18:36:28 INFO - PROCESS | 1644 | __start_cc_report 18:36:28 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,366 18:36:28 INFO - PROCESS | 1644 | __end_cc_report 18:36:28 INFO - PROCESS | 1644 | __startTimestamp1479954988719__endTimestamp 18:36:28 INFO - PROCESS | 1644 | 18:36:28 INFO - PROCESS | 1644 | ------- Summary: start ------- 18:36:28 INFO - PROCESS | 1644 | Number of tests: 6 18:36:28 INFO - PROCESS | 1644 | 18:36:28 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5900.87 Median:6174.81 stddev:666.13 (10.8%) stddev-sans-first:631.78 18:36:28 INFO - PROCESS | 1644 | Values: 3132.5 3449.2 3455.2 3460.0 3483.7 4783.4 5847.9 5982.3 6031.0 6057.9 5968.5 5979.3 6036.1 6046.3 6084.9 5342.7 5426.8 5451.3 5456.5 5470.8 4005.5 5485.7 5508.4 5510.5 5528.1 5305.3 5317.1 5506.1 5511.4 5533.0 5331.0 5351.9 5402.2 5424.4 5491.7 5285.6 5369.8 5387.8 5396.9 5419.3 5819.4 5951.2 5966.0 6000.3 6028.6 6283.1 6291.7 6295.8 6326.1 6386.2 6381.0 6395.2 6402.4 6406.2 6412.6 6125.2 6136.0 6146.8 6165.7 6171.0 6265.4 6310.0 6334.3 6344.9 6378.0 6322.9 6367.0 6382.6 6403.1 6408.8 6370.9 6400.7 6404.4 6406.4 6435.5 6159.2 6234.9 6242.9 6252.7 6304.9 6267.0 6294.6 6327.4 6346.3 6364.3 5053.4 5093.9 5116.2 5120.2 5123.6 4479.8 4500.6 4547.3 4616.7 4623.3 5840.0 5979.2 6001.0 6044.8 6111.8 6368.6 6370.5 6374.0 6387.5 6405.4 5892.8 5939.3 5997.8 6015.6 6022.2 5022.4 5587.1 5627.3 5655.3 5677.6 6234.7 6299.0 6393.0 6398.6 6408.7 6246.8 6290.8 6322.8 6386.9 6404.6 6400.4 6407.8 6408.8 6414.6 6575.8 6193.1 6328.5 6350.6 6356.8 6366.5 6142.1 6282.4 6312.2 6332.2 6383.9 6214.8 6310.5 6332.9 6338.1 6357.7 6315.7 6325.9 6332.1 6350.6 6367.3 5094.8 5907.5 6045.2 6100.5 6120.8 6235.9 6275.2 6301.3 6305.2 6327.8 6174.8 6201.0 6201.2 6208.4 6223.4 18:36:28 INFO - PROCESS | 1644 | 18:36:28 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:74192.83 Median:40273.90 stddev:167144.09 (415.0%) stddev-sans-first:167521.20 18:36:28 INFO - PROCESS | 1644 | Values: 424.8 473.0 493.6 535.4 576.9 36281.3 38798.0 38836.2 38854.2 38857.6 39444.4 40227.6 40320.2 40424.8 40475.4 8196.9 8353.3 8934.5 9199.6 9250.4 9214.2 9326.0 9333.2 9352.1 9352.6 9279.0 9335.9 9340.0 9353.8 9363.5 9236.1 9339.3 9345.4 9346.1 9356.7 9073.8 9146.2 9251.2 9255.3 9282.7 38541.8 38606.0 38722.4 38966.4 39111.8 3121.6 3215.9 3249.3 3277.2 3294.5 27999.7 28017.6 28114.6 28259.7 28281.3 3147.2 3168.5 3276.6 3333.9 3348.2 509743.0 514591.0 515123.0 516890.0 517043.4 60290.0 60580.1 60605.1 60619.7 60823.0 62825.0 62876.4 63185.0 63235.7 63378.0 16032.5 16133.4 16227.2 16249.8 16256.6 12454.4 12478.4 12617.6 12620.2 12646.1 15550.0 17853.0 18368.9 18378.1 18385.4 7793.7 7945.9 8017.5 8021.3 8044.7 849.6 940.6 965.7 1011.0 1029.1 24312.0 27376.3 28103.4 28820.1 29313.3 832.9 908.5 1011.7 1033.2 1111.0 9981.8 13559.8 13975.7 14765.9 15104.8 691243.0 703516.5 703766.0 705072.0 705618.0 53577.7 53826.5 53842.7 53865.5 53903.5 1061.5 1214.7 1252.2 1341.7 1358.2 28731.4 29804.0 30227.0 30660.0 30933.0 47537.8 52641.7 54338.5 54568.0 58219.4 605272.0 628952.0 649881.0 656400.0 656494.0 40427.0 40914.0 41349.8 41420.4 41544.8 44965.8 45028.0 45109.5 45274.0 45722.9 35618.3 36802.8 37991.1 38841.0 39942.4 36935.8 37521.0 37819.6 38420.8 38820.2 33626.7 35986.3 36961.3 37176.6 38451.8 31349.5 33668.0 33709.7 33767.8 33879.2 24729.0 24972.5 25175.5 25273.5 25609.9 18:36:28 INFO - PROCESS | 1644 | 18:36:28 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:34087.79 Median:182570.73 stddev:98990.62 (54.2%) stddev-sans-first:99235.94 18:36:28 INFO - PROCESS | 1644 | Values: 371.0 375.6 376.0 383.9 401.4 18563.3 19195.5 19370.8 19674.0 19900.9 19033.6 19073.0 19544.3 19544.8 19744.4 5193.9 5412.4 5596.2 5605.6 5993.0 6037.8 6142.6 6145.6 6147.1 6156.1 5162.8 5844.7 6014.2 6094.5 6102.9 6009.3 6030.4 6037.7 6060.8 6064.7 5185.7 5226.0 5308.7 5344.2 5344.7 12668.4 12942.7 12949.5 13087.3 13133.8 2068.7 2163.2 2271.6 2287.9 2309.3 8407.6 9768.8 9912.1 10265.9 10292.7 1529.5 1810.4 1886.6 1923.0 1976.8 106185.0 117598.0 128292.0 136769.0 137616.3 11266.1 11437.7 11730.6 11759.5 11947.6 14657.3 14665.1 14687.2 14689.9 14739.6 8341.5 8544.6 8586.1 8662.0 8697.7 6966.3 8182.2 8484.5 8512.7 8622.5 4441.4 4600.5 4604.4 4622.4 4622.9 1929.4 1967.3 1974.6 1979.1 1992.2 811.8 825.3 863.2 870.7 891.8 20181.4 20579.4 20644.7 20737.9 20835.3 885.2 886.0 911.1 943.7 943.8 10274.1 10872.5 10878.9 10905.6 11037.7 362832.2 628524.0 636042.8 638606.0 638792.0 82354.8 83818.6 129327.0 132669.0 140782.0 945.3 984.8 1006.2 1009.7 1011.5 16372.3 17858.2 18490.8 19025.8 19218.9 43757.3 55140.0 56269.4 57588.0 58193.0 84165.6 86345.6 86935.0 88146.6 88301.0 7896.8 7925.4 8007.4 8201.7 8285.4 8432.0 11316.5 12171.3 12312.9 12475.1 8466.7 10228.8 10562.0 10649.5 11745.2 10409.2 10441.9 10530.0 10675.9 10884.5 10746.3 10848.0 10904.0 10994.0 11013.0 7022.6 7431.1 7436.9 7943.2 7999.4 10274.4 10733.9 10749.8 10805.7 11143.2 18:36:28 INFO - PROCESS | 1644 | 18:36:28 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:5534.09 Median:5000.40 stddev:3087.36 (61.7%) stddev-sans-first:3080.86 18:36:28 INFO - PROCESS | 1644 | Values: 1462.8 1471.3 1620.8 1659.1 1735.5 7615.4 7914.7 8112.1 8556.5 8597.2 6850.2 8299.2 8423.2 8475.2 8506.8 2315.3 2357.8 2359.0 2359.4 2365.6 2359.8 2382.0 2406.7 2410.7 2413.5 2344.5 2352.2 2358.2 2371.5 2377.7 2259.9 2283.1 2286.3 2292.7 2355.8 1854.6 1981.9 2373.4 2381.2 2386.6 8451.0 8482.0 8502.1 8567.3 8599.0 5173.8 5326.1 5342.2 5348.0 5372.8 3147.0 3345.8 3355.8 3689.5 3693.0 861.7 873.7 1084.4 1102.1 1117.2 11176.2 11186.9 11280.8 11286.8 11386.8 4374.6 4461.6 4545.3 4691.3 4828.0 9624.0 9651.0 9673.1 9677.9 9695.6 5225.3 5255.9 5268.9 5273.2 5277.8 4312.8 4339.0 4821.6 4852.1 4854.8 3805.8 3875.8 3914.8 3937.2 3965.7 1083.4 1271.6 1279.2 1282.4 1283.2 819.9 909.1 915.2 921.5 921.7 5837.9 5846.5 5868.5 5931.3 6088.8 448.7 454.5 456.9 457.3 464.0 3581.0 3872.2 3934.6 4043.0 4060.7 10155.6 10441.5 10516.5 10535.0 10538.5 10094.2 10153.3 10302.3 10455.3 10473.7 7771.5 7890.1 7915.4 8033.2 8035.6 5627.4 5710.7 5758.2 5774.9 5785.4 9291.4 9398.5 9494.1 9528.8 9615.0 9653.8 9764.4 9806.3 9865.6 9871.4 6728.0 6951.8 7214.3 7326.8 7405.0 7870.3 8170.0 8190.2 8190.6 8198.8 5645.7 7133.5 7271.7 7292.6 7305.7 7058.5 7085.3 7098.1 7140.0 7193.1 6840.9 6857.3 6951.9 6956.1 6973.7 4919.6 4948.4 4965.5 4979.1 4990.4 4813.3 4913.7 4924.8 5010.4 5085.8 18:36:28 INFO - PROCESS | 1644 | 18:36:28 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4541.57 Median:5037.76 stddev:1432.66 (28.4%) stddev-sans-first:1400.72 18:36:28 INFO - PROCESS | 1644 | Values: 292.0 300.8 301.5 307.8 310.9 4687.5 5268.8 5566.8 5568.2 5570.2 4063.4 4556.0 4849.8 4917.4 5466.9 3280.7 3533.3 4137.8 4158.2 4160.6 3300.7 3488.4 3864.8 4168.0 4169.6 3336.8 3591.0 3795.0 4164.8 4175.7 4123.5 4184.9 4186.1 4188.1 4188.1 3032.1 3645.5 3647.4 3686.0 3690.6 4524.5 4604.4 5108.8 5276.4 5315.5 3776.8 4213.8 4662.2 4798.2 4802.3 5652.3 5784.7 5793.7 5818.3 5824.2 3075.4 3814.9 3894.9 3964.8 4026.7 5696.1 5982.9 6058.4 6081.5 6109.0 3337.2 3833.1 3947.7 3959.8 3960.1 3620.9 3665.3 3758.6 3815.8 4030.7 4047.7 4063.9 4406.0 4566.1 4701.6 5848.5 5855.0 5866.6 5890.6 5897.3 1065.6 1151.2 1260.4 1279.7 1304.9 869.2 889.4 891.4 892.0 895.7 4412.9 5021.5 5158.8 5161.8 5213.9 5115.1 5291.4 5555.3 5572.2 5624.4 4571.4 4615.3 5036.6 5158.6 5190.1 2924.9 2939.4 2993.5 2999.5 3021.4 4783.4 5144.9 5547.7 5807.9 5820.6 4801.8 5518.2 5745.0 5782.9 5784.6 4694.1 4903.6 5744.9 5747.6 5765.0 5209.7 5473.7 5612.1 5632.1 5635.6 4993.7 5012.6 5498.9 5522.3 5541.4 5831.9 5837.4 6159.3 6186.9 6215.7 3534.3 3726.5 3736.5 3753.5 4039.7 5038.9 5606.2 5744.4 6002.6 6008.6 5241.7 5626.9 5761.4 5827.0 5872.7 4955.1 5802.9 5840.1 5841.6 5900.7 5590.9 5666.4 5679.1 5741.6 5763.9 4855.5 4998.3 5505.4 5877.8 5905.4 5454.9 5630.8 5712.3 5773.4 5774.3 18:36:28 INFO - PROCESS | 1644 | 18:36:28 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:20419.92 Median:24940.23 stddev:30107.27 (120.7%) stddev-sans-first:30155.12 18:36:28 INFO - PROCESS | 1644 | Values: 645.6 647.7 731.2 753.5 807.9 11378.4 14749.2 14894.4 14900.6 14909.4 12089.1 13378.7 13469.6 14402.6 14911.6 661.1 674.3 812.6 853.9 861.4 845.9 849.0 852.4 853.7 854.6 761.5 808.1 877.8 881.9 885.2 863.7 865.7 870.3 873.7 874.6 628.5 640.4 647.5 648.1 649.1 13024.7 13101.7 14011.4 14378.9 14639.0 10903.8 10923.7 10925.8 11115.7 11180.9 11041.2 11350.8 11390.3 11587.2 11601.7 3551.5 3576.3 3584.6 3614.1 3672.8 87090.0 91409.5 92222.0 92306.0 93168.1 10231.2 10268.9 10359.3 10535.7 10623.0 20270.7 21405.8 21926.7 22060.7 22097.1 7768.1 7791.6 7878.7 7916.9 8070.9 4019.9 4087.0 4145.6 4176.3 4243.9 3462.1 3579.3 3581.4 3590.0 3591.4 641.5 662.5 668.2 670.0 682.3 595.3 598.5 603.9 639.3 643.2 8960.3 9190.8 9323.6 9358.9 9363.3 567.4 596.8 615.1 633.8 661.6 4410.0 4477.4 4524.5 4549.2 4626.2 115577.0 116410.0 116970.0 117138.0 120926.4 66069.0 81337.6 86743.6 88380.6 92134.0 65623.3 85342.0 85841.0 87533.0 88657.6 9501.0 9860.4 9895.8 9902.6 9909.9 45356.0 55608.7 56042.2 56763.0 56972.4 70393.2 74332.9 75354.6 78639.4 80362.0 11804.5 12412.3 12428.5 12556.2 12709.5 14012.7 14302.0 14389.8 14392.1 14400.6 12804.9 12989.5 13063.0 13111.7 13170.0 12197.1 12559.7 12857.4 12890.9 13175.1 9881.7 10236.6 10528.2 11223.7 12374.2 11002.3 11054.7 11417.0 11465.8 11477.2 9399.6 11274.5 11306.7 11611.4 11645.0 18:36:28 INFO - PROCESS | 1644 | -------- Summary: end -------- 18:36:28 INFO - PROCESS | 1644 | 18:36:29 INFO - TEST-INFO | 1644: exit 0 18:36:29 INFO - TEST-OK | dromaeo_css | took 1116010ms 18:36:29 INFO - TEST-START | kraken 18:36:29 INFO - operating with platform_type : mac_ 18:36:29 INFO - Initialising browser for kraken test... 18:36:29 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmp5nB1rR/profile http://localhost:49241/getInfo.html) 18:36:31 ERROR - PROCESS | 1657 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5373: TypeError: parent.firstChild is null 18:36:32 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 18:36:32 INFO - PROCESS | 1657 | colorDepth:24 18:36:32 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 18:36:32 INFO - PROCESS | 1657 | __metrics 18:36:32 ERROR - PROCESS | 1657 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 18:36:33 INFO - TEST-INFO | 1657: exit 0 18:36:33 INFO - Browser initialized. 18:36:33 INFO - Running cycle 1/1 for kraken test... 18:36:33 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmp5nB1rR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:36:45 INFO - PROCESS | 1659 | RSS: Main: 230002688 18:36:45 INFO - PROCESS | 1659 | 18:36:48 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 18:36:49 INFO - PROCESS | 1659 | RSS: Main: 241889280 18:36:49 INFO - PROCESS | 1659 | 18:36:51 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 18:36:52 INFO - PROCESS | 1659 | RSS: Main: 249528320 18:36:52 INFO - PROCESS | 1659 | 18:36:55 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 18:36:56 INFO - PROCESS | 1659 | RSS: Main: 251379712 18:36:56 INFO - PROCESS | 1659 | 18:36:58 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 18:36:58 INFO - PROCESS | 1659 | RSS: Main: 253984768 18:36:58 INFO - PROCESS | 1659 | 18:37:00 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:37:01 INFO - PROCESS | 1659 | RSS: Main: 254308352 18:37:01 INFO - PROCESS | 1659 | 18:37:04 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 18:37:04 INFO - PROCESS | 1659 | RSS: Main: 258314240 18:37:04 INFO - PROCESS | 1659 | 18:37:08 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 18:37:08 INFO - PROCESS | 1659 | RSS: Main: 257978368 18:37:08 INFO - PROCESS | 1659 | 18:37:11 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 18:37:12 INFO - PROCESS | 1659 | RSS: Main: 257839104 18:37:12 INFO - PROCESS | 1659 | 18:37:13 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:37:14 INFO - PROCESS | 1659 | RSS: Main: 257069056 18:37:14 INFO - PROCESS | 1659 | 18:37:16 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:37:17 INFO - PROCESS | 1659 | RSS: Main: 257126400 18:37:17 INFO - PROCESS | 1659 | 18:37:19 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:37:20 INFO - PROCESS | 1659 | RSS: Main: 257187840 18:37:20 INFO - PROCESS | 1659 | 18:37:23 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:37:23 INFO - PROCESS | 1659 | RSS: Main: 257687552 18:37:23 INFO - PROCESS | 1659 | 18:37:26 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:37:27 INFO - PROCESS | 1659 | RSS: Main: 257572864 18:37:27 INFO - PROCESS | 1659 | 18:37:29 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 18:37:29 INFO - PROCESS | 1659 | RSS: Main: 259026944 18:37:29 INFO - PROCESS | 1659 | 18:37:29 INFO - PROCESS | 1659 | __start_tp_report 18:37:29 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 18:37:29 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 18:37:29 INFO - PROCESS | 1659 | |i|pagename|runs| 18:37:29 INFO - PROCESS | 1659 | |0;ai-astar;105;120;118;119;115;118;121;121;137;119 18:37:29 INFO - PROCESS | 1659 | |1;audio-beat-detection;144;117;124;181;143;114;115;118;147;116 18:37:29 INFO - PROCESS | 1659 | |2;audio-dft;198;197;197;201;196;221;277;211;220;284 18:37:29 INFO - PROCESS | 1659 | |3;audio-fft;87;58;60;86;60;60;60;60;77;70 18:37:29 INFO - PROCESS | 1659 | |4;audio-oscillator;87;72;89;71;70;70;85;69;73;89 18:37:29 INFO - PROCESS | 1659 | |5;imaging-gaussian-blur;137;124;89;89;88;85;91;76;91;85 18:37:29 INFO - PROCESS | 1659 | |6;imaging-darkroom;95;117;154;155;150;112;92;138;152;125 18:37:29 INFO - PROCESS | 1659 | |7;imaging-desaturate;89;95;86;87;89;94;130;93;90;90 18:37:29 INFO - PROCESS | 1659 | |8;json-parse-financial;56;54;54;56;57;59;59;58;57;58 18:37:29 INFO - PROCESS | 1659 | |9;json-stringify-tinderbox;68;73;70;82;79;61;90;93;62;74 18:37:29 INFO - PROCESS | 1659 | |10;stanford-crypto-aes;87;86;87;83;76;102;104;105;105;77 18:37:29 INFO - PROCESS | 1659 | |11;stanford-crypto-ccm;141;150;157;159;161;156;154;144;285;142 18:37:29 INFO - PROCESS | 1659 | |12;stanford-crypto-pbkdf2;173;174;178;213;172;161;204;216;170;168 18:37:29 INFO - PROCESS | 1659 | |13;stanford-crypto-sha256-iterative;67;68;66;68;71;83;73;90;65;70 18:37:29 INFO - PROCESS | 1659 | __end_tp_report 18:37:29 INFO - PROCESS | 1659 | __start_cc_report 18:37:29 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,965 18:37:29 INFO - PROCESS | 1659 | __end_cc_report 18:37:29 INFO - PROCESS | 1659 | __startTimestamp1479955049547__endTimestamp 18:37:29 INFO - PROCESS | 1659 | 18:37:29 INFO - PROCESS | 1659 | ------- Summary: start ------- 18:37:29 INFO - PROCESS | 1659 | Number of tests: 14 18:37:29 INFO - PROCESS | 1659 | 18:37:29 INFO - PROCESS | 1659 | [#0] ai-astar Cycles:10 Average:119.30 Median:119.50 stddev:7.79 (6.5%) stddev-sans-first:6.31 18:37:29 INFO - PROCESS | 1659 | Values: 105.0 120.0 118.0 119.0 115.0 118.0 121.0 121.0 137.0 119.0 18:37:29 INFO - PROCESS | 1659 | 18:37:29 INFO - PROCESS | 1659 | [#1] audio-beat-detection Cycles:10 Average:131.90 Median:133.50 stddev:21.72 (16.3%) stddev-sans-first:22.59 18:37:29 INFO - PROCESS | 1659 | Values: 144.0 117.0 124.0 181.0 143.0 114.0 115.0 118.0 147.0 116.0 18:37:29 INFO - PROCESS | 1659 | 18:37:29 INFO - PROCESS | 1659 | [#2] audio-dft Cycles:10 Average:220.20 Median:215.50 stddev:33.18 (15.4%) stddev-sans-first:34.20 18:37:29 INFO - PROCESS | 1659 | Values: 198.0 197.0 197.0 201.0 196.0 221.0 277.0 211.0 220.0 284.0 18:37:29 INFO - PROCESS | 1659 | 18:37:29 INFO - PROCESS | 1659 | [#3] audio-fft Cycles:10 Average:67.80 Median:65.00 stddev:11.50 (17.7%) stddev-sans-first:9.87 18:37:29 INFO - PROCESS | 1659 | Values: 87.0 58.0 60.0 86.0 60.0 60.0 60.0 60.0 77.0 70.0 18:37:29 INFO - PROCESS | 1659 | 18:37:29 INFO - PROCESS | 1659 | [#4] audio-oscillator Cycles:10 Average:77.50 Median:79.00 stddev:8.75 (11.1%) stddev-sans-first:8.57 18:37:29 INFO - PROCESS | 1659 | Values: 87.0 72.0 89.0 71.0 70.0 70.0 85.0 69.0 73.0 89.0 18:37:29 INFO - PROCESS | 1659 | 18:37:29 INFO - PROCESS | 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:95.50 Median:88.50 stddev:19.20 (21.7%) stddev-sans-first:13.24 18:37:29 INFO - PROCESS | 1659 | Values: 137.0 124.0 89.0 89.0 88.0 85.0 91.0 76.0 91.0 85.0 18:37:29 INFO - PROCESS | 1659 | 18:37:29 INFO - PROCESS | 1659 | [#6] imaging-darkroom Cycles:10 Average:129.00 Median:153.00 stddev:24.33 (15.9%) stddev-sans-first:22.48 18:37:29 INFO - PROCESS | 1659 | Values: 95.0 117.0 154.0 155.0 150.0 112.0 92.0 138.0 152.0 125.0 18:37:29 INFO - PROCESS | 1659 | 18:37:29 INFO - PROCESS | 1659 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:90.00 stddev:12.88 (14.3%) stddev-sans-first:13.51 18:37:29 INFO - PROCESS | 1659 | Values: 89.0 95.0 86.0 87.0 89.0 94.0 130.0 93.0 90.0 90.0 18:37:29 INFO - PROCESS | 1659 | 18:37:29 INFO - PROCESS | 1659 | [#8] json-parse-financial Cycles:10 Average:56.80 Median:57.50 stddev:1.81 (3.2%) stddev-sans-first:1.90 18:37:29 INFO - PROCESS | 1659 | Values: 56.0 54.0 54.0 56.0 57.0 59.0 59.0 58.0 57.0 58.0 18:37:29 INFO - PROCESS | 1659 | 18:37:29 INFO - PROCESS | 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:75.20 Median:76.50 stddev:10.84 (14.2%) stddev-sans-first:11.18 18:37:29 INFO - PROCESS | 1659 | Values: 68.0 73.0 70.0 82.0 79.0 61.0 90.0 93.0 62.0 74.0 18:37:29 INFO - PROCESS | 1659 | 18:37:29 INFO - PROCESS | 1659 | [#10] stanford-crypto-aes Cycles:10 Average:91.20 Median:80.00 stddev:11.66 (14.6%) stddev-sans-first:12.27 18:37:29 INFO - PROCESS | 1659 | Values: 87.0 86.0 87.0 83.0 76.0 102.0 104.0 105.0 105.0 77.0 18:37:29 INFO - PROCESS | 1659 | 18:37:29 INFO - PROCESS | 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:164.90 Median:156.50 stddev:42.80 (27.4%) stddev-sans-first:44.52 18:37:29 INFO - PROCESS | 1659 | Values: 141.0 150.0 157.0 159.0 161.0 156.0 154.0 144.0 285.0 142.0 18:37:29 INFO - PROCESS | 1659 | 18:37:29 INFO - PROCESS | 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.90 Median:176.00 stddev:20.10 (11.4%) stddev-sans-first:20.99 18:37:29 INFO - PROCESS | 1659 | Values: 173.0 174.0 178.0 213.0 172.0 161.0 204.0 216.0 170.0 168.0 18:37:29 INFO - PROCESS | 1659 | 18:37:29 INFO - PROCESS | 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.10 Median:70.50 stddev:8.12 (11.5%) stddev-sans-first:8.40 18:37:29 INFO - PROCESS | 1659 | Values: 67.0 68.0 66.0 68.0 71.0 83.0 73.0 90.0 65.0 70.0 18:37:29 INFO - PROCESS | 1659 | -------- Summary: end -------- 18:37:29 INFO - PROCESS | 1659 | 18:37:30 INFO - TEST-INFO | 1659: exit 0 18:37:30 INFO - TEST-OK | kraken | took 61241ms 18:37:30 INFO - SUITE-END | took 1177s 18:37:30 INFO - Completed test suite (00:19:37) 18:37:30 INFO - javascript benchmark 18:37:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7193.141091771362, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3132.545780861775, 3449.1894404814866, 3455.2398472336076, 3460.0, 3483.721302295816, 4783.425988881339, 5847.8538036549, 5982.34194238634, 6031.035034394497, 6057.878842423154, 5968.462838344548, 5979.252593425822, 6036.12476190952, 6046.274447066358, 6084.908726369097, 5342.679439233649, 5426.782928682867, 5451.318585176853, 5456.536194423459, 5470.835874923759, 4005.4592629995013, 5485.725713714318, 5508.394076651565, 5510.476504732064, 5528.115501519757, 5305.257263983045, 5317.075608415375, 5506.119020956648, 5511.366192887809, 5532.972335138339, 5330.973345133269, 5351.919721204185, 5402.189671549259, 5424.430434804368, 5491.725413729338, 5285.577153827723, 5369.7583608737705, 5387.838364849061, 5396.892062158774, 5419.349678038621, 5819.418058194189, 5951.166836642873, 5965.955957707427, 6000.309964354112, 6028.608140470856, 6283.088952101965, 6291.748330066792, 6295.811125666238, 6326.082718005863, 6386.201724784402, 6381.010943303749, 6395.232572091332, 6402.359764023607, 6406.167198264196, 6412.551121421502, 6125.173101631267, 6135.969320153371, 6146.78486252979, 6165.722542485554, 6171.0, 6265.404786545362, 6310.0, 6334.3032266450655, 6344.873102537924, 6378.0, 6322.9051564226575, 6367.0, 6382.648954307558, 6403.103565500834, 6408.775692850728, 6370.936290637127, 6400.7119679614525, 6404.391582799627, 6406.423421892052, 6435.485161187094, 6159.19930409044, 6234.875302494015, 6242.875142497124, 6252.74989000444, 6304.873902521924, 6266.997280435108, 6294.622322660655, 6327.430531252117, 6346.30190679025, 6364.268109167412, 5053.418856831438, 5093.8726531836255, 5116.2069879168785, 5120.155174396183, 5123.590112790967, 4479.7984090716, 4500.572445617661, 4547.3179023146795, 4616.722996620214, 4623.329617205486, 5839.970800145972, 5979.222701048836, 6001.0, 6044.788432404933, 6111.8472038198515, 6368.617882927039, 6370.458511026614, 6374.0, 6387.489000879918, 6405.3594640535575, 5892.793752218652, 5939.2575928009, 5997.760089596367, 6015.639061656315, 6022.18700475439, 5022.422421421583, 5587.078132108238, 5627.296587926509, 5655.349634792052, 5677.630953987978, 6234.657093859882, 6298.968505157628, 6393.041043843367, 6398.648074355955, 6408.679566021773, 6246.843828904313, 6290.811275661737, 6322.841928951813, 6386.87226255463, 6404.615723056631, 6400.423961843457, 6407.839804004937, 6408.839779005562, 6414.583052101599, 6575.80272591823, 6193.102000210034, 6328.525360597881, 6350.587211831401, 6356.809295721136, 6366.4588509977175, 6142.078688196806, 6282.371762823681, 6312.179416675803, 6332.208473940757, 6383.9361606383345, 6214.782482613087, 6310.5267104968225, 6332.873342533031, 6338.11266422692, 6357.650329231937, 6315.715792789428, 6325.905111423424, 6332.0818481320875, 6350.555461117862, 6367.299597044229, 5094.847154585368, 5907.49786268172, 6045.183900173596, 6100.54245931548, 6120.751898419022, 6235.937640623536, 6275.184226050585, 6301.306856245901, 6305.243370795534, 6327.810165695037, 6174.8147555573405, 6200.968995155175, 6201.162843016135, 6208.441240288395, 6223.37766223374], "value": 5858.2900255714385, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.8406847432213, 472.9594891239274, 493.55086870947446, 535.3896557923965, 576.8722148200269, 36281.2743745125, 38798.0, 38836.22327553454, 38854.22291554167, 38857.611423885784, 39444.40833387501, 40227.59772402275, 40320.193596128134, 40424.787256382355, 40475.39286910699, 8196.918030819705, 8353.289970352527, 8934.508602026903, 9199.632014719436, 9250.35247532673, 9214.170724634781, 9325.974142844281, 9333.160015598594, 9352.111549402796, 9352.579133938954, 9279.025702301262, 9335.906640933572, 9339.953300233523, 9353.76615584609, 9363.48500832454, 9236.122568355995, 9339.252859771203, 9345.439273643604, 9346.11211934872, 9356.719298421058, 9073.77315567116, 9146.176844083999, 9251.16739493442, 9255.259579233645, 9282.675106371313, 38541.80729096365, 38606.0, 38722.41916371257, 38966.41550376718, 39111.80444097761, 3121.562981182636, 3215.8552865121123, 3249.2851572653976, 3277.1807048237943, 3294.5222942673176, 27999.720002799913, 28017.57973630397, 28114.578281325797, 28259.71740282612, 28281.292967675767, 3147.1974646465233, 3168.4930411134337, 3276.6231883333244, 3333.949990750141, 3348.196432856121, 509743.0, 514591.0, 515123.0, 516890.0, 517043.41478292743, 60290.0, 60580.091298630556, 60605.090923636184, 60619.696901516094, 60823.0, 62825.0, 62876.37123628797, 63185.0, 63235.6838215806, 63378.0, 16032.519024429286, 16133.354665813471, 16227.18864056816, 16249.837501625068, 16256.59358516023, 12454.377281135907, 12478.43847026886, 12617.558385456648, 12620.242785432903, 12646.051546134076, 15550.0, 17853.0, 18368.897866128074, 18378.08109594515, 18385.448436546925, 7793.688252469951, 7945.880811787828, 8017.478863873829, 8021.27808497238, 8044.678212871419, 849.6091797773094, 940.6472572785206, 965.7392504024015, 1010.9797804043878, 1029.063552167524, 24312.0, 27376.315592109957, 28103.43793124126, 28820.135395938156, 29313.267168320963, 832.8584140696096, 908.5184852028435, 1011.7167193185772, 1033.214756784834, 1111.0, 9981.750456238507, 13559.796603050961, 13975.672311130425, 14765.852341476662, 15104.773428398583, 691243.0, 703516.4824175846, 703766.0, 705072.0, 705618.0, 53577.732111339974, 53826.46173538293, 53842.73078634582, 53865.461345386044, 53903.46096539063, 1061.506399524225, 1214.6538236602526, 1252.2486508095253, 1341.704824938512, 1358.1850889466043, 28731.42537149287, 29804.0, 30227.0, 30660.0, 30933.0, 47537.76231118822, 52641.7367913158, 54338.45661543334, 54568.0, 58219.4178058231, 605272.0, 628952.0, 649881.0, 656400.0, 656494.0, 40426.97865106792, 40913.977150571474, 41349.79325103355, 41420.378694320214, 41544.792276038424, 44965.77517112524, 45028.0, 45109.548904511845, 45274.0, 45722.85692857705, 35618.28763424769, 36802.8159859199, 37991.05022374352, 38841.0, 39942.400863986484, 36935.82730368968, 37521.0, 37819.62180378161, 38420.807895960344, 38820.223595527365, 33626.66373336333, 35986.280274393845, 36961.26077478489, 37176.62823371732, 38451.807740961114, 31349.529757053206, 33668.0, 33709.651613935166, 33767.83116084404, 33879.153021174665, 24729.0, 24972.50054998926, 25175.49649007046, 25273.494530109663, 25609.871950640874], "value": 19768.607811521968, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.98330575124135, 375.56246972277296, 375.95864454909974, 383.93473109571363, 401.3898873711958, 18563.257469701213, 19195.520111997208, 19370.806291937075, 19674.016299185037, 19900.905450200233, 19033.619327613473, 19073.046347682593, 19544.31594894179, 19544.804551954476, 19744.407667769952, 5193.896122077556, 5412.431694672072, 5596.2165296858475, 5605.5515558755205, 5992.970035149818, 6037.75848966043, 6142.600730952496, 6145.600535965148, 6147.077938309245, 6156.076588511737, 5162.767675454595, 5844.707764611752, 6014.188084608566, 6094.512439004891, 6102.938970610281, 6009.278886533601, 6030.396960303979, 6037.698115094227, 6060.757569697184, 6064.696765161724, 5185.688858668492, 5225.973870130663, 5308.70802105884, 5344.171653393731, 5344.652597581145, 12668.4299206535, 12942.741145177139, 12949.54676586315, 13087.345632718327, 13133.802992955114, 2068.7414073240843, 2163.1888041984257, 2271.5684020036306, 2287.874166920819, 2309.2725791375665, 8407.621657025387, 9768.755781105458, 9912.058354456316, 10265.948670256601, 10292.742681433025, 1529.5334922848526, 1810.36637176987, 1886.6321067391864, 1922.9711554326695, 1976.8319692826128, 106185.0, 117598.0, 128292.0, 136768.999999999, 137616.31191843978, 11266.098712103012, 11437.656870293904, 11730.648080557597, 11759.47082381295, 11947.581834635746, 14657.34041968114, 14665.120092794466, 14687.192204428646, 14689.853101469062, 14739.631509212355, 8341.45780524264, 8544.572771361407, 8586.055533891267, 8661.956690216508, 8697.695580654647, 6966.303369662993, 8182.1817818217705, 8484.490930544187, 8512.74461766148, 8622.525761083201, 4441.422615060086, 4600.5169457207185, 4604.447466303998, 4622.422197225347, 4622.930656040162, 1929.411529483521, 1967.331107423483, 1974.5712773288935, 1979.139074502596, 1992.2329902987165, 811.8213992921548, 825.2737590919952, 863.218786997768, 870.7344260000634, 891.8350105230553, 20181.39455816328, 20579.382618521468, 20644.69032964507, 20737.94828692361, 20835.270765523135, 885.1812073831809, 886.0, 911.129870973219, 943.7451887990204, 943.8395472769646, 10274.075333220046, 10872.510737016853, 10878.891211087788, 10905.618303359344, 11037.724056898642, 362832.1858390744, 628524.0, 636042.8197858981, 638606.0, 638792.0, 82354.76467852987, 83818.5809070963, 129327.0, 132669.0, 140782.0, 945.2863088368375, 984.8227319082633, 1006.2201793610011, 1009.6769033909079, 1011.4891979550428, 16372.345106195618, 17858.231974044043, 18490.798098123578, 19025.80974190296, 19218.942958137435, 43757.34363984606, 55139.96730179879, 56269.43730562642, 57588.0, 58193.0, 84165.57917210375, 86345.56827215824, 86935.0, 88146.55926720325, 88301.0, 7896.763097107096, 7925.365970722213, 8007.399445041528, 8201.671933122609, 8285.3785966054, 8432.0, 11316.49075791575, 12171.2697238166, 12312.938435307766, 12475.126741128033, 8466.703665371682, 10228.795424091626, 10562.0, 10649.520771565452, 11745.236559623598, 10409.1672666185, 10441.895581044397, 10530.0, 10675.946620266848, 10884.45577721095, 10746.313421644589, 10848.0, 10904.0, 10994.0, 11013.0, 7022.613756243456, 7431.071116110486, 7436.92563074384, 7943.165967573322, 7999.360051195774, 10274.434906080238, 10733.892661073289, 10749.838752418564, 10805.675829725122, 11143.161378372477], "value": 9222.019007579313, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1462.839087700353, 1471.337897945924, 1620.7811945387377, 1659.1289572974179, 1735.5227312489055, 7615.390768738502, 7914.722984695537, 8112.107668156498, 8556.486610803357, 8597.226249637524, 6850.24647288799, 8299.211574900375, 8423.199796019384, 8475.194856488639, 8506.787330316745, 2315.2822624986266, 2357.7760112789256, 2358.9528209435803, 2359.3629719975597, 2365.550545396378, 2359.846609970355, 2381.9761802382013, 2406.68713067301, 2410.7227668818045, 2413.4811015631635, 2344.460774021976, 2352.223766157164, 2358.1864256831363, 2371.4782747795543, 2377.6909001829736, 2259.9209027684033, 2283.132409684326, 2286.2683941138844, 2292.6904867842795, 2355.7997570206553, 1854.619802940394, 1981.890141520753, 2373.4066483379156, 2381.2261015169966, 2386.642003699459, 8451.0, 8481.95759021207, 8502.06477287498, 8567.314614830799, 8599.0, 5173.793048278064, 5326.094563924142, 5342.198670199462, 5348.0, 5372.838814835562, 3146.961502704125, 3345.800815781742, 3355.76509644325, 3689.5203623529037, 3693.0398096495105, 861.7113267055544, 873.6505397840851, 1084.4252546150553, 1102.0687519046394, 1117.1956191542076, 11176.161787865862, 11186.888131118665, 11280.830787538194, 11286.774264514746, 11386.772264554662, 4374.58441448062, 4461.57615026572, 4545.272756358967, 4691.296305554194, 4828.0, 9624.0, 9650.986646402167, 9673.129418352382, 9677.90322096784, 9695.563699633534, 5225.268462415265, 5255.921161182585, 5268.920966185434, 5273.209018647157, 5277.788888444419, 4312.7627980461375, 4338.956610433918, 4821.614270858323, 4852.053849499384, 4854.805807767721, 3805.7906815125434, 3875.8255878485534, 3914.8434062637743, 3937.2322397132857, 3965.72239943207, 1083.4041277297456, 1271.5613113475836, 1279.2132838304376, 1282.3588205897051, 1283.1980012492193, 819.8606236939735, 909.1136142261374, 915.158054589779, 921.4793641592479, 921.6958403726754, 5837.88324233513, 5846.532277417797, 5868.530517558585, 5931.288245410578, 6088.817335479854, 448.7195502810743, 454.51367037270467, 456.86979210924727, 457.2501098198956, 463.99081996657134, 3580.98209508956, 3872.186840763471, 3934.6065393461, 4043.0094626816617, 4060.7360521566, 10155.593776249016, 10441.530131144116, 10516.526756295985, 10535.0, 10538.473076346152, 10094.242931780145, 10153.340032897986, 10302.33034852732, 10455.320404173704, 10473.738156546147, 7771.533707977426, 7890.132085470594, 7915.44591878563, 8033.19668033192, 8035.598220088972, 5627.352854421631, 5710.685912274865, 5758.193852860519, 5774.884502310014, 5785.3636100028125, 9291.442513449214, 9398.530073496435, 9494.05059494045, 9528.809423811346, 9614.990426005448, 9653.758656033375, 9764.414135151914, 9806.313558050868, 9865.556049977911, 9871.358361706752, 6728.0, 6951.756688515878, 7214.2785721427435, 7326.780196594111, 7404.9633051371775, 7870.331021863206, 8170.0, 8190.180981901762, 8190.631421585924, 8198.754037378889, 5645.661260324394, 7133.464990125865, 7271.709131634887, 7292.598907060163, 7305.671244793891, 7058.505904586629, 7085.326893945067, 7098.114843551515, 7140.0, 7193.136823581203, 6840.9315906842285, 6857.348551887562, 6951.895721564281, 6956.0609317741455, 6973.686184121828, 4919.557239848287, 4948.35671362735, 4965.503449655005, 4979.103761322996, 4990.426100998432, 4813.309499795218, 4913.70517768935, 4924.778384972757, 5010.3737032870895, 5085.796568137233], "value": 4390.871610896226, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.99562006569903, 300.78493876878025, 301.458881308052, 307.81992534367396, 310.8647738233868, 4687.531246875311, 5268.789248430058, 5566.805161819332, 5568.192612071252, 5570.2480165177685, 4063.3701776224657, 4556.0, 4849.781759820808, 4917.3607431033915, 5466.890662186754, 3280.6555311692287, 3533.3463309287736, 4137.793110344478, 4158.189153115144, 4160.604742549468, 3300.7359411247044, 3488.406970814955, 3864.787436690981, 4167.9583204167875, 4169.60388763067, 3336.816475093869, 3590.9765716770726, 3794.9810250948844, 4164.791760411982, 4175.728577642459, 4123.463949686551, 4184.8744537663915, 4186.0581369192, 4188.078622703, 4188.141431006637, 3032.1358412852233, 3645.5078564393734, 3647.434647629595, 3686.00477870976, 3690.612485689018, 4524.547545245482, 4604.447466304032, 5108.770105345268, 5276.393214780311, 5315.495027972336, 3776.7733935963656, 4213.820130363503, 4662.230731929228, 4798.184308667535, 4802.255650374198, 5652.293463317085, 5784.652920824764, 5793.7392817323325, 5818.283606336141, 5824.184614154022, 3075.369549242422, 3814.8855534334016, 3894.92889455402, 3964.7621142731527, 4026.71812973092, 5696.088625819849, 5982.8504287392725, 6058.39416058395, 6081.483073938721, 6109.022556391001, 3337.18239031434, 3833.099221682902, 3947.684185265172, 3959.7822119783114, 3960.128771670228, 3620.873269435557, 3665.303592317451, 3758.624137586274, 3815.828287727059, 4030.6573941215324, 4047.6761859051207, 4063.8577649782706, 4405.968538787038, 4566.109608626286, 4701.553352431513, 5848.5321174305955, 5855.0, 5866.6480011199465, 5890.558208134407, 5897.262842144732, 1065.5950738719391, 1151.205668089016, 1260.426505939795, 1279.6992706713916, 1304.8564657887625, 869.2046777198792, 889.3729920406098, 891.4473026723473, 891.987594080723, 895.7312806158127, 4412.933805992912, 5021.548060674557, 5158.819441319611, 5161.845144645667, 5213.881033983187, 5115.0844018908865, 5291.439025487491, 5555.278113482731, 5572.192032155314, 5624.409437009137, 4571.405717256735, 4615.284760673212, 5036.64743467961, 5158.632962265003, 5190.091733946544, 2924.8976285829895, 2939.3974235281717, 2993.476141675198, 2999.460097182528, 3021.416688731147, 4783.4499032611, 5144.8713782155, 5547.667139971614, 5807.854803629859, 5820.563457740602, 4801.831935882227, 5518.172274158908, 5745.0198009418755, 5782.855428614318, 5784.640609456775, 4694.0611877624615, 4903.632227583017, 5744.913826292609, 5747.597668163188, 5764.982256210293, 5209.661372010807, 5473.67157970523, 5612.102063669793, 5632.113242063954, 5635.619273278073, 4993.675411098267, 5012.649114561946, 5498.890022199614, 5522.2544956431975, 5541.3904470507405, 5831.854203644943, 5837.409992854279, 6159.29168145669, 6186.938130618816, 6215.689215539295, 3534.3461459630053, 3726.4721464199547, 3736.495573097706, 3753.4745135681574, 4039.6768258539832, 5038.874028149326, 5606.159076138449, 5744.396838331915, 6002.639841609517, 6008.639481631116, 5241.659292145999, 5626.943730562805, 5761.448455719934, 5826.970865145647, 5872.677002764889, 4955.132851751001, 5802.854928626818, 5840.065589505829, 5841.620294680833, 5900.675462849585, 5590.888182236251, 5666.433356664301, 5679.088634846035, 5741.593309639165, 5763.9135412968, 4855.465898751205, 4998.300237966615, 5505.394406615189, 5877.794277200277, 5905.35041145465, 5454.863628409322, 5630.83212963004, 5712.285964254468, 5773.364929857628, 5774.335951365647], "value": 4082.1685338948955, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [645.6475815489591, 647.7409036385442, 731.1664702239448, 753.4650398217266, 807.9394045446589, 11378.37418941958, 14749.188794616291, 14894.404223831047, 14900.552983410487, 14909.403623855047, 12089.093318001142, 13378.66553336167, 13469.595912122628, 14402.567922962302, 14911.552653420387, 661.1009027722317, 674.2818897873761, 812.6140083460339, 853.9402241843073, 861.3927181337174, 845.8731190321439, 848.9830203395928, 852.3820230333021, 853.7182729699184, 854.5940678177878, 761.4517547365888, 808.1393316118315, 877.7761670773943, 881.9338549608805, 885.2165833237599, 863.7365603490932, 865.7316231968078, 870.3298460185654, 873.6767396063466, 874.6282829797311, 628.5411649495894, 640.4204195203301, 647.4626060369882, 648.099142192353, 649.1431310669917, 13024.674383140497, 13101.672458188526, 14011.369488373048, 14378.856211437856, 14639.0, 10903.83644245337, 10923.726906827313, 10925.836112458319, 11115.666530004031, 11180.944095279552, 11041.171912106623, 11350.829737553944, 11390.316581005083, 11587.188896777228, 11601.709957251052, 3551.5383000210068, 3576.266865292635, 3584.5698516177963, 3614.060344310472, 3672.761270517408, 87090.0, 91409.54295228548, 92222.0, 92306.0, 93168.06831931662, 10231.232657550714, 10268.948655256676, 10359.326643768281, 10535.736606584744, 10623.0, 20270.69593956092, 21405.78594214038, 21926.671099933516, 22060.669089963663, 22097.116115355206, 7768.145503994556, 7791.649375778104, 7878.72424465141, 7916.852056451898, 8070.878936815952, 4019.9459177850867, 4086.959130408658, 4145.626893579592, 4176.269152898291, 4243.872683819491, 3462.082548124749, 3579.266250418658, 3581.4448760442174, 3589.964100359015, 3591.3894637911617, 641.5477088652489, 662.453475882399, 668.2281964331205, 669.9631520266432, 682.3006418421159, 595.3153873046032, 598.5480961873844, 603.9124326972565, 639.3031595560861, 643.2088531106765, 8960.283177345797, 9190.816183676288, 9323.580438880266, 9358.859617105749, 9363.250939924927, 567.4325674325675, 596.7881402102191, 615.1295916278506, 633.7655067625009, 661.5534514202894, 4410.0, 4477.350784136282, 4524.457065152137, 4549.226631472658, 4626.21354369758, 115577.0, 116410.0, 116970.0, 117138.0, 120926.39536802436, 66069.0, 81337.59331203306, 86743.56628216819, 88380.5580972091, 92134.0, 65623.34376656268, 85342.0, 85840.99999999875, 87533.0, 88657.55671221603, 9501.0, 9860.359076659994, 9895.752606184902, 9902.603895844373, 9909.851352229578, 45356.0, 55608.721956389956, 56042.15936761034, 56763.0, 56972.43027569837, 70393.24016899618, 74332.88500672387, 75354.62322688352, 78639.42721145431, 80362.0, 11804.468798904241, 12412.317322547347, 12428.502859885502, 12556.246625202517, 12709.497904396536, 14012.719745605234, 14301.998860079695, 14389.841523771518, 14392.06451580644, 14400.639984000065, 12804.871951280367, 12989.545365912525, 13063.020273479337, 13111.672208194872, 13170.0, 12197.146199765932, 12559.748805024032, 12857.357132143168, 12890.871091288967, 13175.07774455779, 9881.654142104991, 10236.641717539851, 10528.210384221062, 11223.71940701489, 12374.195677280948, 11002.284851484628, 11054.723631909266, 11417.0, 11465.770684586429, 11477.196596238184, 9399.577019034297, 11274.492647831032, 11306.679449346018, 11611.361375124448, 11645.010174135294], "value": 7235.2008232638955, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1578.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 120.0, 118.0, 119.0, 115.0, 118.0, 121.0, 121.0, 137.0, 119.0], "value": 119.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 117.0, 124.0, 181.0, 143.0, 114.0, 115.0, 118.0, 147.0, 116.0], "value": 131.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 197.0, 197.0, 201.0, 196.0, 221.0, 277.0, 211.0, 220.0, 284.0], "value": 220.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 58.0, 60.0, 86.0, 60.0, 60.0, 60.0, 60.0, 77.0, 70.0], "value": 67.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 72.0, 89.0, 71.0, 70.0, 70.0, 85.0, 69.0, 73.0, 89.0], "value": 77.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 124.0, 89.0, 89.0, 88.0, 85.0, 91.0, 76.0, 91.0, 85.0], "value": 95.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 117.0, 154.0, 155.0, 150.0, 112.0, 92.0, 138.0, 152.0, 125.0], "value": 129.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 95.0, 86.0, 87.0, 89.0, 94.0, 130.0, 93.0, 90.0, 90.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 54.0, 56.0, 57.0, 59.0, 59.0, 58.0, 57.0, 58.0], "value": 56.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 73.0, 70.0, 82.0, 79.0, 61.0, 90.0, 93.0, 62.0, 74.0], "value": 75.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 87.0, 83.0, 76.0, 102.0, 104.0, 105.0, 105.0, 77.0], "value": 91.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 150.0, 157.0, 159.0, 161.0, 156.0, 154.0, 144.0, 285.0, 142.0], "value": 164.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 174.0, 178.0, 213.0, 172.0, 161.0, 204.0, 216.0, 170.0, 168.0], "value": 182.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 66.0, 68.0, 71.0, 83.0, 73.0, 90.0, 65.0, 70.0], "value": 72.1, "unit": "score"}]}]} 18:37:30 INFO - Return code: 0 18:37:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:37:30 ERROR - # TBPL SUCCESS # 18:37:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:37:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:37:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:37:30 INFO - 2016-11-23 18:37:30.886 screenresolution[1664:10620] starting screenresolution argv=screenresolution get 18:37:30 INFO - 2016-11-23 18:37:30.889 screenresolution[1664:10620] Display 0: 1600x1200x32@60 18:37:30 INFO - 2016-11-23 18:37:30.895 screenresolution[1665:10623] starting screenresolution argv=screenresolution list 18:37:30 INFO - Available Modes on Display 0 18:37:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:37:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:37:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:37:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:37:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:37:31 INFO - Intel Iris: 18:37:31 INFO - Chipset Model: Intel Iris 18:37:31 INFO - Type: GPU 18:37:31 INFO - Bus: Built-In 18:37:31 INFO - VRAM (Dynamic, Max): 1536 MB 18:37:31 INFO - Vendor: Intel (0x8086) 18:37:31 INFO - Device ID: 0x0a2e 18:37:31 INFO - Revision ID: 0x0009 18:37:31 INFO - Displays: 18:37:31 INFO - Display: 18:37:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:37:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:37:31 INFO - Display Serial Number: 1600x1200 60 18:37:31 INFO - Main Display: Yes 18:37:31 INFO - Mirror: Off 18:37:31 INFO - Online: Yes 18:37:31 INFO - Rotation: Supported 18:37:31 INFO - Return code: 0 18:37:31 INFO - Running post-action listener: _resource_record_post_action 18:37:31 INFO - [mozharness: 2016-11-24 02:37:31.144749Z] Finished run-tests step (success) 18:37:31 INFO - Running post-run listener: _resource_record_post_run 18:37:31 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 38945792; Write bytes: 661045248; Read time: 383; Write time: 1830 18:37:31 INFO - TinderboxPrint: CPU usage
54.5% 18:37:31 INFO - TinderboxPrint: I/O read bytes / time
38,945,792 / 383 18:37:31 INFO - TinderboxPrint: I/O write bytes / time
661,045,248 / 1,830 18:37:31 INFO - TinderboxPrint: CPU idle
2,202.8 (45.5%) 18:37:31 INFO - TinderboxPrint: CPU system
977.7 (20.2%) 18:37:31 INFO - TinderboxPrint: CPU user
1,657.2 (34.3%) 18:37:31 INFO - TinderboxPrint: Swap in / out
386,949,120 / 0 18:37:31 INFO - install - Wall time: 31s; CPU: 55.0%; Read bytes: 345571840; Write bytes: 344008192; Read time: 26897; Write time: 602 18:37:31 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 36860928; Write bytes: 308105728; Read time: 359; Write time: 1135 18:37:31 INFO - Running post-run listener: _upload_blobber_files 18:37:31 INFO - Blob upload gear active. 18:37:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:37:31 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 18:37:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:37:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:37:32 INFO - (blobuploader) - INFO - Open directory for files ... 18:37:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:37:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:37:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:37:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:37:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:37:33 INFO - (blobuploader) - INFO - Done attempting. 18:37:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:37:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:37:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:37:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:37:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:37:34 INFO - (blobuploader) - INFO - Done attempting. 18:37:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:37:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:37:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:37:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:37:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:37:36 INFO - (blobuploader) - INFO - Done attempting. 18:37:36 INFO - (blobuploader) - INFO - Iteration through files over. 18:37:36 INFO - Return code: 0 18:37:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:37:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:37:36 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ab3804387570540d4ff143dad323466a4a0b493b8501fbdb5990711c84432bb8274b1322f65c39832f562809c3200e3b8856cc011d52b7bf58304bd004e3a571", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2745a0e418401e47b20eba2576c7da51362775c3605f29bbcee848dcec20a0405e152da3ab065b6b9277c104769f34c4d02ba40fe3404c9771b324d80bc86055", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/708ab517e7ed31072bfe24ab445cdb9c96442531e346a0485c502d62ee99252840d88338da3d52222c20de9e1e2bcec21411aa588200ae21e42729bef3145d7d"} 18:37:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:37:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:37:36 INFO - Contents: 18:37:36 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ab3804387570540d4ff143dad323466a4a0b493b8501fbdb5990711c84432bb8274b1322f65c39832f562809c3200e3b8856cc011d52b7bf58304bd004e3a571", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2745a0e418401e47b20eba2576c7da51362775c3605f29bbcee848dcec20a0405e152da3ab065b6b9277c104769f34c4d02ba40fe3404c9771b324d80bc86055", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/708ab517e7ed31072bfe24ab445cdb9c96442531e346a0485c502d62ee99252840d88338da3d52222c20de9e1e2bcec21411aa588200ae21e42729bef3145d7d"} 18:37:36 INFO - Running post-run listener: copy_logs_to_upload_dir 18:37:36 INFO - Copying logs to upload dir... 18:37:36 INFO - mkdir: /builds/slave/test/build/upload/logs 18:37:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.505978 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-11-23 18:37:36.488989) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-23 18:37:36.492646) ========= 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.4XwuIxIBNU/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.YLz4vNpzIc/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ab3804387570540d4ff143dad323466a4a0b493b8501fbdb5990711c84432bb8274b1322f65c39832f562809c3200e3b8856cc011d52b7bf58304bd004e3a571", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2745a0e418401e47b20eba2576c7da51362775c3605f29bbcee848dcec20a0405e152da3ab065b6b9277c104769f34c4d02ba40fe3404c9771b324d80bc86055", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/708ab517e7ed31072bfe24ab445cdb9c96442531e346a0485c502d62ee99252840d88338da3d52222c20de9e1e2bcec21411aa588200ae21e42729bef3145d7d"} build_url:https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011002 build_url: 'https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ab3804387570540d4ff143dad323466a4a0b493b8501fbdb5990711c84432bb8274b1322f65c39832f562809c3200e3b8856cc011d52b7bf58304bd004e3a571", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2745a0e418401e47b20eba2576c7da51362775c3605f29bbcee848dcec20a0405e152da3ab065b6b9277c104769f34c4d02ba40fe3404c9771b324d80bc86055", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/708ab517e7ed31072bfe24ab445cdb9c96442531e346a0485c502d62ee99252840d88338da3d52222c20de9e1e2bcec21411aa588200ae21e42729bef3145d7d"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-23 18:37:36.532563) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 18:37:36.532911) ========= 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.4XwuIxIBNU/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.YLz4vNpzIc/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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.005216 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 18:37:36.582258) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-23 18:37:36.582575) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-23 18:37:36.582927) ========= ========= Total master_lag: 0.20 =========