builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0381 starttime: 1478560990.02 results: success (0) buildid: 20161107135720 builduid: 7b331510ebb94d5eb7dc8e0e3efaddfb revision: 000dc91517d648344729bc8764aee1cca8e91e77 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-07 15:23:10.022081) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-07 15:23:10.022616) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-07 15:23:10.022939) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-07 15:23:10.047119) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-07 15:23:10.047825) ========= 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.5zvp43jpL6/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.bZMi6umtdI/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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-07 15:23:10-- 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% 8.29M=0.001s 2016-11-07 15:23:10 (8.29 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.101404 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-07 15:23:10.177496) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-07 15:23:10.178249) ========= 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.5zvp43jpL6/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.bZMi6umtdI/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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.019686 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-07 15:23:10.229053) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-07 15:23:10.229451) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 000dc91517d648344729bc8764aee1cca8e91e77 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 000dc91517d648344729bc8764aee1cca8e91e77 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5zvp43jpL6/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.bZMi6umtdI/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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-07 15:23:10,315 truncating revision to first 12 chars 2016-11-07 15:23:10,315 Setting DEBUG logging. 2016-11-07 15:23:10,315 attempt 1/10 2016-11-07 15:23:10,315 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/000dc91517d6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-07 15:23:10,993 unpacking tar archive at: mozilla-central-000dc91517d6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.951110 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-07 15:23:11.206711) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-07 15:23:11.207526) ========= script_repo_revision: 000dc91517d648344729bc8764aee1cca8e91e77 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-07 15:23:11.208488) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-07 15:23:11.209196) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-07 15:23:11.228302) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-07 15:23:11.228611) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5zvp43jpL6/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.bZMi6umtdI/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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 15:23:11 INFO - MultiFileLogger online at 20161107 15:23:11 in /builds/slave/test 15:23:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 15:23:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:23:11 INFO - {'append_to_log': False, 15:23:11 INFO - 'base_work_dir': '/builds/slave/test', 15:23:11 INFO - 'blob_upload_branch': 'Firefox', 15:23:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:23:11 INFO - 'branch': 'Firefox', 15:23:11 INFO - 'buildbot_json_path': 'buildprops.json', 15:23:11 INFO - 'config_files': ('talos/mac_config.py',), 15:23:11 INFO - 'default_actions': ('clobber', 15:23:11 INFO - 'read-buildbot-config', 15:23:11 INFO - 'download-and-extract', 15:23:11 INFO - 'populate-webroot', 15:23:11 INFO - 'create-virtualenv', 15:23:11 INFO - 'install', 15:23:11 INFO - 'run-tests'), 15:23:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:23:11 INFO - 'download_minidump_stackwalk': True, 15:23:11 INFO - 'download_symbols': 'ondemand', 15:23:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:23:11 INFO - 'tooltool.py': '/tools/tooltool.py', 15:23:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:23:11 INFO - '/tools/misc-python/virtualenv.py')}, 15:23:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:23:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:23:11 INFO - 'installer_path': 'installer.exe', 15:23:11 INFO - 'log_level': 'info', 15:23:11 INFO - 'log_name': 'talos', 15:23:11 INFO - 'log_to_console': True, 15:23:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:23:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:23:11 INFO - 'opt_config_files': (), 15:23:11 INFO - 'pip_index': False, 15:23:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:23:11 INFO - 'cmd': ('bash', 15:23:11 INFO - '-c', 15:23:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:23:11 INFO - 'enabled': True, 15:23:11 INFO - 'halt_on_failure': False, 15:23:11 INFO - 'name': 'check_screen_resolution'},), 15:23:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:23:11 INFO - 'cmd': ('bash', 15:23:11 INFO - '-c', 15:23:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:23:11 INFO - 'enabled': True, 15:23:11 INFO - 'halt_on_failure': False, 15:23:11 INFO - 'name': 'check_screen_resolution'},), 15:23:11 INFO - 'run_cmd_checks_enabled': True, 15:23:11 INFO - 'sps_profile': False, 15:23:11 INFO - 'sps_profile_interval': 0, 15:23:11 INFO - 'suite': 'dromaeojs', 15:23:11 INFO - 'system_bits': '32', 15:23:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:23:11 INFO - 'title': 't-yosemite-r7-0381', 15:23:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:23:11 INFO - 'use_talos_json': True, 15:23:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:23:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:23:11 INFO - 'work_dir': 'build'} 15:23:11 INFO - [mozharness: 2016-11-07 23:23:11.433231Z] Running clobber step. 15:23:11 INFO - Running pre-action listener: _resource_record_pre_action 15:23:11 INFO - Running main action method: clobber 15:23:11 INFO - rmtree: /builds/slave/test/build 15:23:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:23:12 INFO - Running post-action listener: _resource_record_post_action 15:23:12 INFO - [mozharness: 2016-11-07 23:23:12.905175Z] Finished clobber step (success) 15:23:12 INFO - [mozharness: 2016-11-07 23:23:12.905362Z] Running read-buildbot-config step. 15:23:12 INFO - Running pre-action listener: _resource_record_pre_action 15:23:12 INFO - Running main action method: read_buildbot_config 15:23:12 INFO - Using buildbot properties: 15:23:12 INFO - { 15:23:12 INFO - "project": "", 15:23:12 INFO - "product": "firefox", 15:23:12 INFO - "script_repo_revision": "production", 15:23:12 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 15:23:12 INFO - "repository": "", 15:23:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 15:23:12 INFO - "buildid": "20161107135720", 15:23:12 INFO - "pgo_build": "False", 15:23:12 INFO - "basedir": "/builds/slave/test", 15:23:12 INFO - "buildnumber": 134, 15:23:12 INFO - "slavename": "t-yosemite-r7-0381", 15:23:12 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 15:23:12 INFO - "platform": "yosemite_r7", 15:23:12 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 15:23:12 INFO - "branch": "mozilla-central", 15:23:12 INFO - "repo_path": "mozilla-central", 15:23:12 INFO - "revision": "000dc91517d648344729bc8764aee1cca8e91e77", 15:23:12 INFO - "stage_platform": "macosx64", 15:23:12 INFO - "builduid": "7b331510ebb94d5eb7dc8e0e3efaddfb", 15:23:12 INFO - "slavebuilddir": "test" 15:23:12 INFO - } 15:23:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/RmILIgCATwqPDiG8ouspqA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 15:23:12 INFO - Running post-action listener: _resource_record_post_action 15:23:12 INFO - [mozharness: 2016-11-07 23:23:12.908640Z] Finished read-buildbot-config step (success) 15:23:12 INFO - [mozharness: 2016-11-07 23:23:12.908773Z] Running download-and-extract step. 15:23:12 INFO - Running pre-action listener: _resource_record_pre_action 15:23:12 INFO - Running main action method: download_and_extract 15:23:12 INFO - mkdir: /builds/slave/test/build/tests 15:23:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:23:12 INFO - trying https://queue.taskcluster.net/v1/task/RmILIgCATwqPDiG8ouspqA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 15:23:12 INFO - Downloading https://queue.taskcluster.net/v1/task/RmILIgCATwqPDiG8ouspqA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 15:23:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RmILIgCATwqPDiG8ouspqA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 15:23:13 INFO - Downloaded 1199 bytes. 15:23:13 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 15:23:13 INFO - Using the following test package requirements: 15:23:13 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 15:23:13 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:23:13 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 15:23:13 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:23:13 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 15:23:13 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:23:13 INFO - u'jsshell-mac.zip'], 15:23:13 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:23:13 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 15:23:13 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 15:23:13 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:23:13 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 15:23:13 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:23:13 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 15:23:13 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:23:13 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 15:23:13 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:23:13 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 15:23:13 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:23:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RmILIgCATwqPDiG8ouspqA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 15:23:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RmILIgCATwqPDiG8ouspqA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:23:13 INFO - Fetch https://queue.taskcluster.net/v1/task/RmILIgCATwqPDiG8ouspqA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 15:23:14 INFO - Http code: 200 15:23:14 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 15:23:14 INFO - x-amz-cf-id: ACNTnJ7bWzQXnLNLJ4iBUcL0fyJ0_b6CkX9Mhabqpjv97BENMmXV4g== 15:23:14 INFO - x-amz-version-id: 0ymWOSuJv4jyuuQQz14hsS1hD1AoEvuC 15:23:14 INFO - Expected file size: 16308146 15:23:14 INFO - Obtained file size: 16308146 15:23:21 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:23:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RmILIgCATwqPDiG8ouspqA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 15:23:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RmILIgCATwqPDiG8ouspqA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:23:21 INFO - Fetch https://queue.taskcluster.net/v1/task/RmILIgCATwqPDiG8ouspqA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 15:23:21 INFO - Http code: 200 15:23:21 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 15:23:21 INFO - x-amz-cf-id: f6k3elBJFBbFWRXYR4veWTQhXd9Y9-qpZHhd_1cG-e2fmHwnfi_nzg== 15:23:21 INFO - x-amz-version-id: 0ymWOSuJv4jyuuQQz14hsS1hD1AoEvuC 15:23:22 INFO - Expected file size: 16308146 15:23:22 INFO - Obtained file size: 16308146 15:23:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RmILIgCATwqPDiG8ouspqA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 15:23:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RmILIgCATwqPDiG8ouspqA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:23:28 INFO - Fetch https://queue.taskcluster.net/v1/task/RmILIgCATwqPDiG8ouspqA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 15:23:29 INFO - Http code: 200 15:23:29 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 15:23:29 INFO - x-amz-cf-id: 4MQpiNgIGMiDkCl3pF1tnCCxOGVf5lgcetf51a5tE7-bXTxHAHArjQ== 15:23:29 INFO - x-amz-version-id: J2xTkoDJG4TXGpGlxoyywlvnAbNx8QlN 15:23:29 INFO - Expected file size: 14409038 15:23:29 INFO - Obtained file size: 14409038 15:23:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:23:30 INFO - trying https://queue.taskcluster.net/v1/task/RmILIgCATwqPDiG8ouspqA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:23:30 INFO - Downloading https://queue.taskcluster.net/v1/task/RmILIgCATwqPDiG8ouspqA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 15:23:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RmILIgCATwqPDiG8ouspqA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 15:23:32 INFO - Downloaded 111593039 bytes. 15:23:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RmILIgCATwqPDiG8ouspqA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:23:32 INFO - mkdir: /builds/slave/test/properties 15:23:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:23:32 INFO - Writing to file /builds/slave/test/properties/build_url 15:23:32 INFO - Contents: 15:23:32 INFO - build_url:https://queue.taskcluster.net/v1/task/RmILIgCATwqPDiG8ouspqA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:23:33 INFO - Running post-action listener: _resource_record_post_action 15:23:33 INFO - Running post-action listener: set_extra_try_arguments 15:23:33 INFO - [mozharness: 2016-11-07 23:23:33.778627Z] Finished download-and-extract step (success) 15:23:33 INFO - [mozharness: 2016-11-07 23:23:33.778764Z] Running populate-webroot step. 15:23:33 INFO - Running pre-action listener: _resource_record_pre_action 15:23:33 INFO - Running main action method: populate_webroot 15:23:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:23:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:23:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:23:33 INFO - '--tppagecycles', 15:23:33 INFO - '7'], 15:23:33 INFO - 'tests': ['tsvgm']}}, 15:23:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:23:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:23:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:23:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:23:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:23:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:23:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:23:33 INFO - 'talos_options': ['--disable-e10s'], 15:23:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:23:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:23:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:23:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:23:33 INFO - 'talos_options': ['--disable-e10s'], 15:23:33 INFO - 'tests': ['damp', 'tps']}, 15:23:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:23:33 INFO - 'tests': ['damp', 'tps']}, 15:23:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:23:33 INFO - 'tests': ['dromaeo_dom']}, 15:23:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:23:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:23:33 INFO - 'tests': ['basic_compositor_video']}, 15:23:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:23:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:23:33 INFO - 'tests': ['a11yr', 15:23:33 INFO - 'ts_paint', 15:23:33 INFO - 'tpaint', 15:23:33 INFO - 'sessionrestore', 15:23:33 INFO - 'sessionrestore_no_auto_restore', 15:23:33 INFO - 'tabpaint']}, 15:23:33 INFO - 'other-e10s': {'tests': ['a11yr', 15:23:33 INFO - 'ts_paint', 15:23:33 INFO - 'tpaint', 15:23:33 INFO - 'sessionrestore', 15:23:33 INFO - 'sessionrestore_no_auto_restore', 15:23:33 INFO - 'tabpaint']}, 15:23:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:23:33 INFO - 'ts_paint', 15:23:33 INFO - 'tpaint', 15:23:33 INFO - 'sessionrestore', 15:23:33 INFO - 'sessionrestore_no_auto_restore', 15:23:33 INFO - 'tabpaint']}, 15:23:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:23:33 INFO - 'ts_paint', 15:23:33 INFO - 'tpaint', 15:23:33 INFO - 'sessionrestore', 15:23:33 INFO - 'sessionrestore_no_auto_restore', 15:23:33 INFO - 'tabpaint']}, 15:23:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:23:33 INFO - 'tests': ['a11yr', 15:23:33 INFO - 'ts_paint', 15:23:33 INFO - 'tpaint', 15:23:33 INFO - 'sessionrestore', 15:23:33 INFO - 'sessionrestore_no_auto_restore', 15:23:33 INFO - 'tabpaint']}, 15:23:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:23:33 INFO - 'tests': ['a11yr', 15:23:33 INFO - 'ts_paint', 15:23:33 INFO - 'tpaint', 15:23:33 INFO - 'sessionrestore', 15:23:33 INFO - 'sessionrestore_no_auto_restore', 15:23:33 INFO - 'tabpaint']}, 15:23:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:23:33 INFO - 'tests': ['tsvgx', 15:23:33 INFO - 'tsvgr_opacity', 15:23:33 INFO - 'tart', 15:23:33 INFO - 'tscrollx', 15:23:33 INFO - 'cart']}, 15:23:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:23:33 INFO - 'tsvgr_opacity', 15:23:33 INFO - 'tart', 15:23:33 INFO - 'tscrollx', 15:23:33 INFO - 'cart']}, 15:23:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:23:33 INFO - 'talos_options': ['--disable-e10s'], 15:23:33 INFO - 'tests': ['tp5o']}, 15:23:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:23:33 INFO - 'tests': ['tp5o']}, 15:23:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:23:33 INFO - 'talos_options': ['--disable-e10s', 15:23:33 INFO - '--xperf_path', 15:23:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:23:33 INFO - 'tests': ['tp5n']}, 15:23:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:23:33 INFO - 'talos_options': ['--xperf_path', 15:23:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:23:33 INFO - 'tests': ['tp5n']}}, 15:23:33 INFO - 'talos.zip': {'path': '', 15:23:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:23:33 INFO - Running post-action listener: _resource_record_post_action 15:23:33 INFO - [mozharness: 2016-11-07 23:23:33.797947Z] Finished populate-webroot step (success) 15:23:33 INFO - [mozharness: 2016-11-07 23:23:33.798066Z] Running create-virtualenv step. 15:23:33 INFO - Running pre-action listener: _resource_record_pre_action 15:23:33 INFO - Running main action method: create_virtualenv 15:23:33 INFO - Creating virtualenv /builds/slave/test/build/venv 15:23:33 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 15:23:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:23:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:23:33 INFO - Using real prefix '/tools/python27' 15:23:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:23:34 INFO - Installing distribute.............................................................................................................................................................................................done. 15:23:37 INFO - Installing pip.................done. 15:23:37 INFO - Return code: 0 15:23:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:23:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:23:37 INFO - Reading from file tmpfile_stdout 15:23:37 INFO - Output received: 15:23:37 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) 15:23:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:23:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:23:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:23:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:23:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:23:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d434490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3aa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe192d4fdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d248d30>, '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 0x10d06edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d240b20>, '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.5zvp43jpL6/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.bZMi6umtdI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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 15:23:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:23:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:23:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5zvp43jpL6/Render', 15:23:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:23:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:23:37 INFO - 'HOME': '/Users/cltbld', 15:23:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:23:37 INFO - 'LOGNAME': 'cltbld', 15:23:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:23:37 INFO - 'MOZ_NO_REMOTE': '1', 15:23:37 INFO - 'NO_EM_RESTART': '1', 15:23:37 INFO - 'PAGER': '/bin/cat', 15:23:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:23:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:23:37 INFO - 'PWD': '/builds/slave/test', 15:23:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:23:37 INFO - 'SHELL': '/bin/bash', 15:23:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bZMi6umtdI/Listeners', 15:23:37 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 15:23:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:23:37 INFO - 'USER': 'cltbld', 15:23:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:23:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:23:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:23:37 INFO - 'XPC_FLAGS': '0x0', 15:23:37 INFO - 'XPC_SERVICE_NAME': '0', 15:23:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:23:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:23:38 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 15:23:38 INFO - Cleaning up... 15:23:38 INFO - Return code: 0 15:23:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:23:38 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')]} 15:23:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:23:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:38 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')]} 15:23:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:23:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:38 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 0x10d434490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3aa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe192d4fdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d248d30>, '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 0x10d06edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d240b20>, '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.5zvp43jpL6/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.bZMi6umtdI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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 15:23:38 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 15:23:38 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 15:23:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5zvp43jpL6/Render', 15:23:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:23:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:23:38 INFO - 'HOME': '/Users/cltbld', 15:23:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:23:38 INFO - 'LOGNAME': 'cltbld', 15:23:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:23:38 INFO - 'MOZ_NO_REMOTE': '1', 15:23:38 INFO - 'NO_EM_RESTART': '1', 15:23:38 INFO - 'PAGER': '/bin/cat', 15:23:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:23:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:23:38 INFO - 'PWD': '/builds/slave/test', 15:23:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:23:38 INFO - 'SHELL': '/bin/bash', 15:23:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bZMi6umtdI/Listeners', 15:23:38 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 15:23:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:23:38 INFO - 'USER': 'cltbld', 15:23:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:23:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:23:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:23:38 INFO - 'XPC_FLAGS': '0x0', 15:23:38 INFO - 'XPC_SERVICE_NAME': '0', 15:23:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:23:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:23:38 INFO - Downloading/unpacking psutil>=3.1.1 15:23:38 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 15:23:38 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 15:23:38 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 15:23:38 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 15:23:38 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 15:23:38 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 15:23:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:23:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:23:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:23:41 INFO - Installing collected packages: psutil 15:23:41 INFO - Running setup.py install for psutil 15:23:41 INFO - building 'psutil._psutil_osx' extension 15:23:41 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 15:23:42 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 15:23:42 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 15:23:42 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 15:23:42 INFO - building 'psutil._psutil_posix' extension 15:23:42 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 15:23:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:23:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:23:42 INFO - ^ 15:23:42 INFO - 1 warning generated. 15:23:42 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 15:23:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:23:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:23:42 INFO - Successfully installed psutil 15:23:42 INFO - Cleaning up... 15:23:42 INFO - Return code: 0 15:23:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:23:42 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')]} 15:23:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:23:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:42 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')]} 15:23:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:23:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:42 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 0x10d434490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3aa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe192d4fdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d248d30>, '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 0x10d06edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d240b20>, '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.5zvp43jpL6/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.bZMi6umtdI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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 15:23:42 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 15:23:42 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 15:23:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5zvp43jpL6/Render', 15:23:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:23:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:23:42 INFO - 'HOME': '/Users/cltbld', 15:23:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:23:42 INFO - 'LOGNAME': 'cltbld', 15:23:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:23:42 INFO - 'MOZ_NO_REMOTE': '1', 15:23:42 INFO - 'NO_EM_RESTART': '1', 15:23:42 INFO - 'PAGER': '/bin/cat', 15:23:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:23:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:23:42 INFO - 'PWD': '/builds/slave/test', 15:23:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:23:42 INFO - 'SHELL': '/bin/bash', 15:23:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bZMi6umtdI/Listeners', 15:23:42 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 15:23:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:23:42 INFO - 'USER': 'cltbld', 15:23:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:23:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:23:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:23:42 INFO - 'XPC_FLAGS': '0x0', 15:23:42 INFO - 'XPC_SERVICE_NAME': '0', 15:23:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:23:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:23:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:23:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:23:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:23:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:23:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:23:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:23:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:23:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:23:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:23:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:23:45 INFO - Installing collected packages: mozsystemmonitor 15:23:45 INFO - Running setup.py install for mozsystemmonitor 15:23:45 INFO - Successfully installed mozsystemmonitor 15:23:45 INFO - Cleaning up... 15:23:45 INFO - Return code: 0 15:23:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:23: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')]} 15:23:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:23:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23: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')]} 15:23:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:23:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:45 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 0x10d434490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3aa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe192d4fdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d248d30>, '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 0x10d06edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d240b20>, '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.5zvp43jpL6/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.bZMi6umtdI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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 15:23:45 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 15:23:45 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 15:23:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5zvp43jpL6/Render', 15:23:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:23:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:23:45 INFO - 'HOME': '/Users/cltbld', 15:23:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:23:45 INFO - 'LOGNAME': 'cltbld', 15:23:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:23:45 INFO - 'MOZ_NO_REMOTE': '1', 15:23:45 INFO - 'NO_EM_RESTART': '1', 15:23:45 INFO - 'PAGER': '/bin/cat', 15:23:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:23:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:23:45 INFO - 'PWD': '/builds/slave/test', 15:23:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:23:45 INFO - 'SHELL': '/bin/bash', 15:23:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bZMi6umtdI/Listeners', 15:23:45 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 15:23:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:23:45 INFO - 'USER': 'cltbld', 15:23:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:23:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:23:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:23:45 INFO - 'XPC_FLAGS': '0x0', 15:23:45 INFO - 'XPC_SERVICE_NAME': '0', 15:23:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:23:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:23:46 INFO - Downloading/unpacking jsonschema==2.5.1 15:23:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:23:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:23:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:23:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:23:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:23:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:23:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:23:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:23:48 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 15:23:48 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 15:23:48 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 15:23:48 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 15:23:48 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 15:23:48 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 15:23:49 INFO - Downloading functools32-3.2.3-2.tar.gz 15:23:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:23:49 INFO - warning: no files found matching '*.txt' 15:23:49 INFO - no previously-included directories found matching 'build' 15:23:49 INFO - no previously-included directories found matching 'dist' 15:23:49 INFO - no previously-included directories found matching '.git*' 15:23:49 INFO - Installing collected packages: jsonschema, functools32 15:23:49 INFO - Running setup.py install for functools32 15:23:49 INFO - warning: no files found matching '*.txt' 15:23:49 INFO - no previously-included directories found matching 'build' 15:23:49 INFO - no previously-included directories found matching 'dist' 15:23:49 INFO - no previously-included directories found matching '.git*' 15:23:49 INFO - Successfully installed jsonschema functools32 15:23:49 INFO - Cleaning up... 15:23:49 INFO - Return code: 0 15:23:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:23:49 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')]} 15:23:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:23:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:49 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')]} 15:23:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:23:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:49 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 0x10d434490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3aa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe192d4fdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d248d30>, '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 0x10d06edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d240b20>, '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.5zvp43jpL6/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.bZMi6umtdI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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 15:23:49 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 15:23:49 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 15:23:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5zvp43jpL6/Render', 15:23:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:23:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:23:49 INFO - 'HOME': '/Users/cltbld', 15:23:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:23:49 INFO - 'LOGNAME': 'cltbld', 15:23:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:23:49 INFO - 'MOZ_NO_REMOTE': '1', 15:23:49 INFO - 'NO_EM_RESTART': '1', 15:23:49 INFO - 'PAGER': '/bin/cat', 15:23:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:23:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:23:49 INFO - 'PWD': '/builds/slave/test', 15:23:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:23:49 INFO - 'SHELL': '/bin/bash', 15:23:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bZMi6umtdI/Listeners', 15:23:49 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 15:23:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:23:49 INFO - 'USER': 'cltbld', 15:23:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:23:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:23:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:23:49 INFO - 'XPC_FLAGS': '0x0', 15:23:49 INFO - 'XPC_SERVICE_NAME': '0', 15:23:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:23:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:23:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:23:49 INFO - Cleaning up... 15:23:49 INFO - Return code: 0 15:23:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:23:49 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')]} 15:23:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:23:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:49 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')]} 15:23:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:23:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:49 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 0x10d434490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3aa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe192d4fdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d248d30>, '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 0x10d06edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d240b20>, '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.5zvp43jpL6/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.bZMi6umtdI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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 15:23:49 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 15:23:49 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 15:23:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5zvp43jpL6/Render', 15:23:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:23:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:23:49 INFO - 'HOME': '/Users/cltbld', 15:23:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:23:49 INFO - 'LOGNAME': 'cltbld', 15:23:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:23:49 INFO - 'MOZ_NO_REMOTE': '1', 15:23:49 INFO - 'NO_EM_RESTART': '1', 15:23:49 INFO - 'PAGER': '/bin/cat', 15:23:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:23:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:23:49 INFO - 'PWD': '/builds/slave/test', 15:23:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:23:49 INFO - 'SHELL': '/bin/bash', 15:23:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bZMi6umtdI/Listeners', 15:23:49 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 15:23:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:23:49 INFO - 'USER': 'cltbld', 15:23:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:23:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:23:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:23:49 INFO - 'XPC_FLAGS': '0x0', 15:23:49 INFO - 'XPC_SERVICE_NAME': '0', 15:23:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:23:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:23:49 INFO - Downloading/unpacking blobuploader==1.2.4 15:23:49 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 15:23:49 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 15:23:49 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 15:23:49 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 15:23:49 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 15:23:49 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 15:23:52 INFO - Downloading blobuploader-1.2.4.tar.gz 15:23:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:23:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:23: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 15:23: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 15:23: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 15:23:52 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 15:23: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 15:23: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 15:23:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:23:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:23: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 15:23: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 15:23:53 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 15:23: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 15:23: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 15:23: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 15:23:53 INFO - Downloading docopt-0.6.1.tar.gz 15:23:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:23:53 INFO - Installing collected packages: blobuploader, requests, docopt 15:23:53 INFO - Running setup.py install for blobuploader 15:23:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:23:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:23:53 INFO - Running setup.py install for requests 15:23:54 INFO - Running setup.py install for docopt 15:23:54 INFO - Successfully installed blobuploader requests docopt 15:23:54 INFO - Cleaning up... 15:23:54 INFO - Return code: 0 15:23:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:23:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:23:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:23:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:23:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:23:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:54 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 0x10d434490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3aa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe192d4fdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d248d30>, '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 0x10d06edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d240b20>, '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.5zvp43jpL6/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.bZMi6umtdI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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 15:23:54 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 15:23:54 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 15:23:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5zvp43jpL6/Render', 15:23:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:23:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:23:54 INFO - 'HOME': '/Users/cltbld', 15:23:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:23:54 INFO - 'LOGNAME': 'cltbld', 15:23:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:23:54 INFO - 'MOZ_NO_REMOTE': '1', 15:23:54 INFO - 'NO_EM_RESTART': '1', 15:23:54 INFO - 'PAGER': '/bin/cat', 15:23:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:23:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:23:54 INFO - 'PWD': '/builds/slave/test', 15:23:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:23:54 INFO - 'SHELL': '/bin/bash', 15:23:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bZMi6umtdI/Listeners', 15:23:54 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 15:23:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:23:54 INFO - 'USER': 'cltbld', 15:23:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:23:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:23:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:23:54 INFO - 'XPC_FLAGS': '0x0', 15:23:54 INFO - 'XPC_SERVICE_NAME': '0', 15:23:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:23:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:23:55 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-DUGHNk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:23:55 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-qK8rkB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:23:55 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-N3iq1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:23:55 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-iQ3GDK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:23:55 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-YQEUEp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:23:55 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-7nsed6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:23:55 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-917EPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:23:55 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-LoQVTc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:23:55 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-zADnXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:23:55 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-VpQB6N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:23:56 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-Gh9zsI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:23:56 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-Kcpin9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:23:56 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-WYqJWO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:23:56 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-BqcPLl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:23:56 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-rkSsJR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:23:56 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-60lynf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:23:56 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-8xwLt0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:23:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:23:56 INFO - Running setup.py install for manifestparser 15:23:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:23:56 INFO - Running setup.py install for mozcrash 15:23:57 INFO - Running setup.py install for mozdebug 15:23:57 INFO - Running setup.py install for mozdevice 15:23:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:23:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:23:57 INFO - Running setup.py install for mozfile 15:23:57 INFO - Running setup.py install for mozhttpd 15:23:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:23:57 INFO - Running setup.py install for mozinfo 15:23:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:23:57 INFO - Running setup.py install for mozInstall 15:23:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:23:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:23:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:23:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:23:57 INFO - Running setup.py install for mozleak 15:23:58 INFO - Running setup.py install for mozlog 15:23:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:23:58 INFO - Running setup.py install for moznetwork 15:23:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:23:58 INFO - Running setup.py install for mozprocess 15:23:58 INFO - Running setup.py install for mozprofile 15:23:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:23:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:23:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:23:58 INFO - Running setup.py install for mozrunner 15:23:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:23:58 INFO - Running setup.py install for mozscreenshot 15:23:59 INFO - Running setup.py install for moztest 15:23:59 INFO - Running setup.py install for mozversion 15:23:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:23:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:23:59 INFO - Cleaning up... 15:23:59 INFO - Return code: 0 15:23:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:23:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:23:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:23:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:23:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:23:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:59 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 0x10d434490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3aa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe192d4fdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d248d30>, '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 0x10d06edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d240b20>, '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.5zvp43jpL6/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.bZMi6umtdI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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 15:23:59 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 15:23:59 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 15:23:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5zvp43jpL6/Render', 15:23:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:23:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:23:59 INFO - 'HOME': '/Users/cltbld', 15:23:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:23:59 INFO - 'LOGNAME': 'cltbld', 15:23:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:23:59 INFO - 'MOZ_NO_REMOTE': '1', 15:23:59 INFO - 'NO_EM_RESTART': '1', 15:23:59 INFO - 'PAGER': '/bin/cat', 15:23:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:23:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:23:59 INFO - 'PWD': '/builds/slave/test', 15:23:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:23:59 INFO - 'SHELL': '/bin/bash', 15:23:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bZMi6umtdI/Listeners', 15:23:59 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 15:23:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:23:59 INFO - 'USER': 'cltbld', 15:23:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:23:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:23:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:23:59 INFO - 'XPC_FLAGS': '0x0', 15:23:59 INFO - 'XPC_SERVICE_NAME': '0', 15:23:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:23:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:23:59 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-9mJpAl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:23:59 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)) 15:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:23:59 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-ii7ArD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:23:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 15:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:23:59 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-z7jf8f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:23:59 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)) 15:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:24:00 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-SzvYfj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:24:00 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)) 15:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:24:00 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-lIUnuv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:24:00 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)) 15:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:24:00 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-1gXpQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:24:00 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)) 15:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:24:00 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-42Ten3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:24:00 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)) 15:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:24:00 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-fqswqt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:24:00 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)) 15:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:24:00 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-0tueYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:24:00 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)) 15:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:24:00 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-uZRX57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:24:00 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)) 15:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:24:00 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-4pDfY3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:24:00 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)) 15:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:24:00 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-pQdsd9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:24:00 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)) 15:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:24:00 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-2rJned-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:24:01 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)) 15:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:24:01 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-L3PzLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:24:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 15:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:24:01 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-qq2Oje-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:24:01 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)) 15:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:24:01 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-gm1OVz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:24:01 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)) 15:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:24:01 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-ITyZCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:24:01 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)) 15:24:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:24:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:24:01 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)) 15:24:01 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)) 15:24:01 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)) 15:24:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:24: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 15:24: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 15:24: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 15:24: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 15:24: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 15:24: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 15:24:03 INFO - Downloading blessings-1.6.tar.gz 15:24:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:24:04 INFO - Installing collected packages: blessings 15:24:04 INFO - Running setup.py install for blessings 15:24:04 INFO - Successfully installed blessings 15:24:04 INFO - Cleaning up... 15:24:04 INFO - Return code: 0 15:24:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:24:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:24:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:24:04 INFO - Reading from file tmpfile_stdout 15:24:04 INFO - Current package versions: 15:24:04 INFO - blessings == 1.6 15:24:04 INFO - blobuploader == 1.2.4 15:24:04 INFO - docopt == 0.6.1 15:24:04 INFO - functools32 == 3.2.3-2 15:24:04 INFO - jsonschema == 2.5.1 15:24:04 INFO - manifestparser == 1.1 15:24:04 INFO - mozInstall == 1.12 15:24:04 INFO - mozcrash == 0.17 15:24:04 INFO - mozdebug == 0.1 15:24:04 INFO - mozdevice == 0.48 15:24:04 INFO - mozfile == 1.2 15:24:04 INFO - mozhttpd == 0.7 15:24:04 INFO - mozinfo == 0.9 15:24:04 INFO - mozleak == 0.1 15:24:04 INFO - mozlog == 3.4 15:24:04 INFO - moznetwork == 0.27 15:24:04 INFO - mozprocess == 0.23 15:24:04 INFO - mozprofile == 0.28 15:24:04 INFO - mozrunner == 6.12 15:24:04 INFO - mozscreenshot == 0.1 15:24:04 INFO - mozsystemmonitor == 0.3 15:24:04 INFO - moztest == 0.7 15:24:04 INFO - mozversion == 1.4 15:24:04 INFO - psutil == 3.1.1 15:24:04 INFO - requests == 1.2.3 15:24:04 INFO - wsgiref == 0.1.2 15:24:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:24: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')]} 15:24:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24: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')]} 15:24:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:04 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 0x10d434490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3aa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe192d4fdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d248d30>, '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 0x10d06edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d240b20>, '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.5zvp43jpL6/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.bZMi6umtdI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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 15:24:04 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 15:24:04 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 15:24:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5zvp43jpL6/Render', 15:24:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:04 INFO - 'HOME': '/Users/cltbld', 15:24:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:04 INFO - 'LOGNAME': 'cltbld', 15:24:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:04 INFO - 'MOZ_NO_REMOTE': '1', 15:24:04 INFO - 'NO_EM_RESTART': '1', 15:24:04 INFO - 'PAGER': '/bin/cat', 15:24:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:04 INFO - 'PWD': '/builds/slave/test', 15:24:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:04 INFO - 'SHELL': '/bin/bash', 15:24:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bZMi6umtdI/Listeners', 15:24:04 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 15:24:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:04 INFO - 'USER': 'cltbld', 15:24:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:04 INFO - 'XPC_FLAGS': '0x0', 15:24:04 INFO - 'XPC_SERVICE_NAME': '0', 15:24:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:04 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)) 15:24:04 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)) 15:24:04 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)) 15:24:04 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)) 15:24:04 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)) 15:24:04 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)) 15:24:04 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)) 15:24:04 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)) 15:24:04 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)) 15:24:04 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)) 15:24:04 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)) 15:24:04 INFO - Cleaning up... 15:24:04 INFO - Return code: 0 15:24:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:24: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')]} 15:24:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24: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')]} 15:24:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d434490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3aa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe192d4fdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d248d30>, '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 0x10d06edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d240b20>, '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.5zvp43jpL6/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.bZMi6umtdI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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 15:24: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', 'jsonschema'] in /builds/slave/test/build 15:24: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 jsonschema 15:24:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5zvp43jpL6/Render', 15:24:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:04 INFO - 'HOME': '/Users/cltbld', 15:24:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:04 INFO - 'LOGNAME': 'cltbld', 15:24:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:04 INFO - 'MOZ_NO_REMOTE': '1', 15:24:04 INFO - 'NO_EM_RESTART': '1', 15:24:04 INFO - 'PAGER': '/bin/cat', 15:24:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:04 INFO - 'PWD': '/builds/slave/test', 15:24:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:04 INFO - 'SHELL': '/bin/bash', 15:24:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bZMi6umtdI/Listeners', 15:24:04 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 15:24:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:04 INFO - 'USER': 'cltbld', 15:24:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:04 INFO - 'XPC_FLAGS': '0x0', 15:24:04 INFO - 'XPC_SERVICE_NAME': '0', 15:24:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:24:05 INFO - Cleaning up... 15:24:05 INFO - Return code: 0 15:24:05 INFO - Running post-action listener: _resource_record_post_action 15:24:05 INFO - Running post-action listener: _start_resource_monitoring 15:24:05 INFO - Starting resource monitoring. 15:24:05 INFO - [mozharness: 2016-11-07 23:24:05.190639Z] Finished create-virtualenv step (success) 15:24:05 INFO - [mozharness: 2016-11-07 23:24:05.191681Z] Running install step. 15:24:05 INFO - Running pre-action listener: _resource_record_pre_action 15:24:05 INFO - Running main action method: install 15:24:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:24:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:24:05 INFO - Reading from file tmpfile_stdout 15:24:05 INFO - Detecting whether we're running mozinstall >=1.0... 15:24:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:24:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:24:05 INFO - Reading from file tmpfile_stdout 15:24:05 INFO - Output received: 15:24:05 INFO - Usage: mozinstall [options] installer 15:24:05 INFO - Options: 15:24:05 INFO - -h, --help show this help message and exit 15:24:05 INFO - -d DEST, --destination=DEST 15:24:05 INFO - Directory to install application into. [default: 15:24:05 INFO - "/builds/slave/test"] 15:24:05 INFO - --app=APP Application being installed. [default: firefox] 15:24:05 INFO - mkdir: /builds/slave/test/build/application 15:24:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:24:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:24:39 INFO - Reading from file tmpfile_stdout 15:24:39 INFO - Output received: 15:24:39 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:24:39 INFO - Running post-action listener: _resource_record_post_action 15:24:39 INFO - [mozharness: 2016-11-07 23:24:39.310834Z] Finished install step (success) 15:24:39 INFO - [mozharness: 2016-11-07 23:24:39.310966Z] Running run-tests step. 15:24:39 INFO - Running pre-action listener: _resource_record_pre_action 15:24:39 INFO - Running main action method: run_tests 15:24:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:24:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:24:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:24:39 INFO - 2016-11-07 15:24:39.325 screenresolution[1943:17937] starting screenresolution argv=screenresolution get 15:24:39 INFO - 2016-11-07 15:24:39.328 screenresolution[1943:17937] Display 0: 1600x1200x32@60 15:24:39 INFO - 2016-11-07 15:24:39.334 screenresolution[1944:17940] starting screenresolution argv=screenresolution list 15:24:39 INFO - Available Modes on Display 0 15:24:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:24:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:24:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:24:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:24:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:24:39 INFO - Intel Iris: 15:24:39 INFO - Chipset Model: Intel Iris 15:24:39 INFO - Type: GPU 15:24:39 INFO - Bus: Built-In 15:24:39 INFO - VRAM (Dynamic, Max): 1536 MB 15:24:39 INFO - Vendor: Intel (0x8086) 15:24:39 INFO - Device ID: 0x0a2e 15:24:39 INFO - Revision ID: 0x0009 15:24:39 INFO - Displays: 15:24:39 INFO - Display: 15:24:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:24:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:24:39 INFO - Display Serial Number: 1600x1200 60 15:24:39 INFO - Main Display: Yes 15:24:39 INFO - Mirror: Off 15:24:39 INFO - Online: Yes 15:24:39 INFO - Rotation: Supported 15:24:39 INFO - Return code: 0 15:24:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:24:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:24:39 INFO - Python 2.7.3 15:24:39 INFO - Return code: 0 15:24:39 INFO - grabbing minidump binary from tooltool 15:24:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:39 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 0x10d248d30>, '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 0x10d06edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d240b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:24:39 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 15:24:39 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 15:24:39 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 15:24:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:24:39 INFO - Return code: 0 15:24:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:24:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:24:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:24:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:24:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:24:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RmILIgCATwqPDiG8ouspqA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0381', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:24:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RmILIgCATwqPDiG8ouspqA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0381 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:24:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5zvp43jpL6/Render', 15:24:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:39 INFO - 'HOME': '/Users/cltbld', 15:24:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:39 INFO - 'LOGNAME': 'cltbld', 15:24:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:24:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:24:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:39 INFO - 'MOZ_NO_REMOTE': '1', 15:24:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:24:39 INFO - 'NO_EM_RESTART': '1', 15:24:39 INFO - 'PAGER': '/bin/cat', 15:24:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:39 INFO - 'PWD': '/builds/slave/test', 15:24:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:24:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:39 INFO - 'SHELL': '/bin/bash', 15:24:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bZMi6umtdI/Listeners', 15:24:39 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 15:24:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:39 INFO - 'USER': 'cltbld', 15:24:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:39 INFO - 'XPC_FLAGS': '0x0', 15:24:39 INFO - 'XPC_SERVICE_NAME': '0', 15:24:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RmILIgCATwqPDiG8ouspqA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0381', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:24:39 INFO - mozversion application_buildid: 20161107135720 15:24:39 INFO - mozversion application_changeset: 000dc91517d648344729bc8764aee1cca8e91e77 15:24:39 INFO - mozversion application_display_name: Nightly 15:24:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:24:39 INFO - mozversion application_name: Firefox 15:24:39 INFO - mozversion application_remotingname: firefox 15:24:39 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:24:39 INFO - mozversion application_vendor: Mozilla 15:24:39 INFO - mozversion application_version: 52.0a1 15:24:39 INFO - mozversion platform_buildid: 20161107135720 15:24:39 INFO - mozversion platform_changeset: 000dc91517d648344729bc8764aee1cca8e91e77 15:24:39 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:24:39 INFO - mozversion platform_version: 52.0a1 15:24:39 INFO - using testdate: 1478561079 15:24:39 INFO - actual date: 1478561079 15:24:39 INFO - starting webserver on 'localhost:54489' 15:24:39 INFO - SUITE-START | Running 2 tests 15:24:39 INFO - TEST-START | dromaeo_css 15:24:39 INFO - operating with platform_type : mac_ 15:24:39 INFO - Initialising browser for dromaeo_css test... 15:24:39 INFO - TEST-INFO | started process 1954 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/tmp8EBKvg/profile http://localhost:54489/getInfo.html) 15:24:42 INFO - PROCESS | 1954 | __metrics Screen width/height:1600/1200 15:24:42 INFO - PROCESS | 1954 | colorDepth:24 15:24:42 INFO - PROCESS | 1954 | Browser inner width/height: 1024/689 15:24:42 INFO - PROCESS | 1954 | __metrics 15:24:42 INFO - PROCESS | 1954 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:24:42 INFO - PROCESS | 1954 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:24:43 INFO - TEST-INFO | 1954: exit 0 15:24:43 INFO - Browser initialized. 15:24:43 INFO - Running cycle 1/1 for dromaeo_css test... 15:24:43 INFO - TEST-INFO | started process 1956 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/tmp8EBKvg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:24:55 INFO - PROCESS | 1956 | RSS: Main: 239345664 15:24:55 INFO - PROCESS | 1956 | 15:25:46 INFO - PROCESS | 1956 | 1478561146993 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] 15:28:00 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54489/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54489/tests/dromaeo/cssquery-ext.html) 15:28:02 INFO - PROCESS | 1956 | RSS: Main: 371949568 15:28:02 INFO - PROCESS | 1956 | 15:31:04 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54489/tests/dromaeo/cssquery-ext.html (next: http://localhost:54489/tests/dromaeo/cssquery-jquery.html) 15:31:05 INFO - PROCESS | 1956 | RSS: Main: 382902272 15:31:05 INFO - PROCESS | 1956 | 15:34:07 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54489/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54489/tests/dromaeo/cssquery-mootools.html) 15:34:08 INFO - PROCESS | 1956 | RSS: Main: 362815488 15:34:08 INFO - PROCESS | 1956 | 15:37:10 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54489/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54489/tests/dromaeo/cssquery-prototype.html) 15:37:11 INFO - PROCESS | 1956 | RSS: Main: 338362368 15:37:11 INFO - PROCESS | 1956 | 15:40:14 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54489/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54489/tests/dromaeo/cssquery-yui.html) 15:40:15 INFO - PROCESS | 1956 | RSS: Main: 334536704 15:40:15 INFO - PROCESS | 1956 | 15:43:18 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54489/tests/dromaeo/cssquery-yui.html (next: http://localhost:54489/tests/dromaeo/cssquery-dojo.html) 15:43:19 INFO - PROCESS | 1956 | RSS: Main: 388595712 15:43:19 INFO - PROCESS | 1956 | 15:43:19 INFO - PROCESS | 1956 | __start_tp_report 15:43:19 INFO - PROCESS | 1956 | _x_x_mozilla_page_load 15:43:19 INFO - PROCESS | 1956 | _x_x_mozilla_page_load_details 15:43:19 INFO - PROCESS | 1956 | |i|pagename|runs| 15:43:19 INFO - PROCESS | 1956 | |0;dojo.html;3301.059198128534;3303.339332133573;3305.9338813223735;3327.2014716468057;3372.679595438434;5548.833534993946;5602.327720673515;5620.887582248352;5682.17608446775;5711.771529138835;5623.943760562393;5654.406287339832;5682.51698605618;5759.193712880189;5783.5373170146395;5086.491350864921;5119.616028797836;5149.304843846089;5190.844274671766;5199.974000129994;5252.606054545924;5282.947170528303;5284.2866213061325;5288.418273989877;5326.7070311132875;5117.02776472469;5133.024725302181;5271.156614941591;5283.656562323476;5321.627486075976;5163.819266325698;5169.017886601534;5174.301469301671;5175.301334319856;5190.429052804188;5093.312402825645;5151.023869503382;5155.381354237478;5157.4584668610005;5160.406553246387;5579.804706835282;5594.524465420444;5608.214849921014;5616.550675945915;5634.4365563443735;6073.51411887048;6077.3314935357075;6083.270007599072;6097.359777223416;6109.969450152721;5235.816746413857;6175.197224360849;6175.907361389583;6189.071639254103;6263.279722831841;5764.193012978187;5775.942240577624;5780.450857168562;5787.681677507735;5805.274340707454;6306.369363063749;6311.842203944938;6312.495000399957;6319.431251187415;6324.051392291101;5880.7353669085;5910.261217347832;5931.792387266425;5942.732577034044;5952.940470595238;6087.939120608826;6109.755609775648;6117.357677443893;6126.448619624255;6127.938720612826;6024.186734790747;6032.3967603239325;6053.667048312297;6056.152138700585;6057.061155520902;6050.213472248667;6053.910296146648;6066.545009124334;6069;6094.45149936499;4669;4823.27650852368;4829.492903245108;4873;4898.191798353303;4271.209826182167;4290.570942905747;4292.720973136798;4307.3754305626135;4350.695451318377;5597.916031259534;5686.2039314496005;5697.800576979786;5698.971505142447;5720.42795720433;6108.511319094462;6130.367562715059;6146.985747351726;6189.721462534197;6261.5616906816995;5563.721813909288;5731.684757338387;5757.453041961007;5770.711464426762;5794.985877471426;5363.758630861621;5371.221172929903;5379.058664733656;5387.81142660005;5390.326209223847;6225.937740622536;6282.371762823681;6301.8109456716375;6301.842453938597;6325.525585581192;5197.740112994411;6188.4739797117745;6190.133381326708;6207;6320.936790632219;6263.342349053466;6287.528435367274;6288.371162883675;6289.371062893674;6290.811275661737;6085.300190478151;6171.290301615192;6182.783602574068;6183.319834818257;6200.782972595937;4613.723176609415;6122.78570250057;6131.529590167291;6137.407629318989;6184.81445556634;6064.696765161812;6074.088886667036;6081.817545473643;6083.330833608388;6099.207103076572;6018.157457955977;6044.335123136363;6049.304330002106;6052.061930400795;6072.392760723892;5757.251557297524;5781.971090144522;5788.900108979449;5834.766609335579;5837.153612726046;5955.434233747787;6006.219191505251;6019.849503762441;6043.909341359795;6087.178230938766;5904.645721256738;5905.822825315247;5914.852128696645;5915.40845915405;5948.286205655176 15:43:19 INFO - PROCESS | 1956 | |1;ext.html;512.8128233194884;514.4598171919483;519.3559985617833;527.4646234072417;544.4936209325326;38211.617883821156;39700.60299397005;39707.205855882865;39711.418757780724;41550.37674434878;40667.796661016655;40882;40953.795231023876;40978.770636880865;41009.97475063125;9343.2525397968;9479;9498.28762842786;9506.239500839916;9517.428954262767;8109.95945020274;9369.48467834269;9394;9499.572519236652;9519.238460923145;9491.95254023729;9499;9535.570899309549;9556.283278754086;9564.617415303379;9415.152636262736;9492.905070949271;9499.667511637059;9499.810003799885;9504.192143667831;9353.953230233874;9443.527823608792;9444.102810233086;9454.385464944757;9466.621335146587;38775.61224387777;38977;39029.829105126606;39049;39059.60940390588;3321.4519604265265;3452.499387588811;3475.270193259393;3817.7709337439564;4017.3773065174946;24152.516949661083;28991.710082899113;29709.554356684668;30042.79828806845;30057;3129.4210571044428;3169.1126484584124;3186.2512309607223;3234.094453552985;3236.7086962173516;427684;428023;428971;430488.99999999686;434549;54102;55686.44313556812;55914.161287579904;56381.43618563762;56450.04849660567;55924;57440;57534.7123264381;57735.71132144312;57897.710511448015;15194.164320962233;15431.691366172614;15442.382304707911;15454.459093931884;15463.845361546466;11823.88176118245;11903.285802851857;11905.880941190651;11908.166428350105;11917.582884598998;12345.93827030859;15678.921605392128;15727.135007574465;15866.127362994916;15910.602234943988;5985.401459854067;6146.938530614726;6196.81409557714;6214.782482613087;6239.656818875005;845.9873101903477;919.2737737187681;994.5325696922434;1007.1489591295343;1029.341221618165;29923.650444460032;30849.845750771103;30945.226369340497;31220.219494512818;31880.84059579687;800.4956877167464;954.236610711428;988.515627342597;1019.6431249062769;1054.947252637365;14008.369623366973;14205.85794142066;14754.852451475563;14908.403663853325;15196.848031519765;581396;602904;631139;635641;636016;53507.92984140373;53539;53539.73230133902;53548.464515354346;53571.928561428555;1148.7530181011123;1227.6501197158766;1229.6802831263935;1232.1313474000988;1315.3620494060565;26064.73935260699;26414;29582.852085739432;29700;30526.542101868934;46889.53110468852;47768;47869.76065119652;48052.75973620109;48150.277745834544;498249;500009;500380;502338;503506;24869.62695559532;25160.245192644248;25847.741522584533;26639.600405993537;26884.59673104866;44601.776991114835;44825.55174448344;45116;45609.54390456053;46076;36894;37181.81409092937;37346.036951132075;37832;38787.6121238784;36875.446868297535;36999.81500092482;37089.814550927076;38180.427293590066;38183.809080954416;37019.07452313714;37404.43893341657;37758.86723398302;37801.62198378091;37818.81090594529;27615.585766213924;31607.313378362178;32973.01080967575;33704.1573960653;34027.3194536103;22816.31551053471;24052.037918483064;24849.378765531008;25147.880340261796;25413.745862541877 15:43:19 INFO - PROCESS | 1956 | |2;jquery.html;313.346672188487;346.45087536255033;359.4984995930675;370.49242537723313;398.81455123367647;14414.882456964151;14598.343074561644;15818.446354377586;16142.435014774484;19751;18712.812871871276;19247.51881202967;20030.89830059346;20217.191312347524;20334.491637709027;5609.1305847593585;5973.283206015284;5979.342272350038;5994.730237139339;5999.820005399845;4081.1837632473475;5645.576581756364;5791.768329266824;5839.503642190419;5928.644281343133;4307.418498502709;4337.089211265638;6116.327204007555;6421.614703117804;6441.227052753676;6271.905921411182;6405.455536279422;6430.896980196571;6473.8705225895455;6489.480841532666;5600.859978500529;5608.383077861432;5620;5627.915581266284;5634.521065709419;23578.882105589535;24414.14550490742;24457.755422445905;24464;24753.62869556958;2207;2256.356938272601;2328.1502251678016;2366.0890557135453;2371.67982322386;14605.488807891672;15225.467108651144;15445.845541544553;15731.14900145998;16009.439669611507;1815.4462888818905;1842.97235541467;1855.1280897977929;1859.4979355574055;1866.188208129468;103325;104309.47845260725;104538.47730761298;106775.46612266966;120393.39803301015;7389.408847292204;7988.920110798934;9750.951245243728;9874.555644995993;10110.342827716175;12029;12361;12389.814152787716;12446.564370246997;12454.875451245553;7605.885911711329;7619.847603047908;7763.262490063435;7819.452638315377;8022.799430014296;8108.635111420001;8108.797280068045;8110.674623404376;8280.668773249123;8311.6675332986;3841.3085644584244;3861.575226725058;3870;3909.843606255718;3944.171723938005;1704.0201883916798;1831.2949514437037;1925.2491528303972;1942.0093056692535;1944.2417457191707;678.9898151527731;739.9556026638311;878.2490970220497;897.237348253992;936.9927328122296;16777.496675099766;19125.61748765045;19840.50398740043;20988.68516972216;21301.36095917125;882.9426087304305;892.7366426904078;919.2829592917535;926.1664501948165;947.9004704505927;9026.548672566345;10038.347507411994;10803.459827008619;10812.621558245424;10827.458627068616;569382;569561;575693;580618;580658;117366;125318;130431.34784326208;133398.6317320229;142742;828.5401602110886;936.7517607833929;999.3204620857887;1000.0549480740631;1000.2698030437821;18192.545186370447;18248.087595620433;18272.451826445227;18274.441578179332;18348.81651183471;50966.490335096176;51731.741341293055;52788.736056319474;54702;55668.491786958686;79407.61777146695;80143.59928200321;82185.03497267245;83429;83477.58261208655;7255.709771609077;7738.690452382067;7823.90465334865;8039.799005024921;8254.339652827879;11302.604408845651;12564.308963007123;12635.620931372074;13043.608691739262;13078.411471483616;10927.672169834917;11152.49813758399;11378.772424551296;11410.429478525875;11463.31220126795;10797.92020797914;10882.61910833117;10986;11089.889101108885;11226.382548959886;10800.405977671304;11202.327860328407;11375.374354410256;11476.876777796135;11560.248583842023;8053.798655033671;8074.698710867375;8266.338692904674;8462.280706139802;8501.319894408556;10901.890981090088;11146.944265278622;11358.398335415868;11505.482253298454;11597.6520704379 15:43:19 INFO - PROCESS | 1956 | |3;mootools.html;1340.5174137310569;1455.9272036398177;1599.9920000399995;1748.886322389044;1833.4499650104965;7879.054513458386;8250.174982501747;8262.091169971298;8272.83454330913;8278.834423311539;7281.927180728191;7523.962380188105;7828.334591559725;8051.758447246577;8061.677532898677;2254.9887250563725;2293.827962902789;2295.5867943770113;2298.655201719747;2301.102569997702;2290.5876942150408;2323.372689373868;2325.5697695926224;2327.592671282527;2338.473843385233;2274.8180145588312;2278.065992942894;2304.7119110111234;2305.9077636894503;2306.7808558186944;2249.853759505627;2263.230501629454;2276.3057267533477;2276.3967548599635;2279.327598358488;2272.6022945984387;2297.7127859017623;2297.988510057439;2301.3786277704926;2302.26327575176;7071.717131314793;8112.6349314280405;8177.264046235868;8209.220124088202;8219.383546234056;4971.27916452116;5144.61415393847;5154.845354639329;5158.123119069767;5163.483651634844;3179.1734149121394;3255.202475393544;3517.26137511123;3535.045537704805;3535.9292814143573;1038.2368958815264;1042.671558459083;1054.7046826888484;1070.3203465799274;1083.6044843632096;10498.475076246157;10616;10660.893391066067;10671.679849604447;10687.305325153919;4378.343248512749;4497.617702495325;4580.610648094949;4600.148972440109;4655.02244528646;9206.723798286062;9210.263178945668;9212.769680757901;9218.354715169873;9294.535273236446;5144.382674079135;5150.690958542499;5157.8452646420665;5161.561267292247;5165.690058596496;4221.894452638709;4383.254846676073;4454.643628509712;4674.205385084544;4734.337192792943;3641.8725344612812;3676.613955534684;3703.111253299243;3795.449659799314;3797.35444974355;1223.4005337384617;1229.9016078713682;1230.0712961713848;1239.8574163971077;1247.500999600149;830.9418340716092;841.9536925469035;882.5851849630664;888.7022847346083;933.4539294512786;5467.972660136674;5574.303212098488;5760.3951585083805;5848.473637372657;6012.098185272157;907.3512438606429;919.5126582911067;921.4977837078674;939.281449690987;974.936629119105;3366.7979921204806;3673.3755261605593;3751.7431660394072;3848.249591329664;3860.4926657607616;9517.333786635008;9739.172170365599;9846.212303015742;9882.604695812232;9910.653127140515;9660.17888479473;9706.611735530641;9844.901550984541;9859.704208873745;9864.964178761127;7542.509736867086;7672.616369181518;7675.961620191975;7701.191374905667;7718.343940764986;5265.262863199232;5489.176623506346;5580.38615752275;5622.156676498558;5639.069553523786;9120.817583648422;9187.265018798615;9221.446713197229;9279.257659387367;9402.81194376095;9224.584893679934;9348.766280842761;9392;9472.621095156117;9492.667756628485;6237.4698150657705;6440.420362167428;6520.18497687789;6615.966920165369;6766.15423072116;7773.222677732181;7891.487053341514;7918.920810791818;7919;7922.801929951566;6155.815325540241;6983.336583024605;7036.331548502884;7072.25741297177;7216.494845360774;6913.170419549686;6913.688884000131;6917.204521480095;6947.340002699735;6972.9302706972285;6416.262129855127;6537.771178008747;6614.272430032789;6619.536632435876;6638.203415589967;4782.282657601388;4820.638452116035;4831.855044348676;4871.196252618278;4960.677555958995;4789.233722604366;4873;4888.095702295087;4942.8022879085875;4955.35580374549 15:43:19 INFO - PROCESS | 1956 | |4;prototype.html;253.35900172563413;284.78783306436696;290.2511520277682;306.1489060412054;315.4369450530803;5026.346574945252;5036.67261627994;5068.214426763849;5078.568321692651;5099.668521546097;4302.419173411597;4436.800343984521;4827.51724827518;4858.587020103296;4897.632677549189;3372.6458721834224;3617.728670349721;3812.8856134316015;3823.7896915669626;3830.8084595770238;3689.1883785567134;3745.3632882410056;3960.4455376247347;3962.445257663929;4007.2786898358286;2863.5418333066714;3244.2376041630314;3378.3581119587325;3414.0440676610465;3561.269939662376;3879.3405121129476;3935.2488142776474;3940.699569142205;3997.780122093284;3998.5812130628183;3402.0474267205223;3425.6403077676982;3433.3133373325186;3449.87925422609;3466.4107101792756;4088.5707000764787;4910.582600478928;4913.852584422437;4928.802847886116;4938.777755001033;4289.605878089602;4376.321670141133;4445.310976798602;4487.640988720927;4526.6831321807485;5245.580353571743;5245.921311180336;5309.33633295838;5329.573634109262;5348.973255133699;3217.6460589335156;3293.9788665513765;3798.981005094957;3819.7722924129343;3832.7125465589916;4631.675782695179;5230.97384513075;5444.265024221719;5508.6970216638065;5523.183176579933;3088.98455507721;3404.0343000404746;3449.0515108345407;3604.6395360463744;3819.3385877143533;3702.5742039665242;3824.808759562011;3872.3861393559237;3885.0768869409258;3888.863889763845;3476.860925562949;4417.204903117406;4458.331250312414;4471.418715567021;4514.751688657156;4522.479914809774;5240.68555886648;5283.867903302448;5316.813911513079;5324.334458192726;1122.7144919067784;1223.8959688426405;1231.4520038582746;1244.1772802843877;1245.682351000489;783.7922950418143;789.6880732110784;823.8887750153773;831.2892476932257;841.8947631546057;4088.345864661699;4594;4610.654200934876;4637.86086417408;4642.419697537808;4502.887427814266;4688.546550569266;4774.4509381420885;5001.7499125043605;5019.84940451787;3930.6462418382484;4304.375865499485;4430.490493593214;4496.955030449653;4582.564656357641;2342.9531409371716;2632.210336898961;2634.9604755928676;2636.670416197975;2654.6150808133098;4461.821527138943;4748.738819364899;5035.697858128451;5100.1074811907765;5209.557187639018;4675.929861052087;4850.902981940341;4933.531314525186;4952.9291839060015;5758.395368486333;5736.791335335843;5741.799037033684;5845.386234445407;5849.356570777211;5857.857717745084;5059.747012649279;5194.636375453682;5494.945050549443;5528.778848846001;5619.662820230799;4625.011245164612;5181.585473162063;5468;5512.2558454608115;5545.833624991095;4526.8682829292675;4840.491748366371;5747.224124743107;5762.913556296743;5771.134329850556;3697.4823524706026;3731.003912906582;3731.160488889978;3734.981325093466;3758.3235017697075;4643.933449615024;4816.060868130681;5478.780848766164;5507.228987941771;5662.377138514842;4898.387701537308;5061.544460998381;5167.630577896785;5182.870428239325;5389.51494365509;4111.671066314748;5125.846224613268;5153.45888681683;5414.891702166014;5443.945560544344;4602.125596136723;4802.571988812186;5189.4032186297545;5203.245529398292;5297.9801513137145;4727.149113159527;5011.9498805012945;5043.823466178666;5358.544523715372;5467.8833065152485;5306.602004849575;5315.9468405317;5401.405845357086;5406.648567843077;5414.918776218438 15:43:19 INFO - PROCESS | 1956 | |5;yui.html;674.5885010143814;683.4498228925714;739.5821360931077;764.2968469008512;775.7168633448791;13755.449782008707;13977.30113494325;13984.860151398483;13988.510402135928;14310.856891431082;12706.17409868358;12847.48610055599;12869.09916305859;14497.782533262009;15144.469943551978;820.2699597358355;840.5965136734371;842.1620487614802;844.2233145506149;844.8437039147777;832.0680837462021;840.1052878684212;841.2092632925062;841.6591280531395;841.9789505262356;890.2699786175339;893.0399820193286;893.5800173918248;894.6689724801832;896.0636135238659;881.6914080071988;882.0297672560197;883.355150739957;884.8053428245807;884.9513276769775;651.4983462733716;652.0610321137594;653.1639501438119;654.2835595023441;655.6525041727892;14393.208373539452;14413.567592972122;14422.567322980327;14440.277986100758;14465.421383144661;10587.94120587935;12909.483620655163;13341.799873001914;13454.32728363578;13525.932370338085;9765.316427850054;11389.259698119597;11495.712607184887;11523.48144333507;11644.41777911101;3581.4448760442174;3613.5663720353464;3620.6560376764164;3627.3108109459117;3651.6896063834606;89848.55075724646;90013.64979525378;90388.09611903928;91575.54212228963;91851;10086.899131008742;10270.539088919892;10285.918548711446;10320.380777153396;10368.931071419705;20033.499162520762;20082.89958550198;20096.59806803856;20098;20110.296139635335;6146.815595532141;7180.497365184491;7452.180260171377;7624.19945905683;7669.501482403626;3500.6149323574386;4197.349410841324;4297.656187504992;4375.1031038637;4456.955430445719;3233.886813961547;3356.8825091121694;3358.714509266691;3362.2434952135573;3362.8654853805574;611.3977731934042;622.6264241455182;634.8698516804044;635.5043066408117;636.5639536917226;609.6037575575912;629.4366541944934;649.2630863969334;664.6408095444827;671.8325769436618;8744.431611945203;8749.650013999497;8867.73396798097;8890.511021893857;9077.636894524278;517.5962749055742;554.8945700316926;594.9315828679671;618.2673531864755;651.1242379000237;3995.380715989026;4379.321205213197;4406.294992801137;4444.2222611042935;4517.864464066083;90320;94168.05831941593;96010.03989960151;98107.50946245223;113886.43056784663;62953.68523157355;62984;63709.04436433457;67532;80130.19869801344;75494;77968;81156.37687246222;83841.16158838455;87571.56214219143;8248.793780155544;8446.944131983502;8658.220760131619;9512.1439070484;9551.952240238754;49294.50108308966;50403.49596503988;52564;54000.45999539954;54315;70530;72856.63571682108;73180;73802;76665;11799.469023893773;12078;12110.333931633844;12149.392530373269;12203;13641;13725.313734313444;14025.158490490603;14025.859741402863;14073.437062517385;12459.688507787016;12718.491260349483;13143.079984401;13327.533536326182;13336;10702.304350217211;12544.184627999151;12698.746025079261;12962.416691248727;13159.013074019296;12496.812547811609;12580.748385032432;12611.991040716537;12809;13222.669433264246;9153.908460915305;10388;10721.678349649523;11079.224454288122;11250.606228781953;10553.472326383804;10962.123030157727;11086.501107450345;11091.44542772842;11134.443277835913 15:43:19 INFO - PROCESS | 1956 | __end_tp_report 15:43:19 INFO - PROCESS | 1956 | __start_cc_report 15:43:19 INFO - PROCESS | 1956 | _x_x_mozilla_cycle_collect,1727 15:43:19 INFO - PROCESS | 1956 | __end_cc_report 15:43:19 INFO - PROCESS | 1956 | __startTimestamp1478562199101__endTimestamp 15:43:19 INFO - PROCESS | 1956 | 15:43:19 INFO - PROCESS | 1956 | ------- Summary: start ------- 15:43:19 INFO - PROCESS | 1956 | Number of tests: 6 15:43:19 INFO - PROCESS | 1956 | 15:43:19 INFO - PROCESS | 1956 | [#0] dojo.html Cycles:165 Average:5670.28 Median:5837.15 stddev:636.17 (10.9%) stddev-sans-first:610.37 15:43:19 INFO - PROCESS | 1956 | Values: 3301.1 3303.3 3305.9 3327.2 3372.7 5548.8 5602.3 5620.9 5682.2 5711.8 5623.9 5654.4 5682.5 5759.2 5783.5 5086.5 5119.6 5149.3 5190.8 5200.0 5252.6 5282.9 5284.3 5288.4 5326.7 5117.0 5133.0 5271.2 5283.7 5321.6 5163.8 5169.0 5174.3 5175.3 5190.4 5093.3 5151.0 5155.4 5157.5 5160.4 5579.8 5594.5 5608.2 5616.6 5634.4 6073.5 6077.3 6083.3 6097.4 6110.0 5235.8 6175.2 6175.9 6189.1 6263.3 5764.2 5775.9 5780.5 5787.7 5805.3 6306.4 6311.8 6312.5 6319.4 6324.1 5880.7 5910.3 5931.8 5942.7 5952.9 6087.9 6109.8 6117.4 6126.4 6127.9 6024.2 6032.4 6053.7 6056.2 6057.1 6050.2 6053.9 6066.5 6069.0 6094.5 4669.0 4823.3 4829.5 4873.0 4898.2 4271.2 4290.6 4292.7 4307.4 4350.7 5597.9 5686.2 5697.8 5699.0 5720.4 6108.5 6130.4 6147.0 6189.7 6261.6 5563.7 5731.7 5757.5 5770.7 5795.0 5363.8 5371.2 5379.1 5387.8 5390.3 6225.9 6282.4 6301.8 6301.8 6325.5 5197.7 6188.5 6190.1 6207.0 6320.9 6263.3 6287.5 6288.4 6289.4 6290.8 6085.3 6171.3 6182.8 6183.3 6200.8 4613.7 6122.8 6131.5 6137.4 6184.8 6064.7 6074.1 6081.8 6083.3 6099.2 6018.2 6044.3 6049.3 6052.1 6072.4 5757.3 5782.0 5788.9 5834.8 5837.2 5955.4 6006.2 6019.8 6043.9 6087.2 5904.6 5905.8 5914.9 5915.4 5948.3 15:43:19 INFO - PROCESS | 1956 | 15:43:19 INFO - PROCESS | 1956 | [#1] ext.html Cycles:180 Average:64754.73 Median:39709.31 stddev:139361.74 (351.0%) stddev-sans-first:139669.22 15:43:19 INFO - PROCESS | 1956 | Values: 512.8 514.5 519.4 527.5 544.5 38211.6 39700.6 39707.2 39711.4 41550.4 40667.8 40882.0 40953.8 40978.8 41010.0 9343.3 9479.0 9498.3 9506.2 9517.4 8110.0 9369.5 9394.0 9499.6 9519.2 9492.0 9499.0 9535.6 9556.3 9564.6 9415.2 9492.9 9499.7 9499.8 9504.2 9354.0 9443.5 9444.1 9454.4 9466.6 38775.6 38977.0 39029.8 39049.0 39059.6 3321.5 3452.5 3475.3 3817.8 4017.4 24152.5 28991.7 29709.6 30042.8 30057.0 3129.4 3169.1 3186.3 3234.1 3236.7 427684.0 428023.0 428971.0 430489.0 434549.0 54102.0 55686.4 55914.2 56381.4 56450.0 55924.0 57440.0 57534.7 57735.7 57897.7 15194.2 15431.7 15442.4 15454.5 15463.8 11823.9 11903.3 11905.9 11908.2 11917.6 12345.9 15678.9 15727.1 15866.1 15910.6 5985.4 6146.9 6196.8 6214.8 6239.7 846.0 919.3 994.5 1007.1 1029.3 29923.7 30849.8 30945.2 31220.2 31880.8 800.5 954.2 988.5 1019.6 1054.9 14008.4 14205.9 14754.9 14908.4 15196.8 581396.0 602904.0 631139.0 635641.0 636016.0 53507.9 53539.0 53539.7 53548.5 53571.9 1148.8 1227.7 1229.7 1232.1 1315.4 26064.7 26414.0 29582.9 29700.0 30526.5 46889.5 47768.0 47869.8 48052.8 48150.3 498249.0 500009.0 500380.0 502338.0 503506.0 24869.6 25160.2 25847.7 26639.6 26884.6 44601.8 44825.6 45116.0 45609.5 46076.0 36894.0 37181.8 37346.0 37832.0 38787.6 36875.4 36999.8 37089.8 38180.4 38183.8 37019.1 37404.4 37758.9 37801.6 37818.8 27615.6 31607.3 32973.0 33704.2 34027.3 22816.3 24052.0 24849.4 25147.9 25413.7 15:43:19 INFO - PROCESS | 1956 | 15:43:19 INFO - PROCESS | 1956 | [#2] jquery.html Cycles:180 Average:34006.40 Median:24608.81 stddev:96156.78 (390.7%) stddev-sans-first:96393.25 15:43:19 INFO - PROCESS | 1956 | Values: 313.3 346.5 359.5 370.5 398.8 14414.9 14598.3 15818.4 16142.4 19751.0 18712.8 19247.5 20030.9 20217.2 20334.5 5609.1 5973.3 5979.3 5994.7 5999.8 4081.2 5645.6 5791.8 5839.5 5928.6 4307.4 4337.1 6116.3 6421.6 6441.2 6271.9 6405.5 6430.9 6473.9 6489.5 5600.9 5608.4 5620.0 5627.9 5634.5 23578.9 24414.1 24457.8 24464.0 24753.6 2207.0 2256.4 2328.2 2366.1 2371.7 14605.5 15225.5 15445.8 15731.1 16009.4 1815.4 1843.0 1855.1 1859.5 1866.2 103325.0 104309.5 104538.5 106775.5 120393.4 7389.4 7988.9 9751.0 9874.6 10110.3 12029.0 12361.0 12389.8 12446.6 12454.9 7605.9 7619.8 7763.3 7819.5 8022.8 8108.6 8108.8 8110.7 8280.7 8311.7 3841.3 3861.6 3870.0 3909.8 3944.2 1704.0 1831.3 1925.2 1942.0 1944.2 679.0 740.0 878.2 897.2 937.0 16777.5 19125.6 19840.5 20988.7 21301.4 882.9 892.7 919.3 926.2 947.9 9026.5 10038.3 10803.5 10812.6 10827.5 569382.0 569561.0 575693.0 580618.0 580658.0 117366.0 125318.0 130431.3 133398.6 142742.0 828.5 936.8 999.3 1000.1 1000.3 18192.5 18248.1 18272.5 18274.4 18348.8 50966.5 51731.7 52788.7 54702.0 55668.5 79407.6 80143.6 82185.0 83429.0 83477.6 7255.7 7738.7 7823.9 8039.8 8254.3 11302.6 12564.3 12635.6 13043.6 13078.4 10927.7 11152.5 11378.8 11410.4 11463.3 10797.9 10882.6 10986.0 11089.9 11226.4 10800.4 11202.3 11375.4 11476.9 11560.2 8053.8 8074.7 8266.3 8462.3 8501.3 10901.9 11146.9 11358.4 11505.5 11597.7 15:43:19 INFO - PROCESS | 1956 | 15:43:19 INFO - PROCESS | 1956 | [#3] mootools.html Cycles:180 Average:5352.69 Median:5366.62 stddev:2901.95 (54.1%) stddev-sans-first:2894.42 15:43:19 INFO - PROCESS | 1956 | Values: 1340.5 1455.9 1600.0 1748.9 1833.4 7879.1 8250.2 8262.1 8272.8 8278.8 7281.9 7524.0 7828.3 8051.8 8061.7 2255.0 2293.8 2295.6 2298.7 2301.1 2290.6 2323.4 2325.6 2327.6 2338.5 2274.8 2278.1 2304.7 2305.9 2306.8 2249.9 2263.2 2276.3 2276.4 2279.3 2272.6 2297.7 2298.0 2301.4 2302.3 7071.7 8112.6 8177.3 8209.2 8219.4 4971.3 5144.6 5154.8 5158.1 5163.5 3179.2 3255.2 3517.3 3535.0 3535.9 1038.2 1042.7 1054.7 1070.3 1083.6 10498.5 10616.0 10660.9 10671.7 10687.3 4378.3 4497.6 4580.6 4600.1 4655.0 9206.7 9210.3 9212.8 9218.4 9294.5 5144.4 5150.7 5157.8 5161.6 5165.7 4221.9 4383.3 4454.6 4674.2 4734.3 3641.9 3676.6 3703.1 3795.4 3797.4 1223.4 1229.9 1230.1 1239.9 1247.5 830.9 842.0 882.6 888.7 933.5 5468.0 5574.3 5760.4 5848.5 6012.1 907.4 919.5 921.5 939.3 974.9 3366.8 3673.4 3751.7 3848.2 3860.5 9517.3 9739.2 9846.2 9882.6 9910.7 9660.2 9706.6 9844.9 9859.7 9865.0 7542.5 7672.6 7676.0 7701.2 7718.3 5265.3 5489.2 5580.4 5622.2 5639.1 9120.8 9187.3 9221.4 9279.3 9402.8 9224.6 9348.8 9392.0 9472.6 9492.7 6237.5 6440.4 6520.2 6616.0 6766.2 7773.2 7891.5 7918.9 7919.0 7922.8 6155.8 6983.3 7036.3 7072.3 7216.5 6913.2 6913.7 6917.2 6947.3 6972.9 6416.3 6537.8 6614.3 6619.5 6638.2 4782.3 4820.6 4831.9 4871.2 4960.7 4789.2 4873.0 4888.1 4942.8 4955.4 15:43:19 INFO - PROCESS | 1956 | 15:43:19 INFO - PROCESS | 1956 | [#4] prototype.html Cycles:180 Average:4248.86 Median:4643.18 stddev:1312.11 (28.3%) stddev-sans-first:1281.06 15:43:19 INFO - PROCESS | 1956 | Values: 253.4 284.8 290.3 306.1 315.4 5026.3 5036.7 5068.2 5078.6 5099.7 4302.4 4436.8 4827.5 4858.6 4897.6 3372.6 3617.7 3812.9 3823.8 3830.8 3689.2 3745.4 3960.4 3962.4 4007.3 2863.5 3244.2 3378.4 3414.0 3561.3 3879.3 3935.2 3940.7 3997.8 3998.6 3402.0 3425.6 3433.3 3449.9 3466.4 4088.6 4910.6 4913.9 4928.8 4938.8 4289.6 4376.3 4445.3 4487.6 4526.7 5245.6 5245.9 5309.3 5329.6 5349.0 3217.6 3294.0 3799.0 3819.8 3832.7 4631.7 5231.0 5444.3 5508.7 5523.2 3089.0 3404.0 3449.1 3604.6 3819.3 3702.6 3824.8 3872.4 3885.1 3888.9 3476.9 4417.2 4458.3 4471.4 4514.8 4522.5 5240.7 5283.9 5316.8 5324.3 1122.7 1223.9 1231.5 1244.2 1245.7 783.8 789.7 823.9 831.3 841.9 4088.3 4594.0 4610.7 4637.9 4642.4 4502.9 4688.5 4774.5 5001.7 5019.8 3930.6 4304.4 4430.5 4497.0 4582.6 2343.0 2632.2 2635.0 2636.7 2654.6 4461.8 4748.7 5035.7 5100.1 5209.6 4675.9 4850.9 4933.5 4952.9 5758.4 5736.8 5741.8 5845.4 5849.4 5857.9 5059.7 5194.6 5494.9 5528.8 5619.7 4625.0 5181.6 5468.0 5512.3 5545.8 4526.9 4840.5 5747.2 5762.9 5771.1 3697.5 3731.0 3731.2 3735.0 3758.3 4643.9 4816.1 5478.8 5507.2 5662.4 4898.4 5061.5 5167.6 5182.9 5389.5 4111.7 5125.8 5153.5 5414.9 5443.9 4602.1 4802.6 5189.4 5203.2 5298.0 4727.1 5011.9 5043.8 5358.5 5467.9 5306.6 5315.9 5401.4 5406.6 5414.9 15:43:19 INFO - PROCESS | 1956 | 15:43:19 INFO - PROCESS | 1956 | [#5] yui.html Cycles:180 Average:19235.53 Median:25460.55 stddev:27320.46 (107.3%) stddev-sans-first:27361.55 15:43:19 INFO - PROCESS | 1956 | Values: 674.6 683.4 739.6 764.3 775.7 13755.4 13977.3 13984.9 13988.5 14310.9 12706.2 12847.5 12869.1 14497.8 15144.5 820.3 840.6 842.2 844.2 844.8 832.1 840.1 841.2 841.7 842.0 890.3 893.0 893.6 894.7 896.1 881.7 882.0 883.4 884.8 885.0 651.5 652.1 653.2 654.3 655.7 14393.2 14413.6 14422.6 14440.3 14465.4 10587.9 12909.5 13341.8 13454.3 13525.9 9765.3 11389.3 11495.7 11523.5 11644.4 3581.4 3613.6 3620.7 3627.3 3651.7 89848.6 90013.6 90388.1 91575.5 91851.0 10086.9 10270.5 10285.9 10320.4 10368.9 20033.5 20082.9 20096.6 20098.0 20110.3 6146.8 7180.5 7452.2 7624.2 7669.5 3500.6 4197.3 4297.7 4375.1 4457.0 3233.9 3356.9 3358.7 3362.2 3362.9 611.4 622.6 634.9 635.5 636.6 609.6 629.4 649.3 664.6 671.8 8744.4 8749.7 8867.7 8890.5 9077.6 517.6 554.9 594.9 618.3 651.1 3995.4 4379.3 4406.3 4444.2 4517.9 90320.0 94168.1 96010.0 98107.5 113886.4 62953.7 62984.0 63709.0 67532.0 80130.2 75494.0 77968.0 81156.4 83841.2 87571.6 8248.8 8446.9 8658.2 9512.1 9552.0 49294.5 50403.5 52564.0 54000.5 54315.0 70530.0 72856.6 73180.0 73802.0 76665.0 11799.5 12078.0 12110.3 12149.4 12203.0 13641.0 13725.3 14025.2 14025.9 14073.4 12459.7 12718.5 13143.1 13327.5 13336.0 10702.3 12544.2 12698.7 12962.4 13159.0 12496.8 12580.7 12612.0 12809.0 13222.7 9153.9 10388.0 10721.7 11079.2 11250.6 10553.5 10962.1 11086.5 11091.4 11134.4 15:43:19 INFO - PROCESS | 1956 | -------- Summary: end -------- 15:43:19 INFO - PROCESS | 1956 | 15:43:19 INFO - TEST-INFO | 1956: exit 0 15:43:19 INFO - TEST-OK | dromaeo_css | took 1119849ms 15:43:19 INFO - TEST-START | kraken 15:43:19 INFO - operating with platform_type : mac_ 15:43:19 INFO - Initialising browser for kraken test... 15:43:19 INFO - TEST-INFO | started process 1961 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/tmp8B4ur8/profile http://localhost:54489/getInfo.html) 15:43:22 INFO - PROCESS | 1961 | __metrics Screen width/height:1600/1200 15:43:22 INFO - PROCESS | 1961 | colorDepth:24 15:43:22 INFO - PROCESS | 1961 | Browser inner width/height: 1024/689 15:43:22 INFO - PROCESS | 1961 | __metrics 15:43:22 INFO - PROCESS | 1961 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:43:22 INFO - PROCESS | 1961 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:43:23 INFO - TEST-INFO | 1961: exit 0 15:43:23 INFO - Browser initialized. 15:43:23 INFO - Running cycle 1/1 for kraken test... 15:43:23 INFO - TEST-INFO | started process 1962 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/tmp8B4ur8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:43:35 INFO - PROCESS | 1962 | RSS: Main: 231550976 15:43:35 INFO - PROCESS | 1962 | 15:43:38 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:54489/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54489/tests/kraken/driver.html?testName=audio-beat-detection) 15:43:39 INFO - PROCESS | 1962 | RSS: Main: 290828288 15:43:39 INFO - PROCESS | 1962 | 15:43:41 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:54489/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54489/tests/kraken/driver.html?testName=audio-dft) 15:43:42 INFO - PROCESS | 1962 | RSS: Main: 292798464 15:43:42 INFO - PROCESS | 1962 | 15:43:46 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:54489/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54489/tests/kraken/driver.html?testName=audio-fft) 15:43:46 INFO - PROCESS | 1962 | RSS: Main: 385220608 15:43:46 INFO - PROCESS | 1962 | 15:43:48 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:54489/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54489/tests/kraken/driver.html?testName=audio-oscillator) 15:43:49 INFO - PROCESS | 1962 | RSS: Main: 285175808 15:43:49 INFO - PROCESS | 1962 | 15:43:51 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:54489/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54489/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:43:51 INFO - PROCESS | 1962 | RSS: Main: 290013184 15:43:51 INFO - PROCESS | 1962 | 15:43:54 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:54489/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54489/tests/kraken/driver.html?testName=imaging-darkroom) 15:43:55 INFO - PROCESS | 1962 | RSS: Main: 326881280 15:43:55 INFO - PROCESS | 1962 | 15:43:58 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:54489/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54489/tests/kraken/driver.html?testName=imaging-desaturate) 15:43:58 INFO - PROCESS | 1962 | RSS: Main: 327180288 15:43:58 INFO - PROCESS | 1962 | 15:44:01 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:54489/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54489/tests/kraken/driver.html?testName=json-parse-financial) 15:44:02 INFO - PROCESS | 1962 | RSS: Main: 325427200 15:44:02 INFO - PROCESS | 1962 | 15:44:03 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:54489/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54489/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:44:04 INFO - PROCESS | 1962 | RSS: Main: 302387200 15:44:04 INFO - PROCESS | 1962 | 15:44:06 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:54489/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54489/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:44:06 INFO - PROCESS | 1962 | RSS: Main: 303546368 15:44:06 INFO - PROCESS | 1962 | 15:44:09 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:54489/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54489/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:44:09 INFO - PROCESS | 1962 | RSS: Main: 310267904 15:44:09 INFO - PROCESS | 1962 | 15:44:12 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:54489/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54489/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:44:13 INFO - PROCESS | 1962 | RSS: Main: 324976640 15:44:13 INFO - PROCESS | 1962 | 15:44:16 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:54489/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54489/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:44:17 INFO - PROCESS | 1962 | RSS: Main: 318808064 15:44:17 INFO - PROCESS | 1962 | 15:44:19 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:54489/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54489/tests/kraken/driver.html?testName=ai-astar) 15:44:19 INFO - PROCESS | 1962 | RSS: Main: 362438656 15:44:19 INFO - PROCESS | 1962 | 15:44:19 INFO - PROCESS | 1962 | __start_tp_report 15:44:19 INFO - PROCESS | 1962 | _x_x_mozilla_page_load 15:44:19 INFO - PROCESS | 1962 | _x_x_mozilla_page_load_details 15:44:19 INFO - PROCESS | 1962 | |i|pagename|runs| 15:44:19 INFO - PROCESS | 1962 | |0;ai-astar;104;113;126;169;146;113;112;114;112;112 15:44:19 INFO - PROCESS | 1962 | |1;audio-beat-detection;146;117;112;152;113;113;112;117;143;110 15:44:19 INFO - PROCESS | 1962 | |2;audio-dft;185;185;185;188;185;187;184;183;181;183 15:44:19 INFO - PROCESS | 1962 | |3;audio-fft;81;59;60;90;65;61;60;60;82;58 15:44:19 INFO - PROCESS | 1962 | |4;audio-oscillator;84;71;94;70;68;70;90;67;69;93 15:44:19 INFO - PROCESS | 1962 | |5;imaging-gaussian-blur;88;131;88;85;88;83;88;85;87;84 15:44:19 INFO - PROCESS | 1962 | |6;imaging-darkroom;93;93;93;93;92;91;93;94;93;92 15:44:19 INFO - PROCESS | 1962 | |7;imaging-desaturate;80;82;79;79;81;83;82;83;80;83 15:44:19 INFO - PROCESS | 1962 | |8;json-parse-financial;57;52;53;53;54;55;54;55;55;56 15:44:19 INFO - PROCESS | 1962 | |9;json-stringify-tinderbox;64;72;71;80;73;70;71;70;70;73 15:44:19 INFO - PROCESS | 1962 | |10;stanford-crypto-aes;87;86;84;87;85;89;89;90;87;92 15:44:19 INFO - PROCESS | 1962 | |11;stanford-crypto-ccm;146;153;163;163;147;151;163;154;200;170 15:44:19 INFO - PROCESS | 1962 | |12;stanford-crypto-pbkdf2;175;178;176;174;175;175;174;170;220;236 15:44:19 INFO - PROCESS | 1962 | |13;stanford-crypto-sha256-iterative;67;68;69;67;68;68;68;66;67;66 15:44:19 INFO - PROCESS | 1962 | __end_tp_report 15:44:19 INFO - PROCESS | 1962 | __start_cc_report 15:44:19 INFO - PROCESS | 1962 | _x_x_mozilla_cycle_collect,2109 15:44:19 INFO - PROCESS | 1962 | __end_cc_report 15:44:19 INFO - PROCESS | 1962 | __startTimestamp1478562259297__endTimestamp 15:44:19 INFO - PROCESS | 1962 | 15:44:19 INFO - PROCESS | 1962 | ------- Summary: start ------- 15:44:19 INFO - PROCESS | 1962 | Number of tests: 14 15:44:19 INFO - PROCESS | 1962 | 15:44:19 INFO - PROCESS | 1962 | [#0] ai-astar Cycles:10 Average:122.10 Median:113.50 stddev:20.14 (17.7%) stddev-sans-first:20.27 15:44:19 INFO - PROCESS | 1962 | Values: 104.0 113.0 126.0 169.0 146.0 113.0 112.0 114.0 112.0 112.0 15:44:19 INFO - PROCESS | 1962 | 15:44:19 INFO - PROCESS | 1962 | [#1] audio-beat-detection Cycles:10 Average:123.50 Median:117.00 stddev:16.50 (14.1%) stddev-sans-first:15.36 15:44:19 INFO - PROCESS | 1962 | Values: 146.0 117.0 112.0 152.0 113.0 113.0 112.0 117.0 143.0 110.0 15:44:19 INFO - PROCESS | 1962 | 15:44:19 INFO - PROCESS | 1962 | [#2] audio-dft Cycles:10 Average:184.60 Median:185.00 stddev:2.01 (1.1%) stddev-sans-first:2.13 15:44:19 INFO - PROCESS | 1962 | Values: 185.0 185.0 185.0 188.0 185.0 187.0 184.0 183.0 181.0 183.0 15:44:19 INFO - PROCESS | 1962 | 15:44:19 INFO - PROCESS | 1962 | [#3] audio-fft Cycles:10 Average:67.60 Median:63.00 stddev:11.92 (18.9%) stddev-sans-first:11.61 15:44:19 INFO - PROCESS | 1962 | Values: 81.0 59.0 60.0 90.0 65.0 61.0 60.0 60.0 82.0 58.0 15:44:19 INFO - PROCESS | 1962 | 15:44:19 INFO - PROCESS | 1962 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:77.50 stddev:11.25 (14.5%) stddev-sans-first:11.69 15:44:19 INFO - PROCESS | 1962 | Values: 84.0 71.0 94.0 70.0 68.0 70.0 90.0 67.0 69.0 93.0 15:44:19 INFO - PROCESS | 1962 | 15:44:19 INFO - PROCESS | 1962 | [#5] imaging-gaussian-blur Cycles:10 Average:90.70 Median:87.50 stddev:14.28 (16.3%) stddev-sans-first:15.12 15:44:19 INFO - PROCESS | 1962 | Values: 88.0 131.0 88.0 85.0 88.0 83.0 88.0 85.0 87.0 84.0 15:44:19 INFO - PROCESS | 1962 | 15:44:19 INFO - PROCESS | 1962 | [#6] imaging-darkroom Cycles:10 Average:92.70 Median:93.00 stddev:0.82 (0.9%) stddev-sans-first:0.87 15:44:19 INFO - PROCESS | 1962 | Values: 93.0 93.0 93.0 93.0 92.0 91.0 93.0 94.0 93.0 92.0 15:44:19 INFO - PROCESS | 1962 | 15:44:19 INFO - PROCESS | 1962 | [#7] imaging-desaturate Cycles:10 Average:81.20 Median:82.00 stddev:1.62 (2.0%) stddev-sans-first:1.66 15:44:19 INFO - PROCESS | 1962 | Values: 80.0 82.0 79.0 79.0 81.0 83.0 82.0 83.0 80.0 83.0 15:44:19 INFO - PROCESS | 1962 | 15:44:19 INFO - PROCESS | 1962 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:55.00 stddev:1.51 (2.7%) stddev-sans-first:1.27 15:44:19 INFO - PROCESS | 1962 | Values: 57.0 52.0 53.0 53.0 54.0 55.0 54.0 55.0 55.0 56.0 15:44:19 INFO - PROCESS | 1962 | 15:44:19 INFO - PROCESS | 1962 | [#9] json-stringify-tinderbox Cycles:10 Average:71.40 Median:71.50 stddev:3.95 (5.5%) stddev-sans-first:3.15 15:44:19 INFO - PROCESS | 1962 | Values: 64.0 72.0 71.0 80.0 73.0 70.0 71.0 70.0 70.0 73.0 15:44:19 INFO - PROCESS | 1962 | 15:44:19 INFO - PROCESS | 1962 | [#10] stanford-crypto-aes Cycles:10 Average:87.60 Median:88.00 stddev:2.41 (2.7%) stddev-sans-first:2.55 15:44:19 INFO - PROCESS | 1962 | Values: 87.0 86.0 84.0 87.0 85.0 89.0 89.0 90.0 87.0 92.0 15:44:19 INFO - PROCESS | 1962 | 15:44:19 INFO - PROCESS | 1962 | [#11] stanford-crypto-ccm Cycles:10 Average:161.00 Median:163.00 stddev:15.80 (9.7%) stddev-sans-first:15.80 15:44:19 INFO - PROCESS | 1962 | Values: 146.0 153.0 163.0 163.0 147.0 151.0 163.0 154.0 200.0 170.0 15:44:19 INFO - PROCESS | 1962 | 15:44:19 INFO - PROCESS | 1962 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.30 Median:175.50 stddev:22.91 (13.1%) stddev-sans-first:23.99 15:44:19 INFO - PROCESS | 1962 | Values: 175.0 178.0 176.0 174.0 175.0 175.0 174.0 170.0 220.0 236.0 15:44:19 INFO - PROCESS | 1962 | 15:44:19 INFO - PROCESS | 1962 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.00 stddev:0.97 (1.4%) stddev-sans-first:1.01 15:44:19 INFO - PROCESS | 1962 | Values: 67.0 68.0 69.0 67.0 68.0 68.0 68.0 66.0 67.0 66.0 15:44:19 INFO - PROCESS | 1962 | -------- Summary: end -------- 15:44:19 INFO - PROCESS | 1962 | 15:44:20 INFO - TEST-INFO | 1962: exit 0 15:44:20 INFO - TEST-OK | kraken | took 60421ms 15:44:20 INFO - SUITE-END | took 1180s 15:44:20 INFO - Completed test suite (00:19:40) 15:44:20 INFO - javascript benchmark 15:44:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3301.059198128534, 3303.339332133573, 3305.9338813223735, 3327.2014716468057, 3372.679595438434, 5548.833534993946, 5602.327720673515, 5620.887582248352, 5682.17608446775, 5711.771529138835, 5623.943760562393, 5654.406287339832, 5682.51698605618, 5759.193712880189, 5783.5373170146395, 5086.491350864921, 5119.616028797836, 5149.304843846089, 5190.844274671766, 5199.974000129994, 5252.606054545924, 5282.947170528303, 5284.2866213061325, 5288.418273989877, 5326.7070311132875, 5117.02776472469, 5133.024725302181, 5271.156614941591, 5283.656562323476, 5321.627486075976, 5163.819266325698, 5169.017886601534, 5174.301469301671, 5175.301334319856, 5190.429052804188, 5093.312402825645, 5151.023869503382, 5155.381354237478, 5157.4584668610005, 5160.406553246387, 5579.804706835282, 5594.524465420444, 5608.214849921014, 5616.550675945915, 5634.4365563443735, 6073.51411887048, 6077.3314935357075, 6083.270007599072, 6097.359777223416, 6109.969450152721, 5235.816746413857, 6175.197224360849, 6175.907361389583, 6189.071639254103, 6263.279722831841, 5764.193012978187, 5775.942240577624, 5780.450857168562, 5787.681677507735, 5805.274340707454, 6306.369363063749, 6311.842203944938, 6312.495000399957, 6319.431251187415, 6324.051392291101, 5880.7353669085, 5910.261217347832, 5931.792387266425, 5942.732577034044, 5952.940470595238, 6087.939120608826, 6109.755609775648, 6117.357677443893, 6126.448619624255, 6127.938720612826, 6024.186734790747, 6032.3967603239325, 6053.667048312297, 6056.152138700585, 6057.061155520902, 6050.213472248667, 6053.910296146648, 6066.545009124334, 6069.0, 6094.45149936499, 4669.0, 4823.27650852368, 4829.492903245108, 4873.0, 4898.191798353303, 4271.209826182167, 4290.570942905747, 4292.720973136798, 4307.3754305626135, 4350.695451318377, 5597.916031259534, 5686.2039314496005, 5697.800576979786, 5698.971505142447, 5720.42795720433, 6108.511319094462, 6130.367562715059, 6146.985747351726, 6189.721462534197, 6261.5616906816995, 5563.721813909288, 5731.684757338387, 5757.453041961007, 5770.711464426762, 5794.985877471426, 5363.758630861621, 5371.221172929903, 5379.058664733656, 5387.81142660005, 5390.326209223847, 6225.937740622536, 6282.371762823681, 6301.8109456716375, 6301.842453938597, 6325.525585581192, 5197.740112994411, 6188.4739797117745, 6190.133381326708, 6207.0, 6320.936790632219, 6263.342349053466, 6287.528435367274, 6288.371162883675, 6289.371062893674, 6290.811275661737, 6085.300190478151, 6171.290301615192, 6182.783602574068, 6183.319834818257, 6200.782972595937, 4613.723176609415, 6122.78570250057, 6131.529590167291, 6137.407629318989, 6184.81445556634, 6064.696765161812, 6074.088886667036, 6081.817545473643, 6083.330833608388, 6099.207103076572, 6018.157457955977, 6044.335123136363, 6049.304330002106, 6052.061930400795, 6072.392760723892, 5757.251557297524, 5781.971090144522, 5788.900108979449, 5834.766609335579, 5837.153612726046, 5955.434233747787, 6006.219191505251, 6019.849503762441, 6043.909341359795, 6087.178230938766, 5904.645721256738, 5905.822825315247, 5914.852128696645, 5915.40845915405, 5948.286205655176], "value": 5630.024255969646, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.8128233194884, 514.4598171919483, 519.3559985617833, 527.4646234072417, 544.4936209325326, 38211.617883821156, 39700.60299397005, 39707.205855882865, 39711.418757780724, 41550.37674434878, 40667.796661016655, 40882.0, 40953.795231023876, 40978.770636880865, 41009.97475063125, 9343.2525397968, 9479.0, 9498.28762842786, 9506.239500839916, 9517.428954262767, 8109.95945020274, 9369.48467834269, 9394.0, 9499.572519236652, 9519.238460923145, 9491.95254023729, 9499.0, 9535.570899309549, 9556.283278754086, 9564.617415303379, 9415.152636262736, 9492.905070949271, 9499.667511637059, 9499.810003799885, 9504.192143667831, 9353.953230233874, 9443.527823608792, 9444.102810233086, 9454.385464944757, 9466.621335146587, 38775.61224387777, 38977.0, 39029.829105126606, 39049.0, 39059.60940390588, 3321.4519604265265, 3452.499387588811, 3475.270193259393, 3817.7709337439564, 4017.3773065174946, 24152.516949661083, 28991.710082899113, 29709.554356684668, 30042.79828806845, 30057.0, 3129.4210571044428, 3169.1126484584124, 3186.2512309607223, 3234.094453552985, 3236.7086962173516, 427684.0, 428023.0, 428971.0, 430488.99999999686, 434549.0, 54102.0, 55686.44313556812, 55914.161287579904, 56381.43618563762, 56450.04849660567, 55924.0, 57440.0, 57534.7123264381, 57735.71132144312, 57897.710511448015, 15194.164320962233, 15431.691366172614, 15442.382304707911, 15454.459093931884, 15463.845361546466, 11823.88176118245, 11903.285802851857, 11905.880941190651, 11908.166428350105, 11917.582884598998, 12345.93827030859, 15678.921605392128, 15727.135007574465, 15866.127362994916, 15910.602234943988, 5985.401459854067, 6146.938530614726, 6196.81409557714, 6214.782482613087, 6239.656818875005, 845.9873101903477, 919.2737737187681, 994.5325696922434, 1007.1489591295343, 1029.341221618165, 29923.650444460032, 30849.845750771103, 30945.226369340497, 31220.219494512818, 31880.84059579687, 800.4956877167464, 954.236610711428, 988.515627342597, 1019.6431249062769, 1054.947252637365, 14008.369623366973, 14205.85794142066, 14754.852451475563, 14908.403663853325, 15196.848031519765, 581396.0, 602904.0, 631139.0, 635641.0, 636016.0, 53507.92984140373, 53539.0, 53539.73230133902, 53548.464515354346, 53571.928561428555, 1148.7530181011123, 1227.6501197158766, 1229.6802831263935, 1232.1313474000988, 1315.3620494060565, 26064.73935260699, 26414.0, 29582.852085739432, 29700.0, 30526.542101868934, 46889.53110468852, 47768.0, 47869.76065119652, 48052.75973620109, 48150.277745834544, 498249.0, 500009.0, 500380.0, 502338.0, 503506.0, 24869.62695559532, 25160.245192644248, 25847.741522584533, 26639.600405993537, 26884.59673104866, 44601.776991114835, 44825.55174448344, 45116.0, 45609.54390456053, 46076.0, 36894.0, 37181.81409092937, 37346.036951132075, 37832.0, 38787.6121238784, 36875.446868297535, 36999.81500092482, 37089.814550927076, 38180.427293590066, 38183.809080954416, 37019.07452313714, 37404.43893341657, 37758.86723398302, 37801.62198378091, 37818.81090594529, 27615.585766213924, 31607.313378362178, 32973.01080967575, 33704.1573960653, 34027.3194536103, 22816.31551053471, 24052.037918483064, 24849.378765531008, 25147.880340261796, 25413.745862541877], "value": 18993.278005753153, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.346672188487, 346.45087536255033, 359.4984995930675, 370.49242537723313, 398.81455123367647, 14414.882456964151, 14598.343074561644, 15818.446354377586, 16142.435014774484, 19751.0, 18712.812871871276, 19247.51881202967, 20030.89830059346, 20217.191312347524, 20334.491637709027, 5609.1305847593585, 5973.283206015284, 5979.342272350038, 5994.730237139339, 5999.820005399845, 4081.1837632473475, 5645.576581756364, 5791.768329266824, 5839.503642190419, 5928.644281343133, 4307.418498502709, 4337.089211265638, 6116.327204007555, 6421.614703117804, 6441.227052753676, 6271.905921411182, 6405.455536279422, 6430.896980196571, 6473.8705225895455, 6489.480841532666, 5600.859978500529, 5608.383077861432, 5620.0, 5627.915581266284, 5634.521065709419, 23578.882105589535, 24414.14550490742, 24457.755422445905, 24464.0, 24753.62869556958, 2207.0, 2256.356938272601, 2328.1502251678016, 2366.0890557135453, 2371.67982322386, 14605.488807891672, 15225.467108651144, 15445.845541544553, 15731.14900145998, 16009.439669611507, 1815.4462888818905, 1842.97235541467, 1855.1280897977929, 1859.4979355574055, 1866.188208129468, 103325.0, 104309.47845260725, 104538.47730761298, 106775.46612266966, 120393.39803301015, 7389.408847292204, 7988.920110798934, 9750.951245243728, 9874.555644995993, 10110.342827716175, 12029.0, 12361.0, 12389.814152787716, 12446.564370246997, 12454.875451245553, 7605.885911711329, 7619.847603047908, 7763.262490063435, 7819.452638315377, 8022.799430014296, 8108.635111420001, 8108.797280068045, 8110.674623404376, 8280.668773249123, 8311.6675332986, 3841.3085644584244, 3861.575226725058, 3870.0, 3909.843606255718, 3944.171723938005, 1704.0201883916798, 1831.2949514437037, 1925.2491528303972, 1942.0093056692535, 1944.2417457191707, 678.9898151527731, 739.9556026638311, 878.2490970220497, 897.237348253992, 936.9927328122296, 16777.496675099766, 19125.61748765045, 19840.50398740043, 20988.68516972216, 21301.36095917125, 882.9426087304305, 892.7366426904078, 919.2829592917535, 926.1664501948165, 947.9004704505927, 9026.548672566345, 10038.347507411994, 10803.459827008619, 10812.621558245424, 10827.458627068616, 569382.0, 569561.0, 575693.0, 580618.0, 580658.0, 117366.0, 125318.0, 130431.34784326208, 133398.6317320229, 142742.0, 828.5401602110886, 936.7517607833929, 999.3204620857887, 1000.0549480740631, 1000.2698030437821, 18192.545186370447, 18248.087595620433, 18272.451826445227, 18274.441578179332, 18348.81651183471, 50966.490335096176, 51731.741341293055, 52788.736056319474, 54702.0, 55668.491786958686, 79407.61777146695, 80143.59928200321, 82185.03497267245, 83429.0, 83477.58261208655, 7255.709771609077, 7738.690452382067, 7823.90465334865, 8039.799005024921, 8254.339652827879, 11302.604408845651, 12564.308963007123, 12635.620931372074, 13043.608691739262, 13078.411471483616, 10927.672169834917, 11152.49813758399, 11378.772424551296, 11410.429478525875, 11463.31220126795, 10797.92020797914, 10882.61910833117, 10986.0, 11089.889101108885, 11226.382548959886, 10800.405977671304, 11202.327860328407, 11375.374354410256, 11476.876777796135, 11560.248583842023, 8053.798655033671, 8074.698710867375, 8266.338692904674, 8462.280706139802, 8501.319894408556, 10901.890981090088, 11146.944265278622, 11358.398335415868, 11505.482253298454, 11597.6520704379], "value": 9294.748453502652, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1340.5174137310569, 1455.9272036398177, 1599.9920000399995, 1748.886322389044, 1833.4499650104965, 7879.054513458386, 8250.174982501747, 8262.091169971298, 8272.83454330913, 8278.834423311539, 7281.927180728191, 7523.962380188105, 7828.334591559725, 8051.758447246577, 8061.677532898677, 2254.9887250563725, 2293.827962902789, 2295.5867943770113, 2298.655201719747, 2301.102569997702, 2290.5876942150408, 2323.372689373868, 2325.5697695926224, 2327.592671282527, 2338.473843385233, 2274.8180145588312, 2278.065992942894, 2304.7119110111234, 2305.9077636894503, 2306.7808558186944, 2249.853759505627, 2263.230501629454, 2276.3057267533477, 2276.3967548599635, 2279.327598358488, 2272.6022945984387, 2297.7127859017623, 2297.988510057439, 2301.3786277704926, 2302.26327575176, 7071.717131314793, 8112.6349314280405, 8177.264046235868, 8209.220124088202, 8219.383546234056, 4971.27916452116, 5144.61415393847, 5154.845354639329, 5158.123119069767, 5163.483651634844, 3179.1734149121394, 3255.202475393544, 3517.26137511123, 3535.045537704805, 3535.9292814143573, 1038.2368958815264, 1042.671558459083, 1054.7046826888484, 1070.3203465799274, 1083.6044843632096, 10498.475076246157, 10616.0, 10660.893391066067, 10671.679849604447, 10687.305325153919, 4378.343248512749, 4497.617702495325, 4580.610648094949, 4600.148972440109, 4655.02244528646, 9206.723798286062, 9210.263178945668, 9212.769680757901, 9218.354715169873, 9294.535273236446, 5144.382674079135, 5150.690958542499, 5157.8452646420665, 5161.561267292247, 5165.690058596496, 4221.894452638709, 4383.254846676073, 4454.643628509712, 4674.205385084544, 4734.337192792943, 3641.8725344612812, 3676.613955534684, 3703.111253299243, 3795.449659799314, 3797.35444974355, 1223.4005337384617, 1229.9016078713682, 1230.0712961713848, 1239.8574163971077, 1247.500999600149, 830.9418340716092, 841.9536925469035, 882.5851849630664, 888.7022847346083, 933.4539294512786, 5467.972660136674, 5574.303212098488, 5760.3951585083805, 5848.473637372657, 6012.098185272157, 907.3512438606429, 919.5126582911067, 921.4977837078674, 939.281449690987, 974.936629119105, 3366.7979921204806, 3673.3755261605593, 3751.7431660394072, 3848.249591329664, 3860.4926657607616, 9517.333786635008, 9739.172170365599, 9846.212303015742, 9882.604695812232, 9910.653127140515, 9660.17888479473, 9706.611735530641, 9844.901550984541, 9859.704208873745, 9864.964178761127, 7542.509736867086, 7672.616369181518, 7675.961620191975, 7701.191374905667, 7718.343940764986, 5265.262863199232, 5489.176623506346, 5580.38615752275, 5622.156676498558, 5639.069553523786, 9120.817583648422, 9187.265018798615, 9221.446713197229, 9279.257659387367, 9402.81194376095, 9224.584893679934, 9348.766280842761, 9392.0, 9472.621095156117, 9492.667756628485, 6237.4698150657705, 6440.420362167428, 6520.18497687789, 6615.966920165369, 6766.15423072116, 7773.222677732181, 7891.487053341514, 7918.920810791818, 7919.0, 7922.801929951566, 6155.815325540241, 6983.336583024605, 7036.331548502884, 7072.25741297177, 7216.494845360774, 6913.170419549686, 6913.688884000131, 6917.204521480095, 6947.340002699735, 6972.9302706972285, 6416.262129855127, 6537.771178008747, 6614.272430032789, 6619.536632435876, 6638.203415589967, 4782.282657601388, 4820.638452116035, 4831.855044348676, 4871.196252618278, 4960.677555958995, 4789.233722604366, 4873.0, 4888.095702295087, 4942.8022879085875, 4955.35580374549], "value": 4359.061497967531, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [253.35900172563413, 284.78783306436696, 290.2511520277682, 306.1489060412054, 315.4369450530803, 5026.346574945252, 5036.67261627994, 5068.214426763849, 5078.568321692651, 5099.668521546097, 4302.419173411597, 4436.800343984521, 4827.51724827518, 4858.587020103296, 4897.632677549189, 3372.6458721834224, 3617.728670349721, 3812.8856134316015, 3823.7896915669626, 3830.8084595770238, 3689.1883785567134, 3745.3632882410056, 3960.4455376247347, 3962.445257663929, 4007.2786898358286, 2863.5418333066714, 3244.2376041630314, 3378.3581119587325, 3414.0440676610465, 3561.269939662376, 3879.3405121129476, 3935.2488142776474, 3940.699569142205, 3997.780122093284, 3998.5812130628183, 3402.0474267205223, 3425.6403077676982, 3433.3133373325186, 3449.87925422609, 3466.4107101792756, 4088.5707000764787, 4910.582600478928, 4913.852584422437, 4928.802847886116, 4938.777755001033, 4289.605878089602, 4376.321670141133, 4445.310976798602, 4487.640988720927, 4526.6831321807485, 5245.580353571743, 5245.921311180336, 5309.33633295838, 5329.573634109262, 5348.973255133699, 3217.6460589335156, 3293.9788665513765, 3798.981005094957, 3819.7722924129343, 3832.7125465589916, 4631.675782695179, 5230.97384513075, 5444.265024221719, 5508.6970216638065, 5523.183176579933, 3088.98455507721, 3404.0343000404746, 3449.0515108345407, 3604.6395360463744, 3819.3385877143533, 3702.5742039665242, 3824.808759562011, 3872.3861393559237, 3885.0768869409258, 3888.863889763845, 3476.860925562949, 4417.204903117406, 4458.331250312414, 4471.418715567021, 4514.751688657156, 4522.479914809774, 5240.68555886648, 5283.867903302448, 5316.813911513079, 5324.334458192726, 1122.7144919067784, 1223.8959688426405, 1231.4520038582746, 1244.1772802843877, 1245.682351000489, 783.7922950418143, 789.6880732110784, 823.8887750153773, 831.2892476932257, 841.8947631546057, 4088.345864661699, 4594.0, 4610.654200934876, 4637.86086417408, 4642.419697537808, 4502.887427814266, 4688.546550569266, 4774.4509381420885, 5001.7499125043605, 5019.84940451787, 3930.6462418382484, 4304.375865499485, 4430.490493593214, 4496.955030449653, 4582.564656357641, 2342.9531409371716, 2632.210336898961, 2634.9604755928676, 2636.670416197975, 2654.6150808133098, 4461.821527138943, 4748.738819364899, 5035.697858128451, 5100.1074811907765, 5209.557187639018, 4675.929861052087, 4850.902981940341, 4933.531314525186, 4952.9291839060015, 5758.395368486333, 5736.791335335843, 5741.799037033684, 5845.386234445407, 5849.356570777211, 5857.857717745084, 5059.747012649279, 5194.636375453682, 5494.945050549443, 5528.778848846001, 5619.662820230799, 4625.011245164612, 5181.585473162063, 5468.0, 5512.2558454608115, 5545.833624991095, 4526.8682829292675, 4840.491748366371, 5747.224124743107, 5762.913556296743, 5771.134329850556, 3697.4823524706026, 3731.003912906582, 3731.160488889978, 3734.981325093466, 3758.3235017697075, 4643.933449615024, 4816.060868130681, 5478.780848766164, 5507.228987941771, 5662.377138514842, 4898.387701537308, 5061.544460998381, 5167.630577896785, 5182.870428239325, 5389.51494365509, 4111.671066314748, 5125.846224613268, 5153.45888681683, 5414.891702166014, 5443.945560544344, 4602.125596136723, 4802.571988812186, 5189.4032186297545, 5203.245529398292, 5297.9801513137145, 4727.149113159527, 5011.9498805012945, 5043.823466178666, 5358.544523715372, 5467.8833065152485, 5306.602004849575, 5315.9468405317, 5401.405845357086, 5406.648567843077, 5414.918776218438], "value": 3829.2901217402064, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [674.5885010143814, 683.4498228925714, 739.5821360931077, 764.2968469008512, 775.7168633448791, 13755.449782008707, 13977.30113494325, 13984.860151398483, 13988.510402135928, 14310.856891431082, 12706.17409868358, 12847.48610055599, 12869.09916305859, 14497.782533262009, 15144.469943551978, 820.2699597358355, 840.5965136734371, 842.1620487614802, 844.2233145506149, 844.8437039147777, 832.0680837462021, 840.1052878684212, 841.2092632925062, 841.6591280531395, 841.9789505262356, 890.2699786175339, 893.0399820193286, 893.5800173918248, 894.6689724801832, 896.0636135238659, 881.6914080071988, 882.0297672560197, 883.355150739957, 884.8053428245807, 884.9513276769775, 651.4983462733716, 652.0610321137594, 653.1639501438119, 654.2835595023441, 655.6525041727892, 14393.208373539452, 14413.567592972122, 14422.567322980327, 14440.277986100758, 14465.421383144661, 10587.94120587935, 12909.483620655163, 13341.799873001914, 13454.32728363578, 13525.932370338085, 9765.316427850054, 11389.259698119597, 11495.712607184887, 11523.48144333507, 11644.41777911101, 3581.4448760442174, 3613.5663720353464, 3620.6560376764164, 3627.3108109459117, 3651.6896063834606, 89848.55075724646, 90013.64979525378, 90388.09611903928, 91575.54212228963, 91851.0, 10086.899131008742, 10270.539088919892, 10285.918548711446, 10320.380777153396, 10368.931071419705, 20033.499162520762, 20082.89958550198, 20096.59806803856, 20098.0, 20110.296139635335, 6146.815595532141, 7180.497365184491, 7452.180260171377, 7624.19945905683, 7669.501482403626, 3500.6149323574386, 4197.349410841324, 4297.656187504992, 4375.1031038637, 4456.955430445719, 3233.886813961547, 3356.8825091121694, 3358.714509266691, 3362.2434952135573, 3362.8654853805574, 611.3977731934042, 622.6264241455182, 634.8698516804044, 635.5043066408117, 636.5639536917226, 609.6037575575912, 629.4366541944934, 649.2630863969334, 664.6408095444827, 671.8325769436618, 8744.431611945203, 8749.650013999497, 8867.73396798097, 8890.511021893857, 9077.636894524278, 517.5962749055742, 554.8945700316926, 594.9315828679671, 618.2673531864755, 651.1242379000237, 3995.380715989026, 4379.321205213197, 4406.294992801137, 4444.2222611042935, 4517.864464066083, 90320.0, 94168.05831941593, 96010.03989960151, 98107.50946245223, 113886.43056784663, 62953.68523157355, 62984.0, 63709.04436433457, 67532.0, 80130.19869801344, 75494.0, 77968.0, 81156.37687246222, 83841.16158838455, 87571.56214219143, 8248.793780155544, 8446.944131983502, 8658.220760131619, 9512.1439070484, 9551.952240238754, 49294.50108308966, 50403.49596503988, 52564.0, 54000.45999539954, 54315.0, 70530.0, 72856.63571682108, 73180.0, 73802.0, 76665.0, 11799.469023893773, 12078.0, 12110.333931633844, 12149.392530373269, 12203.0, 13641.0, 13725.313734313444, 14025.158490490603, 14025.859741402863, 14073.437062517385, 12459.688507787016, 12718.491260349483, 13143.079984401, 13327.533536326182, 13336.0, 10702.304350217211, 12544.184627999151, 12698.746025079261, 12962.416691248727, 13159.013074019296, 12496.812547811609, 12580.748385032432, 12611.991040716537, 12809.0, 13222.669433264246, 9153.908460915305, 10388.0, 10721.678349649523, 11079.224454288122, 11250.606228781953, 10553.472326383804, 10962.123030157727, 11086.501107450345, 11091.44542772842, 11134.443277835913], "value": 7114.107811519992, "unit": "score"}], "name": "dromaeo_css", "value": 7003.86957743015, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 113.0, 126.0, 169.0, 146.0, 113.0, 112.0, 114.0, 112.0, 112.0], "value": 122.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 117.0, 112.0, 152.0, 113.0, 113.0, 112.0, 117.0, 143.0, 110.0], "value": 123.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 185.0, 188.0, 185.0, 187.0, 184.0, 183.0, 181.0, 183.0], "value": 184.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 60.0, 90.0, 65.0, 61.0, 60.0, 60.0, 82.0, 58.0], "value": 67.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 71.0, 94.0, 70.0, 68.0, 70.0, 90.0, 67.0, 69.0, 93.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 131.0, 88.0, 85.0, 88.0, 83.0, 88.0, 85.0, 87.0, 84.0], "value": 90.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 93.0, 92.0, 91.0, 93.0, 94.0, 93.0, 92.0], "value": 92.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 82.0, 79.0, 79.0, 81.0, 83.0, 82.0, 83.0, 80.0, 83.0], "value": 81.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 52.0, 53.0, 53.0, 54.0, 55.0, 54.0, 55.0, 55.0, 56.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 72.0, 71.0, 80.0, 73.0, 70.0, 71.0, 70.0, 70.0, 73.0], "value": 71.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 84.0, 87.0, 85.0, 89.0, 89.0, 90.0, 87.0, 92.0], "value": 87.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 153.0, 163.0, 163.0, 147.0, 151.0, 163.0, 154.0, 200.0, 170.0], "value": 161.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 178.0, 176.0, 174.0, 175.0, 175.0, 174.0, 170.0, 220.0, 236.0], "value": 185.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 69.0, 67.0, 68.0, 68.0, 68.0, 66.0, 67.0, 66.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1467.1000000000001, "alertThreshold": 2.0}]} 15:44:20 INFO - Return code: 0 15:44:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:44:20 INFO - # TBPL SUCCESS # 15:44:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:44:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:44:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:44:20 INFO - 2016-11-07 15:44:20.617 screenresolution[1966:19908] starting screenresolution argv=screenresolution get 15:44:20 INFO - 2016-11-07 15:44:20.619 screenresolution[1966:19908] Display 0: 1600x1200x32@60 15:44:20 INFO - 2016-11-07 15:44:20.626 screenresolution[1967:19911] starting screenresolution argv=screenresolution list 15:44:20 INFO - Available Modes on Display 0 15:44:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:44:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:44:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:44:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:44:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:44:20 INFO - Intel Iris: 15:44:20 INFO - Chipset Model: Intel Iris 15:44:20 INFO - Type: GPU 15:44:20 INFO - Bus: Built-In 15:44:20 INFO - VRAM (Dynamic, Max): 1536 MB 15:44:20 INFO - Vendor: Intel (0x8086) 15:44:20 INFO - Device ID: 0x0a2e 15:44:20 INFO - Revision ID: 0x0009 15:44:20 INFO - Displays: 15:44:20 INFO - Display: 15:44:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:44:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:44:20 INFO - Display Serial Number: 1600x1200 60 15:44:20 INFO - Main Display: Yes 15:44:20 INFO - Mirror: Off 15:44:20 INFO - Online: Yes 15:44:20 INFO - Rotation: Supported 15:44:20 INFO - Return code: 0 15:44:20 INFO - Running post-action listener: _resource_record_post_action 15:44:20 INFO - [mozharness: 2016-11-07 23:44:20.867215Z] Finished run-tests step (success) 15:44:20 INFO - Running post-run listener: _resource_record_post_run 15:44:20 INFO - Total resource usage - Wall time: 1215s; CPU: 54.0%; Read bytes: 647168; Write bytes: 704143872; Read time: 7; Write time: 1977 15:44:20 INFO - TinderboxPrint: CPU usage
53.6% 15:44:20 INFO - TinderboxPrint: I/O read bytes / time
647,168 / 7 15:44:20 INFO - TinderboxPrint: I/O write bytes / time
704,143,872 / 1,977 15:44:20 INFO - TinderboxPrint: CPU idle
2,254.7 (46.4%) 15:44:20 INFO - TinderboxPrint: CPU system
977.1 (20.1%) 15:44:20 INFO - TinderboxPrint: CPU user
1,629.9 (33.5%) 15:44:20 INFO - TinderboxPrint: Swap in / out
404,070,400 / 0 15:44:20 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 404130304; Write bytes: 405423616; Read time: 29822; Write time: 864 15:44:20 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 602112; Write bytes: 297462784; Read time: 7; Write time: 1104 15:44:21 INFO - Running post-run listener: _upload_blobber_files 15:44:21 INFO - Blob upload gear active. 15:44:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:44:21 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 15:44:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:44:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:44:21 INFO - (blobuploader) - INFO - Open directory for files ... 15:44:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:44:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:44:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:44:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:44:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:44:23 INFO - (blobuploader) - INFO - Done attempting. 15:44:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:44:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:44:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:44:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:44:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:44:24 INFO - (blobuploader) - INFO - Done attempting. 15:44:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:44:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:44:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:44:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:44:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:44:26 INFO - (blobuploader) - INFO - Done attempting. 15:44:26 INFO - (blobuploader) - INFO - Iteration through files over. 15:44:26 INFO - Return code: 0 15:44:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:44:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:44:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff869f58df40d24d18713771af103a7c7bc90918a256f7c29c05d2ffc9df45a83847aae5c74c93432bd442a7e67ea984c61b481720f094d0a2dff277c25f5803", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/859ede85181258f0fe9ea613661d877764e678f3a93d332aaa1ef755aff9994b17818b3a2613932cec7358225f16b97e934836ee4883056ce33b066e87052ff7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45628c39bdbe915b8b09106e5c31fdd0ae7db17b2dc37e11405107bc2f1033cb741cbb2f261f118c5dacacc598189ead117f7e5e708df8a7137a9034702233d6"} 15:44:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:44:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:44:26 INFO - Contents: 15:44:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff869f58df40d24d18713771af103a7c7bc90918a256f7c29c05d2ffc9df45a83847aae5c74c93432bd442a7e67ea984c61b481720f094d0a2dff277c25f5803", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/859ede85181258f0fe9ea613661d877764e678f3a93d332aaa1ef755aff9994b17818b3a2613932cec7358225f16b97e934836ee4883056ce33b066e87052ff7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45628c39bdbe915b8b09106e5c31fdd0ae7db17b2dc37e11405107bc2f1033cb741cbb2f261f118c5dacacc598189ead117f7e5e708df8a7137a9034702233d6"} 15:44:26 INFO - Running post-run listener: copy_logs_to_upload_dir 15:44:26 INFO - Copying logs to upload dir... 15:44:26 INFO - mkdir: /builds/slave/test/build/upload/logs 15:44:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.042367 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-07 15:44:26.310819) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-07 15:44:26.318580) ========= 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.5zvp43jpL6/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.bZMi6umtdI/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff869f58df40d24d18713771af103a7c7bc90918a256f7c29c05d2ffc9df45a83847aae5c74c93432bd442a7e67ea984c61b481720f094d0a2dff277c25f5803", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/859ede85181258f0fe9ea613661d877764e678f3a93d332aaa1ef755aff9994b17818b3a2613932cec7358225f16b97e934836ee4883056ce33b066e87052ff7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45628c39bdbe915b8b09106e5c31fdd0ae7db17b2dc37e11405107bc2f1033cb741cbb2f261f118c5dacacc598189ead117f7e5e708df8a7137a9034702233d6"} build_url:https://queue.taskcluster.net/v1/task/RmILIgCATwqPDiG8ouspqA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011220 build_url: 'https://queue.taskcluster.net/v1/task/RmILIgCATwqPDiG8ouspqA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff869f58df40d24d18713771af103a7c7bc90918a256f7c29c05d2ffc9df45a83847aae5c74c93432bd442a7e67ea984c61b481720f094d0a2dff277c25f5803", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/859ede85181258f0fe9ea613661d877764e678f3a93d332aaa1ef755aff9994b17818b3a2613932cec7358225f16b97e934836ee4883056ce33b066e87052ff7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45628c39bdbe915b8b09106e5c31fdd0ae7db17b2dc37e11405107bc2f1033cb741cbb2f261f118c5dacacc598189ead117f7e5e708df8a7137a9034702233d6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-07 15:44:26.352300) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-07 15:44:26.352651) ========= 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.5zvp43jpL6/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.bZMi6umtdI/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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.005092 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-07 15:44:26.401702) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-07 15:44:26.402016) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-07 15:44:26.402343) ========= ========= Total master_lag: 0.19 =========