builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0222 starttime: 1492040625.48 results: success (0) buildid: 20170412154708 builduid: aa8715def7eb487e9871e32d6e61c67d revision: acb4e7fbf2027ba50dfbe954bd301da5d1975404 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-12 16:43:45.478288) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-12 16:43:45.478808) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-12 16:43:45.479121) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-12 16:43:45.505821) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 16:43:45.506179) ========= 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.fFdLf2LmNB/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.lDfsA7x1nH/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-12 16:43:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 20.7M=0.001s 2017-04-12 16:43:45 (20.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.141651 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 16:43:45.672645) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 16:43:45.673027) ========= 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.fFdLf2LmNB/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.lDfsA7x1nH/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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.026765 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 16:43:45.721758) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-12 16:43:45.722158) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev acb4e7fbf2027ba50dfbe954bd301da5d1975404 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev acb4e7fbf2027ba50dfbe954bd301da5d1975404 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fFdLf2LmNB/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.lDfsA7x1nH/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-12 16:43:46,057 truncating revision to first 12 chars 2017-04-12 16:43:46,057 Setting DEBUG logging. 2017-04-12 16:43:46,057 attempt 1/10 2017-04-12 16:43:46,057 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/acb4e7fbf202?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-12 16:43:48,264 unpacking tar archive at: pine-acb4e7fbf202/testing/mozharness/ program finished with exit code 0 elapsedTime=2.480415 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-12 16:43:48.228502) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-12 16:43:48.228846) ========= script_repo_revision: acb4e7fbf2027ba50dfbe954bd301da5d1975404 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-12 16:43:48.229278) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-12 16:43:48.229582) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-12 16:43:48.246952) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2017-04-12 16:43:48.247318) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 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', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fFdLf2LmNB/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.lDfsA7x1nH/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 16:43:48 INFO - MultiFileLogger online at 20170412 16:43:48 in /builds/slave/test 16:43:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 16:43:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:43:48 INFO - {'append_to_log': False, 16:43:48 INFO - 'base_work_dir': '/builds/slave/test', 16:43:48 INFO - 'blob_upload_branch': 'Pine', 16:43:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:43:48 INFO - 'branch': 'Pine', 16:43:48 INFO - 'buildbot_json_path': 'buildprops.json', 16:43:48 INFO - 'config_files': ('talos/mac_config.py',), 16:43:48 INFO - 'default_actions': ('clobber', 16:43:48 INFO - 'read-buildbot-config', 16:43:48 INFO - 'download-and-extract', 16:43:48 INFO - 'populate-webroot', 16:43:48 INFO - 'create-virtualenv', 16:43:48 INFO - 'install', 16:43:48 INFO - 'run-tests'), 16:43:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:43:48 INFO - 'download_minidump_stackwalk': True, 16:43:48 INFO - 'download_symbols': 'ondemand', 16:43:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:43:48 INFO - 'tooltool.py': '/tools/tooltool.py', 16:43:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:43:48 INFO - '/tools/misc-python/virtualenv.py')}, 16:43:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:43:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:43:48 INFO - 'gecko_profile': False, 16:43:48 INFO - 'gecko_profile_interval': 0, 16:43:48 INFO - 'installer_path': 'installer.exe', 16:43:48 INFO - 'log_level': 'info', 16:43:48 INFO - 'log_name': 'talos', 16:43:48 INFO - 'log_to_console': True, 16:43:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:43:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:43:48 INFO - 'opt_config_files': (), 16:43:48 INFO - 'pip_index': False, 16:43:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:43:48 INFO - 'cmd': ('bash', 16:43:48 INFO - '-c', 16:43:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:43:48 INFO - 'enabled': True, 16:43:48 INFO - 'halt_on_failure': False, 16:43:48 INFO - 'name': 'check_screen_resolution'},), 16:43:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:43:48 INFO - 'cmd': ('bash', 16:43:48 INFO - '-c', 16:43:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:43:48 INFO - 'enabled': True, 16:43:48 INFO - 'halt_on_failure': False, 16:43:48 INFO - 'name': 'check_screen_resolution'},), 16:43:48 INFO - 'run_cmd_checks_enabled': True, 16:43:48 INFO - 'suite': 'dromaeojs-e10s', 16:43:48 INFO - 'system_bits': '32', 16:43:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:43:48 INFO - 'title': 't-yosemite-r7-0222', 16:43:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:43:48 INFO - 'use_talos_json': True, 16:43:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:43:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:43:48 INFO - 'work_dir': 'build'} 16:43:48 INFO - [mozharness: 2017-04-12 23:43:48.704056Z] Running clobber step. 16:43:48 INFO - Running pre-action listener: _resource_record_pre_action 16:43:48 INFO - Running main action method: clobber 16:43:48 INFO - rmtree: /builds/slave/test/build 16:43:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:43:51 INFO - Running post-action listener: _resource_record_post_action 16:43:51 INFO - [mozharness: 2017-04-12 23:43:51.150686Z] Finished clobber step (success) 16:43:51 INFO - [mozharness: 2017-04-12 23:43:51.150814Z] Running read-buildbot-config step. 16:43:51 INFO - Running pre-action listener: _resource_record_pre_action 16:43:51 INFO - Running main action method: read_buildbot_config 16:43:51 INFO - Using buildbot properties: 16:43:51 INFO - { 16:43:51 INFO - "project": "", 16:43:51 INFO - "product": "firefox", 16:43:51 INFO - "script_repo_revision": "default", 16:43:51 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 16:43:51 INFO - "repository": "", 16:43:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 16:43:51 INFO - "buildid": "20170412154708", 16:43:51 INFO - "stage_platform": "macosx64", 16:43:51 INFO - "basedir": "/builds/slave/test", 16:43:51 INFO - "buildnumber": 1, 16:43:51 INFO - "platform": "yosemite_r7", 16:43:51 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 16:43:51 INFO - "slavebuilddir": "test", 16:43:51 INFO - "branch": "pine", 16:43:51 INFO - "revision": "acb4e7fbf2027ba50dfbe954bd301da5d1975404", 16:43:51 INFO - "slavename": "t-yosemite-r7-0222", 16:43:51 INFO - "builduid": "aa8715def7eb487e9871e32d6e61c67d", 16:43:51 INFO - "repo_path": "projects/pine" 16:43:51 INFO - } 16:43:51 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491939968/firefox-55.0a1.en-US.mac.dmg. 16:43:51 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491939968/firefox-55.0a1.en-US.mac.test_packages.json. 16:43:51 INFO - Running post-action listener: _resource_record_post_action 16:43:51 INFO - [mozharness: 2017-04-12 23:43:51.154073Z] Finished read-buildbot-config step (success) 16:43:51 INFO - [mozharness: 2017-04-12 23:43:51.154166Z] Running download-and-extract step. 16:43:51 INFO - Running pre-action listener: _resource_record_pre_action 16:43:51 INFO - Running main action method: download_and_extract 16:43:51 INFO - mkdir: /builds/slave/test/build/tests 16:43:51 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')]} 16:43:51 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491939968/firefox-55.0a1.en-US.mac.test_packages.json 16:43:51 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491939968/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 16:43:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491939968/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 16:43:51 INFO - Downloaded 1325 bytes. 16:43:51 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 16:43:51 INFO - Using the following test package requirements: 16:43:51 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:43:51 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 16:43:51 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:43:51 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:43:51 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 16:43:51 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:43:51 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 16:43:51 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:43:51 INFO - u'jsshell-mac.zip'], 16:43:51 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:43:51 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 16:43:51 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:43:51 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:43:51 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 16:43:51 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:43:51 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 16:43:51 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:43:51 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 16:43:51 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:43:51 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 16:43:51 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:43:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491939968/firefox-55.0a1.en-US.mac.common.tests.zip 16:43:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491939968/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:43:51 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491939968/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:43:52 INFO - Expected file size: 52581209 16:43:52 INFO - Obtained file size: 52581209 16:44:08 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:44:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491939968/firefox-55.0a1.en-US.mac.common.tests.zip 16:44:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491939968/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:44:08 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491939968/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:44:09 INFO - Expected file size: 52581209 16:44:09 INFO - Obtained file size: 52581209 16:44:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491939968/firefox-55.0a1.en-US.mac.talos.tests.zip 16:44:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491939968/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:44:24 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491939968/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 16:44:24 INFO - Expected file size: 14405596 16:44:24 INFO - Obtained file size: 14405596 16:44:25 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')]} 16:44:25 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491939968/firefox-55.0a1.en-US.mac.dmg 16:44:25 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491939968/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 16:44:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491939968/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 16:44:26 INFO - Downloaded 70998870 bytes. 16:44:26 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491939968/firefox-55.0a1.en-US.mac.dmg 16:44:26 INFO - mkdir: /builds/slave/test/properties 16:44:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:44:26 INFO - Writing to file /builds/slave/test/properties/build_url 16:44:26 INFO - Contents: 16:44:26 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491939968/firefox-55.0a1.en-US.mac.dmg 16:44:27 INFO - Running post-action listener: _resource_record_post_action 16:44:27 INFO - Running post-action listener: set_extra_try_arguments 16:44:27 INFO - [mozharness: 2017-04-12 23:44:27.074875Z] Finished download-and-extract step (success) 16:44:27 INFO - [mozharness: 2017-04-12 23:44:27.074983Z] Running populate-webroot step. 16:44:27 INFO - Running pre-action listener: _resource_record_pre_action 16:44:27 INFO - Running main action method: populate_webroot 16:44:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:44:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:44:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:44:27 INFO - '--tppagecycles', 16:44:27 INFO - '7'], 16:44:27 INFO - 'tests': ['tsvgm']}}, 16:44:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:44:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:44:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:44:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:44:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:44:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:44:27 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:44:27 INFO - 'talos_options': ['--disable-e10s'], 16:44:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:44:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:44:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:44:27 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:44:27 INFO - 'talos_options': ['--disable-e10s'], 16:44:27 INFO - 'tests': ['damp', 'tps']}, 16:44:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:44:27 INFO - 'tests': ['damp', 'tps']}, 16:44:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:44:27 INFO - 'tests': ['dromaeo_dom']}, 16:44:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:44:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:44:27 INFO - 'tests': ['basic_compositor_video']}, 16:44:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:44:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:44:27 INFO - 'tests': ['a11yr', 16:44:27 INFO - 'ts_paint', 16:44:27 INFO - 'tpaint', 16:44:27 INFO - 'sessionrestore', 16:44:27 INFO - 'sessionrestore_no_auto_restore', 16:44:27 INFO - 'tabpaint']}, 16:44:27 INFO - 'other-e10s': {'tests': ['a11yr', 16:44:27 INFO - 'ts_paint', 16:44:27 INFO - 'tpaint', 16:44:27 INFO - 'sessionrestore', 16:44:27 INFO - 'sessionrestore_no_auto_restore', 16:44:27 INFO - 'tabpaint']}, 16:44:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:44:27 INFO - 'ts_paint', 16:44:27 INFO - 'tpaint', 16:44:27 INFO - 'sessionrestore', 16:44:27 INFO - 'sessionrestore_no_auto_restore', 16:44:27 INFO - 'tabpaint']}, 16:44:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:44:27 INFO - 'ts_paint', 16:44:27 INFO - 'tpaint', 16:44:27 INFO - 'sessionrestore', 16:44:27 INFO - 'sessionrestore_no_auto_restore', 16:44:27 INFO - 'tabpaint']}, 16:44:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:44:27 INFO - 'tests': ['a11yr', 16:44:27 INFO - 'ts_paint', 16:44:27 INFO - 'tpaint', 16:44:27 INFO - 'sessionrestore', 16:44:27 INFO - 'sessionrestore_no_auto_restore', 16:44:27 INFO - 'tabpaint']}, 16:44:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:44:27 INFO - 'tests': ['a11yr', 16:44:27 INFO - 'ts_paint', 16:44:27 INFO - 'tpaint', 16:44:27 INFO - 'sessionrestore', 16:44:27 INFO - 'sessionrestore_no_auto_restore', 16:44:27 INFO - 'tabpaint']}, 16:44:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:44:27 INFO - 'tests': ['tsvgx', 16:44:27 INFO - 'tsvgr_opacity', 16:44:27 INFO - 'tart', 16:44:27 INFO - 'tscrollx', 16:44:27 INFO - 'cart', 16:44:27 INFO - 'tsvg_static']}, 16:44:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:44:27 INFO - 'tsvgr_opacity', 16:44:27 INFO - 'tart', 16:44:27 INFO - 'tscrollx', 16:44:27 INFO - 'cart', 16:44:27 INFO - 'tsvg_static']}, 16:44:27 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:44:27 INFO - 'talos_options': ['--disable-e10s'], 16:44:27 INFO - 'tests': ['tp5o']}, 16:44:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:44:27 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:44:27 INFO - 'talos_options': ['--disable-e10s', 16:44:27 INFO - '--xperf_path', 16:44:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:44:27 INFO - 'tests': ['tp5n']}, 16:44:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:44:27 INFO - 'talos_options': ['--xperf_path', 16:44:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:44:27 INFO - 'tests': ['tp5n']}}, 16:44:27 INFO - 'talos.zip': {'path': '', 16:44:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:44:27 INFO - Running post-action listener: _resource_record_post_action 16:44:27 INFO - [mozharness: 2017-04-12 23:44:27.093780Z] Finished populate-webroot step (success) 16:44:27 INFO - [mozharness: 2017-04-12 23:44:27.093895Z] Running create-virtualenv step. 16:44:27 INFO - Running pre-action listener: _resource_record_pre_action 16:44:27 INFO - Running main action method: create_virtualenv 16:44:27 INFO - Creating virtualenv /builds/slave/test/build/venv 16:44:27 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 16:44:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:44:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:44:27 INFO - Using real prefix '/tools/python27' 16:44:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:44:27 INFO - Installing distribute.............................................................................................................................................................................................done. 16:44:30 INFO - Installing pip.................done. 16:44:30 INFO - Return code: 0 16:44:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:44:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:44:31 INFO - Reading from file tmpfile_stdout 16:44:31 INFO - Output received: 16:44:31 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) 16:44:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:44:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106175b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106178768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9049ca9200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f53d30>, '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 0x105e7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105f48be8>, '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.fFdLf2LmNB/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.lDfsA7x1nH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 16:44:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:44:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:44:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fFdLf2LmNB/Render', 16:44:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:31 INFO - 'HOME': '/Users/cltbld', 16:44:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:31 INFO - 'LOGNAME': 'cltbld', 16:44:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:31 INFO - 'MOZ_NO_REMOTE': '1', 16:44:31 INFO - 'NO_EM_RESTART': '1', 16:44:31 INFO - 'PAGER': '/bin/cat', 16:44:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:31 INFO - 'PWD': '/builds/slave/test', 16:44:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:31 INFO - 'SHELL': '/bin/bash', 16:44:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lDfsA7x1nH/Listeners', 16:44:31 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 16:44:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:31 INFO - 'USER': 'cltbld', 16:44:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:31 INFO - 'XPC_FLAGS': '0x0', 16:44:31 INFO - 'XPC_SERVICE_NAME': '0', 16:44:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:31 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 16:44:31 INFO - Cleaning up... 16:44:31 INFO - Return code: 0 16:44:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:44:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106175b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106178768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9049ca9200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f53d30>, '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 0x105e7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105f48be8>, '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.fFdLf2LmNB/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.lDfsA7x1nH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 16:44:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:44:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:44:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fFdLf2LmNB/Render', 16:44:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:31 INFO - 'HOME': '/Users/cltbld', 16:44:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:31 INFO - 'LOGNAME': 'cltbld', 16:44:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:31 INFO - 'MOZ_NO_REMOTE': '1', 16:44:31 INFO - 'NO_EM_RESTART': '1', 16:44:31 INFO - 'PAGER': '/bin/cat', 16:44:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:31 INFO - 'PWD': '/builds/slave/test', 16:44:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:31 INFO - 'SHELL': '/bin/bash', 16:44:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lDfsA7x1nH/Listeners', 16:44:31 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 16:44:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:31 INFO - 'USER': 'cltbld', 16:44:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:31 INFO - 'XPC_FLAGS': '0x0', 16:44:31 INFO - 'XPC_SERVICE_NAME': '0', 16:44:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:31 INFO - Downloading/unpacking psutil>=3.1.1 16:44:31 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 16:44:31 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 16:44:31 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 16:44:31 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 16:44:31 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 16:44:31 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 16:44:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:44:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:44:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:44:35 INFO - Installing collected packages: psutil 16:44:35 INFO - Running setup.py install for psutil 16:44:35 INFO - building 'psutil._psutil_osx' extension 16:44:35 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 16:44:35 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 16:44:35 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 16:44:35 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 16:44:35 INFO - building 'psutil._psutil_posix' extension 16:44:35 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 16:44:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:44:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:44:35 INFO - ^ 16:44:36 INFO - 1 warning generated. 16:44:36 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 16:44:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:44:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:44:36 INFO - Successfully installed psutil 16:44:36 INFO - Cleaning up... 16:44:36 INFO - Return code: 0 16:44:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:44:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106175b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106178768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9049ca9200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f53d30>, '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 0x105e7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105f48be8>, '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.fFdLf2LmNB/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.lDfsA7x1nH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 16:44:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:44:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:44:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fFdLf2LmNB/Render', 16:44:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:36 INFO - 'HOME': '/Users/cltbld', 16:44:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:36 INFO - 'LOGNAME': 'cltbld', 16:44:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:36 INFO - 'MOZ_NO_REMOTE': '1', 16:44:36 INFO - 'NO_EM_RESTART': '1', 16:44:36 INFO - 'PAGER': '/bin/cat', 16:44:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:36 INFO - 'PWD': '/builds/slave/test', 16:44:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:36 INFO - 'SHELL': '/bin/bash', 16:44:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lDfsA7x1nH/Listeners', 16:44:36 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 16:44:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:36 INFO - 'USER': 'cltbld', 16:44:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:36 INFO - 'XPC_FLAGS': '0x0', 16:44:36 INFO - 'XPC_SERVICE_NAME': '0', 16:44:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:44:36 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 16:44:36 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 16:44:36 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 16:44:36 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 16:44:36 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 16:44:36 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 16:44:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:44:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:44:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:44:39 INFO - Installing collected packages: mozsystemmonitor 16:44:39 INFO - Running setup.py install for mozsystemmonitor 16:44:39 INFO - Successfully installed mozsystemmonitor 16:44:39 INFO - Cleaning up... 16:44:39 INFO - Return code: 0 16:44:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:44:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106175b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106178768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9049ca9200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f53d30>, '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 0x105e7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105f48be8>, '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.fFdLf2LmNB/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.lDfsA7x1nH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 16:44:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:44:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:44:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fFdLf2LmNB/Render', 16:44:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:39 INFO - 'HOME': '/Users/cltbld', 16:44:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:39 INFO - 'LOGNAME': 'cltbld', 16:44:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:39 INFO - 'MOZ_NO_REMOTE': '1', 16:44:39 INFO - 'NO_EM_RESTART': '1', 16:44:39 INFO - 'PAGER': '/bin/cat', 16:44:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:39 INFO - 'PWD': '/builds/slave/test', 16:44:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:39 INFO - 'SHELL': '/bin/bash', 16:44:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lDfsA7x1nH/Listeners', 16:44:39 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 16:44:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:39 INFO - 'USER': 'cltbld', 16:44:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:39 INFO - 'XPC_FLAGS': '0x0', 16:44:39 INFO - 'XPC_SERVICE_NAME': '0', 16:44:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:39 INFO - Downloading/unpacking jsonschema==2.5.1 16:44:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:44:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:42 INFO - Downloading functools32-3.2.3-2.tar.gz 16:44:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:44:42 INFO - warning: no files found matching '*.txt' 16:44:42 INFO - no previously-included directories found matching 'build' 16:44:42 INFO - no previously-included directories found matching 'dist' 16:44:42 INFO - no previously-included directories found matching '.git*' 16:44:42 INFO - Installing collected packages: jsonschema, functools32 16:44:42 INFO - Running setup.py install for functools32 16:44:43 INFO - warning: no files found matching '*.txt' 16:44:43 INFO - no previously-included directories found matching 'build' 16:44:43 INFO - no previously-included directories found matching 'dist' 16:44:43 INFO - no previously-included directories found matching '.git*' 16:44:43 INFO - Successfully installed jsonschema functools32 16:44:43 INFO - Cleaning up... 16:44:43 INFO - Return code: 0 16:44:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:44:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:43 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 0x106175b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106178768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9049ca9200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f53d30>, '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 0x105e7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105f48be8>, '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.fFdLf2LmNB/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.lDfsA7x1nH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 16:44:43 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 16:44:43 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 16:44:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fFdLf2LmNB/Render', 16:44:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:43 INFO - 'HOME': '/Users/cltbld', 16:44:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:43 INFO - 'LOGNAME': 'cltbld', 16:44:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:43 INFO - 'MOZ_NO_REMOTE': '1', 16:44:43 INFO - 'NO_EM_RESTART': '1', 16:44:43 INFO - 'PAGER': '/bin/cat', 16:44:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:43 INFO - 'PWD': '/builds/slave/test', 16:44:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:43 INFO - 'SHELL': '/bin/bash', 16:44:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lDfsA7x1nH/Listeners', 16:44:43 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 16:44:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:43 INFO - 'USER': 'cltbld', 16:44:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:43 INFO - 'XPC_FLAGS': '0x0', 16:44:43 INFO - 'XPC_SERVICE_NAME': '0', 16:44:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:44:44 INFO - Cleaning up... 16:44:44 INFO - Return code: 0 16:44:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:44 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 0x106175b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106178768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9049ca9200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f53d30>, '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 0x105e7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105f48be8>, '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.fFdLf2LmNB/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.lDfsA7x1nH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 16:44:44 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 16:44:44 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 16:44:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fFdLf2LmNB/Render', 16:44:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:44 INFO - 'HOME': '/Users/cltbld', 16:44:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:44 INFO - 'LOGNAME': 'cltbld', 16:44:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:44 INFO - 'MOZ_NO_REMOTE': '1', 16:44:44 INFO - 'NO_EM_RESTART': '1', 16:44:44 INFO - 'PAGER': '/bin/cat', 16:44:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:44 INFO - 'PWD': '/builds/slave/test', 16:44:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:44 INFO - 'SHELL': '/bin/bash', 16:44:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lDfsA7x1nH/Listeners', 16:44:44 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 16:44:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:44 INFO - 'USER': 'cltbld', 16:44:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:44 INFO - 'XPC_FLAGS': '0x0', 16:44:44 INFO - 'XPC_SERVICE_NAME': '0', 16:44:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:44 INFO - Downloading/unpacking blobuploader==1.2.4 16:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:47 INFO - Downloading blobuploader-1.2.4.tar.gz 16:44:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:44:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:44:47 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 16:44:47 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 16:44:47 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 16:44:47 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 16:44:47 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 16:44:47 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 16:44:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:44:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:49 INFO - Downloading docopt-0.6.1.tar.gz 16:44:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:44:49 INFO - Installing collected packages: blobuploader, requests, docopt 16:44:49 INFO - Running setup.py install for blobuploader 16:44:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:44:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:44:49 INFO - Running setup.py install for requests 16:44:49 INFO - Running setup.py install for docopt 16:44:50 INFO - Successfully installed blobuploader requests docopt 16:44:50 INFO - Cleaning up... 16:44:50 INFO - Return code: 0 16:44:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:44: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')]} 16:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44: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')]} 16:44:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:50 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 0x106175b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106178768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9049ca9200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f53d30>, '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 0x105e7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105f48be8>, '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.fFdLf2LmNB/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.lDfsA7x1nH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 16:44:50 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 16:44:50 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 16:44:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fFdLf2LmNB/Render', 16:44:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:50 INFO - 'HOME': '/Users/cltbld', 16:44:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:50 INFO - 'LOGNAME': 'cltbld', 16:44:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:50 INFO - 'MOZ_NO_REMOTE': '1', 16:44:50 INFO - 'NO_EM_RESTART': '1', 16:44:50 INFO - 'PAGER': '/bin/cat', 16:44:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:50 INFO - 'PWD': '/builds/slave/test', 16:44:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:50 INFO - 'SHELL': '/bin/bash', 16:44:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lDfsA7x1nH/Listeners', 16:44:50 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 16:44:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:50 INFO - 'USER': 'cltbld', 16:44:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:50 INFO - 'XPC_FLAGS': '0x0', 16:44:50 INFO - 'XPC_SERVICE_NAME': '0', 16:44:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:44:50 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-vP7SlO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:44:50 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-ndByXF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:44:50 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-34szHy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:44:50 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-iXSsog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:44:50 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-GmOGeR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:44:50 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-CTS9mH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:44:50 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-rgmAmr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:44:51 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-BograZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:44:51 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-dgkvkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:44:51 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-x8ZpYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:44:51 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-SzNkC3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:44:51 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-_aBknc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:44:51 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-s_x7eb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:44:51 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-T2HUH_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:44:51 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-Op2OSP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:44:51 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-Dqz0tY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:44:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:44:52 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-o3zXiS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:44:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:44:52 INFO - Running setup.py install for manifestparser 16:44:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:44:52 INFO - Running setup.py install for mozcrash 16:44:52 INFO - Running setup.py install for mozdebug 16:44:52 INFO - Running setup.py install for mozdevice 16:44:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:44:52 INFO - Running setup.py install for mozfile 16:44:52 INFO - Running setup.py install for mozhttpd 16:44:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:44:52 INFO - Running setup.py install for mozinfo 16:44:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:44:53 INFO - Running setup.py install for mozInstall 16:44:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:44:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:44:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:44:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:44:53 INFO - Running setup.py install for mozleak 16:44:53 INFO - Running setup.py install for mozlog 16:44:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:44:53 INFO - Running setup.py install for moznetwork 16:44:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:44:53 INFO - Running setup.py install for mozprocess 16:44:53 INFO - Running setup.py install for mozprofile 16:44:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:44:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:44:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:44:54 INFO - Running setup.py install for mozrunner 16:44:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:44:54 INFO - Running setup.py install for mozscreenshot 16:44:54 INFO - Running setup.py install for moztest 16:44:54 INFO - Running setup.py install for mozversion 16:44:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:44:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:44:54 INFO - Cleaning up... 16:44:54 INFO - Return code: 0 16:44:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:44:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:54 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 0x106175b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106178768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9049ca9200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f53d30>, '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 0x105e7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105f48be8>, '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.fFdLf2LmNB/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.lDfsA7x1nH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 16:44:54 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 16:44:54 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 16:44:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fFdLf2LmNB/Render', 16:44:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:54 INFO - 'HOME': '/Users/cltbld', 16:44:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:54 INFO - 'LOGNAME': 'cltbld', 16:44:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:54 INFO - 'MOZ_NO_REMOTE': '1', 16:44:54 INFO - 'NO_EM_RESTART': '1', 16:44:54 INFO - 'PAGER': '/bin/cat', 16:44:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:54 INFO - 'PWD': '/builds/slave/test', 16:44:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:54 INFO - 'SHELL': '/bin/bash', 16:44:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lDfsA7x1nH/Listeners', 16:44:54 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 16:44:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:54 INFO - 'USER': 'cltbld', 16:44:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:54 INFO - 'XPC_FLAGS': '0x0', 16:44:54 INFO - 'XPC_SERVICE_NAME': '0', 16:44:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:44:54 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-5YmXe8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:44:55 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)) 16:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:44:55 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-2hBf9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:44:55 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)) 16:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:44:55 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-JxmMvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:44:55 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)) 16:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:44:55 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-GG9IVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:44:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:44:55 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-GBdUcy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:44:55 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)) 16:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:44:55 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-0wzDRb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:44:55 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)) 16:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:44:55 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-YQG3t6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:44:55 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)) 16:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:44:55 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-zqprjv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:44:55 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)) 16:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:44:55 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-qNnker-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:44:55 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)) 16:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:44:55 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-6KXEnU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:44:56 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)) 16:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:44:56 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-ZflHff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:44:56 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)) 16:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:44:56 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-TXDzXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:44:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:44:56 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-4HpgfD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:44:56 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)) 16:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:44:56 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-bkXQta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:44:56 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)) 16:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:44:56 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-bAdKFq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:44:56 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)) 16:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:44:56 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-584MDn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:44:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:44:56 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-m5_5vw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:44:56 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)) 16:44:56 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)) 16:44:56 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)) 16:44:56 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)) 16:44:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:44:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:44:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:44: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 16:44: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 16:44: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 16:44: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 16:44: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 16:44: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 16:44:59 INFO - Downloading blessings-1.6.tar.gz 16:44:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:44:59 INFO - Installing collected packages: blessings 16:44:59 INFO - Running setup.py install for blessings 16:44:59 INFO - Successfully installed blessings 16:44:59 INFO - Cleaning up... 16:44:59 INFO - Return code: 0 16:44:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:44:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:44:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:45:00 INFO - Reading from file tmpfile_stdout 16:45:00 INFO - Current package versions: 16:45:00 INFO - blessings == 1.6 16:45:00 INFO - blobuploader == 1.2.4 16:45:00 INFO - docopt == 0.6.1 16:45:00 INFO - functools32 == 3.2.3-2 16:45:00 INFO - jsonschema == 2.5.1 16:45:00 INFO - manifestparser == 1.1 16:45:00 INFO - mozInstall == 1.12 16:45:00 INFO - mozcrash == 1.0 16:45:00 INFO - mozdebug == 0.1 16:45:00 INFO - mozdevice == 0.50 16:45:00 INFO - mozfile == 1.2 16:45:00 INFO - mozhttpd == 0.7 16:45:00 INFO - mozinfo == 0.9 16:45:00 INFO - mozleak == 0.1 16:45:00 INFO - mozlog == 3.4 16:45:00 INFO - moznetwork == 0.27 16:45:00 INFO - mozprocess == 0.25 16:45:00 INFO - mozprofile == 0.28 16:45:00 INFO - mozrunner == 6.13 16:45:00 INFO - mozscreenshot == 0.1 16:45:00 INFO - mozsystemmonitor == 0.3 16:45:00 INFO - moztest == 0.8 16:45:00 INFO - mozversion == 1.4 16:45:00 INFO - psutil == 3.1.1 16:45:00 INFO - requests == 1.2.3 16:45:00 INFO - wsgiref == 0.1.2 16:45:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:45:00 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')]} 16:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:00 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')]} 16:45:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:00 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 0x106175b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106178768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9049ca9200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f53d30>, '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 0x105e7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105f48be8>, '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.fFdLf2LmNB/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.lDfsA7x1nH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 16:45:00 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 16:45:00 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 16:45:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fFdLf2LmNB/Render', 16:45:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:00 INFO - 'HOME': '/Users/cltbld', 16:45:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:00 INFO - 'LOGNAME': 'cltbld', 16:45:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:00 INFO - 'MOZ_NO_REMOTE': '1', 16:45:00 INFO - 'NO_EM_RESTART': '1', 16:45:00 INFO - 'PAGER': '/bin/cat', 16:45:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:00 INFO - 'PWD': '/builds/slave/test', 16:45:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:00 INFO - 'SHELL': '/bin/bash', 16:45:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lDfsA7x1nH/Listeners', 16:45:00 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 16:45:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:00 INFO - 'USER': 'cltbld', 16:45:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:00 INFO - 'XPC_FLAGS': '0x0', 16:45:00 INFO - 'XPC_SERVICE_NAME': '0', 16:45:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:00 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)) 16:45:00 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)) 16:45:00 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)) 16:45:00 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)) 16:45:00 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)) 16:45:00 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)) 16:45:00 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)) 16:45:00 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)) 16:45:00 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)) 16:45:00 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)) 16:45:00 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)) 16:45:00 INFO - Cleaning up... 16:45:00 INFO - Return code: 0 16:45:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:45:00 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')]} 16:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:00 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')]} 16:45:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:00 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 0x106175b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106178768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9049ca9200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f53d30>, '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 0x105e7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105f48be8>, '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.fFdLf2LmNB/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.lDfsA7x1nH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 16:45:00 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 16:45:00 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 16:45:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fFdLf2LmNB/Render', 16:45:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:00 INFO - 'HOME': '/Users/cltbld', 16:45:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:00 INFO - 'LOGNAME': 'cltbld', 16:45:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:00 INFO - 'MOZ_NO_REMOTE': '1', 16:45:00 INFO - 'NO_EM_RESTART': '1', 16:45:00 INFO - 'PAGER': '/bin/cat', 16:45:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:00 INFO - 'PWD': '/builds/slave/test', 16:45:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:00 INFO - 'SHELL': '/bin/bash', 16:45:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lDfsA7x1nH/Listeners', 16:45:00 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 16:45:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:00 INFO - 'USER': 'cltbld', 16:45:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:00 INFO - 'XPC_FLAGS': '0x0', 16:45:00 INFO - 'XPC_SERVICE_NAME': '0', 16:45:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:45:00 INFO - Cleaning up... 16:45:00 INFO - Return code: 0 16:45:00 INFO - Running post-action listener: _resource_record_post_action 16:45:00 INFO - Running post-action listener: _start_resource_monitoring 16:45:00 INFO - Starting resource monitoring. 16:45:00 INFO - [mozharness: 2017-04-12 23:45:00.652100Z] Finished create-virtualenv step (success) 16:45:00 INFO - [mozharness: 2017-04-12 23:45:00.653035Z] Running install step. 16:45:00 INFO - Running pre-action listener: _resource_record_pre_action 16:45:00 INFO - Running main action method: install 16:45:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:45:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:45:00 INFO - Reading from file tmpfile_stdout 16:45:00 INFO - Detecting whether we're running mozinstall >=1.0... 16:45:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:45:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:45:01 INFO - Reading from file tmpfile_stdout 16:45:01 INFO - Output received: 16:45:01 INFO - Usage: mozinstall [options] installer 16:45:01 INFO - Options: 16:45:01 INFO - -h, --help show this help message and exit 16:45:01 INFO - -d DEST, --destination=DEST 16:45:01 INFO - Directory to install application into. [default: 16:45:01 INFO - "/builds/slave/test"] 16:45:01 INFO - --app=APP Application being installed. [default: firefox] 16:45:01 INFO - mkdir: /builds/slave/test/build/application 16:45:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:45:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:45:20 INFO - Reading from file tmpfile_stdout 16:45:20 INFO - Output received: 16:45:20 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:45:20 INFO - Running post-action listener: _resource_record_post_action 16:45:20 INFO - [mozharness: 2017-04-12 23:45:20.457780Z] Finished install step (success) 16:45:20 INFO - [mozharness: 2017-04-12 23:45:20.457918Z] Running run-tests step. 16:45:20 INFO - Running pre-action listener: _resource_record_pre_action 16:45:20 INFO - Running main action method: run_tests 16:45:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:45:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:45:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:45:20 INFO - 2017-04-12 16:45:20.472 screenresolution[1625:7328] starting screenresolution argv=screenresolution get 16:45:20 INFO - 2017-04-12 16:45:20.475 screenresolution[1625:7328] Display 0: 1600x1200x32@60 16:45:20 INFO - 2017-04-12 16:45:20.480 screenresolution[1626:7331] starting screenresolution argv=screenresolution list 16:45:20 INFO - Available Modes on Display 0 16:45:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:45:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:45:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:45:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:45:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:45:20 INFO - Intel Iris: 16:45:20 INFO - Chipset Model: Intel Iris 16:45:20 INFO - Type: GPU 16:45:20 INFO - Bus: Built-In 16:45:20 INFO - VRAM (Dynamic, Max): 1536 MB 16:45:20 INFO - Vendor: Intel (0x8086) 16:45:20 INFO - Device ID: 0x0a2e 16:45:20 INFO - Revision ID: 0x0009 16:45:20 INFO - Displays: 16:45:20 INFO - Display: 16:45:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:45:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:45:20 INFO - Display Serial Number: 1600x1200 60 16:45:20 INFO - Main Display: Yes 16:45:20 INFO - Mirror: Off 16:45:20 INFO - Online: Yes 16:45:20 INFO - Rotation: Supported 16:45:20 INFO - Return code: 0 16:45:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:45:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:45:20 INFO - Python 2.7.3 16:45:20 INFO - Return code: 0 16:45:20 INFO - grabbing minidump binary from tooltool 16:45: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')]} 16:45:20 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 0x105f53d30>, '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 0x105e7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105f48be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:45:20 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 16:45:20 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 16:45:20 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 16:45:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:45:20 INFO - Return code: 0 16:45:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:45:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:45:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:45:20 INFO - ENV: RUST_BACKTRACE is now 1 16:45:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:45:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:45:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491939968/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0222', '--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 16:45:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491939968/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0222 --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 16:45:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fFdLf2LmNB/Render', 16:45:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:20 INFO - 'HOME': '/Users/cltbld', 16:45:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:20 INFO - 'LOGNAME': 'cltbld', 16:45:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:45:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:45:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:20 INFO - 'MOZ_NO_REMOTE': '1', 16:45:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:45:20 INFO - 'NO_EM_RESTART': '1', 16:45:20 INFO - 'PAGER': '/bin/cat', 16:45:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:20 INFO - 'PWD': '/builds/slave/test', 16:45:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:45:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:20 INFO - 'RUST_BACKTRACE': '1', 16:45:20 INFO - 'SHELL': '/bin/bash', 16:45:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lDfsA7x1nH/Listeners', 16:45:20 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 16:45:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:20 INFO - 'USER': 'cltbld', 16:45:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:20 INFO - 'XPC_FLAGS': '0x0', 16:45:20 INFO - 'XPC_SERVICE_NAME': '0', 16:45:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491939968/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0222', '--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 16:45:21 INFO - mozversion application_buildid: 20170411124608 16:45:21 INFO - mozversion application_changeset: acb4e7fbf2027ba50dfbe954bd301da5d1975404 16:45:21 INFO - mozversion application_display_name: Nightly 16:45:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:45:21 INFO - mozversion application_name: Firefox 16:45:21 INFO - mozversion application_remotingname: firefox 16:45:21 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 16:45:21 INFO - mozversion application_vendor: Mozilla 16:45:21 INFO - mozversion application_version: 55.0a1 16:45:21 INFO - mozversion platform_buildid: 20170411124608 16:45:21 INFO - mozversion platform_changeset: acb4e7fbf2027ba50dfbe954bd301da5d1975404 16:45:21 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 16:45:21 INFO - mozversion platform_version: 55.0a1 16:45:21 INFO - using testdate: 1492040721 16:45:21 INFO - actual date: 1492040721 16:45:21 INFO - starting webserver on 'localhost:49236' 16:45:21 INFO - SUITE-START | Running 2 tests 16:45:21 INFO - TEST-START | dromaeo_css 16:45:21 INFO - operating with platform_type : mac_ 16:45:21 INFO - Initialising browser for dromaeo_css test... 16:45:21 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/tmpi7_L16/profile http://localhost:49236/getInfo.html) 16:45:24 INFO - PID 1636 | 2017-04-12 16:45:24.381 plugin-container[1638:7509] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c47, name = 'com.apple.tsm.portname' 16:45:24 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:45:24 INFO - PID 1636 | 2017-04-12 16:45:24.383 plugin-container[1638:7509] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 16:45:24 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:45:24 INFO - PID 1636 | 2017-04-12 16:45:24.383 plugin-container[1638:7509] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:45:24 INFO - PID 1636 | __metrics Screen width/height:1600/1200 16:45:24 INFO - PID 1636 | colorDepth:24 16:45:24 INFO - PID 1636 | Browser inner width/height: 1024/689 16:45:24 INFO - PID 1636 | __metrics 16:45:24 ERROR - PID 1636 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 16:45:25 INFO - TEST-INFO | 1636: exit 0 16:45:25 INFO - Browser initialized. 16:45:25 INFO - Running cycle 1/1 for dromaeo_css test... 16:45:25 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/tmpi7_L16/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:45:28 INFO - PID 1639 | 2017-04-12 16:45:28.115 plugin-container[1640:7642] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 16:45:28 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:45:28 INFO - PID 1639 | 2017-04-12 16:45:28.115 plugin-container[1640:7642] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4513, name = 'com.apple.CFPasteboardClient' 16:45:28 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:45:28 INFO - PID 1639 | 2017-04-12 16:45:28.115 plugin-container[1640:7642] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:45:37 INFO - PID 1639 | RSS: Main: 235745280 16:45:37 INFO - PID 1639 | 16:48:41 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 16:48:43 INFO - PID 1639 | RSS: Main: 253853696 16:48:43 INFO - PID 1639 | 16:50:26 INFO - PID 1639 | ************************* 16:50:26 INFO - PID 1639 | A coding exception was thrown and uncaught in a Task. 16:50:26 INFO - PID 1639 | 16:50:26 ERROR - PID 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:50:26 INFO - PID 1639 | Full stack: 16:50:26 INFO - PID 1639 | ************************* 16:50:26 INFO - PID 1639 | ************************* 16:50:26 INFO - PID 1639 | A coding exception was thrown and uncaught in a Task. 16:50:26 INFO - PID 1639 | 16:50:26 ERROR - PID 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:50:26 INFO - PID 1639 | Full stack: 16:50:26 INFO - PID 1639 | ************************* 16:50:26 INFO - PID 1639 | ************************* 16:50:26 INFO - PID 1639 | A coding exception was thrown and uncaught in a Task. 16:50:26 INFO - PID 1639 | 16:50:26 ERROR - PID 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:50:26 INFO - PID 1639 | Full stack: 16:50:26 INFO - PID 1639 | ************************* 16:50:26 INFO - PID 1639 | ************************* 16:50:26 INFO - PID 1639 | A coding exception was thrown and uncaught in a Task. 16:50:26 INFO - PID 1639 | 16:50:26 ERROR - PID 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:50:26 INFO - PID 1639 | Full stack: 16:50:26 INFO - PID 1639 | ************************* 16:51:45 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 16:51:45 INFO - PID 1639 | RSS: Main: 261980160 16:51:45 INFO - PID 1639 | 16:54:47 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 16:54:48 INFO - PID 1639 | RSS: Main: 274288640 16:54:48 INFO - PID 1639 | 16:57:50 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 16:57:50 INFO - PID 1639 | RSS: Main: 266690560 16:57:50 INFO - PID 1639 | 17:00:53 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 17:00:54 INFO - PID 1639 | RSS: Main: 276889600 17:00:54 INFO - PID 1639 | 17:03:56 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 17:03:56 INFO - PID 1639 | RSS: Main: 270618624 17:03:56 INFO - PID 1639 | 17:03:56 INFO - PID 1639 | __start_tp_report 17:03:56 INFO - PID 1639 | _x_x_mozilla_page_load 17:03:56 INFO - PID 1639 | _x_x_mozilla_page_load_details 17:03:56 INFO - PID 1639 | |i|pagename|runs| 17:03:56 INFO - PID 1639 | |0;dojo.html;2747.6840163381216;3543.7696549724283;3549.059499232703;3585.498030275761;3588.1926566522534;6125.662799505643;6249;6281.371862813722;6285.0572414137905;6311.495080393569;6193.845153871155;6226.128342032119;6250.187475628162;6252.749890004406;6302.369763023696;5466.863328416802;5543.362513310981;5550.111982082867;5608.10270356743;5608.9439105609035;4706.552877476634;5609.9902017636805;5665.688387138705;5669.348024977118;5710.857228569298;5342.252084708144;5470.699111548864;5560.110382338807;5570.749316280777;5629.155626655994;5441.047816632074;5528.391876893538;5531.363893152299;5549.750261238254;5587.552995760329;5450.264214331054;5454.209139674765;5469.835904922819;5498.257735205736;5504.146857237135;6151.630902145841;6155.292141403751;6277.529185311074;6305.4009869062365;6315.526335524854;6587.670616469205;6675.566088204251;6752.864942701119;6762.120924279861;6775.62734049627;6846.726130954756;6850.794476165723;6858.759943402007;6870.553414028072;6876.587404755681;5268.2097685346735;6104.610739188311;6375.426211640928;6384.872302553969;6394.84012899672;5808.332041815245;6824.658767061627;6910.378065974087;6947.7220911162985;6983.092198014328;6368.968155159288;6434.292227854932;6440.8389790255615;6553.606783593;6600.240972288152;6648.501362397743;6701.664916754143;6755.628440435725;6778.76274330406;6823.351781580742;6697.531172817856;6713.295104014106;6713.69788359525;6719.764808231688;6732.697028633724;6481.222253329631;6564.343565643398;6661.633610151392;6679.198496180489;6723.327667233238;5370.677759334452;5386.946130538645;5400.378956419983;5412.485813847678;5427.185922111715;4642;4803.711777293373;4813.253945638432;4821.710697358099;4850.296706977469;5105.6936583804345;5304.204369344629;6184.474319682878;6214.316425193225;6334.271558770708;6829.829254268584;6867.656617169122;6870.587764734032;6873.5875847449315;6885.277045910208;6305.842353941096;6313.715882785285;6349.365063493614;6356.491480681534;6357.459615932605;5884.499817515559;5955.434233747787;5964.701764911737;5970.940290597038;6040.6677632730625;6840.384365407237;6942.722291108299;6969.965150174216;6970.895436568557;6976.197737260077;6667.49993750461;6850.965745171242;6856.280090590416;6874.9656251718425;6891.173059232924;6149.538784591263;6252.749890004349;6404.135441715309;6959.19969203548;6973.96513017452;6570.8028759137305;6662.500312476487;6715.9590263509235;6723.394894459522;6775.62734049632;5533.723313834373;5659.858503537444;6832.077669514552;6888.173419189729;6917.896231556631;5350.6254562180275;6408.551401401857;6514.120593719787;6570;6648.966755166193;6623.867522649423;6638.137042184485;6649.73401063952;6651.866962660816;6657.667116644246;6115.052166914135;6345.936540634534;6373.362663733589;6426.260979987174;6432.549721519449;6546.738130474728;6602.042703808017;6609;6610.735570577315;6612.140421745143;6454.290028097;6461.741530338733;6469.967650161907;6475.352464753487;6481.449076828522 17:03:56 INFO - PID 1639 | |1;ext.html;679.8674258519587;687.7077242172077;692.4356649330795;1012.8987101289867;1265.9620211393656;37485.68800091994;38461.23077538447;38467.845964621105;38477.61522384775;38626.61373386262;37949.81025094871;38158.23683526328;38306.8084659577;38323;38368.04079898004;8041.919580804205;8185.918140818605;8651.48091114532;8873.29013678904;8904.599293031797;8600.698975535857;8687.218150366465;8693.391462597621;8694.782630434227;8713.520756358397;8642.178992995656;8708.17272359128;8719.564021798884;8723.386173557912;8753.255973242281;8674.956625216897;8699.869501957475;8703.260222881063;8715.041345451995;8730.214280714703;8633.13668633138;8691.95654021726;8710.216080552716;8716.607752651145;8729.7817554561;37912.43131353032;38033.809830950944;38087;38128.85613431602;38167.618323816685;3540.398132317512;3561.6972557332656;3578.320119177374;3587.443946188345;3590.622984586633;31015.379692406026;31085.844570777226;31129.221769455944;31143.68856311453;31427.685723142935;3171.2230503526553;3188.6651901550235;3191.6489186189733;3191.792533485316;3206.679332066798;250119;250366;289875;291887.0811291881;292673;48017.75991120022;48475.788105296946;48619.270710938654;48769;48863.51136488661;50616.24075638868;50681;50736.74631626818;50827.74586127046;50937.490625093276;12182.451789669487;12511;14369.6407589809;14499.710005799825;14536.055156414799;11776.646700598996;11929.164958452826;11931.284122952651;11956.760864782656;11984.981276591587;11750.708611820613;12646.304453254976;13365.326934613116;13385.665358365924;13407.262600556867;6002.939970600238;6006.699665016732;6022.819315420545;6043.884640375921;6050.9394906050375;1002.4085789384299;1011.716719318592;1040.2198351236573;1059.1315121600214;1090.6073813427317;20450;23502;24258.393540161283;24282.392940176636;24622.507549848902;1136.2898188632105;1162.1864694713734;1168.6727716239466;1172.2380452705809;1181.8818118188112;12132.939335303443;12143.089268304913;12480.063995200397;16393.754093688603;16574.17129143538;384210.57894210354;385537;385704.07147964643;387147;387324;57133.714331428906;57443.425565743804;57551;57573.42426575765;57588.424115759146;1500.18240059168;1508.064999700171;1540.5763415060724;1548.434821290221;1556.2685537797217;29864;29902.401951960404;30129.94545190908;30443;30451.086467406014;37565.93387363612;37715.43426848545;37827.05432364213;37891.62108378881;37907.81046094752;320488;321731;321846;323368;325035;28455.715442846136;28754.712452875203;33904.15239619029;36193.45709814408;37166.62833371632;45070.549294506636;47765.04469910652;48282;48789.75605121952;49136.75431622819;32632.34735305328;32770.52532635989;33001.83499082489;33062.83468582642;33585.83207083949;31930.68069319277;33141.66858331482;33500.659973600785;33576.832115839265;33679.83160084184;39377.60622393739;39680.60319396769;39874.800625996686;39922.201555969295;40077.1984560313;30911.2272193197;31062.534061988637;31752.682473174973;31797;31880.36239275248;23902.163424280763;24965.001399943798;25504.617430738926;26020;26092.217233483025 17:03:56 INFO - PID 1639 | |2;jquery.html;411.2022676008543;412.8782009307254;431.1635427271096;433.1207648473598;439.4550757061243;18545.814541854594;21007.894960525195;22681.77318226817;22874.1994030209;23061.654075188886;22871.97076131574;23058.65412018821;23309.65035524464;23397.532049359004;23626.5274694506;6183.412575805291;6262.780802671908;6405.487560995132;6462.25684046336;6463.353664633546;6172.413620706048;6436.292007879129;6497.1553698019425;6507.7071531781185;6513.641749703765;6235.220597425322;6332.081848132018;6444.935550644504;6449.484041276686;6452.774152904649;5443.8094666686475;5472.480114389126;6375.489960803125;6384;6387.169667943161;5525.668459892419;5680.488756011938;5707.686077265748;5712.400197979194;5723.399043100456;28398;28841.56737648937;28856.855715721285;29138.854305728546;29303.2674183145;2218.689383486313;2261.118163916074;2280.7947284744455;2282.132789539981;2282.3153054083873;15529;18226.90886545572;18384.908075459673;18736.969466679326;18918.62162756751;1836.2838492987746;2099.3177217404404;2102.568973360457;2144.1530595414724;2149.6560550312033;125771.99999999908;174192;177247;177801;177934;11198.768135505088;12501.43743531525;12824.80762788559;13086.672833179247;13170.934145329213;16885.662286754196;17179.31282748701;17244.568885777706;17267.136643167792;17430.912845435945;9262.120098590623;9327.533623318943;9368.063193680577;9439;9487.857682134774;7332;8885.644574216958;8898.688545900863;9077.77305567366;9095.181433671001;7238.674259658223;7669.578173200527;7990.600469976478;8030.23712747288;8186.631601577943;2265.3317271404976;2270.6821045053707;2274.601944659711;2277.7266727992414;2478.6862962629834;881.9559022048871;928.744595236302;953.7758626722715;959.7523219814353;984.9064338887795;21096.261630842848;21367.14531418726;21463.46341341479;21575.13699452036;21847.235346762787;978.280963491833;980.5244456438621;995.3430735714394;1016.2225897188656;1026.1790567545934;9206.585703643352;10756.386542018603;11142.938556367098;11152.498137583829;11401.372924489233;704915;705731;712850;724257;729590;160822.39177608074;161232;164909.17545412437;167025;167109;1126.121625132398;1143.9656810295705;1170.8185231288994;1209.25026483579;1245.9501619935281;21968.90155492251;22352.105915763186;22751.431214219778;22915.65626515637;23033.309000730005;65579.34420655924;65688.34311657013;65853.67073164604;66271.66864165648;67141.32858671351;83680;87011;87258.5637071836;88379.55810220908;95838.89530494592;9038.593263303035;9105.499197544199;9122;9222.354435189472;9232.861507077534;13734.175949443066;14338.354774035402;14782.408703651734;14948.775768363266;15088.54734357971;11116.388598627153;11839.940800295943;12307.076969227164;12335.259884406963;12485.500579976699;8631.525266110424;9567.473788941397;11639.53441862316;11771;12034.63896083119;10344.37933723979;11612.606487221587;12266.325352105363;12321;12406.379681016093;7825.843483130419;9137.40606860552;9139.954300228455;9173.77065573366;9201.263898888206;11906.642800715992;11968.820467692818;12154.270743755404;12200.328981906081;12205.877941220475 17:03:56 INFO - PID 1639 | |3;mootools.html;1299.9415026323813;1395.6510872281929;1419.6379923119603;1425.6222101143205;1471.8233811942569;11412.429378531071;11846.466908989094;11908.702282442933;12008.339541325224;12204.633860984162;11839.171258011942;11981.64055078349;11998.7600247995;12019.759604807921;12028;2342.999999999996;2377.1561095811;2572.717001129874;2576.9613455798085;2578.4327447961514;2482.441450673602;2499.9250322361377;2549.1332946798084;2549.1332946798084;2551.5407226699185;2467.8154485846803;2517.974820251792;2527.5955847064474;2534.087728417768;2540.2506260653154;2125.2774056820754;2448.8408253463467;2502.199296225209;2509.008941468119;2531.936701582457;2092.654711972523;2442.853428794278;2522.2181123851665;2526.4820711754046;2557.8465292082346;11923;11934.224275422157;12067.818982715266;12087;12095.818562721566;6640.900386494206;6867.141607299088;6924.549904256207;6931.653417329113;6932.23745388012;4121.814518346681;4414.028913638995;4446.110777844444;4491.820327186909;4505.121501307246;1257.7547378261243;1260.0045963688674;1265.4621785741024;1291.9031072669588;1308.045127057244;15142.470013549582;15656.686866262611;16617.83382166175;16710.4151354702;16805.24376403065;5584.720763961785;5779.248697669276;5948.791792287336;5955.196048533393;5965.940340596539;11095.667129986114;11800.701922788487;12352.567660131852;12400.689982750504;12416.689582760322;6603.174603174603;6654.26803051664;6658.03458498515;6661;6676.298988606223;6045.758169673251;6116.418940200674;6145.600535965148;6178.351273116258;6255.311915689271;4340.305551111807;4447.7998490067375;4457.331400289918;4492.483364413069;4493.932591011137;1297.9740405192033;1314.901382396305;1332.6135420728074;1340.3231368159018;1344.9798253026213;778.3345239819872;789.1871372486348;804.4449329962424;805.5408417202256;813.1218284252992;6116.296625887991;6163.322034576193;6989.860202796017;7125.1093613298335;7194.532355396883;930.413839281262;934.2992755433424;935.5649622925372;950.2587981374539;979.9559019843981;3945.64489195968;4128.174365126986;4224.854250991997;4260.211860805761;4281.73688761816;14600.269986500633;15134.318955647022;15218.315175816893;15218.391264349302;15277.236138193046;14992.325345359484;15160.848391516165;15162.545123646309;15176.24118794078;15177.6205594861;10644.115405877896;10909.890901091047;10966.177536684781;11110.777784444266;11110.944445277722;5171.198464238049;5719.799807006902;6891.483138764713;6910.481713871581;6982.546134501241;12599.622011339674;12661.366931653565;12677.366131693563;12788.680282993;12797.36013199355;11149.609763658233;13088.149270297401;13116.803247951097;13171.538996135088;13255.933720331337;9450.43297402158;9506.287028472754;9708.951455242679;9780;9838.114569688762;11181.496832642388;11223.438828058728;11297.548098075986;11301.660950171508;11315.773684526428;7744.578695712575;9127.452352858849;9356.578953947224;9392.201662858733;9400.29497787655;7639.388848891964;9139.497327647043;9144.63421463134;9145;9239.953800231226;8080.232377924088;8111.432199745961;8175.345972322319;8176.509409435453;8179.83640327202;5182.093133701512;5239.345081864767;5262.526372626482;5264.52619264268;5289.841304760864;5849.093390524476;5964.463198311999;5998.9100163496705;6062.272527296753;6232.950564711992 17:03:56 INFO - PID 1639 | |4;prototype.html;360.70602458995916;397.0807580451255;416.0493272871489;419.2662840029948;421.3868820865639;6193.752249909998;6402.935970640292;6440.227172739272;6476.3199864014305;6545.509086818483;6140.7850725224625;6178.783742569011;6236.438720515152;6309.49524038077;6345.809625711225;4627.3984382030285;4802.495737947517;4802.783874725646;4812.786118385547;4859.489753575876;4049.3318602430563;4436.401085853417;4611.953880461186;4641.651876109288;4845.588125009378;3437.98579419072;3800.353939830222;4199.874003779892;4553.043860789222;4634.791434385461;4195.517515485728;4291;4379.277419225824;4621.4223222097235;4649.558291962258;3303.702666759979;3880.3985382265796;3914.4983269240315;3978.5026871641044;3981.223661386031;5010.649254552182;6167.537434692416;6190.876182476325;6214;6245.594036387665;4559.316102584606;5047.419546752134;5230.398504172031;5345.144776835727;5516.3380394352935;5430.049741295257;5934.495567876693;6013.707052983629;6720.865582688368;6777.254502004825;4493.640508759291;4502.842400515999;4508.5266047064915;4521.683482156251;4521.7739113044545;6243.62538247702;6516.022596610546;6888.621125838077;6910.965445172792;7186.425085993108;3683.852645894134;3793.6016718244186;4524.308036718147;4541.954580454153;4571.451425828922;4247.256730072225;4653.115907977473;4656.557627025427;4668.719876807402;4671.32265821454;4603.723776573416;5259.474052594786;5446.992306423324;5466.671999680032;5480.616356854982;5247.895042099137;6544.116544266558;6833.760818371333;6843.178818541807;6847.657617119125;1563.702896449671;1579.5577238373273;1583.9683206335808;1587.4681981536085;1587.9682406351808;962.3456049886145;997.8403455447094;1045.968620941373;1046.8168070587617;1060.4591658254192;6340.59057558125;6383.06379065635;6412.775552855627;6423.871522569616;6447.903281450782;6025.307089684742;6867.587944723333;7062.399696025793;7079;7118.046010905417;4863.537963893425;5659.801906933238;6240.656763878031;6322.494200464043;6359.300476947532;3047.6038115044903;3572.3034008368118;3669.5779985302033;3676.834542445543;3682.650148235913;6123.663198524124;6603.877340851971;7108.822279443055;7151.070360853056;7165.713371465187;6100.328963813977;6270.561060725797;6534.856400129958;6846.938724497711;6854.485913556404;6331.733653269456;6999.510034297652;7084.362407383257;7180.533265337736;7222.927770722225;7031;7037.331453511908;7046.089420371743;7053.365197132282;7067.858642827217;6622.304658011032;6656.866862662817;6805.659717014229;6858.5199036067;7005.229424763374;6346.111544383883;6750.818606743898;6871.209810871814;7101.68618805502;7256.564606123649;3942.8422863086303;4036.979815100906;4339.699959021724;4546.976930190681;4606.309053641847;6081.604695694766;6515.381038801306;6635.1374321339845;6739.898901516579;7099.751508697171;6001.729922153426;6733.158355205599;6817.977303404331;6864.5881247125335;6932.925396563541;5748.913766293425;6264.812055638338;6326.703025879591;6827.795166145023;6839.350261725128;5471.6169868108855;6620.602763834186;6674.933250667431;6681.899771503334;6709.416874503254;6549.770758023446;6669.699863506251;6722.9663851680425;6809.625470599165;6878.415334696607;6395.42441180296;6638.070670106285;6643.582017958004;6669.266380698216;6669.533132680665 17:03:56 INFO - PID 1639 | |5;yui.html;777.1878387085495;819.4632515702217;841.2722994609665;864.8486514859902;868.1145231863497;14011.159330440181;14656.926715366408;16305.83694163058;16721.33114675413;16764.83235167648;16469.505914822545;16894.915525422388;17082.231299591516;17125;17545.82454175458;962.7208109648211;998.5356809083775;1005.8843233028185;1007.6573964851949;1010.4695035106554;994.567363197008;1010.5048526222172;1011.4234886114917;1013.4628646817199;1015.3197357770275;900.9369344145913;1049.7533079726259;1054.1356088007838;1055.5777688924454;1055.6674647486057;1044.5143008501007;1046.670298855858;1049.04536871447;1049.9002594753485;1050.558765318568;774.5430196184278;774.7675697290847;777.215012837033;777.4052849570083;778.493978913705;14409;16311.021338719715;16377.005013470887;17149.14254287293;17334.306627734877;16212.351505939749;16655.91672041644;16775;16975.15124243795;16998.660026799396;13494.460221591125;13871.930640346834;13918.791218131635;14085.929570352186;14098.436062557588;3350.664933506654;3364.6467120952193;3381.7970921744527;3389.4915762635555;3415.9146021349416;114137;114274.85725142725;114851;115939.42030289878;117249;12071.939640301742;12147.635570932885;12241.877581224251;12309.26144431337;12331.815022774666;19003;20652.07065682048;20689.482762931046;20733.68899466479;20747;8484.618192181366;8820.470771753715;8825;8847.292216622654;8854.335924805666;4741.834035808799;4802.975985120052;4819;4836.274558816135;4845.030993801254;4205.516365617932;4377.430933978562;4380.802863871133;4385.254506733863;4390.3414487827085;930.5998420679173;962.7978124593774;971.4996776660025;972.1687956796837;972.7762614598682;646.7679071369049;649.555054787472;654;658.5488940076063;688.1776277348621;10333.48332583383;10965.56137754481;11690.649280521598;11831.616614440409;11892.345920974429;518.6447283610738;577.3706659740902;648.5265755998148;659.2128223356846;684.0525871667776;5181.6890986540175;5415.296011518556;5436.184572314105;5520.944790552124;5633.6619802811965;105650.47174764231;107293;107394;108779;110066.349004765;95165.57251641231;95320;96196;96535.51732241294;96758;58776;65040.02439963404;69946.99999999898;81617;81934.59032704917;10868.836967445653;11118.777624447304;11149;11182.161337899854;11287.7742445149;42584.78707606442;42860.14279714451;43063.354049688656;43782.34326485169;44412.555874442136;32573.674263257064;56605.71697141488;56698.7165064172;56996.45565570383;58141.127883082634;13965.511207107702;16171.67656646884;16857.578560536083;16892.324307027582;16923.91538042302;17752.28990840352;18017.279308828023;18082.98767613549;18270;18537.721934170728;16510.83489165093;16901.32394704198;16994.66010679804;17004.064776437415;17173.398931037354;15593.76609350838;16260.268287926836;16353.018818870905;16480.17599120015;17384.47846564605;15176.468823591122;16456.094914779802;16680.833191668415;16788;16834;10715.839262410915;11608.187426880375;11736.723191895915;12237.938810305892;12344.506219751109;11195;11708.88291117078;12993.675158121123;13027.869721303045;13043.28261945602 17:03:56 INFO - PID 1639 | __end_tp_report 17:03:56 INFO - PID 1639 | __start_cc_report 17:03:56 INFO - PID 1639 | _x_x_mozilla_cycle_collect,430 17:03:56 INFO - PID 1639 | __end_cc_report 17:03:56 INFO - PID 1639 | __startTimestamp1492041836445__endTimestamp 17:03:56 INFO - PID 1639 | 17:03:56 INFO - PID 1639 | ------- Summary: start ------- 17:03:56 INFO - PID 1639 | Number of tests: 6 17:03:56 INFO - PID 1639 | 17:03:56 INFO - PID 1639 | [#0] dojo.html Cycles:165 Average:6167.06 Median:6375.43 stddev:756.68 (11.9%) stddev-sans-first:709.87 17:03:56 INFO - PID 1639 | Values: 2747.7 3543.8 3549.1 3585.5 3588.2 6125.7 6249.0 6281.4 6285.1 6311.5 6193.8 6226.1 6250.2 6252.7 6302.4 5466.9 5543.4 5550.1 5608.1 5608.9 4706.6 5610.0 5665.7 5669.3 5710.9 5342.3 5470.7 5560.1 5570.7 5629.2 5441.0 5528.4 5531.4 5549.8 5587.6 5450.3 5454.2 5469.8 5498.3 5504.1 6151.6 6155.3 6277.5 6305.4 6315.5 6587.7 6675.6 6752.9 6762.1 6775.6 6846.7 6850.8 6858.8 6870.6 6876.6 5268.2 6104.6 6375.4 6384.9 6394.8 5808.3 6824.7 6910.4 6947.7 6983.1 6369.0 6434.3 6440.8 6553.6 6600.2 6648.5 6701.7 6755.6 6778.8 6823.4 6697.5 6713.3 6713.7 6719.8 6732.7 6481.2 6564.3 6661.6 6679.2 6723.3 5370.7 5386.9 5400.4 5412.5 5427.2 4642.0 4803.7 4813.3 4821.7 4850.3 5105.7 5304.2 6184.5 6214.3 6334.3 6829.8 6867.7 6870.6 6873.6 6885.3 6305.8 6313.7 6349.4 6356.5 6357.5 5884.5 5955.4 5964.7 5970.9 6040.7 6840.4 6942.7 6970.0 6970.9 6976.2 6667.5 6851.0 6856.3 6875.0 6891.2 6149.5 6252.7 6404.1 6959.2 6974.0 6570.8 6662.5 6716.0 6723.4 6775.6 5533.7 5659.9 6832.1 6888.2 6917.9 5350.6 6408.6 6514.1 6570.0 6649.0 6623.9 6638.1 6649.7 6651.9 6657.7 6115.1 6345.9 6373.4 6426.3 6432.5 6546.7 6602.0 6609.0 6610.7 6612.1 6454.3 6461.7 6470.0 6475.4 6481.4 17:03:56 INFO - PID 1639 | 17:03:56 INFO - PID 1639 | [#1] ext.html Cycles:180 Average:48163.59 Median:3551.05 stddev:87149.01 (2454.2%) stddev-sans-first:87320.56 17:03:56 INFO - PID 1639 | Values: 679.9 687.7 692.4 1012.9 1266.0 37485.7 38461.2 38467.8 38477.6 38626.6 37949.8 38158.2 38306.8 38323.0 38368.0 8041.9 8185.9 8651.5 8873.3 8904.6 8600.7 8687.2 8693.4 8694.8 8713.5 8642.2 8708.2 8719.6 8723.4 8753.3 8675.0 8699.9 8703.3 8715.0 8730.2 8633.1 8692.0 8710.2 8716.6 8729.8 37912.4 38033.8 38087.0 38128.9 38167.6 3540.4 3561.7 3578.3 3587.4 3590.6 31015.4 31085.8 31129.2 31143.7 31427.7 3171.2 3188.7 3191.6 3191.8 3206.7 250119.0 250366.0 289875.0 291887.1 292673.0 48017.8 48475.8 48619.3 48769.0 48863.5 50616.2 50681.0 50736.7 50827.7 50937.5 12182.5 12511.0 14369.6 14499.7 14536.1 11776.6 11929.2 11931.3 11956.8 11985.0 11750.7 12646.3 13365.3 13385.7 13407.3 6002.9 6006.7 6022.8 6043.9 6050.9 1002.4 1011.7 1040.2 1059.1 1090.6 20450.0 23502.0 24258.4 24282.4 24622.5 1136.3 1162.2 1168.7 1172.2 1181.9 12132.9 12143.1 12480.1 16393.8 16574.2 384210.6 385537.0 385704.1 387147.0 387324.0 57133.7 57443.4 57551.0 57573.4 57588.4 1500.2 1508.1 1540.6 1548.4 1556.3 29864.0 29902.4 30129.9 30443.0 30451.1 37565.9 37715.4 37827.1 37891.6 37907.8 320488.0 321731.0 321846.0 323368.0 325035.0 28455.7 28754.7 33904.2 36193.5 37166.6 45070.5 47765.0 48282.0 48789.8 49136.8 32632.3 32770.5 33001.8 33062.8 33585.8 31930.7 33141.7 33500.7 33576.8 33679.8 39377.6 39680.6 39874.8 39922.2 40077.2 30911.2 31062.5 31752.7 31797.0 31880.4 23902.2 24965.0 25504.6 26020.0 26092.2 17:03:56 INFO - PID 1639 | 17:03:56 INFO - PID 1639 | [#2] jquery.html Cycles:180 Average:42313.12 Median:23353.59 stddev:120515.93 (516.0%) stddev-sans-first:120812.94 17:03:56 INFO - PID 1639 | Values: 411.2 412.9 431.2 433.1 439.5 18545.8 21007.9 22681.8 22874.2 23061.7 22872.0 23058.7 23309.7 23397.5 23626.5 6183.4 6262.8 6405.5 6462.3 6463.4 6172.4 6436.3 6497.2 6507.7 6513.6 6235.2 6332.1 6444.9 6449.5 6452.8 5443.8 5472.5 6375.5 6384.0 6387.2 5525.7 5680.5 5707.7 5712.4 5723.4 28398.0 28841.6 28856.9 29138.9 29303.3 2218.7 2261.1 2280.8 2282.1 2282.3 15529.0 18226.9 18384.9 18737.0 18918.6 1836.3 2099.3 2102.6 2144.2 2149.7 125772.0 174192.0 177247.0 177801.0 177934.0 11198.8 12501.4 12824.8 13086.7 13170.9 16885.7 17179.3 17244.6 17267.1 17430.9 9262.1 9327.5 9368.1 9439.0 9487.9 7332.0 8885.6 8898.7 9077.8 9095.2 7238.7 7669.6 7990.6 8030.2 8186.6 2265.3 2270.7 2274.6 2277.7 2478.7 882.0 928.7 953.8 959.8 984.9 21096.3 21367.1 21463.5 21575.1 21847.2 978.3 980.5 995.3 1016.2 1026.2 9206.6 10756.4 11142.9 11152.5 11401.4 704915.0 705731.0 712850.0 724257.0 729590.0 160822.4 161232.0 164909.2 167025.0 167109.0 1126.1 1144.0 1170.8 1209.3 1246.0 21968.9 22352.1 22751.4 22915.7 23033.3 65579.3 65688.3 65853.7 66271.7 67141.3 83680.0 87011.0 87258.6 88379.6 95838.9 9038.6 9105.5 9122.0 9222.4 9232.9 13734.2 14338.4 14782.4 14948.8 15088.5 11116.4 11839.9 12307.1 12335.3 12485.5 8631.5 9567.5 11639.5 11771.0 12034.6 10344.4 11612.6 12266.3 12321.0 12406.4 7825.8 9137.4 9140.0 9173.8 9201.3 11906.6 11968.8 12154.3 12200.3 12205.9 17:03:56 INFO - PID 1639 | 17:03:56 INFO - PID 1639 | [#3] mootools.html Cycles:180 Average:7183.55 Median:2554.69 stddev:4528.00 (177.2%) stddev-sans-first:4519.12 17:03:56 INFO - PID 1639 | Values: 1299.9 1395.7 1419.6 1425.6 1471.8 11412.4 11846.5 11908.7 12008.3 12204.6 11839.2 11981.6 11998.8 12019.8 12028.0 2343.0 2377.2 2572.7 2577.0 2578.4 2482.4 2499.9 2549.1 2549.1 2551.5 2467.8 2518.0 2527.6 2534.1 2540.3 2125.3 2448.8 2502.2 2509.0 2531.9 2092.7 2442.9 2522.2 2526.5 2557.8 11923.0 11934.2 12067.8 12087.0 12095.8 6640.9 6867.1 6924.5 6931.7 6932.2 4121.8 4414.0 4446.1 4491.8 4505.1 1257.8 1260.0 1265.5 1291.9 1308.0 15142.5 15656.7 16617.8 16710.4 16805.2 5584.7 5779.2 5948.8 5955.2 5965.9 11095.7 11800.7 12352.6 12400.7 12416.7 6603.2 6654.3 6658.0 6661.0 6676.3 6045.8 6116.4 6145.6 6178.4 6255.3 4340.3 4447.8 4457.3 4492.5 4493.9 1298.0 1314.9 1332.6 1340.3 1345.0 778.3 789.2 804.4 805.5 813.1 6116.3 6163.3 6989.9 7125.1 7194.5 930.4 934.3 935.6 950.3 980.0 3945.6 4128.2 4224.9 4260.2 4281.7 14600.3 15134.3 15218.3 15218.4 15277.2 14992.3 15160.8 15162.5 15176.2 15177.6 10644.1 10909.9 10966.2 11110.8 11110.9 5171.2 5719.8 6891.5 6910.5 6982.5 12599.6 12661.4 12677.4 12788.7 12797.4 11149.6 13088.1 13116.8 13171.5 13255.9 9450.4 9506.3 9709.0 9780.0 9838.1 11181.5 11223.4 11297.5 11301.7 11315.8 7744.6 9127.5 9356.6 9392.2 9400.3 7639.4 9139.5 9144.6 9145.0 9240.0 8080.2 8111.4 8175.3 8176.5 8179.8 5182.1 5239.3 5262.5 5264.5 5289.8 5849.1 5964.5 5998.9 6062.3 6233.0 17:03:56 INFO - PID 1639 | 17:03:56 INFO - PID 1639 | [#4] prototype.html Cycles:180 Average:5383.05 Median:6192.31 stddev:1720.48 (27.8%) stddev-sans-first:1683.50 17:03:56 INFO - PID 1639 | Values: 360.7 397.1 416.0 419.3 421.4 6193.8 6402.9 6440.2 6476.3 6545.5 6140.8 6178.8 6236.4 6309.5 6345.8 4627.4 4802.5 4802.8 4812.8 4859.5 4049.3 4436.4 4612.0 4641.7 4845.6 3438.0 3800.4 4199.9 4553.0 4634.8 4195.5 4291.0 4379.3 4621.4 4649.6 3303.7 3880.4 3914.5 3978.5 3981.2 5010.6 6167.5 6190.9 6214.0 6245.6 4559.3 5047.4 5230.4 5345.1 5516.3 5430.0 5934.5 6013.7 6720.9 6777.3 4493.6 4502.8 4508.5 4521.7 4521.8 6243.6 6516.0 6888.6 6911.0 7186.4 3683.9 3793.6 4524.3 4542.0 4571.5 4247.3 4653.1 4656.6 4668.7 4671.3 4603.7 5259.5 5447.0 5466.7 5480.6 5247.9 6544.1 6833.8 6843.2 6847.7 1563.7 1579.6 1584.0 1587.5 1588.0 962.3 997.8 1046.0 1046.8 1060.5 6340.6 6383.1 6412.8 6423.9 6447.9 6025.3 6867.6 7062.4 7079.0 7118.0 4863.5 5659.8 6240.7 6322.5 6359.3 3047.6 3572.3 3669.6 3676.8 3682.7 6123.7 6603.9 7108.8 7151.1 7165.7 6100.3 6270.6 6534.9 6846.9 6854.5 6331.7 6999.5 7084.4 7180.5 7222.9 7031.0 7037.3 7046.1 7053.4 7067.9 6622.3 6656.9 6805.7 6858.5 7005.2 6346.1 6750.8 6871.2 7101.7 7256.6 3942.8 4037.0 4339.7 4547.0 4606.3 6081.6 6515.4 6635.1 6739.9 7099.8 6001.7 6733.2 6818.0 6864.6 6932.9 5748.9 6264.8 6326.7 6827.8 6839.4 5471.6 6620.6 6674.9 6681.9 6709.4 6549.8 6669.7 6723.0 6809.6 6878.4 6395.4 6638.1 6643.6 6669.3 6669.5 17:03:56 INFO - PID 1639 | 17:03:56 INFO - PID 1639 | [#5] yui.html Cycles:180 Average:21211.95 Median:17137.07 stddev:29805.21 (173.9%) stddev-sans-first:29849.32 17:03:56 INFO - PID 1639 | Values: 777.2 819.5 841.3 864.8 868.1 14011.2 14656.9 16305.8 16721.3 16764.8 16469.5 16894.9 17082.2 17125.0 17545.8 962.7 998.5 1005.9 1007.7 1010.5 994.6 1010.5 1011.4 1013.5 1015.3 900.9 1049.8 1054.1 1055.6 1055.7 1044.5 1046.7 1049.0 1049.9 1050.6 774.5 774.8 777.2 777.4 778.5 14409.0 16311.0 16377.0 17149.1 17334.3 16212.4 16655.9 16775.0 16975.2 16998.7 13494.5 13871.9 13918.8 14085.9 14098.4 3350.7 3364.6 3381.8 3389.5 3415.9 114137.0 114274.9 114851.0 115939.4 117249.0 12071.9 12147.6 12241.9 12309.3 12331.8 19003.0 20652.1 20689.5 20733.7 20747.0 8484.6 8820.5 8825.0 8847.3 8854.3 4741.8 4803.0 4819.0 4836.3 4845.0 4205.5 4377.4 4380.8 4385.3 4390.3 930.6 962.8 971.5 972.2 972.8 646.8 649.6 654.0 658.5 688.2 10333.5 10965.6 11690.6 11831.6 11892.3 518.6 577.4 648.5 659.2 684.1 5181.7 5415.3 5436.2 5520.9 5633.7 105650.5 107293.0 107394.0 108779.0 110066.3 95165.6 95320.0 96196.0 96535.5 96758.0 58776.0 65040.0 69947.0 81617.0 81934.6 10868.8 11118.8 11149.0 11182.2 11287.8 42584.8 42860.1 43063.4 43782.3 44412.6 32573.7 56605.7 56698.7 56996.5 58141.1 13965.5 16171.7 16857.6 16892.3 16923.9 17752.3 18017.3 18083.0 18270.0 18537.7 16510.8 16901.3 16994.7 17004.1 17173.4 15593.8 16260.3 16353.0 16480.2 17384.5 15176.5 16456.1 16680.8 16788.0 16834.0 10715.8 11608.2 11736.7 12237.9 12344.5 11195.0 11708.9 12993.7 13027.9 13043.3 17:03:56 INFO - PID 1639 | -------- Summary: end -------- 17:03:56 INFO - PID 1639 | 17:03:57 INFO - TEST-INFO | 1639: exit 0 17:03:57 INFO - TEST-OK | dromaeo_css | took 1116132ms 17:03:57 INFO - TEST-START | kraken 17:03:57 INFO - operating with platform_type : mac_ 17:03:57 INFO - Initialising browser for kraken test... 17:03:57 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/tmp6RfcpZ/profile http://localhost:49236/getInfo.html) 17:04:00 INFO - PID 1650 | 2017-04-12 17:04:00.285 plugin-container[1651:10059] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 17:04:00 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:04:00 INFO - PID 1650 | 2017-04-12 17:04:00.287 plugin-container[1651:10059] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.CFPasteboardClient' 17:04:00 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:04:00 INFO - PID 1650 | 2017-04-12 17:04:00.287 plugin-container[1651:10059] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:04:00 INFO - PID 1650 | __metrics Screen width/height:1600/1200 17:04:00 INFO - PID 1650 | colorDepth:24 17:04:00 INFO - PID 1650 | Browser inner width/height: 1024/689 17:04:00 INFO - PID 1650 | __metrics 17:04:00 ERROR - PID 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 17:04:01 INFO - TEST-INFO | 1650: exit 0 17:04:01 INFO - Browser initialized. 17:04:01 INFO - Running cycle 1/1 for kraken test... 17:04:01 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/tmp6RfcpZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:04:04 INFO - PID 1652 | 2017-04-12 17:04:04.048 plugin-container[1653:10183] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 17:04:04 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:04:04 INFO - PID 1652 | 2017-04-12 17:04:04.048 plugin-container[1653:10183] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e1f, name = 'com.apple.CFPasteboardClient' 17:04:04 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:04:04 INFO - PID 1652 | 2017-04-12 17:04:04.048 plugin-container[1653:10183] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:04:13 INFO - PID 1652 | RSS: Main: 235929600 17:04:13 INFO - PID 1652 | 17:04:16 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 17:04:17 INFO - PID 1652 | RSS: Main: 250671104 17:04:17 INFO - PID 1652 | 17:04:20 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 17:04:20 INFO - PID 1652 | RSS: Main: 259272704 17:04:20 INFO - PID 1652 | 17:04:24 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 17:04:24 INFO - PID 1652 | RSS: Main: 261701632 17:04:24 INFO - PID 1652 | 17:04:26 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 17:04:27 INFO - PID 1652 | RSS: Main: 263544832 17:04:27 INFO - PID 1652 | 17:04:29 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:04:30 INFO - PID 1652 | RSS: Main: 263905280 17:04:30 INFO - PID 1652 | 17:04:33 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 17:04:34 INFO - PID 1652 | RSS: Main: 269185024 17:04:34 INFO - PID 1652 | 17:04:37 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 17:04:37 INFO - PID 1652 | RSS: Main: 269930496 17:04:37 INFO - PID 1652 | 17:04:41 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 17:04:41 INFO - PID 1652 | RSS: Main: 269762560 17:04:41 INFO - PID 1652 | 17:04:43 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:04:44 INFO - PID 1652 | RSS: Main: 269426688 17:04:44 INFO - PID 1652 | 17:04:45 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:04:46 INFO - PID 1652 | RSS: Main: 269393920 17:04:46 INFO - PID 1652 | 17:04:48 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:04:49 INFO - PID 1652 | RSS: Main: 269398016 17:04:49 INFO - PID 1652 | 17:04:52 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:04:53 INFO - PID 1652 | RSS: Main: 269930496 17:04:53 INFO - PID 1652 | 17:04:56 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:04:57 INFO - PID 1652 | RSS: Main: 266907648 17:04:57 INFO - PID 1652 | 17:04:59 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 17:04:59 INFO - PID 1652 | RSS: Main: 270266368 17:04:59 INFO - PID 1652 | 17:04:59 INFO - PID 1652 | __start_tp_report 17:04:59 INFO - PID 1652 | _x_x_mozilla_page_load 17:04:59 INFO - PID 1652 | _x_x_mozilla_page_load_details 17:04:59 INFO - PID 1652 | |i|pagename|runs| 17:04:59 INFO - PID 1652 | |0;ai-astar;109;122;123;121;121;123;120;122;122;120 17:04:59 INFO - PID 1652 | |1;audio-beat-detection;154;120;117;159;116;120;135;163;186;126 17:04:59 INFO - PID 1652 | |2;audio-dft;216;224;229;227;228;210;209;213;217;207 17:04:59 INFO - PID 1652 | |3;audio-fft;83;61;63;93;64;60;64;51;86;87 17:04:59 INFO - PID 1652 | |4;audio-oscillator;86;70;90;73;74;75;115;108;92;85 17:04:59 INFO - PID 1652 | |5;imaging-gaussian-blur;135;142;91;139;84;143;100;144;109;89 17:04:59 INFO - PID 1652 | |6;imaging-darkroom;101;98;102;102;102;100;102;102;101;100 17:04:59 INFO - PID 1652 | |7;imaging-desaturate;101;103;102;102;100;156;163;126;104;102 17:04:59 INFO - PID 1652 | |8;json-parse-financial;52;53;52;52;53;53;54;53;53;53 17:04:59 INFO - PID 1652 | |9;json-stringify-tinderbox;63;55;63;66;66;60;61;60;59;61 17:04:59 INFO - PID 1652 | |10;stanford-crypto-aes;101;117;113;100;91;88;88;86;101;115 17:04:59 INFO - PID 1652 | |11;stanford-crypto-ccm;154;173;144;146;181;144;160;153;205;170 17:04:59 INFO - PID 1652 | |12;stanford-crypto-pbkdf2;228;232;161;228;176;178;262;249;204;178 17:04:59 INFO - PID 1652 | |13;stanford-crypto-sha256-iterative;72;71;69;71;69;80;70;69;74;72 17:04:59 INFO - PID 1652 | __end_tp_report 17:04:59 INFO - PID 1652 | __start_cc_report 17:04:59 INFO - PID 1652 | _x_x_mozilla_cycle_collect,1018 17:04:59 INFO - PID 1652 | __end_cc_report 17:04:59 INFO - PID 1652 | __startTimestamp1492041899396__endTimestamp 17:04:59 INFO - PID 1652 | 17:04:59 INFO - PID 1652 | ------- Summary: start ------- 17:04:59 INFO - PID 1652 | Number of tests: 14 17:04:59 INFO - PID 1652 | 17:04:59 INFO - PID 1652 | [#0] ai-astar Cycles:10 Average:120.30 Median:122.00 stddev:4.11 (3.4%) stddev-sans-first:1.13 17:04:59 INFO - PID 1652 | Values: 109.0 122.0 123.0 121.0 121.0 123.0 120.0 122.0 122.0 120.0 17:04:59 INFO - PID 1652 | 17:04:59 INFO - PID 1652 | [#1] audio-beat-detection Cycles:10 Average:139.60 Median:144.50 stddev:24.33 (16.8%) stddev-sans-first:25.24 17:04:59 INFO - PID 1652 | Values: 154.0 120.0 117.0 159.0 116.0 120.0 135.0 163.0 186.0 126.0 17:04:59 INFO - PID 1652 | 17:04:59 INFO - PID 1652 | [#2] audio-dft Cycles:10 Average:218.00 Median:220.50 stddev:8.39 (3.8%) stddev-sans-first:8.87 17:04:59 INFO - PID 1652 | Values: 216.0 224.0 229.0 227.0 228.0 210.0 209.0 213.0 217.0 207.0 17:04:59 INFO - PID 1652 | 17:04:59 INFO - PID 1652 | [#3] audio-fft Cycles:10 Average:71.20 Median:73.50 stddev:14.50 (19.7%) stddev-sans-first:14.73 17:04:59 INFO - PID 1652 | Values: 83.0 61.0 63.0 93.0 64.0 60.0 64.0 51.0 86.0 87.0 17:04:59 INFO - PID 1652 | 17:04:59 INFO - PID 1652 | [#4] audio-oscillator Cycles:10 Average:86.80 Median:80.00 stddev:15.13 (18.9%) stddev-sans-first:16.05 17:04:59 INFO - PID 1652 | Values: 86.0 70.0 90.0 73.0 74.0 75.0 115.0 108.0 92.0 85.0 17:04:59 INFO - PID 1652 | 17:04:59 INFO - PID 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:117.60 Median:143.50 stddev:25.25 (17.6%) stddev-sans-first:25.98 17:04:59 INFO - PID 1652 | Values: 135.0 142.0 91.0 139.0 84.0 143.0 100.0 144.0 109.0 89.0 17:04:59 INFO - PID 1652 | 17:04:59 INFO - PID 1652 | [#6] imaging-darkroom Cycles:10 Average:101.00 Median:102.00 stddev:1.33 (1.3%) stddev-sans-first:1.41 17:04:59 INFO - PID 1652 | Values: 101.0 98.0 102.0 102.0 102.0 100.0 102.0 102.0 101.0 100.0 17:04:59 INFO - PID 1652 | 17:04:59 INFO - PID 1652 | [#7] imaging-desaturate Cycles:10 Average:115.90 Median:103.50 stddev:24.25 (23.4%) stddev-sans-first:25.11 17:04:59 INFO - PID 1652 | Values: 101.0 103.0 102.0 102.0 100.0 156.0 163.0 126.0 104.0 102.0 17:04:59 INFO - PID 1652 | 17:04:59 INFO - PID 1652 | [#8] json-parse-financial Cycles:10 Average:52.80 Median:53.00 stddev:0.63 (1.2%) stddev-sans-first:0.60 17:04:59 INFO - PID 1652 | Values: 52.0 53.0 52.0 52.0 53.0 53.0 54.0 53.0 53.0 53.0 17:04:59 INFO - PID 1652 | 17:04:59 INFO - PID 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:61.40 Median:62.00 stddev:3.31 (5.3%) stddev-sans-first:3.46 17:04:59 INFO - PID 1652 | Values: 63.0 55.0 63.0 66.0 66.0 60.0 61.0 60.0 59.0 61.0 17:04:59 INFO - PID 1652 | 17:04:59 INFO - PID 1652 | [#10] stanford-crypto-aes Cycles:10 Average:100.00 Median:101.50 stddev:11.79 (11.6%) stddev-sans-first:12.49 17:04:59 INFO - PID 1652 | Values: 101.0 117.0 113.0 100.0 91.0 88.0 88.0 86.0 101.0 115.0 17:04:59 INFO - PID 1652 | 17:04:59 INFO - PID 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:163.00 Median:165.00 stddev:19.54 (11.8%) stddev-sans-first:20.46 17:04:59 INFO - PID 1652 | Values: 154.0 173.0 144.0 146.0 181.0 144.0 160.0 153.0 205.0 170.0 17:04:59 INFO - PID 1652 | 17:04:59 INFO - PID 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:209.60 Median:228.00 stddev:34.95 (15.3%) stddev-sans-first:36.44 17:04:59 INFO - PID 1652 | Values: 228.0 232.0 161.0 228.0 176.0 178.0 262.0 249.0 204.0 178.0 17:04:59 INFO - PID 1652 | 17:04:59 INFO - PID 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.70 Median:71.50 stddev:3.33 (4.7%) stddev-sans-first:3.54 17:04:59 INFO - PID 1652 | Values: 72.0 71.0 69.0 71.0 69.0 80.0 70.0 69.0 74.0 72.0 17:04:59 INFO - PID 1652 | -------- Summary: end -------- 17:04:59 INFO - PID 1652 | 17:05:00 INFO - TEST-INFO | 1652: exit 0 17:05:00 INFO - TEST-OK | kraken | took 63260ms 17:05:00 INFO - SUITE-END | took 1179s 17:05:00 INFO - Completed test suite (00:19:39) 17:05:00 INFO - javascript benchmark 17:05:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8003.559168076721, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2747.6840163381216, 3543.7696549724283, 3549.059499232703, 3585.498030275761, 3588.1926566522534, 6125.662799505643, 6249.0, 6281.371862813722, 6285.0572414137905, 6311.495080393569, 6193.845153871155, 6226.128342032119, 6250.187475628162, 6252.749890004406, 6302.369763023696, 5466.863328416802, 5543.362513310981, 5550.111982082867, 5608.10270356743, 5608.9439105609035, 4706.552877476634, 5609.9902017636805, 5665.688387138705, 5669.348024977118, 5710.857228569298, 5342.252084708144, 5470.699111548864, 5560.110382338807, 5570.749316280777, 5629.155626655994, 5441.047816632074, 5528.391876893538, 5531.363893152299, 5549.750261238254, 5587.552995760329, 5450.264214331054, 5454.209139674765, 5469.835904922819, 5498.257735205736, 5504.146857237135, 6151.630902145841, 6155.292141403751, 6277.529185311074, 6305.4009869062365, 6315.526335524854, 6587.670616469205, 6675.566088204251, 6752.864942701119, 6762.120924279861, 6775.62734049627, 6846.726130954756, 6850.794476165723, 6858.759943402007, 6870.553414028072, 6876.587404755681, 5268.2097685346735, 6104.610739188311, 6375.426211640928, 6384.872302553969, 6394.84012899672, 5808.332041815245, 6824.658767061627, 6910.378065974087, 6947.7220911162985, 6983.092198014328, 6368.968155159288, 6434.292227854932, 6440.8389790255615, 6553.606783593, 6600.240972288152, 6648.501362397743, 6701.664916754143, 6755.628440435725, 6778.76274330406, 6823.351781580742, 6697.531172817856, 6713.295104014106, 6713.69788359525, 6719.764808231688, 6732.697028633724, 6481.222253329631, 6564.343565643398, 6661.633610151392, 6679.198496180489, 6723.327667233238, 5370.677759334452, 5386.946130538645, 5400.378956419983, 5412.485813847678, 5427.185922111715, 4642.0, 4803.711777293373, 4813.253945638432, 4821.710697358099, 4850.296706977469, 5105.6936583804345, 5304.204369344629, 6184.474319682878, 6214.316425193225, 6334.271558770708, 6829.829254268584, 6867.656617169122, 6870.587764734032, 6873.5875847449315, 6885.277045910208, 6305.842353941096, 6313.715882785285, 6349.365063493614, 6356.491480681534, 6357.459615932605, 5884.499817515559, 5955.434233747787, 5964.701764911737, 5970.940290597038, 6040.6677632730625, 6840.384365407237, 6942.722291108299, 6969.965150174216, 6970.895436568557, 6976.197737260077, 6667.49993750461, 6850.965745171242, 6856.280090590416, 6874.9656251718425, 6891.173059232924, 6149.538784591263, 6252.749890004349, 6404.135441715309, 6959.19969203548, 6973.96513017452, 6570.8028759137305, 6662.500312476487, 6715.9590263509235, 6723.394894459522, 6775.62734049632, 5533.723313834373, 5659.858503537444, 6832.077669514552, 6888.173419189729, 6917.896231556631, 5350.6254562180275, 6408.551401401857, 6514.120593719787, 6570.0, 6648.966755166193, 6623.867522649423, 6638.137042184485, 6649.73401063952, 6651.866962660816, 6657.667116644246, 6115.052166914135, 6345.936540634534, 6373.362663733589, 6426.260979987174, 6432.549721519449, 6546.738130474728, 6602.042703808017, 6609.0, 6610.735570577315, 6612.140421745143, 6454.290028097, 6461.741530338733, 6469.967650161907, 6475.352464753487, 6481.449076828522], "value": 6116.10401997821, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.8674258519587, 687.7077242172077, 692.4356649330795, 1012.8987101289867, 1265.9620211393656, 37485.68800091994, 38461.23077538447, 38467.845964621105, 38477.61522384775, 38626.61373386262, 37949.81025094871, 38158.23683526328, 38306.8084659577, 38323.0, 38368.04079898004, 8041.919580804205, 8185.918140818605, 8651.48091114532, 8873.29013678904, 8904.599293031797, 8600.698975535857, 8687.218150366465, 8693.391462597621, 8694.782630434227, 8713.520756358397, 8642.178992995656, 8708.17272359128, 8719.564021798884, 8723.386173557912, 8753.255973242281, 8674.956625216897, 8699.869501957475, 8703.260222881063, 8715.041345451995, 8730.214280714703, 8633.13668633138, 8691.95654021726, 8710.216080552716, 8716.607752651145, 8729.7817554561, 37912.43131353032, 38033.809830950944, 38087.0, 38128.85613431602, 38167.618323816685, 3540.398132317512, 3561.6972557332656, 3578.320119177374, 3587.443946188345, 3590.622984586633, 31015.379692406026, 31085.844570777226, 31129.221769455944, 31143.68856311453, 31427.685723142935, 3171.2230503526553, 3188.6651901550235, 3191.6489186189733, 3191.792533485316, 3206.679332066798, 250119.0, 250366.0, 289875.0, 291887.0811291881, 292673.0, 48017.75991120022, 48475.788105296946, 48619.270710938654, 48769.0, 48863.51136488661, 50616.24075638868, 50681.0, 50736.74631626818, 50827.74586127046, 50937.490625093276, 12182.451789669487, 12511.0, 14369.6407589809, 14499.710005799825, 14536.055156414799, 11776.646700598996, 11929.164958452826, 11931.284122952651, 11956.760864782656, 11984.981276591587, 11750.708611820613, 12646.304453254976, 13365.326934613116, 13385.665358365924, 13407.262600556867, 6002.939970600238, 6006.699665016732, 6022.819315420545, 6043.884640375921, 6050.9394906050375, 1002.4085789384299, 1011.716719318592, 1040.2198351236573, 1059.1315121600214, 1090.6073813427317, 20450.0, 23502.0, 24258.393540161283, 24282.392940176636, 24622.507549848902, 1136.2898188632105, 1162.1864694713734, 1168.6727716239466, 1172.2380452705809, 1181.8818118188112, 12132.939335303443, 12143.089268304913, 12480.063995200397, 16393.754093688603, 16574.17129143538, 384210.57894210354, 385537.0, 385704.07147964643, 387147.0, 387324.0, 57133.714331428906, 57443.425565743804, 57551.0, 57573.42426575765, 57588.424115759146, 1500.18240059168, 1508.064999700171, 1540.5763415060724, 1548.434821290221, 1556.2685537797217, 29864.0, 29902.401951960404, 30129.94545190908, 30443.0, 30451.086467406014, 37565.93387363612, 37715.43426848545, 37827.05432364213, 37891.62108378881, 37907.81046094752, 320488.0, 321731.0, 321846.0, 323368.0, 325035.0, 28455.715442846136, 28754.712452875203, 33904.15239619029, 36193.45709814408, 37166.62833371632, 45070.549294506636, 47765.04469910652, 48282.0, 48789.75605121952, 49136.75431622819, 32632.34735305328, 32770.52532635989, 33001.83499082489, 33062.83468582642, 33585.83207083949, 31930.68069319277, 33141.66858331482, 33500.659973600785, 33576.832115839265, 33679.83160084184, 39377.60622393739, 39680.60319396769, 39874.800625996686, 39922.201555969295, 40077.1984560313, 30911.2272193197, 31062.534061988637, 31752.682473174973, 31797.0, 31880.36239275248, 23902.163424280763, 24965.001399943798, 25504.617430738926, 26020.0, 26092.217233483025], "value": 18112.799845598845, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [411.2022676008543, 412.8782009307254, 431.1635427271096, 433.1207648473598, 439.4550757061243, 18545.814541854594, 21007.894960525195, 22681.77318226817, 22874.1994030209, 23061.654075188886, 22871.97076131574, 23058.65412018821, 23309.65035524464, 23397.532049359004, 23626.5274694506, 6183.412575805291, 6262.780802671908, 6405.487560995132, 6462.25684046336, 6463.353664633546, 6172.413620706048, 6436.292007879129, 6497.1553698019425, 6507.7071531781185, 6513.641749703765, 6235.220597425322, 6332.081848132018, 6444.935550644504, 6449.484041276686, 6452.774152904649, 5443.8094666686475, 5472.480114389126, 6375.489960803125, 6384.0, 6387.169667943161, 5525.668459892419, 5680.488756011938, 5707.686077265748, 5712.400197979194, 5723.399043100456, 28398.0, 28841.56737648937, 28856.855715721285, 29138.854305728546, 29303.2674183145, 2218.689383486313, 2261.118163916074, 2280.7947284744455, 2282.132789539981, 2282.3153054083873, 15529.0, 18226.90886545572, 18384.908075459673, 18736.969466679326, 18918.62162756751, 1836.2838492987746, 2099.3177217404404, 2102.568973360457, 2144.1530595414724, 2149.6560550312033, 125771.99999999908, 174192.0, 177247.0, 177801.0, 177934.0, 11198.768135505088, 12501.43743531525, 12824.80762788559, 13086.672833179247, 13170.934145329213, 16885.662286754196, 17179.31282748701, 17244.568885777706, 17267.136643167792, 17430.912845435945, 9262.120098590623, 9327.533623318943, 9368.063193680577, 9439.0, 9487.857682134774, 7332.0, 8885.644574216958, 8898.688545900863, 9077.77305567366, 9095.181433671001, 7238.674259658223, 7669.578173200527, 7990.600469976478, 8030.23712747288, 8186.631601577943, 2265.3317271404976, 2270.6821045053707, 2274.601944659711, 2277.7266727992414, 2478.6862962629834, 881.9559022048871, 928.744595236302, 953.7758626722715, 959.7523219814353, 984.9064338887795, 21096.261630842848, 21367.14531418726, 21463.46341341479, 21575.13699452036, 21847.235346762787, 978.280963491833, 980.5244456438621, 995.3430735714394, 1016.2225897188656, 1026.1790567545934, 9206.585703643352, 10756.386542018603, 11142.938556367098, 11152.498137583829, 11401.372924489233, 704915.0, 705731.0, 712850.0, 724257.0, 729590.0, 160822.39177608074, 161232.0, 164909.17545412437, 167025.0, 167109.0, 1126.121625132398, 1143.9656810295705, 1170.8185231288994, 1209.25026483579, 1245.9501619935281, 21968.90155492251, 22352.105915763186, 22751.431214219778, 22915.65626515637, 23033.309000730005, 65579.34420655924, 65688.34311657013, 65853.67073164604, 66271.66864165648, 67141.32858671351, 83680.0, 87011.0, 87258.5637071836, 88379.55810220908, 95838.89530494592, 9038.593263303035, 9105.499197544199, 9122.0, 9222.354435189472, 9232.861507077534, 13734.175949443066, 14338.354774035402, 14782.408703651734, 14948.775768363266, 15088.54734357971, 11116.388598627153, 11839.940800295943, 12307.076969227164, 12335.259884406963, 12485.500579976699, 8631.525266110424, 9567.473788941397, 11639.53441862316, 11771.0, 12034.63896083119, 10344.37933723979, 11612.606487221587, 12266.325352105363, 12321.0, 12406.379681016093, 7825.843483130419, 9137.40606860552, 9139.954300228455, 9173.77065573366, 9201.263898888206, 11906.642800715992, 11968.820467692818, 12154.270743755404, 12200.328981906081, 12205.877941220475], "value": 10886.225540244988, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1299.9415026323813, 1395.6510872281929, 1419.6379923119603, 1425.6222101143205, 1471.8233811942569, 11412.429378531071, 11846.466908989094, 11908.702282442933, 12008.339541325224, 12204.633860984162, 11839.171258011942, 11981.64055078349, 11998.7600247995, 12019.759604807921, 12028.0, 2342.999999999996, 2377.1561095811, 2572.717001129874, 2576.9613455798085, 2578.4327447961514, 2482.441450673602, 2499.9250322361377, 2549.1332946798084, 2549.1332946798084, 2551.5407226699185, 2467.8154485846803, 2517.974820251792, 2527.5955847064474, 2534.087728417768, 2540.2506260653154, 2125.2774056820754, 2448.8408253463467, 2502.199296225209, 2509.008941468119, 2531.936701582457, 2092.654711972523, 2442.853428794278, 2522.2181123851665, 2526.4820711754046, 2557.8465292082346, 11923.0, 11934.224275422157, 12067.818982715266, 12087.0, 12095.818562721566, 6640.900386494206, 6867.141607299088, 6924.549904256207, 6931.653417329113, 6932.23745388012, 4121.814518346681, 4414.028913638995, 4446.110777844444, 4491.820327186909, 4505.121501307246, 1257.7547378261243, 1260.0045963688674, 1265.4621785741024, 1291.9031072669588, 1308.045127057244, 15142.470013549582, 15656.686866262611, 16617.83382166175, 16710.4151354702, 16805.24376403065, 5584.720763961785, 5779.248697669276, 5948.791792287336, 5955.196048533393, 5965.940340596539, 11095.667129986114, 11800.701922788487, 12352.567660131852, 12400.689982750504, 12416.689582760322, 6603.174603174603, 6654.26803051664, 6658.03458498515, 6661.0, 6676.298988606223, 6045.758169673251, 6116.418940200674, 6145.600535965148, 6178.351273116258, 6255.311915689271, 4340.305551111807, 4447.7998490067375, 4457.331400289918, 4492.483364413069, 4493.932591011137, 1297.9740405192033, 1314.901382396305, 1332.6135420728074, 1340.3231368159018, 1344.9798253026213, 778.3345239819872, 789.1871372486348, 804.4449329962424, 805.5408417202256, 813.1218284252992, 6116.296625887991, 6163.322034576193, 6989.860202796017, 7125.1093613298335, 7194.532355396883, 930.413839281262, 934.2992755433424, 935.5649622925372, 950.2587981374539, 979.9559019843981, 3945.64489195968, 4128.174365126986, 4224.854250991997, 4260.211860805761, 4281.73688761816, 14600.269986500633, 15134.318955647022, 15218.315175816893, 15218.391264349302, 15277.236138193046, 14992.325345359484, 15160.848391516165, 15162.545123646309, 15176.24118794078, 15177.6205594861, 10644.115405877896, 10909.890901091047, 10966.177536684781, 11110.777784444266, 11110.944445277722, 5171.198464238049, 5719.799807006902, 6891.483138764713, 6910.481713871581, 6982.546134501241, 12599.622011339674, 12661.366931653565, 12677.366131693563, 12788.680282993, 12797.36013199355, 11149.609763658233, 13088.149270297401, 13116.803247951097, 13171.538996135088, 13255.933720331337, 9450.43297402158, 9506.287028472754, 9708.951455242679, 9780.0, 9838.114569688762, 11181.496832642388, 11223.438828058728, 11297.548098075986, 11301.660950171508, 11315.773684526428, 7744.578695712575, 9127.452352858849, 9356.578953947224, 9392.201662858733, 9400.29497787655, 7639.388848891964, 9139.497327647043, 9144.63421463134, 9145.0, 9239.953800231226, 8080.232377924088, 8111.432199745961, 8175.345972322319, 8176.509409435453, 8179.83640327202, 5182.093133701512, 5239.345081864767, 5262.526372626482, 5264.52619264268, 5289.841304760864, 5849.093390524476, 5964.463198311999, 5998.9100163496705, 6062.272527296753, 6232.950564711992], "value": 5431.646351372472, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.70602458995916, 397.0807580451255, 416.0493272871489, 419.2662840029948, 421.3868820865639, 6193.752249909998, 6402.935970640292, 6440.227172739272, 6476.3199864014305, 6545.509086818483, 6140.7850725224625, 6178.783742569011, 6236.438720515152, 6309.49524038077, 6345.809625711225, 4627.3984382030285, 4802.495737947517, 4802.783874725646, 4812.786118385547, 4859.489753575876, 4049.3318602430563, 4436.401085853417, 4611.953880461186, 4641.651876109288, 4845.588125009378, 3437.98579419072, 3800.353939830222, 4199.874003779892, 4553.043860789222, 4634.791434385461, 4195.517515485728, 4291.0, 4379.277419225824, 4621.4223222097235, 4649.558291962258, 3303.702666759979, 3880.3985382265796, 3914.4983269240315, 3978.5026871641044, 3981.223661386031, 5010.649254552182, 6167.537434692416, 6190.876182476325, 6214.0, 6245.594036387665, 4559.316102584606, 5047.419546752134, 5230.398504172031, 5345.144776835727, 5516.3380394352935, 5430.049741295257, 5934.495567876693, 6013.707052983629, 6720.865582688368, 6777.254502004825, 4493.640508759291, 4502.842400515999, 4508.5266047064915, 4521.683482156251, 4521.7739113044545, 6243.62538247702, 6516.022596610546, 6888.621125838077, 6910.965445172792, 7186.425085993108, 3683.852645894134, 3793.6016718244186, 4524.308036718147, 4541.954580454153, 4571.451425828922, 4247.256730072225, 4653.115907977473, 4656.557627025427, 4668.719876807402, 4671.32265821454, 4603.723776573416, 5259.474052594786, 5446.992306423324, 5466.671999680032, 5480.616356854982, 5247.895042099137, 6544.116544266558, 6833.760818371333, 6843.178818541807, 6847.657617119125, 1563.702896449671, 1579.5577238373273, 1583.9683206335808, 1587.4681981536085, 1587.9682406351808, 962.3456049886145, 997.8403455447094, 1045.968620941373, 1046.8168070587617, 1060.4591658254192, 6340.59057558125, 6383.06379065635, 6412.775552855627, 6423.871522569616, 6447.903281450782, 6025.307089684742, 6867.587944723333, 7062.399696025793, 7079.0, 7118.046010905417, 4863.537963893425, 5659.801906933238, 6240.656763878031, 6322.494200464043, 6359.300476947532, 3047.6038115044903, 3572.3034008368118, 3669.5779985302033, 3676.834542445543, 3682.650148235913, 6123.663198524124, 6603.877340851971, 7108.822279443055, 7151.070360853056, 7165.713371465187, 6100.328963813977, 6270.561060725797, 6534.856400129958, 6846.938724497711, 6854.485913556404, 6331.733653269456, 6999.510034297652, 7084.362407383257, 7180.533265337736, 7222.927770722225, 7031.0, 7037.331453511908, 7046.089420371743, 7053.365197132282, 7067.858642827217, 6622.304658011032, 6656.866862662817, 6805.659717014229, 6858.5199036067, 7005.229424763374, 6346.111544383883, 6750.818606743898, 6871.209810871814, 7101.68618805502, 7256.564606123649, 3942.8422863086303, 4036.979815100906, 4339.699959021724, 4546.976930190681, 4606.309053641847, 6081.604695694766, 6515.381038801306, 6635.1374321339845, 6739.898901516579, 7099.751508697171, 6001.729922153426, 6733.158355205599, 6817.977303404331, 6864.5881247125335, 6932.925396563541, 5748.913766293425, 6264.812055638338, 6326.703025879591, 6827.795166145023, 6839.350261725128, 5471.6169868108855, 6620.602763834186, 6674.933250667431, 6681.899771503334, 6709.416874503254, 6549.770758023446, 6669.699863506251, 6722.9663851680425, 6809.625470599165, 6878.415334696607, 6395.42441180296, 6638.070670106285, 6643.582017958004, 6669.266380698216, 6669.533132680665], "value": 4847.447069988063, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [777.1878387085495, 819.4632515702217, 841.2722994609665, 864.8486514859902, 868.1145231863497, 14011.159330440181, 14656.926715366408, 16305.83694163058, 16721.33114675413, 16764.83235167648, 16469.505914822545, 16894.915525422388, 17082.231299591516, 17125.0, 17545.82454175458, 962.7208109648211, 998.5356809083775, 1005.8843233028185, 1007.6573964851949, 1010.4695035106554, 994.567363197008, 1010.5048526222172, 1011.4234886114917, 1013.4628646817199, 1015.3197357770275, 900.9369344145913, 1049.7533079726259, 1054.1356088007838, 1055.5777688924454, 1055.6674647486057, 1044.5143008501007, 1046.670298855858, 1049.04536871447, 1049.9002594753485, 1050.558765318568, 774.5430196184278, 774.7675697290847, 777.215012837033, 777.4052849570083, 778.493978913705, 14409.0, 16311.021338719715, 16377.005013470887, 17149.14254287293, 17334.306627734877, 16212.351505939749, 16655.91672041644, 16775.0, 16975.15124243795, 16998.660026799396, 13494.460221591125, 13871.930640346834, 13918.791218131635, 14085.929570352186, 14098.436062557588, 3350.664933506654, 3364.6467120952193, 3381.7970921744527, 3389.4915762635555, 3415.9146021349416, 114137.0, 114274.85725142725, 114851.0, 115939.42030289878, 117249.0, 12071.939640301742, 12147.635570932885, 12241.877581224251, 12309.26144431337, 12331.815022774666, 19003.0, 20652.07065682048, 20689.482762931046, 20733.68899466479, 20747.0, 8484.618192181366, 8820.470771753715, 8825.0, 8847.292216622654, 8854.335924805666, 4741.834035808799, 4802.975985120052, 4819.0, 4836.274558816135, 4845.030993801254, 4205.516365617932, 4377.430933978562, 4380.802863871133, 4385.254506733863, 4390.3414487827085, 930.5998420679173, 962.7978124593774, 971.4996776660025, 972.1687956796837, 972.7762614598682, 646.7679071369049, 649.555054787472, 654.0, 658.5488940076063, 688.1776277348621, 10333.48332583383, 10965.56137754481, 11690.649280521598, 11831.616614440409, 11892.345920974429, 518.6447283610738, 577.3706659740902, 648.5265755998148, 659.2128223356846, 684.0525871667776, 5181.6890986540175, 5415.296011518556, 5436.184572314105, 5520.944790552124, 5633.6619802811965, 105650.47174764231, 107293.0, 107394.0, 108779.0, 110066.349004765, 95165.57251641231, 95320.0, 96196.0, 96535.51732241294, 96758.0, 58776.0, 65040.02439963404, 69946.99999999898, 81617.0, 81934.59032704917, 10868.836967445653, 11118.777624447304, 11149.0, 11182.161337899854, 11287.7742445149, 42584.78707606442, 42860.14279714451, 43063.354049688656, 43782.34326485169, 44412.555874442136, 32573.674263257064, 56605.71697141488, 56698.7165064172, 56996.45565570383, 58141.127883082634, 13965.511207107702, 16171.67656646884, 16857.578560536083, 16892.324307027582, 16923.91538042302, 17752.28990840352, 18017.279308828023, 18082.98767613549, 18270.0, 18537.721934170728, 16510.83489165093, 16901.32394704198, 16994.66010679804, 17004.064776437415, 17173.398931037354, 15593.76609350838, 16260.268287926836, 16353.018818870905, 16480.17599120015, 17384.47846564605, 15176.468823591122, 16456.094914779802, 16680.833191668415, 16788.0, 16834.0, 10715.839262410915, 11608.187426880375, 11736.723191895915, 12237.938810305892, 12344.506219751109, 11195.0, 11708.88291117078, 12993.675158121123, 13027.869721303045, 13043.28261945602], "value": 8277.235393563058, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1628.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 122.0, 123.0, 121.0, 121.0, 123.0, 120.0, 122.0, 122.0, 120.0], "value": 120.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 120.0, 117.0, 159.0, 116.0, 120.0, 135.0, 163.0, 186.0, 126.0], "value": 139.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [216.0, 224.0, 229.0, 227.0, 228.0, 210.0, 209.0, 213.0, 217.0, 207.0], "value": 218.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 61.0, 63.0, 93.0, 64.0, 60.0, 64.0, 51.0, 86.0, 87.0], "value": 71.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 70.0, 90.0, 73.0, 74.0, 75.0, 115.0, 108.0, 92.0, 85.0], "value": 86.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 142.0, 91.0, 139.0, 84.0, 143.0, 100.0, 144.0, 109.0, 89.0], "value": 117.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 98.0, 102.0, 102.0, 102.0, 100.0, 102.0, 102.0, 101.0, 100.0], "value": 101.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 103.0, 102.0, 102.0, 100.0, 156.0, 163.0, 126.0, 104.0, 102.0], "value": 115.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 52.0, 52.0, 53.0, 53.0, 54.0, 53.0, 53.0, 53.0], "value": 52.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 55.0, 63.0, 66.0, 66.0, 60.0, 61.0, 60.0, 59.0, 61.0], "value": 61.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 117.0, 113.0, 100.0, 91.0, 88.0, 88.0, 86.0, 101.0, 115.0], "value": 100.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 173.0, 144.0, 146.0, 181.0, 144.0, 160.0, 153.0, 205.0, 170.0], "value": 163.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [228.0, 232.0, 161.0, 228.0, 176.0, 178.0, 262.0, 249.0, 204.0, 178.0], "value": 209.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 71.0, 69.0, 71.0, 69.0, 80.0, 70.0, 69.0, 74.0, 72.0], "value": 71.7, "unit": "score"}]}]} 17:05:00 INFO - Return code: 0 17:05:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:05:00 ERROR - # TBPL SUCCESS # 17:05:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:05:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:05:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:05:00 INFO - 2017-04-12 17:05:00.725 screenresolution[1657:10570] starting screenresolution argv=screenresolution get 17:05:00 INFO - 2017-04-12 17:05:00.728 screenresolution[1657:10570] Display 0: 1600x1200x32@60 17:05:00 INFO - 2017-04-12 17:05:00.734 screenresolution[1658:10573] starting screenresolution argv=screenresolution list 17:05:00 INFO - Available Modes on Display 0 17:05:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:05:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:05:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:05:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:05:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:05:00 INFO - Intel Iris: 17:05:00 INFO - Chipset Model: Intel Iris 17:05:00 INFO - Type: GPU 17:05:00 INFO - Bus: Built-In 17:05:00 INFO - VRAM (Dynamic, Max): 1536 MB 17:05:00 INFO - Vendor: Intel (0x8086) 17:05:00 INFO - Device ID: 0x0a2e 17:05:00 INFO - Revision ID: 0x0009 17:05:00 INFO - Displays: 17:05:00 INFO - Display: 17:05:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:05:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:05:00 INFO - Display Serial Number: 1600x1200 60 17:05:00 INFO - Main Display: Yes 17:05:00 INFO - Mirror: Off 17:05:00 INFO - Online: Yes 17:05:00 INFO - Rotation: Supported 17:05:00 INFO - Return code: 0 17:05:00 INFO - Running post-action listener: _resource_record_post_action 17:05:00 INFO - [mozharness: 2017-04-13 00:05:00.977197Z] Finished run-tests step (success) 17:05:00 INFO - Running post-run listener: _resource_record_post_run 17:05:01 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 39373312; Write bytes: 519508992; Read time: 251; Write time: 1662 17:05:01 INFO - TinderboxPrint: CPU usage
54.2% 17:05:01 INFO - TinderboxPrint: I/O read bytes / time
39,373,312 / 251 17:05:01 INFO - TinderboxPrint: I/O write bytes / time
519,508,992 / 1,662 17:05:01 INFO - TinderboxPrint: CPU idle
2,197.9 (45.8%) 17:05:01 INFO - TinderboxPrint: CPU system
960.8 (20.0%) 17:05:01 INFO - TinderboxPrint: CPU user
1,639.0 (34.2%) 17:05:01 INFO - TinderboxPrint: Swap in / out
246,947,840 / 0 17:05:01 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 196463104; Write bytes: 194816000; Read time: 14928; Write time: 428 17:05:01 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 38431232; Write bytes: 307846144; Read time: 239; Write time: 1184 17:05:01 INFO - Running post-run listener: _upload_blobber_files 17:05:01 INFO - Blob upload gear active. 17:05:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:05:01 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 17:05:01 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:05:01 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:05:01 INFO - (blobuploader) - INFO - Open directory for files ... 17:05:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:05:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:05:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:05:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:05:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:05:03 INFO - (blobuploader) - INFO - Done attempting. 17:05:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:05:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:05:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:05:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:05:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:05:04 INFO - (blobuploader) - INFO - Done attempting. 17:05:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:05:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:05:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:05:05 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:05:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:05:05 INFO - (blobuploader) - INFO - Done attempting. 17:05:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:05:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:05:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:05:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:05:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:05:07 INFO - (blobuploader) - INFO - Done attempting. 17:05:07 INFO - (blobuploader) - INFO - Iteration through files over. 17:05:07 INFO - Return code: 0 17:05:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:05:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:05:07 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/4d1669bd81757e870c496b2e645b117e8e6c791bcd1dccf07a3239314d34acbf4cb279956818b564438082c9064ee54dadb9aee1633679ac6a6a4bda0f604d5f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/fb103294ce3591497b13e4d1aa96f253b31fb2fa537d7d7deb137acbf94ac378729d6e97d7cc0289f37c0bb5ef4e37841de129c3cbb2b9640f844f117955a685", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/3f2a7335dbdab41574bfdda673aa9d43738a24775ebbe1ad7c09d0ffa566de89315c268a997d0a01dc805267cc0036d76ee9d9df5fbeb2287529b4f4f34211a6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5c067b453da96f87ace946cb06c5691d1e1de2f84d60386c05185edfc97f8abb89e5e5da68422ae80a5a3bae97c67b0f8259fa1c53f5cb4cd750bd04634232f6"} 17:05:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:05:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:05:07 INFO - Contents: 17:05:07 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/4d1669bd81757e870c496b2e645b117e8e6c791bcd1dccf07a3239314d34acbf4cb279956818b564438082c9064ee54dadb9aee1633679ac6a6a4bda0f604d5f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/fb103294ce3591497b13e4d1aa96f253b31fb2fa537d7d7deb137acbf94ac378729d6e97d7cc0289f37c0bb5ef4e37841de129c3cbb2b9640f844f117955a685", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/3f2a7335dbdab41574bfdda673aa9d43738a24775ebbe1ad7c09d0ffa566de89315c268a997d0a01dc805267cc0036d76ee9d9df5fbeb2287529b4f4f34211a6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5c067b453da96f87ace946cb06c5691d1e1de2f84d60386c05185edfc97f8abb89e5e5da68422ae80a5a3bae97c67b0f8259fa1c53f5cb4cd750bd04634232f6"} 17:05:07 INFO - Running post-run listener: copy_logs_to_upload_dir 17:05:07 INFO - Copying logs to upload dir... 17:05:07 INFO - mkdir: /builds/slave/test/build/upload/logs 17:05:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.836812 ========= master_lag: 0.26 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2017-04-12 17:05:07.339307) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-12 17:05:07.342951) ========= 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.fFdLf2LmNB/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.lDfsA7x1nH/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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/Pine/sha512/4d1669bd81757e870c496b2e645b117e8e6c791bcd1dccf07a3239314d34acbf4cb279956818b564438082c9064ee54dadb9aee1633679ac6a6a4bda0f604d5f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/fb103294ce3591497b13e4d1aa96f253b31fb2fa537d7d7deb137acbf94ac378729d6e97d7cc0289f37c0bb5ef4e37841de129c3cbb2b9640f844f117955a685", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/3f2a7335dbdab41574bfdda673aa9d43738a24775ebbe1ad7c09d0ffa566de89315c268a997d0a01dc805267cc0036d76ee9d9df5fbeb2287529b4f4f34211a6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5c067b453da96f87ace946cb06c5691d1e1de2f84d60386c05185edfc97f8abb89e5e5da68422ae80a5a3bae97c67b0f8259fa1c53f5cb4cd750bd04634232f6"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491939968/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011062 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491939968/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/4d1669bd81757e870c496b2e645b117e8e6c791bcd1dccf07a3239314d34acbf4cb279956818b564438082c9064ee54dadb9aee1633679ac6a6a4bda0f604d5f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/fb103294ce3591497b13e4d1aa96f253b31fb2fa537d7d7deb137acbf94ac378729d6e97d7cc0289f37c0bb5ef4e37841de129c3cbb2b9640f844f117955a685", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/3f2a7335dbdab41574bfdda673aa9d43738a24775ebbe1ad7c09d0ffa566de89315c268a997d0a01dc805267cc0036d76ee9d9df5fbeb2287529b4f4f34211a6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5c067b453da96f87ace946cb06c5691d1e1de2f84d60386c05185edfc97f8abb89e5e5da68422ae80a5a3bae97c67b0f8259fa1c53f5cb4cd750bd04634232f6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-12 17:05:07.378506) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 17:05:07.378969) ========= 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.fFdLf2LmNB/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.lDfsA7x1nH/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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.005066 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 17:05:07.427756) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-12 17:05:07.428110) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-12 17:05:07.428480) ========= ========= Total master_lag: 0.40 =========