builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0117 starttime: 1493207198.52 results: success (0) buildid: 20170426030329 builduid: 4a8c9d4c5ae24c509d3b38794aa1d9f5 revision: 0f5ba06c4c5959030a05cb852656d854065e2226 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-26 04:46:38.517272) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-26 04:46:38.517804) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-26 04:46:38.518101) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-26 04:46:38.546223) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-26 04:46:38.546610) ========= 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.rrV5EUZgo9/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.dUFRjRd7uW/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-26 04:46:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 24.2M=0s 2017-04-26 04:46:38 (24.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.206045 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-26 04:46:38.777982) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-26 04:46:38.778372) ========= 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.rrV5EUZgo9/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.dUFRjRd7uW/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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.030647 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-26 04:46:38.831130) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-26 04:46:38.831494) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0f5ba06c4c5959030a05cb852656d854065e2226 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 0f5ba06c4c5959030a05cb852656d854065e2226 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rrV5EUZgo9/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.dUFRjRd7uW/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-26 04:46:38,934 truncating revision to first 12 chars 2017-04-26 04:46:38,934 Setting DEBUG logging. 2017-04-26 04:46:38,934 attempt 1/10 2017-04-26 04:46:38,934 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0f5ba06c4c59?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-26 04:46:40,266 unpacking tar archive at: mozilla-central-0f5ba06c4c59/testing/mozharness/ program finished with exit code 0 elapsedTime=1.598082 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-26 04:46:40.453779) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-26 04:46:40.454105) ========= script_repo_revision: 0f5ba06c4c5959030a05cb852656d854065e2226 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-26 04:46:40.454499) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-26 04:46:40.454766) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-26 04:46:40.475230) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-04-26 04:46:40.475620) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rrV5EUZgo9/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.dUFRjRd7uW/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:46:40 INFO - MultiFileLogger online at 20170426 04:46:40 in /builds/slave/test 04:46:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:46:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:46:40 INFO - {'append_to_log': False, 04:46:40 INFO - 'base_work_dir': '/builds/slave/test', 04:46:40 INFO - 'blob_upload_branch': 'Firefox', 04:46:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:46:40 INFO - 'branch': 'Firefox', 04:46:40 INFO - 'buildbot_json_path': 'buildprops.json', 04:46:40 INFO - 'config_files': ('talos/mac_config.py',), 04:46:40 INFO - 'default_actions': ('clobber', 04:46:40 INFO - 'read-buildbot-config', 04:46:40 INFO - 'download-and-extract', 04:46:40 INFO - 'populate-webroot', 04:46:40 INFO - 'create-virtualenv', 04:46:40 INFO - 'install', 04:46:40 INFO - 'run-tests'), 04:46:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:46:40 INFO - 'download_minidump_stackwalk': True, 04:46:40 INFO - 'download_symbols': 'ondemand', 04:46:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:46:40 INFO - 'tooltool.py': '/tools/tooltool.py', 04:46:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:46:40 INFO - '/tools/misc-python/virtualenv.py')}, 04:46:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:46:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:46:40 INFO - 'gecko_profile': False, 04:46:40 INFO - 'gecko_profile_interval': 0, 04:46:40 INFO - 'installer_path': 'installer.exe', 04:46:40 INFO - 'log_level': 'info', 04:46:40 INFO - 'log_name': 'talos', 04:46:40 INFO - 'log_to_console': True, 04:46:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:46:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:46:40 INFO - 'opt_config_files': (), 04:46:40 INFO - 'pip_index': False, 04:46:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:46:40 INFO - 'cmd': ('bash', 04:46:40 INFO - '-c', 04:46:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:46:40 INFO - 'enabled': True, 04:46:40 INFO - 'halt_on_failure': False, 04:46:40 INFO - 'name': 'check_screen_resolution'},), 04:46:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:46:40 INFO - 'cmd': ('bash', 04:46:40 INFO - '-c', 04:46:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:46:40 INFO - 'enabled': True, 04:46:40 INFO - 'halt_on_failure': False, 04:46:40 INFO - 'name': 'check_screen_resolution'},), 04:46:40 INFO - 'run_cmd_checks_enabled': True, 04:46:40 INFO - 'suite': 'dromaeojs-e10s', 04:46:40 INFO - 'system_bits': '32', 04:46:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:46:40 INFO - 'title': 't-yosemite-r7-0117', 04:46:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:46:40 INFO - 'use_talos_json': True, 04:46:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:46:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:46:40 INFO - 'work_dir': 'build'} 04:46:40 INFO - [mozharness: 2017-04-26 11:46:40.691163Z] Running clobber step. 04:46:40 INFO - Running pre-action listener: _resource_record_pre_action 04:46:40 INFO - Running main action method: clobber 04:46:40 INFO - rmtree: /builds/slave/test/build 04:46:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:46:43 INFO - Running post-action listener: _resource_record_post_action 04:46:43 INFO - [mozharness: 2017-04-26 11:46:43.227561Z] Finished clobber step (success) 04:46:43 INFO - [mozharness: 2017-04-26 11:46:43.227685Z] Running read-buildbot-config step. 04:46:43 INFO - Running pre-action listener: _resource_record_pre_action 04:46:43 INFO - Running main action method: read_buildbot_config 04:46:43 INFO - Using buildbot properties: 04:46:43 INFO - { 04:46:43 INFO - "project": "", 04:46:43 INFO - "product": "firefox", 04:46:43 INFO - "script_repo_revision": "production", 04:46:43 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:46:43 INFO - "repository": "", 04:46:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:46:43 INFO - "buildid": "20170426030329", 04:46:43 INFO - "stage_platform": "macosx64", 04:46:43 INFO - "basedir": "/builds/slave/test", 04:46:43 INFO - "nightly_build": "True", 04:46:43 INFO - "buildnumber": 42, 04:46:43 INFO - "slavename": "t-yosemite-r7-0117", 04:46:43 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 04:46:43 INFO - "platform": "yosemite_r7", 04:46:43 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:46:43 INFO - "branch": "mozilla-central", 04:46:43 INFO - "repo_path": "mozilla-central", 04:46:43 INFO - "revision": "0f5ba06c4c5959030a05cb852656d854065e2226", 04:46:43 INFO - "pgo_build": "False", 04:46:43 INFO - "builduid": "4a8c9d4c5ae24c509d3b38794aa1d9f5", 04:46:43 INFO - "slavebuilddir": "test" 04:46:43 INFO - } 04:46:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/YMSY83mzSwCqf7vnFu3CBA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 04:46:43 INFO - Running post-action listener: _resource_record_post_action 04:46:43 INFO - [mozharness: 2017-04-26 11:46:43.231169Z] Finished read-buildbot-config step (success) 04:46:43 INFO - [mozharness: 2017-04-26 11:46:43.231298Z] Running download-and-extract step. 04:46:43 INFO - Running pre-action listener: _resource_record_pre_action 04:46:43 INFO - Running main action method: download_and_extract 04:46:43 INFO - mkdir: /builds/slave/test/build/tests 04:46:43 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')]} 04:46:43 INFO - trying https://queue.taskcluster.net/v1/task/YMSY83mzSwCqf7vnFu3CBA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 04:46:43 INFO - Downloading https://queue.taskcluster.net/v1/task/YMSY83mzSwCqf7vnFu3CBA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:46:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YMSY83mzSwCqf7vnFu3CBA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 04:46:43 INFO - Downloaded 1325 bytes. 04:46:43 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:46:43 INFO - Using the following test package requirements: 04:46:43 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:46:43 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 04:46:43 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:46:43 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:46:43 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 04:46:43 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:46:43 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 04:46:43 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:46:43 INFO - u'jsshell-mac.zip'], 04:46:43 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:46:43 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 04:46:43 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:46:43 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:46:43 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 04:46:43 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:46:43 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 04:46:43 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:46:43 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 04:46:43 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:46:43 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 04:46:43 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:46:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YMSY83mzSwCqf7vnFu3CBA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:46:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YMSY83mzSwCqf7vnFu3CBA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:46:43 INFO - Fetch https://queue.taskcluster.net/v1/task/YMSY83mzSwCqf7vnFu3CBA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:46:45 INFO - Content-Length response header: 52533271 04:46:45 INFO - Bytes received: 52533271 04:47:01 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:47:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YMSY83mzSwCqf7vnFu3CBA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:47:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YMSY83mzSwCqf7vnFu3CBA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:47:01 INFO - Fetch https://queue.taskcluster.net/v1/task/YMSY83mzSwCqf7vnFu3CBA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:47:03 INFO - Content-Length response header: 52533271 04:47:03 INFO - Bytes received: 52533271 04:47:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YMSY83mzSwCqf7vnFu3CBA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 04:47:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YMSY83mzSwCqf7vnFu3CBA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:47:19 INFO - Fetch https://queue.taskcluster.net/v1/task/YMSY83mzSwCqf7vnFu3CBA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 04:47:20 INFO - Content-Length response header: 14408809 04:47:20 INFO - Bytes received: 14408809 04:47:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:20 INFO - trying https://queue.taskcluster.net/v1/task/YMSY83mzSwCqf7vnFu3CBA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:47:20 INFO - Downloading https://queue.taskcluster.net/v1/task/YMSY83mzSwCqf7vnFu3CBA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 04:47:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YMSY83mzSwCqf7vnFu3CBA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 04:47:22 INFO - Downloaded 71277645 bytes. 04:47:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YMSY83mzSwCqf7vnFu3CBA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:47:22 INFO - mkdir: /builds/slave/test/properties 04:47:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:47:22 INFO - Writing to file /builds/slave/test/properties/build_url 04:47:22 INFO - Contents: 04:47:22 INFO - build_url:https://queue.taskcluster.net/v1/task/YMSY83mzSwCqf7vnFu3CBA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:47:23 INFO - Running post-action listener: _resource_record_post_action 04:47:23 INFO - Running post-action listener: set_extra_try_arguments 04:47:23 INFO - [mozharness: 2017-04-26 11:47:23.591469Z] Finished download-and-extract step (success) 04:47:23 INFO - [mozharness: 2017-04-26 11:47:23.591632Z] Running populate-webroot step. 04:47:23 INFO - Running pre-action listener: _resource_record_pre_action 04:47:23 INFO - Running main action method: populate_webroot 04:47:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:47:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:47:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:47:23 INFO - '--tppagecycles', 04:47:23 INFO - '7'], 04:47:23 INFO - 'tests': ['tsvgm']}}, 04:47:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:47:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:47:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:47:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:47:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:47:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:47:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:47:23 INFO - 'talos_options': ['--disable-e10s'], 04:47:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:47:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:47:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:47:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:47:23 INFO - 'talos_options': ['--disable-e10s'], 04:47:23 INFO - 'tests': ['damp', 'tps']}, 04:47:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:47:23 INFO - 'tests': ['damp', 'tps']}, 04:47:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:47:23 INFO - 'tests': ['dromaeo_dom']}, 04:47:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:47:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:47:23 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 04:47:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:47:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:47:23 INFO - 'tests': ['a11yr', 04:47:23 INFO - 'ts_paint', 04:47:23 INFO - 'tpaint', 04:47:23 INFO - 'sessionrestore', 04:47:23 INFO - 'sessionrestore_no_auto_restore', 04:47:23 INFO - 'tabpaint']}, 04:47:23 INFO - 'other-e10s': {'tests': ['a11yr', 04:47:23 INFO - 'ts_paint', 04:47:23 INFO - 'tpaint', 04:47:23 INFO - 'sessionrestore', 04:47:23 INFO - 'sessionrestore_no_auto_restore', 04:47:23 INFO - 'tabpaint']}, 04:47:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:47:23 INFO - 'ts_paint', 04:47:23 INFO - 'tpaint', 04:47:23 INFO - 'sessionrestore', 04:47:23 INFO - 'sessionrestore_no_auto_restore', 04:47:23 INFO - 'tabpaint']}, 04:47:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:47:23 INFO - 'ts_paint', 04:47:23 INFO - 'tpaint', 04:47:23 INFO - 'sessionrestore', 04:47:23 INFO - 'sessionrestore_no_auto_restore', 04:47:23 INFO - 'tabpaint']}, 04:47:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:47:23 INFO - 'tests': ['a11yr', 04:47:23 INFO - 'ts_paint', 04:47:23 INFO - 'tpaint', 04:47:23 INFO - 'sessionrestore', 04:47:23 INFO - 'sessionrestore_no_auto_restore', 04:47:23 INFO - 'tabpaint']}, 04:47:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:47:23 INFO - 'tests': ['a11yr', 04:47:23 INFO - 'ts_paint', 04:47:23 INFO - 'tpaint', 04:47:23 INFO - 'sessionrestore', 04:47:23 INFO - 'sessionrestore_no_auto_restore', 04:47:23 INFO - 'tabpaint']}, 04:47:23 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 04:47:23 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 04:47:23 INFO - 'bloom_basic_ref']}, 04:47:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:47:23 INFO - 'tests': ['tsvgx', 04:47:23 INFO - 'tsvgr_opacity', 04:47:23 INFO - 'tart', 04:47:23 INFO - 'tscrollx', 04:47:23 INFO - 'cart', 04:47:23 INFO - 'tsvg_static']}, 04:47:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:47:23 INFO - 'tsvgr_opacity', 04:47:23 INFO - 'tart', 04:47:23 INFO - 'tscrollx', 04:47:23 INFO - 'cart', 04:47:23 INFO - 'tsvg_static']}, 04:47:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:47:23 INFO - 'talos_options': ['--disable-e10s'], 04:47:23 INFO - 'tests': ['tp5o']}, 04:47:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:47:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:47:23 INFO - 'talos_options': ['--disable-e10s', 04:47:23 INFO - '--xperf_path', 04:47:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:47:23 INFO - 'tests': ['tp5n']}, 04:47:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:47:23 INFO - 'talos_options': ['--xperf_path', 04:47:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:47:23 INFO - 'tests': ['tp5n']}}, 04:47:23 INFO - 'talos.zip': {'path': '', 04:47:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:47:23 INFO - Running post-action listener: _resource_record_post_action 04:47:23 INFO - [mozharness: 2017-04-26 11:47:23.610953Z] Finished populate-webroot step (success) 04:47:23 INFO - [mozharness: 2017-04-26 11:47:23.611068Z] Running create-virtualenv step. 04:47:23 INFO - Running pre-action listener: _resource_record_pre_action 04:47:23 INFO - Running main action method: create_virtualenv 04:47:23 INFO - Creating virtualenv /builds/slave/test/build/venv 04:47:23 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 04:47:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:47:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:47:23 INFO - Using real prefix '/tools/python27' 04:47:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:47:24 INFO - Installing distribute.............................................................................................................................................................................................done. 04:47:27 INFO - Installing pip.................done. 04:47:27 INFO - Return code: 0 04:47:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:47:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:47:27 INFO - Reading from file tmpfile_stdout 04:47:27 INFO - Output received: 04:47:27 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) 04:47:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:47: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')]} 04:47:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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')]} 04:47:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10153fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101542768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb392ca1120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10131dd30>, '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 0x101243db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101312be8>, '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.rrV5EUZgo9/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.dUFRjRd7uW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:47: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', 'pip>=1.5'] in /builds/slave/test/build 04:47: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 pip>=1.5 04:47:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rrV5EUZgo9/Render', 04:47:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:27 INFO - 'HOME': '/Users/cltbld', 04:47:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:27 INFO - 'LOGNAME': 'cltbld', 04:47:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:27 INFO - 'MOZ_NO_REMOTE': '1', 04:47:27 INFO - 'NO_EM_RESTART': '1', 04:47:27 INFO - 'PAGER': '/bin/cat', 04:47:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:27 INFO - 'PWD': '/builds/slave/test', 04:47:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:27 INFO - 'SHELL': '/bin/bash', 04:47:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dUFRjRd7uW/Listeners', 04:47:27 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:47:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:27 INFO - 'USER': 'cltbld', 04:47:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:27 INFO - 'XPC_FLAGS': '0x0', 04:47:27 INFO - 'XPC_SERVICE_NAME': '0', 04:47:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:28 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:47:28 INFO - Cleaning up... 04:47:28 INFO - Return code: 0 04:47:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:47:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10153fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101542768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb392ca1120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10131dd30>, '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 0x101243db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101312be8>, '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.rrV5EUZgo9/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.dUFRjRd7uW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:47:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:47:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:47:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rrV5EUZgo9/Render', 04:47:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:28 INFO - 'HOME': '/Users/cltbld', 04:47:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:28 INFO - 'LOGNAME': 'cltbld', 04:47:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:28 INFO - 'MOZ_NO_REMOTE': '1', 04:47:28 INFO - 'NO_EM_RESTART': '1', 04:47:28 INFO - 'PAGER': '/bin/cat', 04:47:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:28 INFO - 'PWD': '/builds/slave/test', 04:47:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:28 INFO - 'SHELL': '/bin/bash', 04:47:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dUFRjRd7uW/Listeners', 04:47:28 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:47:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:28 INFO - 'USER': 'cltbld', 04:47:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:28 INFO - 'XPC_FLAGS': '0x0', 04:47:28 INFO - 'XPC_SERVICE_NAME': '0', 04:47:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:28 INFO - Downloading/unpacking psutil>=3.1.1 04:47:28 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 04:47:28 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 04:47:28 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 04:47:28 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 04:47:28 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 04:47:28 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 04:47:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:47:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:47:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:47:31 INFO - Installing collected packages: psutil 04:47:31 INFO - Running setup.py install for psutil 04:47:31 INFO - building 'psutil._psutil_osx' extension 04:47: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_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:47:32 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 04:47:32 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 04:47:32 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 04:47:32 INFO - building 'psutil._psutil_posix' extension 04:47:32 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 04:47:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:47:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:47:32 INFO - ^ 04:47:32 INFO - 1 warning generated. 04:47:32 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 04:47:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:47:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:47:33 INFO - Successfully installed psutil 04:47:33 INFO - Cleaning up... 04:47:33 INFO - Return code: 0 04:47:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:47:33 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')]} 04:47:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:33 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')]} 04:47:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:33 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 0x10153fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101542768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb392ca1120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10131dd30>, '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 0x101243db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101312be8>, '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.rrV5EUZgo9/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.dUFRjRd7uW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:47:33 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 04:47:33 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 04:47:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rrV5EUZgo9/Render', 04:47:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:33 INFO - 'HOME': '/Users/cltbld', 04:47:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:33 INFO - 'LOGNAME': 'cltbld', 04:47:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:33 INFO - 'MOZ_NO_REMOTE': '1', 04:47:33 INFO - 'NO_EM_RESTART': '1', 04:47:33 INFO - 'PAGER': '/bin/cat', 04:47:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:33 INFO - 'PWD': '/builds/slave/test', 04:47:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:33 INFO - 'SHELL': '/bin/bash', 04:47:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dUFRjRd7uW/Listeners', 04:47:33 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:47:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:33 INFO - 'USER': 'cltbld', 04:47:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:33 INFO - 'XPC_FLAGS': '0x0', 04:47:33 INFO - 'XPC_SERVICE_NAME': '0', 04:47:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:47:33 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 04:47:33 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 04:47:33 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 04:47:33 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 04:47:33 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 04:47:33 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 04:47:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:47:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:47:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:47:36 INFO - Installing collected packages: mozsystemmonitor 04:47:36 INFO - Running setup.py install for mozsystemmonitor 04:47:36 INFO - Successfully installed mozsystemmonitor 04:47:36 INFO - Cleaning up... 04:47:36 INFO - Return code: 0 04:47:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:47:36 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')]} 04:47:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:36 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')]} 04:47:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:36 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 0x10153fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101542768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb392ca1120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10131dd30>, '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 0x101243db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101312be8>, '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.rrV5EUZgo9/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.dUFRjRd7uW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:47:36 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 04:47:36 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 04:47:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rrV5EUZgo9/Render', 04:47:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:36 INFO - 'HOME': '/Users/cltbld', 04:47:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:36 INFO - 'LOGNAME': 'cltbld', 04:47:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:36 INFO - 'MOZ_NO_REMOTE': '1', 04:47:36 INFO - 'NO_EM_RESTART': '1', 04:47:36 INFO - 'PAGER': '/bin/cat', 04:47:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:36 INFO - 'PWD': '/builds/slave/test', 04:47:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:36 INFO - 'SHELL': '/bin/bash', 04:47:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dUFRjRd7uW/Listeners', 04:47:36 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:47:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:36 INFO - 'USER': 'cltbld', 04:47:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:36 INFO - 'XPC_FLAGS': '0x0', 04:47:36 INFO - 'XPC_SERVICE_NAME': '0', 04:47:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:37 INFO - Downloading/unpacking jsonschema==2.5.1 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:47:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:47:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:40 INFO - Downloading functools32-3.2.3-2.tar.gz 04:47:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:47:40 INFO - warning: no files found matching '*.txt' 04:47:40 INFO - no previously-included directories found matching 'build' 04:47:40 INFO - no previously-included directories found matching 'dist' 04:47:40 INFO - no previously-included directories found matching '.git*' 04:47:40 INFO - Installing collected packages: jsonschema, functools32 04:47:40 INFO - Running setup.py install for functools32 04:47:40 INFO - warning: no files found matching '*.txt' 04:47:40 INFO - no previously-included directories found matching 'build' 04:47:40 INFO - no previously-included directories found matching 'dist' 04:47:40 INFO - no previously-included directories found matching '.git*' 04:47:40 INFO - Successfully installed jsonschema functools32 04:47:40 INFO - Cleaning up... 04:47:40 INFO - Return code: 0 04:47:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:47:40 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')]} 04:47:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:40 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')]} 04:47:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:40 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 0x10153fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101542768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb392ca1120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10131dd30>, '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 0x101243db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101312be8>, '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.rrV5EUZgo9/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.dUFRjRd7uW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:47:40 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 04:47:40 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 04:47:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rrV5EUZgo9/Render', 04:47:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:40 INFO - 'HOME': '/Users/cltbld', 04:47:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:40 INFO - 'LOGNAME': 'cltbld', 04:47:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:40 INFO - 'MOZ_NO_REMOTE': '1', 04:47:40 INFO - 'NO_EM_RESTART': '1', 04:47:40 INFO - 'PAGER': '/bin/cat', 04:47:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:40 INFO - 'PWD': '/builds/slave/test', 04:47:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:40 INFO - 'SHELL': '/bin/bash', 04:47:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dUFRjRd7uW/Listeners', 04:47:40 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:47:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:40 INFO - 'USER': 'cltbld', 04:47:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:40 INFO - 'XPC_FLAGS': '0x0', 04:47:40 INFO - 'XPC_SERVICE_NAME': '0', 04:47:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:47:41 INFO - Cleaning up... 04:47:41 INFO - Return code: 0 04:47:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:47:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10153fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101542768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb392ca1120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10131dd30>, '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 0x101243db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101312be8>, '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.rrV5EUZgo9/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.dUFRjRd7uW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:47:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:47:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:47:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rrV5EUZgo9/Render', 04:47:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:41 INFO - 'HOME': '/Users/cltbld', 04:47:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:41 INFO - 'LOGNAME': 'cltbld', 04:47:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:41 INFO - 'MOZ_NO_REMOTE': '1', 04:47:41 INFO - 'NO_EM_RESTART': '1', 04:47:41 INFO - 'PAGER': '/bin/cat', 04:47:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:41 INFO - 'PWD': '/builds/slave/test', 04:47:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:41 INFO - 'SHELL': '/bin/bash', 04:47:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dUFRjRd7uW/Listeners', 04:47:41 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:47:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:41 INFO - 'USER': 'cltbld', 04:47:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:41 INFO - 'XPC_FLAGS': '0x0', 04:47:41 INFO - 'XPC_SERVICE_NAME': '0', 04:47:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:41 INFO - Downloading/unpacking blobuploader==1.2.4 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47:44 INFO - Downloading blobuploader-1.2.4.tar.gz 04:47:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:47:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:47:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:47:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:47:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:45 INFO - Downloading docopt-0.6.1.tar.gz 04:47:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:47:45 INFO - Installing collected packages: blobuploader, requests, docopt 04:47:45 INFO - Running setup.py install for blobuploader 04:47:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:47:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:47:45 INFO - Running setup.py install for requests 04:47:46 INFO - Running setup.py install for docopt 04:47:46 INFO - Successfully installed blobuploader requests docopt 04:47:46 INFO - Cleaning up... 04:47:46 INFO - Return code: 0 04:47:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:47:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10153fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101542768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb392ca1120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10131dd30>, '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 0x101243db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101312be8>, '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.rrV5EUZgo9/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.dUFRjRd7uW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:47:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:47:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:47:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rrV5EUZgo9/Render', 04:47:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:46 INFO - 'HOME': '/Users/cltbld', 04:47:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:46 INFO - 'LOGNAME': 'cltbld', 04:47:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:46 INFO - 'MOZ_NO_REMOTE': '1', 04:47:46 INFO - 'NO_EM_RESTART': '1', 04:47:46 INFO - 'PAGER': '/bin/cat', 04:47:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:46 INFO - 'PWD': '/builds/slave/test', 04:47:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:46 INFO - 'SHELL': '/bin/bash', 04:47:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dUFRjRd7uW/Listeners', 04:47:46 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:47:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:46 INFO - 'USER': 'cltbld', 04:47:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:46 INFO - 'XPC_FLAGS': '0x0', 04:47:46 INFO - 'XPC_SERVICE_NAME': '0', 04:47:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:47:46 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-ZVdDDs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:47:46 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-c3EMGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:47:46 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-xbSzYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:47:46 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-BjXIjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:47:46 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-qJnbna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:47:47 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-4EzYXs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:47:47 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-2adQjR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:47:47 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-yl4CAG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:47:47 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-ffJi9L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:47:47 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-NBIZbU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:47:47 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-98ouMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:47:47 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-k92Kpq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:47:47 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-qCgde2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:47:47 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-DF8Swn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:47 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-2x7chi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:47:48 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-LmlXlf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:47:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:47:48 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-EvDqe3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:47:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:47:48 INFO - Running setup.py install for manifestparser 04:47:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:47:48 INFO - Running setup.py install for mozcrash 04:47:48 INFO - Running setup.py install for mozdebug 04:47:48 INFO - Running setup.py install for mozdevice 04:47:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:47:48 INFO - Running setup.py install for mozfile 04:47:49 INFO - Running setup.py install for mozhttpd 04:47:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:47:49 INFO - Running setup.py install for mozinfo 04:47:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:47:49 INFO - Running setup.py install for mozInstall 04:47:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:47:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:47:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:47:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:47:49 INFO - Running setup.py install for mozleak 04:47:49 INFO - Running setup.py install for mozlog 04:47:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:47:49 INFO - Running setup.py install for moznetwork 04:47:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:47:49 INFO - Running setup.py install for mozprocess 04:47:50 INFO - Running setup.py install for mozprofile 04:47:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:47:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:47:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:47:50 INFO - Running setup.py install for mozrunner 04:47:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:47:50 INFO - Running setup.py install for mozscreenshot 04:47:50 INFO - Running setup.py install for moztest 04:47:50 INFO - Running setup.py install for mozversion 04:47:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:47:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:47:50 INFO - Cleaning up... 04:47:50 INFO - Return code: 0 04:47:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:47:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10153fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101542768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb392ca1120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10131dd30>, '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 0x101243db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101312be8>, '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.rrV5EUZgo9/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.dUFRjRd7uW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:47:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:47:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:47:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rrV5EUZgo9/Render', 04:47:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:50 INFO - 'HOME': '/Users/cltbld', 04:47:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:50 INFO - 'LOGNAME': 'cltbld', 04:47:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:50 INFO - 'MOZ_NO_REMOTE': '1', 04:47:50 INFO - 'NO_EM_RESTART': '1', 04:47:50 INFO - 'PAGER': '/bin/cat', 04:47:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:50 INFO - 'PWD': '/builds/slave/test', 04:47:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:50 INFO - 'SHELL': '/bin/bash', 04:47:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dUFRjRd7uW/Listeners', 04:47:50 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:47:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:50 INFO - 'USER': 'cltbld', 04:47:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:50 INFO - 'XPC_FLAGS': '0x0', 04:47:50 INFO - 'XPC_SERVICE_NAME': '0', 04:47:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:47:51 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-fM_sNP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:47:51 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:47:51 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-GbVdBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:47:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:47:51 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-2yn9iR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:47:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:47:51 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-5qqAUL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:47:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 04:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:47:51 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-jpGdxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:47:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:47:51 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-SwhA9J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:47:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:47:51 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-nTAwo5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:47:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:47:51 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-Bqah_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:47:51 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)) 04:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:47:51 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-fDdek1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:47:52 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-Kn1aMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:47:52 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-icdzl4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:47:52 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-CNjMM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:47:52 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-n1W_0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:47:52 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-01XJyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:52 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-C2AYXS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:47:52 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-UP2Xcu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:47:52 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-B7y_U8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:47:52 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)) 04:47:52 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)) 04:47:52 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)) 04:47:52 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)) 04:47:52 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:47:52 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:47:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:47:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:55 INFO - Downloading blessings-1.6.tar.gz 04:47:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:47:55 INFO - Installing collected packages: blessings 04:47:55 INFO - Running setup.py install for blessings 04:47:55 INFO - Successfully installed blessings 04:47:55 INFO - Cleaning up... 04:47:55 INFO - Return code: 0 04:47:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:47:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:47:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:47:56 INFO - Reading from file tmpfile_stdout 04:47:56 INFO - Current package versions: 04:47:56 INFO - blessings == 1.6 04:47:56 INFO - blobuploader == 1.2.4 04:47:56 INFO - docopt == 0.6.1 04:47:56 INFO - functools32 == 3.2.3-2 04:47:56 INFO - jsonschema == 2.5.1 04:47:56 INFO - manifestparser == 1.1 04:47:56 INFO - mozInstall == 1.12 04:47:56 INFO - mozcrash == 1.0 04:47:56 INFO - mozdebug == 0.1 04:47:56 INFO - mozdevice == 0.50 04:47:56 INFO - mozfile == 1.2 04:47:56 INFO - mozhttpd == 0.7 04:47:56 INFO - mozinfo == 0.9 04:47:56 INFO - mozleak == 0.1 04:47:56 INFO - mozlog == 3.4 04:47:56 INFO - moznetwork == 0.27 04:47:56 INFO - mozprocess == 0.25 04:47:56 INFO - mozprofile == 0.28 04:47:56 INFO - mozrunner == 6.13 04:47:56 INFO - mozscreenshot == 0.1 04:47:56 INFO - mozsystemmonitor == 0.3 04:47:56 INFO - moztest == 0.8 04:47:56 INFO - mozversion == 1.4 04:47:56 INFO - psutil == 3.1.1 04:47:56 INFO - requests == 1.2.3 04:47:56 INFO - wsgiref == 0.1.2 04:47:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:47:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10153fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101542768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb392ca1120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10131dd30>, '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 0x101243db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101312be8>, '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.rrV5EUZgo9/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.dUFRjRd7uW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:47:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:47:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:47:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rrV5EUZgo9/Render', 04:47:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:56 INFO - 'HOME': '/Users/cltbld', 04:47:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:56 INFO - 'LOGNAME': 'cltbld', 04:47:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:56 INFO - 'MOZ_NO_REMOTE': '1', 04:47:56 INFO - 'NO_EM_RESTART': '1', 04:47:56 INFO - 'PAGER': '/bin/cat', 04:47:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:56 INFO - 'PWD': '/builds/slave/test', 04:47:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:56 INFO - 'SHELL': '/bin/bash', 04:47:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dUFRjRd7uW/Listeners', 04:47:56 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:47:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:56 INFO - 'USER': 'cltbld', 04:47:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:56 INFO - 'XPC_FLAGS': '0x0', 04:47:56 INFO - 'XPC_SERVICE_NAME': '0', 04:47:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:47:56 INFO - Cleaning up... 04:47:56 INFO - Return code: 0 04:47:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:47:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10153fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101542768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb392ca1120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10131dd30>, '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 0x101243db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101312be8>, '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.rrV5EUZgo9/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.dUFRjRd7uW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:47:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:47:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:47:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rrV5EUZgo9/Render', 04:47:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:56 INFO - 'HOME': '/Users/cltbld', 04:47:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:56 INFO - 'LOGNAME': 'cltbld', 04:47:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:56 INFO - 'MOZ_NO_REMOTE': '1', 04:47:56 INFO - 'NO_EM_RESTART': '1', 04:47:56 INFO - 'PAGER': '/bin/cat', 04:47:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:56 INFO - 'PWD': '/builds/slave/test', 04:47:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:56 INFO - 'SHELL': '/bin/bash', 04:47:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dUFRjRd7uW/Listeners', 04:47:56 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:47:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:56 INFO - 'USER': 'cltbld', 04:47:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:56 INFO - 'XPC_FLAGS': '0x0', 04:47:56 INFO - 'XPC_SERVICE_NAME': '0', 04:47:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:47:56 INFO - Cleaning up... 04:47:56 INFO - Return code: 0 04:47:56 INFO - Running post-action listener: _resource_record_post_action 04:47:56 INFO - Running post-action listener: _start_resource_monitoring 04:47:56 INFO - Starting resource monitoring. 04:47:56 INFO - [mozharness: 2017-04-26 11:47:56.660142Z] Finished create-virtualenv step (success) 04:47:56 INFO - [mozharness: 2017-04-26 11:47:56.661054Z] Running install step. 04:47:56 INFO - Running pre-action listener: _resource_record_pre_action 04:47:56 INFO - Running main action method: install 04:47:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:47:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:47:56 INFO - Reading from file tmpfile_stdout 04:47:56 INFO - Detecting whether we're running mozinstall >=1.0... 04:47:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:47:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:47:57 INFO - Reading from file tmpfile_stdout 04:47:57 INFO - Output received: 04:47:57 INFO - Usage: mozinstall [options] installer 04:47:57 INFO - Options: 04:47:57 INFO - -h, --help show this help message and exit 04:47:57 INFO - -d DEST, --destination=DEST 04:47:57 INFO - Directory to install application into. [default: 04:47:57 INFO - "/builds/slave/test"] 04:47:57 INFO - --app=APP Application being installed. [default: firefox] 04:47:57 INFO - mkdir: /builds/slave/test/build/application 04:47:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:47:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:48:16 INFO - Reading from file tmpfile_stdout 04:48:16 INFO - Output received: 04:48:16 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:48:16 INFO - Running post-action listener: _resource_record_post_action 04:48:16 INFO - [mozharness: 2017-04-26 11:48:16.773894Z] Finished install step (success) 04:48:16 INFO - [mozharness: 2017-04-26 11:48:16.774079Z] Running run-tests step. 04:48:16 INFO - Running pre-action listener: _resource_record_pre_action 04:48:16 INFO - Running main action method: run_tests 04:48:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:48:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:48:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:48:16 INFO - 2017-04-26 04:48:16.787 screenresolution[1622:7363] starting screenresolution argv=screenresolution get 04:48:16 INFO - 2017-04-26 04:48:16.789 screenresolution[1622:7363] Display 0: 1600x1200x32@60 04:48:16 INFO - 2017-04-26 04:48:16.795 screenresolution[1623:7367] starting screenresolution argv=screenresolution list 04:48:16 INFO - Available Modes on Display 0 04:48:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:48:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:48:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:48:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:48:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:48:17 INFO - Intel Iris: 04:48:17 INFO - Chipset Model: Intel Iris 04:48:17 INFO - Type: GPU 04:48:17 INFO - Bus: Built-In 04:48:17 INFO - VRAM (Dynamic, Max): 1536 MB 04:48:17 INFO - Vendor: Intel (0x8086) 04:48:17 INFO - Device ID: 0x0a2e 04:48:17 INFO - Revision ID: 0x0009 04:48:17 INFO - Displays: 04:48:17 INFO - Display: 04:48:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:48:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:48:17 INFO - Display Serial Number: 1600x1200 60 04:48:17 INFO - Main Display: Yes 04:48:17 INFO - Mirror: Off 04:48:17 INFO - Online: Yes 04:48:17 INFO - Rotation: Supported 04:48:17 INFO - Return code: 0 04:48:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:48:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:48:17 INFO - Python 2.7.3 04:48:17 INFO - Return code: 0 04:48:17 INFO - grabbing minidump binary from tooltool 04:48:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:17 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 0x10131dd30>, '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 0x101243db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101312be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:48:17 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 04:48:17 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 04:48:17 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 04:48:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:48:17 INFO - Return code: 0 04:48:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:48:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:48:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:48:17 INFO - ENV: RUST_BACKTRACE is now 1 04:48:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:48:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:48:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YMSY83mzSwCqf7vnFu3CBA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0117', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:48:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YMSY83mzSwCqf7vnFu3CBA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0117 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:48:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rrV5EUZgo9/Render', 04:48:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:17 INFO - 'HOME': '/Users/cltbld', 04:48:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:17 INFO - 'LOGNAME': 'cltbld', 04:48:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:48:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:48:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:17 INFO - 'MOZ_NO_REMOTE': '1', 04:48:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:48:17 INFO - 'NO_EM_RESTART': '1', 04:48:17 INFO - 'PAGER': '/bin/cat', 04:48:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:17 INFO - 'PWD': '/builds/slave/test', 04:48:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:48:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:17 INFO - 'RUST_BACKTRACE': '1', 04:48:17 INFO - 'SHELL': '/bin/bash', 04:48:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dUFRjRd7uW/Listeners', 04:48:17 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:48:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:17 INFO - 'USER': 'cltbld', 04:48:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:17 INFO - 'XPC_FLAGS': '0x0', 04:48:17 INFO - 'XPC_SERVICE_NAME': '0', 04:48:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YMSY83mzSwCqf7vnFu3CBA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0117', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:48:17 INFO - mozversion application_buildid: 20170426030329 04:48:17 INFO - mozversion application_changeset: 0f5ba06c4c5959030a05cb852656d854065e2226 04:48:17 INFO - mozversion application_display_name: Nightly 04:48:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:48:17 INFO - mozversion application_name: Firefox 04:48:17 INFO - mozversion application_remotingname: firefox 04:48:17 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:48:17 INFO - mozversion application_vendor: Mozilla 04:48:17 INFO - mozversion application_version: 55.0a1 04:48:17 INFO - mozversion platform_buildid: 20170426030329 04:48:17 INFO - mozversion platform_changeset: 0f5ba06c4c5959030a05cb852656d854065e2226 04:48:17 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:48:17 INFO - mozversion platform_version: 55.0a1 04:48:17 INFO - using testdate: 1493207297 04:48:17 INFO - actual date: 1493207297 04:48:17 INFO - starting webserver on 'localhost:49244' 04:48:17 INFO - SUITE-START | Running 2 tests 04:48:17 INFO - TEST-START | dromaeo_css 04:48:17 INFO - operating with platform_type : mac_ 04:48:17 INFO - Initialising browser for dromaeo_css test... 04:48:17 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpqVN_fb/profile http://localhost:49244/getInfo.html) 04:48:20 INFO - PID 1633 | 2017-04-26 04:48:20.272 plugin-container[1636:7556] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c4b, name = 'com.apple.tsm.portname' 04:48:20 INFO - PID 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:48:20 INFO - PID 1633 | 2017-04-26 04:48:20.273 plugin-container[1636:7556] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c4f, name = 'com.apple.CFPasteboardClient' 04:48:20 INFO - PID 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:48:20 INFO - PID 1633 | 2017-04-26 04:48:20.273 plugin-container[1636:7556] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:48:20 INFO - PID 1633 | __metrics Screen width/height:1600/1200 04:48:20 INFO - PID 1633 | colorDepth:24 04:48:20 INFO - PID 1633 | Browser inner width/height: 1024/689 04:48:20 INFO - PID 1633 | __metrics 04:48:20 ERROR - PID 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3424: TypeError: this.tabs is undefined 04:48:21 INFO - TEST-INFO | 1633: exit 0 04:48:21 INFO - Browser initialized. 04:48:21 INFO - Running cycle 1/1 for dromaeo_css test... 04:48:21 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpqVN_fb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:48:23 INFO - PID 1637 | 2017-04-26 04:48:23.734 plugin-container[1638:7684] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 04:48:23 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:48:23 INFO - PID 1637 | 2017-04-26 04:48:23.735 plugin-container[1638:7684] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 04:48:23 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:48:23 INFO - PID 1637 | 2017-04-26 04:48:23.735 plugin-container[1638:7684] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:48:33 INFO - PID 1637 | RSS: Main: 235909120 04:48:33 INFO - PID 1637 | 04:51:36 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 04:51:38 INFO - PID 1637 | RSS: Main: 252329984 04:51:38 INFO - PID 1637 | 04:53:21 INFO - PID 1637 | ************************* 04:53:21 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 04:53:21 INFO - PID 1637 | 04:53:21 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:53:21 INFO - PID 1637 | Full stack: 04:53:21 INFO - PID 1637 | ************************* 04:53:21 INFO - PID 1637 | ************************* 04:53:21 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 04:53:21 INFO - PID 1637 | 04:53:21 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:53:21 INFO - PID 1637 | Full stack: 04:53:21 INFO - PID 1637 | ************************* 04:53:21 INFO - PID 1637 | ************************* 04:53:21 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 04:53:21 INFO - PID 1637 | 04:53:21 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:53:21 INFO - PID 1637 | Full stack: 04:53:21 INFO - PID 1637 | ************************* 04:53:21 INFO - PID 1637 | ************************* 04:53:21 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 04:53:21 INFO - PID 1637 | 04:53:21 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:53:21 INFO - PID 1637 | Full stack: 04:53:21 INFO - PID 1637 | ************************* 04:54:40 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 04:54:40 INFO - PID 1637 | RSS: Main: 260726784 04:54:40 INFO - PID 1637 | 04:57:42 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 04:57:43 INFO - PID 1637 | RSS: Main: 270331904 04:57:43 INFO - PID 1637 | 05:00:45 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 05:00:45 INFO - PID 1637 | RSS: Main: 263450624 05:00:45 INFO - PID 1637 | 05:03:48 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 05:03:49 INFO - PID 1637 | RSS: Main: 272044032 05:03:49 INFO - PID 1637 | 05:06:50 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 05:06:51 INFO - PID 1637 | RSS: Main: 266678272 05:06:51 INFO - PID 1637 | 05:06:51 INFO - PID 1637 | __start_tp_report 05:06:51 INFO - PID 1637 | _x_x_mozilla_page_load 05:06:51 INFO - PID 1637 | _x_x_mozilla_page_load_details 05:06:51 INFO - PID 1637 | |i|pagename|runs| 05:06:51 INFO - PID 1637 | |0;dojo.html;3352.631210566838;3620.1673615068535;3629.5100161478194;3647.106458917565;3660.4509323601455;6079.696015199244;6135.478484328832;6157.291911430187;6206.379362063792;6219.875602487948;6142.3453045438;6145.77084583083;6170.413810687989;6172.135900973867;6177.227846519185;4342.196693611676;5332.973335133319;5335.306410166673;5342.91985620216;5347.385050719168;5385.946140538604;5480.561555075605;5488.670679759227;5528.170774383834;5567.8886422271535;4501.932471012938;5341.6527925685095;5385.35375754908;5434.538064264522;5473.67157970521;5402.081646120129;5455.454454554552;5492.917606235909;5507.7246137692955;5547.750351234205;5403.2705584746345;5448.509634132907;5469.72651367434;5483.506484416422;5488.17677348401;5976.432238937294;6213.596116252429;6216.720247588869;6231.345708700566;6243.65659888706;6359.36406359365;6477.255115661665;6552.344765523457;6667.366600172976;6668.733250669968;5646.491815736563;5889.411058894119;6627.536072474929;6812.727490900358;6820.215675197366;6260.780872669435;6270.46701030413;6274.090256912722;6278.026905829649;6294.181756371734;6558.573692709959;6724.831879203059;6835.7949261522235;6849.1438570178725;6880.346367095218;6294.653794041372;6365.140706004723;6525.3800888915475;6530.869382612416;6613.503987200979;6796.422304104108;6808.217055038734;6812.931870681329;6813.625250611165;6837.076994605764;5904.143899134601;6574.934250657528;6683.331666833278;6711.765088221987;6745.2242992056545;5517.69652669107;6391.265004524446;6464.515161362917;6558.573692709959;6621.1392518972225;5327.3074500314715;5345.572354211731;5365.704886231217;5374.865628359323;5378.650387724785;4714.080754252887;4736.3369128322065;4745.715257084586;4762.857114286577;4787.042591481718;5221.6083793715625;6141.539384546177;6207.317195108534;6306.085617585424;6334.556581039283;6741.494387920926;6796.082528858639;6814.182298124257;6821.829454263683;6840.863182736317;6276.403741644628;6318.905216421757;6321.3678632137335;6325.114483972247;6336.746530138835;5706.828795136153;5950.8809823803285;5968.283805943228;5989.790357337472;5993.580449368589;6816.079829222991;6965.825854353681;7012.158540974911;7047;7072.681729322296;6832.795016149524;6855.622940738307;6858.382745552724;6871.4502839773695;6881.587104773729;6898.412286048317;6942.6181560012665;7034.648267586703;7042.43660507148;7052.294770522906;6396.456301214449;6475.546711730133;6605.141331626858;6665.500087493554;6719.899201511884;6665;6836.521443499107;6842;6851.212110607344;6858.897116543156;6279.246490421178;6388.456981156468;6483.448906842971;6578.7368505261165;6596.241432235354;6463.870722585428;6465.062565928009;6569.9343006569325;6590.242122155822;6593.472522198117;5259.447757985509;6076.939230607637;6360.968195158995;6445.581037232565;6446.7098980546925;6592.208934927839;6605;6609.900851487328;6620.205575330991;6622.933770662232;6122.032243765608;6284.842878928063;6347.71435285404;6410.134631824831;6424.871502570016 05:06:51 INFO - PID 1637 | |1;ext.html;692.5325405351388;756.6632848382469;837.330135891287;1261.2558590431643;1270.803025531043;33521.66478335216;39899.60100398995;39903.800480997554;39912.60087399125;39920.60277890271;39393.606063939354;39690.007749806195;39737.40393894094;39747.403788943186;39763;7458.179600243969;8296.211859873318;8969.820603587925;9038.051004644516;9074.228690561311;7705.499142555744;8982.146696063863;9034;9049.3665443419;9071.002189759121;8950.104989501064;8977.32670049749;8988.23599993998;9040.593173307218;9052.683156089539;8938.597763100644;8964.193222609963;8994.910050899472;9021;9031.32265080115;8278.96512935883;8737.257333126692;8757.124287571192;8801.163889430494;8834.249088827457;38443.80778096092;38547.8072609638;38653.8403847885;38732.612673873184;38747;3621.619729928346;3657.8902632921054;3659.249853779969;3663.432168013962;3669.1560940983723;32272.386380681106;32353;32378;32379.6762032379;32435;3156.463401221775;3159.6524382318157;3185.1877771168206;3186.4423725847882;3203.855826487814;217668;221412.78587214244;284202.5789871058;302852;303745.4812725944;50721.23918141231;50785.98428031419;51034.489655102974;51091.48908510942;51107;43927;50547.24179137316;53171;53226;53294;14407;14491;14517.346719397652;14532.854671453362;14546.927265363605;11866.821997670042;11869;11913.344766037952;11932.701682458008;11937.940310298567;13810.10234334765;13849.584512464642;13880.861191387958;13898.027138100237;14041.438342466392;5988.131720900445;6011.609245399035;6035.245594300713;6051;6070.848228794315;986.8306057322096;997.8802543694658;1000.2698030437821;1015.2487159501916;1045.5608644369383;24023.03907843667;24776.380590485383;25003.999840006196;25025.62461563078;25032.37419064538;1123.4326665034105;1127.2334812327697;1151.3897634253858;1165.5629139072848;1177.4819079605118;10780.838287425695;12644.114911956258;12833.294168820617;12856.742865142645;12874.613761587167;327899;329714;329889;330454;330516;56851;56921.43078569244;57176.42823571711;57249.71375143098;57270.14094788498;1498.715244103617;1500.2648702135875;1538.3846461415299;1542.7300222461024;1558.3610719605135;18635.204719292215;24574;24749;24770.25689229326;25194.74805251924;39662;39715;39718.60281397265;39933;39939.60060399475;287528;288868;289352;289660;290345;35251.82374088113;35764.46353304754;36219.456708149926;36382.27235455329;36479;47127.89179383466;47692;47759.28361074517;47788.28317575309;48274.75862620665;25934.842716094612;31185.53221701631;31771.364572708877;31866.330578266872;32361;29702.70297297086;31240.687593124687;32059.519107212946;32075.839620801748;32120.839395802872;38545.807270963465;38716.80641596774;38736.61263387443;38752;38780;24536.509269814145;31431;31915.824647943686;33015.66984330222;33131.66868331382;23223.535529289657;24818.131365402125;25097.082768995544;25346.74653253444;25373.85817638174 05:06:51 INFO - PID 1637 | |2;jquery.html;384.4617535450369;440.9140217657558;441.7945655270299;452.1770377912199;464.7536805493088;19898.801011989875;21636.675449868246;22273.220437284697;22503.437414064614;22582.20962266321;22072.779272207274;22461.32616021518;22859.199928002523;23040.769592304074;23537.529249415045;6298.181236439256;6378.585391949531;6567.244766851825;6587.967060164717;6592.044153597725;6314.463270622003;6510.348965103475;6514.543981921267;6539.673016349164;6548.574342667712;6203.75184992602;6395.616263024233;6469.223693156828;6478.222613286389;6505.577137486048;6408.134901788246;6448.419642232175;6495.253045899711;6496.3828436298945;6502.7398904043785;5496.532794712454;5701.971490142523;5747.971260143714;5758.539316854642;5762.135679648044;27104.051358202567;27419.725802741916;27469.86265068662;27551.173464796088;27865.721342786514;2296.494771150344;2354.2231063748923;2364.373441038126;2365.5860224460653;2374.750651181618;17611.823881761276;17726.20232089559;18384;18501.62996740071;18507.074646267767;1826.6529359421668;2045.6113338465793;2221.7556068832414;2253.9549209015727;2261.7398999114985;161803;162900.18549907292;164401.60192791233;165659;165720.17139914224;12826.743465130645;12848.678783030498;13184.60446186616;13386.26375549354;13483;14898.255087245594;15517.922410387875;16036;16846.494605161864;17008.31966721342;8240.816183711322;9068.81862362749;9144.359894807432;9279.489628070385;9281.72154835356;7229.530080544853;7820.882686759704;8101.906242657165;8631.395802293779;8665.046844847062;6859.588424694534;6865.828354291183;8142.918570814216;8280.461769984931;8330.75038744181;2081.302763574189;2154.7414310282866;2229.687843701883;2247.842651014446;2412.9879350603487;841.8063845315489;939.8073394954018;1001.8747656542932;1016.598443614768;1023.7594165371132;18503;20509.692354614694;20844.687329690067;21232.469188270417;21251.787482124982;1014.8934361892043;1017.8524114003428;1042.7393151712072;1053.4522048534873;1063.547992103353;10224.386536807815;11011.504482298316;11034.282771619819;11056.11551075912;11090.722731931766;448507;505036.3740906507;529612;545845;572697;121837;130677;131676.3416182913;134971;140064;1232.279116716713;1244.1104610203747;1286.8777466140702;1291.8643542428097;1293.734784369221;20994.895025525384;21460.570788584453;21723.891380542995;21958;22024.339269821932;56591.717041416174;56917.43082569121;57691.711541442026;58193.4180658188;58277;73373;73421;73513.26486735065;73588.52822943404;74571.8814217798;7681.539107653559;8995.415298005608;9000.729978100668;9098.090190981115;9115.134062264075;12946.41741121633;12994.415251313525;13025.348732563523;13682.726345473235;14175.858241417454;11639.941800290944;11675.88324116748;11858.52565897389;11928.105392095455;11932.522699091938;10966;11213.71965700864;11369.43152842338;11492.425378730863;11570.710732231762;10220.795584088128;11997.380353652145;12269.75460490803;12295.44670489848;12308.446119924803;8265.132161122996;9010.414323068979;9083.863742043743;9087.454752714859;9108.180263776292;10451.634192803216;11750.70623234426;11856.585019524275;12092.818607721067;12198.390080495763 05:06:51 INFO - PID 1637 | |3;mootools.html;1436.1670231265864;1486.52431222009;1494.5889880282914;1503.263400933543;1520.6122438778114;11670;11910.34493102879;12195.512179512809;12202.206856554318;12235.877641223586;12069.939650301758;12132.332721700302;12237.898589126977;12253.938730306358;12394.442250098744;2541.7331180226042;2590.352411897026;2593.222033389985;2602.3754298968206;2609.986950065247;2535.632333311669;2581.4578938422956;2592.6499922510407;2594.883230254643;2597.1169802267227;2541.885615147323;2554.4891021795625;2591.9611205831834;2593.5850263957773;2599.753023462768;2514.170323793153;2544.9745502544924;2557.232830150966;2571.4342844574353;2573.4467089575737;2532.4935012997475;2544.643749875019;2547.630593563923;2560.4111054457394;2577.36854470654;12005.819912701316;12034.037277017818;12247.142700011003;12261.93869030658;12272.938635306855;5669.8582535436535;6717.6977036033495;6922.2731613180895;6958.165020197609;7061.1173603299585;4246.341817018367;4431.8670439886855;4467.463904331468;4523.502414734409;4531.3882625845745;1050.6427814543015;1275.2412314672754;1281.9679508012375;1289.8774616411426;1351.5877657314516;16412.261448234858;16616.916915415466;16923.06923119228;17004.404845830337;17035.488935331836;5682.54539636828;5767.567432442584;5897.262842144646;5938.762449502058;5957.314908785458;11896;12107.515699372103;12118.454669539891;12127.272363658032;12144.514219431301;6734.0572319875255;6734.326567343324;6745.819481590702;6750.257471678112;6764.0530325754435;6101.908471372933;6136.938630613726;6257.937420625826;6292.182016337938;6311.463525600284;4337.804798784062;4437.423134992495;4468.419105516262;4470.926977525371;4474.284114541658;1277.067740549404;1296.293520031585;1302.3813688497887;1316.9539066132638;1316.9868301317056;800.9879851802227;802.4823988527368;830.7134038756619;830.779843341515;848.3128665780737;4916.803327866845;6217.658028808369;6352.396522330371;6378.553501254868;6396.968015159894;591.3170288317001;594.0435898203888;599.7780821096222;613.6624856329001;618.5793660311032;3873.205992771475;4457.665675074382;4462.040661257845;4484.663650226246;4548.431446069241;12158.452869620889;15193.544193674208;15318.617034574001;15399.46101886451;15410.306536205897;14690.485832996008;14990.250487475583;15423.920325577326;15433.45982890616;15447.150406727738;7671.309582137635;8438.36712246584;8778.912210877937;8827.558622069;8878.911210887938;6732.596044237165;7300.525465844703;7340.669669864762;7360.558366498028;7414.406847452083;12787.552435664707;12816;12824.422900969292;12826.486940522647;13052.54316098932;13502.25737584403;13942;14015.859841401863;14021.158730476203;14370.497032604228;9454.574544145393;9620.37467564606;9654.806903862023;9670.56482458305;9700.90299097;11040.944795275973;11144.944275278573;11233.831492527455;11262.211645184758;11277.21059525858;9272.814543708953;9324.813503730024;9419.152276295166;9549.283803714889;9575.712728618153;9128.634854605742;9212.539373031455;9223.58493867791;9250.814983700153;9412.105849944244;8284.917150828656;8379.45533540318;8494.53280069602;8523.062463128927;8593.570321483776;5970.223870896755;5975.133605627073;6075.696215189135;6076.331603523698;6185.103160041859;5547.528460080777;6178.783742568989;6193.659348735863;6308.990561510136;6353.809385718436 05:06:51 INFO - PID 1637 | |4;prototype.html;333.5480424025445;382.43590703712044;393.3607887183327;419.4903192620965;421.3027439587482;6009.278886533622;6145.907811382827;6181.3818618138275;6306.306306306314;6331.366863313667;6009.926575331504;6131.9080213796715;6139.447449729523;6193.225846769154;6214.782482613109;4406.757628330441;4678.789454474557;4704.506026867181;4711.9764401177945;4732.266498692708;3886.1839013807135;4486.932696009562;4595.264757638778;4627.537246275362;4709.387779588648;3890.6498415142632;4086.835251953194;4343.435353404053;4432.490263619677;4657.6273898088075;4457.799399027019;4643.8374656886845;4685.929711054337;4695.765211739416;4703.482616912137;4022.4770779798732;4120.835166593362;4152.252594532983;4157.521884983235;4178.958210417887;4798.448178459487;5272;5842.844863773557;5987.880242395171;5999.310079340845;5085.745712714386;5585.162225666182;5599.272094627713;5599.636023658449;5609.971950140264;6429.549931504796;6444.420002199778;6464.5798023128345;6503.918746001282;6525.184351956005;3505.2288496530723;3617.077645200457;3774.2640185163905;4311.1808756336195;4334.718243314174;5638;6028.728707208143;6876.484263680244;6988.440924726061;7049.612271325023;3821.808909554511;4332.545082766327;4338.240807858612;4340.479142502857;4373.234683930299;4260.126674031816;4409.699138754076;4415.139047885631;4421.602055814992;4436.977815110904;4813.590844778163;5398.217258497496;5572.359178694421;5636.802711905064;5640.605157639008;6454.419102280724;6624.304448032887;6667.43326817226;6687.264400915896;6707.463402927754;1324.6092402741215;1471.749802533572;1577.290219401274;1616.7817344658556;1704.5735598183428;898.9865152022725;1139.743557699511;1182.9408529573486;1198.646399312205;1205.511767734069;5522.779088836482;5865.35481097079;5879.353271140171;6198.194234749454;6229.726149955233;6878.69045892936;6905.620190889549;6916.6541672916155;6920.550164239308;6935;6005.429484198994;6213.844653883689;6287.622742635365;6298.1812364392335;6344.175257216532;3597.7661452005536;3608.891733248007;3609.9170248925216;3613.9638603613625;3623.655752703489;5817.854553636108;6099.841030204247;6544.541882068304;6999.265077166926;7119.857602847914;6226.159468471789;6523.934760652332;7007.789766306917;7144.606556803529;7146.106736657918;5916.230889984275;6592.373724496165;6983.44132469391;7009.12385951756;7115.786526404113;5731.423858438962;5928.347881733092;6564.964860893138;6638.0374845648075;6718;6379.617222966637;6424.618706978023;6673.332666733482;6768.56004359715;6779.966100169468;5404.459554044564;6470.28826829039;6946.930530694628;7005.299470052954;7119.501634885508;4326.836581709145;4327.761973091384;4366.585174408426;4369.759663218553;4378.365137055172;6227.657478838707;6716.361945615159;6839.384455399038;6861.416779573592;6864.141982252218;5768.875069361434;6283.088952102011;6403.4877209824035;6479.546431749733;6565.409113179837;6508;6554.410103090745;6575.835604109936;6582.045603387578;6605.471562275103;6214.25428948529;6368.936310637019;6483.00808377556;6497.772577959749;6506.3818937200895;5851.1515830205235;6464;6552.6068435894;6559.6064236145985;6563.638999855054;5990.251218597676;6027.547933904887;6069.117169344526;6235.937640623536;6421.357864213542 05:06:51 INFO - PID 1637 | |5;yui.html;760.874455714807;790.1663744749291;855.632078206371;858.2061593026449;912.9865848907712;13987.51043713469;15380.538583842503;16813.915930420346;17295.39466118686;19119.426417207473;18234.452966410994;18311.17599708013;18353.898766074046;18470.353537626186;18587.442376728686;823.6664151018847;1016.5019140621098;1034.5086084110026;1036.6216331039193;1039.0544604409988;1029.3360782295415;1039.870016247969;1041.281515754131;1041.343953309414;1043.4104730827096;1097.780443911217;1100.8073587122262;1100.983485247718;1101.399737143255;1102.3826657072025;945.8392073347547;1093.3767752381145;1097.1058587251443;1098.5386137822134;1105.6848798092585;596.6956852005464;782.1904329019437;789.0176729971165;791.3550456378033;798.4490701416063;16772.83227167725;17111.31554737809;17230.224639891236;17307.3942412015;17360.91319543407;16059.598510037225;17242.56893577658;17629;17837.910810445865;17880.91059544707;11233.606823761129;13818.861811381857;13893;14037.71924561503;14041.22773247471;3521.4893840393;3560.928781424357;3567.108222944264;3587.497750314958;3588.784672919633;129158;129305.35347323203;131262;131573;132804.33597832045;13324.333783310989;13500.594982150551;13620.387082581308;13774.65563360904;13907.79138312926;25305.74694253034;25439.982400704135;25573.360665983128;26007.479850403266;26027.08905188347;7756.379489640815;7839.45123841337;7861.4103942204565;7921.683132674629;7931.405144614177;3905.628965248297;3980.1840622672285;3984.5816189300285;3988.9002774930277;3990.8004599769897;4303.784810759449;4336.154449882306;4401.471823381215;4418.734875907456;4426.490953540384;884.8407286688458;899.3344924755764;905.6603773584906;906.7370462565782;908.1009800297658;644.5262731892055;656.2551504042946;662.8541720821412;668.6055227415751;690.1752405874931;9986;10818.891811081945;10998.560057597606;11639.709007274885;11640.126990475748;647.3106141958799;656.8587753632992;658.9670516474157;663.9701213445406;688.6040526697169;5341.577184047977;5390.703511306916;5481;5533.833984980377;5593.216949627055;103749.48125259325;104708.47645761723;106910;107325;109901.90098099077;82472.58763706143;91711.1657766841;92824.53587732019;94941.5252923731;96805.51597241969;80181.59909200417;80976.68768091509;81085.37829243382;82443.7633435487;83178.58410707908;9498.715038548855;9779.853302200616;11083.72290692739;11416.200865939636;12969.41637626328;42402.78798605987;42828;42894.78552607217;43292.91767705706;44233.33649995317;47134.528654714384;50902.981940360245;54029.3791186265;55373.44626553844;55643.44356556382;16660.750088748922;16961.321547137977;16998.405055822986;17039.40362087321;17049.40327088546;18080.72878906791;18259.908700456413;18313.175907083947;18524.44426667202;18605.162767675218;14432.567022989328;16896.577585559968;17120.657586847945;17264.482065538054;17693.911530442267;17041.23314450828;17155.485335439957;17245.65508689844;17312.826871731624;17426;16463.670726585642;17043.31826726917;17084.572885677957;17103.230354633823;17152.142392880556;11675.35785531804;11792.76414471723;12013.63959081229;12039.097067720131;12177.269363838199;13042.4130914111;13798;13871.791923121364;13896.235707035805;13929.791053134413 05:06:51 INFO - PID 1637 | __end_tp_report 05:06:51 INFO - PID 1637 | __start_cc_report 05:06:51 INFO - PID 1637 | _x_x_mozilla_cycle_collect,413 05:06:51 INFO - PID 1637 | __end_cc_report 05:06:51 INFO - PID 1637 | __startTimestamp1493208411206__endTimestamp 05:06:51 INFO - PID 1637 | 05:06:51 INFO - PID 1637 | ------- Summary: start ------- 05:06:51 INFO - PID 1637 | Number of tests: 6 05:06:51 INFO - PID 1637 | 05:06:51 INFO - PID 1637 | [#0] dojo.html Cycles:165 Average:6133.30 Median:6325.11 stddev:752.45 (11.9%) stddev-sans-first:722.45 05:06:51 INFO - PID 1637 | Values: 3352.6 3620.2 3629.5 3647.1 3660.5 6079.7 6135.5 6157.3 6206.4 6219.9 6142.3 6145.8 6170.4 6172.1 6177.2 4342.2 5333.0 5335.3 5342.9 5347.4 5385.9 5480.6 5488.7 5528.2 5567.9 4501.9 5341.7 5385.4 5434.5 5473.7 5402.1 5455.5 5492.9 5507.7 5547.8 5403.3 5448.5 5469.7 5483.5 5488.2 5976.4 6213.6 6216.7 6231.3 6243.7 6359.4 6477.3 6552.3 6667.4 6668.7 5646.5 5889.4 6627.5 6812.7 6820.2 6260.8 6270.5 6274.1 6278.0 6294.2 6558.6 6724.8 6835.8 6849.1 6880.3 6294.7 6365.1 6525.4 6530.9 6613.5 6796.4 6808.2 6812.9 6813.6 6837.1 5904.1 6574.9 6683.3 6711.8 6745.2 5517.7 6391.3 6464.5 6558.6 6621.1 5327.3 5345.6 5365.7 5374.9 5378.7 4714.1 4736.3 4745.7 4762.9 4787.0 5221.6 6141.5 6207.3 6306.1 6334.6 6741.5 6796.1 6814.2 6821.8 6840.9 6276.4 6318.9 6321.4 6325.1 6336.7 5706.8 5950.9 5968.3 5989.8 5993.6 6816.1 6965.8 7012.2 7047.0 7072.7 6832.8 6855.6 6858.4 6871.5 6881.6 6898.4 6942.6 7034.6 7042.4 7052.3 6396.5 6475.5 6605.1 6665.5 6719.9 6665.0 6836.5 6842.0 6851.2 6858.9 6279.2 6388.5 6483.4 6578.7 6596.2 6463.9 6465.1 6569.9 6590.2 6593.5 5259.4 6076.9 6361.0 6445.6 6446.7 6592.2 6605.0 6609.9 6620.2 6622.9 6122.0 6284.8 6347.7 6410.1 6424.9 05:06:51 INFO - PID 1637 | 05:06:51 INFO - PID 1637 | [#1] ext.html Cycles:180 Average:45429.13 Median:34386.74 stddev:77819.35 (226.3%) stddev-sans-first:77965.16 05:06:51 INFO - PID 1637 | Values: 692.5 756.7 837.3 1261.3 1270.8 33521.7 39899.6 39903.8 39912.6 39920.6 39393.6 39690.0 39737.4 39747.4 39763.0 7458.2 8296.2 8969.8 9038.1 9074.2 7705.5 8982.1 9034.0 9049.4 9071.0 8950.1 8977.3 8988.2 9040.6 9052.7 8938.6 8964.2 8994.9 9021.0 9031.3 8279.0 8737.3 8757.1 8801.2 8834.2 38443.8 38547.8 38653.8 38732.6 38747.0 3621.6 3657.9 3659.2 3663.4 3669.2 32272.4 32353.0 32378.0 32379.7 32435.0 3156.5 3159.7 3185.2 3186.4 3203.9 217668.0 221412.8 284202.6 302852.0 303745.5 50721.2 50786.0 51034.5 51091.5 51107.0 43927.0 50547.2 53171.0 53226.0 53294.0 14407.0 14491.0 14517.3 14532.9 14546.9 11866.8 11869.0 11913.3 11932.7 11937.9 13810.1 13849.6 13880.9 13898.0 14041.4 5988.1 6011.6 6035.2 6051.0 6070.8 986.8 997.9 1000.3 1015.2 1045.6 24023.0 24776.4 25004.0 25025.6 25032.4 1123.4 1127.2 1151.4 1165.6 1177.5 10780.8 12644.1 12833.3 12856.7 12874.6 327899.0 329714.0 329889.0 330454.0 330516.0 56851.0 56921.4 57176.4 57249.7 57270.1 1498.7 1500.3 1538.4 1542.7 1558.4 18635.2 24574.0 24749.0 24770.3 25194.7 39662.0 39715.0 39718.6 39933.0 39939.6 287528.0 288868.0 289352.0 289660.0 290345.0 35251.8 35764.5 36219.5 36382.3 36479.0 47127.9 47692.0 47759.3 47788.3 48274.8 25934.8 31185.5 31771.4 31866.3 32361.0 29702.7 31240.7 32059.5 32075.8 32120.8 38545.8 38716.8 38736.6 38752.0 38780.0 24536.5 31431.0 31915.8 33015.7 33131.7 23223.5 24818.1 25097.1 25346.7 25373.9 05:06:51 INFO - PID 1637 | 05:06:51 INFO - PID 1637 | [#2] jquery.html Cycles:180 Average:35119.87 Median:12378.70 stddev:89386.32 (722.1%) stddev-sans-first:89599.03 05:06:51 INFO - PID 1637 | Values: 384.5 440.9 441.8 452.2 464.8 19898.8 21636.7 22273.2 22503.4 22582.2 22072.8 22461.3 22859.2 23040.8 23537.5 6298.2 6378.6 6567.2 6588.0 6592.0 6314.5 6510.3 6514.5 6539.7 6548.6 6203.8 6395.6 6469.2 6478.2 6505.6 6408.1 6448.4 6495.3 6496.4 6502.7 5496.5 5702.0 5748.0 5758.5 5762.1 27104.1 27419.7 27469.9 27551.2 27865.7 2296.5 2354.2 2364.4 2365.6 2374.8 17611.8 17726.2 18384.0 18501.6 18507.1 1826.7 2045.6 2221.8 2254.0 2261.7 161803.0 162900.2 164401.6 165659.0 165720.2 12826.7 12848.7 13184.6 13386.3 13483.0 14898.3 15517.9 16036.0 16846.5 17008.3 8240.8 9068.8 9144.4 9279.5 9281.7 7229.5 7820.9 8101.9 8631.4 8665.0 6859.6 6865.8 8142.9 8280.5 8330.8 2081.3 2154.7 2229.7 2247.8 2413.0 841.8 939.8 1001.9 1016.6 1023.8 18503.0 20509.7 20844.7 21232.5 21251.8 1014.9 1017.9 1042.7 1053.5 1063.5 10224.4 11011.5 11034.3 11056.1 11090.7 448507.0 505036.4 529612.0 545845.0 572697.0 121837.0 130677.0 131676.3 134971.0 140064.0 1232.3 1244.1 1286.9 1291.9 1293.7 20994.9 21460.6 21723.9 21958.0 22024.3 56591.7 56917.4 57691.7 58193.4 58277.0 73373.0 73421.0 73513.3 73588.5 74571.9 7681.5 8995.4 9000.7 9098.1 9115.1 12946.4 12994.4 13025.3 13682.7 14175.9 11639.9 11675.9 11858.5 11928.1 11932.5 10966.0 11213.7 11369.4 11492.4 11570.7 10220.8 11997.4 12269.8 12295.4 12308.4 8265.1 9010.4 9083.9 9087.5 9108.2 10451.6 11750.7 11856.6 12092.8 12198.4 05:06:51 INFO - PID 1637 | 05:06:51 INFO - PID 1637 | [#3] mootools.html Cycles:180 Average:7249.47 Median:4059.77 stddev:4574.05 (112.7%) stddev-sans-first:4566.03 05:06:51 INFO - PID 1637 | Values: 1436.2 1486.5 1494.6 1503.3 1520.6 11670.0 11910.3 12195.5 12202.2 12235.9 12069.9 12132.3 12237.9 12253.9 12394.4 2541.7 2590.4 2593.2 2602.4 2610.0 2535.6 2581.5 2592.6 2594.9 2597.1 2541.9 2554.5 2592.0 2593.6 2599.8 2514.2 2545.0 2557.2 2571.4 2573.4 2532.5 2544.6 2547.6 2560.4 2577.4 12005.8 12034.0 12247.1 12261.9 12272.9 5669.9 6717.7 6922.3 6958.2 7061.1 4246.3 4431.9 4467.5 4523.5 4531.4 1050.6 1275.2 1282.0 1289.9 1351.6 16412.3 16616.9 16923.1 17004.4 17035.5 5682.5 5767.6 5897.3 5938.8 5957.3 11896.0 12107.5 12118.5 12127.3 12144.5 6734.1 6734.3 6745.8 6750.3 6764.1 6101.9 6136.9 6257.9 6292.2 6311.5 4337.8 4437.4 4468.4 4470.9 4474.3 1277.1 1296.3 1302.4 1317.0 1317.0 801.0 802.5 830.7 830.8 848.3 4916.8 6217.7 6352.4 6378.6 6397.0 591.3 594.0 599.8 613.7 618.6 3873.2 4457.7 4462.0 4484.7 4548.4 12158.5 15193.5 15318.6 15399.5 15410.3 14690.5 14990.3 15423.9 15433.5 15447.2 7671.3 8438.4 8778.9 8827.6 8878.9 6732.6 7300.5 7340.7 7360.6 7414.4 12787.6 12816.0 12824.4 12826.5 13052.5 13502.3 13942.0 14015.9 14021.2 14370.5 9454.6 9620.4 9654.8 9670.6 9700.9 11040.9 11144.9 11233.8 11262.2 11277.2 9272.8 9324.8 9419.2 9549.3 9575.7 9128.6 9212.5 9223.6 9250.8 9412.1 8284.9 8379.5 8494.5 8523.1 8593.6 5970.2 5975.1 6075.7 6076.3 6185.1 5547.5 6178.8 6193.7 6309.0 6353.8 05:06:51 INFO - PID 1637 | 05:06:51 INFO - PID 1637 | [#4] prototype.html Cycles:180 Average:5314.41 Median:6002.37 stddev:1654.12 (27.6%) stddev-sans-first:1615.96 05:06:51 INFO - PID 1637 | Values: 333.5 382.4 393.4 419.5 421.3 6009.3 6145.9 6181.4 6306.3 6331.4 6009.9 6131.9 6139.4 6193.2 6214.8 4406.8 4678.8 4704.5 4712.0 4732.3 3886.2 4486.9 4595.3 4627.5 4709.4 3890.6 4086.8 4343.4 4432.5 4657.6 4457.8 4643.8 4685.9 4695.8 4703.5 4022.5 4120.8 4152.3 4157.5 4179.0 4798.4 5272.0 5842.8 5987.9 5999.3 5085.7 5585.2 5599.3 5599.6 5610.0 6429.5 6444.4 6464.6 6503.9 6525.2 3505.2 3617.1 3774.3 4311.2 4334.7 5638.0 6028.7 6876.5 6988.4 7049.6 3821.8 4332.5 4338.2 4340.5 4373.2 4260.1 4409.7 4415.1 4421.6 4437.0 4813.6 5398.2 5572.4 5636.8 5640.6 6454.4 6624.3 6667.4 6687.3 6707.5 1324.6 1471.7 1577.3 1616.8 1704.6 899.0 1139.7 1182.9 1198.6 1205.5 5522.8 5865.4 5879.4 6198.2 6229.7 6878.7 6905.6 6916.7 6920.6 6935.0 6005.4 6213.8 6287.6 6298.2 6344.2 3597.8 3608.9 3609.9 3614.0 3623.7 5817.9 6099.8 6544.5 6999.3 7119.9 6226.2 6523.9 7007.8 7144.6 7146.1 5916.2 6592.4 6983.4 7009.1 7115.8 5731.4 5928.3 6565.0 6638.0 6718.0 6379.6 6424.6 6673.3 6768.6 6780.0 5404.5 6470.3 6946.9 7005.3 7119.5 4326.8 4327.8 4366.6 4369.8 4378.4 6227.7 6716.4 6839.4 6861.4 6864.1 5768.9 6283.1 6403.5 6479.5 6565.4 6508.0 6554.4 6575.8 6582.0 6605.5 6214.3 6368.9 6483.0 6497.8 6506.4 5851.2 6464.0 6552.6 6559.6 6563.6 5990.3 6027.5 6069.1 6235.9 6421.4 05:06:51 INFO - PID 1637 | 05:06:51 INFO - PID 1637 | [#5] yui.html Cycles:180 Average:22099.03 Median:18247.18 stddev:31297.69 (171.5%) stddev-sans-first:31344.47 05:06:51 INFO - PID 1637 | Values: 760.9 790.2 855.6 858.2 913.0 13987.5 15380.5 16813.9 17295.4 19119.4 18234.5 18311.2 18353.9 18470.4 18587.4 823.7 1016.5 1034.5 1036.6 1039.1 1029.3 1039.9 1041.3 1041.3 1043.4 1097.8 1100.8 1101.0 1101.4 1102.4 945.8 1093.4 1097.1 1098.5 1105.7 596.7 782.2 789.0 791.4 798.4 16772.8 17111.3 17230.2 17307.4 17360.9 16059.6 17242.6 17629.0 17837.9 17880.9 11233.6 13818.9 13893.0 14037.7 14041.2 3521.5 3560.9 3567.1 3587.5 3588.8 129158.0 129305.4 131262.0 131573.0 132804.3 13324.3 13500.6 13620.4 13774.7 13907.8 25305.7 25440.0 25573.4 26007.5 26027.1 7756.4 7839.5 7861.4 7921.7 7931.4 3905.6 3980.2 3984.6 3988.9 3990.8 4303.8 4336.2 4401.5 4418.7 4426.5 884.8 899.3 905.7 906.7 908.1 644.5 656.3 662.9 668.6 690.2 9986.0 10818.9 10998.6 11639.7 11640.1 647.3 656.9 659.0 664.0 688.6 5341.6 5390.7 5481.0 5533.8 5593.2 103749.5 104708.5 106910.0 107325.0 109901.9 82472.6 91711.2 92824.5 94941.5 96805.5 80181.6 80976.7 81085.4 82443.8 83178.6 9498.7 9779.9 11083.7 11416.2 12969.4 42402.8 42828.0 42894.8 43292.9 44233.3 47134.5 50903.0 54029.4 55373.4 55643.4 16660.8 16961.3 16998.4 17039.4 17049.4 18080.7 18259.9 18313.2 18524.4 18605.2 14432.6 16896.6 17120.7 17264.5 17693.9 17041.2 17155.5 17245.7 17312.8 17426.0 16463.7 17043.3 17084.6 17103.2 17152.1 11675.4 11792.8 12013.6 12039.1 12177.3 13042.4 13798.0 13871.8 13896.2 13929.8 05:06:51 INFO - PID 1637 | -------- Summary: end -------- 05:06:51 INFO - PID 1637 | 05:06:51 INFO - TEST-INFO | 1637: exit 0 05:06:51 INFO - TEST-OK | dromaeo_css | took 1114442ms 05:06:51 INFO - TEST-START | kraken 05:06:51 INFO - operating with platform_type : mac_ 05:06:51 INFO - Initialising browser for kraken test... 05:06:51 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpWMrf7z/profile http://localhost:49244/getInfo.html) 05:06:54 INFO - PID 1649 | 2017-04-26 05:06:54.627 plugin-container[1651:10109] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9547, name = 'com.apple.tsm.portname' 05:06:54 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:06:54 INFO - PID 1649 | 2017-04-26 05:06:54.627 plugin-container[1651:10109] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0f, name = 'com.apple.CFPasteboardClient' 05:06:54 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:06:54 INFO - PID 1649 | 2017-04-26 05:06:54.627 plugin-container[1651:10109] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:06:54 INFO - PID 1649 | __metrics Screen width/height:1600/1200 05:06:54 INFO - PID 1649 | colorDepth:24 05:06:54 INFO - PID 1649 | Browser inner width/height: 1024/689 05:06:54 INFO - PID 1649 | __metrics 05:06:54 ERROR - PID 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3424: TypeError: this.tabs is undefined 05:06:55 INFO - TEST-INFO | 1649: exit 0 05:06:55 INFO - Browser initialized. 05:06:55 INFO - Running cycle 1/1 for kraken test... 05:06:55 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpWMrf7z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:06:58 INFO - PID 1652 | 2017-04-26 05:06:58.113 plugin-container[1653:10239] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9547, name = 'com.apple.tsm.portname' 05:06:58 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:06:58 INFO - PID 1652 | 2017-04-26 05:06:58.113 plugin-container[1653:10239] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x460b, name = 'com.apple.CFPasteboardClient' 05:06:58 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:06:58 INFO - PID 1652 | 2017-04-26 05:06:58.113 plugin-container[1653:10239] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:07:08 INFO - PID 1652 | RSS: Main: 238661632 05:07:08 INFO - PID 1652 | 05:07:10 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 05:07:11 INFO - PID 1652 | RSS: Main: 249438208 05:07:11 INFO - PID 1652 | 05:07:14 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 05:07:14 INFO - PID 1652 | RSS: Main: 257425408 05:07:14 INFO - PID 1652 | 05:07:18 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 05:07:18 INFO - PID 1652 | RSS: Main: 259350528 05:07:18 INFO - PID 1652 | 05:07:20 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 05:07:21 INFO - PID 1652 | RSS: Main: 260702208 05:07:21 INFO - PID 1652 | 05:07:23 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:07:24 INFO - PID 1652 | RSS: Main: 262619136 05:07:24 INFO - PID 1652 | 05:07:27 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 05:07:27 INFO - PID 1652 | RSS: Main: 268128256 05:07:27 INFO - PID 1652 | 05:07:31 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 05:07:31 INFO - PID 1652 | RSS: Main: 269123584 05:07:31 INFO - PID 1652 | 05:07:34 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 05:07:35 INFO - PID 1652 | RSS: Main: 268763136 05:07:35 INFO - PID 1652 | 05:07:36 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:07:37 INFO - PID 1652 | RSS: Main: 268300288 05:07:37 INFO - PID 1652 | 05:07:39 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:07:39 INFO - PID 1652 | RSS: Main: 268382208 05:07:39 INFO - PID 1652 | 05:07:42 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:07:42 INFO - PID 1652 | RSS: Main: 268075008 05:07:42 INFO - PID 1652 | 05:07:45 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:07:46 INFO - PID 1652 | RSS: Main: 268218368 05:07:46 INFO - PID 1652 | 05:07:49 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:07:49 INFO - PID 1652 | RSS: Main: 268320768 05:07:49 INFO - PID 1652 | 05:07:51 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 05:07:51 INFO - PID 1652 | RSS: Main: 270102528 05:07:51 INFO - PID 1652 | 05:07:51 INFO - PID 1652 | __start_tp_report 05:07:51 INFO - PID 1652 | _x_x_mozilla_page_load 05:07:51 INFO - PID 1652 | _x_x_mozilla_page_load_details 05:07:51 INFO - PID 1652 | |i|pagename|runs| 05:07:51 INFO - PID 1652 | |0;ai-astar;103;116;115;115;114;115;114;115;115;114 05:07:51 INFO - PID 1652 | |1;audio-beat-detection;145;109;124;181;111;115;107;110;138;108 05:07:51 INFO - PID 1652 | |2;audio-dft;195;207;197;224;271;247;271;267;196;198 05:07:51 INFO - PID 1652 | |3;audio-fft;77;59;57;88;86;82;80;79;86;61 05:07:51 INFO - PID 1652 | |4;audio-oscillator;91;60;76;78;54;56;94;93;103;114 05:07:51 INFO - PID 1652 | |5;imaging-gaussian-blur;88;91;109;76;104;124;129;88;88;89 05:07:51 INFO - PID 1652 | |6;imaging-darkroom;95;97;95;95;96;123;105;148;137;141 05:07:51 INFO - PID 1652 | |7;imaging-desaturate;94;117;102;99;89;87;92;143;91;94 05:07:51 INFO - PID 1652 | |8;json-parse-financial;52;52;50;71;77;50;42;71;75;74 05:07:51 INFO - PID 1652 | |9;json-stringify-tinderbox;64;56;58;61;61;59;55;57;58;59 05:07:51 INFO - PID 1652 | |10;stanford-crypto-aes;83;84;83;82;79;82;81;83;82;81 05:07:51 INFO - PID 1652 | |11;stanford-crypto-ccm;135;149;147;139;138;134;154;156;198;127 05:07:51 INFO - PID 1652 | |12;stanford-crypto-pbkdf2;181;188;183;185;180;183;180;181;185;192 05:07:51 INFO - PID 1652 | |13;stanford-crypto-sha256-iterative;70;70;68;67;69;93;90;96;91;69 05:07:51 INFO - PID 1652 | __end_tp_report 05:07:51 INFO - PID 1652 | __start_cc_report 05:07:51 INFO - PID 1652 | _x_x_mozilla_cycle_collect,984 05:07:51 INFO - PID 1652 | __end_cc_report 05:07:51 INFO - PID 1652 | __startTimestamp1493208471884__endTimestamp 05:07:51 INFO - PID 1652 | 05:07:51 INFO - PID 1652 | ------- Summary: start ------- 05:07:51 INFO - PID 1652 | Number of tests: 14 05:07:51 INFO - PID 1652 | 05:07:51 INFO - PID 1652 | [#0] ai-astar Cycles:10 Average:113.60 Median:115.00 stddev:3.78 (3.3%) stddev-sans-first:0.67 05:07:51 INFO - PID 1652 | Values: 103.0 116.0 115.0 115.0 114.0 115.0 114.0 115.0 115.0 114.0 05:07:51 INFO - PID 1652 | 05:07:51 INFO - PID 1652 | [#1] audio-beat-detection Cycles:10 Average:124.80 Median:119.50 stddev:23.79 (19.9%) stddev-sans-first:24.09 05:07:51 INFO - PID 1652 | Values: 145.0 109.0 124.0 181.0 111.0 115.0 107.0 110.0 138.0 108.0 05:07:51 INFO - PID 1652 | 05:07:51 INFO - PID 1652 | [#2] audio-dft Cycles:10 Average:227.30 Median:235.50 stddev:33.34 (14.2%) stddev-sans-first:33.25 05:07:51 INFO - PID 1652 | Values: 195.0 207.0 197.0 224.0 271.0 247.0 271.0 267.0 196.0 198.0 05:07:51 INFO - PID 1652 | 05:07:51 INFO - PID 1652 | [#3] audio-fft Cycles:10 Average:75.50 Median:81.00 stddev:11.92 (14.7%) stddev-sans-first:12.63 05:07:51 INFO - PID 1652 | Values: 77.0 59.0 57.0 88.0 86.0 82.0 80.0 79.0 86.0 61.0 05:07:51 INFO - PID 1652 | 05:07:51 INFO - PID 1652 | [#4] audio-oscillator Cycles:10 Average:81.90 Median:77.00 stddev:20.57 (26.7%) stddev-sans-first:21.55 05:07:51 INFO - PID 1652 | Values: 91.0 60.0 76.0 78.0 54.0 56.0 94.0 93.0 103.0 114.0 05:07:51 INFO - PID 1652 | 05:07:51 INFO - PID 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:98.60 Median:88.00 stddev:17.33 (19.7%) stddev-sans-first:17.96 05:07:51 INFO - PID 1652 | Values: 88.0 91.0 109.0 76.0 104.0 124.0 129.0 88.0 88.0 89.0 05:07:51 INFO - PID 1652 | 05:07:51 INFO - PID 1652 | [#6] imaging-darkroom Cycles:10 Average:113.20 Median:95.00 stddev:21.77 (22.9%) stddev-sans-first:22.07 05:07:51 INFO - PID 1652 | Values: 95.0 97.0 95.0 95.0 96.0 123.0 105.0 148.0 137.0 141.0 05:07:51 INFO - PID 1652 | 05:07:51 INFO - PID 1652 | [#7] imaging-desaturate Cycles:10 Average:100.80 Median:91.50 stddev:17.14 (18.7%) stddev-sans-first:18.00 05:07:51 INFO - PID 1652 | Values: 94.0 117.0 102.0 99.0 89.0 87.0 92.0 143.0 91.0 94.0 05:07:51 INFO - PID 1652 | 05:07:51 INFO - PID 1652 | [#8] json-parse-financial Cycles:10 Average:61.40 Median:71.00 stddev:13.27 (18.7%) stddev-sans-first:13.63 05:07:51 INFO - PID 1652 | Values: 52.0 52.0 50.0 71.0 77.0 50.0 42.0 71.0 75.0 74.0 05:07:51 INFO - PID 1652 | 05:07:51 INFO - PID 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:58.80 Median:59.00 stddev:2.66 (4.5%) stddev-sans-first:2.05 05:07:51 INFO - PID 1652 | Values: 64.0 56.0 58.0 61.0 61.0 59.0 55.0 57.0 58.0 59.0 05:07:51 INFO - PID 1652 | 05:07:51 INFO - PID 1652 | [#10] stanford-crypto-aes Cycles:10 Average:82.00 Median:82.50 stddev:1.41 (1.7%) stddev-sans-first:1.45 05:07:51 INFO - PID 1652 | Values: 83.0 84.0 83.0 82.0 79.0 82.0 81.0 83.0 82.0 81.0 05:07:51 INFO - PID 1652 | 05:07:51 INFO - PID 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:147.70 Median:148.00 stddev:19.97 (13.5%) stddev-sans-first:20.64 05:07:51 INFO - PID 1652 | Values: 135.0 149.0 147.0 139.0 138.0 134.0 154.0 156.0 198.0 127.0 05:07:51 INFO - PID 1652 | 05:07:51 INFO - PID 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.80 Median:184.00 stddev:3.85 (2.1%) stddev-sans-first:3.95 05:07:51 INFO - PID 1652 | Values: 181.0 188.0 183.0 185.0 180.0 183.0 180.0 181.0 185.0 192.0 05:07:51 INFO - PID 1652 | 05:07:51 INFO - PID 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.30 Median:80.00 stddev:12.35 (15.4%) stddev-sans-first:12.73 05:07:51 INFO - PID 1652 | Values: 70.0 70.0 68.0 67.0 69.0 93.0 90.0 96.0 91.0 69.0 05:07:51 INFO - PID 1652 | -------- Summary: end -------- 05:07:51 INFO - PID 1652 | 05:07:53 INFO - TEST-INFO | 1652: exit 0 05:07:53 INFO - TEST-OK | kraken | took 61283ms 05:07:53 INFO - SUITE-END | took 1175s 05:07:53 INFO - Completed test suite (00:19:36) 05:07:53 INFO - javascript benchmark 05:07:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7970.535978670916, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3352.631210566838, 3620.1673615068535, 3629.5100161478194, 3647.106458917565, 3660.4509323601455, 6079.696015199244, 6135.478484328832, 6157.291911430187, 6206.379362063792, 6219.875602487948, 6142.3453045438, 6145.77084583083, 6170.413810687989, 6172.135900973867, 6177.227846519185, 4342.196693611676, 5332.973335133319, 5335.306410166673, 5342.91985620216, 5347.385050719168, 5385.946140538604, 5480.561555075605, 5488.670679759227, 5528.170774383834, 5567.8886422271535, 4501.932471012938, 5341.6527925685095, 5385.35375754908, 5434.538064264522, 5473.67157970521, 5402.081646120129, 5455.454454554552, 5492.917606235909, 5507.7246137692955, 5547.750351234205, 5403.2705584746345, 5448.509634132907, 5469.72651367434, 5483.506484416422, 5488.17677348401, 5976.432238937294, 6213.596116252429, 6216.720247588869, 6231.345708700566, 6243.65659888706, 6359.36406359365, 6477.255115661665, 6552.344765523457, 6667.366600172976, 6668.733250669968, 5646.491815736563, 5889.411058894119, 6627.536072474929, 6812.727490900358, 6820.215675197366, 6260.780872669435, 6270.46701030413, 6274.090256912722, 6278.026905829649, 6294.181756371734, 6558.573692709959, 6724.831879203059, 6835.7949261522235, 6849.1438570178725, 6880.346367095218, 6294.653794041372, 6365.140706004723, 6525.3800888915475, 6530.869382612416, 6613.503987200979, 6796.422304104108, 6808.217055038734, 6812.931870681329, 6813.625250611165, 6837.076994605764, 5904.143899134601, 6574.934250657528, 6683.331666833278, 6711.765088221987, 6745.2242992056545, 5517.69652669107, 6391.265004524446, 6464.515161362917, 6558.573692709959, 6621.1392518972225, 5327.3074500314715, 5345.572354211731, 5365.704886231217, 5374.865628359323, 5378.650387724785, 4714.080754252887, 4736.3369128322065, 4745.715257084586, 4762.857114286577, 4787.042591481718, 5221.6083793715625, 6141.539384546177, 6207.317195108534, 6306.085617585424, 6334.556581039283, 6741.494387920926, 6796.082528858639, 6814.182298124257, 6821.829454263683, 6840.863182736317, 6276.403741644628, 6318.905216421757, 6321.3678632137335, 6325.114483972247, 6336.746530138835, 5706.828795136153, 5950.8809823803285, 5968.283805943228, 5989.790357337472, 5993.580449368589, 6816.079829222991, 6965.825854353681, 7012.158540974911, 7047.0, 7072.681729322296, 6832.795016149524, 6855.622940738307, 6858.382745552724, 6871.4502839773695, 6881.587104773729, 6898.412286048317, 6942.6181560012665, 7034.648267586703, 7042.43660507148, 7052.294770522906, 6396.456301214449, 6475.546711730133, 6605.141331626858, 6665.500087493554, 6719.899201511884, 6665.0, 6836.521443499107, 6842.0, 6851.212110607344, 6858.897116543156, 6279.246490421178, 6388.456981156468, 6483.448906842971, 6578.7368505261165, 6596.241432235354, 6463.870722585428, 6465.062565928009, 6569.9343006569325, 6590.242122155822, 6593.472522198117, 5259.447757985509, 6076.939230607637, 6360.968195158995, 6445.581037232565, 6446.7098980546925, 6592.208934927839, 6605.0, 6609.900851487328, 6620.205575330991, 6622.933770662232, 6122.032243765608, 6284.842878928063, 6347.71435285404, 6410.134631824831, 6424.871502570016], "value": 6083.3963002882865, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.5325405351388, 756.6632848382469, 837.330135891287, 1261.2558590431643, 1270.803025531043, 33521.66478335216, 39899.60100398995, 39903.800480997554, 39912.60087399125, 39920.60277890271, 39393.606063939354, 39690.007749806195, 39737.40393894094, 39747.403788943186, 39763.0, 7458.179600243969, 8296.211859873318, 8969.820603587925, 9038.051004644516, 9074.228690561311, 7705.499142555744, 8982.146696063863, 9034.0, 9049.3665443419, 9071.002189759121, 8950.104989501064, 8977.32670049749, 8988.23599993998, 9040.593173307218, 9052.683156089539, 8938.597763100644, 8964.193222609963, 8994.910050899472, 9021.0, 9031.32265080115, 8278.96512935883, 8737.257333126692, 8757.124287571192, 8801.163889430494, 8834.249088827457, 38443.80778096092, 38547.8072609638, 38653.8403847885, 38732.612673873184, 38747.0, 3621.619729928346, 3657.8902632921054, 3659.249853779969, 3663.432168013962, 3669.1560940983723, 32272.386380681106, 32353.0, 32378.0, 32379.6762032379, 32435.0, 3156.463401221775, 3159.6524382318157, 3185.1877771168206, 3186.4423725847882, 3203.855826487814, 217668.0, 221412.78587214244, 284202.5789871058, 302852.0, 303745.4812725944, 50721.23918141231, 50785.98428031419, 51034.489655102974, 51091.48908510942, 51107.0, 43927.0, 50547.24179137316, 53171.0, 53226.0, 53294.0, 14407.0, 14491.0, 14517.346719397652, 14532.854671453362, 14546.927265363605, 11866.821997670042, 11869.0, 11913.344766037952, 11932.701682458008, 11937.940310298567, 13810.10234334765, 13849.584512464642, 13880.861191387958, 13898.027138100237, 14041.438342466392, 5988.131720900445, 6011.609245399035, 6035.245594300713, 6051.0, 6070.848228794315, 986.8306057322096, 997.8802543694658, 1000.2698030437821, 1015.2487159501916, 1045.5608644369383, 24023.03907843667, 24776.380590485383, 25003.999840006196, 25025.62461563078, 25032.37419064538, 1123.4326665034105, 1127.2334812327697, 1151.3897634253858, 1165.5629139072848, 1177.4819079605118, 10780.838287425695, 12644.114911956258, 12833.294168820617, 12856.742865142645, 12874.613761587167, 327899.0, 329714.0, 329889.0, 330454.0, 330516.0, 56851.0, 56921.43078569244, 57176.42823571711, 57249.71375143098, 57270.14094788498, 1498.715244103617, 1500.2648702135875, 1538.3846461415299, 1542.7300222461024, 1558.3610719605135, 18635.204719292215, 24574.0, 24749.0, 24770.25689229326, 25194.74805251924, 39662.0, 39715.0, 39718.60281397265, 39933.0, 39939.60060399475, 287528.0, 288868.0, 289352.0, 289660.0, 290345.0, 35251.82374088113, 35764.46353304754, 36219.456708149926, 36382.27235455329, 36479.0, 47127.89179383466, 47692.0, 47759.28361074517, 47788.28317575309, 48274.75862620665, 25934.842716094612, 31185.53221701631, 31771.364572708877, 31866.330578266872, 32361.0, 29702.70297297086, 31240.687593124687, 32059.519107212946, 32075.839620801748, 32120.839395802872, 38545.807270963465, 38716.80641596774, 38736.61263387443, 38752.0, 38780.0, 24536.509269814145, 31431.0, 31915.824647943686, 33015.66984330222, 33131.66868331382, 23223.535529289657, 24818.131365402125, 25097.082768995544, 25346.74653253444, 25373.85817638174], "value": 17996.6298729226, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.4617535450369, 440.9140217657558, 441.7945655270299, 452.1770377912199, 464.7536805493088, 19898.801011989875, 21636.675449868246, 22273.220437284697, 22503.437414064614, 22582.20962266321, 22072.779272207274, 22461.32616021518, 22859.199928002523, 23040.769592304074, 23537.529249415045, 6298.181236439256, 6378.585391949531, 6567.244766851825, 6587.967060164717, 6592.044153597725, 6314.463270622003, 6510.348965103475, 6514.543981921267, 6539.673016349164, 6548.574342667712, 6203.75184992602, 6395.616263024233, 6469.223693156828, 6478.222613286389, 6505.577137486048, 6408.134901788246, 6448.419642232175, 6495.253045899711, 6496.3828436298945, 6502.7398904043785, 5496.532794712454, 5701.971490142523, 5747.971260143714, 5758.539316854642, 5762.135679648044, 27104.051358202567, 27419.725802741916, 27469.86265068662, 27551.173464796088, 27865.721342786514, 2296.494771150344, 2354.2231063748923, 2364.373441038126, 2365.5860224460653, 2374.750651181618, 17611.823881761276, 17726.20232089559, 18384.0, 18501.62996740071, 18507.074646267767, 1826.6529359421668, 2045.6113338465793, 2221.7556068832414, 2253.9549209015727, 2261.7398999114985, 161803.0, 162900.18549907292, 164401.60192791233, 165659.0, 165720.17139914224, 12826.743465130645, 12848.678783030498, 13184.60446186616, 13386.26375549354, 13483.0, 14898.255087245594, 15517.922410387875, 16036.0, 16846.494605161864, 17008.31966721342, 8240.816183711322, 9068.81862362749, 9144.359894807432, 9279.489628070385, 9281.72154835356, 7229.530080544853, 7820.882686759704, 8101.906242657165, 8631.395802293779, 8665.046844847062, 6859.588424694534, 6865.828354291183, 8142.918570814216, 8280.461769984931, 8330.75038744181, 2081.302763574189, 2154.7414310282866, 2229.687843701883, 2247.842651014446, 2412.9879350603487, 841.8063845315489, 939.8073394954018, 1001.8747656542932, 1016.598443614768, 1023.7594165371132, 18503.0, 20509.692354614694, 20844.687329690067, 21232.469188270417, 21251.787482124982, 1014.8934361892043, 1017.8524114003428, 1042.7393151712072, 1053.4522048534873, 1063.547992103353, 10224.386536807815, 11011.504482298316, 11034.282771619819, 11056.11551075912, 11090.722731931766, 448507.0, 505036.3740906507, 529612.0, 545845.0, 572697.0, 121837.0, 130677.0, 131676.3416182913, 134971.0, 140064.0, 1232.279116716713, 1244.1104610203747, 1286.8777466140702, 1291.8643542428097, 1293.734784369221, 20994.895025525384, 21460.570788584453, 21723.891380542995, 21958.0, 22024.339269821932, 56591.717041416174, 56917.43082569121, 57691.711541442026, 58193.4180658188, 58277.0, 73373.0, 73421.0, 73513.26486735065, 73588.52822943404, 74571.8814217798, 7681.539107653559, 8995.415298005608, 9000.729978100668, 9098.090190981115, 9115.134062264075, 12946.41741121633, 12994.415251313525, 13025.348732563523, 13682.726345473235, 14175.858241417454, 11639.941800290944, 11675.88324116748, 11858.52565897389, 11928.105392095455, 11932.522699091938, 10966.0, 11213.71965700864, 11369.43152842338, 11492.425378730863, 11570.710732231762, 10220.795584088128, 11997.380353652145, 12269.75460490803, 12295.44670489848, 12308.446119924803, 8265.132161122996, 9010.414323068979, 9083.863742043743, 9087.454752714859, 9108.180263776292, 10451.634192803216, 11750.70623234426, 11856.585019524275, 12092.818607721067, 12198.390080495763], "value": 10594.724029893689, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1436.1670231265864, 1486.52431222009, 1494.5889880282914, 1503.263400933543, 1520.6122438778114, 11670.0, 11910.34493102879, 12195.512179512809, 12202.206856554318, 12235.877641223586, 12069.939650301758, 12132.332721700302, 12237.898589126977, 12253.938730306358, 12394.442250098744, 2541.7331180226042, 2590.352411897026, 2593.222033389985, 2602.3754298968206, 2609.986950065247, 2535.632333311669, 2581.4578938422956, 2592.6499922510407, 2594.883230254643, 2597.1169802267227, 2541.885615147323, 2554.4891021795625, 2591.9611205831834, 2593.5850263957773, 2599.753023462768, 2514.170323793153, 2544.9745502544924, 2557.232830150966, 2571.4342844574353, 2573.4467089575737, 2532.4935012997475, 2544.643749875019, 2547.630593563923, 2560.4111054457394, 2577.36854470654, 12005.819912701316, 12034.037277017818, 12247.142700011003, 12261.93869030658, 12272.938635306855, 5669.8582535436535, 6717.6977036033495, 6922.2731613180895, 6958.165020197609, 7061.1173603299585, 4246.341817018367, 4431.8670439886855, 4467.463904331468, 4523.502414734409, 4531.3882625845745, 1050.6427814543015, 1275.2412314672754, 1281.9679508012375, 1289.8774616411426, 1351.5877657314516, 16412.261448234858, 16616.916915415466, 16923.06923119228, 17004.404845830337, 17035.488935331836, 5682.54539636828, 5767.567432442584, 5897.262842144646, 5938.762449502058, 5957.314908785458, 11896.0, 12107.515699372103, 12118.454669539891, 12127.272363658032, 12144.514219431301, 6734.0572319875255, 6734.326567343324, 6745.819481590702, 6750.257471678112, 6764.0530325754435, 6101.908471372933, 6136.938630613726, 6257.937420625826, 6292.182016337938, 6311.463525600284, 4337.804798784062, 4437.423134992495, 4468.419105516262, 4470.926977525371, 4474.284114541658, 1277.067740549404, 1296.293520031585, 1302.3813688497887, 1316.9539066132638, 1316.9868301317056, 800.9879851802227, 802.4823988527368, 830.7134038756619, 830.779843341515, 848.3128665780737, 4916.803327866845, 6217.658028808369, 6352.396522330371, 6378.553501254868, 6396.968015159894, 591.3170288317001, 594.0435898203888, 599.7780821096222, 613.6624856329001, 618.5793660311032, 3873.205992771475, 4457.665675074382, 4462.040661257845, 4484.663650226246, 4548.431446069241, 12158.452869620889, 15193.544193674208, 15318.617034574001, 15399.46101886451, 15410.306536205897, 14690.485832996008, 14990.250487475583, 15423.920325577326, 15433.45982890616, 15447.150406727738, 7671.309582137635, 8438.36712246584, 8778.912210877937, 8827.558622069, 8878.911210887938, 6732.596044237165, 7300.525465844703, 7340.669669864762, 7360.558366498028, 7414.406847452083, 12787.552435664707, 12816.0, 12824.422900969292, 12826.486940522647, 13052.54316098932, 13502.25737584403, 13942.0, 14015.859841401863, 14021.158730476203, 14370.497032604228, 9454.574544145393, 9620.37467564606, 9654.806903862023, 9670.56482458305, 9700.90299097, 11040.944795275973, 11144.944275278573, 11233.831492527455, 11262.211645184758, 11277.21059525858, 9272.814543708953, 9324.813503730024, 9419.152276295166, 9549.283803714889, 9575.712728618153, 9128.634854605742, 9212.539373031455, 9223.58493867791, 9250.814983700153, 9412.105849944244, 8284.917150828656, 8379.45533540318, 8494.53280069602, 8523.062463128927, 8593.570321483776, 5970.223870896755, 5975.133605627073, 6075.696215189135, 6076.331603523698, 6185.103160041859, 5547.528460080777, 6178.783742568989, 6193.659348735863, 6308.990561510136, 6353.809385718436], "value": 5440.38433587988, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.5480424025445, 382.43590703712044, 393.3607887183327, 419.4903192620965, 421.3027439587482, 6009.278886533622, 6145.907811382827, 6181.3818618138275, 6306.306306306314, 6331.366863313667, 6009.926575331504, 6131.9080213796715, 6139.447449729523, 6193.225846769154, 6214.782482613109, 4406.757628330441, 4678.789454474557, 4704.506026867181, 4711.9764401177945, 4732.266498692708, 3886.1839013807135, 4486.932696009562, 4595.264757638778, 4627.537246275362, 4709.387779588648, 3890.6498415142632, 4086.835251953194, 4343.435353404053, 4432.490263619677, 4657.6273898088075, 4457.799399027019, 4643.8374656886845, 4685.929711054337, 4695.765211739416, 4703.482616912137, 4022.4770779798732, 4120.835166593362, 4152.252594532983, 4157.521884983235, 4178.958210417887, 4798.448178459487, 5272.0, 5842.844863773557, 5987.880242395171, 5999.310079340845, 5085.745712714386, 5585.162225666182, 5599.272094627713, 5599.636023658449, 5609.971950140264, 6429.549931504796, 6444.420002199778, 6464.5798023128345, 6503.918746001282, 6525.184351956005, 3505.2288496530723, 3617.077645200457, 3774.2640185163905, 4311.1808756336195, 4334.718243314174, 5638.0, 6028.728707208143, 6876.484263680244, 6988.440924726061, 7049.612271325023, 3821.808909554511, 4332.545082766327, 4338.240807858612, 4340.479142502857, 4373.234683930299, 4260.126674031816, 4409.699138754076, 4415.139047885631, 4421.602055814992, 4436.977815110904, 4813.590844778163, 5398.217258497496, 5572.359178694421, 5636.802711905064, 5640.605157639008, 6454.419102280724, 6624.304448032887, 6667.43326817226, 6687.264400915896, 6707.463402927754, 1324.6092402741215, 1471.749802533572, 1577.290219401274, 1616.7817344658556, 1704.5735598183428, 898.9865152022725, 1139.743557699511, 1182.9408529573486, 1198.646399312205, 1205.511767734069, 5522.779088836482, 5865.35481097079, 5879.353271140171, 6198.194234749454, 6229.726149955233, 6878.69045892936, 6905.620190889549, 6916.6541672916155, 6920.550164239308, 6935.0, 6005.429484198994, 6213.844653883689, 6287.622742635365, 6298.1812364392335, 6344.175257216532, 3597.7661452005536, 3608.891733248007, 3609.9170248925216, 3613.9638603613625, 3623.655752703489, 5817.854553636108, 6099.841030204247, 6544.541882068304, 6999.265077166926, 7119.857602847914, 6226.159468471789, 6523.934760652332, 7007.789766306917, 7144.606556803529, 7146.106736657918, 5916.230889984275, 6592.373724496165, 6983.44132469391, 7009.12385951756, 7115.786526404113, 5731.423858438962, 5928.347881733092, 6564.964860893138, 6638.0374845648075, 6718.0, 6379.617222966637, 6424.618706978023, 6673.332666733482, 6768.56004359715, 6779.966100169468, 5404.459554044564, 6470.28826829039, 6946.930530694628, 7005.299470052954, 7119.501634885508, 4326.836581709145, 4327.761973091384, 4366.585174408426, 4369.759663218553, 4378.365137055172, 6227.657478838707, 6716.361945615159, 6839.384455399038, 6861.416779573592, 6864.141982252218, 5768.875069361434, 6283.088952102011, 6403.4877209824035, 6479.546431749733, 6565.409113179837, 6508.0, 6554.410103090745, 6575.835604109936, 6582.045603387578, 6605.471562275103, 6214.25428948529, 6368.936310637019, 6483.00808377556, 6497.772577959749, 6506.3818937200895, 5851.1515830205235, 6464.0, 6552.6068435894, 6559.6064236145985, 6563.638999855054, 5990.251218597676, 6027.547933904887, 6069.117169344526, 6235.937640623536, 6421.357864213542], "value": 4802.303131125479, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [760.874455714807, 790.1663744749291, 855.632078206371, 858.2061593026449, 912.9865848907712, 13987.51043713469, 15380.538583842503, 16813.915930420346, 17295.39466118686, 19119.426417207473, 18234.452966410994, 18311.17599708013, 18353.898766074046, 18470.353537626186, 18587.442376728686, 823.6664151018847, 1016.5019140621098, 1034.5086084110026, 1036.6216331039193, 1039.0544604409988, 1029.3360782295415, 1039.870016247969, 1041.281515754131, 1041.343953309414, 1043.4104730827096, 1097.780443911217, 1100.8073587122262, 1100.983485247718, 1101.399737143255, 1102.3826657072025, 945.8392073347547, 1093.3767752381145, 1097.1058587251443, 1098.5386137822134, 1105.6848798092585, 596.6956852005464, 782.1904329019437, 789.0176729971165, 791.3550456378033, 798.4490701416063, 16772.83227167725, 17111.31554737809, 17230.224639891236, 17307.3942412015, 17360.91319543407, 16059.598510037225, 17242.56893577658, 17629.0, 17837.910810445865, 17880.91059544707, 11233.606823761129, 13818.861811381857, 13893.0, 14037.71924561503, 14041.22773247471, 3521.4893840393, 3560.928781424357, 3567.108222944264, 3587.497750314958, 3588.784672919633, 129158.0, 129305.35347323203, 131262.0, 131573.0, 132804.33597832045, 13324.333783310989, 13500.594982150551, 13620.387082581308, 13774.65563360904, 13907.79138312926, 25305.74694253034, 25439.982400704135, 25573.360665983128, 26007.479850403266, 26027.08905188347, 7756.379489640815, 7839.45123841337, 7861.4103942204565, 7921.683132674629, 7931.405144614177, 3905.628965248297, 3980.1840622672285, 3984.5816189300285, 3988.9002774930277, 3990.8004599769897, 4303.784810759449, 4336.154449882306, 4401.471823381215, 4418.734875907456, 4426.490953540384, 884.8407286688458, 899.3344924755764, 905.6603773584906, 906.7370462565782, 908.1009800297658, 644.5262731892055, 656.2551504042946, 662.8541720821412, 668.6055227415751, 690.1752405874931, 9986.0, 10818.891811081945, 10998.560057597606, 11639.709007274885, 11640.126990475748, 647.3106141958799, 656.8587753632992, 658.9670516474157, 663.9701213445406, 688.6040526697169, 5341.577184047977, 5390.703511306916, 5481.0, 5533.833984980377, 5593.216949627055, 103749.48125259325, 104708.47645761723, 106910.0, 107325.0, 109901.90098099077, 82472.58763706143, 91711.1657766841, 92824.53587732019, 94941.5252923731, 96805.51597241969, 80181.59909200417, 80976.68768091509, 81085.37829243382, 82443.7633435487, 83178.58410707908, 9498.715038548855, 9779.853302200616, 11083.72290692739, 11416.200865939636, 12969.41637626328, 42402.78798605987, 42828.0, 42894.78552607217, 43292.91767705706, 44233.33649995317, 47134.528654714384, 50902.981940360245, 54029.3791186265, 55373.44626553844, 55643.44356556382, 16660.750088748922, 16961.321547137977, 16998.405055822986, 17039.40362087321, 17049.40327088546, 18080.72878906791, 18259.908700456413, 18313.175907083947, 18524.44426667202, 18605.162767675218, 14432.567022989328, 16896.577585559968, 17120.657586847945, 17264.482065538054, 17693.911530442267, 17041.23314450828, 17155.485335439957, 17245.65508689844, 17312.826871731624, 17426.0, 16463.670726585642, 17043.31826726917, 17084.572885677957, 17103.230354633823, 17152.142392880556, 11675.35785531804, 11792.76414471723, 12013.63959081229, 12039.097067720131, 12177.269363838199, 13042.4130914111, 13798.0, 13871.791923121364, 13896.235707035805, 13929.791053134413], "value": 8460.341390326454, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1547.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 116.0, 115.0, 115.0, 114.0, 115.0, 114.0, 115.0, 115.0, 114.0], "value": 113.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 109.0, 124.0, 181.0, 111.0, 115.0, 107.0, 110.0, 138.0, 108.0], "value": 124.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 207.0, 197.0, 224.0, 271.0, 247.0, 271.0, 267.0, 196.0, 198.0], "value": 227.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 59.0, 57.0, 88.0, 86.0, 82.0, 80.0, 79.0, 86.0, 61.0], "value": 75.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 60.0, 76.0, 78.0, 54.0, 56.0, 94.0, 93.0, 103.0, 114.0], "value": 81.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 91.0, 109.0, 76.0, 104.0, 124.0, 129.0, 88.0, 88.0, 89.0], "value": 98.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 95.0, 95.0, 96.0, 123.0, 105.0, 148.0, 137.0, 141.0], "value": 113.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 117.0, 102.0, 99.0, 89.0, 87.0, 92.0, 143.0, 91.0, 94.0], "value": 100.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 50.0, 71.0, 77.0, 50.0, 42.0, 71.0, 75.0, 74.0], "value": 61.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 56.0, 58.0, 61.0, 61.0, 59.0, 55.0, 57.0, 58.0, 59.0], "value": 58.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 83.0, 82.0, 79.0, 82.0, 81.0, 83.0, 82.0, 81.0], "value": 82.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 149.0, 147.0, 139.0, 138.0, 134.0, 154.0, 156.0, 198.0, 127.0], "value": 147.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 188.0, 183.0, 185.0, 180.0, 183.0, 180.0, 181.0, 185.0, 192.0], "value": 183.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 68.0, 67.0, 69.0, 93.0, 90.0, 96.0, 91.0, 69.0], "value": 78.3, "unit": "score"}]}]} 05:07:53 INFO - Return code: 0 05:07:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:07:53 ERROR - # TBPL SUCCESS # 05:07:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:07:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:07:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:07:53 INFO - 2017-04-26 05:07:53.714 screenresolution[1657:10622] starting screenresolution argv=screenresolution get 05:07:53 INFO - 2017-04-26 05:07:53.716 screenresolution[1657:10622] Display 0: 1600x1200x32@60 05:07:53 INFO - 2017-04-26 05:07:53.722 screenresolution[1658:10625] starting screenresolution argv=screenresolution list 05:07:53 INFO - Available Modes on Display 0 05:07:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:07:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:07:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:07:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:07:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:07:53 INFO - Intel Iris: 05:07:53 INFO - Chipset Model: Intel Iris 05:07:53 INFO - Type: GPU 05:07:53 INFO - Bus: Built-In 05:07:53 INFO - VRAM (Dynamic, Max): 1536 MB 05:07:53 INFO - Vendor: Intel (0x8086) 05:07:53 INFO - Device ID: 0x0a2e 05:07:53 INFO - Revision ID: 0x0009 05:07:53 INFO - Displays: 05:07:53 INFO - Display: 05:07:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:07:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:07:53 INFO - Display Serial Number: 1600x1200 60 05:07:53 INFO - Main Display: Yes 05:07:53 INFO - Mirror: Off 05:07:53 INFO - Online: Yes 05:07:53 INFO - Rotation: Supported 05:07:53 INFO - Return code: 0 05:07:53 INFO - Running post-action listener: _resource_record_post_action 05:07:53 INFO - [mozharness: 2017-04-26 12:07:53.957253Z] Finished run-tests step (success) 05:07:53 INFO - Running post-run listener: _resource_record_post_run 05:07:54 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 41801728; Write bytes: 515186688; Read time: 411; Write time: 1986 05:07:54 INFO - TinderboxPrint: CPU usage
54.1% 05:07:54 INFO - TinderboxPrint: I/O read bytes / time
41,801,728 / 411 05:07:54 INFO - TinderboxPrint: I/O write bytes / time
515,186,688 / 1,986 05:07:54 INFO - TinderboxPrint: CPU idle
2,194.5 (45.9%) 05:07:54 INFO - TinderboxPrint: CPU system
962.2 (20.1%) 05:07:54 INFO - TinderboxPrint: CPU user
1,629.0 (34.0%) 05:07:54 INFO - TinderboxPrint: Swap in / out
248,860,672 / 0 05:07:54 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 209721856; Write bytes: 213198848; Read time: 16157; Write time: 476 05:07:54 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 36743168; Write bytes: 300476416; Read time: 375; Write time: 1503 05:07:54 INFO - Running post-run listener: _upload_blobber_files 05:07:54 INFO - Blob upload gear active. 05:07:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:07:54 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:07:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:07:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:07:54 INFO - (blobuploader) - INFO - Open directory for files ... 05:07:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:07:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:07:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:07:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:07:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:07:57 INFO - (blobuploader) - INFO - Done attempting. 05:07:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:07:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:07:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:07:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:07:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:07:58 INFO - (blobuploader) - INFO - Done attempting. 05:07:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:07:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:07:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:07:59 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:07:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:07:59 INFO - (blobuploader) - INFO - Done attempting. 05:07:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:07:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:07:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:08:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:08:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:08:01 INFO - (blobuploader) - INFO - Done attempting. 05:08:01 INFO - (blobuploader) - INFO - Iteration through files over. 05:08:01 INFO - Return code: 0 05:08:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:08:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:08:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06ac1e680a2f25ce68a6d4c1b1ddceb81e1f61b8622a1cc9599072612c473b67eaad7991835468b0a0c9e4d3269e37c9b0d62b2535717a02e5f85b9003bc3196", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5b86fb8ed32b94dc63cba42a5cb927d801d0887dd680cc9fbd4177f32c53e845690f54e81efbb5ae055e126b3edd50f4640c0979c3b80fa9de597d1feabdc2f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0707a8ef55f9f1558f6c258c023f14b296174c2777d08ad28d99e49adcbd37e0654be5b455edf2bc99cb0d78d1edc1d3f614edb40fff5022cd1d96f9f07ac279", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62c40114f410bd7f8eec9a833d9b640746f3009d0af61ec1b170f9214b18ad85920e74439dd0dce534c4360e4c1776dd798507cdc5cbb3242d8cf721c8b72d7f"} 05:08:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:08:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:08:01 INFO - Contents: 05:08:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06ac1e680a2f25ce68a6d4c1b1ddceb81e1f61b8622a1cc9599072612c473b67eaad7991835468b0a0c9e4d3269e37c9b0d62b2535717a02e5f85b9003bc3196", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5b86fb8ed32b94dc63cba42a5cb927d801d0887dd680cc9fbd4177f32c53e845690f54e81efbb5ae055e126b3edd50f4640c0979c3b80fa9de597d1feabdc2f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0707a8ef55f9f1558f6c258c023f14b296174c2777d08ad28d99e49adcbd37e0654be5b455edf2bc99cb0d78d1edc1d3f614edb40fff5022cd1d96f9f07ac279", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62c40114f410bd7f8eec9a833d9b640746f3009d0af61ec1b170f9214b18ad85920e74439dd0dce534c4360e4c1776dd798507cdc5cbb3242d8cf721c8b72d7f"} 05:08:01 INFO - Running post-run listener: copy_logs_to_upload_dir 05:08:01 INFO - Copying logs to upload dir... 05:08:01 INFO - mkdir: /builds/slave/test/build/upload/logs 05:08:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.198211 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-04-26 05:08:01.712925) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-26 05:08:01.718684) ========= 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.rrV5EUZgo9/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.dUFRjRd7uW/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06ac1e680a2f25ce68a6d4c1b1ddceb81e1f61b8622a1cc9599072612c473b67eaad7991835468b0a0c9e4d3269e37c9b0d62b2535717a02e5f85b9003bc3196", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5b86fb8ed32b94dc63cba42a5cb927d801d0887dd680cc9fbd4177f32c53e845690f54e81efbb5ae055e126b3edd50f4640c0979c3b80fa9de597d1feabdc2f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0707a8ef55f9f1558f6c258c023f14b296174c2777d08ad28d99e49adcbd37e0654be5b455edf2bc99cb0d78d1edc1d3f614edb40fff5022cd1d96f9f07ac279", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62c40114f410bd7f8eec9a833d9b640746f3009d0af61ec1b170f9214b18ad85920e74439dd0dce534c4360e4c1776dd798507cdc5cbb3242d8cf721c8b72d7f"} build_url:https://queue.taskcluster.net/v1/task/YMSY83mzSwCqf7vnFu3CBA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011130 build_url: 'https://queue.taskcluster.net/v1/task/YMSY83mzSwCqf7vnFu3CBA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06ac1e680a2f25ce68a6d4c1b1ddceb81e1f61b8622a1cc9599072612c473b67eaad7991835468b0a0c9e4d3269e37c9b0d62b2535717a02e5f85b9003bc3196", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5b86fb8ed32b94dc63cba42a5cb927d801d0887dd680cc9fbd4177f32c53e845690f54e81efbb5ae055e126b3edd50f4640c0979c3b80fa9de597d1feabdc2f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0707a8ef55f9f1558f6c258c023f14b296174c2777d08ad28d99e49adcbd37e0654be5b455edf2bc99cb0d78d1edc1d3f614edb40fff5022cd1d96f9f07ac279", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62c40114f410bd7f8eec9a833d9b640746f3009d0af61ec1b170f9214b18ad85920e74439dd0dce534c4360e4c1776dd798507cdc5cbb3242d8cf721c8b72d7f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-26 05:08:01.753806) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-26 05:08:01.754130) ========= 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.rrV5EUZgo9/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.dUFRjRd7uW/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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.005210 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-26 05:08:01.803282) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-26 05:08:01.803630) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-26 05:08:01.804023) ========= ========= Total master_lag: 0.18 =========