builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0184 starttime: 1483200418.87 results: success (0) buildid: 20161231070020 builduid: 2ef26604d014461394c2c28f88b52f6b revision: 623f7b10ae96b129068d1ceb85d0e83ef5b19502 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-31 08:06:58.868168) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-31 08:06:58.868558) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-31 08:06:58.868824) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-31 08:06:58.895253) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-31 08:06:58.895554) ========= 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.2amGtLwCi3/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.HP51ICGU96/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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-12-31 08:06:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2016-12-31 08:06:59 (161 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.592159 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-31 08:06:59.511508) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-31 08:06:59.511818) ========= 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.2amGtLwCi3/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.HP51ICGU96/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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.026403 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-31 08:06:59.562769) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-31 08:06:59.563084) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 623f7b10ae96b129068d1ceb85d0e83ef5b19502 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 623f7b10ae96b129068d1ceb85d0e83ef5b19502 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2amGtLwCi3/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.HP51ICGU96/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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-12-31 08:06:59,659 truncating revision to first 12 chars 2016-12-31 08:06:59,659 Setting DEBUG logging. 2016-12-31 08:06:59,659 attempt 1/10 2016-12-31 08:06:59,659 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/623f7b10ae96?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-31 08:07:00,379 unpacking tar archive at: mozilla-aurora-623f7b10ae96/testing/mozharness/ program finished with exit code 0 elapsedTime=1.002565 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-31 08:07:00.588869) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-31 08:07:00.589192) ========= script_repo_revision: 623f7b10ae96b129068d1ceb85d0e83ef5b19502 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-31 08:07:00.592623) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-31 08:07:00.592888) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-31 08:07:00.612801) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-12-31 08:07:00.613194) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2amGtLwCi3/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.HP51ICGU96/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 08:07:00 INFO - MultiFileLogger online at 20161231 08:07:00 in /builds/slave/test 08:07:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:07:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:07:00 INFO - {'append_to_log': False, 08:07:00 INFO - 'base_work_dir': '/builds/slave/test', 08:07:00 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:07:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:07:00 INFO - 'branch': 'Mozilla-Aurora', 08:07:00 INFO - 'buildbot_json_path': 'buildprops.json', 08:07:00 INFO - 'config_files': ('talos/mac_config.py',), 08:07:00 INFO - 'default_actions': ('clobber', 08:07:00 INFO - 'read-buildbot-config', 08:07:00 INFO - 'download-and-extract', 08:07:00 INFO - 'populate-webroot', 08:07:00 INFO - 'create-virtualenv', 08:07:00 INFO - 'install', 08:07:00 INFO - 'run-tests'), 08:07:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:07:00 INFO - 'download_minidump_stackwalk': True, 08:07:00 INFO - 'download_symbols': 'ondemand', 08:07:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:07:00 INFO - 'tooltool.py': '/tools/tooltool.py', 08:07:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:07:00 INFO - '/tools/misc-python/virtualenv.py')}, 08:07:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:07:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:07:00 INFO - 'installer_path': 'installer.exe', 08:07:00 INFO - 'log_level': 'info', 08:07:00 INFO - 'log_name': 'talos', 08:07:00 INFO - 'log_to_console': True, 08:07:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:07:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:07:00 INFO - 'opt_config_files': (), 08:07:00 INFO - 'pip_index': False, 08:07:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:07:00 INFO - 'cmd': ('bash', 08:07:00 INFO - '-c', 08:07:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:07:00 INFO - 'enabled': True, 08:07:00 INFO - 'halt_on_failure': False, 08:07:00 INFO - 'name': 'check_screen_resolution'},), 08:07:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:07:00 INFO - 'cmd': ('bash', 08:07:00 INFO - '-c', 08:07:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:07:00 INFO - 'enabled': True, 08:07:00 INFO - 'halt_on_failure': False, 08:07:00 INFO - 'name': 'check_screen_resolution'},), 08:07:00 INFO - 'run_cmd_checks_enabled': True, 08:07:00 INFO - 'sps_profile': False, 08:07:00 INFO - 'sps_profile_interval': 0, 08:07:00 INFO - 'suite': 'dromaeojs-e10s', 08:07:00 INFO - 'system_bits': '32', 08:07:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:07:00 INFO - 'title': 't-yosemite-r7-0184', 08:07:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:07:00 INFO - 'use_talos_json': True, 08:07:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:07:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:07:00 INFO - 'work_dir': 'build'} 08:07:00 INFO - [mozharness: 2016-12-31 16:07:00.819234Z] Running clobber step. 08:07:00 INFO - Running pre-action listener: _resource_record_pre_action 08:07:00 INFO - Running main action method: clobber 08:07:00 INFO - rmtree: /builds/slave/test/build 08:07:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:07:03 INFO - Running post-action listener: _resource_record_post_action 08:07:03 INFO - [mozharness: 2016-12-31 16:07:03.832214Z] Finished clobber step (success) 08:07:03 INFO - [mozharness: 2016-12-31 16:07:03.832509Z] Running read-buildbot-config step. 08:07:03 INFO - Running pre-action listener: _resource_record_pre_action 08:07:03 INFO - Running main action method: read_buildbot_config 08:07:03 INFO - Using buildbot properties: 08:07:03 INFO - { 08:07:03 INFO - "project": "", 08:07:03 INFO - "product": "firefox", 08:07:03 INFO - "script_repo_revision": "production", 08:07:03 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 08:07:03 INFO - "repository": "", 08:07:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 08:07:03 INFO - "buildid": "20161231070020", 08:07:03 INFO - "pgo_build": "False", 08:07:03 INFO - "basedir": "/builds/slave/test", 08:07:03 INFO - "buildnumber": 19, 08:07:03 INFO - "slavename": "t-yosemite-r7-0184", 08:07:03 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 08:07:03 INFO - "platform": "yosemite_r7", 08:07:03 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:07:03 INFO - "branch": "mozilla-aurora", 08:07:03 INFO - "repo_path": "releases/mozilla-aurora", 08:07:03 INFO - "revision": "623f7b10ae96b129068d1ceb85d0e83ef5b19502", 08:07:03 INFO - "stage_platform": "macosx64", 08:07:03 INFO - "builduid": "2ef26604d014461394c2c28f88b52f6b", 08:07:03 INFO - "slavebuilddir": "test" 08:07:03 INFO - } 08:07:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/EuGe-kXjQsqs8C7jN9kUmA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 08:07:03 INFO - Running post-action listener: _resource_record_post_action 08:07:03 INFO - [mozharness: 2016-12-31 16:07:03.838197Z] Finished read-buildbot-config step (success) 08:07:03 INFO - [mozharness: 2016-12-31 16:07:03.838408Z] Running download-and-extract step. 08:07:03 INFO - Running pre-action listener: _resource_record_pre_action 08:07:03 INFO - Running main action method: download_and_extract 08:07:03 INFO - mkdir: /builds/slave/test/build/tests 08:07:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:03 INFO - trying https://queue.taskcluster.net/v1/task/EuGe-kXjQsqs8C7jN9kUmA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 08:07:03 INFO - Downloading https://queue.taskcluster.net/v1/task/EuGe-kXjQsqs8C7jN9kUmA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:07:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EuGe-kXjQsqs8C7jN9kUmA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 08:07:04 INFO - Downloaded 1199 bytes. 08:07:04 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:07:04 INFO - Using the following test package requirements: 08:07:04 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:07:04 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:07:04 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 08:07:04 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:07:04 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 08:07:04 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:07:04 INFO - u'jsshell-mac.zip'], 08:07:04 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:07:04 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 08:07:04 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:07:04 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:07:04 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 08:07:04 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:07:04 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 08:07:04 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:07:04 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 08:07:04 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:07:04 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 08:07:04 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:07:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EuGe-kXjQsqs8C7jN9kUmA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:07:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EuGe-kXjQsqs8C7jN9kUmA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:07:04 INFO - Fetch https://queue.taskcluster.net/v1/task/EuGe-kXjQsqs8C7jN9kUmA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:07:05 INFO - Http code: 200 08:07:05 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 08:07:05 INFO - x-amz-cf-id: DX1RpfUasli_4GtCIvgqeeJce_vIXLdWssG7rGCMCp8dwnOu7Lxnpw== 08:07:05 INFO - x-amz-version-id: RJNrlgrhpFResQmf5j.hcu_vHBrxv1ZG 08:07:05 INFO - Expected file size: 16268400 08:07:05 INFO - Obtained file size: 16268400 08:07:12 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 08:07:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EuGe-kXjQsqs8C7jN9kUmA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:07:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EuGe-kXjQsqs8C7jN9kUmA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:07:12 INFO - Fetch https://queue.taskcluster.net/v1/task/EuGe-kXjQsqs8C7jN9kUmA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:07:12 INFO - Http code: 200 08:07:12 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 08:07:12 INFO - x-amz-cf-id: jb3rp2M0x6h58OkSHhlZELlhnWGOXmB5oDYL1Gx3a_vL90AEQu5OVQ== 08:07:12 INFO - x-amz-version-id: RJNrlgrhpFResQmf5j.hcu_vHBrxv1ZG 08:07:13 INFO - Expected file size: 16268400 08:07:13 INFO - Obtained file size: 16268400 08:07:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EuGe-kXjQsqs8C7jN9kUmA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 08:07:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EuGe-kXjQsqs8C7jN9kUmA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:07:19 INFO - Fetch https://queue.taskcluster.net/v1/task/EuGe-kXjQsqs8C7jN9kUmA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 08:07:20 INFO - Http code: 200 08:07:20 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 08:07:20 INFO - x-amz-cf-id: XGzTLQ6FzRU5J0otlw6_lYUHwV1ARv7J2XRF39sldON9qg2r56_BaQ== 08:07:20 INFO - x-amz-version-id: YteXydAb18xV864wR8UKU4KhU0Ge8fyK 08:07:20 INFO - Expected file size: 14409038 08:07:20 INFO - Obtained file size: 14409038 08:07:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:21 INFO - trying https://queue.taskcluster.net/v1/task/EuGe-kXjQsqs8C7jN9kUmA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:07:21 INFO - Downloading https://queue.taskcluster.net/v1/task/EuGe-kXjQsqs8C7jN9kUmA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 08:07:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EuGe-kXjQsqs8C7jN9kUmA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 08:07:23 INFO - Downloaded 109829352 bytes. 08:07:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EuGe-kXjQsqs8C7jN9kUmA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:07:23 INFO - mkdir: /builds/slave/test/properties 08:07:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:07:23 INFO - Writing to file /builds/slave/test/properties/build_url 08:07:23 INFO - Contents: 08:07:23 INFO - build_url:https://queue.taskcluster.net/v1/task/EuGe-kXjQsqs8C7jN9kUmA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:07:24 INFO - Running post-action listener: _resource_record_post_action 08:07:24 INFO - Running post-action listener: set_extra_try_arguments 08:07:24 INFO - [mozharness: 2016-12-31 16:07:24.546970Z] Finished download-and-extract step (success) 08:07:24 INFO - [mozharness: 2016-12-31 16:07:24.547076Z] Running populate-webroot step. 08:07:24 INFO - Running pre-action listener: _resource_record_pre_action 08:07:24 INFO - Running main action method: populate_webroot 08:07:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:07:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:07:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:07:24 INFO - '--tppagecycles', 08:07:24 INFO - '7'], 08:07:24 INFO - 'tests': ['tsvgm']}}, 08:07:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:07:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:07:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:07:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:07:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:07:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:07:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:07:24 INFO - 'talos_options': ['--disable-e10s'], 08:07:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:07:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:07:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:07:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:07:24 INFO - 'talos_options': ['--disable-e10s'], 08:07:24 INFO - 'tests': ['damp', 'tps']}, 08:07:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:07:24 INFO - 'tests': ['damp', 'tps']}, 08:07:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:07:24 INFO - 'tests': ['dromaeo_dom']}, 08:07:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:07:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:07:24 INFO - 'tests': ['basic_compositor_video']}, 08:07:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:07:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:07:24 INFO - 'tests': ['a11yr', 08:07:24 INFO - 'ts_paint', 08:07:24 INFO - 'tpaint', 08:07:24 INFO - 'sessionrestore', 08:07:24 INFO - 'sessionrestore_no_auto_restore', 08:07:24 INFO - 'tabpaint']}, 08:07:24 INFO - 'other-e10s': {'tests': ['a11yr', 08:07:24 INFO - 'ts_paint', 08:07:24 INFO - 'tpaint', 08:07:24 INFO - 'sessionrestore', 08:07:24 INFO - 'sessionrestore_no_auto_restore', 08:07:24 INFO - 'tabpaint']}, 08:07:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:07:24 INFO - 'ts_paint', 08:07:24 INFO - 'tpaint', 08:07:24 INFO - 'sessionrestore', 08:07:24 INFO - 'sessionrestore_no_auto_restore', 08:07:24 INFO - 'tabpaint']}, 08:07:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:07:24 INFO - 'ts_paint', 08:07:24 INFO - 'tpaint', 08:07:24 INFO - 'sessionrestore', 08:07:24 INFO - 'sessionrestore_no_auto_restore', 08:07:24 INFO - 'tabpaint']}, 08:07:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:07:24 INFO - 'tests': ['a11yr', 08:07:24 INFO - 'ts_paint', 08:07:24 INFO - 'tpaint', 08:07:24 INFO - 'sessionrestore', 08:07:24 INFO - 'sessionrestore_no_auto_restore', 08:07:24 INFO - 'tabpaint']}, 08:07:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:07:24 INFO - 'tests': ['a11yr', 08:07:24 INFO - 'ts_paint', 08:07:24 INFO - 'tpaint', 08:07:24 INFO - 'sessionrestore', 08:07:24 INFO - 'sessionrestore_no_auto_restore', 08:07:24 INFO - 'tabpaint']}, 08:07:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:07:24 INFO - 'tests': ['tsvgx', 08:07:24 INFO - 'tsvgr_opacity', 08:07:24 INFO - 'tart', 08:07:24 INFO - 'tscrollx', 08:07:24 INFO - 'cart']}, 08:07:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:07:24 INFO - 'tsvgr_opacity', 08:07:24 INFO - 'tart', 08:07:24 INFO - 'tscrollx', 08:07:24 INFO - 'cart']}, 08:07:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:07:24 INFO - 'talos_options': ['--disable-e10s'], 08:07:24 INFO - 'tests': ['tp5o']}, 08:07:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:07:24 INFO - 'tests': ['tp5o']}, 08:07:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:07:24 INFO - 'talos_options': ['--disable-e10s', 08:07:24 INFO - '--xperf_path', 08:07:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:07:24 INFO - 'tests': ['tp5n']}, 08:07:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:07:24 INFO - 'talos_options': ['--xperf_path', 08:07:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:07:24 INFO - 'tests': ['tp5n']}}, 08:07:24 INFO - 'talos.zip': {'path': '', 08:07:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:07:24 INFO - Running post-action listener: _resource_record_post_action 08:07:24 INFO - [mozharness: 2016-12-31 16:07:24.566246Z] Finished populate-webroot step (success) 08:07:24 INFO - [mozharness: 2016-12-31 16:07:24.566364Z] Running create-virtualenv step. 08:07:24 INFO - Running pre-action listener: _resource_record_pre_action 08:07:24 INFO - Running main action method: create_virtualenv 08:07:24 INFO - Creating virtualenv /builds/slave/test/build/venv 08:07:24 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 08:07:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:07:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:07:24 INFO - Using real prefix '/tools/python27' 08:07:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:07:25 INFO - Installing distribute.............................................................................................................................................................................................done. 08:07:28 INFO - Installing pip.................done. 08:07:28 INFO - Return code: 0 08:07:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:07:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:07:28 INFO - Reading from file tmpfile_stdout 08:07:28 INFO - Output received: 08:07:28 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) 08:07:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:07:28 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')]} 08:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:28 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')]} 08:07:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:28 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 0x10b1f7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b16d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff79b48b070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b00bd30>, '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 0x10ae71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b003b20>, '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.2amGtLwCi3/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.HP51ICGU96/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 08:07:28 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 08:07:28 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 08:07:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2amGtLwCi3/Render', 08:07:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:07:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:07:28 INFO - 'HOME': '/Users/cltbld', 08:07:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:07:28 INFO - 'LOGNAME': 'cltbld', 08:07:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:28 INFO - 'MOZ_NO_REMOTE': '1', 08:07:28 INFO - 'NO_EM_RESTART': '1', 08:07:28 INFO - 'PAGER': '/bin/cat', 08:07:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:07:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:28 INFO - 'PWD': '/builds/slave/test', 08:07:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:07:28 INFO - 'SHELL': '/bin/bash', 08:07:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HP51ICGU96/Listeners', 08:07:28 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 08:07:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:07:28 INFO - 'USER': 'cltbld', 08:07:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:07:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:07:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:28 INFO - 'XPC_FLAGS': '0x0', 08:07:28 INFO - 'XPC_SERVICE_NAME': '0', 08:07:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:07:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:28 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 08:07:28 INFO - Cleaning up... 08:07:28 INFO - Return code: 0 08:07:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:07:28 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')]} 08:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:28 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')]} 08:07:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:28 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 0x10b1f7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b16d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff79b48b070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b00bd30>, '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 0x10ae71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b003b20>, '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.2amGtLwCi3/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.HP51ICGU96/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 08:07:28 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 08:07:28 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 08:07:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2amGtLwCi3/Render', 08:07:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:07:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:07:28 INFO - 'HOME': '/Users/cltbld', 08:07:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:07:28 INFO - 'LOGNAME': 'cltbld', 08:07:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:28 INFO - 'MOZ_NO_REMOTE': '1', 08:07:28 INFO - 'NO_EM_RESTART': '1', 08:07:28 INFO - 'PAGER': '/bin/cat', 08:07:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:07:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:28 INFO - 'PWD': '/builds/slave/test', 08:07:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:07:28 INFO - 'SHELL': '/bin/bash', 08:07:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HP51ICGU96/Listeners', 08:07:28 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 08:07:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:07:28 INFO - 'USER': 'cltbld', 08:07:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:07:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:07:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:28 INFO - 'XPC_FLAGS': '0x0', 08:07:28 INFO - 'XPC_SERVICE_NAME': '0', 08:07:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:07:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:29 INFO - Downloading/unpacking psutil>=3.1.1 08:07:29 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 08:07:29 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 08:07:29 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 08:07:29 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 08:07:29 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 08:07:29 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 08:07:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:07:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:07:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:07:33 INFO - Installing collected packages: psutil 08:07:33 INFO - Running setup.py install for psutil 08:07:33 INFO - building 'psutil._psutil_osx' extension 08:07:33 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 08:07:33 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 08:07:33 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 08:07:33 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 08:07:33 INFO - building 'psutil._psutil_posix' extension 08:07:33 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 08:07:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:07:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:07:34 INFO - ^ 08:07:34 INFO - 1 warning generated. 08:07:34 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 08:07:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:07:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:07:34 INFO - Successfully installed psutil 08:07:34 INFO - Cleaning up... 08:07:34 INFO - Return code: 0 08:07:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:07:34 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')]} 08:07:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:34 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')]} 08:07:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:34 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 0x10b1f7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b16d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff79b48b070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b00bd30>, '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 0x10ae71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b003b20>, '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.2amGtLwCi3/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.HP51ICGU96/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 08:07:34 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 08:07:34 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 08:07:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2amGtLwCi3/Render', 08:07:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:07:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:07:34 INFO - 'HOME': '/Users/cltbld', 08:07:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:07:34 INFO - 'LOGNAME': 'cltbld', 08:07:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:34 INFO - 'MOZ_NO_REMOTE': '1', 08:07:34 INFO - 'NO_EM_RESTART': '1', 08:07:34 INFO - 'PAGER': '/bin/cat', 08:07:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:07:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:34 INFO - 'PWD': '/builds/slave/test', 08:07:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:07:34 INFO - 'SHELL': '/bin/bash', 08:07:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HP51ICGU96/Listeners', 08:07:34 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 08:07:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:07:34 INFO - 'USER': 'cltbld', 08:07:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:07:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:07:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:34 INFO - 'XPC_FLAGS': '0x0', 08:07:34 INFO - 'XPC_SERVICE_NAME': '0', 08:07:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:07:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:07:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:07:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:07:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:07:37 INFO - Installing collected packages: mozsystemmonitor 08:07:37 INFO - Running setup.py install for mozsystemmonitor 08:07:37 INFO - Successfully installed mozsystemmonitor 08:07:37 INFO - Cleaning up... 08:07:37 INFO - Return code: 0 08:07:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:07: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')]} 08:07:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07: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')]} 08:07:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b1f7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b16d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff79b48b070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b00bd30>, '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 0x10ae71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b003b20>, '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.2amGtLwCi3/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.HP51ICGU96/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 08:07:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:07:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:07:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2amGtLwCi3/Render', 08:07:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:07:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:07:37 INFO - 'HOME': '/Users/cltbld', 08:07:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:07:37 INFO - 'LOGNAME': 'cltbld', 08:07:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:37 INFO - 'MOZ_NO_REMOTE': '1', 08:07:37 INFO - 'NO_EM_RESTART': '1', 08:07:37 INFO - 'PAGER': '/bin/cat', 08:07:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:07:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:37 INFO - 'PWD': '/builds/slave/test', 08:07:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:07:37 INFO - 'SHELL': '/bin/bash', 08:07:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HP51ICGU96/Listeners', 08:07:37 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 08:07:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:07:37 INFO - 'USER': 'cltbld', 08:07:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:07:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:07:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:37 INFO - 'XPC_FLAGS': '0x0', 08:07:37 INFO - 'XPC_SERVICE_NAME': '0', 08:07:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:07:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:37 INFO - Downloading/unpacking jsonschema==2.5.1 08:07:37 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 08:07:37 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 08:07:37 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 08:07:37 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 08:07:37 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 08:07:37 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 08:07:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:07:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:07:40 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 08:07:40 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 08:07:40 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 08:07:40 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 08:07:40 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 08:07:40 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 08:07:40 INFO - Downloading functools32-3.2.3-2.tar.gz 08:07:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:07:40 INFO - warning: no files found matching '*.txt' 08:07:40 INFO - no previously-included directories found matching 'build' 08:07:40 INFO - no previously-included directories found matching 'dist' 08:07:40 INFO - no previously-included directories found matching '.git*' 08:07:40 INFO - Installing collected packages: jsonschema, functools32 08:07:40 INFO - Running setup.py install for functools32 08:07:40 INFO - warning: no files found matching '*.txt' 08:07:40 INFO - no previously-included directories found matching 'build' 08:07:40 INFO - no previously-included directories found matching 'dist' 08:07:40 INFO - no previously-included directories found matching '.git*' 08:07:40 INFO - Successfully installed jsonschema functools32 08:07:40 INFO - Cleaning up... 08:07:41 INFO - Return code: 0 08:07:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:07:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:41 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 0x10b1f7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b16d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff79b48b070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b00bd30>, '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 0x10ae71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b003b20>, '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.2amGtLwCi3/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.HP51ICGU96/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 08:07:41 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 08:07:41 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 08:07:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2amGtLwCi3/Render', 08:07:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:07:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:07:41 INFO - 'HOME': '/Users/cltbld', 08:07:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:07:41 INFO - 'LOGNAME': 'cltbld', 08:07:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:41 INFO - 'MOZ_NO_REMOTE': '1', 08:07:41 INFO - 'NO_EM_RESTART': '1', 08:07:41 INFO - 'PAGER': '/bin/cat', 08:07:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:07:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:41 INFO - 'PWD': '/builds/slave/test', 08:07:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:07:41 INFO - 'SHELL': '/bin/bash', 08:07:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HP51ICGU96/Listeners', 08:07:41 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 08:07:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:07:41 INFO - 'USER': 'cltbld', 08:07:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:07:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:07:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:41 INFO - 'XPC_FLAGS': '0x0', 08:07:41 INFO - 'XPC_SERVICE_NAME': '0', 08:07:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:07:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:07:41 INFO - Cleaning up... 08:07:41 INFO - Return code: 0 08:07:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:07:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:41 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 0x10b1f7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b16d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff79b48b070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b00bd30>, '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 0x10ae71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b003b20>, '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.2amGtLwCi3/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.HP51ICGU96/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 08:07:41 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 08:07:41 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 08:07:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2amGtLwCi3/Render', 08:07:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:07:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:07:41 INFO - 'HOME': '/Users/cltbld', 08:07:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:07:41 INFO - 'LOGNAME': 'cltbld', 08:07:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:41 INFO - 'MOZ_NO_REMOTE': '1', 08:07:41 INFO - 'NO_EM_RESTART': '1', 08:07:41 INFO - 'PAGER': '/bin/cat', 08:07:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:07:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:41 INFO - 'PWD': '/builds/slave/test', 08:07:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:07:41 INFO - 'SHELL': '/bin/bash', 08:07:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HP51ICGU96/Listeners', 08:07:41 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 08:07:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:07:41 INFO - 'USER': 'cltbld', 08:07:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:07:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:07:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:41 INFO - 'XPC_FLAGS': '0x0', 08:07:41 INFO - 'XPC_SERVICE_NAME': '0', 08:07:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:07:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:41 INFO - Downloading/unpacking blobuploader==1.2.4 08:07:41 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 08:07:41 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 08:07:41 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 08:07:41 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 08:07:41 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 08:07:41 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 08:07:44 INFO - Downloading blobuploader-1.2.4.tar.gz 08:07:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:07:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:07:44 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 08:07:44 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 08:07:44 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 08:07:44 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 08:07:44 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 08:07:44 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 08:07:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:07:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:07:44 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 08:07:44 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 08:07:44 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 08:07:44 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 08:07:44 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 08:07:44 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 08:07:45 INFO - Downloading docopt-0.6.1.tar.gz 08:07:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:07:45 INFO - Installing collected packages: blobuploader, requests, docopt 08:07:45 INFO - Running setup.py install for blobuploader 08:07:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:07:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:07:45 INFO - Running setup.py install for requests 08:07:46 INFO - Running setup.py install for docopt 08:07:46 INFO - Successfully installed blobuploader requests docopt 08:07:46 INFO - Cleaning up... 08:07:46 INFO - Return code: 0 08:07:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:07:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:46 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 0x10b1f7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b16d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff79b48b070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b00bd30>, '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 0x10ae71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b003b20>, '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.2amGtLwCi3/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.HP51ICGU96/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 08:07:46 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 08:07:46 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 08:07:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2amGtLwCi3/Render', 08:07:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:07:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:07:46 INFO - 'HOME': '/Users/cltbld', 08:07:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:07:46 INFO - 'LOGNAME': 'cltbld', 08:07:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:46 INFO - 'MOZ_NO_REMOTE': '1', 08:07:46 INFO - 'NO_EM_RESTART': '1', 08:07:46 INFO - 'PAGER': '/bin/cat', 08:07:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:07:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:46 INFO - 'PWD': '/builds/slave/test', 08:07:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:07:46 INFO - 'SHELL': '/bin/bash', 08:07:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HP51ICGU96/Listeners', 08:07:46 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 08:07:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:07:46 INFO - 'USER': 'cltbld', 08:07:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:07:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:07:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:46 INFO - 'XPC_FLAGS': '0x0', 08:07:46 INFO - 'XPC_SERVICE_NAME': '0', 08:07:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:07:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:07:46 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-pVSpzA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:07:46 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-KfMwuS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:07:46 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-ziZFGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:07:46 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-tDDXyR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:07:47 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-nL7ECz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:07:47 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-1u35EU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:07:47 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-OXtZAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:07:47 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-zGTjAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:07:47 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-NHRirj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:07:47 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-5AzeDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:07:47 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-PonjAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:07:47 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-rDFw7I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:07:47 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-AKS8zH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:07:47 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-dO8N7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:07:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:07:48 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-FNgRdC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:07:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:07:48 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-ClLDH2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:07:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:07:48 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-IfZAgF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:07:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:07:48 INFO - Running setup.py install for manifestparser 08:07:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:07:48 INFO - Running setup.py install for mozcrash 08:07:48 INFO - Running setup.py install for mozdebug 08:07:48 INFO - Running setup.py install for mozdevice 08:07:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:07:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:07:48 INFO - Running setup.py install for mozfile 08:07:49 INFO - Running setup.py install for mozhttpd 08:07:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:07:49 INFO - Running setup.py install for mozinfo 08:07:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:07:49 INFO - Running setup.py install for mozInstall 08:07:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:07:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:07:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:07:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:07:49 INFO - Running setup.py install for mozleak 08:07:49 INFO - Running setup.py install for mozlog 08:07:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:07:49 INFO - Running setup.py install for moznetwork 08:07:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:07:50 INFO - Running setup.py install for mozprocess 08:07:50 INFO - Running setup.py install for mozprofile 08:07:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:07:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:07:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:07:50 INFO - Running setup.py install for mozrunner 08:07:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:07:50 INFO - Running setup.py install for mozscreenshot 08:07:50 INFO - Running setup.py install for moztest 08:07:50 INFO - Running setup.py install for mozversion 08:07:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:07:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:07:50 INFO - Cleaning up... 08:07:50 INFO - Return code: 0 08:07:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:07:50 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')]} 08:07:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:50 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')]} 08:07:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:50 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 0x10b1f7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b16d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff79b48b070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b00bd30>, '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 0x10ae71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b003b20>, '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.2amGtLwCi3/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.HP51ICGU96/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 08:07:50 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 08:07:50 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 08:07:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2amGtLwCi3/Render', 08:07:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:07:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:07:50 INFO - 'HOME': '/Users/cltbld', 08:07:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:07:50 INFO - 'LOGNAME': 'cltbld', 08:07:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:50 INFO - 'MOZ_NO_REMOTE': '1', 08:07:50 INFO - 'NO_EM_RESTART': '1', 08:07:50 INFO - 'PAGER': '/bin/cat', 08:07:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:07:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:50 INFO - 'PWD': '/builds/slave/test', 08:07:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:07:50 INFO - 'SHELL': '/bin/bash', 08:07:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HP51ICGU96/Listeners', 08:07:50 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 08:07:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:07:50 INFO - 'USER': 'cltbld', 08:07:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:07:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:07:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:50 INFO - 'XPC_FLAGS': '0x0', 08:07:50 INFO - 'XPC_SERVICE_NAME': '0', 08:07:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:07:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:07:51 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-a_xXjW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:07:51 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)) 08:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:07:51 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-n0mIDG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:07:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:07:51 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-FtAFBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:07:51 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)) 08:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:07:51 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-OcaTBF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:07:51 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)) 08:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:07:51 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-SaoxKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:07:51 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)) 08:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:07:51 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-vTYcxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:07:51 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)) 08:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:07:51 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-89o2Sm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:07:51 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)) 08:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:07:51 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-ISB49q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:07:52 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)) 08:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:07:52 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-djuiJo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:07:52 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)) 08:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:07:52 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-zJ809D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:07:52 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)) 08:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:07:52 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-yUqZLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:07:52 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)) 08:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:07:52 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-e00rYB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:07:52 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)) 08:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:07:52 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-IeCDnc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:07:52 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)) 08:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:07:52 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-fts3bS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:07:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:07:52 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-m6kBVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:07:52 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)) 08:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:07:52 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-lkb0UK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:07:52 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)) 08:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:07:52 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-k2B1l3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:07:53 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)) 08:07:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:07:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:07:53 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)) 08:07:53 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)) 08:07:53 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)) 08:07:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:07: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 08:07: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 08:07: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 08:07: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 08:07: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 08:07: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 08:07:55 INFO - Downloading blessings-1.6.tar.gz 08:07:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:07:55 INFO - Installing collected packages: blessings 08:07:55 INFO - Running setup.py install for blessings 08:07:55 INFO - Successfully installed blessings 08:07:55 INFO - Cleaning up... 08:07:55 INFO - Return code: 0 08:07:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:07:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:07:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:07:56 INFO - Reading from file tmpfile_stdout 08:07:56 INFO - Current package versions: 08:07:56 INFO - blessings == 1.6 08:07:56 INFO - blobuploader == 1.2.4 08:07:56 INFO - docopt == 0.6.1 08:07:56 INFO - functools32 == 3.2.3-2 08:07:56 INFO - jsonschema == 2.5.1 08:07:56 INFO - manifestparser == 1.1 08:07:56 INFO - mozInstall == 1.12 08:07:56 INFO - mozcrash == 1.0 08:07:56 INFO - mozdebug == 0.1 08:07:56 INFO - mozdevice == 0.48 08:07:56 INFO - mozfile == 1.2 08:07:56 INFO - mozhttpd == 0.7 08:07:56 INFO - mozinfo == 0.9 08:07:56 INFO - mozleak == 0.1 08:07:56 INFO - mozlog == 3.4 08:07:56 INFO - moznetwork == 0.27 08:07:56 INFO - mozprocess == 0.23 08:07:56 INFO - mozprofile == 0.28 08:07:56 INFO - mozrunner == 6.13 08:07:56 INFO - mozscreenshot == 0.1 08:07:56 INFO - mozsystemmonitor == 0.3 08:07:56 INFO - moztest == 0.7 08:07:56 INFO - mozversion == 1.4 08:07:56 INFO - psutil == 3.1.1 08:07:56 INFO - requests == 1.2.3 08:07:56 INFO - wsgiref == 0.1.2 08:07:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:07:56 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')]} 08:07:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:56 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')]} 08:07:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:56 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 0x10b1f7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b16d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff79b48b070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b00bd30>, '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 0x10ae71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b003b20>, '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.2amGtLwCi3/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.HP51ICGU96/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 08:07:56 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 08:07:56 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 08:07:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2amGtLwCi3/Render', 08:07:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:07:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:07:56 INFO - 'HOME': '/Users/cltbld', 08:07:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:07:56 INFO - 'LOGNAME': 'cltbld', 08:07:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:56 INFO - 'MOZ_NO_REMOTE': '1', 08:07:56 INFO - 'NO_EM_RESTART': '1', 08:07:56 INFO - 'PAGER': '/bin/cat', 08:07:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:07:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:56 INFO - 'PWD': '/builds/slave/test', 08:07:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:07:56 INFO - 'SHELL': '/bin/bash', 08:07:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HP51ICGU96/Listeners', 08:07:56 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 08:07:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:07:56 INFO - 'USER': 'cltbld', 08:07:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:07:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:07:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:56 INFO - 'XPC_FLAGS': '0x0', 08:07:56 INFO - 'XPC_SERVICE_NAME': '0', 08:07:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:07:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:56 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)) 08:07:56 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)) 08:07:56 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)) 08:07:56 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)) 08:07:56 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)) 08:07:56 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)) 08:07:56 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)) 08:07:56 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)) 08:07:56 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)) 08:07:56 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)) 08:07:56 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)) 08:07:56 INFO - Cleaning up... 08:07:56 INFO - Return code: 0 08:07:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:07:56 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')]} 08:07:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:56 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')]} 08:07:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:56 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 0x10b1f7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b16d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff79b48b070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b00bd30>, '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 0x10ae71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b003b20>, '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.2amGtLwCi3/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.HP51ICGU96/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 08:07:56 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 08:07:56 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 08:07:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2amGtLwCi3/Render', 08:07:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:07:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:07:56 INFO - 'HOME': '/Users/cltbld', 08:07:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:07:56 INFO - 'LOGNAME': 'cltbld', 08:07:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:56 INFO - 'MOZ_NO_REMOTE': '1', 08:07:56 INFO - 'NO_EM_RESTART': '1', 08:07:56 INFO - 'PAGER': '/bin/cat', 08:07:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:07:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:56 INFO - 'PWD': '/builds/slave/test', 08:07:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:07:56 INFO - 'SHELL': '/bin/bash', 08:07:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HP51ICGU96/Listeners', 08:07:56 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 08:07:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:07:56 INFO - 'USER': 'cltbld', 08:07:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:07:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:07:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:56 INFO - 'XPC_FLAGS': '0x0', 08:07:56 INFO - 'XPC_SERVICE_NAME': '0', 08:07:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:07:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:07:56 INFO - Cleaning up... 08:07:56 INFO - Return code: 0 08:07:56 INFO - Running post-action listener: _resource_record_post_action 08:07:56 INFO - Running post-action listener: _start_resource_monitoring 08:07:56 INFO - Starting resource monitoring. 08:07:56 INFO - [mozharness: 2016-12-31 16:07:56.829108Z] Finished create-virtualenv step (success) 08:07:56 INFO - [mozharness: 2016-12-31 16:07:56.830135Z] Running install step. 08:07:56 INFO - Running pre-action listener: _resource_record_pre_action 08:07:56 INFO - Running main action method: install 08:07:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:07:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:07:57 INFO - Reading from file tmpfile_stdout 08:07:57 INFO - Detecting whether we're running mozinstall >=1.0... 08:07:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:07:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:07:57 INFO - Reading from file tmpfile_stdout 08:07:57 INFO - Output received: 08:07:57 INFO - Usage: mozinstall [options] installer 08:07:57 INFO - Options: 08:07:57 INFO - -h, --help show this help message and exit 08:07:57 INFO - -d DEST, --destination=DEST 08:07:57 INFO - Directory to install application into. [default: 08:07:57 INFO - "/builds/slave/test"] 08:07:57 INFO - --app=APP Application being installed. [default: firefox] 08:07:57 INFO - mkdir: /builds/slave/test/build/application 08:07:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:07:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 08:08:28 INFO - Reading from file tmpfile_stdout 08:08:28 INFO - Output received: 08:08:28 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:08:28 INFO - Running post-action listener: _resource_record_post_action 08:08:28 INFO - [mozharness: 2016-12-31 16:08:28.152961Z] Finished install step (success) 08:08:28 INFO - [mozharness: 2016-12-31 16:08:28.153113Z] Running run-tests step. 08:08:28 INFO - Running pre-action listener: _resource_record_pre_action 08:08:28 INFO - Running main action method: run_tests 08:08:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:08:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:08:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:08:28 INFO - 2016-12-31 08:08:28.167 screenresolution[1629:9132] starting screenresolution argv=screenresolution get 08:08:28 INFO - 2016-12-31 08:08:28.170 screenresolution[1629:9132] Display 0: 1600x1200x32@60 08:08:28 INFO - 2016-12-31 08:08:28.176 screenresolution[1630:9135] starting screenresolution argv=screenresolution list 08:08:28 INFO - Available Modes on Display 0 08:08:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:08:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:08:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:08:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:08:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:08:28 INFO - Intel Iris: 08:08:28 INFO - Chipset Model: Intel Iris 08:08:28 INFO - Type: GPU 08:08:28 INFO - Bus: Built-In 08:08:28 INFO - VRAM (Dynamic, Max): 1536 MB 08:08:28 INFO - Vendor: Intel (0x8086) 08:08:28 INFO - Device ID: 0x0a2e 08:08:28 INFO - Revision ID: 0x0009 08:08:28 INFO - Displays: 08:08:28 INFO - Display: 08:08:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:08:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:08:28 INFO - Display Serial Number: 1600x1200 60 08:08:28 INFO - Main Display: Yes 08:08:28 INFO - Mirror: Off 08:08:28 INFO - Online: Yes 08:08:28 INFO - Rotation: Supported 08:08:28 INFO - Return code: 0 08:08:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:08:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:08:28 INFO - Python 2.7.3 08:08:28 INFO - Return code: 0 08:08:28 INFO - grabbing minidump binary from tooltool 08:08:28 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')]} 08:08:28 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 0x10b00bd30>, '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 0x10ae71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b003b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:08:28 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 08:08:28 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 08:08:28 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 08:08:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:08:28 INFO - Return code: 0 08:08:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:08:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:08:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:08:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:08:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:08:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EuGe-kXjQsqs8C7jN9kUmA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0184', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 08:08:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EuGe-kXjQsqs8C7jN9kUmA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0184 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 08:08:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2amGtLwCi3/Render', 08:08:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:08:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:08:28 INFO - 'HOME': '/Users/cltbld', 08:08:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:08:28 INFO - 'LOGNAME': 'cltbld', 08:08:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:08:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:08:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:28 INFO - 'MOZ_NO_REMOTE': '1', 08:08:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:08:28 INFO - 'NO_EM_RESTART': '1', 08:08:28 INFO - 'PAGER': '/bin/cat', 08:08:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:08:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:28 INFO - 'PWD': '/builds/slave/test', 08:08:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:08:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:08:28 INFO - 'SHELL': '/bin/bash', 08:08:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HP51ICGU96/Listeners', 08:08:28 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 08:08:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:08:28 INFO - 'USER': 'cltbld', 08:08:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:08:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:08:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:28 INFO - 'XPC_FLAGS': '0x0', 08:08:28 INFO - 'XPC_SERVICE_NAME': '0', 08:08:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:08:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EuGe-kXjQsqs8C7jN9kUmA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0184', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 08:08:28 INFO - mozversion application_buildid: 20161231070020 08:08:28 INFO - mozversion application_changeset: 623f7b10ae96b129068d1ceb85d0e83ef5b19502 08:08:28 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:08:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:08:28 INFO - mozversion application_name: Firefox 08:08:28 INFO - mozversion application_remotingname: firefox-dev 08:08:28 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:08:28 INFO - mozversion application_vendor: Mozilla 08:08:28 INFO - mozversion application_version: 52.0a2 08:08:28 INFO - mozversion platform_buildid: 20161231070020 08:08:28 INFO - mozversion platform_changeset: 623f7b10ae96b129068d1ceb85d0e83ef5b19502 08:08:28 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:08:28 INFO - mozversion platform_version: 52.0a2 08:08:28 INFO - using testdate: 1483200508 08:08:28 INFO - actual date: 1483200508 08:08:28 INFO - starting webserver on 'localhost:49245' 08:08:28 INFO - SUITE-START | Running 2 tests 08:08:28 INFO - TEST-START | dromaeo_css 08:08:28 INFO - operating with platform_type : mac_ 08:08:28 INFO - Initialising browser for dromaeo_css test... 08:08:28 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/tmpbv9Hlz/profile http://localhost:49245/getInfo.html) 08:08:31 INFO - PROCESS | 1640 | 2016-12-31 08:08:31.782 plugin-container[1642:9322] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 08:08:31 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:08:31 INFO - PROCESS | 1640 | 2016-12-31 08:08:31.785 plugin-container[1642:9322] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d43, name = 'com.apple.CFPasteboardClient' 08:08:31 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:08:31 INFO - PROCESS | 1640 | 2016-12-31 08:08:31.785 plugin-container[1642:9322] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:08:31 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 08:08:31 INFO - PROCESS | 1640 | colorDepth:24 08:08:31 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 08:08:31 INFO - PROCESS | 1640 | __metrics 08:08:31 ERROR - PROCESS | 1640 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 08:08:32 INFO - TEST-INFO | 1640: exit 0 08:08:32 INFO - Browser initialized. 08:08:32 INFO - Running cycle 1/1 for dromaeo_css test... 08:08:32 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/tmpbv9Hlz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:08:35 INFO - PROCESS | 1644 | 2016-12-31 08:08:35.582 plugin-container[1645:9458] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 08:08:35 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:08:35 INFO - PROCESS | 1644 | 2016-12-31 08:08:35.583 plugin-container[1645:9458] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x424b, name = 'com.apple.CFPasteboardClient' 08:08:35 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:08:35 INFO - PROCESS | 1644 | 2016-12-31 08:08:35.583 plugin-container[1645:9458] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:08:45 INFO - PROCESS | 1644 | RSS: Main: 229793792 08:08:45 INFO - PROCESS | 1644 | 08:09:34 INFO - PROCESS | 1644 | 1483200574813 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] 08:11:48 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49245/tests/dromaeo/cssquery-ext.html) 08:11:50 INFO - PROCESS | 1644 | RSS: Main: 232243200 08:11:50 INFO - PROCESS | 1644 | 08:14:52 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-ext.html (next: http://localhost:49245/tests/dromaeo/cssquery-jquery.html) 08:14:53 INFO - PROCESS | 1644 | RSS: Main: 246620160 08:14:53 INFO - PROCESS | 1644 | 08:17:55 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49245/tests/dromaeo/cssquery-mootools.html) 08:17:56 INFO - PROCESS | 1644 | RSS: Main: 250007552 08:17:56 INFO - PROCESS | 1644 | 08:20:57 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49245/tests/dromaeo/cssquery-prototype.html) 08:20:58 INFO - PROCESS | 1644 | RSS: Main: 248754176 08:20:58 INFO - PROCESS | 1644 | 08:24:01 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49245/tests/dromaeo/cssquery-yui.html) 08:24:02 INFO - PROCESS | 1644 | RSS: Main: 252362752 08:24:02 INFO - PROCESS | 1644 | 08:27:04 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-yui.html (next: http://localhost:49245/tests/dromaeo/cssquery-dojo.html) 08:27:05 INFO - PROCESS | 1644 | RSS: Main: 253865984 08:27:05 INFO - PROCESS | 1644 | 08:27:05 INFO - PROCESS | 1644 | __start_tp_report 08:27:05 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 08:27:05 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 08:27:05 INFO - PROCESS | 1644 | |i|pagename|runs| 08:27:05 INFO - PROCESS | 1644 | |0;dojo.html;3345.4480010798216;3352.4133276676607;3369.5956485221777;3391.253924136692;3395.234478071403;5661.688607126612;5669.688167150805;5697.857553561163;5728.942710572893;5758.3665796762425;4713.316569097479;5636.4927156555905;5709.172170035353;5721.599488035839;5771.076627739562;5162.045021671003;5172.456892026321;5244.056069907434;5304.177852432879;5313.388960269561;4340.761258130801;5272.446393128724;5301.443348448396;5306.151015837467;5347.866303342428;5135.460776618476;5183.2743415921805;5211.973940130313;5282.550983166436;5291.28567643367;5232.398274198478;5243.056249875021;5251.396089449724;5269.604779641504;5271.762770675329;5060.316857224265;5160.045391602565;5167.044096842096;5179.663321884065;5192.636515443883;5478.972605136989;5655.123455864347;5721.7711291548285;5787.247657804501;5840.678762668052;5155.432902380735;6253.249610046823;6262.12330273762;6267.843303917393;6270.3416141305415;6123.099607468082;6174.8147555573405;6263.1544741459775;6266.342034086447;6277.403646653606;4938.481459446778;5887.2052272943465;5888.97055514724;5891.293044834561;5920.733566989496;6144.07838824171;6188.1027251048345;6190.0405437157315;6325.620462772248;6330.430261276507;6049.3950604939155;6081.452669259788;6096.451319381188;6110.663913484712;6117.857853872152;6150.661713605795;6229.470495007885;6243.157173781481;6256.906146407808;6278.246610406689;5652.689102099424;5802.24570805801;6186.783462578788;6195.380461953859;6207.410296021871;6141.447269745655;6149.8007888461325;6163.075538669146;6252.249730032334;6264.342244064399;4851.781669824866;4904.509549045067;4936.3829521309835;4954.727489988085;4981.875453113628;4429.844955426544;4435.246008178553;4437.8224871004795;4447.177272204653;4487.192305385062;5637.379888212294;5730.541556675456;5793.362730099686;5837;5857.648541087548;6170.876582468325;6181.567290289637;6197.783077592353;6211.378862113753;6233.376662333731;4466.531014243523;5680.11958146488;5701.885962280814;5794.333651630117;5820.854478637983;5368.087425137658;5376.704281264568;5425.701586412785;5435.592330575223;5446.863828404243;6355.618662880242;6358.459530939922;6369.299377068519;6380.234371875405;6445.2265728112925;5230.215467679878;6156.969215153896;6188.164597779242;6237.438630523275;6242.440599354424;6285.182926219562;6290.716917738804;6322.272938612118;6330.588511746721;6410.102585637921;6209.161763162096;6219.253689557282;6233.127362169391;6233.501319894307;6248.4064013918605;6191.473724733448;6203.7828675996125;6220.284667263322;6221.160143380585;6265.968670156803;5972.044472884318;5997.190379298739;6014.0678194879865;6049.909251361145;6125.907024958145;4769.9046019080115;5101.438841727481;5277.419483856849;6141.815745527641;6159.784407545714;5855.502282306052;6004.339522652417;6011.128386384037;6025.216721826135;6030.823989322215;6001.279846418458;6172.876542469216;6174.9691251545255;6180.536459765445;6190.7523699051535;4994.550490455877;5877.882442351214;5924.585279030426;5980.192673988956;5984.940150598438 08:27:05 INFO - PROCESS | 1644 | |1;ext.html;456.9885752856178;470.92465205567095;498.6708772210342;499.0169366348294;529.1982646290868;38844.22311553767;40492.59507404925;40523.39214911779;40542.59457405425;41338.793306033505;41834;42229.94425139373;42391;42417.515386961386;42473.78763106188;8040.356771458299;9241.676541321056;9322.533873306342;9357.251419886426;9412.81174376512;9211.585478653476;9267.165955064043;9287.164155226028;9300.720978370626;9303.72088837336;9123;9145.268378529734;9250.953745231265;9319.347645664806;9340.206082482997;9129.908700913038;9255.53722313885;9319.953400233024;9327.533623318875;9331.346805723602;9189.81620367589;9290.767730806716;9296.581653825526;9299.628014879398;9300.8604870927;39575.01062473432;39740.20519589621;39746;39928;39996.60003399958;2912.3156058326276;3265;3512.473129030666;3741.625837416264;3887.8639247626197;28448.857755711295;29860;30166.69833301683;30884.382312353628;30988.22529436782;3197.6962188592047;3201.3597280543986;3212.61448626164;3238.983805080983;3280.212748940261;476936;477483;478674;478810.605946975;479811;48898;51663.48336516662;59262;59964;60100.39899601036;45520.77239613781;62492.68753656203;63050.0542491863;64101.35898641047;64799.67600162063;14191.503297384543;16282.88322518065;16327.67344653124;16391.508254752378;16391.754123688155;12467.438965246585;12513.49946002168;12546.121771476091;12567.120301578801;12587.992960563133;15560.844391556166;16266.75599866003;16360.33730386834;17119.65760684779;17366.39217627377;3127.6715944825464;3199.8240096794434;5590.630295577609;6151.138840562325;6436.8390790230615;844.4257904624791;972.5429048347265;990.7813389530918;1012.6152062216458;1051.747580580655;30224;30990.69009309923;31520.684793152235;32961;33477.163070922936;1008.2135933971514;1032.3547782635853;1036.1658864613908;1128.3737525673166;1163.9989608936307;15531;16654.75017874733;16787.580310492336;16874.73439492043;16923.492295231164;701712;704248;706303;707669;710239;59215.70392148012;59538.80922381528;59566.70216648889;59566.70216648889;59848.70075649594;1311.344327836082;1353.8510763815973;1365.904386692922;1368.3431952662777;1369.459063669845;30002.099937001924;30180.245493861952;32013.719451221692;32068.877589284264;32635.510467343483;56512.71743641255;56589.86820263654;57350.42649573618;57456.71271643615;57533.71233143807;663783;668151;669183;671257;672366;41947.790261048496;42276.78861605672;42369.78815106028;42866.57133428626;42954.35568466538;39788;45946.08107837891;46190.30714539217;46474;46940.765296173304;38610;38972;39103.022424439616;39911.40132898067;40615.79692101521;38631.227375452894;38895.611043890334;39023.80488097541;39445.01387465336;40190.99522511961;37996.81001594974;38340.80829595834;38541;38583.80708096441;38898.61101389063;32926.34147317088;33843.66156338405;34414.655853441145;34602.82698586491;34813.30373392467;20603.381898543066;25678.716064197088;25728.61407078858;25746.227613171635;26145.215643530726 08:27:05 INFO - PROCESS | 1644 | |2;jquery.html;312.6966842163102;335.1804837173111;373.14735828631564;380.70495366091257;387.58721961111416;19214.519637009063;19883;20009.499762505944;20696.48258793531;20957.266495672655;19859.702104468408;19915.60168796623;20197.495062623442;20353.694694579593;20432.591348173028;5565.359983601877;5754.539636829044;5756.740946657411;5999.1301261317085;6022.578419510636;5833.241678581779;5997.310309314441;6009.87980240395;6011.398860113997;6015.127806468059;5682.801101961432;5846.386129456409;5905.379935106816;5947.4052594740615;5960.314563825173;5827.796027139051;5869.50109240715;5870.148828419855;5890.352061273256;5897.439743224387;5001.8499445016705;5184.377874655065;5287.4976877196605;5337.813176538842;5380.408155102975;12983.02627302956;13190.802137967938;13305.933470332684;21509.247176348752;23858.926348314366;2114.5242320477923;2244.4164517225477;2367.135995361698;2419.794317483016;2746.6566679165103;8554.786130346729;10197.133243674296;10249.538770755335;13399.129056611386;14368.281585920662;1596.8962017468943;1763.761892144557;1835.9632807343912;1867.561123136062;1877.558773688182;104117;115648;115743.42128289389;115923;117269;11318.490667919963;11361.602343917923;11424.714382140513;11575.958163765301;11737.237079589799;10548.419836909045;14456.783148252785;14469.204193769443;14488.637784055483;14920.253987300808;8279.793005174799;8350.874736878952;8375.482894579627;8378.204070613281;8458.323334133378;6918;8261.669533218725;8273.669053237923;8344.874826877602;8396.874046889301;4365.454318210223;4486.551344865487;4500.099980004013;4536.251518499476;4549.840755573605;1649.5463747469541;1879.5676994291389;1939.2727727102338;1989.711491833676;2003.2087325506634;812.3914648994905;957.1481381570269;983.1888691829126;997.2869398380193;1022.7852151048214;20433.386998390073;20859.1656333748;21004.26485073046;21039.15843366279;21186.682199767016;861.2507118806678;943.9292053095908;961.6153538584482;967.1634036558327;976.7753416714196;10893.564257429773;11169.162312826571;11175.552977880956;11195.440227988567;11247.718807029889;611778;613441;614121;625150;626155;131209;134622;134903.32548337197;139239;139466;957.7414098193449;966.0629189685993;981.651513712636;984.4339504784778;986.0583143098324;18227;18451.800632958813;18837.6232475347;18901.44830447872;19162.23351065942;43638.909027274574;56340.87318253694;56677.43322566721;57388.71305643445;57926.7103664479;64031.71936561336;78239;79117;80749;81977.59011204906;7181.964090179516;7990.400719946144;8041.155678653658;8078.071021832329;8167.877481837896;9120.179183873483;9504.524773761146;10068.848967265643;11543.538258469567;12324.50701971911;10430.58277668915;10562.630307939186;10753.032227099598;10885.673429797118;10989.175811813986;10591.152707783205;10682.092022178202;10719.464026798785;10908.890911091104;10994.945025274823;10740.946295268473;10767.946160269148;11020;11348.602798901999;11350.978411942964;8014.759557213293;8020.518768873886;8185.836283274182;8190.7952301192945;8194.631241594023;9232.169104780602;10812.026917577456;11063;11097.889021109686;11127.332360058423 08:27:05 INFO - PROCESS | 1644 | |3;mootools.html;1401.5444980381376;1440.7766796146598;1580.5100418870152;1724.2240991553804;1730.3251731824582;6952.930470695292;7810.882836757453;8043.396745244108;8511.574421278934;8552.91447085529;7075.575465472062;8045.718399856006;8313.542755148479;8355.122712115231;8415.873761893561;2289.9656505152434;2298.482841360693;2322.756110608387;2324.2562380038394;2330;2343.695319608457;2356.7878890899815;2389.426537630965;2390.964135537968;2392.102961389479;2339.181286549711;2352.094443639203;2357.8467399619058;2362.83460157789;2364.586197415454;2306.5271619318;2321.3152120124605;2350.3066595354326;2353.0234952494693;2358.3514533503253;2309.399556115405;2363;2365.2431222008963;2371.822113341489;2376.9558179965215;8427.789305267355;8463;8493.745187644381;8497.787555311104;8503.532305723184;4852.951470485285;5214.322138122057;5218.217267409881;5221.008008478377;5235.947640523584;3339.482380231068;3548.7338449616122;3628.2743451309843;3636.30910127075;3657.7805331680183;1030.7268573827942;1047.4919663962971;1088.6733979806106;1089.2484185911696;1094.5402930769096;10353.171746260281;10968.725781855437;11085.002349788478;11086.778264434746;11098.778024439465;4517.164324599959;4628.490866004737;4712.976435117803;4713.128071306751;4777.283407488835;9406.05939406054;9480.620775169054;9487.857682134774;9489.952550237205;9502.619895204114;4954.20732682769;5225.608079393985;5228.712420816815;5237.319148510669;5245.449227831023;4297.484301883794;4315.244832154361;4733.692309999908;4787.018661174451;4824.589909857631;3189.696978787058;3296.78570892896;3784.2242340320167;3938.980305098456;3973.0663294125857;1265.6203139058246;1279.7504486625205;1282.2819221235952;1290.9289989050505;1293.8253335799548;839.4837175137246;890.3189060368823;894.4365050018447;897.3001059173855;925.5233554719325;5694.743736531783;5759.971200143972;5794.449527294901;5839.941600584025;5871.471567558855;786.0999155966387;927.4806108579218;943.6744323208482;950.6054987180381;971.0726256425097;3011.533212352045;3796.1079146400575;3865.594112618191;3907.779200015922;3909.4917660704496;9977.750556236153;10326.586936522604;10358.53386597605;10374.27380083387;10393.324433911772;10158.746031349276;10294.691159265234;10324.483775811179;10348.379097254188;10376.740581485372;7837.412194085467;7884.881726774103;7933.087694915159;7934.920650793533;7940.245676660708;5337.519623233772;5479.6164268500825;5518.806841760519;5610.158476228598;5666.376698563073;9401.247900168108;9449.858252126087;9548.045195480674;9558.235341172829;9569.521523923915;7810.726624568113;9042.63829446841;9463.148316651534;9464.905350946403;9471.337006409762;6631.834204144935;6835.58986460814;7045.823854403681;7183.964080179566;7347.595882226529;7803.648835802289;7958.681652734057;7961.16407777175;8059;8075.232852878967;7074.9292507076325;7204.819879503054;7210.783676489504;7226.674799634135;7328.633568321456;6965.6168910708375;7043.612601306977;7112.253213412517;7154.248803875518;7220.350168484862;6613.503987201076;6723.125993620798;6748.460123190232;6761.290064543349;6774.729010839708;4857.635677324144;4905.558499735041;4923.926141107814;4925.16272233714;4951.455339912679;4232.751338355254;4905.4358748742925;4906.779194936308;4987.401511818605;4987.451380348231 08:27:05 INFO - PROCESS | 1644 | |4;prototype.html;256.29135440506997;300.9202561321252;306.7622592490821;312.7373006674394;316.5062502496106;4730.881727956803;4922.6308026898;5098.852758129419;5435.619506634537;5578.888422231553;4634.420697412824;5100.225832883667;5389.434109418504;5492.203630473579;5494.725263736817;4195.538490766014;4197.874063778077;4203.348480985444;4207.727826973431;4212.220739163265;3883.8058097095172;4146.740026685448;4148.8511488511485;4191.979040104795;4197.027397123363;3269.1173383186524;3796.8481260749536;4077.9397356687327;4124.154548317588;4146.8963275918195;3289.0626171541235;3575.049570224903;3709.443583462475;3771.774173393658;4094.467719196515;2762.6408566886175;3024.6975302469577;3395.711364534018;3555.4489054196642;3556.697680697118;4110.828413902053;5000.449950505442;5024.919642276928;5288.629795914288;5340.839774806723;3548.1484443733575;4293.883590266522;4550.726956382595;4686.461056978457;4688.835890743808;5247.1866860636665;5744.6840423776675;5746.798862039808;5768.913466298009;5794.435502414324;3598.1934091408743;3797.525309336333;3845.5000849889616;3864.4589757433982;3943.363504145776;6009.519238460913;6018.886505996361;6069.575129740876;6070.150178974991;6087;3856.325143099946;3940.7438516496336;3940.763554186758;3980.980095099564;3982.9885907616467;3131.765117616188;3320.684534969174;3994.6005399459823;4001.8599349023225;4008.7594744315434;4665.6967297125575;4675.625949923998;4679.64902632304;4702.882427939329;4728;5497.39528651848;5524.668519888739;5612.831615051555;5804.825855224266;5817.0983497557945;1143.822707480342;1144.8626164860104;1240.8262843201958;1244.2410129820803;1310.5478609879576;842.8368850985723;884.6594061286451;886.7295474880224;887.4098724348365;890.6660002499062;4821.613301415751;4821.614270858323;4861.781219845116;5178.352705911761;5213.175388613955;4846.764075160882;5003.649744517922;5547.55619550443;5603.954556586817;5625.971870140705;4459.910801783946;4894.1679914414635;5115.93522412816;5173.405058418255;5174.637775355689;2875.4967880620807;2955.3645966117388;3034.863431145604;3047.481928072233;3047.9789270594156;4955.4548999610015;5048.798048078036;5161.761177317417;5678.365845858654;5928.695686948864;5066.945000724099;5234.476552344735;5750.2088187453;5760.8271751847515;5805.303363596396;4859.97940432506;5158.226266060196;5628.5084452620085;5854.941450585439;5868.882622347614;5257.237700533325;5353.78584856617;5616.7191640416995;5630.493255607015;5679.835633695166;5166.535011848951;5540.537164272329;5552.944470555242;5592.888142237051;5632.88734225305;5030.8239211627415;5284.735763211747;5388.461153884737;5845.269341332333;5851.64890106595;3713.387291096939;3736;3738;3746.23202243534;3750.6122734588375;5583.55331573465;5730.968425683417;5770.2498675171955;6061.696915154313;6070.939290607037;5156.9742151289;5741.25363702716;5770.798022069207;5885.616880033274;5915.704214789157;5580.609357344947;5837.416258374298;5843.561732870136;5858.949367721263;5864.270040338025;5032.295478633067;5474.425185355481;5658.745356458887;5663.57523185771;5710.114932185518;5135.7432128394175;5361.329833770778;5422.783088676409;5833.562482813891;5853.912191317048;5101;5567.137093750475;5630.915536267041;5674.517665998271;5680.318361796611 08:27:05 INFO - PROCESS | 1644 | |5;yui.html;738.8226825561864;741.614360532523;744.6760659113282;765.7511308824631;807.5477732469819;10465.267431279814;10801;12165.45255463504;13326.534081251068;14173.92913035436;12240.714724953885;12553.623391298275;13478.932605336984;13499.527516536924;13505.864941350583;796.956167410795;811.6185392865359;812.8943237379162;848.2916764501648;855.4396870050127;718.619131860115;844.7761343244046;851.6763629820659;853.4623187391966;857.7641148684099;874.6020560644923;877.1754550722333;882.6557642519423;883.9381243312971;885.8051228729669;877.1666916429417;880.0495464897926;880.6829541365136;882.386741214856;884.3588398411165;632.6457183976942;655.9180102487189;656.1896058367912;657.3098246840798;657.4444594317841;14397.64005899861;14421;14466;14481.56555303332;14492.20292883891;10141.746456338577;12790.168639038526;12819.16675416095;13264.469421223139;13569.932150339284;12025.759484810254;12086.33525156116;12304.753904921941;12359.505619775198;12522.436490357864;4361.105973275471;4370.978145109254;4384.473863136413;4406.096750166208;4546.227141385973;85537;85968.14031859726;87220.99999999936;88259.55870220608;88263.11736882613;10176.949115254525;10217.489125543692;10313.793724125475;10353.741156470998;10450.268481206243;18886;18899.24403023891;18926.52683682919;19032;19056.284934355695;7229.457790665721;7240.746573869889;7381.88927166093;7419.183889772121;7578.886316705254;4189.664826813848;4227.492700875853;4270.978645106755;4272.124214536013;4273.673969801349;3255.9023229303157;3311.966880331166;3364.3944090063533;3366.7138293244857;3367.1582104473882;512.8794733237686;617.299365220478;647.0100745858844;651.8891788395985;652.9706163222667;601.3145014683265;614.4820900345575;655.8327626455224;669.8928171492538;673.2795908377989;8833.779155521164;8935.642574296955;8960.327975401871;9090.36367454285;9229.907700923039;568.3350479938482;669.8794217040979;672.6468603982851;684.305429988562;696.2758730919787;4387.42963414754;4429.09203613265;4435.534268901783;4523.274111801116;4550.453945526492;58284.70857645685;84662.46012619631;88507.55746221356;98881;112444.43777781058;78208;81307;85127;101943.49028254811;104644.47677761715;75898.86151707618;89965.10034899568;91076;91135.63296550557;92502.14995700183;8368.661014237692;8564.400491965753;8999.730008099768;9798.461084640128;9832.803343932937;43393.698189054376;48175.59122043814;50844;51325;55238.17142742782;66173;68712.6564367175;71463;71916;71980;11932.582359617372;12204.145709800583;12411.75176496483;12437.315947622968;12814.55149069778;13876.236806975714;13974.441022358991;14185.858141418454;14760.48338308154;14833.554993350217;13200.94392448621;13244.867551324749;13643.044986850826;13737.450501979809;13785.51750688721;12146.028317734383;12701.047421443243;12850;12998.805017924928;13359.732805344032;11381.829272561085;12931.612051638465;13144.816966473063;13316.533921312708;13438.664033399244;10431.321964072311;10652.307600005975;11071.225014249247;11093.223474356717;11167.776644466903;11095.72260693489;11267.436628168723;11273.778302190762;11454.770904581696;11483.942580287045 08:27:05 INFO - PROCESS | 1644 | __end_tp_report 08:27:05 INFO - PROCESS | 1644 | __start_cc_report 08:27:05 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,371 08:27:05 INFO - PROCESS | 1644 | __end_cc_report 08:27:05 INFO - PROCESS | 1644 | __startTimestamp1483201625163__endTimestamp 08:27:05 INFO - PROCESS | 1644 | 08:27:05 INFO - PROCESS | 1644 | ------- Summary: start ------- 08:27:05 INFO - PROCESS | 1644 | Number of tests: 6 08:27:05 INFO - PROCESS | 1644 | 08:27:05 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5691.24 Median:5888.97 stddev:661.34 (11.2%) stddev-sans-first:637.25 08:27:05 INFO - PROCESS | 1644 | Values: 3345.4 3352.4 3369.6 3391.3 3395.2 5661.7 5669.7 5697.9 5728.9 5758.4 4713.3 5636.5 5709.2 5721.6 5771.1 5162.0 5172.5 5244.1 5304.2 5313.4 4340.8 5272.4 5301.4 5306.2 5347.9 5135.5 5183.3 5212.0 5282.6 5291.3 5232.4 5243.1 5251.4 5269.6 5271.8 5060.3 5160.0 5167.0 5179.7 5192.6 5479.0 5655.1 5721.8 5787.2 5840.7 5155.4 6253.2 6262.1 6267.8 6270.3 6123.1 6174.8 6263.2 6266.3 6277.4 4938.5 5887.2 5889.0 5891.3 5920.7 6144.1 6188.1 6190.0 6325.6 6330.4 6049.4 6081.5 6096.5 6110.7 6117.9 6150.7 6229.5 6243.2 6256.9 6278.2 5652.7 5802.2 6186.8 6195.4 6207.4 6141.4 6149.8 6163.1 6252.2 6264.3 4851.8 4904.5 4936.4 4954.7 4981.9 4429.8 4435.2 4437.8 4447.2 4487.2 5637.4 5730.5 5793.4 5837.0 5857.6 6170.9 6181.6 6197.8 6211.4 6233.4 4466.5 5680.1 5701.9 5794.3 5820.9 5368.1 5376.7 5425.7 5435.6 5446.9 6355.6 6358.5 6369.3 6380.2 6445.2 5230.2 6157.0 6188.2 6237.4 6242.4 6285.2 6290.7 6322.3 6330.6 6410.1 6209.2 6219.3 6233.1 6233.5 6248.4 6191.5 6203.8 6220.3 6221.2 6266.0 5972.0 5997.2 6014.1 6049.9 6125.9 4769.9 5101.4 5277.4 6141.8 6159.8 5855.5 6004.3 6011.1 6025.2 6030.8 6001.3 6172.9 6175.0 6180.5 6190.8 4994.6 5877.9 5924.6 5980.2 5984.9 08:27:05 INFO - PROCESS | 1644 | 08:27:05 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:74677.62 Median:40507.99 stddev:167732.35 (414.1%) stddev-sans-first:168110.32 08:27:05 INFO - PROCESS | 1644 | Values: 457.0 470.9 498.7 499.0 529.2 38844.2 40492.6 40523.4 40542.6 41338.8 41834.0 42229.9 42391.0 42417.5 42473.8 8040.4 9241.7 9322.5 9357.3 9412.8 9211.6 9267.2 9287.2 9300.7 9303.7 9123.0 9145.3 9251.0 9319.3 9340.2 9129.9 9255.5 9320.0 9327.5 9331.3 9189.8 9290.8 9296.6 9299.6 9300.9 39575.0 39740.2 39746.0 39928.0 39996.6 2912.3 3265.0 3512.5 3741.6 3887.9 28448.9 29860.0 30166.7 30884.4 30988.2 3197.7 3201.4 3212.6 3239.0 3280.2 476936.0 477483.0 478674.0 478810.6 479811.0 48898.0 51663.5 59262.0 59964.0 60100.4 45520.8 62492.7 63050.1 64101.4 64799.7 14191.5 16282.9 16327.7 16391.5 16391.8 12467.4 12513.5 12546.1 12567.1 12588.0 15560.8 16266.8 16360.3 17119.7 17366.4 3127.7 3199.8 5590.6 6151.1 6436.8 844.4 972.5 990.8 1012.6 1051.7 30224.0 30990.7 31520.7 32961.0 33477.2 1008.2 1032.4 1036.2 1128.4 1164.0 15531.0 16654.8 16787.6 16874.7 16923.5 701712.0 704248.0 706303.0 707669.0 710239.0 59215.7 59538.8 59566.7 59566.7 59848.7 1311.3 1353.9 1365.9 1368.3 1369.5 30002.1 30180.2 32013.7 32068.9 32635.5 56512.7 56589.9 57350.4 57456.7 57533.7 663783.0 668151.0 669183.0 671257.0 672366.0 41947.8 42276.8 42369.8 42866.6 42954.4 39788.0 45946.1 46190.3 46474.0 46940.8 38610.0 38972.0 39103.0 39911.4 40615.8 38631.2 38895.6 39023.8 39445.0 40191.0 37996.8 38340.8 38541.0 38583.8 38898.6 32926.3 33843.7 34414.7 34602.8 34813.3 20603.4 25678.7 25728.6 25746.2 26145.2 08:27:05 INFO - PROCESS | 1644 | 08:27:05 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:35342.08 Median:2583.23 stddev:103170.46 (3993.9%) stddev-sans-first:103426.35 08:27:05 INFO - PROCESS | 1644 | Values: 312.7 335.2 373.1 380.7 387.6 19214.5 19883.0 20009.5 20696.5 20957.3 19859.7 19915.6 20197.5 20353.7 20432.6 5565.4 5754.5 5756.7 5999.1 6022.6 5833.2 5997.3 6009.9 6011.4 6015.1 5682.8 5846.4 5905.4 5947.4 5960.3 5827.8 5869.5 5870.1 5890.4 5897.4 5001.8 5184.4 5287.5 5337.8 5380.4 12983.0 13190.8 13305.9 21509.2 23858.9 2114.5 2244.4 2367.1 2419.8 2746.7 8554.8 10197.1 10249.5 13399.1 14368.3 1596.9 1763.8 1836.0 1867.6 1877.6 104117.0 115648.0 115743.4 115923.0 117269.0 11318.5 11361.6 11424.7 11576.0 11737.2 10548.4 14456.8 14469.2 14488.6 14920.3 8279.8 8350.9 8375.5 8378.2 8458.3 6918.0 8261.7 8273.7 8344.9 8396.9 4365.5 4486.6 4500.1 4536.3 4549.8 1649.5 1879.6 1939.3 1989.7 2003.2 812.4 957.1 983.2 997.3 1022.8 20433.4 20859.2 21004.3 21039.2 21186.7 861.3 943.9 961.6 967.2 976.8 10893.6 11169.2 11175.6 11195.4 11247.7 611778.0 613441.0 614121.0 625150.0 626155.0 131209.0 134622.0 134903.3 139239.0 139466.0 957.7 966.1 981.7 984.4 986.1 18227.0 18451.8 18837.6 18901.4 19162.2 43638.9 56340.9 56677.4 57388.7 57926.7 64031.7 78239.0 79117.0 80749.0 81977.6 7182.0 7990.4 8041.2 8078.1 8167.9 9120.2 9504.5 10068.8 11543.5 12324.5 10430.6 10562.6 10753.0 10885.7 10989.2 10591.2 10682.1 10719.5 10908.9 10994.9 10740.9 10767.9 11020.0 11348.6 11351.0 8014.8 8020.5 8185.8 8190.8 8194.6 9232.2 10812.0 11063.0 11097.9 11127.3 08:27:05 INFO - PROCESS | 1644 | 08:27:05 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:5470.39 Median:5100.89 stddev:2998.59 (58.8%) stddev-sans-first:2991.41 08:27:05 INFO - PROCESS | 1644 | Values: 1401.5 1440.8 1580.5 1724.2 1730.3 6952.9 7810.9 8043.4 8511.6 8552.9 7075.6 8045.7 8313.5 8355.1 8415.9 2290.0 2298.5 2322.8 2324.3 2330.0 2343.7 2356.8 2389.4 2391.0 2392.1 2339.2 2352.1 2357.8 2362.8 2364.6 2306.5 2321.3 2350.3 2353.0 2358.4 2309.4 2363.0 2365.2 2371.8 2377.0 8427.8 8463.0 8493.7 8497.8 8503.5 4853.0 5214.3 5218.2 5221.0 5235.9 3339.5 3548.7 3628.3 3636.3 3657.8 1030.7 1047.5 1088.7 1089.2 1094.5 10353.2 10968.7 11085.0 11086.8 11098.8 4517.2 4628.5 4713.0 4713.1 4777.3 9406.1 9480.6 9487.9 9490.0 9502.6 4954.2 5225.6 5228.7 5237.3 5245.4 4297.5 4315.2 4733.7 4787.0 4824.6 3189.7 3296.8 3784.2 3939.0 3973.1 1265.6 1279.8 1282.3 1290.9 1293.8 839.5 890.3 894.4 897.3 925.5 5694.7 5760.0 5794.4 5839.9 5871.5 786.1 927.5 943.7 950.6 971.1 3011.5 3796.1 3865.6 3907.8 3909.5 9977.8 10326.6 10358.5 10374.3 10393.3 10158.7 10294.7 10324.5 10348.4 10376.7 7837.4 7884.9 7933.1 7934.9 7940.2 5337.5 5479.6 5518.8 5610.2 5666.4 9401.2 9449.9 9548.0 9558.2 9569.5 7810.7 9042.6 9463.1 9464.9 9471.3 6631.8 6835.6 7045.8 7184.0 7347.6 7803.6 7958.7 7961.2 8059.0 8075.2 7074.9 7204.8 7210.8 7226.7 7328.6 6965.6 7043.6 7112.3 7154.2 7220.4 6613.5 6723.1 6748.5 6761.3 6774.7 4857.6 4905.6 4923.9 4925.2 4951.5 4232.8 4905.4 4906.8 4987.4 4987.5 08:27:05 INFO - PROCESS | 1644 | 08:27:05 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4540.21 Median:5100.61 stddev:1417.73 (27.8%) stddev-sans-first:1384.76 08:27:05 INFO - PROCESS | 1644 | Values: 256.3 300.9 306.8 312.7 316.5 4730.9 4922.6 5098.9 5435.6 5578.9 4634.4 5100.2 5389.4 5492.2 5494.7 4195.5 4197.9 4203.3 4207.7 4212.2 3883.8 4146.7 4148.9 4192.0 4197.0 3269.1 3796.8 4077.9 4124.2 4146.9 3289.1 3575.0 3709.4 3771.8 4094.5 2762.6 3024.7 3395.7 3555.4 3556.7 4110.8 5000.4 5024.9 5288.6 5340.8 3548.1 4293.9 4550.7 4686.5 4688.8 5247.2 5744.7 5746.8 5768.9 5794.4 3598.2 3797.5 3845.5 3864.5 3943.4 6009.5 6018.9 6069.6 6070.2 6087.0 3856.3 3940.7 3940.8 3981.0 3983.0 3131.8 3320.7 3994.6 4001.9 4008.8 4665.7 4675.6 4679.6 4702.9 4728.0 5497.4 5524.7 5612.8 5804.8 5817.1 1143.8 1144.9 1240.8 1244.2 1310.5 842.8 884.7 886.7 887.4 890.7 4821.6 4821.6 4861.8 5178.4 5213.2 4846.8 5003.6 5547.6 5604.0 5626.0 4459.9 4894.2 5115.9 5173.4 5174.6 2875.5 2955.4 3034.9 3047.5 3048.0 4955.5 5048.8 5161.8 5678.4 5928.7 5066.9 5234.5 5750.2 5760.8 5805.3 4860.0 5158.2 5628.5 5854.9 5868.9 5257.2 5353.8 5616.7 5630.5 5679.8 5166.5 5540.5 5552.9 5592.9 5632.9 5030.8 5284.7 5388.5 5845.3 5851.6 3713.4 3736.0 3738.0 3746.2 3750.6 5583.6 5731.0 5770.2 6061.7 6070.9 5157.0 5741.3 5770.8 5885.6 5915.7 5580.6 5837.4 5843.6 5858.9 5864.3 5032.3 5474.4 5658.7 5663.6 5710.1 5135.7 5361.3 5422.8 5833.6 5853.9 5101.0 5567.1 5630.9 5674.5 5680.3 08:27:05 INFO - PROCESS | 1644 | 08:27:05 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:19563.85 Median:4479.40 stddev:28041.88 (626.0%) stddev-sans-first:28084.92 08:27:05 INFO - PROCESS | 1644 | Values: 738.8 741.6 744.7 765.8 807.5 10465.3 10801.0 12165.5 13326.5 14173.9 12240.7 12553.6 13478.9 13499.5 13505.9 797.0 811.6 812.9 848.3 855.4 718.6 844.8 851.7 853.5 857.8 874.6 877.2 882.7 883.9 885.8 877.2 880.0 880.7 882.4 884.4 632.6 655.9 656.2 657.3 657.4 14397.6 14421.0 14466.0 14481.6 14492.2 10141.7 12790.2 12819.2 13264.5 13569.9 12025.8 12086.3 12304.8 12359.5 12522.4 4361.1 4371.0 4384.5 4406.1 4546.2 85537.0 85968.1 87221.0 88259.6 88263.1 10176.9 10217.5 10313.8 10353.7 10450.3 18886.0 18899.2 18926.5 19032.0 19056.3 7229.5 7240.7 7381.9 7419.2 7578.9 4189.7 4227.5 4271.0 4272.1 4273.7 3255.9 3312.0 3364.4 3366.7 3367.2 512.9 617.3 647.0 651.9 653.0 601.3 614.5 655.8 669.9 673.3 8833.8 8935.6 8960.3 9090.4 9229.9 568.3 669.9 672.6 684.3 696.3 4387.4 4429.1 4435.5 4523.3 4550.5 58284.7 84662.5 88507.6 98881.0 112444.4 78208.0 81307.0 85127.0 101943.5 104644.5 75898.9 89965.1 91076.0 91135.6 92502.1 8368.7 8564.4 8999.7 9798.5 9832.8 43393.7 48175.6 50844.0 51325.0 55238.2 66173.0 68712.7 71463.0 71916.0 71980.0 11932.6 12204.1 12411.8 12437.3 12814.6 13876.2 13974.4 14185.9 14760.5 14833.6 13200.9 13244.9 13643.0 13737.5 13785.5 12146.0 12701.0 12850.0 12998.8 13359.7 11381.8 12931.6 13144.8 13316.5 13438.7 10431.3 10652.3 11071.2 11093.2 11167.8 11095.7 11267.4 11273.8 11454.8 11483.9 08:27:05 INFO - PROCESS | 1644 | -------- Summary: end -------- 08:27:05 INFO - PROCESS | 1644 | 08:27:05 INFO - TEST-INFO | 1644: exit 0 08:27:05 INFO - TEST-OK | dromaeo_css | took 1117018ms 08:27:05 INFO - TEST-START | kraken 08:27:05 INFO - operating with platform_type : mac_ 08:27:05 INFO - Initialising browser for kraken test... 08:27:05 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/tmpphqSzy/profile http://localhost:49245/getInfo.html) 08:27:08 INFO - PROCESS | 1652 | 2016-12-31 08:27:08.704 plugin-container[1653:11739] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 08:27:08 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:27:08 INFO - PROCESS | 1652 | 2016-12-31 08:27:08.707 plugin-container[1653:11739] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0f, name = 'com.apple.CFPasteboardClient' 08:27:08 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:27:08 INFO - PROCESS | 1652 | 2016-12-31 08:27:08.707 plugin-container[1653:11739] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:27:08 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 08:27:08 INFO - PROCESS | 1652 | colorDepth:24 08:27:08 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 08:27:08 INFO - PROCESS | 1652 | __metrics 08:27:08 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 08:27:09 INFO - TEST-INFO | 1652: exit 0 08:27:09 INFO - Browser initialized. 08:27:09 INFO - Running cycle 1/1 for kraken test... 08:27:09 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/tmpphqSzy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:27:12 INFO - PROCESS | 1654 | 2016-12-31 08:27:12.401 plugin-container[1655:11863] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4247, name = 'com.apple.tsm.portname' 08:27:12 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:27:12 INFO - PROCESS | 1654 | 2016-12-31 08:27:12.402 plugin-container[1655:11863] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 08:27:12 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:27:12 INFO - PROCESS | 1654 | 2016-12-31 08:27:12.402 plugin-container[1655:11863] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:27:22 INFO - PROCESS | 1654 | RSS: Main: 229789696 08:27:22 INFO - PROCESS | 1654 | 08:27:24 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection) 08:27:25 INFO - PROCESS | 1654 | RSS: Main: 241668096 08:27:25 INFO - PROCESS | 1654 | 08:27:28 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-dft) 08:27:29 INFO - PROCESS | 1654 | RSS: Main: 249462784 08:27:29 INFO - PROCESS | 1654 | 08:27:32 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-fft) 08:27:32 INFO - PROCESS | 1654 | RSS: Main: 253300736 08:27:32 INFO - PROCESS | 1654 | 08:27:34 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator) 08:27:35 INFO - PROCESS | 1654 | RSS: Main: 253489152 08:27:35 INFO - PROCESS | 1654 | 08:27:37 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:27:38 INFO - PROCESS | 1654 | RSS: Main: 253546496 08:27:38 INFO - PROCESS | 1654 | 08:27:40 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom) 08:27:41 INFO - PROCESS | 1654 | RSS: Main: 259416064 08:27:41 INFO - PROCESS | 1654 | 08:27:44 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate) 08:27:45 INFO - PROCESS | 1654 | RSS: Main: 259272704 08:27:45 INFO - PROCESS | 1654 | 08:27:48 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial) 08:27:48 INFO - PROCESS | 1654 | RSS: Main: 258740224 08:27:48 INFO - PROCESS | 1654 | 08:27:50 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:27:50 INFO - PROCESS | 1654 | RSS: Main: 257839104 08:27:50 INFO - PROCESS | 1654 | 08:27:52 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:27:53 INFO - PROCESS | 1654 | RSS: Main: 257904640 08:27:53 INFO - PROCESS | 1654 | 08:27:55 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:27:56 INFO - PROCESS | 1654 | RSS: Main: 257843200 08:27:56 INFO - PROCESS | 1654 | 08:27:59 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:28:00 INFO - PROCESS | 1654 | RSS: Main: 257925120 08:28:00 INFO - PROCESS | 1654 | 08:28:02 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:28:03 INFO - PROCESS | 1654 | RSS: Main: 258232320 08:28:03 INFO - PROCESS | 1654 | 08:28:05 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49245/tests/kraken/driver.html?testName=ai-astar) 08:28:05 INFO - PROCESS | 1654 | RSS: Main: 259821568 08:28:05 INFO - PROCESS | 1654 | 08:28:05 INFO - PROCESS | 1654 | __start_tp_report 08:28:05 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 08:28:05 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 08:28:05 INFO - PROCESS | 1654 | |i|pagename|runs| 08:28:05 INFO - PROCESS | 1654 | |0;ai-astar;116;118;140;171;151;116;117;118;118;128 08:28:05 INFO - PROCESS | 1654 | |1;audio-beat-detection;149;111;114;146;115;117;111;114;147;118 08:28:05 INFO - PROCESS | 1654 | |2;audio-dft;190;198;192;194;193;197;194;195;196;194 08:28:05 INFO - PROCESS | 1654 | |3;audio-fft;80;60;62;108;62;63;87;50;69;58 08:28:05 INFO - PROCESS | 1654 | |4;audio-oscillator;79;70;102;60;93;61;85;86;100;120 08:28:05 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;90;89;90;87;91;86;90;90;90;86 08:28:05 INFO - PROCESS | 1654 | |6;imaging-darkroom;97;99;96;94;150;150;98;95;95;152 08:28:05 INFO - PROCESS | 1654 | |7;imaging-desaturate;86;98;92;88;90;90;93;93;88;89 08:28:05 INFO - PROCESS | 1654 | |8;json-parse-financial;56;56;57;60;61;60;60;60;60;61 08:28:05 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;72;89;72;77;109;74;60;61;95;101 08:28:05 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;85;82;85;86;83;87;85;86;95;92 08:28:05 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;158;158;154;159;161;145;142;159;223;180 08:28:05 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;170;173;168;166;169;170;168;171;169;162 08:28:05 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;72;70;68;70;72;70;66;69;67;68 08:28:05 INFO - PROCESS | 1654 | __end_tp_report 08:28:05 INFO - PROCESS | 1654 | __start_cc_report 08:28:05 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,989 08:28:05 INFO - PROCESS | 1654 | __end_cc_report 08:28:05 INFO - PROCESS | 1654 | __startTimestamp1483201685541__endTimestamp 08:28:05 INFO - PROCESS | 1654 | 08:28:05 INFO - PROCESS | 1654 | ------- Summary: start ------- 08:28:05 INFO - PROCESS | 1654 | Number of tests: 14 08:28:05 INFO - PROCESS | 1654 | 08:28:05 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:129.30 Median:123.00 stddev:18.90 (15.4%) stddev-sans-first:19.42 08:28:05 INFO - PROCESS | 1654 | Values: 116.0 118.0 140.0 171.0 151.0 116.0 117.0 118.0 118.0 128.0 08:28:05 INFO - PROCESS | 1654 | 08:28:05 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:124.20 Median:117.50 stddev:16.13 (13.7%) stddev-sans-first:14.40 08:28:05 INFO - PROCESS | 1654 | Values: 149.0 111.0 114.0 146.0 115.0 117.0 111.0 114.0 147.0 118.0 08:28:05 INFO - PROCESS | 1654 | 08:28:05 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:194.30 Median:194.50 stddev:2.36 (1.2%) stddev-sans-first:1.92 08:28:05 INFO - PROCESS | 1654 | Values: 190.0 198.0 192.0 194.0 193.0 197.0 194.0 195.0 196.0 194.0 08:28:05 INFO - PROCESS | 1654 | 08:28:05 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:69.90 Median:62.50 stddev:17.18 (27.5%) stddev-sans-first:17.82 08:28:05 INFO - PROCESS | 1654 | Values: 80.0 60.0 62.0 108.0 62.0 63.0 87.0 50.0 69.0 58.0 08:28:05 INFO - PROCESS | 1654 | 08:28:05 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:85.60 Median:74.50 stddev:19.04 (25.6%) stddev-sans-first:20.04 08:28:05 INFO - PROCESS | 1654 | Values: 79.0 70.0 102.0 60.0 93.0 61.0 85.0 86.0 100.0 120.0 08:28:05 INFO - PROCESS | 1654 | 08:28:05 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:88.90 Median:90.00 stddev:1.85 (2.1%) stddev-sans-first:1.92 08:28:05 INFO - PROCESS | 1654 | Values: 90.0 89.0 90.0 87.0 91.0 86.0 90.0 90.0 90.0 86.0 08:28:05 INFO - PROCESS | 1654 | 08:28:05 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:112.60 Median:95.50 stddev:26.32 (27.6%) stddev-sans-first:27.30 08:28:05 INFO - PROCESS | 1654 | Values: 97.0 99.0 96.0 94.0 150.0 150.0 98.0 95.0 95.0 152.0 08:28:05 INFO - PROCESS | 1654 | 08:28:05 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:90.70 Median:91.00 stddev:3.43 (3.8%) stddev-sans-first:3.19 08:28:05 INFO - PROCESS | 1654 | Values: 86.0 98.0 92.0 88.0 90.0 90.0 93.0 93.0 88.0 89.0 08:28:05 INFO - PROCESS | 1654 | 08:28:05 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:59.10 Median:60.00 stddev:1.97 (3.3%) stddev-sans-first:1.74 08:28:05 INFO - PROCESS | 1654 | Values: 56.0 56.0 57.0 60.0 61.0 60.0 60.0 60.0 60.0 61.0 08:28:05 INFO - PROCESS | 1654 | 08:28:05 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:81.00 Median:73.00 stddev:16.71 (22.9%) stddev-sans-first:17.40 08:28:05 INFO - PROCESS | 1654 | Values: 72.0 89.0 72.0 77.0 109.0 74.0 60.0 61.0 95.0 101.0 08:28:05 INFO - PROCESS | 1654 | 08:28:05 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:86.60 Median:86.00 stddev:3.98 (4.6%) stddev-sans-first:4.18 08:28:05 INFO - PROCESS | 1654 | Values: 85.0 82.0 85.0 86.0 83.0 87.0 85.0 86.0 95.0 92.0 08:28:05 INFO - PROCESS | 1654 | 08:28:05 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:163.90 Median:159.00 stddev:23.13 (14.5%) stddev-sans-first:24.43 08:28:05 INFO - PROCESS | 1654 | Values: 158.0 158.0 154.0 159.0 161.0 145.0 142.0 159.0 223.0 180.0 08:28:05 INFO - PROCESS | 1654 | 08:28:05 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.60 Median:169.50 stddev:2.99 (1.8%) stddev-sans-first:3.13 08:28:05 INFO - PROCESS | 1654 | Values: 170.0 173.0 168.0 166.0 169.0 170.0 168.0 171.0 169.0 162.0 08:28:05 INFO - PROCESS | 1654 | 08:28:05 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.20 Median:70.00 stddev:1.99 (2.8%) stddev-sans-first:1.83 08:28:05 INFO - PROCESS | 1654 | Values: 72.0 70.0 68.0 70.0 72.0 70.0 66.0 69.0 67.0 68.0 08:28:05 INFO - PROCESS | 1654 | -------- Summary: end -------- 08:28:05 INFO - PROCESS | 1654 | 08:28:06 INFO - TEST-INFO | 1654: exit 0 08:28:06 INFO - TEST-OK | kraken | took 60789ms 08:28:06 INFO - SUITE-END | took 1177s 08:28:06 INFO - Completed test suite (00:19:37) 08:28:06 INFO - javascript benchmark 08:28:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7195.195998337123, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3345.4480010798216, 3352.4133276676607, 3369.5956485221777, 3391.253924136692, 3395.234478071403, 5661.688607126612, 5669.688167150805, 5697.857553561163, 5728.942710572893, 5758.3665796762425, 4713.316569097479, 5636.4927156555905, 5709.172170035353, 5721.599488035839, 5771.076627739562, 5162.045021671003, 5172.456892026321, 5244.056069907434, 5304.177852432879, 5313.388960269561, 4340.761258130801, 5272.446393128724, 5301.443348448396, 5306.151015837467, 5347.866303342428, 5135.460776618476, 5183.2743415921805, 5211.973940130313, 5282.550983166436, 5291.28567643367, 5232.398274198478, 5243.056249875021, 5251.396089449724, 5269.604779641504, 5271.762770675329, 5060.316857224265, 5160.045391602565, 5167.044096842096, 5179.663321884065, 5192.636515443883, 5478.972605136989, 5655.123455864347, 5721.7711291548285, 5787.247657804501, 5840.678762668052, 5155.432902380735, 6253.249610046823, 6262.12330273762, 6267.843303917393, 6270.3416141305415, 6123.099607468082, 6174.8147555573405, 6263.1544741459775, 6266.342034086447, 6277.403646653606, 4938.481459446778, 5887.2052272943465, 5888.97055514724, 5891.293044834561, 5920.733566989496, 6144.07838824171, 6188.1027251048345, 6190.0405437157315, 6325.620462772248, 6330.430261276507, 6049.3950604939155, 6081.452669259788, 6096.451319381188, 6110.663913484712, 6117.857853872152, 6150.661713605795, 6229.470495007885, 6243.157173781481, 6256.906146407808, 6278.246610406689, 5652.689102099424, 5802.24570805801, 6186.783462578788, 6195.380461953859, 6207.410296021871, 6141.447269745655, 6149.8007888461325, 6163.075538669146, 6252.249730032334, 6264.342244064399, 4851.781669824866, 4904.509549045067, 4936.3829521309835, 4954.727489988085, 4981.875453113628, 4429.844955426544, 4435.246008178553, 4437.8224871004795, 4447.177272204653, 4487.192305385062, 5637.379888212294, 5730.541556675456, 5793.362730099686, 5837.0, 5857.648541087548, 6170.876582468325, 6181.567290289637, 6197.783077592353, 6211.378862113753, 6233.376662333731, 4466.531014243523, 5680.11958146488, 5701.885962280814, 5794.333651630117, 5820.854478637983, 5368.087425137658, 5376.704281264568, 5425.701586412785, 5435.592330575223, 5446.863828404243, 6355.618662880242, 6358.459530939922, 6369.299377068519, 6380.234371875405, 6445.2265728112925, 5230.215467679878, 6156.969215153896, 6188.164597779242, 6237.438630523275, 6242.440599354424, 6285.182926219562, 6290.716917738804, 6322.272938612118, 6330.588511746721, 6410.102585637921, 6209.161763162096, 6219.253689557282, 6233.127362169391, 6233.501319894307, 6248.4064013918605, 6191.473724733448, 6203.7828675996125, 6220.284667263322, 6221.160143380585, 6265.968670156803, 5972.044472884318, 5997.190379298739, 6014.0678194879865, 6049.909251361145, 6125.907024958145, 4769.9046019080115, 5101.438841727481, 5277.419483856849, 6141.815745527641, 6159.784407545714, 5855.502282306052, 6004.339522652417, 6011.128386384037, 6025.216721826135, 6030.823989322215, 6001.279846418458, 6172.876542469216, 6174.9691251545255, 6180.536459765445, 6190.7523699051535, 4994.550490455877, 5877.882442351214, 5924.585279030426, 5980.192673988956, 5984.940150598438], "value": 5651.399985658691, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [456.9885752856178, 470.92465205567095, 498.6708772210342, 499.0169366348294, 529.1982646290868, 38844.22311553767, 40492.59507404925, 40523.39214911779, 40542.59457405425, 41338.793306033505, 41834.0, 42229.94425139373, 42391.0, 42417.515386961386, 42473.78763106188, 8040.356771458299, 9241.676541321056, 9322.533873306342, 9357.251419886426, 9412.81174376512, 9211.585478653476, 9267.165955064043, 9287.164155226028, 9300.720978370626, 9303.72088837336, 9123.0, 9145.268378529734, 9250.953745231265, 9319.347645664806, 9340.206082482997, 9129.908700913038, 9255.53722313885, 9319.953400233024, 9327.533623318875, 9331.346805723602, 9189.81620367589, 9290.767730806716, 9296.581653825526, 9299.628014879398, 9300.8604870927, 39575.01062473432, 39740.20519589621, 39746.0, 39928.0, 39996.60003399958, 2912.3156058326276, 3265.0, 3512.473129030666, 3741.625837416264, 3887.8639247626197, 28448.857755711295, 29860.0, 30166.69833301683, 30884.382312353628, 30988.22529436782, 3197.6962188592047, 3201.3597280543986, 3212.61448626164, 3238.983805080983, 3280.212748940261, 476936.0, 477483.0, 478674.0, 478810.605946975, 479811.0, 48898.0, 51663.48336516662, 59262.0, 59964.0, 60100.39899601036, 45520.77239613781, 62492.68753656203, 63050.0542491863, 64101.35898641047, 64799.67600162063, 14191.503297384543, 16282.88322518065, 16327.67344653124, 16391.508254752378, 16391.754123688155, 12467.438965246585, 12513.49946002168, 12546.121771476091, 12567.120301578801, 12587.992960563133, 15560.844391556166, 16266.75599866003, 16360.33730386834, 17119.65760684779, 17366.39217627377, 3127.6715944825464, 3199.8240096794434, 5590.630295577609, 6151.138840562325, 6436.8390790230615, 844.4257904624791, 972.5429048347265, 990.7813389530918, 1012.6152062216458, 1051.747580580655, 30224.0, 30990.69009309923, 31520.684793152235, 32961.0, 33477.163070922936, 1008.2135933971514, 1032.3547782635853, 1036.1658864613908, 1128.3737525673166, 1163.9989608936307, 15531.0, 16654.75017874733, 16787.580310492336, 16874.73439492043, 16923.492295231164, 701712.0, 704248.0, 706303.0, 707669.0, 710239.0, 59215.70392148012, 59538.80922381528, 59566.70216648889, 59566.70216648889, 59848.70075649594, 1311.344327836082, 1353.8510763815973, 1365.904386692922, 1368.3431952662777, 1369.459063669845, 30002.099937001924, 30180.245493861952, 32013.719451221692, 32068.877589284264, 32635.510467343483, 56512.71743641255, 56589.86820263654, 57350.42649573618, 57456.71271643615, 57533.71233143807, 663783.0, 668151.0, 669183.0, 671257.0, 672366.0, 41947.790261048496, 42276.78861605672, 42369.78815106028, 42866.57133428626, 42954.35568466538, 39788.0, 45946.08107837891, 46190.30714539217, 46474.0, 46940.765296173304, 38610.0, 38972.0, 39103.022424439616, 39911.40132898067, 40615.79692101521, 38631.227375452894, 38895.611043890334, 39023.80488097541, 39445.01387465336, 40190.99522511961, 37996.81001594974, 38340.80829595834, 38541.0, 38583.80708096441, 38898.61101389063, 32926.34147317088, 33843.66156338405, 34414.655853441145, 34602.82698586491, 34813.30373392467, 20603.381898543066, 25678.716064197088, 25728.61407078858, 25746.227613171635, 26145.215643530726], "value": 20016.986621520795, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.6966842163102, 335.1804837173111, 373.14735828631564, 380.70495366091257, 387.58721961111416, 19214.519637009063, 19883.0, 20009.499762505944, 20696.48258793531, 20957.266495672655, 19859.702104468408, 19915.60168796623, 20197.495062623442, 20353.694694579593, 20432.591348173028, 5565.359983601877, 5754.539636829044, 5756.740946657411, 5999.1301261317085, 6022.578419510636, 5833.241678581779, 5997.310309314441, 6009.87980240395, 6011.398860113997, 6015.127806468059, 5682.801101961432, 5846.386129456409, 5905.379935106816, 5947.4052594740615, 5960.314563825173, 5827.796027139051, 5869.50109240715, 5870.148828419855, 5890.352061273256, 5897.439743224387, 5001.8499445016705, 5184.377874655065, 5287.4976877196605, 5337.813176538842, 5380.408155102975, 12983.02627302956, 13190.802137967938, 13305.933470332684, 21509.247176348752, 23858.926348314366, 2114.5242320477923, 2244.4164517225477, 2367.135995361698, 2419.794317483016, 2746.6566679165103, 8554.786130346729, 10197.133243674296, 10249.538770755335, 13399.129056611386, 14368.281585920662, 1596.8962017468943, 1763.761892144557, 1835.9632807343912, 1867.561123136062, 1877.558773688182, 104117.0, 115648.0, 115743.42128289389, 115923.0, 117269.0, 11318.490667919963, 11361.602343917923, 11424.714382140513, 11575.958163765301, 11737.237079589799, 10548.419836909045, 14456.783148252785, 14469.204193769443, 14488.637784055483, 14920.253987300808, 8279.793005174799, 8350.874736878952, 8375.482894579627, 8378.204070613281, 8458.323334133378, 6918.0, 8261.669533218725, 8273.669053237923, 8344.874826877602, 8396.874046889301, 4365.454318210223, 4486.551344865487, 4500.099980004013, 4536.251518499476, 4549.840755573605, 1649.5463747469541, 1879.5676994291389, 1939.2727727102338, 1989.711491833676, 2003.2087325506634, 812.3914648994905, 957.1481381570269, 983.1888691829126, 997.2869398380193, 1022.7852151048214, 20433.386998390073, 20859.1656333748, 21004.26485073046, 21039.15843366279, 21186.682199767016, 861.2507118806678, 943.9292053095908, 961.6153538584482, 967.1634036558327, 976.7753416714196, 10893.564257429773, 11169.162312826571, 11175.552977880956, 11195.440227988567, 11247.718807029889, 611778.0, 613441.0, 614121.0, 625150.0, 626155.0, 131209.0, 134622.0, 134903.32548337197, 139239.0, 139466.0, 957.7414098193449, 966.0629189685993, 981.651513712636, 984.4339504784778, 986.0583143098324, 18227.0, 18451.800632958813, 18837.6232475347, 18901.44830447872, 19162.23351065942, 43638.909027274574, 56340.87318253694, 56677.43322566721, 57388.71305643445, 57926.7103664479, 64031.71936561336, 78239.0, 79117.0, 80749.0, 81977.59011204906, 7181.964090179516, 7990.400719946144, 8041.155678653658, 8078.071021832329, 8167.877481837896, 9120.179183873483, 9504.524773761146, 10068.848967265643, 11543.538258469567, 12324.50701971911, 10430.58277668915, 10562.630307939186, 10753.032227099598, 10885.673429797118, 10989.175811813986, 10591.152707783205, 10682.092022178202, 10719.464026798785, 10908.890911091104, 10994.945025274823, 10740.946295268473, 10767.946160269148, 11020.0, 11348.602798901999, 11350.978411942964, 8014.759557213293, 8020.518768873886, 8185.836283274182, 8190.7952301192945, 8194.631241594023, 9232.169104780602, 10812.026917577456, 11063.0, 11097.889021109686, 11127.332360058423], "value": 9367.457493903257, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1401.5444980381376, 1440.7766796146598, 1580.5100418870152, 1724.2240991553804, 1730.3251731824582, 6952.930470695292, 7810.882836757453, 8043.396745244108, 8511.574421278934, 8552.91447085529, 7075.575465472062, 8045.718399856006, 8313.542755148479, 8355.122712115231, 8415.873761893561, 2289.9656505152434, 2298.482841360693, 2322.756110608387, 2324.2562380038394, 2330.0, 2343.695319608457, 2356.7878890899815, 2389.426537630965, 2390.964135537968, 2392.102961389479, 2339.181286549711, 2352.094443639203, 2357.8467399619058, 2362.83460157789, 2364.586197415454, 2306.5271619318, 2321.3152120124605, 2350.3066595354326, 2353.0234952494693, 2358.3514533503253, 2309.399556115405, 2363.0, 2365.2431222008963, 2371.822113341489, 2376.9558179965215, 8427.789305267355, 8463.0, 8493.745187644381, 8497.787555311104, 8503.532305723184, 4852.951470485285, 5214.322138122057, 5218.217267409881, 5221.008008478377, 5235.947640523584, 3339.482380231068, 3548.7338449616122, 3628.2743451309843, 3636.30910127075, 3657.7805331680183, 1030.7268573827942, 1047.4919663962971, 1088.6733979806106, 1089.2484185911696, 1094.5402930769096, 10353.171746260281, 10968.725781855437, 11085.002349788478, 11086.778264434746, 11098.778024439465, 4517.164324599959, 4628.490866004737, 4712.976435117803, 4713.128071306751, 4777.283407488835, 9406.05939406054, 9480.620775169054, 9487.857682134774, 9489.952550237205, 9502.619895204114, 4954.20732682769, 5225.608079393985, 5228.712420816815, 5237.319148510669, 5245.449227831023, 4297.484301883794, 4315.244832154361, 4733.692309999908, 4787.018661174451, 4824.589909857631, 3189.696978787058, 3296.78570892896, 3784.2242340320167, 3938.980305098456, 3973.0663294125857, 1265.6203139058246, 1279.7504486625205, 1282.2819221235952, 1290.9289989050505, 1293.8253335799548, 839.4837175137246, 890.3189060368823, 894.4365050018447, 897.3001059173855, 925.5233554719325, 5694.743736531783, 5759.971200143972, 5794.449527294901, 5839.941600584025, 5871.471567558855, 786.0999155966387, 927.4806108579218, 943.6744323208482, 950.6054987180381, 971.0726256425097, 3011.533212352045, 3796.1079146400575, 3865.594112618191, 3907.779200015922, 3909.4917660704496, 9977.750556236153, 10326.586936522604, 10358.53386597605, 10374.27380083387, 10393.324433911772, 10158.746031349276, 10294.691159265234, 10324.483775811179, 10348.379097254188, 10376.740581485372, 7837.412194085467, 7884.881726774103, 7933.087694915159, 7934.920650793533, 7940.245676660708, 5337.519623233772, 5479.6164268500825, 5518.806841760519, 5610.158476228598, 5666.376698563073, 9401.247900168108, 9449.858252126087, 9548.045195480674, 9558.235341172829, 9569.521523923915, 7810.726624568113, 9042.63829446841, 9463.148316651534, 9464.905350946403, 9471.337006409762, 6631.834204144935, 6835.58986460814, 7045.823854403681, 7183.964080179566, 7347.595882226529, 7803.648835802289, 7958.681652734057, 7961.16407777175, 8059.0, 8075.232852878967, 7074.9292507076325, 7204.819879503054, 7210.783676489504, 7226.674799634135, 7328.633568321456, 6965.6168910708375, 7043.612601306977, 7112.253213412517, 7154.248803875518, 7220.350168484862, 6613.503987201076, 6723.125993620798, 6748.460123190232, 6761.290064543349, 6774.729010839708, 4857.635677324144, 4905.558499735041, 4923.926141107814, 4925.16272233714, 4951.455339912679, 4232.751338355254, 4905.4358748742925, 4906.779194936308, 4987.401511818605, 4987.451380348231], "value": 4440.364834641402, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [256.29135440506997, 300.9202561321252, 306.7622592490821, 312.7373006674394, 316.5062502496106, 4730.881727956803, 4922.6308026898, 5098.852758129419, 5435.619506634537, 5578.888422231553, 4634.420697412824, 5100.225832883667, 5389.434109418504, 5492.203630473579, 5494.725263736817, 4195.538490766014, 4197.874063778077, 4203.348480985444, 4207.727826973431, 4212.220739163265, 3883.8058097095172, 4146.740026685448, 4148.8511488511485, 4191.979040104795, 4197.027397123363, 3269.1173383186524, 3796.8481260749536, 4077.9397356687327, 4124.154548317588, 4146.8963275918195, 3289.0626171541235, 3575.049570224903, 3709.443583462475, 3771.774173393658, 4094.467719196515, 2762.6408566886175, 3024.6975302469577, 3395.711364534018, 3555.4489054196642, 3556.697680697118, 4110.828413902053, 5000.449950505442, 5024.919642276928, 5288.629795914288, 5340.839774806723, 3548.1484443733575, 4293.883590266522, 4550.726956382595, 4686.461056978457, 4688.835890743808, 5247.1866860636665, 5744.6840423776675, 5746.798862039808, 5768.913466298009, 5794.435502414324, 3598.1934091408743, 3797.525309336333, 3845.5000849889616, 3864.4589757433982, 3943.363504145776, 6009.519238460913, 6018.886505996361, 6069.575129740876, 6070.150178974991, 6087.0, 3856.325143099946, 3940.7438516496336, 3940.763554186758, 3980.980095099564, 3982.9885907616467, 3131.765117616188, 3320.684534969174, 3994.6005399459823, 4001.8599349023225, 4008.7594744315434, 4665.6967297125575, 4675.625949923998, 4679.64902632304, 4702.882427939329, 4728.0, 5497.39528651848, 5524.668519888739, 5612.831615051555, 5804.825855224266, 5817.0983497557945, 1143.822707480342, 1144.8626164860104, 1240.8262843201958, 1244.2410129820803, 1310.5478609879576, 842.8368850985723, 884.6594061286451, 886.7295474880224, 887.4098724348365, 890.6660002499062, 4821.613301415751, 4821.614270858323, 4861.781219845116, 5178.352705911761, 5213.175388613955, 4846.764075160882, 5003.649744517922, 5547.55619550443, 5603.954556586817, 5625.971870140705, 4459.910801783946, 4894.1679914414635, 5115.93522412816, 5173.405058418255, 5174.637775355689, 2875.4967880620807, 2955.3645966117388, 3034.863431145604, 3047.481928072233, 3047.9789270594156, 4955.4548999610015, 5048.798048078036, 5161.761177317417, 5678.365845858654, 5928.695686948864, 5066.945000724099, 5234.476552344735, 5750.2088187453, 5760.8271751847515, 5805.303363596396, 4859.97940432506, 5158.226266060196, 5628.5084452620085, 5854.941450585439, 5868.882622347614, 5257.237700533325, 5353.78584856617, 5616.7191640416995, 5630.493255607015, 5679.835633695166, 5166.535011848951, 5540.537164272329, 5552.944470555242, 5592.888142237051, 5632.88734225305, 5030.8239211627415, 5284.735763211747, 5388.461153884737, 5845.269341332333, 5851.64890106595, 3713.387291096939, 3736.0, 3738.0, 3746.23202243534, 3750.6122734588375, 5583.55331573465, 5730.968425683417, 5770.2498675171955, 6061.696915154313, 6070.939290607037, 5156.9742151289, 5741.25363702716, 5770.798022069207, 5885.616880033274, 5915.704214789157, 5580.609357344947, 5837.416258374298, 5843.561732870136, 5858.949367721263, 5864.270040338025, 5032.295478633067, 5474.425185355481, 5658.745356458887, 5663.57523185771, 5710.114932185518, 5135.7432128394175, 5361.329833770778, 5422.783088676409, 5833.562482813891, 5853.912191317048, 5101.0, 5567.137093750475, 5630.915536267041, 5674.517665998271, 5680.318361796611], "value": 4080.573242935574, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [738.8226825561864, 741.614360532523, 744.6760659113282, 765.7511308824631, 807.5477732469819, 10465.267431279814, 10801.0, 12165.45255463504, 13326.534081251068, 14173.92913035436, 12240.714724953885, 12553.623391298275, 13478.932605336984, 13499.527516536924, 13505.864941350583, 796.956167410795, 811.6185392865359, 812.8943237379162, 848.2916764501648, 855.4396870050127, 718.619131860115, 844.7761343244046, 851.6763629820659, 853.4623187391966, 857.7641148684099, 874.6020560644923, 877.1754550722333, 882.6557642519423, 883.9381243312971, 885.8051228729669, 877.1666916429417, 880.0495464897926, 880.6829541365136, 882.386741214856, 884.3588398411165, 632.6457183976942, 655.9180102487189, 656.1896058367912, 657.3098246840798, 657.4444594317841, 14397.64005899861, 14421.0, 14466.0, 14481.56555303332, 14492.20292883891, 10141.746456338577, 12790.168639038526, 12819.16675416095, 13264.469421223139, 13569.932150339284, 12025.759484810254, 12086.33525156116, 12304.753904921941, 12359.505619775198, 12522.436490357864, 4361.105973275471, 4370.978145109254, 4384.473863136413, 4406.096750166208, 4546.227141385973, 85537.0, 85968.14031859726, 87220.99999999936, 88259.55870220608, 88263.11736882613, 10176.949115254525, 10217.489125543692, 10313.793724125475, 10353.741156470998, 10450.268481206243, 18886.0, 18899.24403023891, 18926.52683682919, 19032.0, 19056.284934355695, 7229.457790665721, 7240.746573869889, 7381.88927166093, 7419.183889772121, 7578.886316705254, 4189.664826813848, 4227.492700875853, 4270.978645106755, 4272.124214536013, 4273.673969801349, 3255.9023229303157, 3311.966880331166, 3364.3944090063533, 3366.7138293244857, 3367.1582104473882, 512.8794733237686, 617.299365220478, 647.0100745858844, 651.8891788395985, 652.9706163222667, 601.3145014683265, 614.4820900345575, 655.8327626455224, 669.8928171492538, 673.2795908377989, 8833.779155521164, 8935.642574296955, 8960.327975401871, 9090.36367454285, 9229.907700923039, 568.3350479938482, 669.8794217040979, 672.6468603982851, 684.305429988562, 696.2758730919787, 4387.42963414754, 4429.09203613265, 4435.534268901783, 4523.274111801116, 4550.453945526492, 58284.70857645685, 84662.46012619631, 88507.55746221356, 98881.0, 112444.43777781058, 78208.0, 81307.0, 85127.0, 101943.49028254811, 104644.47677761715, 75898.86151707618, 89965.10034899568, 91076.0, 91135.63296550557, 92502.14995700183, 8368.661014237692, 8564.400491965753, 8999.730008099768, 9798.461084640128, 9832.803343932937, 43393.698189054376, 48175.59122043814, 50844.0, 51325.0, 55238.17142742782, 66173.0, 68712.6564367175, 71463.0, 71916.0, 71980.0, 11932.582359617372, 12204.145709800583, 12411.75176496483, 12437.315947622968, 12814.55149069778, 13876.236806975714, 13974.441022358991, 14185.858141418454, 14760.48338308154, 14833.554993350217, 13200.94392448621, 13244.867551324749, 13643.044986850826, 13737.450501979809, 13785.51750688721, 12146.028317734383, 12701.047421443243, 12850.0, 12998.805017924928, 13359.732805344032, 11381.829272561085, 12931.612051638465, 13144.816966473063, 13316.533921312708, 13438.664033399244, 10431.321964072311, 10652.307600005975, 11071.225014249247, 11093.223474356717, 11167.776644466903, 11095.72260693489, 11267.436628168723, 11273.778302190762, 11454.770904581696, 11483.942580287045], "value": 7225.907313691411, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1523.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 118.0, 140.0, 171.0, 151.0, 116.0, 117.0, 118.0, 118.0, 128.0], "value": 129.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 111.0, 114.0, 146.0, 115.0, 117.0, 111.0, 114.0, 147.0, 118.0], "value": 124.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 198.0, 192.0, 194.0, 193.0, 197.0, 194.0, 195.0, 196.0, 194.0], "value": 194.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 60.0, 62.0, 108.0, 62.0, 63.0, 87.0, 50.0, 69.0, 58.0], "value": 69.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 70.0, 102.0, 60.0, 93.0, 61.0, 85.0, 86.0, 100.0, 120.0], "value": 85.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 90.0, 87.0, 91.0, 86.0, 90.0, 90.0, 90.0, 86.0], "value": 88.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 99.0, 96.0, 94.0, 150.0, 150.0, 98.0, 95.0, 95.0, 152.0], "value": 112.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 98.0, 92.0, 88.0, 90.0, 90.0, 93.0, 93.0, 88.0, 89.0], "value": 90.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 57.0, 60.0, 61.0, 60.0, 60.0, 60.0, 60.0, 61.0], "value": 59.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 89.0, 72.0, 77.0, 109.0, 74.0, 60.0, 61.0, 95.0, 101.0], "value": 81.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 85.0, 86.0, 83.0, 87.0, 85.0, 86.0, 95.0, 92.0], "value": 86.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 158.0, 154.0, 159.0, 161.0, 145.0, 142.0, 159.0, 223.0, 180.0], "value": 163.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 173.0, 168.0, 166.0, 169.0, 170.0, 168.0, 171.0, 169.0, 162.0], "value": 168.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 70.0, 68.0, 70.0, 72.0, 70.0, 66.0, 69.0, 67.0, 68.0], "value": 69.2, "unit": "score"}]}]} 08:28:06 INFO - Return code: 0 08:28:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:28:06 ERROR - # TBPL SUCCESS # 08:28:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:28:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:28:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:28:06 INFO - 2016-12-31 08:28:06.879 screenresolution[1659:12251] starting screenresolution argv=screenresolution get 08:28:06 INFO - 2016-12-31 08:28:06.883 screenresolution[1659:12251] Display 0: 1600x1200x32@60 08:28:06 INFO - 2016-12-31 08:28:06.889 screenresolution[1660:12254] starting screenresolution argv=screenresolution list 08:28:06 INFO - Available Modes on Display 0 08:28:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:28:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:28:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:28:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:28:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:28:07 INFO - Intel Iris: 08:28:07 INFO - Chipset Model: Intel Iris 08:28:07 INFO - Type: GPU 08:28:07 INFO - Bus: Built-In 08:28:07 INFO - VRAM (Dynamic, Max): 1536 MB 08:28:07 INFO - Vendor: Intel (0x8086) 08:28:07 INFO - Device ID: 0x0a2e 08:28:07 INFO - Revision ID: 0x0009 08:28:07 INFO - Displays: 08:28:07 INFO - Display: 08:28:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:28:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:28:07 INFO - Display Serial Number: 1600x1200 60 08:28:07 INFO - Main Display: Yes 08:28:07 INFO - Mirror: Off 08:28:07 INFO - Online: Yes 08:28:07 INFO - Rotation: Supported 08:28:07 INFO - Return code: 0 08:28:07 INFO - Running post-action listener: _resource_record_post_action 08:28:07 INFO - [mozharness: 2016-12-31 16:28:07.129586Z] Finished run-tests step (success) 08:28:07 INFO - Running post-run listener: _resource_record_post_run 08:28:07 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 34238976; Write bytes: 659395584; Read time: 322; Write time: 2124 08:28:07 INFO - TinderboxPrint: CPU usage
54.5% 08:28:07 INFO - TinderboxPrint: I/O read bytes / time
34,238,976 / 322 08:28:07 INFO - TinderboxPrint: I/O write bytes / time
659,395,584 / 2,124 08:28:07 INFO - TinderboxPrint: CPU idle
2,202.6 (45.5%) 08:28:07 INFO - TinderboxPrint: CPU system
977.1 (20.2%) 08:28:07 INFO - TinderboxPrint: CPU user
1,657.9 (34.3%) 08:28:07 INFO - TinderboxPrint: Swap in / out
379,580,416 / 0 08:28:07 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 346247680; Write bytes: 346802176; Read time: 26891; Write time: 674 08:28:07 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 32158208; Write bytes: 305445888; Read time: 300; Write time: 1422 08:28:07 INFO - Running post-run listener: _upload_blobber_files 08:28:07 INFO - Blob upload gear active. 08:28:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:28:07 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 08:28:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:28:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:28:08 INFO - (blobuploader) - INFO - Open directory for files ... 08:28:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:28:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:28:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:28:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:28:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:28:09 INFO - (blobuploader) - INFO - Done attempting. 08:28:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:28:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:28:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:28:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:28:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:28:10 INFO - (blobuploader) - INFO - Done attempting. 08:28:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:28:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:28:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:28:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:28:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:28:12 INFO - (blobuploader) - INFO - Done attempting. 08:28:12 INFO - (blobuploader) - INFO - Iteration through files over. 08:28:12 INFO - Return code: 0 08:28:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:28:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:28:12 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f72383ebf01d0bacf03fdceb6a4ae57fd7a7571947bcb5060b9cfbdf89fe40809e538026757c2479a7d7ff77df812fb26f5d03ab124a37840dafcaf6d1b09fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b683d703dbefa1116af0dd12eead6010a77dfe1711d38b8f6709d77eea6c3514d67c3c7f35e7edd2a3a4f1035a721be71eb7075eeac7ac559b9863dc31e68d00", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b742a6ce13fa3147b524e8982d0ebef7e5d6f5ae45f8da6e91d68c1607c32e10b07263ff69c916bbe77eb4226ee808c8352139900fc7d07e58b5e6ef5bb42e75"} 08:28:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:28:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:28:12 INFO - Contents: 08:28:12 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f72383ebf01d0bacf03fdceb6a4ae57fd7a7571947bcb5060b9cfbdf89fe40809e538026757c2479a7d7ff77df812fb26f5d03ab124a37840dafcaf6d1b09fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b683d703dbefa1116af0dd12eead6010a77dfe1711d38b8f6709d77eea6c3514d67c3c7f35e7edd2a3a4f1035a721be71eb7075eeac7ac559b9863dc31e68d00", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b742a6ce13fa3147b524e8982d0ebef7e5d6f5ae45f8da6e91d68c1607c32e10b07263ff69c916bbe77eb4226ee808c8352139900fc7d07e58b5e6ef5bb42e75"} 08:28:12 INFO - Running post-run listener: copy_logs_to_upload_dir 08:28:12 INFO - Copying logs to upload dir... 08:28:12 INFO - mkdir: /builds/slave/test/build/upload/logs 08:28:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.728578 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-12-31 08:28:12.409944) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-31 08:28:12.418701) ========= 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.2amGtLwCi3/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.HP51ICGU96/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f72383ebf01d0bacf03fdceb6a4ae57fd7a7571947bcb5060b9cfbdf89fe40809e538026757c2479a7d7ff77df812fb26f5d03ab124a37840dafcaf6d1b09fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b683d703dbefa1116af0dd12eead6010a77dfe1711d38b8f6709d77eea6c3514d67c3c7f35e7edd2a3a4f1035a721be71eb7075eeac7ac559b9863dc31e68d00", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b742a6ce13fa3147b524e8982d0ebef7e5d6f5ae45f8da6e91d68c1607c32e10b07263ff69c916bbe77eb4226ee808c8352139900fc7d07e58b5e6ef5bb42e75"} build_url:https://queue.taskcluster.net/v1/task/EuGe-kXjQsqs8C7jN9kUmA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010805 build_url: 'https://queue.taskcluster.net/v1/task/EuGe-kXjQsqs8C7jN9kUmA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f72383ebf01d0bacf03fdceb6a4ae57fd7a7571947bcb5060b9cfbdf89fe40809e538026757c2479a7d7ff77df812fb26f5d03ab124a37840dafcaf6d1b09fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b683d703dbefa1116af0dd12eead6010a77dfe1711d38b8f6709d77eea6c3514d67c3c7f35e7edd2a3a4f1035a721be71eb7075eeac7ac559b9863dc31e68d00", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b742a6ce13fa3147b524e8982d0ebef7e5d6f5ae45f8da6e91d68c1607c32e10b07263ff69c916bbe77eb4226ee808c8352139900fc7d07e58b5e6ef5bb42e75"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-31 08:28:12.453945) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-31 08:28:12.454283) ========= 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.2amGtLwCi3/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.HP51ICGU96/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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.005234 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-31 08:28:12.507181) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-31 08:28:12.507533) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-31 08:28:12.507867) ========= ========= Total master_lag: 0.21 =========