builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0206 starttime: 1480001098.03 results: success (0) buildid: 20161124050619 builduid: 8df6fc0f8c944eedad569230d5879ce5 revision: 233a0890b3b35d778b2a50f1d7b064a8bcb82fb1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-24 07:24:58.029278) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-24 07:24:58.029990) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-24 07:24:58.030417) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-24 07:24:58.056964) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 07:24:58.057350) ========= 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.HifDsG6fy6/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.YaAJPeofdi/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-24 07:24:57-- 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% 46.3M=0s 2016-11-24 07:24:58 (46.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.095492 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 07:24:58.176468) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 07:24:58.176776) ========= 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.HifDsG6fy6/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.YaAJPeofdi/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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.025165 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 07:24:58.224827) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-24 07:24:58.225189) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 233a0890b3b35d778b2a50f1d7b064a8bcb82fb1 --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 233a0890b3b35d778b2a50f1d7b064a8bcb82fb1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HifDsG6fy6/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.YaAJPeofdi/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-24 07:24:58,224 truncating revision to first 12 chars 2016-11-24 07:24:58,224 Setting DEBUG logging. 2016-11-24 07:24:58,224 attempt 1/10 2016-11-24 07:24:58,224 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/233a0890b3b3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-24 07:24:59,270 unpacking tar archive at: mozilla-aurora-233a0890b3b3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.331743 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-24 07:24:59.579990) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-24 07:24:59.580309) ========= script_repo_revision: 233a0890b3b35d778b2a50f1d7b064a8bcb82fb1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-24 07:24:59.580694) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-24 07:24:59.580958) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-24 07:24:59.600491) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-24 07:24:59.600854) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.HifDsG6fy6/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.YaAJPeofdi/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 07:24:59 INFO - MultiFileLogger online at 20161124 07:24:59 in /builds/slave/test 07:24:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 07:24:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:24:59 INFO - {'append_to_log': False, 07:24:59 INFO - 'base_work_dir': '/builds/slave/test', 07:24:59 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:24:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:24:59 INFO - 'branch': 'Mozilla-Aurora', 07:24:59 INFO - 'buildbot_json_path': 'buildprops.json', 07:24:59 INFO - 'config_files': ('talos/mac_config.py',), 07:24:59 INFO - 'default_actions': ('clobber', 07:24:59 INFO - 'read-buildbot-config', 07:24:59 INFO - 'download-and-extract', 07:24:59 INFO - 'populate-webroot', 07:24:59 INFO - 'create-virtualenv', 07:24:59 INFO - 'install', 07:24:59 INFO - 'run-tests'), 07:24:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:24:59 INFO - 'download_minidump_stackwalk': True, 07:24:59 INFO - 'download_symbols': 'ondemand', 07:24:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:24:59 INFO - 'tooltool.py': '/tools/tooltool.py', 07:24:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:24:59 INFO - '/tools/misc-python/virtualenv.py')}, 07:24:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:24:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:24:59 INFO - 'installer_path': 'installer.exe', 07:24:59 INFO - 'log_level': 'info', 07:24:59 INFO - 'log_name': 'talos', 07:24:59 INFO - 'log_to_console': True, 07:24:59 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:24:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:24:59 INFO - 'opt_config_files': (), 07:24:59 INFO - 'pip_index': False, 07:24:59 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:24:59 INFO - 'cmd': ('bash', 07:24:59 INFO - '-c', 07:24:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:24:59 INFO - 'enabled': True, 07:24:59 INFO - 'halt_on_failure': False, 07:24:59 INFO - 'name': 'check_screen_resolution'},), 07:24:59 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:24:59 INFO - 'cmd': ('bash', 07:24:59 INFO - '-c', 07:24:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:24:59 INFO - 'enabled': True, 07:24:59 INFO - 'halt_on_failure': False, 07:24:59 INFO - 'name': 'check_screen_resolution'},), 07:24:59 INFO - 'run_cmd_checks_enabled': True, 07:24:59 INFO - 'sps_profile': False, 07:24:59 INFO - 'sps_profile_interval': 0, 07:24:59 INFO - 'suite': 'dromaeojs', 07:24:59 INFO - 'system_bits': '32', 07:24:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:24:59 INFO - 'title': 't-yosemite-r7-0206', 07:24:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:24:59 INFO - 'use_talos_json': True, 07:24:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:24:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:24:59 INFO - 'work_dir': 'build'} 07:24:59 INFO - [mozharness: 2016-11-24 15:24:59.706042Z] Running clobber step. 07:24:59 INFO - Running pre-action listener: _resource_record_pre_action 07:24:59 INFO - Running main action method: clobber 07:24:59 INFO - rmtree: /builds/slave/test/build 07:24:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:25:02 INFO - Running post-action listener: _resource_record_post_action 07:25:02 INFO - [mozharness: 2016-11-24 15:25:02.124990Z] Finished clobber step (success) 07:25:02 INFO - [mozharness: 2016-11-24 15:25:02.125128Z] Running read-buildbot-config step. 07:25:02 INFO - Running pre-action listener: _resource_record_pre_action 07:25:02 INFO - Running main action method: read_buildbot_config 07:25:02 INFO - Using buildbot properties: 07:25:02 INFO - { 07:25:02 INFO - "project": "", 07:25:02 INFO - "product": "firefox", 07:25:02 INFO - "script_repo_revision": "production", 07:25:02 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 07:25:02 INFO - "repository": "", 07:25:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 07:25:02 INFO - "buildid": "20161124050619", 07:25:02 INFO - "pgo_build": "False", 07:25:02 INFO - "basedir": "/builds/slave/test", 07:25:02 INFO - "buildnumber": 33, 07:25:02 INFO - "slavename": "t-yosemite-r7-0206", 07:25:02 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 07:25:02 INFO - "platform": "yosemite_r7", 07:25:02 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 07:25:02 INFO - "branch": "mozilla-aurora", 07:25:02 INFO - "repo_path": "releases/mozilla-aurora", 07:25:02 INFO - "revision": "233a0890b3b35d778b2a50f1d7b064a8bcb82fb1", 07:25:02 INFO - "stage_platform": "macosx64", 07:25:02 INFO - "builduid": "8df6fc0f8c944eedad569230d5879ce5", 07:25:02 INFO - "slavebuilddir": "test" 07:25:02 INFO - } 07:25:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/XYPchGGWQl28pRk0TOHaPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 07:25:02 INFO - Running post-action listener: _resource_record_post_action 07:25:02 INFO - [mozharness: 2016-11-24 15:25:02.128249Z] Finished read-buildbot-config step (success) 07:25:02 INFO - [mozharness: 2016-11-24 15:25:02.128363Z] Running download-and-extract step. 07:25:02 INFO - Running pre-action listener: _resource_record_pre_action 07:25:02 INFO - Running main action method: download_and_extract 07:25:02 INFO - mkdir: /builds/slave/test/build/tests 07:25:02 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')]} 07:25:02 INFO - trying https://queue.taskcluster.net/v1/task/XYPchGGWQl28pRk0TOHaPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 07:25:02 INFO - Downloading https://queue.taskcluster.net/v1/task/XYPchGGWQl28pRk0TOHaPQ/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 07:25:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XYPchGGWQl28pRk0TOHaPQ/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 07:25:03 INFO - Downloaded 1199 bytes. 07:25:03 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 07:25:03 INFO - Using the following test package requirements: 07:25:03 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 07:25:03 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:25:03 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 07:25:03 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:25:03 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 07:25:03 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:25:03 INFO - u'jsshell-mac.zip'], 07:25:03 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:25:03 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 07:25:03 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 07:25:03 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:25:03 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 07:25:03 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:25:03 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 07:25:03 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:25:03 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 07:25:03 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:25:03 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 07:25:03 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 07:25:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XYPchGGWQl28pRk0TOHaPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 07:25:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XYPchGGWQl28pRk0TOHaPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:25:03 INFO - Fetch https://queue.taskcluster.net/v1/task/XYPchGGWQl28pRk0TOHaPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 07:25:03 INFO - Http code: 200 07:25:03 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 07:25:03 INFO - x-amz-cf-id: fUkTJptULbSKWMqD0pEM6O5ghKimq0swPWESsd3GHmVSdHJzrD6K7g== 07:25:03 INFO - x-amz-version-id: gNw46dZME_Tw284Rc5v8cvpMmQeH1DBz 07:25:04 INFO - Expected file size: 16279015 07:25:04 INFO - Obtained file size: 16279015 07:25:10 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 07:25:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XYPchGGWQl28pRk0TOHaPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 07:25:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XYPchGGWQl28pRk0TOHaPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:25:10 INFO - Fetch https://queue.taskcluster.net/v1/task/XYPchGGWQl28pRk0TOHaPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 07:25:11 INFO - Http code: 200 07:25:11 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 07:25:11 INFO - x-amz-cf-id: Um37xy-i4IrCQGraylycAqkNpqnzsXtfgHY1PzGFZkQEJwaJyi8mGA== 07:25:11 INFO - x-amz-version-id: gNw46dZME_Tw284Rc5v8cvpMmQeH1DBz 07:25:11 INFO - Expected file size: 16279015 07:25:11 INFO - Obtained file size: 16279015 07:25:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XYPchGGWQl28pRk0TOHaPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 07:25:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XYPchGGWQl28pRk0TOHaPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 07:25:17 INFO - Fetch https://queue.taskcluster.net/v1/task/XYPchGGWQl28pRk0TOHaPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 07:25:18 INFO - Http code: 200 07:25:18 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 07:25:18 INFO - x-amz-cf-id: sfLVnt7SRJv9SA5g70pgmXuwsPl3NRs1SzC9EkEbN8eT8zaYcOGH-g== 07:25:18 INFO - x-amz-version-id: ldorKSg_fKDK54RwkBI4672ezYAUvC32 07:25:18 INFO - Expected file size: 14409038 07:25:18 INFO - Obtained file size: 14409038 07:25:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:25:19 INFO - trying https://queue.taskcluster.net/v1/task/XYPchGGWQl28pRk0TOHaPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:25:19 INFO - Downloading https://queue.taskcluster.net/v1/task/XYPchGGWQl28pRk0TOHaPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 07:25:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XYPchGGWQl28pRk0TOHaPQ/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 07:25:22 INFO - Downloaded 109704015 bytes. 07:25:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XYPchGGWQl28pRk0TOHaPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:25:22 INFO - mkdir: /builds/slave/test/properties 07:25:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:25:22 INFO - Writing to file /builds/slave/test/properties/build_url 07:25:22 INFO - Contents: 07:25:22 INFO - build_url:https://queue.taskcluster.net/v1/task/XYPchGGWQl28pRk0TOHaPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:25:22 INFO - Running post-action listener: _resource_record_post_action 07:25:22 INFO - Running post-action listener: set_extra_try_arguments 07:25:22 INFO - [mozharness: 2016-11-24 15:25:22.754328Z] Finished download-and-extract step (success) 07:25:22 INFO - [mozharness: 2016-11-24 15:25:22.754481Z] Running populate-webroot step. 07:25:22 INFO - Running pre-action listener: _resource_record_pre_action 07:25:22 INFO - Running main action method: populate_webroot 07:25:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:25:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:25:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:25:22 INFO - '--tppagecycles', 07:25:22 INFO - '7'], 07:25:22 INFO - 'tests': ['tsvgm']}}, 07:25:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:25:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:25:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:25:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:25:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:25:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:25:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:25:22 INFO - 'talos_options': ['--disable-e10s'], 07:25:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:25:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:25:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:25:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:25:22 INFO - 'talos_options': ['--disable-e10s'], 07:25:22 INFO - 'tests': ['damp', 'tps']}, 07:25:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:25:22 INFO - 'tests': ['damp', 'tps']}, 07:25:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:25:22 INFO - 'tests': ['dromaeo_dom']}, 07:25:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:25:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:25:22 INFO - 'tests': ['basic_compositor_video']}, 07:25:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:25:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:25:22 INFO - 'tests': ['a11yr', 07:25:22 INFO - 'ts_paint', 07:25:22 INFO - 'tpaint', 07:25:22 INFO - 'sessionrestore', 07:25:22 INFO - 'sessionrestore_no_auto_restore', 07:25:22 INFO - 'tabpaint']}, 07:25:22 INFO - 'other-e10s': {'tests': ['a11yr', 07:25:22 INFO - 'ts_paint', 07:25:22 INFO - 'tpaint', 07:25:22 INFO - 'sessionrestore', 07:25:22 INFO - 'sessionrestore_no_auto_restore', 07:25:22 INFO - 'tabpaint']}, 07:25:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:25:22 INFO - 'ts_paint', 07:25:22 INFO - 'tpaint', 07:25:22 INFO - 'sessionrestore', 07:25:22 INFO - 'sessionrestore_no_auto_restore', 07:25:22 INFO - 'tabpaint']}, 07:25:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:25:22 INFO - 'ts_paint', 07:25:22 INFO - 'tpaint', 07:25:22 INFO - 'sessionrestore', 07:25:22 INFO - 'sessionrestore_no_auto_restore', 07:25:22 INFO - 'tabpaint']}, 07:25:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:25:22 INFO - 'tests': ['a11yr', 07:25:22 INFO - 'ts_paint', 07:25:22 INFO - 'tpaint', 07:25:22 INFO - 'sessionrestore', 07:25:22 INFO - 'sessionrestore_no_auto_restore', 07:25:22 INFO - 'tabpaint']}, 07:25:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:25:22 INFO - 'tests': ['a11yr', 07:25:22 INFO - 'ts_paint', 07:25:22 INFO - 'tpaint', 07:25:22 INFO - 'sessionrestore', 07:25:22 INFO - 'sessionrestore_no_auto_restore', 07:25:22 INFO - 'tabpaint']}, 07:25:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:25:22 INFO - 'tests': ['tsvgx', 07:25:22 INFO - 'tsvgr_opacity', 07:25:22 INFO - 'tart', 07:25:22 INFO - 'tscrollx', 07:25:22 INFO - 'cart']}, 07:25:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:25:22 INFO - 'tsvgr_opacity', 07:25:22 INFO - 'tart', 07:25:22 INFO - 'tscrollx', 07:25:22 INFO - 'cart']}, 07:25:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:25:22 INFO - 'talos_options': ['--disable-e10s'], 07:25:22 INFO - 'tests': ['tp5o']}, 07:25:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:25:22 INFO - 'tests': ['tp5o']}, 07:25:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:25:22 INFO - 'talos_options': ['--disable-e10s', 07:25:22 INFO - '--xperf_path', 07:25:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:25:22 INFO - 'tests': ['tp5n']}, 07:25:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:25:22 INFO - 'talos_options': ['--xperf_path', 07:25:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:25:22 INFO - 'tests': ['tp5n']}}, 07:25:22 INFO - 'talos.zip': {'path': '', 07:25:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:25:22 INFO - Running post-action listener: _resource_record_post_action 07:25:22 INFO - [mozharness: 2016-11-24 15:25:22.773683Z] Finished populate-webroot step (success) 07:25:22 INFO - [mozharness: 2016-11-24 15:25:22.773814Z] Running create-virtualenv step. 07:25:22 INFO - Running pre-action listener: _resource_record_pre_action 07:25:22 INFO - Running main action method: create_virtualenv 07:25:22 INFO - Creating virtualenv /builds/slave/test/build/venv 07:25:22 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 07:25:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:25:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:25:22 INFO - Using real prefix '/tools/python27' 07:25:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:25:23 INFO - Installing distribute.............................................................................................................................................................................................done. 07:25:26 INFO - Installing pip.................done. 07:25:26 INFO - Return code: 0 07:25:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:25:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:25:26 INFO - Reading from file tmpfile_stdout 07:25:26 INFO - Output received: 07:25:26 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) 07:25:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:25:26 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')]} 07:25:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:26 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')]} 07:25:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:26 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 0x104eaa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e20570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa18958de70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cbed30>, '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 0x104b24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cb6b20>, '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.HifDsG6fy6/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.YaAJPeofdi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 07:25:26 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 07:25:26 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 07:25:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HifDsG6fy6/Render', 07:25:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:26 INFO - 'HOME': '/Users/cltbld', 07:25:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:26 INFO - 'LOGNAME': 'cltbld', 07:25:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:26 INFO - 'MOZ_NO_REMOTE': '1', 07:25:26 INFO - 'NO_EM_RESTART': '1', 07:25:26 INFO - 'PAGER': '/bin/cat', 07:25:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:26 INFO - 'PWD': '/builds/slave/test', 07:25:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:26 INFO - 'SHELL': '/bin/bash', 07:25:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaAJPeofdi/Listeners', 07:25:26 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 07:25:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:26 INFO - 'USER': 'cltbld', 07:25:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:26 INFO - 'XPC_FLAGS': '0x0', 07:25:26 INFO - 'XPC_SERVICE_NAME': '0', 07:25:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:27 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 07:25:27 INFO - Cleaning up... 07:25:27 INFO - Return code: 0 07:25:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:25:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:25:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:25:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104eaa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e20570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa18958de70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cbed30>, '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 0x104b24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cb6b20>, '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.HifDsG6fy6/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.YaAJPeofdi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 07:25:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:25:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:25:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HifDsG6fy6/Render', 07:25:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:27 INFO - 'HOME': '/Users/cltbld', 07:25:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:27 INFO - 'LOGNAME': 'cltbld', 07:25:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:27 INFO - 'MOZ_NO_REMOTE': '1', 07:25:27 INFO - 'NO_EM_RESTART': '1', 07:25:27 INFO - 'PAGER': '/bin/cat', 07:25:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:27 INFO - 'PWD': '/builds/slave/test', 07:25:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:27 INFO - 'SHELL': '/bin/bash', 07:25:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaAJPeofdi/Listeners', 07:25:27 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 07:25:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:27 INFO - 'USER': 'cltbld', 07:25:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:27 INFO - 'XPC_FLAGS': '0x0', 07:25:27 INFO - 'XPC_SERVICE_NAME': '0', 07:25:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:27 INFO - Downloading/unpacking psutil>=3.1.1 07:25:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:25:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:25:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:25:30 INFO - Installing collected packages: psutil 07:25:30 INFO - Running setup.py install for psutil 07:25:30 INFO - building 'psutil._psutil_osx' extension 07:25:30 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 07:25:31 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 07:25:31 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 07:25:31 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 07:25:31 INFO - building 'psutil._psutil_posix' extension 07:25:31 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 07:25:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:25:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:25:31 INFO - ^ 07:25:31 INFO - 1 warning generated. 07:25:31 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 07:25:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:25:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:25:31 INFO - Successfully installed psutil 07:25:31 INFO - Cleaning up... 07:25:31 INFO - Return code: 0 07:25:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:25:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:25:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:25:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104eaa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e20570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa18958de70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cbed30>, '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 0x104b24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cb6b20>, '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.HifDsG6fy6/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.YaAJPeofdi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 07:25:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:25:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:25:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HifDsG6fy6/Render', 07:25:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:31 INFO - 'HOME': '/Users/cltbld', 07:25:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:31 INFO - 'LOGNAME': 'cltbld', 07:25:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:31 INFO - 'MOZ_NO_REMOTE': '1', 07:25:31 INFO - 'NO_EM_RESTART': '1', 07:25:31 INFO - 'PAGER': '/bin/cat', 07:25:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:31 INFO - 'PWD': '/builds/slave/test', 07:25:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:31 INFO - 'SHELL': '/bin/bash', 07:25:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaAJPeofdi/Listeners', 07:25:31 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 07:25:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:31 INFO - 'USER': 'cltbld', 07:25:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:31 INFO - 'XPC_FLAGS': '0x0', 07:25:31 INFO - 'XPC_SERVICE_NAME': '0', 07:25:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:25:32 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 07:25:32 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 07:25:32 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 07:25:32 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 07:25:32 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 07:25:32 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 07:25:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:25:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:25:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:25:34 INFO - Installing collected packages: mozsystemmonitor 07:25:34 INFO - Running setup.py install for mozsystemmonitor 07:25:34 INFO - Successfully installed mozsystemmonitor 07:25:34 INFO - Cleaning up... 07:25:35 INFO - Return code: 0 07:25:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:25:35 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')]} 07:25:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:35 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')]} 07:25:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:35 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 0x104eaa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e20570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa18958de70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cbed30>, '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 0x104b24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cb6b20>, '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.HifDsG6fy6/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.YaAJPeofdi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 07:25:35 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 07:25:35 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 07:25:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HifDsG6fy6/Render', 07:25:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:35 INFO - 'HOME': '/Users/cltbld', 07:25:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:35 INFO - 'LOGNAME': 'cltbld', 07:25:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:35 INFO - 'MOZ_NO_REMOTE': '1', 07:25:35 INFO - 'NO_EM_RESTART': '1', 07:25:35 INFO - 'PAGER': '/bin/cat', 07:25:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:35 INFO - 'PWD': '/builds/slave/test', 07:25:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:35 INFO - 'SHELL': '/bin/bash', 07:25:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaAJPeofdi/Listeners', 07:25:35 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 07:25:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:35 INFO - 'USER': 'cltbld', 07:25:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:35 INFO - 'XPC_FLAGS': '0x0', 07:25:35 INFO - 'XPC_SERVICE_NAME': '0', 07:25:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:35 INFO - Downloading/unpacking jsonschema==2.5.1 07:25:35 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 07:25:35 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 07:25:35 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 07:25:35 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 07:25:35 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 07:25:35 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 07:25:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:25:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:25: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 07:25: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 07:25: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 07:25: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 07:25: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 07:25: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 07:25:38 INFO - Downloading functools32-3.2.3-2.tar.gz 07:25:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:25:38 INFO - warning: no files found matching '*.txt' 07:25:38 INFO - no previously-included directories found matching 'build' 07:25:38 INFO - no previously-included directories found matching 'dist' 07:25:38 INFO - no previously-included directories found matching '.git*' 07:25:38 INFO - Installing collected packages: jsonschema, functools32 07:25:38 INFO - Running setup.py install for functools32 07:25:38 INFO - warning: no files found matching '*.txt' 07:25:38 INFO - no previously-included directories found matching 'build' 07:25:38 INFO - no previously-included directories found matching 'dist' 07:25:38 INFO - no previously-included directories found matching '.git*' 07:25:38 INFO - Successfully installed jsonschema functools32 07:25:38 INFO - Cleaning up... 07:25:38 INFO - Return code: 0 07:25:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:25:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:25:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:25:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104eaa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e20570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa18958de70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cbed30>, '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 0x104b24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cb6b20>, '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.HifDsG6fy6/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.YaAJPeofdi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 07:25:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:25:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:25:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HifDsG6fy6/Render', 07:25:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:38 INFO - 'HOME': '/Users/cltbld', 07:25:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:38 INFO - 'LOGNAME': 'cltbld', 07:25:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:38 INFO - 'MOZ_NO_REMOTE': '1', 07:25:38 INFO - 'NO_EM_RESTART': '1', 07:25:38 INFO - 'PAGER': '/bin/cat', 07:25:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:38 INFO - 'PWD': '/builds/slave/test', 07:25:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:38 INFO - 'SHELL': '/bin/bash', 07:25:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaAJPeofdi/Listeners', 07:25:38 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 07:25:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:38 INFO - 'USER': 'cltbld', 07:25:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:38 INFO - 'XPC_FLAGS': '0x0', 07:25:38 INFO - 'XPC_SERVICE_NAME': '0', 07:25:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:25:38 INFO - Cleaning up... 07:25:39 INFO - Return code: 0 07:25:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:25:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:25:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:25:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:39 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 0x104eaa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e20570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa18958de70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cbed30>, '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 0x104b24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cb6b20>, '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.HifDsG6fy6/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.YaAJPeofdi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 07:25:39 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 07:25:39 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 07:25:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HifDsG6fy6/Render', 07:25:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:39 INFO - 'HOME': '/Users/cltbld', 07:25:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:39 INFO - 'LOGNAME': 'cltbld', 07:25:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:39 INFO - 'MOZ_NO_REMOTE': '1', 07:25:39 INFO - 'NO_EM_RESTART': '1', 07:25:39 INFO - 'PAGER': '/bin/cat', 07:25:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:39 INFO - 'PWD': '/builds/slave/test', 07:25:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:39 INFO - 'SHELL': '/bin/bash', 07:25:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaAJPeofdi/Listeners', 07:25:39 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 07:25:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:39 INFO - 'USER': 'cltbld', 07:25:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:39 INFO - 'XPC_FLAGS': '0x0', 07:25:39 INFO - 'XPC_SERVICE_NAME': '0', 07:25:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:39 INFO - Downloading/unpacking blobuploader==1.2.4 07:25:39 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 07:25:39 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 07:25:39 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 07:25:39 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 07:25:39 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 07:25:39 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 07:25:41 INFO - Downloading blobuploader-1.2.4.tar.gz 07:25:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:25:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:25: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 07:25: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 07:25: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 07:25: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 07:25: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 07:25: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 07:25:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:25:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:25:42 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 07:25:42 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 07:25:42 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 07:25:42 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 07:25:42 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 07:25:42 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 07:25:43 INFO - Downloading docopt-0.6.1.tar.gz 07:25:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:25:43 INFO - Installing collected packages: blobuploader, requests, docopt 07:25:43 INFO - Running setup.py install for blobuploader 07:25:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:25:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:25:43 INFO - Running setup.py install for requests 07:25:43 INFO - Running setup.py install for docopt 07:25:44 INFO - Successfully installed blobuploader requests docopt 07:25:44 INFO - Cleaning up... 07:25:44 INFO - Return code: 0 07:25:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:25:44 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')]} 07:25:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:44 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')]} 07:25:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:44 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 0x104eaa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e20570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa18958de70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cbed30>, '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 0x104b24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cb6b20>, '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.HifDsG6fy6/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.YaAJPeofdi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 07:25:44 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 07:25:44 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 07:25:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HifDsG6fy6/Render', 07:25:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:44 INFO - 'HOME': '/Users/cltbld', 07:25:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:44 INFO - 'LOGNAME': 'cltbld', 07:25:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:44 INFO - 'MOZ_NO_REMOTE': '1', 07:25:44 INFO - 'NO_EM_RESTART': '1', 07:25:44 INFO - 'PAGER': '/bin/cat', 07:25:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:44 INFO - 'PWD': '/builds/slave/test', 07:25:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:44 INFO - 'SHELL': '/bin/bash', 07:25:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaAJPeofdi/Listeners', 07:25:44 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 07:25:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:44 INFO - 'USER': 'cltbld', 07:25:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:44 INFO - 'XPC_FLAGS': '0x0', 07:25:44 INFO - 'XPC_SERVICE_NAME': '0', 07:25:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:25:44 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-ByyCqp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:25:44 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-JPHqNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:25:44 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-PGwRj_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:25:44 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-snjuFL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:25:44 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-hf7p0N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:25:44 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-hbB8ll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:25:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:25:45 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-EjTGvQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:25:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:25:45 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-fuKvUZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:25:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:25:45 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-F1jVOh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:25:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:25:45 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-tBD7WL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:25:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:25:45 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-Zq8fsi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:25:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:25:45 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-1o8wxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:25:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:25:45 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-i66zVt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:25:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:25:45 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-KtqloK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:25:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:25:45 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-Nsn39y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:25:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:25:45 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-w2XLMv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:25:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:25:46 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-ajC8yZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:25:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:25:46 INFO - Running setup.py install for manifestparser 07:25:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:25:46 INFO - Running setup.py install for mozcrash 07:25:46 INFO - Running setup.py install for mozdebug 07:25:46 INFO - Running setup.py install for mozdevice 07:25:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:25:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:25:46 INFO - Running setup.py install for mozfile 07:25:46 INFO - Running setup.py install for mozhttpd 07:25:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:25:47 INFO - Running setup.py install for mozinfo 07:25:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:25:47 INFO - Running setup.py install for mozInstall 07:25:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:25:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:25:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:25:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:25:47 INFO - Running setup.py install for mozleak 07:25:47 INFO - Running setup.py install for mozlog 07:25:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:25:47 INFO - Running setup.py install for moznetwork 07:25:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:25:47 INFO - Running setup.py install for mozprocess 07:25:47 INFO - Running setup.py install for mozprofile 07:25:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:25:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:25:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:25:48 INFO - Running setup.py install for mozrunner 07:25:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:25:48 INFO - Running setup.py install for mozscreenshot 07:25:48 INFO - Running setup.py install for moztest 07:25:48 INFO - Running setup.py install for mozversion 07:25:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:25:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:25:48 INFO - Cleaning up... 07:25:48 INFO - Return code: 0 07:25:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:25:48 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')]} 07:25:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:48 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')]} 07:25:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:48 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 0x104eaa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e20570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa18958de70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cbed30>, '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 0x104b24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cb6b20>, '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.HifDsG6fy6/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.YaAJPeofdi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 07:25:48 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 07:25:48 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 07:25:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HifDsG6fy6/Render', 07:25:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:48 INFO - 'HOME': '/Users/cltbld', 07:25:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:48 INFO - 'LOGNAME': 'cltbld', 07:25:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:48 INFO - 'MOZ_NO_REMOTE': '1', 07:25:48 INFO - 'NO_EM_RESTART': '1', 07:25:48 INFO - 'PAGER': '/bin/cat', 07:25:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:48 INFO - 'PWD': '/builds/slave/test', 07:25:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:48 INFO - 'SHELL': '/bin/bash', 07:25:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaAJPeofdi/Listeners', 07:25:48 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 07:25:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:48 INFO - 'USER': 'cltbld', 07:25:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:48 INFO - 'XPC_FLAGS': '0x0', 07:25:48 INFO - 'XPC_SERVICE_NAME': '0', 07:25:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:25:48 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-edxBgS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:25:49 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)) 07:25:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:25:49 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-ePiotD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:25:49 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)) 07:25:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:25:49 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-8tyUDp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:25:49 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)) 07:25:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:25:49 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-qBE4w_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:25:49 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)) 07:25:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:25:49 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-iSQjx7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:25:49 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)) 07:25:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:25:49 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-g5pwO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:25:49 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)) 07:25:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:25:49 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-MMPzri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:25:49 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)) 07:25:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:25:49 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-EQzwn5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:25:49 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)) 07:25:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:25:49 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-VUhCcJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:25:49 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)) 07:25:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:25:49 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-NlghrR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:25:50 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)) 07:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:25:50 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-thtUbG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:25:50 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)) 07:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:25:50 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-8NX2ck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:25:50 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)) 07:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:25:50 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-AwJjWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:25:50 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)) 07:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:25:50 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-dqjtCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:25:50 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)) 07:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:25:50 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-W54cdg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:25:50 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)) 07:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:25:50 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-pyUsQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:25:50 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)) 07:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:25:50 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-sm8xeu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:25:50 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)) 07:25:50 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)) 07:25:50 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)) 07:25:50 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)) 07:25:50 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)) 07:25:50 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)) 07:25:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:25:50 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 07:25:50 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 07:25:50 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 07:25:50 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 07:25:50 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 07:25:50 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 07:25:53 INFO - Downloading blessings-1.6.tar.gz 07:25:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:25:53 INFO - Installing collected packages: blessings 07:25:53 INFO - Running setup.py install for blessings 07:25:53 INFO - Successfully installed blessings 07:25:53 INFO - Cleaning up... 07:25:53 INFO - Return code: 0 07:25:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:25:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:25:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:25:53 INFO - Reading from file tmpfile_stdout 07:25:53 INFO - Current package versions: 07:25:53 INFO - blessings == 1.6 07:25:53 INFO - blobuploader == 1.2.4 07:25:53 INFO - docopt == 0.6.1 07:25:53 INFO - functools32 == 3.2.3-2 07:25:53 INFO - jsonschema == 2.5.1 07:25:53 INFO - manifestparser == 1.1 07:25:53 INFO - mozInstall == 1.12 07:25:53 INFO - mozcrash == 1.0 07:25:53 INFO - mozdebug == 0.1 07:25:53 INFO - mozdevice == 0.48 07:25:53 INFO - mozfile == 1.2 07:25:53 INFO - mozhttpd == 0.7 07:25:53 INFO - mozinfo == 0.9 07:25:53 INFO - mozleak == 0.1 07:25:53 INFO - mozlog == 3.4 07:25:53 INFO - moznetwork == 0.27 07:25:53 INFO - mozprocess == 0.23 07:25:53 INFO - mozprofile == 0.28 07:25:53 INFO - mozrunner == 6.13 07:25:53 INFO - mozscreenshot == 0.1 07:25:53 INFO - mozsystemmonitor == 0.3 07:25:53 INFO - moztest == 0.7 07:25:53 INFO - mozversion == 1.4 07:25:53 INFO - psutil == 3.1.1 07:25:53 INFO - requests == 1.2.3 07:25:53 INFO - wsgiref == 0.1.2 07:25:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:25:53 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')]} 07:25:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:25:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:55 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 0x104eaa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e20570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa18958de70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cbed30>, '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 0x104b24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cb6b20>, '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.HifDsG6fy6/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.YaAJPeofdi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 07:25:55 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 07:25:55 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 07:25:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HifDsG6fy6/Render', 07:25:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:55 INFO - 'HOME': '/Users/cltbld', 07:25:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:55 INFO - 'LOGNAME': 'cltbld', 07:25:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:55 INFO - 'MOZ_NO_REMOTE': '1', 07:25:55 INFO - 'NO_EM_RESTART': '1', 07:25:55 INFO - 'PAGER': '/bin/cat', 07:25:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:55 INFO - 'PWD': '/builds/slave/test', 07:25:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:55 INFO - 'SHELL': '/bin/bash', 07:25:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaAJPeofdi/Listeners', 07:25:55 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 07:25:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:55 INFO - 'USER': 'cltbld', 07:25:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:55 INFO - 'XPC_FLAGS': '0x0', 07:25:55 INFO - 'XPC_SERVICE_NAME': '0', 07:25:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:55 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)) 07:25:55 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)) 07:25:55 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)) 07:25:55 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)) 07:25:55 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)) 07:25:55 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)) 07:25:55 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)) 07:25:55 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)) 07:25:55 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)) 07:25:55 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)) 07:25:55 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)) 07:25:55 INFO - Cleaning up... 07:25:55 INFO - Return code: 0 07:25:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:25:55 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')]} 07:25:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:55 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')]} 07:25:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:55 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 0x104eaa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e20570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa18958de70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cbed30>, '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 0x104b24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cb6b20>, '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.HifDsG6fy6/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.YaAJPeofdi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 07:25:55 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 07:25:55 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 07:25:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HifDsG6fy6/Render', 07:25:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:55 INFO - 'HOME': '/Users/cltbld', 07:25:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:55 INFO - 'LOGNAME': 'cltbld', 07:25:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:55 INFO - 'MOZ_NO_REMOTE': '1', 07:25:55 INFO - 'NO_EM_RESTART': '1', 07:25:55 INFO - 'PAGER': '/bin/cat', 07:25:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:55 INFO - 'PWD': '/builds/slave/test', 07:25:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:55 INFO - 'SHELL': '/bin/bash', 07:25:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaAJPeofdi/Listeners', 07:25:55 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 07:25:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:55 INFO - 'USER': 'cltbld', 07:25:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:55 INFO - 'XPC_FLAGS': '0x0', 07:25:55 INFO - 'XPC_SERVICE_NAME': '0', 07:25:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:25:55 INFO - Cleaning up... 07:25:55 INFO - Return code: 0 07:25:55 INFO - Running post-action listener: _resource_record_post_action 07:25:55 INFO - Running post-action listener: _start_resource_monitoring 07:25:55 INFO - Starting resource monitoring. 07:25:55 INFO - [mozharness: 2016-11-24 15:25:55.921426Z] Finished create-virtualenv step (success) 07:25:55 INFO - [mozharness: 2016-11-24 15:25:55.922405Z] Running install step. 07:25:55 INFO - Running pre-action listener: _resource_record_pre_action 07:25:55 INFO - Running main action method: install 07:25:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:25:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:25:56 INFO - Reading from file tmpfile_stdout 07:25:56 INFO - Detecting whether we're running mozinstall >=1.0... 07:25:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:25:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:25:56 INFO - Reading from file tmpfile_stdout 07:25:56 INFO - Output received: 07:25:56 INFO - Usage: mozinstall [options] installer 07:25:56 INFO - Options: 07:25:56 INFO - -h, --help show this help message and exit 07:25:56 INFO - -d DEST, --destination=DEST 07:25:56 INFO - Directory to install application into. [default: 07:25:56 INFO - "/builds/slave/test"] 07:25:56 INFO - --app=APP Application being installed. [default: firefox] 07:25:56 INFO - mkdir: /builds/slave/test/build/application 07:25:56 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'] 07:25:56 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 07:26:27 INFO - Reading from file tmpfile_stdout 07:26:27 INFO - Output received: 07:26:27 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:26:27 INFO - Running post-action listener: _resource_record_post_action 07:26:27 INFO - [mozharness: 2016-11-24 15:26:27.449962Z] Finished install step (success) 07:26:27 INFO - [mozharness: 2016-11-24 15:26:27.450099Z] Running run-tests step. 07:26:27 INFO - Running pre-action listener: _resource_record_pre_action 07:26:27 INFO - Running main action method: run_tests 07:26:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:26:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:26:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:26:27 INFO - 2016-11-24 07:26:27.464 screenresolution[1623:7533] starting screenresolution argv=screenresolution get 07:26:27 INFO - 2016-11-24 07:26:27.467 screenresolution[1623:7533] Display 0: 1600x1200x32@60 07:26:27 INFO - 2016-11-24 07:26:27.473 screenresolution[1624:7536] starting screenresolution argv=screenresolution list 07:26:27 INFO - Available Modes on Display 0 07:26:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:26:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:26:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:26:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:26:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:26:27 INFO - Intel Iris: 07:26:27 INFO - Chipset Model: Intel Iris 07:26:27 INFO - Type: GPU 07:26:27 INFO - Bus: Built-In 07:26:27 INFO - VRAM (Dynamic, Max): 1536 MB 07:26:27 INFO - Vendor: Intel (0x8086) 07:26:27 INFO - Device ID: 0x0a2e 07:26:27 INFO - Revision ID: 0x0009 07:26:27 INFO - Displays: 07:26:27 INFO - Display: 07:26:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:26:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:26:27 INFO - Display Serial Number: 1600x1200 60 07:26:27 INFO - Main Display: Yes 07:26:27 INFO - Mirror: Off 07:26:27 INFO - Online: Yes 07:26:27 INFO - Rotation: Supported 07:26:27 INFO - Return code: 0 07:26:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:26:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:26:27 INFO - Python 2.7.3 07:26:27 INFO - Return code: 0 07:26:27 INFO - grabbing minidump binary from tooltool 07:26:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:26:27 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 0x104cbed30>, '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 0x104b24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cb6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:26:27 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 07:26:27 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 07:26:27 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 07:26:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:26:27 INFO - Return code: 0 07:26:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:26:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:26:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:26:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:26:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:26:27 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', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XYPchGGWQl28pRk0TOHaPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0206', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:26:27 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 --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XYPchGGWQl28pRk0TOHaPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0206 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:26:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HifDsG6fy6/Render', 07:26:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:26:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:26:27 INFO - 'HOME': '/Users/cltbld', 07:26:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:26:27 INFO - 'LOGNAME': 'cltbld', 07:26:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:26:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:26:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:26:27 INFO - 'MOZ_NO_REMOTE': '1', 07:26:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:26:27 INFO - 'NO_EM_RESTART': '1', 07:26:27 INFO - 'PAGER': '/bin/cat', 07:26:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:26:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:26:27 INFO - 'PWD': '/builds/slave/test', 07:26:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:26:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:26:27 INFO - 'SHELL': '/bin/bash', 07:26:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaAJPeofdi/Listeners', 07:26:27 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 07:26:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:26:27 INFO - 'USER': 'cltbld', 07:26:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:26:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:26:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:26:27 INFO - 'XPC_FLAGS': '0x0', 07:26:27 INFO - 'XPC_SERVICE_NAME': '0', 07:26:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:26:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XYPchGGWQl28pRk0TOHaPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0206', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:26:28 INFO - mozversion application_buildid: 20161124050619 07:26:28 INFO - mozversion application_changeset: 233a0890b3b35d778b2a50f1d7b064a8bcb82fb1 07:26:28 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:26:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:26:28 INFO - mozversion application_name: Firefox 07:26:28 INFO - mozversion application_remotingname: firefox-dev 07:26:28 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:26:28 INFO - mozversion application_vendor: Mozilla 07:26:28 INFO - mozversion application_version: 52.0a2 07:26:28 INFO - mozversion platform_buildid: 20161124050619 07:26:28 INFO - mozversion platform_changeset: 233a0890b3b35d778b2a50f1d7b064a8bcb82fb1 07:26:28 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:26:28 INFO - mozversion platform_version: 52.0a2 07:26:28 INFO - using testdate: 1480001188 07:26:28 INFO - actual date: 1480001188 07:26:28 INFO - starting webserver on 'localhost:49249' 07:26:28 INFO - SUITE-START | Running 2 tests 07:26:28 INFO - TEST-START | dromaeo_css 07:26:28 INFO - operating with platform_type : mac_ 07:26:28 INFO - Initialising browser for dromaeo_css test... 07:26:28 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/tmpPxR0VN/profile http://localhost:49249/getInfo.html) 07:26:30 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 07:26:30 INFO - PROCESS | 1634 | colorDepth:24 07:26:30 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 07:26:30 INFO - PROCESS | 1634 | __metrics 07:26:30 INFO - PROCESS | 1634 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:26:30 INFO - PROCESS | 1634 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:26:31 INFO - TEST-INFO | 1634: exit 0 07:26:31 INFO - Browser initialized. 07:26:31 INFO - Running cycle 1/1 for dromaeo_css test... 07:26:31 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/tmpPxR0VN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:26:44 INFO - PROCESS | 1636 | RSS: Main: 222953472 07:26:44 INFO - PROCESS | 1636 | 07:27:35 INFO - PROCESS | 1636 | 1480001255053 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] 07:29:48 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 07:29:50 INFO - PROCESS | 1636 | RSS: Main: 366149632 07:29:50 INFO - PROCESS | 1636 | 07:32:53 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 07:32:53 INFO - PROCESS | 1636 | RSS: Main: 350011392 07:32:53 INFO - PROCESS | 1636 | 07:35:55 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 07:35:56 INFO - PROCESS | 1636 | RSS: Main: 349052928 07:35:56 INFO - PROCESS | 1636 | 07:38:58 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 07:38:59 INFO - PROCESS | 1636 | RSS: Main: 324456448 07:38:59 INFO - PROCESS | 1636 | 07:42:02 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 07:42:03 INFO - PROCESS | 1636 | RSS: Main: 311578624 07:42:03 INFO - PROCESS | 1636 | 07:45:06 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 07:45:07 INFO - PROCESS | 1636 | RSS: Main: 360165376 07:45:07 INFO - PROCESS | 1636 | 07:45:07 INFO - PROCESS | 1636 | __start_tp_report 07:45:07 INFO - PROCESS | 1636 | _x_x_mozilla_page_load 07:45:07 INFO - PROCESS | 1636 | _x_x_mozilla_page_load_details 07:45:07 INFO - PROCESS | 1636 | |i|pagename|runs| 07:45:07 INFO - PROCESS | 1636 | |0;dojo.html;2742.958855617166;3355.194753259217;3367.8147701876387;3383.154211447138;3413.0102270341604;4650.372199753032;5435.293411856458;5605.859853503654;5664.122061080534;5720.056190728525;5589.0219211638005;5610.24261724667;5644.350899646542;5651.8587035324135;5683.744231509571;5128.282040514312;5156.510131537498;5159.535641792238;5231.816886408977;5295.655782374152;5083.339165908426;5156.561692256163;5161.045206636784;5190.71451070191;5203.609729270301;5233.476652334755;5245.973770131144;5250.6587071840395;5279.996800607892;5298.258243845865;5247.947520524784;5250.028744682246;5259.053370393328;5262.8684282892855;5284.524392804666;5015.247712843066;5141.5886729061585;5164.2770012198325;5165.044466773659;5188.766505507261;5593.804216852391;5606;5620.437956204388;5656.745446454878;5697.4587414195585;6133.539984501181;6159.4456498915315;6161.4146656067605;6217.937820621826;6218.31598524162;6279.654618995954;6288.213973253343;6312.715927783215;6313.273973493061;6315.431611155018;5384.73076346185;5474.808381706701;5481.369642491165;5707.429257074259;5799;6334.873302533923;6338.144350512622;6343.936560634427;6356.110144579762;6370.777022804273;6064.0297552392285;6109.908351374733;6111.633302001894;6115.938840611537;6118.357572454917;6116.29662588808;6130.551011451118;6141.499345373184;6174.351693072253;6220.937790622126;6111.755529778848;6123.557704288954;6147.200863887755;6182.938170618326;6190.473809726223;6070.757169713251;6194.163787888668;6200.596961197508;6200.906986395117;6214.658193799384;4197.433346498245;4915.43472500667;4921.237208232729;4924.212126059813;4936.086823937511;3648.2703459308245;4340.652747780233;4341.761203133858;4363.781810909441;4409.5149533551285;5726.169705392695;5731.942680573225;5737.02470579994;5743.971280143656;5766.481016708516;6063.999440092423;6068.726907289182;6072.787452439232;6226.782062627786;6259.96870015647;5638.069718496484;5671.1493276008105;5687.090065589486;5709.486146246774;5749.655020698688;5345.679259244458;5351.57187425005;5395.163749618815;5420.83737487876;5426.213199086111;6312.16812504667;6317.873642527215;6325.96837015812;6327.145835312358;6334.809955701337;4854.975725121493;6209.906851397142;6225.221847269091;6241.469475094486;6247.843803904939;6197.721102550306;6246.718897649525;6248.250209974832;6261.3112557617715;6312.337204593451;6125.295591007091;6132.101331180348;6137.5302083125;6137.8366772191985;6159.599626024294;6165.691715414301;6179.660118693515;6207.65857877803;6214.378562143749;6244;5875.441833026027;5939.940600593939;6019.578629495893;6028.728707208098;6071.635701857903;6076.817695469143;6082.330943596289;6106.389361063858;6108.053251745987;6144.477719393902;5301.1783173608155;5891.05743081105;5895.64626122434;5902.026165682614;5902.4097590240635;5807.245058142584;5953.3153687324775;5965.373635768182;6060.212172417558;6081.422264884829;4711.858644240679;5906.822795316148;5924.9703751480965;5935.317438494456;5938.584299099021 07:45:07 INFO - PROCESS | 1636 | |1;ext.html;502.44228905914434;511.2076281763268;511.53705896163984;517.262900366977;519.4260342321733;30142;39572;40843.59156408439;40890.7955460223;43367.566324336745;30888.53667194988;41625.58374416255;41792.373114403235;41840;43926;9254.86117708235;9431.81136377272;9433.481158536279;9435.622575096988;9447.952760236189;8453.492790432563;8707.869381959244;10985.231033827635;11320.773584528304;11345.489452974594;11254.887451125465;11313.886861131406;11314.151438642093;11337;11342;8552.615132318999;9874.456904870229;11280;11312.151588630844;11337.1497137715;8539.701110461167;10849.403282819441;11163.553457861675;11200.383978881158;11372.976432121066;38947.02632434184;39301;39356.80321598402;39433.60566394328;39880;3136.639286482038;3143.0727935258924;3246.3507298540385;3408.931821363584;3483.3207524532727;29865.55201671977;29887.850560747276;30036.549451758023;30204;30505;3197.728193103566;3200.1199670090678;3203.599550056243;3222.726068284199;3316.05492434655;474419;477775;477836;478153;482618;33115.50326745101;33845;34072.422395648595;58809.41190588125;59213.407865921654;34721.30557388889;34786.82606587002;34809.30381392358;34865.82567087199;34917.650823491946;14026.929865350812;14168.504102356368;14181.645458863612;14217.50238741639;14312.28438578067;10851.78296434067;11074.114070874311;12002.459889305002;12105.15263931534;12112.970397516134;7373.852522949511;7601.733939312097;7785.416093792988;7841.725539606086;7846.136924938252;3190.345979074284;3206.406814739281;3209.4864821628894;3237.3687131009697;3241.286916878283;777.292663676052;895.3285036222833;936.0312077000272;980.8759102259185;1037.1650821088945;27839;28998.635252478358;29019.274518137216;29749;29820.403591928472;1014.8832884218409;1041.9479026048666;1094.2777766674117;1141.275290190735;1154.1401655766508;15198.772018419733;15200;15405.537833865003;15466.845331546765;15470.922645386701;666853;674498;675117;675304.6234768893;675510;55171.72414137904;55680;55700.16449753257;55773.44226557682;55945.720271398386;1209.371127013948;1249.731307768834;1318.2815365625565;1345.159275452842;1369.1784929042694;28817.135485935454;29748.553771693874;30018.699813002466;30598.541021884244;30693.465326733127;49660.751696241285;54173;55788.72105639446;55802;59287.70356148192;615376;620746;621094;623582;623830;25978.96084156688;26533.07134250292;27594.5860812092;39366.80316598398;39989;45018.77490612526;45353.092938141715;45507.31739023851;45529.77235113803;45743.77128114338;37428.81285593664;37920;37982.43026354662;38100.61899380971;39326.80336598299;32320.191995199366;37701.4344784823;38042.61957380391;38271.80864095662;39361.21277574375;36889.44665829961;37110;37391.81304093462;37508.24983500369;37540.81229593835;31706.682933170374;34405.31189376248;34447.82776086204;34519;34593.48109778305;23787.810609469805;24661.13686020981;25284.988600456512;25343.112991045225;25750.484990299716 07:45:07 INFO - PROCESS | 1636 | |2;jquery.html;352.79008989651146;371.8494009925979;376.33764574349146;387.3743903595693;403.3365114386834;14777.26113694315;15037.09777413356;16300.755488667679;19746.70379944302;20607;19481.415557533262;19525.609487810234;20146.992650367498;20283.49291267719;20427.795722042774;5163.251328557376;5716.371199168098;5805.24531810864;6040.909386359208;6046.848828779293;5398.433164517728;5907.350191478934;5963.880722385549;5969.791057312995;5974.611650242742;5643.238162848024;5770.596058275923;5910.822675319726;5917.378675239102;5920.319163296233;5823.4467725566;5870.618409803392;5891.175235466995;5895.528357731371;5906.23218981534;4884.389451318585;5062.746862656889;5131.486851314876;5147.794088236466;5152.871178220537;25100.748992510024;25318;25324.28906339803;25431;25580;2170.34889533141;2278.9180128313787;2316.860988340705;2351.6825228594093;3046.893358732456;12611.117221794479;14388.136711797326;14872.256387180705;14891.032082914577;15539.378424862993;1811.9094045297682;1831.9358822441284;1844.5665268661853;1876.502726777405;1890.1777726688933;115383;115529.19971519739;125525;127240.36379818042;127522;10504.632337868137;10582;10821.404822734825;11182;11477.655670329903;13590.38843252056;13591.116577422436;13604.455821767018;13612.591622251348;13626.386812593457;7086.362227399456;7510.962445187848;8171.63227654757;8260.628271727786;8743.557313043404;7011.92988070123;8310.6287462568;8493.490390576462;8542.572871356531;8639.481631102153;6479.1901012373455;6644.77400070972;6656.36764507371;6671.914389055506;6742.190937087581;1822.2437688359448;1858.2659849359427;1863.9347622833407;1874.5782199005114;1902.4958386027713;867.5878957495139;970.1898914406408;983.1790454970037;1056.287006270764;1094.2996482251365;20655.586888262453;21996.560068798855;22034.559308813736;22054.779452205592;22079;962.9903700962901;972.8394814855611;973.1193270090579;1018.0786388318584;1031.9845202321972;9020.323475739346;10688.358698478116;11317.547298107984;11568.652940411634;11697.649070527897;480631;606555;607465.9253407409;622100.8894955587;623575;82562.93592660231;94669;123046;123708.38145809213;136551;962.129273007915;967.583938906263;1008.5108722269694;1011.489197955028;1012.0840639221516;18345.72481412753;18720.719189212447;19198.520036998627;19215.615687685888;19282.195636875575;51339.743301283255;51985.22022169746;52188.47811521988;52735.20897186622;53283.671632836405;75214.24785752072;77511.61244193742;78807.60596196982;79171.60414197893;80734.596327018;7163.7492687755675;7591.392688585011;8061.1938806118915;8069.838603227785;8178.713745019134;12127.272363658209;12910.935445322713;12988.025898057494;13072.215667059627;13434.731305373642;5399.325084364455;7047.7180912762915;9179.449233046038;11043.061339786209;11379.715507112389;9591.42451452915;10730.302530335503;11052.060574851228;11205.383703896365;11527.423628818358;11401.657950261173;11462.332053742723;11622.94188529052;11627;11706.531738730355;7375.852482950204;7523.1348394935285;7620;7702.768916932501;7708.4218683600075;9679.177269932135;10110.494475276353;11211;11550.48022838991;11574.131940104695 07:45:07 INFO - PROCESS | 1636 | |3;mootools.html;1458.5988853065403;1497.1765528959072;1626.30881875203;1737.5916659585002;1799.199356286453;8120.593970301484;8538.658453661854;8549.145085491447;8555;8561.95719021404;7471.55170689759;7745.070591529025;7747.651355688993;8079.878801817978;8283.792905177374;2294.8623082615013;2322.3381336319153;2327.464683122883;2329.219711396684;2330.114556468548;1944.212593899473;2317.374308936586;2360.492494113765;2360.976390236093;2361.8346715729904;2303.2974942642486;2309.7690230976937;2334.754850740673;2334.9066037358484;2336.918207862725;2289.1186893046215;2298.0577963035075;2321.0599707118645;2323.8605683659034;2330.697009388786;2316.6756654068445;2335.8131349492;2339.3099035784485;2343.5430091132234;2348.283773449097;8344.62449189782;8422.115677853859;8432.030316513617;8447.83104337916;8465.830683386359;5051.964347308793;5217.5565076968505;5219.086659834514;5227.242049902781;5230.058589453897;3231.2083539532728;3423.1271025888486;3499.5450591423205;3573.874914377984;3586.8923932282073;1003.8845532763679;1048.6487026846016;1079.6760971708534;1086.7011571817734;1095.4851219926702;10917;11041.613543525938;11063.336199828036;11075.16936229786;11093.55625774968;4445;4472.619827314715;4669.976650116728;4671.299305104194;4712.986707857827;9174.86237706435;9370.016148304467;9427.104425079606;9437.622495100257;9447.196988255937;5151.896962060813;5159.613029022838;5162.045021670928;5166.121759300928;5173.663711858717;4191.811368488365;4473.418455600751;4703.365045718784;4741.881452963704;4748.928766068511;3761.6458075092946;3774.7735135891935;3848.1726428817938;3894.065424298148;3896.688264938798;1246.1526162209602;1250.4123062160952;1254.0845183016268;1265.7784887644625;1268.7399083187925;732.7801659502235;761.067692077201;763.4045444553258;841.1336323586715;865.6623916672503;5162.793488260502;5233.371995360581;5266.683998960075;5365.007473617393;5367.087595108841;426.02227886998793;430.74370749404056;434.4200492342758;438.2352794373838;448.97530635815343;3474.3051389722154;3506.8246587671024;3517.067976986101;3541.468779683068;3603.5855876574005;7633.770986870402;10327.380357178594;10355.326903751233;10357.119644830274;10376.533056012497;10261.9486902565;10289.536970836181;10293.948530257301;10299;10307.020833020852;7477.999999999891;7791.181925897699;7887.605619718991;7889.2110788920645;7909.644066017043;5425.972870135624;5442.428545002717;5518.006758783457;5519.089350257163;5619.747111380079;7911.525308481279;9419.152276295166;9610.51947402613;9621.663241786504;9676.903230967882;9617.663381781604;9703.93256741772;9737.9026209737;9812.362196457207;9851.359661621971;5713.571482138939;6000.949833779158;6597.835054123493;6950.965245173742;7153.535020223669;7739.729109481141;8009.679612815655;8013.6794528218215;8075.232852878967;8075.9192408077515;7087.751928682472;7198.640067996684;7252.347288744038;7262.963685181751;7349.265073492608;7002.862367814239;7050.083489146583;7053.788386348418;7192.388646965067;7197.928020719726;6645.335466453316;6811.455083593201;6829.795106146824;6835.624040677811;6845.931540684729;4570.245909425041;4615.607673347803;4667.649926255476;4689.343491911203;4722.433308003062;4584.289435137559;4617.722936623679;4694.953050469451;4736.763161841963;4846.5638092571835 07:45:07 INFO - PROCESS | 1636 | |4;prototype.html;257.32452312679214;308.6604734791729;309.4522694830151;313.53596676918147;318.0950196690415;4906.828261010861;4962.280469331945;5215.765290561924;5235.397929238139;5300.92048619271;5174.715390653522;5182.896342073156;5218.660787048839;5224.492243723015;5241.3186285782895;3794.90512737181;3910.1397692507603;3941.7437866538585;3958.9802050989706;3959.3467077932105;3670.7513850242594;3785.848566057354;3942.8619998300064;3987.760734355948;4001.9199616007663;3961.3859851722887;3980.5073097588406;3990.3240638931575;4031.984007996002;4035.47538819953;3739.7098001189547;3767.7362584619086;3815.790131542764;3965.5637879833193;3982.4026396040535;2926.356201635637;3501.8774342897877;3512.3150985557827;3516.40221162403;3522.2603253316834;4623.514530974233;4894.241392584155;5029.4216165141115;5060.291559181717;5103.744812759347;3938.3895496197847;4049.919001619951;4371.426286536845;4586.54134586542;4598.816047358102;4518.683692141552;4539.388517076456;5432.728363581805;5515;5541.944580554183;3156.4476216662224;3419;3834.8878825140678;3869.929587670583;3876.321643712339;5048.115317042032;5774.566907481913;5784.248047753765;5815.244018277639;5856.59003869725;2903.491888919473;3081.0140754958647;3548.6451354864307;3798.4492248623797;3845.673117784964;3762.3227818992846;3870.767753934773;3873.9418908716393;3899.200663863901;3931.253061918226;3655.2324011957785;4350.2387082260475;4509.864704058883;4556.974680696817;4564.931526027112;5299.6025298102795;5372.803182106641;5380.811671591476;5489.286392768914;5624.268845050117;1005.8943810899897;1205.8733832947414;1225.6997035726304;1242.9937850310691;1256.5413624027165;796;815.3721634341642;843.308487040633;853.804673457165;853.9368486234554;4330.050269317762;4542.250528662799;4729.952700472952;4829.420469543677;5061.266116413173;4759.976200119047;4997.575206107522;5006.320354539411;5189.40321862983;5672.404397538199;4882.096812089775;5208.921866172011;5210.322658054429;5219.634625576249;5227.477252274818;2264.29806759905;2493.675822143146;2668.3989606236105;2809.367892224274;2814.437112577493;4954.653174277766;5058.721770302595;5515.200295957144;5548.361938377138;5559.471850174227;5000.89998200034;5263.733532189908;5404.891902161935;5424;5440.945590544123;4621.514740952219;5043.092243396224;5529.917051244234;5551.751540625892;5581.386047534849;4560.3843481129625;4940.061388336205;5041.706023759108;5317.308749862493;5333.359996800409;4337.240982828055;4527.5019747827055;4798.352222449925;5009.924851127309;5653.491185793298;4975.676581022222;5148.58811295088;5342.535532006316;5986.3238293277955;5991.011483105239;3002.954955675623;3828.5214348206473;3877.302085624615;3891.5135608048995;3931.3906344516645;5567.944320556742;5608.80298230005;5690.325546992906;5708.34354049273;5723.399043100415;5269.920951185659;5553.416891226363;5562.193481945176;5562.972185139049;5569.443055694398;5123.28274041627;5477.849651573214;5546.667199968015;5549.640338117187;5551.945130425206;4350.891227719332;5422.457754224546;5463.672179669074;5468.343798744176;5483.271375464722;5336.945276068819;5396.514313711785;5467.890642187054;5489.478499542537;5578.916316255179;4537.886552836206;4722;4758.714477131245;5381.811636592701;5428.281627085459 07:45:07 INFO - PROCESS | 1636 | |5;yui.html;689.2452764223175;703.1807943745537;741.3105811595218;753.6118898767132;788.8343447875944;14329.713405731893;14733.852661473395;14835.925820370896;14838.851611483882;14879.702405951875;13061.151025183357;13094.869051309484;13126.015548833851;13134.73730525389;13264.204147751117;540.2760301196396;650.5023656902473;660.7654282729634;661.6790856434625;805.5851236613134;665.640554100785;669.1635455680399;803.1326167738857;829.9211574900374;830.3689196210893;859.3211363023204;872.8690696395528;874.7638137702817;875.8686197070426;876;865.7662431143556;866.1079088538816;866.6576702202594;868.2489646455833;871.237667041342;508.58804368461665;523.3379774585114;526.3184176491453;648.2286079565303;648.9480841532666;11990.640280791591;12042.578509752117;12090.516379344816;13272.734545309137;13893.374798134008;11818.349990750505;12618.116731828775;12951.611451656465;13914.860851391457;14019.369128389146;11053.081378786443;11174.776504469946;11312.151588630886;11320.490577924013;11351.091912646969;3570.7143428525656;3616.4756110364115;3621.6016238213774;3827.1962887793597;3844.673202777767;76230.23769762287;84608;87786.68319975205;88327.55836220842;91933.08066919312;10194.643187488402;10340.482975851177;10648.254622176528;10766.784664306826;10847.240693151562;20024.69962950557;20046.89976550108;20072;20075.29736459217;20401;7739.883901741478;7751.844963100706;7819.5699236541395;7878.330341921001;7884.329831964233;4131.772752498581;4142.399352093929;4227.788610569459;4375.1031038637;4476.216662084123;3364.175776934667;3467.9826600866836;3468.757186996886;3470.9132271692906;3476;630.9652969086744;633.9809805705836;634.3402861024483;653.9051837483538;658.170704911805;502.8994201159783;546.5081426715988;564.2946317103621;634.9365063493706;674.6795272245644;7667.271609197117;8497.36269779769;8660.826783464296;8917.375783695208;9045.090585773009;602.9125776762345;602.978895738647;611.8898598252268;618.8014322819647;646.4311405962723;4352.869413917588;4365.541618130051;4396.406485124531;4453.487848897418;4484.618807401343;88751.55624221837;111786;113419.86580134257;116521;120590.3970480142;96408;101815.49092254491;103329.48335258276;104806.95193047971;105232;58153.82938994193;60386.69806651027;61187.694061530296;61519.38480615136;79561.60219198867;8788.648454061766;9222.362170364027;9367.391119577205;9426.905730942603;9474.95262523683;48014.759926200146;50870;51048;53262.46737532575;54562.727186363816;56718;69940.6502967482;70640.94038589529;73114.26885731074;75378;11297;11837.150886617355;12041.531486045515;12083.516659333529;12299.93850030744;12528.812067819172;13545.458181672622;13609.727805444034;13710.38303276335;13908.930455347658;12344.197627154206;12492.56276030371;12608.432620531914;12985.35073246315;13032.934835325763;12106.213096148722;12589.622311330675;12891.742165156831;12941.158824676742;13042.54351097707;12305.323207223688;12403;12421.627351179117;12798.296093714935;12809.551665691655;9954.303198776306;10535.473226338805;10633.840492392776;10866.239363244496;11001.449927503752;8648.43785153963;10484.580616775549;10775.528928381029;10843.295185812896;10883.727906802393 07:45:07 INFO - PROCESS | 1636 | __end_tp_report 07:45:07 INFO - PROCESS | 1636 | __start_cc_report 07:45:07 INFO - PROCESS | 1636 | _x_x_mozilla_cycle_collect,1729 07:45:07 INFO - PROCESS | 1636 | __end_cc_report 07:45:07 INFO - PROCESS | 1636 | __startTimestamp1480002307186__endTimestamp 07:45:07 INFO - PROCESS | 1636 | 07:45:07 INFO - PROCESS | 1636 | ------- Summary: start ------- 07:45:07 INFO - PROCESS | 1636 | Number of tests: 6 07:45:07 INFO - PROCESS | 1636 | 07:45:07 INFO - PROCESS | 1636 | [#0] dojo.html Cycles:165 Average:5675.56 Median:5902.03 stddev:677.58 (11.5%) stddev-sans-first:639.41 07:45:07 INFO - PROCESS | 1636 | Values: 2743.0 3355.2 3367.8 3383.2 3413.0 4650.4 5435.3 5605.9 5664.1 5720.1 5589.0 5610.2 5644.4 5651.9 5683.7 5128.3 5156.5 5159.5 5231.8 5295.7 5083.3 5156.6 5161.0 5190.7 5203.6 5233.5 5246.0 5250.7 5280.0 5298.3 5247.9 5250.0 5259.1 5262.9 5284.5 5015.2 5141.6 5164.3 5165.0 5188.8 5593.8 5606.0 5620.4 5656.7 5697.5 6133.5 6159.4 6161.4 6217.9 6218.3 6279.7 6288.2 6312.7 6313.3 6315.4 5384.7 5474.8 5481.4 5707.4 5799.0 6334.9 6338.1 6343.9 6356.1 6370.8 6064.0 6109.9 6111.6 6115.9 6118.4 6116.3 6130.6 6141.5 6174.4 6220.9 6111.8 6123.6 6147.2 6182.9 6190.5 6070.8 6194.2 6200.6 6200.9 6214.7 4197.4 4915.4 4921.2 4924.2 4936.1 3648.3 4340.7 4341.8 4363.8 4409.5 5726.2 5731.9 5737.0 5744.0 5766.5 6064.0 6068.7 6072.8 6226.8 6260.0 5638.1 5671.1 5687.1 5709.5 5749.7 5345.7 5351.6 5395.2 5420.8 5426.2 6312.2 6317.9 6326.0 6327.1 6334.8 4855.0 6209.9 6225.2 6241.5 6247.8 6197.7 6246.7 6248.3 6261.3 6312.3 6125.3 6132.1 6137.5 6137.8 6159.6 6165.7 6179.7 6207.7 6214.4 6244.0 5875.4 5939.9 6019.6 6028.7 6071.6 6076.8 6082.3 6106.4 6108.1 6144.5 5301.2 5891.1 5895.6 5902.0 5902.4 5807.2 5953.3 5965.4 6060.2 6081.4 4711.9 5906.8 5925.0 5935.3 5938.6 07:45:07 INFO - PROCESS | 1636 | 07:45:07 INFO - PROCESS | 1636 | [#1] ext.html Cycles:180 Average:70346.37 Median:34798.06 stddev:160021.50 (459.9%) stddev-sans-first:160384.48 07:45:07 INFO - PROCESS | 1636 | Values: 502.4 511.2 511.5 517.3 519.4 30142.0 39572.0 40843.6 40890.8 43367.6 30888.5 41625.6 41792.4 41840.0 43926.0 9254.9 9431.8 9433.5 9435.6 9448.0 8453.5 8707.9 10985.2 11320.8 11345.5 11254.9 11313.9 11314.2 11337.0 11342.0 8552.6 9874.5 11280.0 11312.2 11337.1 8539.7 10849.4 11163.6 11200.4 11373.0 38947.0 39301.0 39356.8 39433.6 39880.0 3136.6 3143.1 3246.4 3408.9 3483.3 29865.6 29887.9 30036.5 30204.0 30505.0 3197.7 3200.1 3203.6 3222.7 3316.1 474419.0 477775.0 477836.0 478153.0 482618.0 33115.5 33845.0 34072.4 58809.4 59213.4 34721.3 34786.8 34809.3 34865.8 34917.7 14026.9 14168.5 14181.6 14217.5 14312.3 10851.8 11074.1 12002.5 12105.2 12113.0 7373.9 7601.7 7785.4 7841.7 7846.1 3190.3 3206.4 3209.5 3237.4 3241.3 777.3 895.3 936.0 980.9 1037.2 27839.0 28998.6 29019.3 29749.0 29820.4 1014.9 1041.9 1094.3 1141.3 1154.1 15198.8 15200.0 15405.5 15466.8 15470.9 666853.0 674498.0 675117.0 675304.6 675510.0 55171.7 55680.0 55700.2 55773.4 55945.7 1209.4 1249.7 1318.3 1345.2 1369.2 28817.1 29748.6 30018.7 30598.5 30693.5 49660.8 54173.0 55788.7 55802.0 59287.7 615376.0 620746.0 621094.0 623582.0 623830.0 25979.0 26533.1 27594.6 39366.8 39989.0 45018.8 45353.1 45507.3 45529.8 45743.8 37428.8 37920.0 37982.4 38100.6 39326.8 32320.2 37701.4 38042.6 38271.8 39361.2 36889.4 37110.0 37391.8 37508.2 37540.8 31706.7 34405.3 34447.8 34519.0 34593.5 23787.8 24661.1 25285.0 25343.1 25750.5 07:45:07 INFO - PROCESS | 1636 | 07:45:07 INFO - PROCESS | 1636 | [#2] jquery.html Cycles:180 Average:34366.29 Median:25377.64 stddev:98446.01 (387.9%) stddev-sans-first:98689.05 07:45:07 INFO - PROCESS | 1636 | Values: 352.8 371.8 376.3 387.4 403.3 14777.3 15037.1 16300.8 19746.7 20607.0 19481.4 19525.6 20147.0 20283.5 20427.8 5163.3 5716.4 5805.2 6040.9 6046.8 5398.4 5907.4 5963.9 5969.8 5974.6 5643.2 5770.6 5910.8 5917.4 5920.3 5823.4 5870.6 5891.2 5895.5 5906.2 4884.4 5062.7 5131.5 5147.8 5152.9 25100.7 25318.0 25324.3 25431.0 25580.0 2170.3 2278.9 2316.9 2351.7 3046.9 12611.1 14388.1 14872.3 14891.0 15539.4 1811.9 1831.9 1844.6 1876.5 1890.2 115383.0 115529.2 125525.0 127240.4 127522.0 10504.6 10582.0 10821.4 11182.0 11477.7 13590.4 13591.1 13604.5 13612.6 13626.4 7086.4 7511.0 8171.6 8260.6 8743.6 7011.9 8310.6 8493.5 8542.6 8639.5 6479.2 6644.8 6656.4 6671.9 6742.2 1822.2 1858.3 1863.9 1874.6 1902.5 867.6 970.2 983.2 1056.3 1094.3 20655.6 21996.6 22034.6 22054.8 22079.0 963.0 972.8 973.1 1018.1 1032.0 9020.3 10688.4 11317.5 11568.7 11697.6 480631.0 606555.0 607465.9 622100.9 623575.0 82562.9 94669.0 123046.0 123708.4 136551.0 962.1 967.6 1008.5 1011.5 1012.1 18345.7 18720.7 19198.5 19215.6 19282.2 51339.7 51985.2 52188.5 52735.2 53283.7 75214.2 77511.6 78807.6 79171.6 80734.6 7163.7 7591.4 8061.2 8069.8 8178.7 12127.3 12910.9 12988.0 13072.2 13434.7 5399.3 7047.7 9179.4 11043.1 11379.7 9591.4 10730.3 11052.1 11205.4 11527.4 11401.7 11462.3 11622.9 11627.0 11706.5 7375.9 7523.1 7620.0 7702.8 7708.4 9679.2 10110.5 11211.0 11550.5 11574.1 07:45:07 INFO - PROCESS | 1636 | 07:45:07 INFO - PROCESS | 1636 | [#3] mootools.html Cycles:180 Average:5412.48 Median:4739.32 stddev:3032.55 (64.0%) stddev-sans-first:3026.50 07:45:07 INFO - PROCESS | 1636 | Values: 1458.6 1497.2 1626.3 1737.6 1799.2 8120.6 8538.7 8549.1 8555.0 8562.0 7471.6 7745.1 7747.7 8079.9 8283.8 2294.9 2322.3 2327.5 2329.2 2330.1 1944.2 2317.4 2360.5 2361.0 2361.8 2303.3 2309.8 2334.8 2334.9 2336.9 2289.1 2298.1 2321.1 2323.9 2330.7 2316.7 2335.8 2339.3 2343.5 2348.3 8344.6 8422.1 8432.0 8447.8 8465.8 5052.0 5217.6 5219.1 5227.2 5230.1 3231.2 3423.1 3499.5 3573.9 3586.9 1003.9 1048.6 1079.7 1086.7 1095.5 10917.0 11041.6 11063.3 11075.2 11093.6 4445.0 4472.6 4670.0 4671.3 4713.0 9174.9 9370.0 9427.1 9437.6 9447.2 5151.9 5159.6 5162.0 5166.1 5173.7 4191.8 4473.4 4703.4 4741.9 4748.9 3761.6 3774.8 3848.2 3894.1 3896.7 1246.2 1250.4 1254.1 1265.8 1268.7 732.8 761.1 763.4 841.1 865.7 5162.8 5233.4 5266.7 5365.0 5367.1 426.0 430.7 434.4 438.2 449.0 3474.3 3506.8 3517.1 3541.5 3603.6 7633.8 10327.4 10355.3 10357.1 10376.5 10261.9 10289.5 10293.9 10299.0 10307.0 7478.0 7791.2 7887.6 7889.2 7909.6 5426.0 5442.4 5518.0 5519.1 5619.7 7911.5 9419.2 9610.5 9621.7 9676.9 9617.7 9703.9 9737.9 9812.4 9851.4 5713.6 6000.9 6597.8 6951.0 7153.5 7739.7 8009.7 8013.7 8075.2 8075.9 7087.8 7198.6 7252.3 7263.0 7349.3 7002.9 7050.1 7053.8 7192.4 7197.9 6645.3 6811.5 6829.8 6835.6 6845.9 4570.2 4615.6 4667.6 4689.3 4722.4 4584.3 4617.7 4695.0 4736.8 4846.6 07:45:07 INFO - PROCESS | 1636 | 07:45:07 INFO - PROCESS | 1636 | [#4] prototype.html Cycles:180 Average:4368.31 Median:4947.36 stddev:1354.82 (27.4%) stddev-sans-first:1323.02 07:45:07 INFO - PROCESS | 1636 | Values: 257.3 308.7 309.5 313.5 318.1 4906.8 4962.3 5215.8 5235.4 5300.9 5174.7 5182.9 5218.7 5224.5 5241.3 3794.9 3910.1 3941.7 3959.0 3959.3 3670.8 3785.8 3942.9 3987.8 4001.9 3961.4 3980.5 3990.3 4032.0 4035.5 3739.7 3767.7 3815.8 3965.6 3982.4 2926.4 3501.9 3512.3 3516.4 3522.3 4623.5 4894.2 5029.4 5060.3 5103.7 3938.4 4049.9 4371.4 4586.5 4598.8 4518.7 4539.4 5432.7 5515.0 5541.9 3156.4 3419.0 3834.9 3869.9 3876.3 5048.1 5774.6 5784.2 5815.2 5856.6 2903.5 3081.0 3548.6 3798.4 3845.7 3762.3 3870.8 3873.9 3899.2 3931.3 3655.2 4350.2 4509.9 4557.0 4564.9 5299.6 5372.8 5380.8 5489.3 5624.3 1005.9 1205.9 1225.7 1243.0 1256.5 796.0 815.4 843.3 853.8 853.9 4330.1 4542.3 4730.0 4829.4 5061.3 4760.0 4997.6 5006.3 5189.4 5672.4 4882.1 5208.9 5210.3 5219.6 5227.5 2264.3 2493.7 2668.4 2809.4 2814.4 4954.7 5058.7 5515.2 5548.4 5559.5 5000.9 5263.7 5404.9 5424.0 5440.9 4621.5 5043.1 5529.9 5551.8 5581.4 4560.4 4940.1 5041.7 5317.3 5333.4 4337.2 4527.5 4798.4 5009.9 5653.5 4975.7 5148.6 5342.5 5986.3 5991.0 3003.0 3828.5 3877.3 3891.5 3931.4 5567.9 5608.8 5690.3 5708.3 5723.4 5269.9 5553.4 5562.2 5563.0 5569.4 5123.3 5477.8 5546.7 5549.6 5551.9 4350.9 5422.5 5463.7 5468.3 5483.3 5336.9 5396.5 5467.9 5489.5 5578.9 4537.9 4722.0 4758.7 5381.8 5428.3 07:45:07 INFO - PROCESS | 1636 | 07:45:07 INFO - PROCESS | 1636 | [#5] yui.html Cycles:180 Average:19741.93 Median:4370.32 stddev:29163.01 (667.3%) stddev-sans-first:29209.74 07:45:07 INFO - PROCESS | 1636 | Values: 689.2 703.2 741.3 753.6 788.8 14329.7 14733.9 14835.9 14838.9 14879.7 13061.2 13094.9 13126.0 13134.7 13264.2 540.3 650.5 660.8 661.7 805.6 665.6 669.2 803.1 829.9 830.4 859.3 872.9 874.8 875.9 876.0 865.8 866.1 866.7 868.2 871.2 508.6 523.3 526.3 648.2 648.9 11990.6 12042.6 12090.5 13272.7 13893.4 11818.3 12618.1 12951.6 13914.9 14019.4 11053.1 11174.8 11312.2 11320.5 11351.1 3570.7 3616.5 3621.6 3827.2 3844.7 76230.2 84608.0 87786.7 88327.6 91933.1 10194.6 10340.5 10648.3 10766.8 10847.2 20024.7 20046.9 20072.0 20075.3 20401.0 7739.9 7751.8 7819.6 7878.3 7884.3 4131.8 4142.4 4227.8 4375.1 4476.2 3364.2 3468.0 3468.8 3470.9 3476.0 631.0 634.0 634.3 653.9 658.2 502.9 546.5 564.3 634.9 674.7 7667.3 8497.4 8660.8 8917.4 9045.1 602.9 603.0 611.9 618.8 646.4 4352.9 4365.5 4396.4 4453.5 4484.6 88751.6 111786.0 113419.9 116521.0 120590.4 96408.0 101815.5 103329.5 104807.0 105232.0 58153.8 60386.7 61187.7 61519.4 79561.6 8788.6 9222.4 9367.4 9426.9 9475.0 48014.8 50870.0 51048.0 53262.5 54562.7 56718.0 69940.7 70640.9 73114.3 75378.0 11297.0 11837.2 12041.5 12083.5 12299.9 12528.8 13545.5 13609.7 13710.4 13908.9 12344.2 12492.6 12608.4 12985.4 13032.9 12106.2 12589.6 12891.7 12941.2 13042.5 12305.3 12403.0 12421.6 12798.3 12809.6 9954.3 10535.5 10633.8 10866.2 11001.4 8648.4 10484.6 10775.5 10843.3 10883.7 07:45:07 INFO - PROCESS | 1636 | -------- Summary: end -------- 07:45:07 INFO - PROCESS | 1636 | 07:45:07 INFO - TEST-INFO | 1636: exit 0 07:45:07 INFO - TEST-OK | dromaeo_css | took 1119833ms 07:45:07 INFO - TEST-START | kraken 07:45:07 INFO - operating with platform_type : mac_ 07:45:07 INFO - Initialising browser for kraken test... 07:45:07 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/tmpVnINuG/profile http://localhost:49249/getInfo.html) 07:45:10 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 07:45:10 INFO - PROCESS | 1647 | colorDepth:24 07:45:10 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 07:45:10 INFO - PROCESS | 1647 | __metrics 07:45:10 INFO - PROCESS | 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:45:10 INFO - PROCESS | 1647 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:45:11 INFO - TEST-INFO | 1647: exit 0 07:45:11 INFO - Browser initialized. 07:45:11 INFO - Running cycle 1/1 for kraken test... 07:45:11 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/tmpVnINuG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:45:23 INFO - PROCESS | 1648 | RSS: Main: 223088640 07:45:23 INFO - PROCESS | 1648 | 07:45:26 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection) 07:45:27 INFO - PROCESS | 1648 | RSS: Main: 272375808 07:45:27 INFO - PROCESS | 1648 | 07:45:30 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-dft) 07:45:30 INFO - PROCESS | 1648 | RSS: Main: 282132480 07:45:30 INFO - PROCESS | 1648 | 07:45:34 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-fft) 07:45:34 INFO - PROCESS | 1648 | RSS: Main: 379461632 07:45:34 INFO - PROCESS | 1648 | 07:45:36 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator) 07:45:37 INFO - PROCESS | 1648 | RSS: Main: 283607040 07:45:37 INFO - PROCESS | 1648 | 07:45:39 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:45:40 INFO - PROCESS | 1648 | RSS: Main: 284987392 07:45:40 INFO - PROCESS | 1648 | 07:45:42 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom) 07:45:43 INFO - PROCESS | 1648 | RSS: Main: 324677632 07:45:43 INFO - PROCESS | 1648 | 07:45:46 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate) 07:45:47 INFO - PROCESS | 1648 | RSS: Main: 324857856 07:45:47 INFO - PROCESS | 1648 | 07:45:49 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial) 07:45:50 INFO - PROCESS | 1648 | RSS: Main: 321953792 07:45:50 INFO - PROCESS | 1648 | 07:45:52 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:45:52 INFO - PROCESS | 1648 | RSS: Main: 301203456 07:45:52 INFO - PROCESS | 1648 | 07:45:54 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:45:55 INFO - PROCESS | 1648 | RSS: Main: 301322240 07:45:55 INFO - PROCESS | 1648 | 07:45:57 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:45:58 INFO - PROCESS | 1648 | RSS: Main: 304357376 07:45:58 INFO - PROCESS | 1648 | 07:46:01 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:46:01 INFO - PROCESS | 1648 | RSS: Main: 313831424 07:46:01 INFO - PROCESS | 1648 | 07:46:05 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:46:05 INFO - PROCESS | 1648 | RSS: Main: 313442304 07:46:05 INFO - PROCESS | 1648 | 07:46:07 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49249/tests/kraken/driver.html?testName=ai-astar) 07:46:07 INFO - PROCESS | 1648 | RSS: Main: 357253120 07:46:07 INFO - PROCESS | 1648 | 07:46:07 INFO - PROCESS | 1648 | __start_tp_report 07:46:07 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 07:46:07 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 07:46:07 INFO - PROCESS | 1648 | |i|pagename|runs| 07:46:07 INFO - PROCESS | 1648 | |0;ai-astar;114;115;115;114;114;114;113;114;114;114 07:46:07 INFO - PROCESS | 1648 | |1;audio-beat-detection;149;114;116;172;142;144;112;113;143;116 07:46:07 INFO - PROCESS | 1648 | |2;audio-dft;190;262;217;184;185;182;185;186;183;185 07:46:07 INFO - PROCESS | 1648 | |3;audio-fft;79;59;60;90;62;60;59;59;83;65 07:46:07 INFO - PROCESS | 1648 | |4;audio-oscillator;84;67;94;70;68;69;89;66;69;92 07:46:07 INFO - PROCESS | 1648 | |5;imaging-gaussian-blur;118;129;98;85;132;84;89;84;87;84 07:46:07 INFO - PROCESS | 1648 | |6;imaging-darkroom;95;94;94;94;94;93;112;127;140;147 07:46:07 INFO - PROCESS | 1648 | |7;imaging-desaturate;81;81;80;80;82;81;83;95;82;83 07:46:07 INFO - PROCESS | 1648 | |8;json-parse-financial;54;53;53;54;57;57;57;57;57;57 07:46:07 INFO - PROCESS | 1648 | |9;json-stringify-tinderbox;63;71;77;73;72;67;68;68;68;69 07:46:07 INFO - PROCESS | 1648 | |10;stanford-crypto-aes;85;86;86;85;85;110;100;104;85;85 07:46:07 INFO - PROCESS | 1648 | |11;stanford-crypto-ccm;149;147;148;146;146;151;156;162;203;171 07:46:07 INFO - PROCESS | 1648 | |12;stanford-crypto-pbkdf2;169;171;168;166;210;167;211;226;193;168 07:46:07 INFO - PROCESS | 1648 | |13;stanford-crypto-sha256-iterative;67;66;64;67;66;66;66;66;65;67 07:46:07 INFO - PROCESS | 1648 | __end_tp_report 07:46:07 INFO - PROCESS | 1648 | __start_cc_report 07:46:07 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,2045 07:46:07 INFO - PROCESS | 1648 | __end_cc_report 07:46:07 INFO - PROCESS | 1648 | __startTimestamp1480002367617__endTimestamp 07:46:07 INFO - PROCESS | 1648 | 07:46:07 INFO - PROCESS | 1648 | ------- Summary: start ------- 07:46:07 INFO - PROCESS | 1648 | Number of tests: 14 07:46:07 INFO - PROCESS | 1648 | 07:46:07 INFO - PROCESS | 1648 | [#0] ai-astar Cycles:10 Average:114.10 Median:114.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 07:46:07 INFO - PROCESS | 1648 | Values: 114.0 115.0 115.0 114.0 114.0 114.0 113.0 114.0 114.0 114.0 07:46:07 INFO - PROCESS | 1648 | 07:46:07 INFO - PROCESS | 1648 | [#1] audio-beat-detection Cycles:10 Average:132.10 Median:142.50 stddev:20.69 (14.5%) stddev-sans-first:21.02 07:46:07 INFO - PROCESS | 1648 | Values: 149.0 114.0 116.0 172.0 142.0 144.0 112.0 113.0 143.0 116.0 07:46:07 INFO - PROCESS | 1648 | 07:46:07 INFO - PROCESS | 1648 | [#2] audio-dft Cycles:10 Average:195.90 Median:185.50 stddev:25.40 (13.7%) stddev-sans-first:26.85 07:46:07 INFO - PROCESS | 1648 | Values: 190.0 262.0 217.0 184.0 185.0 182.0 185.0 186.0 183.0 185.0 07:46:07 INFO - PROCESS | 1648 | 07:46:07 INFO - PROCESS | 1648 | [#3] audio-fft Cycles:10 Average:67.60 Median:63.50 stddev:11.76 (18.5%) stddev-sans-first:11.73 07:46:07 INFO - PROCESS | 1648 | Values: 79.0 59.0 60.0 90.0 62.0 60.0 59.0 59.0 83.0 65.0 07:46:07 INFO - PROCESS | 1648 | 07:46:07 INFO - PROCESS | 1648 | [#4] audio-oscillator Cycles:10 Average:76.80 Median:77.00 stddev:11.48 (14.9%) stddev-sans-first:11.87 07:46:07 INFO - PROCESS | 1648 | Values: 84.0 67.0 94.0 70.0 68.0 69.0 89.0 66.0 69.0 92.0 07:46:07 INFO - PROCESS | 1648 | 07:46:07 INFO - PROCESS | 1648 | [#5] imaging-gaussian-blur Cycles:10 Average:99.00 Median:84.50 stddev:19.62 (23.2%) stddev-sans-first:19.57 07:46:07 INFO - PROCESS | 1648 | Values: 118.0 129.0 98.0 85.0 132.0 84.0 89.0 84.0 87.0 84.0 07:46:07 INFO - PROCESS | 1648 | 07:46:07 INFO - PROCESS | 1648 | [#6] imaging-darkroom Cycles:10 Average:109.00 Median:94.00 stddev:21.32 (22.7%) stddev-sans-first:22.00 07:46:07 INFO - PROCESS | 1648 | Values: 95.0 94.0 94.0 94.0 94.0 93.0 112.0 127.0 140.0 147.0 07:46:07 INFO - PROCESS | 1648 | 07:46:07 INFO - PROCESS | 1648 | [#7] imaging-desaturate Cycles:10 Average:82.80 Median:82.00 stddev:4.42 (5.4%) stddev-sans-first:4.64 07:46:07 INFO - PROCESS | 1648 | Values: 81.0 81.0 80.0 80.0 82.0 81.0 83.0 95.0 82.0 83.0 07:46:07 INFO - PROCESS | 1648 | 07:46:07 INFO - PROCESS | 1648 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:57.00 stddev:1.84 (3.2%) stddev-sans-first:1.86 07:46:07 INFO - PROCESS | 1648 | Values: 54.0 53.0 53.0 54.0 57.0 57.0 57.0 57.0 57.0 57.0 07:46:07 INFO - PROCESS | 1648 | 07:46:07 INFO - PROCESS | 1648 | [#9] json-stringify-tinderbox Cycles:10 Average:69.60 Median:70.00 stddev:3.84 (5.5%) stddev-sans-first:3.24 07:46:07 INFO - PROCESS | 1648 | Values: 63.0 71.0 77.0 73.0 72.0 67.0 68.0 68.0 68.0 69.0 07:46:07 INFO - PROCESS | 1648 | 07:46:07 INFO - PROCESS | 1648 | [#10] stanford-crypto-aes Cycles:10 Average:91.10 Median:85.00 stddev:9.67 (11.4%) stddev-sans-first:10.00 07:46:07 INFO - PROCESS | 1648 | Values: 85.0 86.0 86.0 85.0 85.0 110.0 100.0 104.0 85.0 85.0 07:46:07 INFO - PROCESS | 1648 | 07:46:07 INFO - PROCESS | 1648 | [#11] stanford-crypto-ccm Cycles:10 Average:157.90 Median:153.50 stddev:17.80 (11.6%) stddev-sans-first:18.59 07:46:07 INFO - PROCESS | 1648 | Values: 149.0 147.0 148.0 146.0 146.0 151.0 156.0 162.0 203.0 171.0 07:46:07 INFO - PROCESS | 1648 | 07:46:07 INFO - PROCESS | 1648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.90 Median:182.00 stddev:23.00 (12.6%) stddev-sans-first:23.66 07:46:07 INFO - PROCESS | 1648 | Values: 169.0 171.0 168.0 166.0 210.0 167.0 211.0 226.0 193.0 168.0 07:46:07 INFO - PROCESS | 1648 | 07:46:07 INFO - PROCESS | 1648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.00 stddev:0.94 (1.4%) stddev-sans-first:0.93 07:46:07 INFO - PROCESS | 1648 | Values: 67.0 66.0 64.0 67.0 66.0 66.0 66.0 66.0 65.0 67.0 07:46:07 INFO - PROCESS | 1648 | -------- Summary: end -------- 07:46:07 INFO - PROCESS | 1648 | 07:46:08 INFO - TEST-INFO | 1648: exit 0 07:46:08 INFO - TEST-OK | kraken | took 60992ms 07:46:08 INFO - SUITE-END | took 1180s 07:46:09 INFO - Completed test suite (00:19:41) 07:46:09 INFO - javascript benchmark 07:46:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2742.958855617166, 3355.194753259217, 3367.8147701876387, 3383.154211447138, 3413.0102270341604, 4650.372199753032, 5435.293411856458, 5605.859853503654, 5664.122061080534, 5720.056190728525, 5589.0219211638005, 5610.24261724667, 5644.350899646542, 5651.8587035324135, 5683.744231509571, 5128.282040514312, 5156.510131537498, 5159.535641792238, 5231.816886408977, 5295.655782374152, 5083.339165908426, 5156.561692256163, 5161.045206636784, 5190.71451070191, 5203.609729270301, 5233.476652334755, 5245.973770131144, 5250.6587071840395, 5279.996800607892, 5298.258243845865, 5247.947520524784, 5250.028744682246, 5259.053370393328, 5262.8684282892855, 5284.524392804666, 5015.247712843066, 5141.5886729061585, 5164.2770012198325, 5165.044466773659, 5188.766505507261, 5593.804216852391, 5606.0, 5620.437956204388, 5656.745446454878, 5697.4587414195585, 6133.539984501181, 6159.4456498915315, 6161.4146656067605, 6217.937820621826, 6218.31598524162, 6279.654618995954, 6288.213973253343, 6312.715927783215, 6313.273973493061, 6315.431611155018, 5384.73076346185, 5474.808381706701, 5481.369642491165, 5707.429257074259, 5799.0, 6334.873302533923, 6338.144350512622, 6343.936560634427, 6356.110144579762, 6370.777022804273, 6064.0297552392285, 6109.908351374733, 6111.633302001894, 6115.938840611537, 6118.357572454917, 6116.29662588808, 6130.551011451118, 6141.499345373184, 6174.351693072253, 6220.937790622126, 6111.755529778848, 6123.557704288954, 6147.200863887755, 6182.938170618326, 6190.473809726223, 6070.757169713251, 6194.163787888668, 6200.596961197508, 6200.906986395117, 6214.658193799384, 4197.433346498245, 4915.43472500667, 4921.237208232729, 4924.212126059813, 4936.086823937511, 3648.2703459308245, 4340.652747780233, 4341.761203133858, 4363.781810909441, 4409.5149533551285, 5726.169705392695, 5731.942680573225, 5737.02470579994, 5743.971280143656, 5766.481016708516, 6063.999440092423, 6068.726907289182, 6072.787452439232, 6226.782062627786, 6259.96870015647, 5638.069718496484, 5671.1493276008105, 5687.090065589486, 5709.486146246774, 5749.655020698688, 5345.679259244458, 5351.57187425005, 5395.163749618815, 5420.83737487876, 5426.213199086111, 6312.16812504667, 6317.873642527215, 6325.96837015812, 6327.145835312358, 6334.809955701337, 4854.975725121493, 6209.906851397142, 6225.221847269091, 6241.469475094486, 6247.843803904939, 6197.721102550306, 6246.718897649525, 6248.250209974832, 6261.3112557617715, 6312.337204593451, 6125.295591007091, 6132.101331180348, 6137.5302083125, 6137.8366772191985, 6159.599626024294, 6165.691715414301, 6179.660118693515, 6207.65857877803, 6214.378562143749, 6244.0, 5875.441833026027, 5939.940600593939, 6019.578629495893, 6028.728707208098, 6071.635701857903, 6076.817695469143, 6082.330943596289, 6106.389361063858, 6108.053251745987, 6144.477719393902, 5301.1783173608155, 5891.05743081105, 5895.64626122434, 5902.026165682614, 5902.4097590240635, 5807.245058142584, 5953.3153687324775, 5965.373635768182, 6060.212172417558, 6081.422264884829, 4711.858644240679, 5906.822795316148, 5924.9703751480965, 5935.317438494456, 5938.584299099021], "value": 5630.6620985736345, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.44228905914434, 511.2076281763268, 511.53705896163984, 517.262900366977, 519.4260342321733, 30142.0, 39572.0, 40843.59156408439, 40890.7955460223, 43367.566324336745, 30888.53667194988, 41625.58374416255, 41792.373114403235, 41840.0, 43926.0, 9254.86117708235, 9431.81136377272, 9433.481158536279, 9435.622575096988, 9447.952760236189, 8453.492790432563, 8707.869381959244, 10985.231033827635, 11320.773584528304, 11345.489452974594, 11254.887451125465, 11313.886861131406, 11314.151438642093, 11337.0, 11342.0, 8552.615132318999, 9874.456904870229, 11280.0, 11312.151588630844, 11337.1497137715, 8539.701110461167, 10849.403282819441, 11163.553457861675, 11200.383978881158, 11372.976432121066, 38947.02632434184, 39301.0, 39356.80321598402, 39433.60566394328, 39880.0, 3136.639286482038, 3143.0727935258924, 3246.3507298540385, 3408.931821363584, 3483.3207524532727, 29865.55201671977, 29887.850560747276, 30036.549451758023, 30204.0, 30505.0, 3197.728193103566, 3200.1199670090678, 3203.599550056243, 3222.726068284199, 3316.05492434655, 474419.0, 477775.0, 477836.0, 478153.0, 482618.0, 33115.50326745101, 33845.0, 34072.422395648595, 58809.41190588125, 59213.407865921654, 34721.30557388889, 34786.82606587002, 34809.30381392358, 34865.82567087199, 34917.650823491946, 14026.929865350812, 14168.504102356368, 14181.645458863612, 14217.50238741639, 14312.28438578067, 10851.78296434067, 11074.114070874311, 12002.459889305002, 12105.15263931534, 12112.970397516134, 7373.852522949511, 7601.733939312097, 7785.416093792988, 7841.725539606086, 7846.136924938252, 3190.345979074284, 3206.406814739281, 3209.4864821628894, 3237.3687131009697, 3241.286916878283, 777.292663676052, 895.3285036222833, 936.0312077000272, 980.8759102259185, 1037.1650821088945, 27839.0, 28998.635252478358, 29019.274518137216, 29749.0, 29820.403591928472, 1014.8832884218409, 1041.9479026048666, 1094.2777766674117, 1141.275290190735, 1154.1401655766508, 15198.772018419733, 15200.0, 15405.537833865003, 15466.845331546765, 15470.922645386701, 666853.0, 674498.0, 675117.0, 675304.6234768893, 675510.0, 55171.72414137904, 55680.0, 55700.16449753257, 55773.44226557682, 55945.720271398386, 1209.371127013948, 1249.731307768834, 1318.2815365625565, 1345.159275452842, 1369.1784929042694, 28817.135485935454, 29748.553771693874, 30018.699813002466, 30598.541021884244, 30693.465326733127, 49660.751696241285, 54173.0, 55788.72105639446, 55802.0, 59287.70356148192, 615376.0, 620746.0, 621094.0, 623582.0, 623830.0, 25978.96084156688, 26533.07134250292, 27594.5860812092, 39366.80316598398, 39989.0, 45018.77490612526, 45353.092938141715, 45507.31739023851, 45529.77235113803, 45743.77128114338, 37428.81285593664, 37920.0, 37982.43026354662, 38100.61899380971, 39326.80336598299, 32320.191995199366, 37701.4344784823, 38042.61957380391, 38271.80864095662, 39361.21277574375, 36889.44665829961, 37110.0, 37391.81304093462, 37508.24983500369, 37540.81229593835, 31706.682933170374, 34405.31189376248, 34447.82776086204, 34519.0, 34593.48109778305, 23787.810609469805, 24661.13686020981, 25284.988600456512, 25343.112991045225, 25750.484990299716], "value": 18620.309556283464, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.79008989651146, 371.8494009925979, 376.33764574349146, 387.3743903595693, 403.3365114386834, 14777.26113694315, 15037.09777413356, 16300.755488667679, 19746.70379944302, 20607.0, 19481.415557533262, 19525.609487810234, 20146.992650367498, 20283.49291267719, 20427.795722042774, 5163.251328557376, 5716.371199168098, 5805.24531810864, 6040.909386359208, 6046.848828779293, 5398.433164517728, 5907.350191478934, 5963.880722385549, 5969.791057312995, 5974.611650242742, 5643.238162848024, 5770.596058275923, 5910.822675319726, 5917.378675239102, 5920.319163296233, 5823.4467725566, 5870.618409803392, 5891.175235466995, 5895.528357731371, 5906.23218981534, 4884.389451318585, 5062.746862656889, 5131.486851314876, 5147.794088236466, 5152.871178220537, 25100.748992510024, 25318.0, 25324.28906339803, 25431.0, 25580.0, 2170.34889533141, 2278.9180128313787, 2316.860988340705, 2351.6825228594093, 3046.893358732456, 12611.117221794479, 14388.136711797326, 14872.256387180705, 14891.032082914577, 15539.378424862993, 1811.9094045297682, 1831.9358822441284, 1844.5665268661853, 1876.502726777405, 1890.1777726688933, 115383.0, 115529.19971519739, 125525.0, 127240.36379818042, 127522.0, 10504.632337868137, 10582.0, 10821.404822734825, 11182.0, 11477.655670329903, 13590.38843252056, 13591.116577422436, 13604.455821767018, 13612.591622251348, 13626.386812593457, 7086.362227399456, 7510.962445187848, 8171.63227654757, 8260.628271727786, 8743.557313043404, 7011.92988070123, 8310.6287462568, 8493.490390576462, 8542.572871356531, 8639.481631102153, 6479.1901012373455, 6644.77400070972, 6656.36764507371, 6671.914389055506, 6742.190937087581, 1822.2437688359448, 1858.2659849359427, 1863.9347622833407, 1874.5782199005114, 1902.4958386027713, 867.5878957495139, 970.1898914406408, 983.1790454970037, 1056.287006270764, 1094.2996482251365, 20655.586888262453, 21996.560068798855, 22034.559308813736, 22054.779452205592, 22079.0, 962.9903700962901, 972.8394814855611, 973.1193270090579, 1018.0786388318584, 1031.9845202321972, 9020.323475739346, 10688.358698478116, 11317.547298107984, 11568.652940411634, 11697.649070527897, 480631.0, 606555.0, 607465.9253407409, 622100.8894955587, 623575.0, 82562.93592660231, 94669.0, 123046.0, 123708.38145809213, 136551.0, 962.129273007915, 967.583938906263, 1008.5108722269694, 1011.489197955028, 1012.0840639221516, 18345.72481412753, 18720.719189212447, 19198.520036998627, 19215.615687685888, 19282.195636875575, 51339.743301283255, 51985.22022169746, 52188.47811521988, 52735.20897186622, 53283.671632836405, 75214.24785752072, 77511.61244193742, 78807.60596196982, 79171.60414197893, 80734.596327018, 7163.7492687755675, 7591.392688585011, 8061.1938806118915, 8069.838603227785, 8178.713745019134, 12127.272363658209, 12910.935445322713, 12988.025898057494, 13072.215667059627, 13434.731305373642, 5399.325084364455, 7047.7180912762915, 9179.449233046038, 11043.061339786209, 11379.715507112389, 9591.42451452915, 10730.302530335503, 11052.060574851228, 11205.383703896365, 11527.423628818358, 11401.657950261173, 11462.332053742723, 11622.94188529052, 11627.0, 11706.531738730355, 7375.852482950204, 7523.1348394935285, 7620.0, 7702.768916932501, 7708.4218683600075, 9679.177269932135, 10110.494475276353, 11211.0, 11550.48022838991, 11574.131940104695], "value": 9564.082032396811, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1458.5988853065403, 1497.1765528959072, 1626.30881875203, 1737.5916659585002, 1799.199356286453, 8120.593970301484, 8538.658453661854, 8549.145085491447, 8555.0, 8561.95719021404, 7471.55170689759, 7745.070591529025, 7747.651355688993, 8079.878801817978, 8283.792905177374, 2294.8623082615013, 2322.3381336319153, 2327.464683122883, 2329.219711396684, 2330.114556468548, 1944.212593899473, 2317.374308936586, 2360.492494113765, 2360.976390236093, 2361.8346715729904, 2303.2974942642486, 2309.7690230976937, 2334.754850740673, 2334.9066037358484, 2336.918207862725, 2289.1186893046215, 2298.0577963035075, 2321.0599707118645, 2323.8605683659034, 2330.697009388786, 2316.6756654068445, 2335.8131349492, 2339.3099035784485, 2343.5430091132234, 2348.283773449097, 8344.62449189782, 8422.115677853859, 8432.030316513617, 8447.83104337916, 8465.830683386359, 5051.964347308793, 5217.5565076968505, 5219.086659834514, 5227.242049902781, 5230.058589453897, 3231.2083539532728, 3423.1271025888486, 3499.5450591423205, 3573.874914377984, 3586.8923932282073, 1003.8845532763679, 1048.6487026846016, 1079.6760971708534, 1086.7011571817734, 1095.4851219926702, 10917.0, 11041.613543525938, 11063.336199828036, 11075.16936229786, 11093.55625774968, 4445.0, 4472.619827314715, 4669.976650116728, 4671.299305104194, 4712.986707857827, 9174.86237706435, 9370.016148304467, 9427.104425079606, 9437.622495100257, 9447.196988255937, 5151.896962060813, 5159.613029022838, 5162.045021670928, 5166.121759300928, 5173.663711858717, 4191.811368488365, 4473.418455600751, 4703.365045718784, 4741.881452963704, 4748.928766068511, 3761.6458075092946, 3774.7735135891935, 3848.1726428817938, 3894.065424298148, 3896.688264938798, 1246.1526162209602, 1250.4123062160952, 1254.0845183016268, 1265.7784887644625, 1268.7399083187925, 732.7801659502235, 761.067692077201, 763.4045444553258, 841.1336323586715, 865.6623916672503, 5162.793488260502, 5233.371995360581, 5266.683998960075, 5365.007473617393, 5367.087595108841, 426.02227886998793, 430.74370749404056, 434.4200492342758, 438.2352794373838, 448.97530635815343, 3474.3051389722154, 3506.8246587671024, 3517.067976986101, 3541.468779683068, 3603.5855876574005, 7633.770986870402, 10327.380357178594, 10355.326903751233, 10357.119644830274, 10376.533056012497, 10261.9486902565, 10289.536970836181, 10293.948530257301, 10299.0, 10307.020833020852, 7477.999999999891, 7791.181925897699, 7887.605619718991, 7889.2110788920645, 7909.644066017043, 5425.972870135624, 5442.428545002717, 5518.006758783457, 5519.089350257163, 5619.747111380079, 7911.525308481279, 9419.152276295166, 9610.51947402613, 9621.663241786504, 9676.903230967882, 9617.663381781604, 9703.93256741772, 9737.9026209737, 9812.362196457207, 9851.359661621971, 5713.571482138939, 6000.949833779158, 6597.835054123493, 6950.965245173742, 7153.535020223669, 7739.729109481141, 8009.679612815655, 8013.6794528218215, 8075.232852878967, 8075.9192408077515, 7087.751928682472, 7198.640067996684, 7252.347288744038, 7262.963685181751, 7349.265073492608, 7002.862367814239, 7050.083489146583, 7053.788386348418, 7192.388646965067, 7197.928020719726, 6645.335466453316, 6811.455083593201, 6829.795106146824, 6835.624040677811, 6845.931540684729, 4570.245909425041, 4615.607673347803, 4667.649926255476, 4689.343491911203, 4722.433308003062, 4584.289435137559, 4617.722936623679, 4694.953050469451, 4736.763161841963, 4846.5638092571835], "value": 4296.22839218112, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [257.32452312679214, 308.6604734791729, 309.4522694830151, 313.53596676918147, 318.0950196690415, 4906.828261010861, 4962.280469331945, 5215.765290561924, 5235.397929238139, 5300.92048619271, 5174.715390653522, 5182.896342073156, 5218.660787048839, 5224.492243723015, 5241.3186285782895, 3794.90512737181, 3910.1397692507603, 3941.7437866538585, 3958.9802050989706, 3959.3467077932105, 3670.7513850242594, 3785.848566057354, 3942.8619998300064, 3987.760734355948, 4001.9199616007663, 3961.3859851722887, 3980.5073097588406, 3990.3240638931575, 4031.984007996002, 4035.47538819953, 3739.7098001189547, 3767.7362584619086, 3815.790131542764, 3965.5637879833193, 3982.4026396040535, 2926.356201635637, 3501.8774342897877, 3512.3150985557827, 3516.40221162403, 3522.2603253316834, 4623.514530974233, 4894.241392584155, 5029.4216165141115, 5060.291559181717, 5103.744812759347, 3938.3895496197847, 4049.919001619951, 4371.426286536845, 4586.54134586542, 4598.816047358102, 4518.683692141552, 4539.388517076456, 5432.728363581805, 5515.0, 5541.944580554183, 3156.4476216662224, 3419.0, 3834.8878825140678, 3869.929587670583, 3876.321643712339, 5048.115317042032, 5774.566907481913, 5784.248047753765, 5815.244018277639, 5856.59003869725, 2903.491888919473, 3081.0140754958647, 3548.6451354864307, 3798.4492248623797, 3845.673117784964, 3762.3227818992846, 3870.767753934773, 3873.9418908716393, 3899.200663863901, 3931.253061918226, 3655.2324011957785, 4350.2387082260475, 4509.864704058883, 4556.974680696817, 4564.931526027112, 5299.6025298102795, 5372.803182106641, 5380.811671591476, 5489.286392768914, 5624.268845050117, 1005.8943810899897, 1205.8733832947414, 1225.6997035726304, 1242.9937850310691, 1256.5413624027165, 796.0, 815.3721634341642, 843.308487040633, 853.804673457165, 853.9368486234554, 4330.050269317762, 4542.250528662799, 4729.952700472952, 4829.420469543677, 5061.266116413173, 4759.976200119047, 4997.575206107522, 5006.320354539411, 5189.40321862983, 5672.404397538199, 4882.096812089775, 5208.921866172011, 5210.322658054429, 5219.634625576249, 5227.477252274818, 2264.29806759905, 2493.675822143146, 2668.3989606236105, 2809.367892224274, 2814.437112577493, 4954.653174277766, 5058.721770302595, 5515.200295957144, 5548.361938377138, 5559.471850174227, 5000.89998200034, 5263.733532189908, 5404.891902161935, 5424.0, 5440.945590544123, 4621.514740952219, 5043.092243396224, 5529.917051244234, 5551.751540625892, 5581.386047534849, 4560.3843481129625, 4940.061388336205, 5041.706023759108, 5317.308749862493, 5333.359996800409, 4337.240982828055, 4527.5019747827055, 4798.352222449925, 5009.924851127309, 5653.491185793298, 4975.676581022222, 5148.58811295088, 5342.535532006316, 5986.3238293277955, 5991.011483105239, 3002.954955675623, 3828.5214348206473, 3877.302085624615, 3891.5135608048995, 3931.3906344516645, 5567.944320556742, 5608.80298230005, 5690.325546992906, 5708.34354049273, 5723.399043100415, 5269.920951185659, 5553.416891226363, 5562.193481945176, 5562.972185139049, 5569.443055694398, 5123.28274041627, 5477.849651573214, 5546.667199968015, 5549.640338117187, 5551.945130425206, 4350.891227719332, 5422.457754224546, 5463.672179669074, 5468.343798744176, 5483.271375464722, 5336.945276068819, 5396.514313711785, 5467.890642187054, 5489.478499542537, 5578.916316255179, 4537.886552836206, 4722.0, 4758.714477131245, 5381.811636592701, 5428.281627085459], "value": 3932.575380865177, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [689.2452764223175, 703.1807943745537, 741.3105811595218, 753.6118898767132, 788.8343447875944, 14329.713405731893, 14733.852661473395, 14835.925820370896, 14838.851611483882, 14879.702405951875, 13061.151025183357, 13094.869051309484, 13126.015548833851, 13134.73730525389, 13264.204147751117, 540.2760301196396, 650.5023656902473, 660.7654282729634, 661.6790856434625, 805.5851236613134, 665.640554100785, 669.1635455680399, 803.1326167738857, 829.9211574900374, 830.3689196210893, 859.3211363023204, 872.8690696395528, 874.7638137702817, 875.8686197070426, 876.0, 865.7662431143556, 866.1079088538816, 866.6576702202594, 868.2489646455833, 871.237667041342, 508.58804368461665, 523.3379774585114, 526.3184176491453, 648.2286079565303, 648.9480841532666, 11990.640280791591, 12042.578509752117, 12090.516379344816, 13272.734545309137, 13893.374798134008, 11818.349990750505, 12618.116731828775, 12951.611451656465, 13914.860851391457, 14019.369128389146, 11053.081378786443, 11174.776504469946, 11312.151588630886, 11320.490577924013, 11351.091912646969, 3570.7143428525656, 3616.4756110364115, 3621.6016238213774, 3827.1962887793597, 3844.673202777767, 76230.23769762287, 84608.0, 87786.68319975205, 88327.55836220842, 91933.08066919312, 10194.643187488402, 10340.482975851177, 10648.254622176528, 10766.784664306826, 10847.240693151562, 20024.69962950557, 20046.89976550108, 20072.0, 20075.29736459217, 20401.0, 7739.883901741478, 7751.844963100706, 7819.5699236541395, 7878.330341921001, 7884.329831964233, 4131.772752498581, 4142.399352093929, 4227.788610569459, 4375.1031038637, 4476.216662084123, 3364.175776934667, 3467.9826600866836, 3468.757186996886, 3470.9132271692906, 3476.0, 630.9652969086744, 633.9809805705836, 634.3402861024483, 653.9051837483538, 658.170704911805, 502.8994201159783, 546.5081426715988, 564.2946317103621, 634.9365063493706, 674.6795272245644, 7667.271609197117, 8497.36269779769, 8660.826783464296, 8917.375783695208, 9045.090585773009, 602.9125776762345, 602.978895738647, 611.8898598252268, 618.8014322819647, 646.4311405962723, 4352.869413917588, 4365.541618130051, 4396.406485124531, 4453.487848897418, 4484.618807401343, 88751.55624221837, 111786.0, 113419.86580134257, 116521.0, 120590.3970480142, 96408.0, 101815.49092254491, 103329.48335258276, 104806.95193047971, 105232.0, 58153.82938994193, 60386.69806651027, 61187.694061530296, 61519.38480615136, 79561.60219198867, 8788.648454061766, 9222.362170364027, 9367.391119577205, 9426.905730942603, 9474.95262523683, 48014.759926200146, 50870.0, 51048.0, 53262.46737532575, 54562.727186363816, 56718.0, 69940.6502967482, 70640.94038589529, 73114.26885731074, 75378.0, 11297.0, 11837.150886617355, 12041.531486045515, 12083.516659333529, 12299.93850030744, 12528.812067819172, 13545.458181672622, 13609.727805444034, 13710.38303276335, 13908.930455347658, 12344.197627154206, 12492.56276030371, 12608.432620531914, 12985.35073246315, 13032.934835325763, 12106.213096148722, 12589.622311330675, 12891.742165156831, 12941.158824676742, 13042.54351097707, 12305.323207223688, 12403.0, 12421.627351179117, 12798.296093714935, 12809.551665691655, 9954.303198776306, 10535.473226338805, 10633.840492392776, 10866.239363244496, 11001.449927503752, 8648.43785153963, 10484.580616775549, 10775.528928381029, 10843.295185812896, 10883.727906802393], "value": 7045.412545076811, "unit": "score"}], "name": "dromaeo_css", "value": 7016.998126608911, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 115.0, 114.0, 114.0, 114.0, 113.0, 114.0, 114.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 114.0, 116.0, 172.0, 142.0, 144.0, 112.0, 113.0, 143.0, 116.0], "value": 132.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 262.0, 217.0, 184.0, 185.0, 182.0, 185.0, 186.0, 183.0, 185.0], "value": 195.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 60.0, 90.0, 62.0, 60.0, 59.0, 59.0, 83.0, 65.0], "value": 67.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 67.0, 94.0, 70.0, 68.0, 69.0, 89.0, 66.0, 69.0, 92.0], "value": 76.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 129.0, 98.0, 85.0, 132.0, 84.0, 89.0, 84.0, 87.0, 84.0], "value": 99.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 94.0, 93.0, 112.0, 127.0, 140.0, 147.0], "value": 109.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 80.0, 80.0, 82.0, 81.0, 83.0, 95.0, 82.0, 83.0], "value": 82.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 53.0, 54.0, 57.0, 57.0, 57.0, 57.0, 57.0, 57.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 71.0, 77.0, 73.0, 72.0, 67.0, 68.0, 68.0, 68.0, 69.0], "value": 69.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 86.0, 85.0, 85.0, 110.0, 100.0, 104.0, 85.0, 85.0], "value": 91.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 147.0, 148.0, 146.0, 146.0, 151.0, 156.0, 162.0, 203.0, 171.0], "value": 157.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 171.0, 168.0, 166.0, 210.0, 167.0, 211.0, 226.0, 193.0, 168.0], "value": 184.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 64.0, 67.0, 66.0, 66.0, 66.0, 66.0, 65.0, 67.0], "value": 66.0, "unit": "score"}], "name": "kraken", "value": 1502.4, "alertThreshold": 2.0}]} 07:46:09 INFO - Return code: 0 07:46:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:46:09 INFO - # TBPL SUCCESS # 07:46:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:46:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:46:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:46:09 INFO - 2016-11-24 07:46:09.460 screenresolution[1652:10112] starting screenresolution argv=screenresolution get 07:46:09 INFO - 2016-11-24 07:46:09.463 screenresolution[1652:10112] Display 0: 1600x1200x32@60 07:46:09 INFO - 2016-11-24 07:46:09.469 screenresolution[1653:10115] starting screenresolution argv=screenresolution list 07:46:09 INFO - Available Modes on Display 0 07:46:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:46:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:46:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:46:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:46:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:46:09 INFO - Intel Iris: 07:46:09 INFO - Chipset Model: Intel Iris 07:46:09 INFO - Type: GPU 07:46:09 INFO - Bus: Built-In 07:46:09 INFO - VRAM (Dynamic, Max): 1536 MB 07:46:09 INFO - Vendor: Intel (0x8086) 07:46:09 INFO - Device ID: 0x0a2e 07:46:09 INFO - Revision ID: 0x0009 07:46:09 INFO - Displays: 07:46:09 INFO - Display: 07:46:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:46:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:46:09 INFO - Display Serial Number: 1600x1200 60 07:46:09 INFO - Main Display: Yes 07:46:09 INFO - Mirror: Off 07:46:09 INFO - Online: Yes 07:46:09 INFO - Rotation: Supported 07:46:09 INFO - Return code: 0 07:46:09 INFO - Running post-action listener: _resource_record_post_action 07:46:09 INFO - [mozharness: 2016-11-24 15:46:09.707885Z] Finished run-tests step (success) 07:46:09 INFO - Running post-run listener: _resource_record_post_run 07:46:09 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 37721088; Write bytes: 661190144; Read time: 238; Write time: 1954 07:46:09 INFO - TinderboxPrint: CPU usage
53.3% 07:46:09 INFO - TinderboxPrint: I/O read bytes / time
37,721,088 / 238 07:46:09 INFO - TinderboxPrint: I/O write bytes / time
661,190,144 / 1,954 07:46:09 INFO - TinderboxPrint: CPU idle
2,268.7 (46.7%) 07:46:09 INFO - TinderboxPrint: CPU system
973.2 (20.1%) 07:46:09 INFO - TinderboxPrint: CPU user
1,611.7 (33.2%) 07:46:09 INFO - TinderboxPrint: Swap in / out
386,056,192 / 0 07:46:09 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 343955968; Write bytes: 347092992; Read time: 27235; Write time: 758 07:46:09 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 35755008; Write bytes: 307133952; Read time: 221; Write time: 1165 07:46:10 INFO - Running post-run listener: _upload_blobber_files 07:46:10 INFO - Blob upload gear active. 07:46:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:46:10 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 07:46:10 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'] 07:46:10 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 07:46:10 INFO - (blobuploader) - INFO - Open directory for files ... 07:46:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:46:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:46:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:46:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:46:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:46:11 INFO - (blobuploader) - INFO - Done attempting. 07:46:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:46:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:46:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:46:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:46:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:46:12 INFO - (blobuploader) - INFO - Done attempting. 07:46:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:46:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:46:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:46:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:46:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:46:15 INFO - (blobuploader) - INFO - Done attempting. 07:46:15 INFO - (blobuploader) - INFO - Iteration through files over. 07:46:15 INFO - Return code: 0 07:46:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:46:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:46:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/06518f73bb52986a5ac2d869f76ecb34fc187e8fa5129d68e989c85096c3cdbc7fb726bd3fa4efb3fbf4558242f4195af45f6322fe437701d0df50f2dbe0fa45", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ef39a8cb3611b0740990969c706130bc0953398c9bea83f03e3435de56e577b8db0af151e845349f4bb01913e34b63c23d2599aa87424ec5a8d5a32d0cd8841", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b71ab3236ca0c43133d4618e205f611753eae37f79330f6a74a87f23619316e2e373342fceb485c281630fcfd49a151ef4a4a7405968403dbe6579fb38e48ce"} 07:46:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:46:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:46:15 INFO - Contents: 07:46:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/06518f73bb52986a5ac2d869f76ecb34fc187e8fa5129d68e989c85096c3cdbc7fb726bd3fa4efb3fbf4558242f4195af45f6322fe437701d0df50f2dbe0fa45", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ef39a8cb3611b0740990969c706130bc0953398c9bea83f03e3435de56e577b8db0af151e845349f4bb01913e34b63c23d2599aa87424ec5a8d5a32d0cd8841", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b71ab3236ca0c43133d4618e205f611753eae37f79330f6a74a87f23619316e2e373342fceb485c281630fcfd49a151ef4a4a7405968403dbe6579fb38e48ce"} 07:46:15 INFO - Running post-run listener: copy_logs_to_upload_dir 07:46:15 INFO - Copying logs to upload dir... 07:46:15 INFO - mkdir: /builds/slave/test/build/upload/logs 07:46:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.783391 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-24 07:46:15.409082) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-24 07:46:15.412772) ========= 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.HifDsG6fy6/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.YaAJPeofdi/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/06518f73bb52986a5ac2d869f76ecb34fc187e8fa5129d68e989c85096c3cdbc7fb726bd3fa4efb3fbf4558242f4195af45f6322fe437701d0df50f2dbe0fa45", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ef39a8cb3611b0740990969c706130bc0953398c9bea83f03e3435de56e577b8db0af151e845349f4bb01913e34b63c23d2599aa87424ec5a8d5a32d0cd8841", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b71ab3236ca0c43133d4618e205f611753eae37f79330f6a74a87f23619316e2e373342fceb485c281630fcfd49a151ef4a4a7405968403dbe6579fb38e48ce"} build_url:https://queue.taskcluster.net/v1/task/XYPchGGWQl28pRk0TOHaPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011411 build_url: 'https://queue.taskcluster.net/v1/task/XYPchGGWQl28pRk0TOHaPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/06518f73bb52986a5ac2d869f76ecb34fc187e8fa5129d68e989c85096c3cdbc7fb726bd3fa4efb3fbf4558242f4195af45f6322fe437701d0df50f2dbe0fa45", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ef39a8cb3611b0740990969c706130bc0953398c9bea83f03e3435de56e577b8db0af151e845349f4bb01913e34b63c23d2599aa87424ec5a8d5a32d0cd8841", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b71ab3236ca0c43133d4618e205f611753eae37f79330f6a74a87f23619316e2e373342fceb485c281630fcfd49a151ef4a4a7405968403dbe6579fb38e48ce"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-24 07:46:15.453145) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 07:46:15.453807) ========= 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.HifDsG6fy6/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.YaAJPeofdi/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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.005214 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 07:46:15.504813) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-24 07:46:15.505176) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-24 07:46:15.505534) ========= ========= Total master_lag: 0.17 =========