builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0097 starttime: 1481735476.89 results: success (0) buildid: 20161214073701 builduid: 2edb72ab3f134a47b56be4930bddff8e revision: 126c324672eaf5673a51a37ed229b2003ecd6b4f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-14 09:11:16.888402) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-14 09:11:16.888830) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-14 09:11:16.889117) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-14 09:11:16.914226) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 09:11:16.914611) ========= 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.djoXUa4j1J/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.hJSTqEwCUS/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-14 09:11:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.43M=0.001s 2016-12-14 09:11:17 (8.43 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.143644 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 09:11:17.081687) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 09:11:17.082014) ========= 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.djoXUa4j1J/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.hJSTqEwCUS/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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.019985 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 09:11:17.131070) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-14 09:11:17.131416) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 126c324672eaf5673a51a37ed229b2003ecd6b4f --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 126c324672eaf5673a51a37ed229b2003ecd6b4f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.djoXUa4j1J/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.hJSTqEwCUS/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-14 09:11:17,204 truncating revision to first 12 chars 2016-12-14 09:11:17,204 Setting DEBUG logging. 2016-12-14 09:11:17,204 attempt 1/10 2016-12-14 09:11:17,204 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/126c324672ea?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-14 09:11:18,508 unpacking tar archive at: mozilla-central-126c324672ea/testing/mozharness/ program finished with exit code 0 elapsedTime=1.563551 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-14 09:11:18.718550) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-14 09:11:18.718946) ========= script_repo_revision: 126c324672eaf5673a51a37ed229b2003ecd6b4f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-14 09:11:18.719346) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-14 09:11:18.719613) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-14 09:11:18.739793) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-12-14 09:11:18.740204) ========= /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.djoXUa4j1J/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.hJSTqEwCUS/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 09:11:18 INFO - MultiFileLogger online at 20161214 09:11:18 in /builds/slave/test 09:11:18 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 09:11:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:11:18 INFO - {'append_to_log': False, 09:11:18 INFO - 'base_work_dir': '/builds/slave/test', 09:11:18 INFO - 'blob_upload_branch': 'Firefox', 09:11:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:11:18 INFO - 'branch': 'Firefox', 09:11:18 INFO - 'buildbot_json_path': 'buildprops.json', 09:11:18 INFO - 'config_files': ('talos/mac_config.py',), 09:11:18 INFO - 'default_actions': ('clobber', 09:11:18 INFO - 'read-buildbot-config', 09:11:18 INFO - 'download-and-extract', 09:11:18 INFO - 'populate-webroot', 09:11:18 INFO - 'create-virtualenv', 09:11:18 INFO - 'install', 09:11:18 INFO - 'run-tests'), 09:11:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:11:18 INFO - 'download_minidump_stackwalk': True, 09:11:18 INFO - 'download_symbols': 'ondemand', 09:11:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:11:18 INFO - 'tooltool.py': '/tools/tooltool.py', 09:11:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:11:18 INFO - '/tools/misc-python/virtualenv.py')}, 09:11:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:11:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:11:18 INFO - 'installer_path': 'installer.exe', 09:11:18 INFO - 'log_level': 'info', 09:11:18 INFO - 'log_name': 'talos', 09:11:18 INFO - 'log_to_console': True, 09:11:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:11:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:11:18 INFO - 'opt_config_files': (), 09:11:18 INFO - 'pip_index': False, 09:11:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:11:18 INFO - 'cmd': ('bash', 09:11:18 INFO - '-c', 09:11:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:11:18 INFO - 'enabled': True, 09:11:18 INFO - 'halt_on_failure': False, 09:11:18 INFO - 'name': 'check_screen_resolution'},), 09:11:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:11:18 INFO - 'cmd': ('bash', 09:11:18 INFO - '-c', 09:11:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:11:18 INFO - 'enabled': True, 09:11:18 INFO - 'halt_on_failure': False, 09:11:18 INFO - 'name': 'check_screen_resolution'},), 09:11:18 INFO - 'run_cmd_checks_enabled': True, 09:11:18 INFO - 'sps_profile': False, 09:11:18 INFO - 'sps_profile_interval': 0, 09:11:18 INFO - 'suite': 'dromaeojs-e10s', 09:11:18 INFO - 'system_bits': '32', 09:11:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:11:18 INFO - 'title': 't-yosemite-r7-0097', 09:11:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:11:18 INFO - 'use_talos_json': True, 09:11:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:11:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:11:18 INFO - 'work_dir': 'build'} 09:11:18 INFO - [mozharness: 2016-12-14 17:11:18.939308Z] Running clobber step. 09:11:18 INFO - Running pre-action listener: _resource_record_pre_action 09:11:18 INFO - Running main action method: clobber 09:11:18 INFO - rmtree: /builds/slave/test/build 09:11:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:11:20 INFO - Running post-action listener: _resource_record_post_action 09:11:20 INFO - [mozharness: 2016-12-14 17:11:20.395034Z] Finished clobber step (success) 09:11:20 INFO - [mozharness: 2016-12-14 17:11:20.395205Z] Running read-buildbot-config step. 09:11:20 INFO - Running pre-action listener: _resource_record_pre_action 09:11:20 INFO - Running main action method: read_buildbot_config 09:11:20 INFO - Using buildbot properties: 09:11:20 INFO - { 09:11:20 INFO - "project": "", 09:11:20 INFO - "product": "firefox", 09:11:20 INFO - "script_repo_revision": "production", 09:11:20 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:11:20 INFO - "repository": "", 09:11:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:11:20 INFO - "buildid": "20161214073701", 09:11:20 INFO - "pgo_build": "False", 09:11:20 INFO - "basedir": "/builds/slave/test", 09:11:20 INFO - "buildnumber": 16, 09:11:20 INFO - "slavename": "t-yosemite-r7-0097", 09:11:20 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 09:11:20 INFO - "platform": "yosemite_r7", 09:11:20 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:11:20 INFO - "branch": "mozilla-central", 09:11:20 INFO - "repo_path": "mozilla-central", 09:11:20 INFO - "revision": "126c324672eaf5673a51a37ed229b2003ecd6b4f", 09:11:20 INFO - "stage_platform": "macosx64", 09:11:20 INFO - "builduid": "2edb72ab3f134a47b56be4930bddff8e", 09:11:20 INFO - "slavebuilddir": "test" 09:11:20 INFO - } 09:11:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/DYYRbLFITDac5tEoxX4o-g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 09:11:20 INFO - Running post-action listener: _resource_record_post_action 09:11:20 INFO - [mozharness: 2016-12-14 17:11:20.398751Z] Finished read-buildbot-config step (success) 09:11:20 INFO - [mozharness: 2016-12-14 17:11:20.398895Z] Running download-and-extract step. 09:11:20 INFO - Running pre-action listener: _resource_record_pre_action 09:11:20 INFO - Running main action method: download_and_extract 09:11:20 INFO - mkdir: /builds/slave/test/build/tests 09:11: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')]} 09:11:20 INFO - trying https://queue.taskcluster.net/v1/task/DYYRbLFITDac5tEoxX4o-g/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 09:11:20 INFO - Downloading https://queue.taskcluster.net/v1/task/DYYRbLFITDac5tEoxX4o-g/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 09:11:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DYYRbLFITDac5tEoxX4o-g/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 09:11:22 INFO - Downloaded 1199 bytes. 09:11:22 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 09:11:22 INFO - Using the following test package requirements: 09:11:22 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 09:11:22 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:11:22 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 09:11:22 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:11:22 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 09:11:22 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:11:22 INFO - u'jsshell-mac.zip'], 09:11:22 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:11:22 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 09:11:22 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 09:11:22 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:11:22 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 09:11:22 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:11:22 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 09:11:22 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:11:22 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 09:11:22 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:11:22 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 09:11:22 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:11:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DYYRbLFITDac5tEoxX4o-g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 09:11:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DYYRbLFITDac5tEoxX4o-g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:11:22 INFO - Fetch https://queue.taskcluster.net/v1/task/DYYRbLFITDac5tEoxX4o-g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 09:11:22 INFO - Expected file size: 16276140 09:11:22 INFO - Obtained file size: 16276140 09:11:29 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:11:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DYYRbLFITDac5tEoxX4o-g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 09:11:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DYYRbLFITDac5tEoxX4o-g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:11:29 INFO - Fetch https://queue.taskcluster.net/v1/task/DYYRbLFITDac5tEoxX4o-g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 09:11:30 INFO - Expected file size: 16276140 09:11:30 INFO - Obtained file size: 16276140 09:11:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DYYRbLFITDac5tEoxX4o-g/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 09:11:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DYYRbLFITDac5tEoxX4o-g/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:11:36 INFO - Fetch https://queue.taskcluster.net/v1/task/DYYRbLFITDac5tEoxX4o-g/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 09:11:37 INFO - Expected file size: 14409734 09:11:37 INFO - Obtained file size: 14409734 09:11:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:38 INFO - trying https://queue.taskcluster.net/v1/task/DYYRbLFITDac5tEoxX4o-g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:11:38 INFO - Downloading https://queue.taskcluster.net/v1/task/DYYRbLFITDac5tEoxX4o-g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 09:11:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DYYRbLFITDac5tEoxX4o-g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 09:11:40 INFO - Downloaded 69671802 bytes. 09:11:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DYYRbLFITDac5tEoxX4o-g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:11:40 INFO - mkdir: /builds/slave/test/properties 09:11:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:11:40 INFO - Writing to file /builds/slave/test/properties/build_url 09:11:40 INFO - Contents: 09:11:40 INFO - build_url:https://queue.taskcluster.net/v1/task/DYYRbLFITDac5tEoxX4o-g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:11:40 INFO - Running post-action listener: _resource_record_post_action 09:11:40 INFO - Running post-action listener: set_extra_try_arguments 09:11:40 INFO - [mozharness: 2016-12-14 17:11:40.929276Z] Finished download-and-extract step (success) 09:11:40 INFO - [mozharness: 2016-12-14 17:11:40.929439Z] Running populate-webroot step. 09:11:40 INFO - Running pre-action listener: _resource_record_pre_action 09:11:40 INFO - Running main action method: populate_webroot 09:11:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:11:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:11:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:11:40 INFO - '--tppagecycles', 09:11:40 INFO - '7'], 09:11:40 INFO - 'tests': ['tsvgm']}}, 09:11:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:11:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:11:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:11:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:11:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:11:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:11:40 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:11:40 INFO - 'talos_options': ['--disable-e10s'], 09:11:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:11:40 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:11:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:11:40 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:11:40 INFO - 'talos_options': ['--disable-e10s'], 09:11:40 INFO - 'tests': ['damp', 'tps']}, 09:11:40 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:11:40 INFO - 'tests': ['damp', 'tps']}, 09:11:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:11:40 INFO - 'tests': ['dromaeo_dom']}, 09:11:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:11:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:11:40 INFO - 'tests': ['basic_compositor_video']}, 09:11:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:11:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:11:40 INFO - 'tests': ['a11yr', 09:11:40 INFO - 'ts_paint', 09:11:40 INFO - 'tpaint', 09:11:40 INFO - 'sessionrestore', 09:11:40 INFO - 'sessionrestore_no_auto_restore', 09:11:40 INFO - 'tabpaint']}, 09:11:40 INFO - 'other-e10s': {'tests': ['a11yr', 09:11:40 INFO - 'ts_paint', 09:11:40 INFO - 'tpaint', 09:11:40 INFO - 'sessionrestore', 09:11:40 INFO - 'sessionrestore_no_auto_restore', 09:11:40 INFO - 'tabpaint']}, 09:11:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:11:40 INFO - 'ts_paint', 09:11:40 INFO - 'tpaint', 09:11:40 INFO - 'sessionrestore', 09:11:40 INFO - 'sessionrestore_no_auto_restore', 09:11:40 INFO - 'tabpaint']}, 09:11:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:11:40 INFO - 'ts_paint', 09:11:40 INFO - 'tpaint', 09:11:40 INFO - 'sessionrestore', 09:11:40 INFO - 'sessionrestore_no_auto_restore', 09:11:40 INFO - 'tabpaint']}, 09:11:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:11:40 INFO - 'tests': ['a11yr', 09:11:40 INFO - 'ts_paint', 09:11:40 INFO - 'tpaint', 09:11:40 INFO - 'sessionrestore', 09:11:40 INFO - 'sessionrestore_no_auto_restore', 09:11:40 INFO - 'tabpaint']}, 09:11:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:11:40 INFO - 'tests': ['a11yr', 09:11:40 INFO - 'ts_paint', 09:11:40 INFO - 'tpaint', 09:11:40 INFO - 'sessionrestore', 09:11:40 INFO - 'sessionrestore_no_auto_restore', 09:11:40 INFO - 'tabpaint']}, 09:11:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:11:40 INFO - 'tests': ['tsvgx', 09:11:40 INFO - 'tsvgr_opacity', 09:11:40 INFO - 'tart', 09:11:40 INFO - 'tscrollx', 09:11:40 INFO - 'cart', 09:11:40 INFO - 'tsvg_static']}, 09:11:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:11:40 INFO - 'tsvgr_opacity', 09:11:40 INFO - 'tart', 09:11:40 INFO - 'tscrollx', 09:11:40 INFO - 'cart', 09:11:40 INFO - 'tsvg_static']}, 09:11:40 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:11:40 INFO - 'talos_options': ['--disable-e10s'], 09:11:40 INFO - 'tests': ['tp5o']}, 09:11:40 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:11:40 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:11:40 INFO - 'talos_options': ['--disable-e10s', 09:11:40 INFO - '--xperf_path', 09:11:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:11:40 INFO - 'tests': ['tp5n']}, 09:11:40 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:11:40 INFO - 'talos_options': ['--xperf_path', 09:11:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:11:40 INFO - 'tests': ['tp5n']}}, 09:11:40 INFO - 'talos.zip': {'path': '', 09:11:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:11:40 INFO - Running post-action listener: _resource_record_post_action 09:11:40 INFO - [mozharness: 2016-12-14 17:11:40.948200Z] Finished populate-webroot step (success) 09:11:40 INFO - [mozharness: 2016-12-14 17:11:40.948326Z] Running create-virtualenv step. 09:11:40 INFO - Running pre-action listener: _resource_record_pre_action 09:11:40 INFO - Running main action method: create_virtualenv 09:11:40 INFO - Creating virtualenv /builds/slave/test/build/venv 09:11:40 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 09:11:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:11:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:11:41 INFO - Using real prefix '/tools/python27' 09:11:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:11:41 INFO - Installing distribute.............................................................................................................................................................................................done. 09:11:44 INFO - Installing pip.................done. 09:11:44 INFO - Return code: 0 09:11:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:11:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:11:45 INFO - Reading from file tmpfile_stdout 09:11:45 INFO - Output received: 09:11:45 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) 09:11:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:11:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1044a23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104418570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6f8c76eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042b7d30>, '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 0x10411edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1042afb20>, '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.djoXUa4j1J/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.hJSTqEwCUS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 09:11:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:11:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:11:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.djoXUa4j1J/Render', 09:11:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:11:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:11:45 INFO - 'HOME': '/Users/cltbld', 09:11:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:11:45 INFO - 'LOGNAME': 'cltbld', 09:11:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:45 INFO - 'MOZ_NO_REMOTE': '1', 09:11:45 INFO - 'NO_EM_RESTART': '1', 09:11:45 INFO - 'PAGER': '/bin/cat', 09:11:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:11:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:45 INFO - 'PWD': '/builds/slave/test', 09:11:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:11:45 INFO - 'SHELL': '/bin/bash', 09:11:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hJSTqEwCUS/Listeners', 09:11:45 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 09:11:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:11:45 INFO - 'USER': 'cltbld', 09:11:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:11:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:11:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:45 INFO - 'XPC_FLAGS': '0x0', 09:11:45 INFO - 'XPC_SERVICE_NAME': '0', 09:11:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:11:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:45 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 09:11:45 INFO - Cleaning up... 09:11:45 INFO - Return code: 0 09:11:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:11:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1044a23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104418570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6f8c76eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042b7d30>, '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 0x10411edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1042afb20>, '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.djoXUa4j1J/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.hJSTqEwCUS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 09:11:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:11:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:11:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.djoXUa4j1J/Render', 09:11:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:11:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:11:45 INFO - 'HOME': '/Users/cltbld', 09:11:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:11:45 INFO - 'LOGNAME': 'cltbld', 09:11:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:45 INFO - 'MOZ_NO_REMOTE': '1', 09:11:45 INFO - 'NO_EM_RESTART': '1', 09:11:45 INFO - 'PAGER': '/bin/cat', 09:11:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:11:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:45 INFO - 'PWD': '/builds/slave/test', 09:11:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:11:45 INFO - 'SHELL': '/bin/bash', 09:11:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hJSTqEwCUS/Listeners', 09:11:45 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 09:11:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:11:45 INFO - 'USER': 'cltbld', 09:11:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:11:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:11:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:45 INFO - 'XPC_FLAGS': '0x0', 09:11:45 INFO - 'XPC_SERVICE_NAME': '0', 09:11:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:11:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:45 INFO - Downloading/unpacking psutil>=3.1.1 09:11:45 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 09:11:45 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 09:11:45 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 09:11:45 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 09:11:45 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 09:11:45 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 09:11:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:11:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:11:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:11:49 INFO - Installing collected packages: psutil 09:11:49 INFO - Running setup.py install for psutil 09:11:49 INFO - building 'psutil._psutil_osx' extension 09:11:49 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 09:11:49 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 09:11:49 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 09:11:49 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 09:11:49 INFO - building 'psutil._psutil_posix' extension 09:11:49 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 09:11:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:11:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:11:49 INFO - ^ 09:11:49 INFO - 1 warning generated. 09:11:49 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 09:11:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:11:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:11:50 INFO - Successfully installed psutil 09:11:50 INFO - Cleaning up... 09:11:50 INFO - Return code: 0 09:11:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:11: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')]} 09:11:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11: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')]} 09:11:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:50 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 0x1044a23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104418570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6f8c76eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042b7d30>, '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 0x10411edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1042afb20>, '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.djoXUa4j1J/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.hJSTqEwCUS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 09:11:50 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 09:11:50 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 09:11:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.djoXUa4j1J/Render', 09:11:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:11:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:11:50 INFO - 'HOME': '/Users/cltbld', 09:11:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:11:50 INFO - 'LOGNAME': 'cltbld', 09:11:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:50 INFO - 'MOZ_NO_REMOTE': '1', 09:11:50 INFO - 'NO_EM_RESTART': '1', 09:11:50 INFO - 'PAGER': '/bin/cat', 09:11:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:11:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:50 INFO - 'PWD': '/builds/slave/test', 09:11:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:11:50 INFO - 'SHELL': '/bin/bash', 09:11:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hJSTqEwCUS/Listeners', 09:11:50 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 09:11:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:11:50 INFO - 'USER': 'cltbld', 09:11:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:11:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:11:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:50 INFO - 'XPC_FLAGS': '0x0', 09:11:50 INFO - 'XPC_SERVICE_NAME': '0', 09:11:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:11:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:11:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:11:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:11:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:11:53 INFO - Installing collected packages: mozsystemmonitor 09:11:53 INFO - Running setup.py install for mozsystemmonitor 09:11:53 INFO - Successfully installed mozsystemmonitor 09:11:53 INFO - Cleaning up... 09:11:53 INFO - Return code: 0 09:11:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:11:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:53 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 0x1044a23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104418570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6f8c76eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042b7d30>, '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 0x10411edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1042afb20>, '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.djoXUa4j1J/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.hJSTqEwCUS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 09:11:53 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 09:11:53 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 09:11:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.djoXUa4j1J/Render', 09:11:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:11:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:11:53 INFO - 'HOME': '/Users/cltbld', 09:11:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:11:53 INFO - 'LOGNAME': 'cltbld', 09:11:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:53 INFO - 'MOZ_NO_REMOTE': '1', 09:11:53 INFO - 'NO_EM_RESTART': '1', 09:11:53 INFO - 'PAGER': '/bin/cat', 09:11:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:11:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:53 INFO - 'PWD': '/builds/slave/test', 09:11:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:11:53 INFO - 'SHELL': '/bin/bash', 09:11:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hJSTqEwCUS/Listeners', 09:11:53 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 09:11:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:11:53 INFO - 'USER': 'cltbld', 09:11:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:11:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:11:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:53 INFO - 'XPC_FLAGS': '0x0', 09:11:53 INFO - 'XPC_SERVICE_NAME': '0', 09:11:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:11:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:53 INFO - Downloading/unpacking jsonschema==2.5.1 09:11:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:11:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:11:56 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 09:11:56 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 09:11:56 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 09:11:56 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 09:11:56 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 09:11:56 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 09:11:56 INFO - Downloading functools32-3.2.3-2.tar.gz 09:11:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:11:56 INFO - warning: no files found matching '*.txt' 09:11:56 INFO - no previously-included directories found matching 'build' 09:11:56 INFO - no previously-included directories found matching 'dist' 09:11:56 INFO - no previously-included directories found matching '.git*' 09:11:56 INFO - Installing collected packages: jsonschema, functools32 09:11:56 INFO - Running setup.py install for functools32 09:11:56 INFO - warning: no files found matching '*.txt' 09:11:56 INFO - no previously-included directories found matching 'build' 09:11:56 INFO - no previously-included directories found matching 'dist' 09:11:56 INFO - no previously-included directories found matching '.git*' 09:11:56 INFO - Successfully installed jsonschema functools32 09:11:56 INFO - Cleaning up... 09:11:56 INFO - Return code: 0 09:11:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:11: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')]} 09:11:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11: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')]} 09:11:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11: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', '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 0x1044a23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104418570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6f8c76eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042b7d30>, '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 0x10411edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1042afb20>, '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.djoXUa4j1J/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.hJSTqEwCUS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 09:11: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:11: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 functools32==3.2.3-2 09:11:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.djoXUa4j1J/Render', 09:11:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:11:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:11:56 INFO - 'HOME': '/Users/cltbld', 09:11:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:11:56 INFO - 'LOGNAME': 'cltbld', 09:11:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:56 INFO - 'MOZ_NO_REMOTE': '1', 09:11:56 INFO - 'NO_EM_RESTART': '1', 09:11:56 INFO - 'PAGER': '/bin/cat', 09:11:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:11:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:56 INFO - 'PWD': '/builds/slave/test', 09:11:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:11:56 INFO - 'SHELL': '/bin/bash', 09:11:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hJSTqEwCUS/Listeners', 09:11:56 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 09:11:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:11:56 INFO - 'USER': 'cltbld', 09:11:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:11:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:11:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:56 INFO - 'XPC_FLAGS': '0x0', 09:11:56 INFO - 'XPC_SERVICE_NAME': '0', 09:11:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:11:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:11:57 INFO - Cleaning up... 09:11:57 INFO - Return code: 0 09:11:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:11:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1044a23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104418570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6f8c76eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042b7d30>, '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 0x10411edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1042afb20>, '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.djoXUa4j1J/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.hJSTqEwCUS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 09:11:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:11:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:11:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.djoXUa4j1J/Render', 09:11:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:11:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:11:57 INFO - 'HOME': '/Users/cltbld', 09:11:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:11:57 INFO - 'LOGNAME': 'cltbld', 09:11:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:57 INFO - 'MOZ_NO_REMOTE': '1', 09:11:57 INFO - 'NO_EM_RESTART': '1', 09:11:57 INFO - 'PAGER': '/bin/cat', 09:11:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:11:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:57 INFO - 'PWD': '/builds/slave/test', 09:11:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:11:57 INFO - 'SHELL': '/bin/bash', 09:11:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hJSTqEwCUS/Listeners', 09:11:57 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 09:11:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:11:57 INFO - 'USER': 'cltbld', 09:11:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:11:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:11:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:57 INFO - 'XPC_FLAGS': '0x0', 09:11:57 INFO - 'XPC_SERVICE_NAME': '0', 09:11:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:11:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:57 INFO - Downloading/unpacking blobuploader==1.2.4 09:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:00 INFO - Downloading blobuploader-1.2.4.tar.gz 09:12:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:12:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:12:00 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 09:12:00 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 09:12:00 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 09:12:00 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 09:12:00 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 09:12:00 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 09:12:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:12:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:12:00 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 09:12:00 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 09:12:00 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 09:12:00 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 09:12:00 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 09:12:00 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 09:12:01 INFO - Downloading docopt-0.6.1.tar.gz 09:12:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:12:01 INFO - Installing collected packages: blobuploader, requests, docopt 09:12:01 INFO - Running setup.py install for blobuploader 09:12:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:12:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:12:01 INFO - Running setup.py install for requests 09:12:01 INFO - Running setup.py install for docopt 09:12:02 INFO - Successfully installed blobuploader requests docopt 09:12:02 INFO - Cleaning up... 09:12:02 INFO - Return code: 0 09:12:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:12:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:12:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:12:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:02 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 0x1044a23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104418570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6f8c76eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042b7d30>, '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 0x10411edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1042afb20>, '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.djoXUa4j1J/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.hJSTqEwCUS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 09:12:02 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 09:12:02 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 09:12:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.djoXUa4j1J/Render', 09:12:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:12:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:12:02 INFO - 'HOME': '/Users/cltbld', 09:12:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:12:02 INFO - 'LOGNAME': 'cltbld', 09:12:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:02 INFO - 'MOZ_NO_REMOTE': '1', 09:12:02 INFO - 'NO_EM_RESTART': '1', 09:12:02 INFO - 'PAGER': '/bin/cat', 09:12:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:12:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:02 INFO - 'PWD': '/builds/slave/test', 09:12:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:12:02 INFO - 'SHELL': '/bin/bash', 09:12:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hJSTqEwCUS/Listeners', 09:12:02 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 09:12:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:12:02 INFO - 'USER': 'cltbld', 09:12:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:12:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:12:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:02 INFO - 'XPC_FLAGS': '0x0', 09:12:02 INFO - 'XPC_SERVICE_NAME': '0', 09:12:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:12:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:12:02 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-W6uboa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:12:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:12:02 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-TJ0w3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:12:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:12:02 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-ANhTZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:12:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:12:02 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-Jn316a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:12:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:12:02 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-UayHIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:12:03 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-QdaxkB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:12:03 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-vDi_r1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:12:03 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-iQrA53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:12:03 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-vlbqh8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:12:03 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-7yWodv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:12:03 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-qqF_7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:12:03 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-YSzYb3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:12:03 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-dM6yZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:12:03 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-O2xnWJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:12:03 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-Slm1Aa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:12:04 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-gLPW5J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:12:04 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-6ACCoH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:12:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:12:04 INFO - Running setup.py install for manifestparser 09:12:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:12:04 INFO - Running setup.py install for mozcrash 09:12:04 INFO - Running setup.py install for mozdebug 09:12:04 INFO - Running setup.py install for mozdevice 09:12:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:12:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:12:04 INFO - Running setup.py install for mozfile 09:12:05 INFO - Running setup.py install for mozhttpd 09:12:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:12:05 INFO - Running setup.py install for mozinfo 09:12:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:12:05 INFO - Running setup.py install for mozInstall 09:12:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:12:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:12:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:12:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:12:05 INFO - Running setup.py install for mozleak 09:12:05 INFO - Running setup.py install for mozlog 09:12:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:12:05 INFO - Running setup.py install for moznetwork 09:12:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:12:05 INFO - Running setup.py install for mozprocess 09:12:06 INFO - Running setup.py install for mozprofile 09:12:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:12:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:12:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:12:06 INFO - Running setup.py install for mozrunner 09:12:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:12:06 INFO - Running setup.py install for mozscreenshot 09:12:06 INFO - Running setup.py install for moztest 09:12:06 INFO - Running setup.py install for mozversion 09:12:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:12:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:12:06 INFO - Cleaning up... 09:12:06 INFO - Return code: 0 09:12:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:12:06 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')]} 09:12:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:06 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')]} 09:12:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:06 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 0x1044a23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104418570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6f8c76eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042b7d30>, '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 0x10411edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1042afb20>, '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.djoXUa4j1J/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.hJSTqEwCUS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 09:12:06 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 09:12:06 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 09:12:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.djoXUa4j1J/Render', 09:12:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:12:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:12:06 INFO - 'HOME': '/Users/cltbld', 09:12:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:12:06 INFO - 'LOGNAME': 'cltbld', 09:12:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:06 INFO - 'MOZ_NO_REMOTE': '1', 09:12:06 INFO - 'NO_EM_RESTART': '1', 09:12:06 INFO - 'PAGER': '/bin/cat', 09:12:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:12:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:06 INFO - 'PWD': '/builds/slave/test', 09:12:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:12:06 INFO - 'SHELL': '/bin/bash', 09:12:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hJSTqEwCUS/Listeners', 09:12:06 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 09:12:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:12:06 INFO - 'USER': 'cltbld', 09:12:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:12:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:12:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:06 INFO - 'XPC_FLAGS': '0x0', 09:12:06 INFO - 'XPC_SERVICE_NAME': '0', 09:12:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:12:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:12:07 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-TPoi3w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:12:07 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)) 09:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:12:07 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-QnPuX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:12:07 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)) 09:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:12:07 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-CoWjQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:12:07 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)) 09:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:12:07 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-q25PI4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:12:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 09:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:12:07 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-iDMR0z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:12:07 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)) 09:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:12:07 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-kgA18r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:12:07 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)) 09:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:12:07 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-wKx883-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:12:07 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)) 09:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:12:07 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-Pj2umJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:12:07 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)) 09:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:12:07 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-qOiZyF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:12:08 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)) 09:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:12:08 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-nSmBzT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:12:08 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)) 09:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:12:08 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-ucf480-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:12:08 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)) 09:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:12:08 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-EvnRWR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:12:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:12:08 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-x9tRy3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:12:08 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)) 09:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:12:08 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-EN9foS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:12:08 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)) 09:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:12:08 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-dQgKG3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:12:08 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)) 09:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:12:08 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-0GQcmN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:12:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:12:08 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-ceWk_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:12:08 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)) 09:12:08 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)) 09:12:08 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)) 09:12:08 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)) 09:12:08 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:12:08 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:12:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:11 INFO - Downloading blessings-1.6.tar.gz 09:12:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:12:11 INFO - Installing collected packages: blessings 09:12:11 INFO - Running setup.py install for blessings 09:12:11 INFO - Successfully installed blessings 09:12:11 INFO - Cleaning up... 09:12:11 INFO - Return code: 0 09:12:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:12:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:12:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:12:12 INFO - Reading from file tmpfile_stdout 09:12:12 INFO - Current package versions: 09:12:12 INFO - blessings == 1.6 09:12:12 INFO - blobuploader == 1.2.4 09:12:12 INFO - docopt == 0.6.1 09:12:12 INFO - functools32 == 3.2.3-2 09:12:12 INFO - jsonschema == 2.5.1 09:12:12 INFO - manifestparser == 1.1 09:12:12 INFO - mozInstall == 1.12 09:12:12 INFO - mozcrash == 1.0 09:12:12 INFO - mozdebug == 0.1 09:12:12 INFO - mozdevice == 0.49 09:12:12 INFO - mozfile == 1.2 09:12:12 INFO - mozhttpd == 0.7 09:12:12 INFO - mozinfo == 0.9 09:12:12 INFO - mozleak == 0.1 09:12:12 INFO - mozlog == 3.4 09:12:12 INFO - moznetwork == 0.27 09:12:12 INFO - mozprocess == 0.23 09:12:12 INFO - mozprofile == 0.28 09:12:12 INFO - mozrunner == 6.13 09:12:12 INFO - mozscreenshot == 0.1 09:12:12 INFO - mozsystemmonitor == 0.3 09:12:12 INFO - moztest == 0.7 09:12:12 INFO - mozversion == 1.4 09:12:12 INFO - psutil == 3.1.1 09:12:12 INFO - requests == 1.2.3 09:12:12 INFO - wsgiref == 0.1.2 09:12:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:12:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:12 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 0x1044a23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104418570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6f8c76eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042b7d30>, '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 0x10411edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1042afb20>, '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.djoXUa4j1J/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.hJSTqEwCUS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 09:12:12 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 09:12:12 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 09:12:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.djoXUa4j1J/Render', 09:12:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:12:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:12:12 INFO - 'HOME': '/Users/cltbld', 09:12:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:12:12 INFO - 'LOGNAME': 'cltbld', 09:12:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:12 INFO - 'MOZ_NO_REMOTE': '1', 09:12:12 INFO - 'NO_EM_RESTART': '1', 09:12:12 INFO - 'PAGER': '/bin/cat', 09:12:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:12:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:12 INFO - 'PWD': '/builds/slave/test', 09:12:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:12:12 INFO - 'SHELL': '/bin/bash', 09:12:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hJSTqEwCUS/Listeners', 09:12:12 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 09:12:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:12:12 INFO - 'USER': 'cltbld', 09:12:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:12:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:12:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:12 INFO - 'XPC_FLAGS': '0x0', 09:12:12 INFO - 'XPC_SERVICE_NAME': '0', 09:12:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:12:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:12 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)) 09:12:12 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)) 09:12:12 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)) 09:12:12 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)) 09:12:12 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)) 09:12:12 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)) 09:12:12 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)) 09:12:12 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)) 09:12:12 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)) 09:12:12 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)) 09:12:12 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)) 09:12:12 INFO - Cleaning up... 09:12:12 INFO - Return code: 0 09:12:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:12:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:12 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 0x1044a23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104418570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6f8c76eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042b7d30>, '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 0x10411edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1042afb20>, '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.djoXUa4j1J/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.hJSTqEwCUS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 09:12:12 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 09:12:12 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 09:12:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.djoXUa4j1J/Render', 09:12:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:12:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:12:12 INFO - 'HOME': '/Users/cltbld', 09:12:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:12:12 INFO - 'LOGNAME': 'cltbld', 09:12:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:12 INFO - 'MOZ_NO_REMOTE': '1', 09:12:12 INFO - 'NO_EM_RESTART': '1', 09:12:12 INFO - 'PAGER': '/bin/cat', 09:12:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:12:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:12 INFO - 'PWD': '/builds/slave/test', 09:12:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:12:12 INFO - 'SHELL': '/bin/bash', 09:12:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hJSTqEwCUS/Listeners', 09:12:12 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 09:12:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:12:12 INFO - 'USER': 'cltbld', 09:12:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:12:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:12:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:12 INFO - 'XPC_FLAGS': '0x0', 09:12:12 INFO - 'XPC_SERVICE_NAME': '0', 09:12:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:12:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:12:12 INFO - Cleaning up... 09:12:12 INFO - Return code: 0 09:12:12 INFO - Running post-action listener: _resource_record_post_action 09:12:12 INFO - Running post-action listener: _start_resource_monitoring 09:12:12 INFO - Starting resource monitoring. 09:12:12 INFO - [mozharness: 2016-12-14 17:12:12.733528Z] Finished create-virtualenv step (success) 09:12:12 INFO - [mozharness: 2016-12-14 17:12:12.734526Z] Running install step. 09:12:12 INFO - Running pre-action listener: _resource_record_pre_action 09:12:12 INFO - Running main action method: install 09:12:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:12:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:12:12 INFO - Reading from file tmpfile_stdout 09:12:12 INFO - Detecting whether we're running mozinstall >=1.0... 09:12:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:12:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:12:13 INFO - Reading from file tmpfile_stdout 09:12:13 INFO - Output received: 09:12:13 INFO - Usage: mozinstall [options] installer 09:12:13 INFO - Options: 09:12:13 INFO - -h, --help show this help message and exit 09:12:13 INFO - -d DEST, --destination=DEST 09:12:13 INFO - Directory to install application into. [default: 09:12:13 INFO - "/builds/slave/test"] 09:12:13 INFO - --app=APP Application being installed. [default: firefox] 09:12:13 INFO - mkdir: /builds/slave/test/build/application 09:12:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:12:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:12:34 INFO - Reading from file tmpfile_stdout 09:12:34 INFO - Output received: 09:12:34 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:12:34 INFO - Running post-action listener: _resource_record_post_action 09:12:34 INFO - [mozharness: 2016-12-14 17:12:34.845163Z] Finished install step (success) 09:12:34 INFO - [mozharness: 2016-12-14 17:12:34.845280Z] Running run-tests step. 09:12:34 INFO - Running pre-action listener: _resource_record_pre_action 09:12:34 INFO - Running main action method: run_tests 09:12:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:12:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:12:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:12:34 INFO - 2016-12-14 09:12:34.859 screenresolution[1897:19748] starting screenresolution argv=screenresolution get 09:12:34 INFO - 2016-12-14 09:12:34.862 screenresolution[1897:19748] Display 0: 1600x1200x32@60 09:12:34 INFO - 2016-12-14 09:12:34.868 screenresolution[1898:19750] starting screenresolution argv=screenresolution list 09:12:34 INFO - Available Modes on Display 0 09:12:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:12:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:12:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:12:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:12:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:12:35 INFO - Intel Iris: 09:12:35 INFO - Chipset Model: Intel Iris 09:12:35 INFO - Type: GPU 09:12:35 INFO - Bus: Built-In 09:12:35 INFO - VRAM (Dynamic, Max): 1536 MB 09:12:35 INFO - Vendor: Intel (0x8086) 09:12:35 INFO - Device ID: 0x0a2e 09:12:35 INFO - Revision ID: 0x0009 09:12:35 INFO - Displays: 09:12:35 INFO - Display: 09:12:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:12:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:12:35 INFO - Display Serial Number: 1600x1200 60 09:12:35 INFO - Main Display: Yes 09:12:35 INFO - Mirror: Off 09:12:35 INFO - Online: Yes 09:12:35 INFO - Rotation: Supported 09:12:35 INFO - Return code: 0 09:12:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:12:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:12:35 INFO - Python 2.7.3 09:12:35 INFO - Return code: 0 09:12:35 INFO - grabbing minidump binary from tooltool 09:12:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:12:35 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 0x1042b7d30>, '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 0x10411edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1042afb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:12:35 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 09:12:35 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 09:12:35 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 09:12:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:12:35 INFO - Return code: 0 09:12:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:12:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:12:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:12:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:12:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:12:35 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DYYRbLFITDac5tEoxX4o-g/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0097', '--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 09:12:35 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/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DYYRbLFITDac5tEoxX4o-g/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0097 --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 09:12:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.djoXUa4j1J/Render', 09:12:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:12:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:12:35 INFO - 'HOME': '/Users/cltbld', 09:12:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:12:35 INFO - 'LOGNAME': 'cltbld', 09:12:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:12:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:12:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:35 INFO - 'MOZ_NO_REMOTE': '1', 09:12:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:12:35 INFO - 'NO_EM_RESTART': '1', 09:12:35 INFO - 'PAGER': '/bin/cat', 09:12:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:12:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:35 INFO - 'PWD': '/builds/slave/test', 09:12:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:12:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:12:35 INFO - 'SHELL': '/bin/bash', 09:12:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hJSTqEwCUS/Listeners', 09:12:35 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 09:12:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:12:35 INFO - 'USER': 'cltbld', 09:12:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:12:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:12:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:35 INFO - 'XPC_FLAGS': '0x0', 09:12:35 INFO - 'XPC_SERVICE_NAME': '0', 09:12:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:12:35 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DYYRbLFITDac5tEoxX4o-g/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0097', '--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 09:12:35 INFO - mozversion application_buildid: 20161214073701 09:12:35 INFO - mozversion application_changeset: 126c324672eaf5673a51a37ed229b2003ecd6b4f 09:12:35 INFO - mozversion application_display_name: Nightly 09:12:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:12:35 INFO - mozversion application_name: Firefox 09:12:35 INFO - mozversion application_remotingname: firefox 09:12:35 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:12:35 INFO - mozversion application_vendor: Mozilla 09:12:35 INFO - mozversion application_version: 53.0a1 09:12:35 INFO - mozversion platform_buildid: 20161214073701 09:12:35 INFO - mozversion platform_changeset: 126c324672eaf5673a51a37ed229b2003ecd6b4f 09:12:35 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:12:35 INFO - mozversion platform_version: 53.0a1 09:12:35 INFO - using testdate: 1481735555 09:12:35 INFO - actual date: 1481735555 09:12:35 INFO - starting webserver on 'localhost:55652' 09:12:35 INFO - SUITE-START | Running 2 tests 09:12:35 INFO - TEST-START | dromaeo_css 09:12:35 INFO - operating with platform_type : mac_ 09:12:35 INFO - Initialising browser for dromaeo_css test... 09:12:35 INFO - TEST-INFO | started process 1908 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/tmpWLrosP/profile http://localhost:55652/getInfo.html) 09:12:38 INFO - PROCESS | 1908 | 2016-12-14 09:12:38.343 plugin-container[1910:19874] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9153, name = 'com.apple.tsm.portname' 09:12:38 INFO - PROCESS | 1908 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:12:38 INFO - PROCESS | 1908 | 2016-12-14 09:12:38.346 plugin-container[1910:19874] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a0f, name = 'com.apple.CFPasteboardClient' 09:12:38 INFO - PROCESS | 1908 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:12:38 INFO - PROCESS | 1908 | 2016-12-14 09:12:38.346 plugin-container[1910:19874] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:12:38 INFO - PROCESS | 1908 | __metrics Screen width/height:1600/1200 09:12:38 INFO - PROCESS | 1908 | colorDepth:24 09:12:38 INFO - PROCESS | 1908 | Browser inner width/height: 1024/689 09:12:38 INFO - PROCESS | 1908 | __metrics 09:12:38 ERROR - PROCESS | 1908 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3212: TypeError: this.tabs is undefined 09:12:39 INFO - TEST-INFO | 1908: exit 0 09:12:39 INFO - Browser initialized. 09:12:39 INFO - Running cycle 1/1 for dromaeo_css test... 09:12:39 INFO - TEST-INFO | started process 1911 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/tmpWLrosP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:12:41 INFO - PROCESS | 1911 | 2016-12-14 09:12:41.601 plugin-container[1912:19989] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 09:12:41 INFO - PROCESS | 1911 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:12:41 INFO - PROCESS | 1911 | 2016-12-14 09:12:41.602 plugin-container[1912:19989] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 09:12:41 INFO - PROCESS | 1911 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:12:41 INFO - PROCESS | 1911 | 2016-12-14 09:12:41.602 plugin-container[1912:19989] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:12:51 INFO - PROCESS | 1911 | RSS: Main: 237080576 09:12:51 INFO - PROCESS | 1911 | 09:13:40 INFO - PROCESS | 1911 | 1481735620805 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:15:55 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:55652/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55652/tests/dromaeo/cssquery-ext.html) 09:15:56 INFO - PROCESS | 1911 | RSS: Main: 255803392 09:15:56 INFO - PROCESS | 1911 | 09:18:59 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:55652/tests/dromaeo/cssquery-ext.html (next: http://localhost:55652/tests/dromaeo/cssquery-jquery.html) 09:19:00 INFO - PROCESS | 1911 | RSS: Main: 266579968 09:19:00 INFO - PROCESS | 1911 | 09:22:02 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:55652/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55652/tests/dromaeo/cssquery-mootools.html) 09:22:02 INFO - PROCESS | 1911 | RSS: Main: 270852096 09:22:02 INFO - PROCESS | 1911 | 09:25:04 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:55652/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55652/tests/dromaeo/cssquery-prototype.html) 09:25:05 INFO - PROCESS | 1911 | RSS: Main: 269570048 09:25:05 INFO - PROCESS | 1911 | 09:28:08 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:55652/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55652/tests/dromaeo/cssquery-yui.html) 09:28:08 INFO - PROCESS | 1911 | RSS: Main: 274018304 09:28:08 INFO - PROCESS | 1911 | 09:31:11 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:55652/tests/dromaeo/cssquery-yui.html (next: http://localhost:55652/tests/dromaeo/cssquery-dojo.html) 09:31:12 INFO - PROCESS | 1911 | RSS: Main: 277004288 09:31:12 INFO - PROCESS | 1911 | 09:31:12 INFO - PROCESS | 1911 | __start_tp_report 09:31:12 INFO - PROCESS | 1911 | _x_x_mozilla_page_load 09:31:12 INFO - PROCESS | 1911 | _x_x_mozilla_page_load_details 09:31:12 INFO - PROCESS | 1911 | |i|pagename|runs| 09:31:12 INFO - PROCESS | 1911 | |0;dojo.html;3123.344097739475;3311.6191637961642;3335.4830001349783;3380.154961259685;3381.1885147564585;5587.441255874421;5622.943770562293;5654.32148142223;5695.202671625976;5711;4659.743714095724;5625.1843482694985;5716.914246286309;5753.712314384285;5827.096799995997;5135.7688903999315;5180.611454140936;5210.2445145453885;5228.947710522903;5309.177077552966;5195.350581177353;5263.657862238962;5278.762455689503;5295.152775555912;5323.973380133094;5082.517160869711;5250.579953603703;5260.684358938438;5279.208118782175;5319.601029922752;5116.053530096907;5120.334356533627;5158.277841102249;5195.610329225286;5288.6826790392315;5131.974340128313;5150.948490515084;5185.585153187736;5202;5203.349581302337;4695.953040469586;5707.258056452676;5736.971315143398;5745.798897038583;5751.223584816038;5982.102684597302;6107.674343494563;6143.880361275353;6151.9234134026365;6210.875782484325;6244.625322480666;6245.87508249837;6256.499480041586;6256.687165641745;6259.436650701458;4790.592799612003;5835.503982161521;5853.678047707417;5893.233879595625;5960.523158147381;6109.755609775648;6197.845053873689;6242.843928901813;6295.433410992941;6299.559030867796;5268.92096618551;5478.506934375925;6051.304100028553;6078.696065196723;6127.84680382994;5663.915041274384;5755.65466072037;6115.724792384353;6192.535559832941;6224.782132625427;5102.464241254689;5934.198883150806;6104.337893874237;6134.079888016833;6140.293866205444;5277.604179686462;6010.699465026732;6181.81454556364;6221.47117495018;6236.812895613139;4109.794510274474;4178.498580170399;4828.203346447796;4880.023995200974;4911.8280860169725;3699.9445008324897;4404.075144219686;4426.402435671143;4438.35643831649;4440.134173836071;4821.300911367902;5888.558358123158;5889.617174883619;5899.793507227227;5933;6113.780377411019;6120.111926550715;6141.854248382324;6231.626102433868;6247.500199983991;5761.337446193657;5824.184614154022;5846.093855452412;5878.764849406061;5888.263967004124;5228.2680424740565;5307.548858347083;5320.17537281722;5328.17413300939;5344.465553444702;6111.07520520229;6224.315325314303;6266.749330026748;6296.842578935563;6298.590591611531;5994.6403215807195;5996;6001.2498437695285;6053.364396738279;6057.909131362945;6153.9845925421805;6189.257289125334;6197.442230199304;6226.688665566794;6231.003039513656;5974.492168165754;5983.790567330123;6000.849978750566;6048.0625503047095;6057.515398768176;5080.034793389244;5916.970415147896;6169.2288463942;6200.224971878515;6272.937270627235;6084.665343406155;6095.323561671189;6098.176746139392;6147.569670123048;6157.907631385443;6004.489618382487;6024.367441418588;6040.939590604038;6145.555794388404;6173.56785025044;4808.711477311373;5460.972695136499;5699.2590963174525;5973.970130149321;6046.1535385045245;6068.423499767515;6076.361981991828;6119.877602448015;6132.2947860996555;6133.815985520441;5756.913646295226;5764.423557644202;5879.059350503899;5999.040153575407;6020.939790602038 09:31:12 INFO - PROCESS | 1911 | |1;ext.html;440.2625602116455;459.6897094461238;462.6160286961822;471.79927085567226;485.3447845408696;37867.621323786756;40428;40791.99999999996;42566.787166064205;42682.93292667685;41910;42449;42486.57513424865;42491;42627.573724262824;8353.289970352527;8666.090060543615;9384.718458446241;9609.855852162224;9638.855417168748;11256.493457794419;11535.480903359368;11552.249103808226;11746.060315174807;11767.117466190028;11539.90370914766;11590.652280431601;11631.418429078554;11639.883601164007;11641.767164656701;10198.623185869887;10737.892621073768;11499.770004599903;11548.595799147073;11567.942160289229;8324.750257492286;11354.148438867118;11393.601223957121;11413.201075924688;11426.542938282459;27850.3037424064;39611;40056.19887602261;40068.59931400678;40100.39849402232;3588.946165807515;3641.289948460051;3872.9031774205587;4063.1061166543313;4070.75575465473;27042.86478567614;29818.85090574555;29888.252793680116;30879;31294.687053129404;2749.6562929633797;3294.7364210863075;3315.0717799016315;3336.9833150834334;3347.2134048498583;484592;488286;488683;489250;489463.5526822379;61150.69424652848;61185.38814611886;61649;61649;61876.762464750456;63804.68097659482;64049.35950640527;64209;64352.71294574176;64443.355566444676;16045.438409655606;16112.75830862538;16374.181290935405;16446.34214631425;16446.588835279214;11156.051735602403;11429.828552571718;12350.567730129402;12586.937065314614;12640.494380224687;17245.482635520955;17391.043492608027;17448.389306374214;17504.5623859402;17544.508716759217;7274.345308922222;7327.45044121693;7444.664990075459;7448.85102297951;7466.70133194677;845.3828705045352;971.8542218667257;980.1619615228876;1017.1913328903539;1038.9792204156026;28589.57115643267;29287.560686589717;29585.704142958726;29902.850485747866;31139.84430077835;865.753315226085;955.2596737528471;980.2696990741942;980.8185485685171;1081.6809041332826;12764;13264.270465124511;13808.585742427744;13966.092204006707;14528.69241768246;487531;572237;579491;580372;581821;50704.746476267384;50739.49260507348;50883.7455812726;51115;51196.720081997504;1317.736452709462;1357.5995081450997;1359.4086572341064;1365.378752667534;1380.6341319550327;26949;27486.312842179104;28074.85962570174;28138.01516946897;28351.291217718895;45437.86405339893;45928;45936.31095533637;45941.31088033749;46037;501891;503345;503422;504059;504565;22004.77995220027;24412.75587244105;24578.016879324627;24757.50484990326;24892.128775492773;34308;41642.79178604088;42452.787736061124;42834;43716.34425483684;34754.9573512795;34813;35787.28425431425;36044.81977590095;36384;34849.78025769086;35063.772767953;35094.824525878226;36173.457398138526;36249.09377265589;29180;34407.139821504665;35114.47328290027;35177;35446;30402.84798575993;31644.683553164174;31767.205819854687;31799.68200317967;31953.68046319507;23288.4177895554;23865.642015369434;24097.51804963926;24205.757942421056;24281.63577546303 09:31:12 INFO - PROCESS | 1911 | |2;jquery.html;357.70310642167004;363.8471841826433;365.7549441873944;375.49308433614607;387.47690617666154;19229.519262018457;19549.218031278768;19801.801981980178;20082.69875951862;20278.49303767405;15593;16380;18481.445556633324;19546.804531954676;20304.593908121864;4119.691023173259;5683.886322273552;5927.466528012478;5930.911036334458;5949.524038076944;5674.262345895048;5890.823275301748;5892.23400957877;5907.291125064999;5911.408859114097;4982.950170498303;5338.7720824210455;5894.852628684296;5903.291605007405;5924.6445213287125;5072.289879416885;5790.623609465392;5846.239988801429;5854.385289544579;5855.121731740231;4475.5748203920575;4993.800247990076;5104.157813960692;5111.284420181178;5130.153524668425;12914.160579562298;13022.088453808237;18054.548636284067;23590.528189436114;24118.517629647307;2211.8341124415733;2274.351809734218;2299.2642354446416;2311.2719493359537;2717.8233414827973;9640.807183856283;9683.128518433376;9720.125188733047;10113.443760593165;10130.392176469435;1705.0392049143584;1820.3628729944546;1839.8436132928716;1844.7232915062714;1886.9056547172586;91054.5447272766;111795.44102279436;114906;115002;120471.19293210609;11068.391238481961;11111.055560277306;11474.254173478865;11482.540698372139;11490.94254528722;13880.652983675489;14018.158910465201;14065.085769424955;14067.577972660836;14080.295985200699;7161.4986950912935;8093.635786389626;8143.959280203561;8259.752207433787;8462.957685211535;8155.714549990722;8192.836143277102;8202;8325.333973282244;8361.790955226046;3802.5817160112365;4247.768147237329;4359.280718681445;4543.386642803245;4573.176828170961;1654.9338026478806;1677.983220167807;1897.7532920720218;1924.1726057795283;1925.1433112264908;811.2495941254316;875.6628697951207;896.171041786345;897.4211633496358;1002.774375765447;16961.49115526536;18931.864088154754;19486.317978870673;20263.581549291408;20406.387808365773;760.3423039071164;901.6213190460022;911.166282851197;925.5557332480313;939.5067589515422;10709.518071686793;11179.44102794857;11190.32858028521;11233.494492747846;11240.55037798479;473495;566563;576084;577773;582414;126656.36671816582;132362;135760;136321;136731.31634341963;953.2802733935835;1019.4800651667852;1028.280203857303;1030.217035053365;1037.1443559063803;18765.906170469607;19716.112774924877;19733.60532789365;19750.209991600175;19802.801971980673;44128.77935610301;46198.53801461942;49699;53433.46566534285;53553.19670204872;65272;79614.60192699;80885.78671319818;80910;81908;7839.25527074927;7971.681132754625;8079.959600201962;8122.715704950535;8197.34421246287;10046.799064018825;12091.75816483683;12182.208156469802;12288.938555307166;12543.937280313905;8791.472511649321;9122.087791220825;10758.246922715334;10865.945670271598;11081.889181108409;10031.648892288735;10572.947135264274;10702;10885.619003334845;11055.391953442639;10739.785204296026;10832.295900766425;11082.445877706245;11161.944190278997;11235.438228088726;7998;8019.117897031439;8160.14318496573;8233.259006689426;8304.252617264234;9640.855387169058;10641.8935810644;10949.397783122005;10984.06635435965;11037.558497660004 09:31:12 INFO - PROCESS | 1911 | |3;mootools.html;1327.568540224427;1497.4309762290331;1682.402747024806;1849.3619701203081;1858.907054647268;7349.191588925214;7637.885431718529;7882.763517094482;8359.665613375466;8375.623096960637;7779.64991575379;8123.431359804816;8126.7561973140755;8137.389695772826;8159.265666090051;2264.6489794081863;2322.535492901418;2327.802136818364;2332.615118505453;2334.159702507096;2324.755900630435;2350.9529809403803;2364.8344615876895;2372.9288121356385;2374.5013547155118;2302.3898666853297;2313;2329.77867102625;2340.239422187787;2347.4600841806396;2291.9312420627407;2301.3786277704926;2330.149495434171;2330.254318618026;2330.475642980333;1911.206849157612;2357.328161473989;2361.7165940087298;2364.5861974154454;2366.8461549999365;8091.716789912325;8125.796855078611;8239.464434811778;8250.587470626444;8272.586370681442;4996;5170.715610641413;5181.0674078665825;5181.792728290864;5186.92219616706;2677.357434215774;3354.412977728912;3485.6862882340456;3523.6476352364557;3545.7340699447386;1036.1555332404066;1039.3555995282898;1057.8942105789436;1079.9136069114452;1082.7726177502734;10316.968303169699;10326;10561.207909406825;10566.999999999924;10567.207459440497;4365.80353884076;4527.705699129546;4604.884877878013;4606;4608.423947006624;7645.350145237717;9113.453192808453;9224.446533207894;9227.031161728057;9232.630694772268;5034.79860805571;5146.305248791366;5157.535821776059;5159.355080614923;5167.431582525919;3762.454444105644;4222.535521092677;4372.191144638252;4656.301554766812;4679.204535229019;3286.342731453719;3757.887263382103;3825.7895815729844;3854.421836724514;3910.276598829226;1155.8555180602425;1241.2870239070025;1267.7274386007052;1285.1839082182694;1291.6835375333103;826.8052663900726;838.8783626374142;874.8250349930039;884.5046773806689;922.4465320807462;5091.439941606421;5397;5428.5385742211565;5451.400345961941;5565.443455654402;350.99473507897403;419.176318534082;426.91248294099637;428.2676622974748;434.70222897314807;3749.1564398010228;3801.5248093988253;3841.2317536492815;3856.3636999895266;3876.6317199865966;8746.212840844355;9603.47180905043;9769.85345219808;9842.163416109712;9883;9497.762555936019;9793.167580755715;9819.116279534876;9857.01429857023;9870.60517579303;7492.887606685904;7701.537907725555;7713.344365728976;7719.884201737091;7736.7292144774665;5400.0819860623005;5443.346798384061;5467.87908478753;5496.725163741877;5523.530499907392;9031.36780425364;9101.7269481913;9122.08779122109;9162.129597688208;9173.816523669622;9190.264778817545;9243.260539156718;9298.349115562116;9352.205062569758;9356.4386136832;6514.804555863332;6669.899951500828;6840.31596840312;7031.332023457763;7106.893396598952;7616.8476630468185;7795.33739632115;7795.64919578609;7801.765947021599;7806.4925779824125;6880.552764070319;6995.335443132894;7006.544574602634;7028;7119.572825630479;5857.4435428634215;6860.5540639858245;6881.311868813079;6889.896651550331;6921.48088893325;6368.522360822864;6461.838454038687;6517.3808488193545;6524.739010439529;6542.084108224947;4562.726236425825;4622.422197225347;4682.531746825291;4728.40894888139;4759.452662943806;3567.6432356764117;4601.792919318572;4718.905621887612;4754.595859351994;4824.107540105092 09:31:12 INFO - PROCESS | 1911 | |4;prototype.html;308.6558487286676;344.6987621727977;356.4106764356947;358.06723485320737;362.64823121572067;4653.581177694007;4932.323010176539;5491;5507.5593952483805;5508.154768152664;4478.641708663307;5397.866448045915;5403;5416.566674666027;5448.91826622601;3446.4141096013614;3995.2409042281947;4070.3894415837567;4136.379543068549;4143.197708996581;3366.8653253869816;4191.077962848169;4199.874003779892;4247.87256382309;4249.787510624472;4013.257547353749;4101.8974525636795;4215.313874450229;4221.472315960505;4235.644593729992;3650.4889315495852;3972.2254160438724;4187.043244134295;4191.434156388879;4211.768352740598;3080.476319025771;3671.76133551321;3673.4673472346626;3676.7793932364143;3685.207680348738;4147.253494371012;4419.535948725402;5235.895282094337;5249.86875328116;5266.92099618506;4301.5698430157045;4576.794044268016;4658.301254811805;4815.181419158752;4834.564889159958;5259;5330.92003619946;5402.540784033362;5607.019261020057;5740;3668.669819716212;4033.657139143176;4061.5126184858004;4064.268431682296;4069.3285607874664;4551.271796512542;5676.999999999959;5745.15614770687;5962.791302304442;5992.490638295706;3535.49741359921;3593.9640603594153;3928.096537796323;4079.4084857695466;4125.443065186221;4194.3708443733685;4208.326667733148;4216.578342165759;4235;4240.872773816791;4218.78906054696;4348.43470348859;4767.4994125617;4792.856214313507;4856.975715121473;5236.371635403776;5280.973595132;5295.940811837648;5601.29983752031;5669.971650141805;1170.0639488409408;1203.0495908232574;1276.90423218259;1284.9935750321376;1297.7080156964607;706.0151089230495;772.6478662340903;792.8453951479521;799.7840583042546;864.5893200729729;4306.310990241546;4535.7278563285645;5118.872028199325;5206.193040078794;5500.532454741312;4900.8774780630765;5047.268146118792;5689;5917.733701983421;6185.010398336244;4750.358701575312;5039.244113383022;5302.628815982921;5443;5451.318585176853;2511.974880251211;2678.874872553532;2787.9442411151663;2881.5245484495244;2986.940261194764;5373.7581808818695;5500.477454641803;5845.88308233833;5852.297724273115;5908.763649454059;4558.566936141127;4901.828436004723;5456.263404440429;5478.698671573022;5641.887162256732;5164.457731938092;5391.892162156813;5559.27729395176;5810.360860305363;5916.313850552572;5071.87320316995;5276.918231762556;5667.8866422672145;5679.346875109415;5682.6306290091015;4925.950740492549;4929.211326187793;5249.690136339998;5387.2727181831515;5554.250176226158;5025.924611130763;5375.139977603565;5390.460953904578;5441.83674489766;5754.510866576388;3433.6051354094598;3537.4870643756;3635.363811333059;3703.722220833502;3764.94352584706;4897.061762942206;5226.660267082628;5420.83737487876;5565.443455654402;5668.659880407189;4594.632429405707;4615.699979501322;4720.221163507983;5458.836234912959;5486.615936884539;4596.678232523825;4671.579557839811;5143.177091665316;5455.863603409946;5472.549774309731;4778.665493415428;5079.120725331651;5251.159814429826;5278.736063196901;5335.746099666653;4509.842155524541;4798.5441383068555;5436.3476382834315;5496.31296087989;5633.774649014157;5285.392179899361;5397.892042159214;5554.916676249779;5583.274174357308;5587.44125587438 09:31:12 INFO - PROCESS | 1911 | |5;yui.html;698.6227437183921;711.3739908880182;712.1454254894127;749.6738918570422;751.5716041856142;14492.63768405789;15163.772543411858;15444.15057171858;15523.456679016237;15532.301046452909;13939;13986.440542378292;14089.929550352233;14127.576172714835;14138.71722565548;661.9470442364599;683.1972432391944;837.9581020948958;845.175953445392;867.5315329721942;865.8960924689046;867.5488745852153;881.4050515901773;882.294164668266;882.960266787996;912.393258483108;914.1681070226095;914.501596629835;914.5838643417262;916.0335845682802;899.5861903524386;906.7461110888962;911.6171208092584;912.1516989200042;912.2656261709321;669.6584741781727;673.5117040145904;673.6126727131918;677.2617846547238;677.6848765324098;14330.92834535831;14562.271886405637;14578.854211457856;14977.475788347465;15227.923860380737;13846.446142154302;14187.219702916336;14209.715805683829;14246.501372451914;14264.71617554425;11510.597129100524;11555.653330400017;11695.766084678258;11770.529178832836;11846.345228638633;3656.6891814195824;3691.298653255873;3707.870224542128;3773.9622603773887;3834.482344883461;106070;107917.46041269822;110288;113115.43442282735;114961.4251928735;8177.79555511117;10584.100351470057;10963.616273430393;11003.449827508593;11197.440127993568;18314.542136446427;18687.906560467112;22191.66712499314;22851.542969140857;22940.31179064631;7433.516821406592;7675.846483070307;7842.333401660809;7846.293833555007;7915.564643944638;4173.444160908932;4245.3631955206965;4260.701750877471;4427.358033085185;4438.245498265303;3285.9835700821823;3323.3851737428167;3494.930101397958;3517.3492903812876;3589.10272431892;545.1903922674825;630.0612087988864;654.2214764430313;665.1419668627514;702.9894551581731;526.210683974039;571.9685417302005;615.4829942848029;631.6778442994014;634.536788144657;9293.256539476826;9322.86015709765;9481.194098501566;9512.952435237918;9629.374090684083;500.8046861723931;582.2111039541445;626.1734510446167;650.2879347114902;655.4985436141355;4413.16149931512;4513.413256276729;4743.478217396084;4949.678270912379;4967.453580106186;82721.17278827135;89970.55014725015;105148;107812.92187078185;111957;83004;83340.58329708313;83607;85365.14634853696;86307.13692863117;54113.72943135259;75434.24565754383;75448.86826697605;76449.61775191089;77442;9533.666321678707;9902.801943961225;9936;9963.50182490858;10008.799824003334;49126.50873491362;49470.752646236535;49767.50232497774;50408.747956259984;51027;61663.38336616576;66405.33594664185;69686.60626787537;71163.28836711566;72538.63730681312;10841.447319271669;12739.044571656977;12991.28547929873;13124.606261812161;13625.386857591631;12994.935025324812;14624.268786560842;14765.335559899615;14980.40078396852;15009.849901501282;13645.590632281048;14026.719465610426;14063.648408789863;14307.713845723236;14361.066530675473;13538.729225415633;13648.86351136476;13970.650733731738;13993.720125597634;14291.785623215452;13260.469581217028;13458.192508449132;13494.392752325972;13895.930520347334;14171.929140354232;10866.728331791768;10975.725606859893;11275.943620281847;11291.153163512934;11722.70693232676;9558.461209191919;10120.848187277345;10126.13927816144;11155.609553665583;11371.48828302745 09:31:12 INFO - PROCESS | 1911 | __end_tp_report 09:31:12 INFO - PROCESS | 1911 | __start_cc_report 09:31:12 INFO - PROCESS | 1911 | _x_x_mozilla_cycle_collect,373 09:31:12 INFO - PROCESS | 1911 | __end_cc_report 09:31:12 INFO - PROCESS | 1911 | __startTimestamp1481736672026__endTimestamp 09:31:12 INFO - PROCESS | 1911 | 09:31:12 INFO - PROCESS | 1911 | ------- Summary: start ------- 09:31:12 INFO - PROCESS | 1911 | Number of tests: 6 09:31:12 INFO - PROCESS | 1911 | 09:31:12 INFO - PROCESS | 1911 | [#0] dojo.html Cycles:165 Average:5638.05 Median:5888.56 stddev:669.33 (11.4%) stddev-sans-first:641.66 09:31:12 INFO - PROCESS | 1911 | Values: 3123.3 3311.6 3335.5 3380.2 3381.2 5587.4 5622.9 5654.3 5695.2 5711.0 4659.7 5625.2 5716.9 5753.7 5827.1 5135.8 5180.6 5210.2 5228.9 5309.2 5195.4 5263.7 5278.8 5295.2 5324.0 5082.5 5250.6 5260.7 5279.2 5319.6 5116.1 5120.3 5158.3 5195.6 5288.7 5132.0 5150.9 5185.6 5202.0 5203.3 4696.0 5707.3 5737.0 5745.8 5751.2 5982.1 6107.7 6143.9 6151.9 6210.9 6244.6 6245.9 6256.5 6256.7 6259.4 4790.6 5835.5 5853.7 5893.2 5960.5 6109.8 6197.8 6242.8 6295.4 6299.6 5268.9 5478.5 6051.3 6078.7 6127.8 5663.9 5755.7 6115.7 6192.5 6224.8 5102.5 5934.2 6104.3 6134.1 6140.3 5277.6 6010.7 6181.8 6221.5 6236.8 4109.8 4178.5 4828.2 4880.0 4911.8 3699.9 4404.1 4426.4 4438.4 4440.1 4821.3 5888.6 5889.6 5899.8 5933.0 6113.8 6120.1 6141.9 6231.6 6247.5 5761.3 5824.2 5846.1 5878.8 5888.3 5228.3 5307.5 5320.2 5328.2 5344.5 6111.1 6224.3 6266.7 6296.8 6298.6 5994.6 5996.0 6001.2 6053.4 6057.9 6154.0 6189.3 6197.4 6226.7 6231.0 5974.5 5983.8 6000.8 6048.1 6057.5 5080.0 5917.0 6169.2 6200.2 6272.9 6084.7 6095.3 6098.2 6147.6 6157.9 6004.5 6024.4 6040.9 6145.6 6173.6 4808.7 5461.0 5699.3 5974.0 6046.2 6068.4 6076.4 6119.9 6132.3 6133.8 5756.9 5764.4 5879.1 5999.0 6020.9 09:31:12 INFO - PROCESS | 1911 | 09:31:12 INFO - PROCESS | 1911 | [#1] ext.html Cycles:180 Average:64990.53 Median:35104.65 stddev:138226.74 (393.8%) stddev-sans-first:138529.54 09:31:12 INFO - PROCESS | 1911 | Values: 440.3 459.7 462.6 471.8 485.3 37867.6 40428.0 40792.0 42566.8 42682.9 41910.0 42449.0 42486.6 42491.0 42627.6 8353.3 8666.1 9384.7 9609.9 9638.9 11256.5 11535.5 11552.2 11746.1 11767.1 11539.9 11590.7 11631.4 11639.9 11641.8 10198.6 10737.9 11499.8 11548.6 11567.9 8324.8 11354.1 11393.6 11413.2 11426.5 27850.3 39611.0 40056.2 40068.6 40100.4 3588.9 3641.3 3872.9 4063.1 4070.8 27042.9 29818.9 29888.3 30879.0 31294.7 2749.7 3294.7 3315.1 3337.0 3347.2 484592.0 488286.0 488683.0 489250.0 489463.6 61150.7 61185.4 61649.0 61649.0 61876.8 63804.7 64049.4 64209.0 64352.7 64443.4 16045.4 16112.8 16374.2 16446.3 16446.6 11156.1 11429.8 12350.6 12586.9 12640.5 17245.5 17391.0 17448.4 17504.6 17544.5 7274.3 7327.5 7444.7 7448.9 7466.7 845.4 971.9 980.2 1017.2 1039.0 28589.6 29287.6 29585.7 29902.9 31139.8 865.8 955.3 980.3 980.8 1081.7 12764.0 13264.3 13808.6 13966.1 14528.7 487531.0 572237.0 579491.0 580372.0 581821.0 50704.7 50739.5 50883.7 51115.0 51196.7 1317.7 1357.6 1359.4 1365.4 1380.6 26949.0 27486.3 28074.9 28138.0 28351.3 45437.9 45928.0 45936.3 45941.3 46037.0 501891.0 503345.0 503422.0 504059.0 504565.0 22004.8 24412.8 24578.0 24757.5 24892.1 34308.0 41642.8 42452.8 42834.0 43716.3 34755.0 34813.0 35787.3 36044.8 36384.0 34849.8 35063.8 35094.8 36173.5 36249.1 29180.0 34407.1 35114.5 35177.0 35446.0 30402.8 31644.7 31767.2 31799.7 31953.7 23288.4 23865.6 24097.5 24205.8 24281.6 09:31:12 INFO - PROCESS | 1911 | 09:31:12 INFO - PROCESS | 1911 | [#2] jquery.html Cycles:180 Average:33213.74 Median:23854.52 stddev:93423.95 (391.6%) stddev-sans-first:93653.45 09:31:12 INFO - PROCESS | 1911 | Values: 357.7 363.8 365.8 375.5 387.5 19229.5 19549.2 19801.8 20082.7 20278.5 15593.0 16380.0 18481.4 19546.8 20304.6 4119.7 5683.9 5927.5 5930.9 5949.5 5674.3 5890.8 5892.2 5907.3 5911.4 4983.0 5338.8 5894.9 5903.3 5924.6 5072.3 5790.6 5846.2 5854.4 5855.1 4475.6 4993.8 5104.2 5111.3 5130.2 12914.2 13022.1 18054.5 23590.5 24118.5 2211.8 2274.4 2299.3 2311.3 2717.8 9640.8 9683.1 9720.1 10113.4 10130.4 1705.0 1820.4 1839.8 1844.7 1886.9 91054.5 111795.4 114906.0 115002.0 120471.2 11068.4 11111.1 11474.3 11482.5 11490.9 13880.7 14018.2 14065.1 14067.6 14080.3 7161.5 8093.6 8144.0 8259.8 8463.0 8155.7 8192.8 8202.0 8325.3 8361.8 3802.6 4247.8 4359.3 4543.4 4573.2 1654.9 1678.0 1897.8 1924.2 1925.1 811.2 875.7 896.2 897.4 1002.8 16961.5 18931.9 19486.3 20263.6 20406.4 760.3 901.6 911.2 925.6 939.5 10709.5 11179.4 11190.3 11233.5 11240.6 473495.0 566563.0 576084.0 577773.0 582414.0 126656.4 132362.0 135760.0 136321.0 136731.3 953.3 1019.5 1028.3 1030.2 1037.1 18765.9 19716.1 19733.6 19750.2 19802.8 44128.8 46198.5 49699.0 53433.5 53553.2 65272.0 79614.6 80885.8 80910.0 81908.0 7839.3 7971.7 8080.0 8122.7 8197.3 10046.8 12091.8 12182.2 12288.9 12543.9 8791.5 9122.1 10758.2 10865.9 11081.9 10031.6 10572.9 10702.0 10885.6 11055.4 10739.8 10832.3 11082.4 11161.9 11235.4 7998.0 8019.1 8160.1 8233.3 8304.3 9640.9 10641.9 10949.4 10984.1 11037.6 09:31:12 INFO - PROCESS | 1911 | 09:31:12 INFO - PROCESS | 1911 | [#3] mootools.html Cycles:180 Average:5294.10 Median:5163.39 stddev:2895.05 (56.1%) stddev-sans-first:2887.82 09:31:12 INFO - PROCESS | 1911 | Values: 1327.6 1497.4 1682.4 1849.4 1858.9 7349.2 7637.9 7882.8 8359.7 8375.6 7779.6 8123.4 8126.8 8137.4 8159.3 2264.6 2322.5 2327.8 2332.6 2334.2 2324.8 2351.0 2364.8 2372.9 2374.5 2302.4 2313.0 2329.8 2340.2 2347.5 2291.9 2301.4 2330.1 2330.3 2330.5 1911.2 2357.3 2361.7 2364.6 2366.8 8091.7 8125.8 8239.5 8250.6 8272.6 4996.0 5170.7 5181.1 5181.8 5186.9 2677.4 3354.4 3485.7 3523.6 3545.7 1036.2 1039.4 1057.9 1079.9 1082.8 10317.0 10326.0 10561.2 10567.0 10567.2 4365.8 4527.7 4604.9 4606.0 4608.4 7645.4 9113.5 9224.4 9227.0 9232.6 5034.8 5146.3 5157.5 5159.4 5167.4 3762.5 4222.5 4372.2 4656.3 4679.2 3286.3 3757.9 3825.8 3854.4 3910.3 1155.9 1241.3 1267.7 1285.2 1291.7 826.8 838.9 874.8 884.5 922.4 5091.4 5397.0 5428.5 5451.4 5565.4 351.0 419.2 426.9 428.3 434.7 3749.2 3801.5 3841.2 3856.4 3876.6 8746.2 9603.5 9769.9 9842.2 9883.0 9497.8 9793.2 9819.1 9857.0 9870.6 7492.9 7701.5 7713.3 7719.9 7736.7 5400.1 5443.3 5467.9 5496.7 5523.5 9031.4 9101.7 9122.1 9162.1 9173.8 9190.3 9243.3 9298.3 9352.2 9356.4 6514.8 6669.9 6840.3 7031.3 7106.9 7616.8 7795.3 7795.6 7801.8 7806.5 6880.6 6995.3 7006.5 7028.0 7119.6 5857.4 6860.6 6881.3 6889.9 6921.5 6368.5 6461.8 6517.4 6524.7 6542.1 4562.7 4622.4 4682.5 4728.4 4759.5 3567.6 4601.8 4718.9 4754.6 4824.1 09:31:12 INFO - PROCESS | 1911 | 09:31:12 INFO - PROCESS | 1911 | [#4] prototype.html Cycles:180 Average:4473.12 Median:4927.58 stddev:1349.93 (27.4%) stddev-sans-first:1317.03 09:31:12 INFO - PROCESS | 1911 | Values: 308.7 344.7 356.4 358.1 362.6 4653.6 4932.3 5491.0 5507.6 5508.2 4478.6 5397.9 5403.0 5416.6 5448.9 3446.4 3995.2 4070.4 4136.4 4143.2 3366.9 4191.1 4199.9 4247.9 4249.8 4013.3 4101.9 4215.3 4221.5 4235.6 3650.5 3972.2 4187.0 4191.4 4211.8 3080.5 3671.8 3673.5 3676.8 3685.2 4147.3 4419.5 5235.9 5249.9 5266.9 4301.6 4576.8 4658.3 4815.2 4834.6 5259.0 5330.9 5402.5 5607.0 5740.0 3668.7 4033.7 4061.5 4064.3 4069.3 4551.3 5677.0 5745.2 5962.8 5992.5 3535.5 3594.0 3928.1 4079.4 4125.4 4194.4 4208.3 4216.6 4235.0 4240.9 4218.8 4348.4 4767.5 4792.9 4857.0 5236.4 5281.0 5295.9 5601.3 5670.0 1170.1 1203.0 1276.9 1285.0 1297.7 706.0 772.6 792.8 799.8 864.6 4306.3 4535.7 5118.9 5206.2 5500.5 4900.9 5047.3 5689.0 5917.7 6185.0 4750.4 5039.2 5302.6 5443.0 5451.3 2512.0 2678.9 2787.9 2881.5 2986.9 5373.8 5500.5 5845.9 5852.3 5908.8 4558.6 4901.8 5456.3 5478.7 5641.9 5164.5 5391.9 5559.3 5810.4 5916.3 5071.9 5276.9 5667.9 5679.3 5682.6 4926.0 4929.2 5249.7 5387.3 5554.3 5025.9 5375.1 5390.5 5441.8 5754.5 3433.6 3537.5 3635.4 3703.7 3764.9 4897.1 5226.7 5420.8 5565.4 5668.7 4594.6 4615.7 4720.2 5458.8 5486.6 4596.7 4671.6 5143.2 5455.9 5472.5 4778.7 5079.1 5251.2 5278.7 5335.7 4509.8 4798.5 5436.3 5496.3 5633.8 5285.4 5397.9 5554.9 5583.3 5587.4 09:31:12 INFO - PROCESS | 1911 | 09:31:12 INFO - PROCESS | 1911 | [#5] yui.html Cycles:180 Average:20167.04 Median:4420.26 stddev:29026.64 (656.7%) stddev-sans-first:29071.26 09:31:12 INFO - PROCESS | 1911 | Values: 698.6 711.4 712.1 749.7 751.6 14492.6 15163.8 15444.2 15523.5 15532.3 13939.0 13986.4 14089.9 14127.6 14138.7 661.9 683.2 838.0 845.2 867.5 865.9 867.5 881.4 882.3 883.0 912.4 914.2 914.5 914.6 916.0 899.6 906.7 911.6 912.2 912.3 669.7 673.5 673.6 677.3 677.7 14330.9 14562.3 14578.9 14977.5 15227.9 13846.4 14187.2 14209.7 14246.5 14264.7 11510.6 11555.7 11695.8 11770.5 11846.3 3656.7 3691.3 3707.9 3774.0 3834.5 106070.0 107917.5 110288.0 113115.4 114961.4 8177.8 10584.1 10963.6 11003.4 11197.4 18314.5 18687.9 22191.7 22851.5 22940.3 7433.5 7675.8 7842.3 7846.3 7915.6 4173.4 4245.4 4260.7 4427.4 4438.2 3286.0 3323.4 3494.9 3517.3 3589.1 545.2 630.1 654.2 665.1 703.0 526.2 572.0 615.5 631.7 634.5 9293.3 9322.9 9481.2 9513.0 9629.4 500.8 582.2 626.2 650.3 655.5 4413.2 4513.4 4743.5 4949.7 4967.5 82721.2 89970.6 105148.0 107812.9 111957.0 83004.0 83340.6 83607.0 85365.1 86307.1 54113.7 75434.2 75448.9 76449.6 77442.0 9533.7 9902.8 9936.0 9963.5 10008.8 49126.5 49470.8 49767.5 50408.7 51027.0 61663.4 66405.3 69686.6 71163.3 72538.6 10841.4 12739.0 12991.3 13124.6 13625.4 12994.9 14624.3 14765.3 14980.4 15009.8 13645.6 14026.7 14063.6 14307.7 14361.1 13538.7 13648.9 13970.7 13993.7 14291.8 13260.5 13458.2 13494.4 13895.9 14171.9 10866.7 10975.7 11275.9 11291.2 11722.7 9558.5 10120.8 10126.1 11155.6 11371.5 09:31:12 INFO - PROCESS | 1911 | -------- Summary: end -------- 09:31:12 INFO - PROCESS | 1911 | 09:31:12 INFO - TEST-INFO | 1911: exit 0 09:31:12 INFO - TEST-OK | dromaeo_css | took 1117222ms 09:31:12 INFO - TEST-START | kraken 09:31:12 INFO - operating with platform_type : mac_ 09:31:12 INFO - Initialising browser for kraken test... 09:31:12 INFO - TEST-INFO | started process 1919 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/tmpRtGeCF/profile http://localhost:55652/getInfo.html) 09:31:15 INFO - PROCESS | 1919 | __metrics Screen width/height:1600/1200 09:31:15 INFO - PROCESS | 1919 | colorDepth:24 09:31:15 INFO - PROCESS | 1919 | Browser inner width/height: 1024/689 09:31:15 INFO - PROCESS | 1919 | __metrics 09:31:15 INFO - PROCESS | 1919 | 2016-12-14 09:31:15.445 plugin-container[1920:22217] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 09:31:15 INFO - PROCESS | 1919 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:31:15 INFO - PROCESS | 1919 | 2016-12-14 09:31:15.448 plugin-container[1920:22217] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e1f, name = 'com.apple.CFPasteboardClient' 09:31:15 INFO - PROCESS | 1919 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:31:15 INFO - PROCESS | 1919 | 2016-12-14 09:31:15.448 plugin-container[1920:22217] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:31:15 ERROR - PROCESS | 1919 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3212: TypeError: this.tabs is undefined 09:31:16 INFO - TEST-INFO | 1919: exit 0 09:31:16 INFO - Browser initialized. 09:31:16 INFO - Running cycle 1/1 for kraken test... 09:31:16 INFO - TEST-INFO | started process 1921 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/tmpRtGeCF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:31:18 INFO - PROCESS | 1921 | 2016-12-14 09:31:18.917 plugin-container[1922:22335] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 09:31:18 INFO - PROCESS | 1921 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:31:18 INFO - PROCESS | 1921 | 2016-12-14 09:31:18.917 plugin-container[1922:22335] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 09:31:18 INFO - PROCESS | 1921 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:31:18 INFO - PROCESS | 1921 | 2016-12-14 09:31:18.917 plugin-container[1922:22335] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:31:28 INFO - PROCESS | 1921 | RSS: Main: 236957696 09:31:28 INFO - PROCESS | 1921 | 09:31:31 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:55652/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55652/tests/kraken/driver.html?testName=audio-beat-detection) 09:31:32 INFO - PROCESS | 1921 | RSS: Main: 249577472 09:31:32 INFO - PROCESS | 1921 | 09:31:35 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:55652/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55652/tests/kraken/driver.html?testName=audio-dft) 09:31:35 INFO - PROCESS | 1921 | RSS: Main: 257208320 09:31:35 INFO - PROCESS | 1921 | 09:31:39 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:55652/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55652/tests/kraken/driver.html?testName=audio-fft) 09:31:39 INFO - PROCESS | 1921 | RSS: Main: 259858432 09:31:39 INFO - PROCESS | 1921 | 09:31:41 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:55652/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55652/tests/kraken/driver.html?testName=audio-oscillator) 09:31:42 INFO - PROCESS | 1921 | RSS: Main: 261103616 09:31:42 INFO - PROCESS | 1921 | 09:31:44 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:55652/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55652/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:31:44 INFO - PROCESS | 1921 | RSS: Main: 261525504 09:31:44 INFO - PROCESS | 1921 | 09:31:48 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:55652/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55652/tests/kraken/driver.html?testName=imaging-darkroom) 09:31:48 INFO - PROCESS | 1921 | RSS: Main: 267259904 09:31:48 INFO - PROCESS | 1921 | 09:31:51 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:55652/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55652/tests/kraken/driver.html?testName=imaging-desaturate) 09:31:52 INFO - PROCESS | 1921 | RSS: Main: 268292096 09:31:52 INFO - PROCESS | 1921 | 09:31:55 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:55652/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55652/tests/kraken/driver.html?testName=json-parse-financial) 09:31:56 INFO - PROCESS | 1921 | RSS: Main: 268488704 09:31:56 INFO - PROCESS | 1921 | 09:31:57 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:55652/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55652/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:31:58 INFO - PROCESS | 1921 | RSS: Main: 267509760 09:31:58 INFO - PROCESS | 1921 | 09:32:00 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:55652/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55652/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:32:01 INFO - PROCESS | 1921 | RSS: Main: 267489280 09:32:01 INFO - PROCESS | 1921 | 09:32:03 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:55652/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55652/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:32:03 INFO - PROCESS | 1921 | RSS: Main: 267530240 09:32:03 INFO - PROCESS | 1921 | 09:32:06 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:55652/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55652/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:32:07 INFO - PROCESS | 1921 | RSS: Main: 267845632 09:32:07 INFO - PROCESS | 1921 | 09:32:10 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:55652/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55652/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:32:11 INFO - PROCESS | 1921 | RSS: Main: 267608064 09:32:11 INFO - PROCESS | 1921 | 09:32:13 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:55652/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55652/tests/kraken/driver.html?testName=ai-astar) 09:32:13 INFO - PROCESS | 1921 | RSS: Main: 268726272 09:32:13 INFO - PROCESS | 1921 | 09:32:13 INFO - PROCESS | 1921 | __start_tp_report 09:32:13 INFO - PROCESS | 1921 | _x_x_mozilla_page_load 09:32:13 INFO - PROCESS | 1921 | _x_x_mozilla_page_load_details 09:32:13 INFO - PROCESS | 1921 | |i|pagename|runs| 09:32:13 INFO - PROCESS | 1921 | |0;ai-astar;103;150;152;116;117;117;116;118;118;118 09:32:13 INFO - PROCESS | 1921 | |1;audio-beat-detection;149;116;117;154;123;117;118;119;138;122 09:32:13 INFO - PROCESS | 1921 | |2;audio-dft;198;211;210;214;196;200;201;200;203;200 09:32:13 INFO - PROCESS | 1921 | |3;audio-fft;86;57;62;85;56;61;59;61;79;56 09:32:13 INFO - PROCESS | 1921 | |4;audio-oscillator;87;71;88;74;71;80;70;87;57;72 09:32:13 INFO - PROCESS | 1921 | |5;imaging-gaussian-blur;151;112;109;114;115;127;113;163;111;112 09:32:13 INFO - PROCESS | 1921 | |6;imaging-darkroom;94;96;95;95;96;105;94;96;95;95 09:32:13 INFO - PROCESS | 1921 | |7;imaging-desaturate;95;94;139;90;139;144;100;76;148;146 09:32:13 INFO - PROCESS | 1921 | |8;json-parse-financial;59;58;55;61;61;91;59;60;60;59 09:32:13 INFO - PROCESS | 1921 | |9;json-stringify-tinderbox;69;67;76;104;112;102;81;72;72;74 09:32:13 INFO - PROCESS | 1921 | |10;stanford-crypto-aes;91;93;95;91;90;89;96;94;91;90 09:32:13 INFO - PROCESS | 1921 | |11;stanford-crypto-ccm;148;150;153;150;151;148;149;151;213;158 09:32:13 INFO - PROCESS | 1921 | |12;stanford-crypto-pbkdf2;233;235;188;175;177;176;181;181;179;181 09:32:13 INFO - PROCESS | 1921 | |13;stanford-crypto-sha256-iterative;71;69;70;69;69;70;70;68;77;78 09:32:13 INFO - PROCESS | 1921 | __end_tp_report 09:32:13 INFO - PROCESS | 1921 | __start_cc_report 09:32:13 INFO - PROCESS | 1921 | _x_x_mozilla_cycle_collect,988 09:32:13 INFO - PROCESS | 1921 | __end_cc_report 09:32:13 INFO - PROCESS | 1921 | __startTimestamp1481736733287__endTimestamp 09:32:13 INFO - PROCESS | 1921 | 09:32:13 INFO - PROCESS | 1921 | ------- Summary: start ------- 09:32:13 INFO - PROCESS | 1921 | Number of tests: 14 09:32:13 INFO - PROCESS | 1921 | 09:32:13 INFO - PROCESS | 1921 | [#0] ai-astar Cycles:10 Average:122.50 Median:118.00 stddev:15.68 (13.3%) stddev-sans-first:14.96 09:32:13 INFO - PROCESS | 1921 | Values: 103.0 150.0 152.0 116.0 117.0 117.0 116.0 118.0 118.0 118.0 09:32:13 INFO - PROCESS | 1921 | 09:32:13 INFO - PROCESS | 1921 | [#1] audio-beat-detection Cycles:10 Average:127.30 Median:122.50 stddev:14.30 (11.7%) stddev-sans-first:12.83 09:32:13 INFO - PROCESS | 1921 | Values: 149.0 116.0 117.0 154.0 123.0 117.0 118.0 119.0 138.0 122.0 09:32:13 INFO - PROCESS | 1921 | 09:32:13 INFO - PROCESS | 1921 | [#2] audio-dft Cycles:10 Average:203.30 Median:202.00 stddev:6.13 (3.0%) stddev-sans-first:6.19 09:32:13 INFO - PROCESS | 1921 | Values: 198.0 211.0 210.0 214.0 196.0 200.0 201.0 200.0 203.0 200.0 09:32:13 INFO - PROCESS | 1921 | 09:32:13 INFO - PROCESS | 1921 | [#3] audio-fft Cycles:10 Average:66.20 Median:61.50 stddev:12.14 (19.7%) stddev-sans-first:10.55 09:32:13 INFO - PROCESS | 1921 | Values: 86.0 57.0 62.0 85.0 56.0 61.0 59.0 61.0 79.0 56.0 09:32:13 INFO - PROCESS | 1921 | 09:32:13 INFO - PROCESS | 1921 | [#4] audio-oscillator Cycles:10 Average:75.70 Median:77.00 stddev:9.82 (12.8%) stddev-sans-first:9.53 09:32:13 INFO - PROCESS | 1921 | Values: 87.0 71.0 88.0 74.0 71.0 80.0 70.0 87.0 57.0 72.0 09:32:13 INFO - PROCESS | 1921 | 09:32:13 INFO - PROCESS | 1921 | [#5] imaging-gaussian-blur Cycles:10 Average:122.70 Median:114.50 stddev:18.93 (16.5%) stddev-sans-first:17.09 09:32:13 INFO - PROCESS | 1921 | Values: 151.0 112.0 109.0 114.0 115.0 127.0 113.0 163.0 111.0 112.0 09:32:13 INFO - PROCESS | 1921 | 09:32:13 INFO - PROCESS | 1921 | [#6] imaging-darkroom Cycles:10 Average:96.10 Median:95.00 stddev:3.21 (3.4%) stddev-sans-first:3.32 09:32:13 INFO - PROCESS | 1921 | Values: 94.0 96.0 95.0 95.0 96.0 105.0 94.0 96.0 95.0 95.0 09:32:13 INFO - PROCESS | 1921 | 09:32:13 INFO - PROCESS | 1921 | [#7] imaging-desaturate Cycles:10 Average:117.10 Median:112.00 stddev:28.31 (25.3%) stddev-sans-first:28.87 09:32:13 INFO - PROCESS | 1921 | Values: 95.0 94.0 139.0 90.0 139.0 144.0 100.0 76.0 148.0 146.0 09:32:13 INFO - PROCESS | 1921 | 09:32:13 INFO - PROCESS | 1921 | [#8] json-parse-financial Cycles:10 Average:62.30 Median:60.00 stddev:10.23 (17.1%) stddev-sans-first:10.78 09:32:13 INFO - PROCESS | 1921 | Values: 59.0 58.0 55.0 61.0 61.0 91.0 59.0 60.0 60.0 59.0 09:32:13 INFO - PROCESS | 1921 | 09:32:13 INFO - PROCESS | 1921 | [#9] json-stringify-tinderbox Cycles:10 Average:82.90 Median:72.00 stddev:16.57 (23.0%) stddev-sans-first:16.79 09:32:13 INFO - PROCESS | 1921 | Values: 69.0 67.0 76.0 104.0 112.0 102.0 81.0 72.0 72.0 74.0 09:32:13 INFO - PROCESS | 1921 | 09:32:13 INFO - PROCESS | 1921 | [#10] stanford-crypto-aes Cycles:10 Average:92.00 Median:92.00 stddev:2.36 (2.6%) stddev-sans-first:2.47 09:32:13 INFO - PROCESS | 1921 | Values: 91.0 93.0 95.0 91.0 90.0 89.0 96.0 94.0 91.0 90.0 09:32:13 INFO - PROCESS | 1921 | 09:32:13 INFO - PROCESS | 1921 | [#11] stanford-crypto-ccm Cycles:10 Average:157.10 Median:151.00 stddev:19.86 (13.2%) stddev-sans-first:20.79 09:32:13 INFO - PROCESS | 1921 | Values: 148.0 150.0 153.0 150.0 151.0 148.0 149.0 151.0 213.0 158.0 09:32:13 INFO - PROCESS | 1921 | 09:32:13 INFO - PROCESS | 1921 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.60 Median:181.00 stddev:23.16 (12.8%) stddev-sans-first:18.81 09:32:13 INFO - PROCESS | 1921 | Values: 233.0 235.0 188.0 175.0 177.0 176.0 181.0 181.0 179.0 181.0 09:32:13 INFO - PROCESS | 1921 | 09:32:13 INFO - PROCESS | 1921 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.10 Median:70.00 stddev:3.48 (5.0%) stddev-sans-first:3.69 09:32:13 INFO - PROCESS | 1921 | Values: 71.0 69.0 70.0 69.0 69.0 70.0 70.0 68.0 77.0 78.0 09:32:13 INFO - PROCESS | 1921 | -------- Summary: end -------- 09:32:13 INFO - PROCESS | 1921 | 09:32:14 INFO - TEST-INFO | 1921: exit 0 09:32:14 INFO - TEST-OK | kraken | took 61658ms 09:32:14 INFO - SUITE-END | took 1178s 09:32:14 INFO - Completed test suite (00:19:38) 09:32:14 INFO - javascript benchmark 09:32:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7083.124881537439, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3123.344097739475, 3311.6191637961642, 3335.4830001349783, 3380.154961259685, 3381.1885147564585, 5587.441255874421, 5622.943770562293, 5654.32148142223, 5695.202671625976, 5711.0, 4659.743714095724, 5625.1843482694985, 5716.914246286309, 5753.712314384285, 5827.096799995997, 5135.7688903999315, 5180.611454140936, 5210.2445145453885, 5228.947710522903, 5309.177077552966, 5195.350581177353, 5263.657862238962, 5278.762455689503, 5295.152775555912, 5323.973380133094, 5082.517160869711, 5250.579953603703, 5260.684358938438, 5279.208118782175, 5319.601029922752, 5116.053530096907, 5120.334356533627, 5158.277841102249, 5195.610329225286, 5288.6826790392315, 5131.974340128313, 5150.948490515084, 5185.585153187736, 5202.0, 5203.349581302337, 4695.953040469586, 5707.258056452676, 5736.971315143398, 5745.798897038583, 5751.223584816038, 5982.102684597302, 6107.674343494563, 6143.880361275353, 6151.9234134026365, 6210.875782484325, 6244.625322480666, 6245.87508249837, 6256.499480041586, 6256.687165641745, 6259.436650701458, 4790.592799612003, 5835.503982161521, 5853.678047707417, 5893.233879595625, 5960.523158147381, 6109.755609775648, 6197.845053873689, 6242.843928901813, 6295.433410992941, 6299.559030867796, 5268.92096618551, 5478.506934375925, 6051.304100028553, 6078.696065196723, 6127.84680382994, 5663.915041274384, 5755.65466072037, 6115.724792384353, 6192.535559832941, 6224.782132625427, 5102.464241254689, 5934.198883150806, 6104.337893874237, 6134.079888016833, 6140.293866205444, 5277.604179686462, 6010.699465026732, 6181.81454556364, 6221.47117495018, 6236.812895613139, 4109.794510274474, 4178.498580170399, 4828.203346447796, 4880.023995200974, 4911.8280860169725, 3699.9445008324897, 4404.075144219686, 4426.402435671143, 4438.35643831649, 4440.134173836071, 4821.300911367902, 5888.558358123158, 5889.617174883619, 5899.793507227227, 5933.0, 6113.780377411019, 6120.111926550715, 6141.854248382324, 6231.626102433868, 6247.500199983991, 5761.337446193657, 5824.184614154022, 5846.093855452412, 5878.764849406061, 5888.263967004124, 5228.2680424740565, 5307.548858347083, 5320.17537281722, 5328.17413300939, 5344.465553444702, 6111.07520520229, 6224.315325314303, 6266.749330026748, 6296.842578935563, 6298.590591611531, 5994.6403215807195, 5996.0, 6001.2498437695285, 6053.364396738279, 6057.909131362945, 6153.9845925421805, 6189.257289125334, 6197.442230199304, 6226.688665566794, 6231.003039513656, 5974.492168165754, 5983.790567330123, 6000.849978750566, 6048.0625503047095, 6057.515398768176, 5080.034793389244, 5916.970415147896, 6169.2288463942, 6200.224971878515, 6272.937270627235, 6084.665343406155, 6095.323561671189, 6098.176746139392, 6147.569670123048, 6157.907631385443, 6004.489618382487, 6024.367441418588, 6040.939590604038, 6145.555794388404, 6173.56785025044, 4808.711477311373, 5460.972695136499, 5699.2590963174525, 5973.970130149321, 6046.1535385045245, 6068.423499767515, 6076.361981991828, 6119.877602448015, 6132.2947860996555, 6133.815985520441, 5756.913646295226, 5764.423557644202, 5879.059350503899, 5999.040153575407, 6020.939790602038], "value": 5596.827643311812, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [440.2625602116455, 459.6897094461238, 462.6160286961822, 471.79927085567226, 485.3447845408696, 37867.621323786756, 40428.0, 40791.99999999996, 42566.787166064205, 42682.93292667685, 41910.0, 42449.0, 42486.57513424865, 42491.0, 42627.573724262824, 8353.289970352527, 8666.090060543615, 9384.718458446241, 9609.855852162224, 9638.855417168748, 11256.493457794419, 11535.480903359368, 11552.249103808226, 11746.060315174807, 11767.117466190028, 11539.90370914766, 11590.652280431601, 11631.418429078554, 11639.883601164007, 11641.767164656701, 10198.623185869887, 10737.892621073768, 11499.770004599903, 11548.595799147073, 11567.942160289229, 8324.750257492286, 11354.148438867118, 11393.601223957121, 11413.201075924688, 11426.542938282459, 27850.3037424064, 39611.0, 40056.19887602261, 40068.59931400678, 40100.39849402232, 3588.946165807515, 3641.289948460051, 3872.9031774205587, 4063.1061166543313, 4070.75575465473, 27042.86478567614, 29818.85090574555, 29888.252793680116, 30879.0, 31294.687053129404, 2749.6562929633797, 3294.7364210863075, 3315.0717799016315, 3336.9833150834334, 3347.2134048498583, 484592.0, 488286.0, 488683.0, 489250.0, 489463.5526822379, 61150.69424652848, 61185.38814611886, 61649.0, 61649.0, 61876.762464750456, 63804.68097659482, 64049.35950640527, 64209.0, 64352.71294574176, 64443.355566444676, 16045.438409655606, 16112.75830862538, 16374.181290935405, 16446.34214631425, 16446.588835279214, 11156.051735602403, 11429.828552571718, 12350.567730129402, 12586.937065314614, 12640.494380224687, 17245.482635520955, 17391.043492608027, 17448.389306374214, 17504.5623859402, 17544.508716759217, 7274.345308922222, 7327.45044121693, 7444.664990075459, 7448.85102297951, 7466.70133194677, 845.3828705045352, 971.8542218667257, 980.1619615228876, 1017.1913328903539, 1038.9792204156026, 28589.57115643267, 29287.560686589717, 29585.704142958726, 29902.850485747866, 31139.84430077835, 865.753315226085, 955.2596737528471, 980.2696990741942, 980.8185485685171, 1081.6809041332826, 12764.0, 13264.270465124511, 13808.585742427744, 13966.092204006707, 14528.69241768246, 487531.0, 572237.0, 579491.0, 580372.0, 581821.0, 50704.746476267384, 50739.49260507348, 50883.7455812726, 51115.0, 51196.720081997504, 1317.736452709462, 1357.5995081450997, 1359.4086572341064, 1365.378752667534, 1380.6341319550327, 26949.0, 27486.312842179104, 28074.85962570174, 28138.01516946897, 28351.291217718895, 45437.86405339893, 45928.0, 45936.31095533637, 45941.31088033749, 46037.0, 501891.0, 503345.0, 503422.0, 504059.0, 504565.0, 22004.77995220027, 24412.75587244105, 24578.016879324627, 24757.50484990326, 24892.128775492773, 34308.0, 41642.79178604088, 42452.787736061124, 42834.0, 43716.34425483684, 34754.9573512795, 34813.0, 35787.28425431425, 36044.81977590095, 36384.0, 34849.78025769086, 35063.772767953, 35094.824525878226, 36173.457398138526, 36249.09377265589, 29180.0, 34407.139821504665, 35114.47328290027, 35177.0, 35446.0, 30402.84798575993, 31644.683553164174, 31767.205819854687, 31799.68200317967, 31953.68046319507, 23288.4177895554, 23865.642015369434, 24097.51804963926, 24205.757942421056, 24281.63577546303], "value": 19428.21139200297, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.70310642167004, 363.8471841826433, 365.7549441873944, 375.49308433614607, 387.47690617666154, 19229.519262018457, 19549.218031278768, 19801.801981980178, 20082.69875951862, 20278.49303767405, 15593.0, 16380.0, 18481.445556633324, 19546.804531954676, 20304.593908121864, 4119.691023173259, 5683.886322273552, 5927.466528012478, 5930.911036334458, 5949.524038076944, 5674.262345895048, 5890.823275301748, 5892.23400957877, 5907.291125064999, 5911.408859114097, 4982.950170498303, 5338.7720824210455, 5894.852628684296, 5903.291605007405, 5924.6445213287125, 5072.289879416885, 5790.623609465392, 5846.239988801429, 5854.385289544579, 5855.121731740231, 4475.5748203920575, 4993.800247990076, 5104.157813960692, 5111.284420181178, 5130.153524668425, 12914.160579562298, 13022.088453808237, 18054.548636284067, 23590.528189436114, 24118.517629647307, 2211.8341124415733, 2274.351809734218, 2299.2642354446416, 2311.2719493359537, 2717.8233414827973, 9640.807183856283, 9683.128518433376, 9720.125188733047, 10113.443760593165, 10130.392176469435, 1705.0392049143584, 1820.3628729944546, 1839.8436132928716, 1844.7232915062714, 1886.9056547172586, 91054.5447272766, 111795.44102279436, 114906.0, 115002.0, 120471.19293210609, 11068.391238481961, 11111.055560277306, 11474.254173478865, 11482.540698372139, 11490.94254528722, 13880.652983675489, 14018.158910465201, 14065.085769424955, 14067.577972660836, 14080.295985200699, 7161.4986950912935, 8093.635786389626, 8143.959280203561, 8259.752207433787, 8462.957685211535, 8155.714549990722, 8192.836143277102, 8202.0, 8325.333973282244, 8361.790955226046, 3802.5817160112365, 4247.768147237329, 4359.280718681445, 4543.386642803245, 4573.176828170961, 1654.9338026478806, 1677.983220167807, 1897.7532920720218, 1924.1726057795283, 1925.1433112264908, 811.2495941254316, 875.6628697951207, 896.171041786345, 897.4211633496358, 1002.774375765447, 16961.49115526536, 18931.864088154754, 19486.317978870673, 20263.581549291408, 20406.387808365773, 760.3423039071164, 901.6213190460022, 911.166282851197, 925.5557332480313, 939.5067589515422, 10709.518071686793, 11179.44102794857, 11190.32858028521, 11233.494492747846, 11240.55037798479, 473495.0, 566563.0, 576084.0, 577773.0, 582414.0, 126656.36671816582, 132362.0, 135760.0, 136321.0, 136731.31634341963, 953.2802733935835, 1019.4800651667852, 1028.280203857303, 1030.217035053365, 1037.1443559063803, 18765.906170469607, 19716.112774924877, 19733.60532789365, 19750.209991600175, 19802.801971980673, 44128.77935610301, 46198.53801461942, 49699.0, 53433.46566534285, 53553.19670204872, 65272.0, 79614.60192699, 80885.78671319818, 80910.0, 81908.0, 7839.25527074927, 7971.681132754625, 8079.959600201962, 8122.715704950535, 8197.34421246287, 10046.799064018825, 12091.75816483683, 12182.208156469802, 12288.938555307166, 12543.937280313905, 8791.472511649321, 9122.087791220825, 10758.246922715334, 10865.945670271598, 11081.889181108409, 10031.648892288735, 10572.947135264274, 10702.0, 10885.619003334845, 11055.391953442639, 10739.785204296026, 10832.295900766425, 11082.445877706245, 11161.944190278997, 11235.438228088726, 7998.0, 8019.117897031439, 8160.14318496573, 8233.259006689426, 8304.252617264234, 9640.855387169058, 10641.8935810644, 10949.397783122005, 10984.06635435965, 11037.558497660004], "value": 9175.70615633409, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1327.568540224427, 1497.4309762290331, 1682.402747024806, 1849.3619701203081, 1858.907054647268, 7349.191588925214, 7637.885431718529, 7882.763517094482, 8359.665613375466, 8375.623096960637, 7779.64991575379, 8123.431359804816, 8126.7561973140755, 8137.389695772826, 8159.265666090051, 2264.6489794081863, 2322.535492901418, 2327.802136818364, 2332.615118505453, 2334.159702507096, 2324.755900630435, 2350.9529809403803, 2364.8344615876895, 2372.9288121356385, 2374.5013547155118, 2302.3898666853297, 2313.0, 2329.77867102625, 2340.239422187787, 2347.4600841806396, 2291.9312420627407, 2301.3786277704926, 2330.149495434171, 2330.254318618026, 2330.475642980333, 1911.206849157612, 2357.328161473989, 2361.7165940087298, 2364.5861974154454, 2366.8461549999365, 8091.716789912325, 8125.796855078611, 8239.464434811778, 8250.587470626444, 8272.586370681442, 4996.0, 5170.715610641413, 5181.0674078665825, 5181.792728290864, 5186.92219616706, 2677.357434215774, 3354.412977728912, 3485.6862882340456, 3523.6476352364557, 3545.7340699447386, 1036.1555332404066, 1039.3555995282898, 1057.8942105789436, 1079.9136069114452, 1082.7726177502734, 10316.968303169699, 10326.0, 10561.207909406825, 10566.999999999924, 10567.207459440497, 4365.80353884076, 4527.705699129546, 4604.884877878013, 4606.0, 4608.423947006624, 7645.350145237717, 9113.453192808453, 9224.446533207894, 9227.031161728057, 9232.630694772268, 5034.79860805571, 5146.305248791366, 5157.535821776059, 5159.355080614923, 5167.431582525919, 3762.454444105644, 4222.535521092677, 4372.191144638252, 4656.301554766812, 4679.204535229019, 3286.342731453719, 3757.887263382103, 3825.7895815729844, 3854.421836724514, 3910.276598829226, 1155.8555180602425, 1241.2870239070025, 1267.7274386007052, 1285.1839082182694, 1291.6835375333103, 826.8052663900726, 838.8783626374142, 874.8250349930039, 884.5046773806689, 922.4465320807462, 5091.439941606421, 5397.0, 5428.5385742211565, 5451.400345961941, 5565.443455654402, 350.99473507897403, 419.176318534082, 426.91248294099637, 428.2676622974748, 434.70222897314807, 3749.1564398010228, 3801.5248093988253, 3841.2317536492815, 3856.3636999895266, 3876.6317199865966, 8746.212840844355, 9603.47180905043, 9769.85345219808, 9842.163416109712, 9883.0, 9497.762555936019, 9793.167580755715, 9819.116279534876, 9857.01429857023, 9870.60517579303, 7492.887606685904, 7701.537907725555, 7713.344365728976, 7719.884201737091, 7736.7292144774665, 5400.0819860623005, 5443.346798384061, 5467.87908478753, 5496.725163741877, 5523.530499907392, 9031.36780425364, 9101.7269481913, 9122.08779122109, 9162.129597688208, 9173.816523669622, 9190.264778817545, 9243.260539156718, 9298.349115562116, 9352.205062569758, 9356.4386136832, 6514.804555863332, 6669.899951500828, 6840.31596840312, 7031.332023457763, 7106.893396598952, 7616.8476630468185, 7795.33739632115, 7795.64919578609, 7801.765947021599, 7806.4925779824125, 6880.552764070319, 6995.335443132894, 7006.544574602634, 7028.0, 7119.572825630479, 5857.4435428634215, 6860.5540639858245, 6881.311868813079, 6889.896651550331, 6921.48088893325, 6368.522360822864, 6461.838454038687, 6517.3808488193545, 6524.739010439529, 6542.084108224947, 4562.726236425825, 4622.422197225347, 4682.531746825291, 4728.40894888139, 4759.452662943806, 3567.6432356764117, 4601.792919318572, 4718.905621887612, 4754.595859351994, 4824.107540105092], "value": 4238.112316879858, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.6558487286676, 344.6987621727977, 356.4106764356947, 358.06723485320737, 362.64823121572067, 4653.581177694007, 4932.323010176539, 5491.0, 5507.5593952483805, 5508.154768152664, 4478.641708663307, 5397.866448045915, 5403.0, 5416.566674666027, 5448.91826622601, 3446.4141096013614, 3995.2409042281947, 4070.3894415837567, 4136.379543068549, 4143.197708996581, 3366.8653253869816, 4191.077962848169, 4199.874003779892, 4247.87256382309, 4249.787510624472, 4013.257547353749, 4101.8974525636795, 4215.313874450229, 4221.472315960505, 4235.644593729992, 3650.4889315495852, 3972.2254160438724, 4187.043244134295, 4191.434156388879, 4211.768352740598, 3080.476319025771, 3671.76133551321, 3673.4673472346626, 3676.7793932364143, 3685.207680348738, 4147.253494371012, 4419.535948725402, 5235.895282094337, 5249.86875328116, 5266.92099618506, 4301.5698430157045, 4576.794044268016, 4658.301254811805, 4815.181419158752, 4834.564889159958, 5259.0, 5330.92003619946, 5402.540784033362, 5607.019261020057, 5740.0, 3668.669819716212, 4033.657139143176, 4061.5126184858004, 4064.268431682296, 4069.3285607874664, 4551.271796512542, 5676.999999999959, 5745.15614770687, 5962.791302304442, 5992.490638295706, 3535.49741359921, 3593.9640603594153, 3928.096537796323, 4079.4084857695466, 4125.443065186221, 4194.3708443733685, 4208.326667733148, 4216.578342165759, 4235.0, 4240.872773816791, 4218.78906054696, 4348.43470348859, 4767.4994125617, 4792.856214313507, 4856.975715121473, 5236.371635403776, 5280.973595132, 5295.940811837648, 5601.29983752031, 5669.971650141805, 1170.0639488409408, 1203.0495908232574, 1276.90423218259, 1284.9935750321376, 1297.7080156964607, 706.0151089230495, 772.6478662340903, 792.8453951479521, 799.7840583042546, 864.5893200729729, 4306.310990241546, 4535.7278563285645, 5118.872028199325, 5206.193040078794, 5500.532454741312, 4900.8774780630765, 5047.268146118792, 5689.0, 5917.733701983421, 6185.010398336244, 4750.358701575312, 5039.244113383022, 5302.628815982921, 5443.0, 5451.318585176853, 2511.974880251211, 2678.874872553532, 2787.9442411151663, 2881.5245484495244, 2986.940261194764, 5373.7581808818695, 5500.477454641803, 5845.88308233833, 5852.297724273115, 5908.763649454059, 4558.566936141127, 4901.828436004723, 5456.263404440429, 5478.698671573022, 5641.887162256732, 5164.457731938092, 5391.892162156813, 5559.27729395176, 5810.360860305363, 5916.313850552572, 5071.87320316995, 5276.918231762556, 5667.8866422672145, 5679.346875109415, 5682.6306290091015, 4925.950740492549, 4929.211326187793, 5249.690136339998, 5387.2727181831515, 5554.250176226158, 5025.924611130763, 5375.139977603565, 5390.460953904578, 5441.83674489766, 5754.510866576388, 3433.6051354094598, 3537.4870643756, 3635.363811333059, 3703.722220833502, 3764.94352584706, 4897.061762942206, 5226.660267082628, 5420.83737487876, 5565.443455654402, 5668.659880407189, 4594.632429405707, 4615.699979501322, 4720.221163507983, 5458.836234912959, 5486.615936884539, 4596.678232523825, 4671.579557839811, 5143.177091665316, 5455.863603409946, 5472.549774309731, 4778.665493415428, 5079.120725331651, 5251.159814429826, 5278.736063196901, 5335.746099666653, 4509.842155524541, 4798.5441383068555, 5436.3476382834315, 5496.31296087989, 5633.774649014157, 5285.392179899361, 5397.892042159214, 5554.916676249779, 5583.274174357308, 5587.44125587438], "value": 4044.921671629436, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [698.6227437183921, 711.3739908880182, 712.1454254894127, 749.6738918570422, 751.5716041856142, 14492.63768405789, 15163.772543411858, 15444.15057171858, 15523.456679016237, 15532.301046452909, 13939.0, 13986.440542378292, 14089.929550352233, 14127.576172714835, 14138.71722565548, 661.9470442364599, 683.1972432391944, 837.9581020948958, 845.175953445392, 867.5315329721942, 865.8960924689046, 867.5488745852153, 881.4050515901773, 882.294164668266, 882.960266787996, 912.393258483108, 914.1681070226095, 914.501596629835, 914.5838643417262, 916.0335845682802, 899.5861903524386, 906.7461110888962, 911.6171208092584, 912.1516989200042, 912.2656261709321, 669.6584741781727, 673.5117040145904, 673.6126727131918, 677.2617846547238, 677.6848765324098, 14330.92834535831, 14562.271886405637, 14578.854211457856, 14977.475788347465, 15227.923860380737, 13846.446142154302, 14187.219702916336, 14209.715805683829, 14246.501372451914, 14264.71617554425, 11510.597129100524, 11555.653330400017, 11695.766084678258, 11770.529178832836, 11846.345228638633, 3656.6891814195824, 3691.298653255873, 3707.870224542128, 3773.9622603773887, 3834.482344883461, 106070.0, 107917.46041269822, 110288.0, 113115.43442282735, 114961.4251928735, 8177.79555511117, 10584.100351470057, 10963.616273430393, 11003.449827508593, 11197.440127993568, 18314.542136446427, 18687.906560467112, 22191.66712499314, 22851.542969140857, 22940.31179064631, 7433.516821406592, 7675.846483070307, 7842.333401660809, 7846.293833555007, 7915.564643944638, 4173.444160908932, 4245.3631955206965, 4260.701750877471, 4427.358033085185, 4438.245498265303, 3285.9835700821823, 3323.3851737428167, 3494.930101397958, 3517.3492903812876, 3589.10272431892, 545.1903922674825, 630.0612087988864, 654.2214764430313, 665.1419668627514, 702.9894551581731, 526.210683974039, 571.9685417302005, 615.4829942848029, 631.6778442994014, 634.536788144657, 9293.256539476826, 9322.86015709765, 9481.194098501566, 9512.952435237918, 9629.374090684083, 500.8046861723931, 582.2111039541445, 626.1734510446167, 650.2879347114902, 655.4985436141355, 4413.16149931512, 4513.413256276729, 4743.478217396084, 4949.678270912379, 4967.453580106186, 82721.17278827135, 89970.55014725015, 105148.0, 107812.92187078185, 111957.0, 83004.0, 83340.58329708313, 83607.0, 85365.14634853696, 86307.13692863117, 54113.72943135259, 75434.24565754383, 75448.86826697605, 76449.61775191089, 77442.0, 9533.666321678707, 9902.801943961225, 9936.0, 9963.50182490858, 10008.799824003334, 49126.50873491362, 49470.752646236535, 49767.50232497774, 50408.747956259984, 51027.0, 61663.38336616576, 66405.33594664185, 69686.60626787537, 71163.28836711566, 72538.63730681312, 10841.447319271669, 12739.044571656977, 12991.28547929873, 13124.606261812161, 13625.386857591631, 12994.935025324812, 14624.268786560842, 14765.335559899615, 14980.40078396852, 15009.849901501282, 13645.590632281048, 14026.719465610426, 14063.648408789863, 14307.713845723236, 14361.066530675473, 13538.729225415633, 13648.86351136476, 13970.650733731738, 13993.720125597634, 14291.785623215452, 13260.469581217028, 13458.192508449132, 13494.392752325972, 13895.930520347334, 14171.929140354232, 10866.728331791768, 10975.725606859893, 11275.943620281847, 11291.153163512934, 11722.70693232676, 9558.461209191919, 10120.848187277345, 10126.13927816144, 11155.609553665583, 11371.48828302745], "value": 7382.517954424192, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1586.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 150.0, 152.0, 116.0, 117.0, 117.0, 116.0, 118.0, 118.0, 118.0], "value": 122.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 116.0, 117.0, 154.0, 123.0, 117.0, 118.0, 119.0, 138.0, 122.0], "value": 127.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 211.0, 210.0, 214.0, 196.0, 200.0, 201.0, 200.0, 203.0, 200.0], "value": 203.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 57.0, 62.0, 85.0, 56.0, 61.0, 59.0, 61.0, 79.0, 56.0], "value": 66.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 88.0, 74.0, 71.0, 80.0, 70.0, 87.0, 57.0, 72.0], "value": 75.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 112.0, 109.0, 114.0, 115.0, 127.0, 113.0, 163.0, 111.0, 112.0], "value": 122.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 95.0, 95.0, 96.0, 105.0, 94.0, 96.0, 95.0, 95.0], "value": 96.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 139.0, 90.0, 139.0, 144.0, 100.0, 76.0, 148.0, 146.0], "value": 117.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 55.0, 61.0, 61.0, 91.0, 59.0, 60.0, 60.0, 59.0], "value": 62.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 76.0, 104.0, 112.0, 102.0, 81.0, 72.0, 72.0, 74.0], "value": 82.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 95.0, 91.0, 90.0, 89.0, 96.0, 94.0, 91.0, 90.0], "value": 92.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 150.0, 153.0, 150.0, 151.0, 148.0, 149.0, 151.0, 213.0, 158.0], "value": 157.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [233.0, 235.0, 188.0, 175.0, 177.0, 176.0, 181.0, 181.0, 179.0, 181.0], "value": 190.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 70.0, 69.0, 69.0, 70.0, 70.0, 68.0, 77.0, 78.0], "value": 71.1, "unit": "score"}]}]} 09:32:14 INFO - Return code: 0 09:32:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:32:14 ERROR - # TBPL SUCCESS # 09:32:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:32:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:32:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:32:14 INFO - 2016-12-14 09:32:14.607 screenresolution[1926:22720] starting screenresolution argv=screenresolution get 09:32:14 INFO - 2016-12-14 09:32:14.610 screenresolution[1926:22720] Display 0: 1600x1200x32@60 09:32:14 INFO - 2016-12-14 09:32:14.616 screenresolution[1927:22723] starting screenresolution argv=screenresolution list 09:32:14 INFO - Available Modes on Display 0 09:32:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:32:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:32:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:32:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:32:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:32:14 INFO - Intel Iris: 09:32:14 INFO - Chipset Model: Intel Iris 09:32:14 INFO - Type: GPU 09:32:14 INFO - Bus: Built-In 09:32:14 INFO - VRAM (Dynamic, Max): 1536 MB 09:32:14 INFO - Vendor: Intel (0x8086) 09:32:14 INFO - Device ID: 0x0a2e 09:32:14 INFO - Revision ID: 0x0009 09:32:14 INFO - Displays: 09:32:14 INFO - Display: 09:32:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:32:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:32:14 INFO - Display Serial Number: 1600x1200 60 09:32:14 INFO - Main Display: Yes 09:32:14 INFO - Mirror: Off 09:32:14 INFO - Online: Yes 09:32:14 INFO - Rotation: Supported 09:32:14 INFO - Return code: 0 09:32:14 INFO - Running post-action listener: _resource_record_post_action 09:32:14 INFO - [mozharness: 2016-12-14 17:32:14.857842Z] Finished run-tests step (success) 09:32:14 INFO - Running post-run listener: _resource_record_post_run 09:32:14 INFO - Total resource usage - Wall time: 1201s; CPU: 55.0%; Read bytes: 3372544; Write bytes: 554267648; Read time: 26; Write time: 1695 09:32:14 INFO - TinderboxPrint: CPU usage
54.6% 09:32:14 INFO - TinderboxPrint: I/O read bytes / time
3,372,544 / 26 09:32:14 INFO - TinderboxPrint: I/O write bytes / time
554,267,648 / 1,695 09:32:14 INFO - TinderboxPrint: CPU idle
2,183.2 (45.4%) 09:32:14 INFO - TinderboxPrint: CPU system
967.4 (20.1%) 09:32:14 INFO - TinderboxPrint: CPU user
1,655.2 (34.4%) 09:32:14 INFO - TinderboxPrint: Swap in / out
253,534,208 / 0 09:32:14 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250206720; Write bytes: 250236928; Read time: 18211; Write time: 467 09:32:14 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 1381888; Write bytes: 301424128; Read time: 18; Write time: 1191 09:32:15 INFO - Running post-run listener: _upload_blobber_files 09:32:15 INFO - Blob upload gear active. 09:32:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:32:15 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 09:32:15 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'] 09:32:15 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 09:32:15 INFO - (blobuploader) - INFO - Open directory for files ... 09:32:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:32:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:32:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:32:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:32:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:32:17 INFO - (blobuploader) - INFO - Done attempting. 09:32:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:32:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:32:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:32:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:32:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:32:18 INFO - (blobuploader) - INFO - Done attempting. 09:32:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:32:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:32:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:32:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:32:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:32:20 INFO - (blobuploader) - INFO - Done attempting. 09:32:20 INFO - (blobuploader) - INFO - Iteration through files over. 09:32:20 INFO - Return code: 0 09:32:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:32:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:32:20 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7e09d37e092272abed206683e5c314e5b2af48a5f067ad237e5d2232f42fdee0054892e177772ca90b57f56fb90a849b8fa86cac70a3f33bc7a7c8db2c0e9c1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d86a0f222f182be3500a05f987ade7a2ac550684a55084257a59e7a7d2281ae8978b9d966099253f7255edb0bf966676b1eb626709392ff34b79215f683fff8a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7cb84c2dec454d620d18b3ead62a2e7242be72910f60e5db5e3546d69fe8f58808c0648bd7bd17295f8b83e5f97b9710abae7a69c140c60c539b4b5e81c75b1b"} 09:32:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:32:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:32:20 INFO - Contents: 09:32:20 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7e09d37e092272abed206683e5c314e5b2af48a5f067ad237e5d2232f42fdee0054892e177772ca90b57f56fb90a849b8fa86cac70a3f33bc7a7c8db2c0e9c1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d86a0f222f182be3500a05f987ade7a2ac550684a55084257a59e7a7d2281ae8978b9d966099253f7255edb0bf966676b1eb626709392ff34b79215f683fff8a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7cb84c2dec454d620d18b3ead62a2e7242be72910f60e5db5e3546d69fe8f58808c0648bd7bd17295f8b83e5f97b9710abae7a69c140c60c539b4b5e81c75b1b"} 09:32:20 INFO - Running post-run listener: copy_logs_to_upload_dir 09:32:20 INFO - Copying logs to upload dir... 09:32:20 INFO - mkdir: /builds/slave/test/build/upload/logs 09:32:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.928302 ========= master_lag: 0.14 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-12-14 09:32:20.812360) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-14 09:32:20.816529) ========= 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.djoXUa4j1J/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.hJSTqEwCUS/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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/b7e09d37e092272abed206683e5c314e5b2af48a5f067ad237e5d2232f42fdee0054892e177772ca90b57f56fb90a849b8fa86cac70a3f33bc7a7c8db2c0e9c1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d86a0f222f182be3500a05f987ade7a2ac550684a55084257a59e7a7d2281ae8978b9d966099253f7255edb0bf966676b1eb626709392ff34b79215f683fff8a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7cb84c2dec454d620d18b3ead62a2e7242be72910f60e5db5e3546d69fe8f58808c0648bd7bd17295f8b83e5f97b9710abae7a69c140c60c539b4b5e81c75b1b"} build_url:https://queue.taskcluster.net/v1/task/DYYRbLFITDac5tEoxX4o-g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011393 build_url: 'https://queue.taskcluster.net/v1/task/DYYRbLFITDac5tEoxX4o-g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7e09d37e092272abed206683e5c314e5b2af48a5f067ad237e5d2232f42fdee0054892e177772ca90b57f56fb90a849b8fa86cac70a3f33bc7a7c8db2c0e9c1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d86a0f222f182be3500a05f987ade7a2ac550684a55084257a59e7a7d2281ae8978b9d966099253f7255edb0bf966676b1eb626709392ff34b79215f683fff8a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7cb84c2dec454d620d18b3ead62a2e7242be72910f60e5db5e3546d69fe8f58808c0648bd7bd17295f8b83e5f97b9710abae7a69c140c60c539b4b5e81c75b1b"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-14 09:32:20.853399) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 09:32:20.853930) ========= 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.djoXUa4j1J/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.hJSTqEwCUS/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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.005154 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 09:32:20.905354) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-14 09:32:20.905928) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-14 09:32:20.906541) ========= ========= Total master_lag: 0.29 =========