builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0281 starttime: 1490664583.24 results: success (0) buildid: 20170327170422 builduid: a7148adcae8640b6a3373a82adc26187 revision: a8bb5c8e0e80e87e21ad63997f8881991b5347cf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 18:29:43.235436) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 18:29:43.236006) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 18:29:43.236382) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 18:29:43.264111) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:29:43.264488) ========= 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.pQjRK4ieYx/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.XVs6KzkZ09/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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-03-27 18:29:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.3M=0.001s 2017-03-27 18:29:43 (18.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.093505 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:29:43.382763) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:29:43.383144) ========= 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.pQjRK4ieYx/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.XVs6KzkZ09/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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.041006 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:29:43.446280) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-27 18:29:43.446676) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a8bb5c8e0e80e87e21ad63997f8881991b5347cf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev a8bb5c8e0e80e87e21ad63997f8881991b5347cf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pQjRK4ieYx/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.XVs6KzkZ09/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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-03-27 18:29:43,545 truncating revision to first 12 chars 2017-03-27 18:29:43,545 Setting DEBUG logging. 2017-03-27 18:29:43,545 attempt 1/10 2017-03-27 18:29:43,546 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a8bb5c8e0e80?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-27 18:29:44,551 unpacking tar archive at: mozilla-central-a8bb5c8e0e80/testing/mozharness/ program finished with exit code 0 elapsedTime=1.306184 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-27 18:29:44.776812) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 18:29:44.777185) ========= script_repo_revision: a8bb5c8e0e80e87e21ad63997f8881991b5347cf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 18:29:44.777663) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 18:29:44.777994) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 18:29:44.797881) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2017-03-27 18:29:44.798205) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pQjRK4ieYx/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.XVs6KzkZ09/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 18:29:44 INFO - MultiFileLogger online at 20170327 18:29:44 in /builds/slave/test 18:29:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:29:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:29:45 INFO - {'append_to_log': False, 18:29:45 INFO - 'base_work_dir': '/builds/slave/test', 18:29:45 INFO - 'blob_upload_branch': 'Firefox', 18:29:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:29:45 INFO - 'branch': 'Firefox', 18:29:45 INFO - 'buildbot_json_path': 'buildprops.json', 18:29:45 INFO - 'config_files': ('talos/mac_config.py',), 18:29:45 INFO - 'default_actions': ('clobber', 18:29:45 INFO - 'read-buildbot-config', 18:29:45 INFO - 'download-and-extract', 18:29:45 INFO - 'populate-webroot', 18:29:45 INFO - 'create-virtualenv', 18:29:45 INFO - 'install', 18:29:45 INFO - 'run-tests'), 18:29:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:29:45 INFO - 'download_minidump_stackwalk': True, 18:29:45 INFO - 'download_symbols': 'ondemand', 18:29:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:29:45 INFO - 'tooltool.py': '/tools/tooltool.py', 18:29:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:29:45 INFO - '/tools/misc-python/virtualenv.py')}, 18:29:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:29:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:29:45 INFO - 'gecko_profile': False, 18:29:45 INFO - 'gecko_profile_interval': 0, 18:29:45 INFO - 'installer_path': 'installer.exe', 18:29:45 INFO - 'log_level': 'info', 18:29:45 INFO - 'log_name': 'talos', 18:29:45 INFO - 'log_to_console': True, 18:29:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:29:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:29:45 INFO - 'opt_config_files': (), 18:29:45 INFO - 'pip_index': False, 18:29:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:29:45 INFO - 'cmd': ('bash', 18:29:45 INFO - '-c', 18:29:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:29:45 INFO - 'enabled': True, 18:29:45 INFO - 'halt_on_failure': False, 18:29:45 INFO - 'name': 'check_screen_resolution'},), 18:29:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:29:45 INFO - 'cmd': ('bash', 18:29:45 INFO - '-c', 18:29:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:29:45 INFO - 'enabled': True, 18:29:45 INFO - 'halt_on_failure': False, 18:29:45 INFO - 'name': 'check_screen_resolution'},), 18:29:45 INFO - 'run_cmd_checks_enabled': True, 18:29:45 INFO - 'suite': 'dromaeojs', 18:29:45 INFO - 'system_bits': '32', 18:29:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:29:45 INFO - 'title': 't-yosemite-r7-0281', 18:29:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:29:45 INFO - 'use_talos_json': True, 18:29:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:29:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:29:45 INFO - 'work_dir': 'build'} 18:29:45 INFO - [mozharness: 2017-03-28 01:29:45.006836Z] Running clobber step. 18:29:45 INFO - Running pre-action listener: _resource_record_pre_action 18:29:45 INFO - Running main action method: clobber 18:29:45 INFO - rmtree: /builds/slave/test/build 18:29:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:29:47 INFO - Running post-action listener: _resource_record_post_action 18:29:47 INFO - [mozharness: 2017-03-28 01:29:47.449123Z] Finished clobber step (success) 18:29:47 INFO - [mozharness: 2017-03-28 01:29:47.449271Z] Running read-buildbot-config step. 18:29:47 INFO - Running pre-action listener: _resource_record_pre_action 18:29:47 INFO - Running main action method: read_buildbot_config 18:29:47 INFO - Using buildbot properties: 18:29:47 INFO - { 18:29:47 INFO - "project": "", 18:29:47 INFO - "product": "firefox", 18:29:47 INFO - "script_repo_revision": "production", 18:29:47 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 18:29:47 INFO - "repository": "", 18:29:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 18:29:47 INFO - "buildid": "20170327170422", 18:29:47 INFO - "pgo_build": "False", 18:29:47 INFO - "basedir": "/builds/slave/test", 18:29:47 INFO - "buildnumber": 58, 18:29:47 INFO - "slavename": "t-yosemite-r7-0281", 18:29:47 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 18:29:47 INFO - "platform": "yosemite_r7", 18:29:47 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:29:47 INFO - "branch": "mozilla-central", 18:29:47 INFO - "repo_path": "mozilla-central", 18:29:47 INFO - "revision": "a8bb5c8e0e80e87e21ad63997f8881991b5347cf", 18:29:47 INFO - "stage_platform": "macosx64", 18:29:47 INFO - "builduid": "a7148adcae8640b6a3373a82adc26187", 18:29:47 INFO - "slavebuilddir": "test" 18:29:47 INFO - } 18:29:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 18:29:47 INFO - Running post-action listener: _resource_record_post_action 18:29:47 INFO - [mozharness: 2017-03-28 01:29:47.452625Z] Finished read-buildbot-config step (success) 18:29:47 INFO - [mozharness: 2017-03-28 01:29:47.452741Z] Running download-and-extract step. 18:29:47 INFO - Running pre-action listener: _resource_record_pre_action 18:29:47 INFO - Running main action method: download_and_extract 18:29:47 INFO - mkdir: /builds/slave/test/build/tests 18:29:47 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')]} 18:29:47 INFO - trying https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 18:29:47 INFO - Downloading https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:29:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 18:29:48 INFO - Downloaded 1325 bytes. 18:29:48 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:29:48 INFO - Using the following test package requirements: 18:29:48 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:29:48 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 18:29:48 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:29:48 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:29:48 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 18:29:48 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:29:48 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 18:29:48 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:29:48 INFO - u'jsshell-mac.zip'], 18:29:48 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:29:48 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 18:29:48 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:29:48 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:29:48 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 18:29:48 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:29:48 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 18:29:48 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:29:48 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 18:29:48 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:29:48 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 18:29:48 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:29:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:29:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:29:48 INFO - Fetch https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:29:50 INFO - Expected file size: 52417303 18:29:50 INFO - Obtained file size: 52417303 18:30:06 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 18:30:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:30:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:30:06 INFO - Fetch https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:30:08 INFO - Expected file size: 52417303 18:30:08 INFO - Obtained file size: 52417303 18:30:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 18:30:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:30:23 INFO - Fetch https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 18:30:24 INFO - Expected file size: 14404171 18:30:24 INFO - Obtained file size: 14404171 18:30: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')]} 18:30:25 INFO - trying https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:30:25 INFO - Downloading https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 18:30:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 18:30:27 INFO - Downloaded 75567943 bytes. 18:30:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:30:27 INFO - mkdir: /builds/slave/test/properties 18:30:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:30:27 INFO - Writing to file /builds/slave/test/properties/build_url 18:30:27 INFO - Contents: 18:30:27 INFO - build_url:https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:30:28 INFO - Running post-action listener: _resource_record_post_action 18:30:28 INFO - Running post-action listener: set_extra_try_arguments 18:30:28 INFO - [mozharness: 2017-03-28 01:30:28.289945Z] Finished download-and-extract step (success) 18:30:28 INFO - [mozharness: 2017-03-28 01:30:28.290104Z] Running populate-webroot step. 18:30:28 INFO - Running pre-action listener: _resource_record_pre_action 18:30:28 INFO - Running main action method: populate_webroot 18:30:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:30:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:30:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:30:28 INFO - '--tppagecycles', 18:30:28 INFO - '7'], 18:30:28 INFO - 'tests': ['tsvgm']}}, 18:30:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:30:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:30:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:30:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:30:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:30:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:30:28 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:30:28 INFO - 'talos_options': ['--disable-e10s'], 18:30:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:30:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:30:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:30:28 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:30:28 INFO - 'talos_options': ['--disable-e10s'], 18:30:28 INFO - 'tests': ['damp', 'tps']}, 18:30:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:30:28 INFO - 'tests': ['damp', 'tps']}, 18:30:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:30:28 INFO - 'tests': ['dromaeo_dom']}, 18:30:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:30:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:30:28 INFO - 'tests': ['basic_compositor_video']}, 18:30:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:30:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:30:28 INFO - 'tests': ['a11yr', 18:30:28 INFO - 'ts_paint', 18:30:28 INFO - 'tpaint', 18:30:28 INFO - 'sessionrestore', 18:30:28 INFO - 'sessionrestore_no_auto_restore', 18:30:28 INFO - 'tabpaint']}, 18:30:28 INFO - 'other-e10s': {'tests': ['a11yr', 18:30:28 INFO - 'ts_paint', 18:30:28 INFO - 'tpaint', 18:30:28 INFO - 'sessionrestore', 18:30:28 INFO - 'sessionrestore_no_auto_restore', 18:30:28 INFO - 'tabpaint']}, 18:30:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:30:28 INFO - 'ts_paint', 18:30:28 INFO - 'tpaint', 18:30:28 INFO - 'sessionrestore', 18:30:28 INFO - 'sessionrestore_no_auto_restore', 18:30:28 INFO - 'tabpaint']}, 18:30:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:30:28 INFO - 'ts_paint', 18:30:28 INFO - 'tpaint', 18:30:28 INFO - 'sessionrestore', 18:30:28 INFO - 'sessionrestore_no_auto_restore', 18:30:28 INFO - 'tabpaint']}, 18:30:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:30:28 INFO - 'tests': ['a11yr', 18:30:28 INFO - 'ts_paint', 18:30:28 INFO - 'tpaint', 18:30:28 INFO - 'sessionrestore', 18:30:28 INFO - 'sessionrestore_no_auto_restore', 18:30:28 INFO - 'tabpaint']}, 18:30:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:30:28 INFO - 'tests': ['a11yr', 18:30:28 INFO - 'ts_paint', 18:30:28 INFO - 'tpaint', 18:30:28 INFO - 'sessionrestore', 18:30:28 INFO - 'sessionrestore_no_auto_restore', 18:30:28 INFO - 'tabpaint']}, 18:30:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:30:28 INFO - 'tests': ['tsvgx', 18:30:28 INFO - 'tsvgr_opacity', 18:30:28 INFO - 'tart', 18:30:28 INFO - 'tscrollx', 18:30:28 INFO - 'cart', 18:30:28 INFO - 'tsvg_static']}, 18:30:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:30:28 INFO - 'tsvgr_opacity', 18:30:28 INFO - 'tart', 18:30:28 INFO - 'tscrollx', 18:30:28 INFO - 'cart', 18:30:28 INFO - 'tsvg_static']}, 18:30:28 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:30:28 INFO - 'talos_options': ['--disable-e10s'], 18:30:28 INFO - 'tests': ['tp5o']}, 18:30:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:30:28 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:30:28 INFO - 'talos_options': ['--disable-e10s', 18:30:28 INFO - '--xperf_path', 18:30:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:30:28 INFO - 'tests': ['tp5n']}, 18:30:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:30:28 INFO - 'talos_options': ['--xperf_path', 18:30:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:30:28 INFO - 'tests': ['tp5n']}}, 18:30:28 INFO - 'talos.zip': {'path': '', 18:30:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:30:28 INFO - Running post-action listener: _resource_record_post_action 18:30:28 INFO - [mozharness: 2017-03-28 01:30:28.309074Z] Finished populate-webroot step (success) 18:30:28 INFO - [mozharness: 2017-03-28 01:30:28.309207Z] Running create-virtualenv step. 18:30:28 INFO - Running pre-action listener: _resource_record_pre_action 18:30:28 INFO - Running main action method: create_virtualenv 18:30:28 INFO - Creating virtualenv /builds/slave/test/build/venv 18:30:28 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 18:30:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:30:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:30:28 INFO - Using real prefix '/tools/python27' 18:30:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:30:29 INFO - Installing distribute.............................................................................................................................................................................................done. 18:30:32 INFO - Installing pip.................done. 18:30:32 INFO - Return code: 0 18:30:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:30:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:30:32 INFO - Reading from file tmpfile_stdout 18:30:32 INFO - Output received: 18:30:32 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) 18:30:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:30:32 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')]} 18:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:32 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')]} 18:30:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:32 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 0x10b6f7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6fa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f60d86f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4d5d30>, '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 0x10b3fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4cabe8>, '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.pQjRK4ieYx/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.XVs6KzkZ09/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 18:30:32 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 18:30:32 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 18:30:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pQjRK4ieYx/Render', 18:30:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:32 INFO - 'HOME': '/Users/cltbld', 18:30:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:32 INFO - 'LOGNAME': 'cltbld', 18:30:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:32 INFO - 'MOZ_NO_REMOTE': '1', 18:30:32 INFO - 'NO_EM_RESTART': '1', 18:30:32 INFO - 'PAGER': '/bin/cat', 18:30:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:32 INFO - 'PWD': '/builds/slave/test', 18:30:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:32 INFO - 'SHELL': '/bin/bash', 18:30:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVs6KzkZ09/Listeners', 18:30:32 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 18:30:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:32 INFO - 'USER': 'cltbld', 18:30:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:32 INFO - 'XPC_FLAGS': '0x0', 18:30:32 INFO - 'XPC_SERVICE_NAME': '0', 18:30:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:32 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 18:30:32 INFO - Cleaning up... 18:30:32 INFO - Return code: 0 18:30:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:30:32 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')]} 18:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:32 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')]} 18:30:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:32 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 0x10b6f7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6fa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f60d86f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4d5d30>, '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 0x10b3fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4cabe8>, '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.pQjRK4ieYx/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.XVs6KzkZ09/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 18:30:32 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 18:30:32 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 18:30:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pQjRK4ieYx/Render', 18:30:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:32 INFO - 'HOME': '/Users/cltbld', 18:30:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:32 INFO - 'LOGNAME': 'cltbld', 18:30:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:32 INFO - 'MOZ_NO_REMOTE': '1', 18:30:32 INFO - 'NO_EM_RESTART': '1', 18:30:32 INFO - 'PAGER': '/bin/cat', 18:30:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:32 INFO - 'PWD': '/builds/slave/test', 18:30:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:32 INFO - 'SHELL': '/bin/bash', 18:30:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVs6KzkZ09/Listeners', 18:30:32 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 18:30:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:32 INFO - 'USER': 'cltbld', 18:30:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:32 INFO - 'XPC_FLAGS': '0x0', 18:30:32 INFO - 'XPC_SERVICE_NAME': '0', 18:30:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:32 INFO - Downloading/unpacking psutil>=3.1.1 18:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:30:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:30:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:30:36 INFO - Installing collected packages: psutil 18:30:36 INFO - Running setup.py install for psutil 18:30:36 INFO - building 'psutil._psutil_osx' extension 18:30:36 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 18:30:36 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 18:30:37 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 18:30:37 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 18:30:37 INFO - building 'psutil._psutil_posix' extension 18:30:37 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 18:30:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:30:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:30:37 INFO - ^ 18:30:37 INFO - 1 warning generated. 18:30:37 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 18:30:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:30:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:30:37 INFO - Successfully installed psutil 18:30:37 INFO - Cleaning up... 18:30:37 INFO - Return code: 0 18:30:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:30:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b6f7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6fa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f60d86f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4d5d30>, '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 0x10b3fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4cabe8>, '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.pQjRK4ieYx/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.XVs6KzkZ09/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 18:30:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:30:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:30:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pQjRK4ieYx/Render', 18:30:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:37 INFO - 'HOME': '/Users/cltbld', 18:30:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:37 INFO - 'LOGNAME': 'cltbld', 18:30:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:37 INFO - 'MOZ_NO_REMOTE': '1', 18:30:37 INFO - 'NO_EM_RESTART': '1', 18:30:37 INFO - 'PAGER': '/bin/cat', 18:30:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:37 INFO - 'PWD': '/builds/slave/test', 18:30:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:37 INFO - 'SHELL': '/bin/bash', 18:30:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVs6KzkZ09/Listeners', 18:30:37 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 18:30:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:37 INFO - 'USER': 'cltbld', 18:30:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:37 INFO - 'XPC_FLAGS': '0x0', 18:30:37 INFO - 'XPC_SERVICE_NAME': '0', 18:30:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:30:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:30:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:30:40 INFO - Installing collected packages: mozsystemmonitor 18:30:40 INFO - Running setup.py install for mozsystemmonitor 18:30:40 INFO - Successfully installed mozsystemmonitor 18:30:40 INFO - Cleaning up... 18:30:40 INFO - Return code: 0 18:30:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:30:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b6f7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6fa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f60d86f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4d5d30>, '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 0x10b3fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4cabe8>, '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.pQjRK4ieYx/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.XVs6KzkZ09/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 18:30:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:30:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:30:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pQjRK4ieYx/Render', 18:30:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:41 INFO - 'HOME': '/Users/cltbld', 18:30:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:41 INFO - 'LOGNAME': 'cltbld', 18:30:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:41 INFO - 'MOZ_NO_REMOTE': '1', 18:30:41 INFO - 'NO_EM_RESTART': '1', 18:30:41 INFO - 'PAGER': '/bin/cat', 18:30:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:41 INFO - 'PWD': '/builds/slave/test', 18:30:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:41 INFO - 'SHELL': '/bin/bash', 18:30:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVs6KzkZ09/Listeners', 18:30:41 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 18:30:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:41 INFO - 'USER': 'cltbld', 18:30:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:41 INFO - 'XPC_FLAGS': '0x0', 18:30:41 INFO - 'XPC_SERVICE_NAME': '0', 18:30:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:41 INFO - Downloading/unpacking jsonschema==2.5.1 18:30:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:30:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:30: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 18:30: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 18:30: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 18:30: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 18:30: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 18:30: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 18:30:44 INFO - Downloading functools32-3.2.3-2.tar.gz 18:30:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:30:44 INFO - warning: no files found matching '*.txt' 18:30:44 INFO - no previously-included directories found matching 'build' 18:30:44 INFO - no previously-included directories found matching 'dist' 18:30:44 INFO - no previously-included directories found matching '.git*' 18:30:44 INFO - Installing collected packages: jsonschema, functools32 18:30:44 INFO - Running setup.py install for functools32 18:30:45 INFO - warning: no files found matching '*.txt' 18:30:45 INFO - no previously-included directories found matching 'build' 18:30:45 INFO - no previously-included directories found matching 'dist' 18:30:45 INFO - no previously-included directories found matching '.git*' 18:30:45 INFO - Successfully installed jsonschema functools32 18:30:45 INFO - Cleaning up... 18:30:45 INFO - Return code: 0 18:30:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:30:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b6f7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6fa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f60d86f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4d5d30>, '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 0x10b3fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4cabe8>, '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.pQjRK4ieYx/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.XVs6KzkZ09/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 18:30:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:30:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:30:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pQjRK4ieYx/Render', 18:30:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:45 INFO - 'HOME': '/Users/cltbld', 18:30:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:45 INFO - 'LOGNAME': 'cltbld', 18:30:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:45 INFO - 'MOZ_NO_REMOTE': '1', 18:30:45 INFO - 'NO_EM_RESTART': '1', 18:30:45 INFO - 'PAGER': '/bin/cat', 18:30:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:45 INFO - 'PWD': '/builds/slave/test', 18:30:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:45 INFO - 'SHELL': '/bin/bash', 18:30:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVs6KzkZ09/Listeners', 18:30:45 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 18:30:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:45 INFO - 'USER': 'cltbld', 18:30:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:45 INFO - 'XPC_FLAGS': '0x0', 18:30:45 INFO - 'XPC_SERVICE_NAME': '0', 18:30:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:30:45 INFO - Cleaning up... 18:30:45 INFO - Return code: 0 18:30:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:30:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b6f7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6fa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f60d86f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4d5d30>, '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 0x10b3fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4cabe8>, '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.pQjRK4ieYx/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.XVs6KzkZ09/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 18:30:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:30:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:30:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pQjRK4ieYx/Render', 18:30:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:45 INFO - 'HOME': '/Users/cltbld', 18:30:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:45 INFO - 'LOGNAME': 'cltbld', 18:30:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:45 INFO - 'MOZ_NO_REMOTE': '1', 18:30:45 INFO - 'NO_EM_RESTART': '1', 18:30:45 INFO - 'PAGER': '/bin/cat', 18:30:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:45 INFO - 'PWD': '/builds/slave/test', 18:30:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:45 INFO - 'SHELL': '/bin/bash', 18:30:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVs6KzkZ09/Listeners', 18:30:45 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 18:30:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:45 INFO - 'USER': 'cltbld', 18:30:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:45 INFO - 'XPC_FLAGS': '0x0', 18:30:45 INFO - 'XPC_SERVICE_NAME': '0', 18:30:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:45 INFO - Downloading/unpacking blobuploader==1.2.4 18:30:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:48 INFO - Downloading blobuploader-1.2.4.tar.gz 18:30:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:30:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:30: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 18:30: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 18:30: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 18:30: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 18:30: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 18:30: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 18:30:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:30:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:30:49 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 18:30:49 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 18:30:49 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 18:30:49 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 18:30:49 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 18:30:49 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 18:30:49 INFO - Downloading docopt-0.6.1.tar.gz 18:30:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:30:49 INFO - Installing collected packages: blobuploader, requests, docopt 18:30:49 INFO - Running setup.py install for blobuploader 18:30:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:30:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:30:49 INFO - Running setup.py install for requests 18:30:50 INFO - Running setup.py install for docopt 18:30:50 INFO - Successfully installed blobuploader requests docopt 18:30:50 INFO - Cleaning up... 18:30:50 INFO - Return code: 0 18:30:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:30: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')]} 18:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30: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')]} 18:30:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30: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 0x10b6f7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6fa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f60d86f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4d5d30>, '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 0x10b3fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4cabe8>, '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.pQjRK4ieYx/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.XVs6KzkZ09/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 18:30: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 18:30: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 18:30:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pQjRK4ieYx/Render', 18:30:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:50 INFO - 'HOME': '/Users/cltbld', 18:30:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:50 INFO - 'LOGNAME': 'cltbld', 18:30:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:50 INFO - 'MOZ_NO_REMOTE': '1', 18:30:50 INFO - 'NO_EM_RESTART': '1', 18:30:50 INFO - 'PAGER': '/bin/cat', 18:30:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:50 INFO - 'PWD': '/builds/slave/test', 18:30:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:50 INFO - 'SHELL': '/bin/bash', 18:30:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVs6KzkZ09/Listeners', 18:30:50 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 18:30:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:50 INFO - 'USER': 'cltbld', 18:30:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:50 INFO - 'XPC_FLAGS': '0x0', 18:30:50 INFO - 'XPC_SERVICE_NAME': '0', 18:30:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:30:50 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-D_wbZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:30:50 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-Kc4Uri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:30:50 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-VZ5x6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:30:51 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-iOGNQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:30:51 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-FGP_rC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:30:51 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-iQEfcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:30:51 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-GwceMe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:30:51 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-3R48CI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:30:51 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-MgegPn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:30:51 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-S3k8SI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:30:51 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-pLhJbe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:30:51 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-LK1lGH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:30:52 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-mfcsaq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:30:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:30:52 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-Z6jzwJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:30:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:52 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-hBweQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:30:52 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-xxdX1G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:30:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:30:52 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-kzFD7s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:30:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:30:52 INFO - Running setup.py install for manifestparser 18:30:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:30:52 INFO - Running setup.py install for mozcrash 18:30:52 INFO - Running setup.py install for mozdebug 18:30:52 INFO - Running setup.py install for mozdevice 18:30:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:30:53 INFO - Running setup.py install for mozfile 18:30:53 INFO - Running setup.py install for mozhttpd 18:30:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:30:53 INFO - Running setup.py install for mozinfo 18:30:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:30:53 INFO - Running setup.py install for mozInstall 18:30:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:30:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:30:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:30:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:30:53 INFO - Running setup.py install for mozleak 18:30:53 INFO - Running setup.py install for mozlog 18:30:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:30:53 INFO - Running setup.py install for moznetwork 18:30:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:30:54 INFO - Running setup.py install for mozprocess 18:30:54 INFO - Running setup.py install for mozprofile 18:30:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:30:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:30:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:30:54 INFO - Running setup.py install for mozrunner 18:30:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:30:54 INFO - Running setup.py install for mozscreenshot 18:30:54 INFO - Running setup.py install for moztest 18:30:54 INFO - Running setup.py install for mozversion 18:30:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:30:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:30:55 INFO - Cleaning up... 18:30:55 INFO - Return code: 0 18:30:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:30:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:55 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 0x10b6f7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6fa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f60d86f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4d5d30>, '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 0x10b3fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4cabe8>, '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.pQjRK4ieYx/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.XVs6KzkZ09/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 18:30:55 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 18:30:55 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 18:30:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pQjRK4ieYx/Render', 18:30:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:55 INFO - 'HOME': '/Users/cltbld', 18:30:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:55 INFO - 'LOGNAME': 'cltbld', 18:30:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:55 INFO - 'MOZ_NO_REMOTE': '1', 18:30:55 INFO - 'NO_EM_RESTART': '1', 18:30:55 INFO - 'PAGER': '/bin/cat', 18:30:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:55 INFO - 'PWD': '/builds/slave/test', 18:30:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:55 INFO - 'SHELL': '/bin/bash', 18:30:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVs6KzkZ09/Listeners', 18:30:55 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 18:30:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:55 INFO - 'USER': 'cltbld', 18:30:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:55 INFO - 'XPC_FLAGS': '0x0', 18:30:55 INFO - 'XPC_SERVICE_NAME': '0', 18:30:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:30:55 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-ogrolx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:30: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)) 18:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:30:55 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-qjGHdf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:30: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)) 18:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:30:55 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-tbE2Z5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:30: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)) 18:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:30:55 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-H7kheG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:30:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:30:55 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-fAXGAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:30: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)) 18:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:30:55 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-o_DgYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:30: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)) 18:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:30:55 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-c9bAn2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:30:56 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)) 18:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:30:56 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-o3HHOG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:30:56 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)) 18:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:30:56 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-2HkllQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:30:56 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)) 18:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:30:56 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-5i_4_c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:30: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)) 18:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:30:56 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-eT407N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:30: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)) 18:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:30:56 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-X3Grvu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:30: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)) 18:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:30:56 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-622QS6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:30: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)) 18:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:30:56 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-LdtVhZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:30: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)) 18:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:56 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-VE_wMv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:30: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)) 18:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:30:56 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-fqsBkx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:30:57 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)) 18:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:30:57 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-9qAiio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:30:57 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)) 18:30:57 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)) 18:30:57 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)) 18:30:57 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)) 18:30:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:30:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:30:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:59 INFO - Downloading blessings-1.6.tar.gz 18:30:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:30:59 INFO - Installing collected packages: blessings 18:30:59 INFO - Running setup.py install for blessings 18:31:00 INFO - Successfully installed blessings 18:31:00 INFO - Cleaning up... 18:31:00 INFO - Return code: 0 18:31:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:31:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:31:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:31:00 INFO - Reading from file tmpfile_stdout 18:31:00 INFO - Current package versions: 18:31:00 INFO - blessings == 1.6 18:31:00 INFO - blobuploader == 1.2.4 18:31:00 INFO - docopt == 0.6.1 18:31:00 INFO - functools32 == 3.2.3-2 18:31:00 INFO - jsonschema == 2.5.1 18:31:00 INFO - manifestparser == 1.1 18:31:00 INFO - mozInstall == 1.12 18:31:00 INFO - mozcrash == 1.0 18:31:00 INFO - mozdebug == 0.1 18:31:00 INFO - mozdevice == 0.49 18:31:00 INFO - mozfile == 1.2 18:31:00 INFO - mozhttpd == 0.7 18:31:00 INFO - mozinfo == 0.9 18:31:00 INFO - mozleak == 0.1 18:31:00 INFO - mozlog == 3.4 18:31:00 INFO - moznetwork == 0.27 18:31:00 INFO - mozprocess == 0.25 18:31:00 INFO - mozprofile == 0.28 18:31:00 INFO - mozrunner == 6.13 18:31:00 INFO - mozscreenshot == 0.1 18:31:00 INFO - mozsystemmonitor == 0.3 18:31:00 INFO - moztest == 0.8 18:31:00 INFO - mozversion == 1.4 18:31:00 INFO - psutil == 3.1.1 18:31:00 INFO - requests == 1.2.3 18:31:00 INFO - wsgiref == 0.1.2 18:31:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:31: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')]} 18:31:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31: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')]} 18:31:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31: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 0x10b6f7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6fa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f60d86f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4d5d30>, '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 0x10b3fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4cabe8>, '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.pQjRK4ieYx/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.XVs6KzkZ09/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 18:31: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 18:31: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 18:31:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pQjRK4ieYx/Render', 18:31:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:00 INFO - 'HOME': '/Users/cltbld', 18:31:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:00 INFO - 'LOGNAME': 'cltbld', 18:31:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:00 INFO - 'MOZ_NO_REMOTE': '1', 18:31:00 INFO - 'NO_EM_RESTART': '1', 18:31:00 INFO - 'PAGER': '/bin/cat', 18:31:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:00 INFO - 'PWD': '/builds/slave/test', 18:31:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:00 INFO - 'SHELL': '/bin/bash', 18:31:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVs6KzkZ09/Listeners', 18:31:00 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 18:31:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:00 INFO - 'USER': 'cltbld', 18:31:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:00 INFO - 'XPC_FLAGS': '0x0', 18:31:00 INFO - 'XPC_SERVICE_NAME': '0', 18:31:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31: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)) 18:31: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)) 18:31: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)) 18:31: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)) 18:31: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)) 18:31: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)) 18:31: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)) 18:31: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)) 18:31: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)) 18:31: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)) 18:31: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)) 18:31:00 INFO - Cleaning up... 18:31:00 INFO - Return code: 0 18:31:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:31: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')]} 18:31:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31: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')]} 18:31:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31: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 0x10b6f7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6fa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f60d86f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4d5d30>, '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 0x10b3fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4cabe8>, '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.pQjRK4ieYx/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.XVs6KzkZ09/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 18:31: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 18:31: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 18:31:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pQjRK4ieYx/Render', 18:31:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:00 INFO - 'HOME': '/Users/cltbld', 18:31:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:00 INFO - 'LOGNAME': 'cltbld', 18:31:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:00 INFO - 'MOZ_NO_REMOTE': '1', 18:31:00 INFO - 'NO_EM_RESTART': '1', 18:31:00 INFO - 'PAGER': '/bin/cat', 18:31:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:00 INFO - 'PWD': '/builds/slave/test', 18:31:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:00 INFO - 'SHELL': '/bin/bash', 18:31:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVs6KzkZ09/Listeners', 18:31:00 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 18:31:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:00 INFO - 'USER': 'cltbld', 18:31:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:00 INFO - 'XPC_FLAGS': '0x0', 18:31:00 INFO - 'XPC_SERVICE_NAME': '0', 18:31:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:31:00 INFO - Cleaning up... 18:31:01 INFO - Return code: 0 18:31:01 INFO - Running post-action listener: _resource_record_post_action 18:31:01 INFO - Running post-action listener: _start_resource_monitoring 18:31:01 INFO - Starting resource monitoring. 18:31:01 INFO - [mozharness: 2017-03-28 01:31:01.031825Z] Finished create-virtualenv step (success) 18:31:01 INFO - [mozharness: 2017-03-28 01:31:01.032769Z] Running install step. 18:31:01 INFO - Running pre-action listener: _resource_record_pre_action 18:31:01 INFO - Running main action method: install 18:31:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:31:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:31:01 INFO - Reading from file tmpfile_stdout 18:31:01 INFO - Detecting whether we're running mozinstall >=1.0... 18:31:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:31:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:31:01 INFO - Reading from file tmpfile_stdout 18:31:01 INFO - Output received: 18:31:01 INFO - Usage: mozinstall [options] installer 18:31:01 INFO - Options: 18:31:01 INFO - -h, --help show this help message and exit 18:31:01 INFO - -d DEST, --destination=DEST 18:31:01 INFO - Directory to install application into. [default: 18:31:01 INFO - "/builds/slave/test"] 18:31:01 INFO - --app=APP Application being installed. [default: firefox] 18:31:01 INFO - mkdir: /builds/slave/test/build/application 18:31: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'] 18:31: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 18:31:22 INFO - Reading from file tmpfile_stdout 18:31:22 INFO - Output received: 18:31:22 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:31:22 INFO - Running post-action listener: _resource_record_post_action 18:31:22 INFO - [mozharness: 2017-03-28 01:31:22.086096Z] Finished install step (success) 18:31:22 INFO - [mozharness: 2017-03-28 01:31:22.086241Z] Running run-tests step. 18:31:22 INFO - Running pre-action listener: _resource_record_pre_action 18:31:22 INFO - Running main action method: run_tests 18:31:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:31:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:31:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:31:22 INFO - 2017-03-27 18:31:22.100 screenresolution[1627:7395] starting screenresolution argv=screenresolution get 18:31:22 INFO - 2017-03-27 18:31:22.103 screenresolution[1627:7395] Display 0: 1600x1200x32@60 18:31:22 INFO - 2017-03-27 18:31:22.109 screenresolution[1628:7398] starting screenresolution argv=screenresolution list 18:31:22 INFO - Available Modes on Display 0 18:31:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:31:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:31:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:31:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:31:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:31:22 INFO - Intel Iris: 18:31:22 INFO - Chipset Model: Intel Iris 18:31:22 INFO - Type: GPU 18:31:22 INFO - Bus: Built-In 18:31:22 INFO - VRAM (Dynamic, Max): 1536 MB 18:31:22 INFO - Vendor: Intel (0x8086) 18:31:22 INFO - Device ID: 0x0a2e 18:31:22 INFO - Revision ID: 0x0009 18:31:22 INFO - Displays: 18:31:22 INFO - Display: 18:31:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:31:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:31:22 INFO - Display Serial Number: 1600x1200 60 18:31:22 INFO - Main Display: Yes 18:31:22 INFO - Mirror: Off 18:31:22 INFO - Online: Yes 18:31:22 INFO - Rotation: Supported 18:31:22 INFO - Return code: 0 18:31:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:31:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:31:22 INFO - Python 2.7.3 18:31:22 INFO - Return code: 0 18:31:22 INFO - grabbing minidump binary from tooltool 18:31:22 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')]} 18:31:22 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 0x10b4d5d30>, '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 0x10b3fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4cabe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:31:22 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 18:31:22 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 18:31:22 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 18:31:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:31:22 INFO - Return code: 0 18:31:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:31:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:31:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:31:22 INFO - ENV: RUST_BACKTRACE is now 1 18:31:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:31:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:31:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0281', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:31:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0281 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:31:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pQjRK4ieYx/Render', 18:31:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:22 INFO - 'HOME': '/Users/cltbld', 18:31:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:22 INFO - 'LOGNAME': 'cltbld', 18:31:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:31:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:31:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:22 INFO - 'MOZ_NO_REMOTE': '1', 18:31:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:31:22 INFO - 'NO_EM_RESTART': '1', 18:31:22 INFO - 'PAGER': '/bin/cat', 18:31:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:22 INFO - 'PWD': '/builds/slave/test', 18:31:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:31:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:22 INFO - 'RUST_BACKTRACE': '1', 18:31:22 INFO - 'SHELL': '/bin/bash', 18:31:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVs6KzkZ09/Listeners', 18:31:22 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 18:31:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:22 INFO - 'USER': 'cltbld', 18:31:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:22 INFO - 'XPC_FLAGS': '0x0', 18:31:22 INFO - 'XPC_SERVICE_NAME': '0', 18:31:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0281', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:31:22 INFO - mozversion application_buildid: 20170327170422 18:31:22 INFO - mozversion application_changeset: a8bb5c8e0e80e87e21ad63997f8881991b5347cf 18:31:22 INFO - mozversion application_display_name: Nightly 18:31:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:31:22 INFO - mozversion application_name: Firefox 18:31:22 INFO - mozversion application_remotingname: firefox 18:31:22 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:31:22 INFO - mozversion application_vendor: Mozilla 18:31:22 INFO - mozversion application_version: 55.0a1 18:31:22 INFO - mozversion platform_buildid: 20170327170422 18:31:22 INFO - mozversion platform_changeset: a8bb5c8e0e80e87e21ad63997f8881991b5347cf 18:31:22 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:31:22 INFO - mozversion platform_version: 55.0a1 18:31:22 INFO - using testdate: 1490664682 18:31:22 INFO - actual date: 1490664682 18:31:22 INFO - starting webserver on 'localhost:49242' 18:31:22 INFO - SUITE-START | Running 2 tests 18:31:22 INFO - TEST-START | dromaeo_css 18:31:22 INFO - operating with platform_type : mac_ 18:31:22 INFO - Initialising browser for dromaeo_css test... 18:31:22 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/tmpsZvC5p/profile http://localhost:49242/getInfo.html) 18:31:25 INFO - PID 1638 | __metrics Screen width/height:1600/1200 18:31:25 INFO - PID 1638 | colorDepth:24 18:31:25 INFO - PID 1638 | Browser inner width/height: 1024/689 18:31:25 INFO - PID 1638 | __metrics 18:31:25 ERROR - PID 1638 | 1490664685350 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 18:31:25 INFO - PID 1638 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:31:25 INFO - PID 1638 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:31:26 INFO - TEST-INFO | 1638: exit 0 18:31:26 INFO - Browser initialized. 18:31:26 INFO - Running cycle 1/1 for dromaeo_css test... 18:31:26 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/tmpsZvC5p/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:31:38 INFO - PID 1640 | RSS: Main: 233648128 18:31:38 INFO - PID 1640 | 18:34:44 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 18:34:45 INFO - PID 1640 | RSS: Main: 397287424 18:34:45 INFO - PID 1640 | 18:36:27 INFO - PID 1640 | ************************* 18:36:27 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 18:36:27 INFO - PID 1640 | 18:36:27 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:36:27 INFO - PID 1640 | Full stack: 18:36:27 INFO - PID 1640 | ************************* 18:36:27 INFO - PID 1640 | ************************* 18:36:27 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 18:36:27 INFO - PID 1640 | 18:36:27 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:36:27 INFO - PID 1640 | Full stack: 18:36:27 INFO - PID 1640 | ************************* 18:36:27 INFO - PID 1640 | ************************* 18:36:27 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 18:36:27 INFO - PID 1640 | 18:36:27 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:36:27 INFO - PID 1640 | Full stack: 18:36:27 INFO - PID 1640 | ************************* 18:36:27 INFO - PID 1640 | ************************* 18:36:27 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 18:36:27 INFO - PID 1640 | 18:36:27 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:36:27 INFO - PID 1640 | Full stack: 18:36:27 INFO - PID 1640 | ************************* 18:37:48 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 18:37:49 INFO - PID 1640 | RSS: Main: 368545792 18:37:49 INFO - PID 1640 | 18:40:51 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 18:40:52 INFO - PID 1640 | RSS: Main: 342605824 18:40:52 INFO - PID 1640 | 18:43:54 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 18:43:55 INFO - PID 1640 | RSS: Main: 333275136 18:43:55 INFO - PID 1640 | 18:46:59 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 18:46:59 INFO - PID 1640 | RSS: Main: 343216128 18:46:59 INFO - PID 1640 | 18:50:03 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 18:50:03 INFO - PID 1640 | RSS: Main: 432250880 18:50:03 INFO - PID 1640 | 18:50:03 INFO - PID 1640 | __start_tp_report 18:50:03 INFO - PID 1640 | _x_x_mozilla_page_load 18:50:03 INFO - PID 1640 | _x_x_mozilla_page_load_details 18:50:03 INFO - PID 1640 | |i|pagename|runs| 18:50:03 INFO - PID 1640 | |0;dojo.html;3029.621297337833;3430.091025878143;3480.199554102558;3494.947575786365;3546.148924258179;6082.932835524482;6109.480694141003;6133.438468486901;6137.048757442592;6141.205751383116;6055.333913269536;6061.090836374546;6071.939280607182;6117.87764244716;6125.87748245036;5062.544371006608;5270.710110943896;5491.28613280275;5515.834524964237;5642.069058605346;5364.463553644643;5532.7786888524615;5604.327480702322;5617.859553511154;5631.943680563203;5407.432219616923;5419.83740487786;5431;5445.264889239941;5460.6996615186545;5075.593952483792;5406.216098665711;5406.729663516809;5419.756110975016;5427.3758517770175;5336.332958380202;5377.381601115882;5386.13821788512;5393.406725260218;5395.271638328853;4957.727324997124;6013.188219590343;6019.217501724747;6032.577719559633;6073.271207455089;6373.43850756563;6423.96788016057;6449.193850768654;6450.774222902176;6451.516136289797;5415.702136382497;6473.061406096137;6490;6492.02619607058;6492.772752953671;6230.190075290183;6241.78153764616;6258.530610204208;6267.96866015667;6277.278113016971;6739.730410783612;6740.898886516707;6744.865102697919;6756.054152418666;6758.594484330956;6272.655003974825;6292.968535157386;6299.275583307886;6342.429181373606;6382.074599183093;6515.837104072436;6515.902261466082;6517.543771936013;6522.869542609216;6539.346065393423;6436.935630643728;6442.9355706443275;6447.451966582799;6453.257875344301;6454.031895215661;6179.5056395488255;6296.118543403927;6349.460295874902;6466.870662586722;6482.189726284215;4094.6928980325997;5238.135707608202;5303.390110137307;5307.28351672527;5320.281761962163;4648.790804413809;4654.720716757005;4663.510331415153;4683.992941517522;4688.0858232644305;6224.315325314212;6255.155554000243;6260.843478913063;6281.874362512815;6306.2747784004505;6346.619202847844;6394.520410969196;6400.423961843363;6530.8367290818105;6560.540762146604;6251.4061164189325;6255.96872015637;6272;6279.7802076928;6308.337624549448;5816.680082595498;5827.79602713903;5832.445917637818;5837.824865254049;5861.267341582302;6442;6514.3811337922825;6635.10426092481;6697.330266973264;6734.831629209309;6386.201724784402;6418.326075762165;6419.165508483868;6425.614463132227;6499.870002599827;6692.397684208445;6705.865882682417;6707.128073350434;6734.629595372302;6755.189377274562;5995.280566331893;6409.391107844747;6459.580127291711;6460.773872914426;6461.709223084878;6325.525585581192;6360.236771587439;6530.118434011348;6557.377049180321;6558.573692709959;6241.21984751906;6289.119523266838;6329.968350158219;6370.394812492806;6389.584676995981;5242.711650859087;6025.548083893813;6302.3067462578165;6311.810645680637;6354.142190804182;6289.49684025286;6329.430351268408;6329.841753956188;6332.715027823667;6339.144215530844;6418.4544313731985;6419.2296924369375;6421.357864213542;6439.517036222396;6440.227172739301;6219.346968568235;6246.718897649525;6256.718447669957;6265.216847894013;6283.308836027942 18:50:03 INFO - PID 1640 | |1;ext.html;750.4221749253076;789.7472808701215;813.1258896686064;1129.7853407852513;1151.2344291046454;27835.30411739705;28282.57576136357;28343.29141771457;28346.724397402115;28357.291067723316;37116.659500644935;37600.81199594005;37601.81199094008;37637.059073523174;37641.435378469345;8408.873866892001;8581.227689507943;8588.355873309527;8598.312135029182;8606.483610983361;6091.604045737013;8428.789280267982;8665.523396213206;8670.349723770742;8678.045415004344;6250;6255.15555400022;6293.968530157343;6294.87410251797;6294.968525157391;8315.334773218128;8497.95751021244;8501.192386723313;8504.44721093127;8513.787155321104;8407.285380742644;8484.830303393897;8496.70261540843;8507.31941444689;8526.829463410759;26020;26451.80966856496;38293.42559861604;38627;38703.80648096742;2933.325335172922;2937.6327959005125;2941.0882626385887;2942.1173647905543;2989.252686828293;26882.865585671945;26972;27391.90432382722;27910.860445697843;28176.013839515723;2515.548314026266;2629.9211023669322;2731.207949694586;2795.3011747063233;2841.1902607756692;272257;272713;272909;273001;273334;46603.5339646606;46795.532044679116;46843.82890427698;46849.76575117103;46909.481788865975;39057.80471097683;48309.275360869615;48368.79078023007;48437.27344089842;48603.513964860605;14389.64025899361;14777.482788102365;14787.482438114614;14797.556073317817;14808.703825923421;11921.225120367148;11928.284302941851;11935.343556104324;11936.92567668914;11941.283522988648;12950.805737913939;13701.246431446367;13728.313584320744;13780.931095344458;13852.961027922946;4747;4822.8794280143275;4984.252362145708;5802.506786923074;5868.853278667982;951.871497347863;955.8375076237056;971.1647982734843;979.2264111351952;1021.7905329407454;21802.672959905613;23360.415989600056;23473.70894600779;23505.059797607904;23531.529369412518;990.762217067913;1071.978560428787;1073.1522097543009;1113.0872684398714;1116.625930313338;9296.163345298955;12133.5146594137;12215.328156951453;12236.265824050586;12243.02055835531;463853;473373.99390091765;473576;474420;475779;54787.80848766084;55311.723441382535;55372.72313638406;55381;55420.16869746957;1463.5170393770031;1515.1742300446062;1529.0367068746618;1529.45704274983;1535.4625880941805;23633.527329452972;23699.64450533209;23746.525069498166;23755.881220593787;23760.762392375855;39672.80163599279;39682.60317396789;39724;39738.40392394058;39835.80492585227;389347;389957;390179;391535;391559;33040.33919321648;33717;33879.830600847825;33883.66116338805;33914.49128263029;39563.406548901214;41338;43421.56578434175;43793;43824.455157576354;29311.974080907064;29646;30198;30345.848270758506;30454.543181852732;29062;29112.417751645273;29962.850185748932;30069.346185959108;30155.69844301617;35967.28065438729;36230.09424764402;36671.63328366682;36737.81631091827;36844.81577592095;24677.38306542351;26345;29920.401591968475;30943.845280773454;31051;18756.15597298152;22014.779852201915;22537.43656408603;24097;24223.51552968966 18:50:03 INFO - PID 1640 | |2;jquery.html;384.0935392473763;421.17206994533404;434.1555674213654;436.91480161368537;455.8085604046298;17177.227024783882;20932.16271349146;22601.982910769013;23077.076916923303;23275.650865236992;22551;22567.322980310575;23270.767292327113;23355.649665255034;23631.881840590817;5894.58737888348;5928.6739229342475;6117.143599896018;6167;6167.321594624587;3613.8193090345508;3805.2009078093633;5986.221791167164;6125.203723515937;6127.325994140641;5753.252077229954;6010.729517171738;6037.72830222641;6051.243594550681;6052.031674932012;5791.739371728282;5960.582759206857;6021.789237376693;6026.457618814327;6027.156198132265;5316.840494785162;5341.1187154119525;5346.331708536433;5353.491418315292;5356.705381204032;25211.369715757253;26194.9522019119;27222.183334499998;27599.586006209924;27856.721432785616;1952.2191123550551;1996.0818023709112;2006.147387360366;2013.788552202027;2017.243533674872;16098.953568018042;16355.754663680054;16482.34070637173;16643.50069497917;16997;1477.7666000961365;1557.7273977054083;1586.8016497937758;1595.1147113352085;1645.2678558041675;139045;142816;144537.83193252215;145653;147076;11165;12213;12299.877001229874;12303.630891073282;12392.62822115338;13191.40638671262;14410.593389087888;15597;15627.218639068002;15631.218439078228;8689.391742578086;8692.52191129494;8815.426997245158;8922.330825188006;8976.41653292534;8328.70849520276;8365.456245344034;8372.246497815226;8535.615897284637;8539.530325832015;7431.405487560982;7545.584992825337;7571.167171611119;7727.49771264866;7744.4578879477895;2165.8917054147228;2167.6098302305422;2189.9452513687283;2194.2539536557647;2350.365401341628;911.6581282019242;913.2465715784505;916.5600511754262;917.8256131335025;950.083169741203;19802.504937376394;20565.485862853548;20635.380938571867;20646;20682.68975965362;937.6155776131753;945.281585994649;974.7758015656439;974.8294048541619;974.848898420746;9552.37909535878;10243.436610986468;10513.632022879316;10688.786224275627;10713.87504312036;662022;708842;712252;730253;733927;151176.9999999978;155213.87660945827;159398.60902086477;160313;160530;932.230909499679;1144.4392247798519;1192.0820967854704;1195.9162858599814;1195.9760804783855;17718.20268087965;17756.644867102845;17800.020998845186;17901.462956111336;18184.90907545454;58502.41497584969;58839.41160588511;59754.80490390081;60107;60723.78552428838;82264;82600.5037583536;84034.15965840263;86318.13681863101;88118.55940720256;8231.670733170846;8382.832343352977;8471.788205294917;8715.520646364257;8936.642534298555;13182.736345273233;13443.86556134426;13467.393967271692;13530.323483825572;13626.795598065839;11368.658940231806;11503.252288601214;11719.88280117188;11802.94098529502;11991.6209635893;10346.844797328196;10632.096271816983;10787.352758834495;11056.834147487954;11308.208425410143;11478.426078695864;11898.76202475963;11940.820887686865;12030.879691202976;12070;7341.412686985135;8424.618188300983;8554.401191916506;8674.436161649473;8729.297786931487;9524.523773811421;11367.602133925273;11745.765084698087;11774.82337764917;11979.880201198224 18:50:03 INFO - PID 1640 | |3;mootools.html;1376.8691974262445;1437.7771445425956;1456.169983109628;1463.9707205855889;1464.00447695567;10289.434081125535;10673.3595984241;10673.78652426951;10706.304090234149;10710.143188544916;10427.478626068703;10719.78560428791;10840.970107839763;10851.969062939028;10854.403007834566;2423.39415146213;2480.5038992201544;2481.8883150258193;2482.143660437155;2484.8633325167107;1957.50083728649;2268.990299316805;2337.235347580563;2406.711194656644;2475.393528585499;2098.6327392706294;2124.2671278408925;2341.672165896776;2466.3094333586623;2468.4816188600416;2414.3360575841607;2449.595816690244;2449.669294645236;2451.840630359021;2453.7668921452523;2339.976600233993;2394.8682822444757;2397.113068164773;2398.59223931932;2398.6521954316527;10396;10605.992430719069;10622.893771062269;10822.350658960488;10844;6159.3224745277985;6390.968045159791;6404.935950640481;6406.423421892052;6409.775657851953;3292.7530435217454;3732.533433320835;3853.4374640863794;4092.3247664135383;4092.386142078682;1125.3135587291745;1177.375990724917;1198.9400529973466;1201.4533387308754;1226.7055906582445;14747.557573272712;14951.028183168059;15059.945803793738;15086.54740357791;15095.924520377437;4794.448638406628;5374.48942350476;5676.375598684142;5777.913331300034;5785.363610002896;12665.683357916127;12701.936490317674;12702.618921432371;12711.872881271069;12748.426320815586;6426.196725409324;6431.196100487439;6431.2604050533855;6436.259830119502;6445.033245013192;5639.956608027528;5791.681457519877;5820.708964551755;5883.823485295448;5909.2908850938165;4289.206496798102;4427.46870375557;4458.464984201916;4501.7974191161475;4508.526604706524;1235.092207227687;1367.705943222212;1395.2744572822075;1412.6609613692845;1433.6200906759716;582.2373136025122;646.8318237258253;739.5156172706886;745.1765798792364;786.6578038553248;5993.670348130808;6210.4100110489435;6251.593646412968;6313.463355614732;6338.461230795434;663.389681493018;802.9718959836378;864.6368525219423;869.3349587565517;922.6401703335705;3397.9830100849335;3790.5261842269715;3790.6019867914024;3860.4595356650093;3938.0548668319398;13176.60470185896;13717.794233086512;13731.794023089662;13751;13770.036097473081;13634.181949082888;13658;13676.794848077287;13691.726165476635;13705.794413083811;9148.176664100263;9918.752031199134;9929.3545919515;9954.253430992705;10082.596696132367;6008.128821320972;6071.119687645355;6170.043643235305;6196.690165491617;6242.438180563771;12032.398380081137;12331.260124392566;12368.876311236772;12387.62837114888;12402.503899844267;12319.568815091428;12445.751084978068;12483.188592741442;12486.375681216085;12524.812127817908;8625.784355391166;8932.687355942511;8943.955280223818;8952.28381729447;8954.194122528743;9787.80424391494;9881.555330010311;9912.35569687968;9977.20182385393;9996.400215987063;8373.539455330016;8468.618912148844;8507.532085735344;8563.61463734146;8715.30277577805;8186.222308880647;8250.711225107332;8339.291160251447;8365.456245344034;8423.578821059045;7052.365287123978;7105.360517553445;7137.500374973702;7144.714211431485;7379.372753316028;4407.862186839445;5541.695206763667;5715.257016587983;5719.428057194247;5784;4728.668993170445;5535.778568857361;5645.83062508109;5683.5506945728575;5873.73568189424 18:50:03 INFO - PID 1640 | |4;prototype.html;334.7823914455605;388.93193691104057;396.72229439392413;401.5121627222924;401.70273997242043;5952.2262105926275;6053.818385448438;6072.5406210484825;6119.3880611938785;6125.9387406125925;4647.488776234612;5464.562834973202;5830.970845145779;5865.266841644794;5982.162497250389;3302.9760774160027;4268.935965960513;4369.890752731191;4529.569690879362;4543.522930092342;3718.4236443351188;4530.569595888385;4530.569595888385;4545.045540436512;4571.2457444521615;3476.0106308574605;3508.7193024557973;3983.9243404280974;4255.1915136124035;4514.593686568208;3697.352963231424;4300.290452075404;4530.773461326937;4531.9546804531865;4545.36364908913;3196.4246435641576;3739.915424526911;3866.8259928303296;3870.9225815483533;3880.844766209348;4256.36154576816;4803.063402636487;5506.421825708283;5894.351621321651;5948.375420580864;4728.621710263171;4750.762461876927;5274.235235890813;5313.256144139824;5319.9202011969855;5393.649412788195;6419.711112999927;6437.517186211053;6450.612963222222;6512.055751915946;4301.354796780508;4340.392345071693;4354.54277300882;4357.535759071546;4365.301551751704;5267.894642107175;5666.5183459406;6378.744850205986;6536.901946470807;6912.896306555405;3663.6519530644546;4098.200850834119;4401.471823381215;4407.074514352022;4415.955840441555;3731.3395538985433;4459.130469558405;4493.528179541101;4498.370228167993;4501.48232953208;4221.894452638709;4898.657094003385;4931.852044438672;5029.547340739351;5354.892902141935;6523.18460192476;6532.366908272932;6551;6551.344865513411;6577.276499585042;1473.7931514892186;1520.247477498623;1521.4674863797582;1529.2277399913196;1533.394309247841;862.6549380247826;989.2778271861438;1013.0932815130417;1019.4698756646502;1028.1158203944601;6137.066823950441;6174.5060395168275;6193.225846769154;6204.255489341308;6221.191245138193;5562.7774889004795;6220.471259942956;6713.2279787824045;6713.462922966151;6725.630090345078;5264.9736751315995;5451.2640793493165;6157.815265541952;6158.445739883342;6159;3396.524486571882;3427.0123328469926;3448.77582957107;3518.82405879705;3522.6125126236093;6246.187995560639;6599;6788.626625535643;6835;6916.783695581578;6530.379613936669;6921.826954326081;6928.16861976566;6971.930280697128;6981.755638552626;6034.909476357858;6774.356436138658;6848.520603557703;6857.485688573277;6866.141732283465;5320.430473010394;6671.733130674913;6673.866522669617;6707.692000060137;6709.865802684017;6041.818745437644;6077.544184186292;6376.489880809431;6515.967420162869;6539.607623542603;5816.70916454184;6063.423974722395;6491.805245842632;6566.540342176002;6842.315768423118;4143.3370660694145;4360.324149756792;4365.4979677337515;4393.143337049245;4394.780260987001;5345.358556973188;6615.503837212132;6625.382973725448;6707.597544147172;6717.328267173243;6307.716152773228;6398.392152745481;6417.839554011187;6440.6813547122065;6468.741250350122;5379.373738584735;5414.269073675004;6237.220347456568;6428.164338636135;6448.645324507197;5726.4273572642405;6243.250809902841;6277.96861015692;6309.873802524015;6344.714487847966;5142.740028692994;5647.435256474319;6415.967920160556;6426.485881129405;6447.2263328400895;5158.948410515847;5523.033469142803;6216.968915155395;6296.048225050249;6317.526185536195 18:50:03 INFO - PID 1640 | |5;yui.html;652.9542931994761;681.3391010719604;694.2190036209265;721.4120491799183;735.6285076036601;14418.35117419716;14638.341274642667;14668.339924703387;15007.624809379771;15625;14561.78157327641;14890.25548722565;14916.701665966673;15072.849271507283;15102.244887755623;928.9581968811402;931.2363861633463;952.2191802721771;955.3933252384751;957.0812020460367;962.2638681408728;967.1150896929337;967.4292167621102;967.4582233948976;968.1528662420383;1012.5291739341205;1013.4780587997187;1013.6857574152;1013.9645112421067;1019.8623185869886;1008.4100801031406;1012.185190921308;1012.6556970629985;1013.022433351819;1014.6398028699779;750.2235186581917;750.3172113376801;751.0124186694479;752.2815710995985;752.5221484357472;15480.612984675361;15519.301631426613;16381.935174213639;16777.66444671112;16994.915025424794;12696.492140314376;14097.36561854719;14602.415903363853;14731.705365892729;14758.55724328272;11677.883221167764;11833.230839995373;12010.63968080959;12074.215176013531;12187.573434929824;3009.5034319337283;3064.601601791775;3098.6281646202374;3100.604837702712;3123.81257124571;107127;112261.75476490424;113480;115884;118271;10531.789364212673;11208.775824483464;11270.887291126985;11302.717432064264;11631.360275184947;25037;25136.74863251344;25165.62251566228;25179.99280028815;25183.37041573975;7702.768916932501;7706.152323244439;7729.227077292338;7775.183605721431;7919.643616037177;3982.2433737589763;4073.1726621551725;4129.855455059059;4130.194612050681;4135.152293779768;3995.320795464778;4087.1825634873144;4307.6338511226895;4475.462944446687;4566.109608626352;815.8245977114949;823.8970128733908;832.4214670803855;839.4669384940485;842.5618678287378;558.1627558662007;560.5851669764345;561.926949496571;568.4330824078855;570.5264630356794;8338.458000229966;8859.689910853089;8941.59762810675;9124.224440922464;9487.193588544913;553.9695316757536;562.7411390760215;564.7261078376984;568.5508448325859;588.5585810642018;3936.3701807710627;4050.432939388488;4236.894077648083;4406.669499787528;4428.601425871687;68801;79566.20433795587;83019;90781;91327.54336228277;83987.5800620993;85112;85547.57226213829;86383;87544;74196;74197.6290118546;75756.62121689356;76978.61510692634;77302.61348693221;7630.923690763243;8480.787980300542;8622.870656940277;8686.78283042929;8788.604512797066;32660.530276137157;33473;33795.99999999902;33932.45506587065;34437.827810860785;33616.66383336135;43740.781296093315;45204.28774273554;45207.773961129984;59429;11864.822027669765;12154.513819447124;12399.194052386567;12484.750304994031;12587.811182832082;12484.687882803002;12500.24998500093;12503.312317822607;12505.812412813633;12645.367731613198;11737.226566955507;11927.582534611247;12039.939800300943;12063.758724825278;12219.144659874079;11740.412979350827;11968.640940771791;11987.34069626179;12101.515939362327;12524.248545087325;11642.88357116418;11740.647780566596;11764.529418823493;11890.643280701246;12164.695882603006;7238.746643867439;7986.081600616004;8198.631061602124;8547.316214702685;8853.911460885567;7645.617719114133;8089.635966381645;8564.74305770828;8756.781080472785;10128.84806727885 18:50:03 INFO - PID 1640 | __end_tp_report 18:50:03 INFO - PID 1640 | __start_cc_report 18:50:03 INFO - PID 1640 | _x_x_mozilla_cycle_collect,1718 18:50:03 INFO - PID 1640 | __end_cc_report 18:50:03 INFO - PID 1640 | __startTimestamp1490665803474__endTimestamp 18:50:03 INFO - PID 1640 | 18:50:03 INFO - PID 1640 | ------- Summary: start ------- 18:50:03 INFO - PID 1640 | Number of tests: 6 18:50:03 INFO - PID 1640 | 18:50:03 INFO - PID 1640 | [#0] dojo.html Cycles:165 Average:6011.24 Median:6279.78 stddev:698.44 (11.1%) stddev-sans-first:660.25 18:50:03 INFO - PID 1640 | Values: 3029.6 3430.1 3480.2 3494.9 3546.1 6082.9 6109.5 6133.4 6137.0 6141.2 6055.3 6061.1 6071.9 6117.9 6125.9 5062.5 5270.7 5491.3 5515.8 5642.1 5364.5 5532.8 5604.3 5617.9 5631.9 5407.4 5419.8 5431.0 5445.3 5460.7 5075.6 5406.2 5406.7 5419.8 5427.4 5336.3 5377.4 5386.1 5393.4 5395.3 4957.7 6013.2 6019.2 6032.6 6073.3 6373.4 6424.0 6449.2 6450.8 6451.5 5415.7 6473.1 6490.0 6492.0 6492.8 6230.2 6241.8 6258.5 6268.0 6277.3 6739.7 6740.9 6744.9 6756.1 6758.6 6272.7 6293.0 6299.3 6342.4 6382.1 6515.8 6515.9 6517.5 6522.9 6539.3 6436.9 6442.9 6447.5 6453.3 6454.0 6179.5 6296.1 6349.5 6466.9 6482.2 4094.7 5238.1 5303.4 5307.3 5320.3 4648.8 4654.7 4663.5 4684.0 4688.1 6224.3 6255.2 6260.8 6281.9 6306.3 6346.6 6394.5 6400.4 6530.8 6560.5 6251.4 6256.0 6272.0 6279.8 6308.3 5816.7 5827.8 5832.4 5837.8 5861.3 6442.0 6514.4 6635.1 6697.3 6734.8 6386.2 6418.3 6419.2 6425.6 6499.9 6692.4 6705.9 6707.1 6734.6 6755.2 5995.3 6409.4 6459.6 6460.8 6461.7 6325.5 6360.2 6530.1 6557.4 6558.6 6241.2 6289.1 6330.0 6370.4 6389.6 5242.7 6025.5 6302.3 6311.8 6354.1 6289.5 6329.4 6329.8 6332.7 6339.1 6418.5 6419.2 6421.4 6439.5 6440.2 6219.3 6246.7 6256.7 6265.2 6283.3 18:50:03 INFO - PID 1640 | 18:50:03 INFO - PID 1640 | [#1] ext.html Cycles:180 Average:50774.63 Median:33881.75 stddev:102997.11 (304.0%) stddev-sans-first:103217.56 18:50:03 INFO - PID 1640 | Values: 750.4 789.7 813.1 1129.8 1151.2 27835.3 28282.6 28343.3 28346.7 28357.3 37116.7 37600.8 37601.8 37637.1 37641.4 8408.9 8581.2 8588.4 8598.3 8606.5 6091.6 8428.8 8665.5 8670.3 8678.0 6250.0 6255.2 6294.0 6294.9 6295.0 8315.3 8498.0 8501.2 8504.4 8513.8 8407.3 8484.8 8496.7 8507.3 8526.8 26020.0 26451.8 38293.4 38627.0 38703.8 2933.3 2937.6 2941.1 2942.1 2989.3 26882.9 26972.0 27391.9 27910.9 28176.0 2515.5 2629.9 2731.2 2795.3 2841.2 272257.0 272713.0 272909.0 273001.0 273334.0 46603.5 46795.5 46843.8 46849.8 46909.5 39057.8 48309.3 48368.8 48437.3 48603.5 14389.6 14777.5 14787.5 14797.6 14808.7 11921.2 11928.3 11935.3 11936.9 11941.3 12950.8 13701.2 13728.3 13780.9 13853.0 4747.0 4822.9 4984.3 5802.5 5868.9 951.9 955.8 971.2 979.2 1021.8 21802.7 23360.4 23473.7 23505.1 23531.5 990.8 1072.0 1073.2 1113.1 1116.6 9296.2 12133.5 12215.3 12236.3 12243.0 463853.0 473374.0 473576.0 474420.0 475779.0 54787.8 55311.7 55372.7 55381.0 55420.2 1463.5 1515.2 1529.0 1529.5 1535.5 23633.5 23699.6 23746.5 23755.9 23760.8 39672.8 39682.6 39724.0 39738.4 39835.8 389347.0 389957.0 390179.0 391535.0 391559.0 33040.3 33717.0 33879.8 33883.7 33914.5 39563.4 41338.0 43421.6 43793.0 43824.5 29312.0 29646.0 30198.0 30345.8 30454.5 29062.0 29112.4 29962.9 30069.3 30155.7 35967.3 36230.1 36671.6 36737.8 36844.8 24677.4 26345.0 29920.4 30943.8 31051.0 18756.2 22014.8 22537.4 24097.0 24223.5 18:50:03 INFO - PID 1640 | 18:50:03 INFO - PID 1640 | [#2] jquery.html Cycles:180 Average:40469.25 Median:12991.12 stddev:118845.69 (914.8%) stddev-sans-first:119140.97 18:50:03 INFO - PID 1640 | Values: 384.1 421.2 434.2 436.9 455.8 17177.2 20932.2 22602.0 23077.1 23275.7 22551.0 22567.3 23270.8 23355.6 23631.9 5894.6 5928.7 6117.1 6167.0 6167.3 3613.8 3805.2 5986.2 6125.2 6127.3 5753.3 6010.7 6037.7 6051.2 6052.0 5791.7 5960.6 6021.8 6026.5 6027.2 5316.8 5341.1 5346.3 5353.5 5356.7 25211.4 26195.0 27222.2 27599.6 27856.7 1952.2 1996.1 2006.1 2013.8 2017.2 16099.0 16355.8 16482.3 16643.5 16997.0 1477.8 1557.7 1586.8 1595.1 1645.3 139045.0 142816.0 144537.8 145653.0 147076.0 11165.0 12213.0 12299.9 12303.6 12392.6 13191.4 14410.6 15597.0 15627.2 15631.2 8689.4 8692.5 8815.4 8922.3 8976.4 8328.7 8365.5 8372.2 8535.6 8539.5 7431.4 7545.6 7571.2 7727.5 7744.5 2165.9 2167.6 2189.9 2194.3 2350.4 911.7 913.2 916.6 917.8 950.1 19802.5 20565.5 20635.4 20646.0 20682.7 937.6 945.3 974.8 974.8 974.8 9552.4 10243.4 10513.6 10688.8 10713.9 662022.0 708842.0 712252.0 730253.0 733927.0 151177.0 155213.9 159398.6 160313.0 160530.0 932.2 1144.4 1192.1 1195.9 1196.0 17718.2 17756.6 17800.0 17901.5 18184.9 58502.4 58839.4 59754.8 60107.0 60723.8 82264.0 82600.5 84034.2 86318.1 88118.6 8231.7 8382.8 8471.8 8715.5 8936.6 13182.7 13443.9 13467.4 13530.3 13626.8 11368.7 11503.3 11719.9 11802.9 11991.6 10346.8 10632.1 10787.4 11056.8 11308.2 11478.4 11898.8 11940.8 12030.9 12070.0 7341.4 8424.6 8554.4 8674.4 8729.3 9524.5 11367.6 11745.8 11774.8 11979.9 18:50:03 INFO - PID 1640 | 18:50:03 INFO - PID 1640 | [#3] mootools.html Cycles:180 Average:6698.41 Median:3822.02 stddev:4174.47 (109.2%) stddev-sans-first:4167.02 18:50:03 INFO - PID 1640 | Values: 1376.9 1437.8 1456.2 1464.0 1464.0 10289.4 10673.4 10673.8 10706.3 10710.1 10427.5 10719.8 10841.0 10852.0 10854.4 2423.4 2480.5 2481.9 2482.1 2484.9 1957.5 2269.0 2337.2 2406.7 2475.4 2098.6 2124.3 2341.7 2466.3 2468.5 2414.3 2449.6 2449.7 2451.8 2453.8 2340.0 2394.9 2397.1 2398.6 2398.7 10396.0 10606.0 10622.9 10822.4 10844.0 6159.3 6391.0 6404.9 6406.4 6409.8 3292.8 3732.5 3853.4 4092.3 4092.4 1125.3 1177.4 1198.9 1201.5 1226.7 14747.6 14951.0 15059.9 15086.5 15095.9 4794.4 5374.5 5676.4 5777.9 5785.4 12665.7 12701.9 12702.6 12711.9 12748.4 6426.2 6431.2 6431.3 6436.3 6445.0 5640.0 5791.7 5820.7 5883.8 5909.3 4289.2 4427.5 4458.5 4501.8 4508.5 1235.1 1367.7 1395.3 1412.7 1433.6 582.2 646.8 739.5 745.2 786.7 5993.7 6210.4 6251.6 6313.5 6338.5 663.4 803.0 864.6 869.3 922.6 3398.0 3790.5 3790.6 3860.5 3938.1 13176.6 13717.8 13731.8 13751.0 13770.0 13634.2 13658.0 13676.8 13691.7 13705.8 9148.2 9918.8 9929.4 9954.3 10082.6 6008.1 6071.1 6170.0 6196.7 6242.4 12032.4 12331.3 12368.9 12387.6 12402.5 12319.6 12445.8 12483.2 12486.4 12524.8 8625.8 8932.7 8944.0 8952.3 8954.2 9787.8 9881.6 9912.4 9977.2 9996.4 8373.5 8468.6 8507.5 8563.6 8715.3 8186.2 8250.7 8339.3 8365.5 8423.6 7052.4 7105.4 7137.5 7144.7 7379.4 4407.9 5541.7 5715.3 5719.4 5784.0 4728.7 5535.8 5645.8 5683.6 5873.7 18:50:03 INFO - PID 1640 | 18:50:03 INFO - PID 1640 | [#4] prototype.html Cycles:180 Average:5125.91 Median:5656.98 stddev:1634.52 (28.9%) stddev-sans-first:1599.05 18:50:03 INFO - PID 1640 | Values: 334.8 388.9 396.7 401.5 401.7 5952.2 6053.8 6072.5 6119.4 6125.9 4647.5 5464.6 5831.0 5865.3 5982.2 3303.0 4268.9 4369.9 4529.6 4543.5 3718.4 4530.6 4530.6 4545.0 4571.2 3476.0 3508.7 3983.9 4255.2 4514.6 3697.4 4300.3 4530.8 4532.0 4545.4 3196.4 3739.9 3866.8 3870.9 3880.8 4256.4 4803.1 5506.4 5894.4 5948.4 4728.6 4750.8 5274.2 5313.3 5319.9 5393.6 6419.7 6437.5 6450.6 6512.1 4301.4 4340.4 4354.5 4357.5 4365.3 5267.9 5666.5 6378.7 6536.9 6912.9 3663.7 4098.2 4401.5 4407.1 4416.0 3731.3 4459.1 4493.5 4498.4 4501.5 4221.9 4898.7 4931.9 5029.5 5354.9 6523.2 6532.4 6551.0 6551.3 6577.3 1473.8 1520.2 1521.5 1529.2 1533.4 862.7 989.3 1013.1 1019.5 1028.1 6137.1 6174.5 6193.2 6204.3 6221.2 5562.8 6220.5 6713.2 6713.5 6725.6 5265.0 5451.3 6157.8 6158.4 6159.0 3396.5 3427.0 3448.8 3518.8 3522.6 6246.2 6599.0 6788.6 6835.0 6916.8 6530.4 6921.8 6928.2 6971.9 6981.8 6034.9 6774.4 6848.5 6857.5 6866.1 5320.4 6671.7 6673.9 6707.7 6709.9 6041.8 6077.5 6376.5 6516.0 6539.6 5816.7 6063.4 6491.8 6566.5 6842.3 4143.3 4360.3 4365.5 4393.1 4394.8 5345.4 6615.5 6625.4 6707.6 6717.3 6307.7 6398.4 6417.8 6440.7 6468.7 5379.4 5414.3 6237.2 6428.2 6448.6 5726.4 6243.3 6278.0 6309.9 6344.7 5142.7 5647.4 6416.0 6426.5 6447.2 5158.9 5523.0 6217.0 6296.0 6317.5 18:50:03 INFO - PID 1640 | 18:50:03 INFO - PID 1640 | [#5] yui.html Cycles:180 Average:18632.94 Median:4061.80 stddev:27257.64 (671.1%) stddev-sans-first:27300.67 18:50:03 INFO - PID 1640 | Values: 653.0 681.3 694.2 721.4 735.6 14418.4 14638.3 14668.3 15007.6 15625.0 14561.8 14890.3 14916.7 15072.8 15102.2 929.0 931.2 952.2 955.4 957.1 962.3 967.1 967.4 967.5 968.2 1012.5 1013.5 1013.7 1014.0 1019.9 1008.4 1012.2 1012.7 1013.0 1014.6 750.2 750.3 751.0 752.3 752.5 15480.6 15519.3 16381.9 16777.7 16994.9 12696.5 14097.4 14602.4 14731.7 14758.6 11677.9 11833.2 12010.6 12074.2 12187.6 3009.5 3064.6 3098.6 3100.6 3123.8 107127.0 112261.8 113480.0 115884.0 118271.0 10531.8 11208.8 11270.9 11302.7 11631.4 25037.0 25136.7 25165.6 25180.0 25183.4 7702.8 7706.2 7729.2 7775.2 7919.6 3982.2 4073.2 4129.9 4130.2 4135.2 3995.3 4087.2 4307.6 4475.5 4566.1 815.8 823.9 832.4 839.5 842.6 558.2 560.6 561.9 568.4 570.5 8338.5 8859.7 8941.6 9124.2 9487.2 554.0 562.7 564.7 568.6 588.6 3936.4 4050.4 4236.9 4406.7 4428.6 68801.0 79566.2 83019.0 90781.0 91327.5 83987.6 85112.0 85547.6 86383.0 87544.0 74196.0 74197.6 75756.6 76978.6 77302.6 7630.9 8480.8 8622.9 8686.8 8788.6 32660.5 33473.0 33796.0 33932.5 34437.8 33616.7 43740.8 45204.3 45207.8 59429.0 11864.8 12154.5 12399.2 12484.8 12587.8 12484.7 12500.2 12503.3 12505.8 12645.4 11737.2 11927.6 12039.9 12063.8 12219.1 11740.4 11968.6 11987.3 12101.5 12524.2 11642.9 11740.6 11764.5 11890.6 12164.7 7238.7 7986.1 8198.6 8547.3 8853.9 7645.6 8089.6 8564.7 8756.8 10128.8 18:50:03 INFO - PID 1640 | -------- Summary: end -------- 18:50:03 INFO - PID 1640 | 18:50:04 INFO - TEST-INFO | 1640: exit 0 18:50:04 INFO - TEST-OK | dromaeo_css | took 1121484ms 18:50:04 INFO - TEST-START | kraken 18:50:04 INFO - operating with platform_type : mac_ 18:50:04 INFO - Initialising browser for kraken test... 18:50:04 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/tmpcSSPXj/profile http://localhost:49242/getInfo.html) 18:50:06 INFO - PID 1650 | __metrics Screen width/height:1600/1200 18:50:06 INFO - PID 1650 | colorDepth:24 18:50:06 INFO - PID 1650 | Browser inner width/height: 1024/689 18:50:06 INFO - PID 1650 | __metrics 18:50:06 ERROR - PID 1650 | 1490665806749 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 18:50:06 INFO - PID 1650 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:50:06 INFO - PID 1650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:50:07 INFO - TEST-INFO | 1650: exit 0 18:50:07 INFO - Browser initialized. 18:50:07 INFO - Running cycle 1/1 for kraken test... 18:50:07 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/tmpcSSPXj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:50:19 INFO - PID 1651 | RSS: Main: 233922560 18:50:19 INFO - PID 1651 | 18:50:22 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 18:50:23 INFO - PID 1651 | RSS: Main: 287490048 18:50:23 INFO - PID 1651 | 18:50:26 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 18:50:27 INFO - PID 1651 | RSS: Main: 290775040 18:50:27 INFO - PID 1651 | 18:50:30 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 18:50:31 INFO - PID 1651 | RSS: Main: 379535360 18:50:31 INFO - PID 1651 | 18:50:33 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 18:50:34 INFO - PID 1651 | RSS: Main: 282275840 18:50:34 INFO - PID 1651 | 18:50:36 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:50:36 INFO - PID 1651 | RSS: Main: 287002624 18:50:36 INFO - PID 1651 | 18:50:39 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 18:50:40 INFO - PID 1651 | RSS: Main: 329121792 18:50:40 INFO - PID 1651 | 18:50:43 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 18:50:44 INFO - PID 1651 | RSS: Main: 332451840 18:50:44 INFO - PID 1651 | 18:50:47 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 18:50:48 INFO - PID 1651 | RSS: Main: 331485184 18:50:48 INFO - PID 1651 | 18:50:49 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:50:50 INFO - PID 1651 | RSS: Main: 305451008 18:50:50 INFO - PID 1651 | 18:50:52 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:50:53 INFO - PID 1651 | RSS: Main: 307826688 18:50:53 INFO - PID 1651 | 18:50:55 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:50:56 INFO - PID 1651 | RSS: Main: 309800960 18:50:56 INFO - PID 1651 | 18:50:59 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:51:00 INFO - PID 1651 | RSS: Main: 326307840 18:51:00 INFO - PID 1651 | 18:51:03 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:51:03 INFO - PID 1651 | RSS: Main: 320491520 18:51:03 INFO - PID 1651 | 18:51:05 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 18:51:06 INFO - PID 1651 | RSS: Main: 382742528 18:51:06 INFO - PID 1651 | 18:51:06 INFO - PID 1651 | __start_tp_report 18:51:06 INFO - PID 1651 | _x_x_mozilla_page_load 18:51:06 INFO - PID 1651 | _x_x_mozilla_page_load_details 18:51:06 INFO - PID 1651 | |i|pagename|runs| 18:51:06 INFO - PID 1651 | |0;ai-astar;112;123;123;124;125;132;123;123;124;123 18:51:06 INFO - PID 1651 | |1;audio-beat-detection;159;118;120;164;122;118;119;115;145;118 18:51:06 INFO - PID 1651 | |2;audio-dft;212;213;215;222;213;212;222;220;216;212 18:51:06 INFO - PID 1651 | |3;audio-fft;87;66;65;96;67;64;66;63;82;61 18:51:06 INFO - PID 1651 | |4;audio-oscillator;98;78;94;77;76;74;90;75;74;92 18:51:06 INFO - PID 1651 | |5;imaging-gaussian-blur;92;92;93;93;94;93;93;93;92;92 18:51:06 INFO - PID 1651 | |6;imaging-darkroom;101;102;100;102;100;101;102;100;101;121 18:51:06 INFO - PID 1651 | |7;imaging-desaturate;102;102;101;102;103;102;99;99;102;103 18:51:06 INFO - PID 1651 | |8;json-parse-financial;66;64;64;65;65;65;65;65;65;65 18:51:06 INFO - PID 1651 | |9;json-stringify-tinderbox;61;65;62;73;70;64;63;65;64;65 18:51:06 INFO - PID 1651 | |10;stanford-crypto-aes;92;89;92;103;118;117;91;91;89;92 18:51:06 INFO - PID 1651 | |11;stanford-crypto-ccm;170;173;144;162;151;155;151;149;205;167 18:51:06 INFO - PID 1651 | |12;stanford-crypto-pbkdf2;206;202;199;193;204;204;212;202;201;201 18:51:06 INFO - PID 1651 | |13;stanford-crypto-sha256-iterative;72;75;73;75;84;107;107;75;73;74 18:51:06 INFO - PID 1651 | __end_tp_report 18:51:06 INFO - PID 1651 | __start_cc_report 18:51:06 INFO - PID 1651 | _x_x_mozilla_cycle_collect,2151 18:51:06 INFO - PID 1651 | __end_cc_report 18:51:06 INFO - PID 1651 | __startTimestamp1490665866235__endTimestamp 18:51:06 INFO - PID 1651 | 18:51:06 INFO - PID 1651 | ------- Summary: start ------- 18:51:06 INFO - PID 1651 | Number of tests: 14 18:51:06 INFO - PID 1651 | 18:51:06 INFO - PID 1651 | [#0] ai-astar Cycles:10 Average:123.20 Median:123.50 stddev:4.80 (3.9%) stddev-sans-first:2.92 18:51:06 INFO - PID 1651 | Values: 112.0 123.0 123.0 124.0 125.0 132.0 123.0 123.0 124.0 123.0 18:51:06 INFO - PID 1651 | 18:51:06 INFO - PID 1651 | [#1] audio-beat-detection Cycles:10 Average:129.80 Median:121.00 stddev:18.75 (15.5%) stddev-sans-first:16.64 18:51:06 INFO - PID 1651 | Values: 159.0 118.0 120.0 164.0 122.0 118.0 119.0 115.0 145.0 118.0 18:51:06 INFO - PID 1651 | 18:51:06 INFO - PID 1651 | [#2] audio-dft Cycles:10 Average:215.70 Median:215.50 stddev:4.14 (1.9%) stddev-sans-first:4.17 18:51:06 INFO - PID 1651 | Values: 212.0 213.0 215.0 222.0 213.0 212.0 222.0 220.0 216.0 212.0 18:51:06 INFO - PID 1651 | 18:51:06 INFO - PID 1651 | [#3] audio-fft Cycles:10 Average:71.70 Median:66.50 stddev:12.07 (18.2%) stddev-sans-first:11.47 18:51:06 INFO - PID 1651 | Values: 87.0 66.0 65.0 96.0 67.0 64.0 66.0 63.0 82.0 61.0 18:51:06 INFO - PID 1651 | 18:51:06 INFO - PID 1651 | [#4] audio-oscillator Cycles:10 Average:82.80 Median:84.00 stddev:9.50 (11.3%) stddev-sans-first:8.33 18:51:06 INFO - PID 1651 | Values: 98.0 78.0 94.0 77.0 76.0 74.0 90.0 75.0 74.0 92.0 18:51:06 INFO - PID 1651 | 18:51:06 INFO - PID 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:92.70 Median:93.00 stddev:0.67 (0.7%) stddev-sans-first:0.67 18:51:06 INFO - PID 1651 | Values: 92.0 92.0 93.0 93.0 94.0 93.0 93.0 93.0 92.0 92.0 18:51:06 INFO - PID 1651 | 18:51:06 INFO - PID 1651 | [#6] imaging-darkroom Cycles:10 Average:103.00 Median:101.50 stddev:6.38 (6.3%) stddev-sans-first:6.72 18:51:06 INFO - PID 1651 | Values: 101.0 102.0 100.0 102.0 100.0 101.0 102.0 100.0 101.0 121.0 18:51:06 INFO - PID 1651 | 18:51:06 INFO - PID 1651 | [#7] imaging-desaturate Cycles:10 Average:101.50 Median:102.50 stddev:1.43 (1.4%) stddev-sans-first:1.51 18:51:06 INFO - PID 1651 | Values: 102.0 102.0 101.0 102.0 103.0 102.0 99.0 99.0 102.0 103.0 18:51:06 INFO - PID 1651 | 18:51:06 INFO - PID 1651 | [#8] json-parse-financial Cycles:10 Average:64.90 Median:65.00 stddev:0.57 (0.9%) stddev-sans-first:0.44 18:51:06 INFO - PID 1651 | Values: 66.0 64.0 64.0 65.0 65.0 65.0 65.0 65.0 65.0 65.0 18:51:06 INFO - PID 1651 | 18:51:06 INFO - PID 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:65.20 Median:65.00 stddev:3.65 (5.6%) stddev-sans-first:3.54 18:51:06 INFO - PID 1651 | Values: 61.0 65.0 62.0 73.0 70.0 64.0 63.0 65.0 64.0 65.0 18:51:06 INFO - PID 1651 | 18:51:06 INFO - PID 1651 | [#10] stanford-crypto-aes Cycles:10 Average:97.40 Median:91.00 stddev:11.31 (12.4%) stddev-sans-first:11.82 18:51:06 INFO - PID 1651 | Values: 92.0 89.0 92.0 103.0 118.0 117.0 91.0 91.0 89.0 92.0 18:51:06 INFO - PID 1651 | 18:51:06 INFO - PID 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:162.70 Median:164.50 stddev:17.76 (10.8%) stddev-sans-first:18.64 18:51:06 INFO - PID 1651 | Values: 170.0 173.0 144.0 162.0 151.0 155.0 151.0 149.0 205.0 167.0 18:51:06 INFO - PID 1651 | 18:51:06 INFO - PID 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:202.40 Median:203.00 stddev:4.88 (2.4%) stddev-sans-first:5.00 18:51:06 INFO - PID 1651 | Values: 206.0 202.0 199.0 193.0 204.0 204.0 212.0 202.0 201.0 201.0 18:51:06 INFO - PID 1651 | 18:51:06 INFO - PID 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:81.50 Median:74.50 stddev:13.84 (18.6%) stddev-sans-first:14.25 18:51:06 INFO - PID 1651 | Values: 72.0 75.0 73.0 75.0 84.0 107.0 107.0 75.0 73.0 74.0 18:51:06 INFO - PID 1651 | -------- Summary: end -------- 18:51:06 INFO - PID 1651 | 18:51:07 INFO - TEST-INFO | 1651: exit 0 18:51:07 INFO - TEST-OK | kraken | took 62990ms 18:51:07 INFO - SUITE-END | took 1184s 18:51:07 INFO - Completed test suite (00:19:44) 18:51:07 INFO - javascript benchmark 18:51:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7469.416886949018, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3029.621297337833, 3430.091025878143, 3480.199554102558, 3494.947575786365, 3546.148924258179, 6082.932835524482, 6109.480694141003, 6133.438468486901, 6137.048757442592, 6141.205751383116, 6055.333913269536, 6061.090836374546, 6071.939280607182, 6117.87764244716, 6125.87748245036, 5062.544371006608, 5270.710110943896, 5491.28613280275, 5515.834524964237, 5642.069058605346, 5364.463553644643, 5532.7786888524615, 5604.327480702322, 5617.859553511154, 5631.943680563203, 5407.432219616923, 5419.83740487786, 5431.0, 5445.264889239941, 5460.6996615186545, 5075.593952483792, 5406.216098665711, 5406.729663516809, 5419.756110975016, 5427.3758517770175, 5336.332958380202, 5377.381601115882, 5386.13821788512, 5393.406725260218, 5395.271638328853, 4957.727324997124, 6013.188219590343, 6019.217501724747, 6032.577719559633, 6073.271207455089, 6373.43850756563, 6423.96788016057, 6449.193850768654, 6450.774222902176, 6451.516136289797, 5415.702136382497, 6473.061406096137, 6490.0, 6492.02619607058, 6492.772752953671, 6230.190075290183, 6241.78153764616, 6258.530610204208, 6267.96866015667, 6277.278113016971, 6739.730410783612, 6740.898886516707, 6744.865102697919, 6756.054152418666, 6758.594484330956, 6272.655003974825, 6292.968535157386, 6299.275583307886, 6342.429181373606, 6382.074599183093, 6515.837104072436, 6515.902261466082, 6517.543771936013, 6522.869542609216, 6539.346065393423, 6436.935630643728, 6442.9355706443275, 6447.451966582799, 6453.257875344301, 6454.031895215661, 6179.5056395488255, 6296.118543403927, 6349.460295874902, 6466.870662586722, 6482.189726284215, 4094.6928980325997, 5238.135707608202, 5303.390110137307, 5307.28351672527, 5320.281761962163, 4648.790804413809, 4654.720716757005, 4663.510331415153, 4683.992941517522, 4688.0858232644305, 6224.315325314212, 6255.155554000243, 6260.843478913063, 6281.874362512815, 6306.2747784004505, 6346.619202847844, 6394.520410969196, 6400.423961843363, 6530.8367290818105, 6560.540762146604, 6251.4061164189325, 6255.96872015637, 6272.0, 6279.7802076928, 6308.337624549448, 5816.680082595498, 5827.79602713903, 5832.445917637818, 5837.824865254049, 5861.267341582302, 6442.0, 6514.3811337922825, 6635.10426092481, 6697.330266973264, 6734.831629209309, 6386.201724784402, 6418.326075762165, 6419.165508483868, 6425.614463132227, 6499.870002599827, 6692.397684208445, 6705.865882682417, 6707.128073350434, 6734.629595372302, 6755.189377274562, 5995.280566331893, 6409.391107844747, 6459.580127291711, 6460.773872914426, 6461.709223084878, 6325.525585581192, 6360.236771587439, 6530.118434011348, 6557.377049180321, 6558.573692709959, 6241.21984751906, 6289.119523266838, 6329.968350158219, 6370.394812492806, 6389.584676995981, 5242.711650859087, 6025.548083893813, 6302.3067462578165, 6311.810645680637, 6354.142190804182, 6289.49684025286, 6329.430351268408, 6329.841753956188, 6332.715027823667, 6339.144215530844, 6418.4544313731985, 6419.2296924369375, 6421.357864213542, 6439.517036222396, 6440.227172739301, 6219.346968568235, 6246.718897649525, 6256.718447669957, 6265.216847894013, 6283.308836027942], "value": 5965.071673649321, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [750.4221749253076, 789.7472808701215, 813.1258896686064, 1129.7853407852513, 1151.2344291046454, 27835.30411739705, 28282.57576136357, 28343.29141771457, 28346.724397402115, 28357.291067723316, 37116.659500644935, 37600.81199594005, 37601.81199094008, 37637.059073523174, 37641.435378469345, 8408.873866892001, 8581.227689507943, 8588.355873309527, 8598.312135029182, 8606.483610983361, 6091.604045737013, 8428.789280267982, 8665.523396213206, 8670.349723770742, 8678.045415004344, 6250.0, 6255.15555400022, 6293.968530157343, 6294.87410251797, 6294.968525157391, 8315.334773218128, 8497.95751021244, 8501.192386723313, 8504.44721093127, 8513.787155321104, 8407.285380742644, 8484.830303393897, 8496.70261540843, 8507.31941444689, 8526.829463410759, 26020.0, 26451.80966856496, 38293.42559861604, 38627.0, 38703.80648096742, 2933.325335172922, 2937.6327959005125, 2941.0882626385887, 2942.1173647905543, 2989.252686828293, 26882.865585671945, 26972.0, 27391.90432382722, 27910.860445697843, 28176.013839515723, 2515.548314026266, 2629.9211023669322, 2731.207949694586, 2795.3011747063233, 2841.1902607756692, 272257.0, 272713.0, 272909.0, 273001.0, 273334.0, 46603.5339646606, 46795.532044679116, 46843.82890427698, 46849.76575117103, 46909.481788865975, 39057.80471097683, 48309.275360869615, 48368.79078023007, 48437.27344089842, 48603.513964860605, 14389.64025899361, 14777.482788102365, 14787.482438114614, 14797.556073317817, 14808.703825923421, 11921.225120367148, 11928.284302941851, 11935.343556104324, 11936.92567668914, 11941.283522988648, 12950.805737913939, 13701.246431446367, 13728.313584320744, 13780.931095344458, 13852.961027922946, 4747.0, 4822.8794280143275, 4984.252362145708, 5802.506786923074, 5868.853278667982, 951.871497347863, 955.8375076237056, 971.1647982734843, 979.2264111351952, 1021.7905329407454, 21802.672959905613, 23360.415989600056, 23473.70894600779, 23505.059797607904, 23531.529369412518, 990.762217067913, 1071.978560428787, 1073.1522097543009, 1113.0872684398714, 1116.625930313338, 9296.163345298955, 12133.5146594137, 12215.328156951453, 12236.265824050586, 12243.02055835531, 463853.0, 473373.99390091765, 473576.0, 474420.0, 475779.0, 54787.80848766084, 55311.723441382535, 55372.72313638406, 55381.0, 55420.16869746957, 1463.5170393770031, 1515.1742300446062, 1529.0367068746618, 1529.45704274983, 1535.4625880941805, 23633.527329452972, 23699.64450533209, 23746.525069498166, 23755.881220593787, 23760.762392375855, 39672.80163599279, 39682.60317396789, 39724.0, 39738.40392394058, 39835.80492585227, 389347.0, 389957.0, 390179.0, 391535.0, 391559.0, 33040.33919321648, 33717.0, 33879.830600847825, 33883.66116338805, 33914.49128263029, 39563.406548901214, 41338.0, 43421.56578434175, 43793.0, 43824.455157576354, 29311.974080907064, 29646.0, 30198.0, 30345.848270758506, 30454.543181852732, 29062.0, 29112.417751645273, 29962.850185748932, 30069.346185959108, 30155.69844301617, 35967.28065438729, 36230.09424764402, 36671.63328366682, 36737.81631091827, 36844.81577592095, 24677.38306542351, 26345.0, 29920.401591968475, 30943.845280773454, 31051.0, 18756.15597298152, 22014.779852201915, 22537.43656408603, 24097.0, 24223.51552968966], "value": 17016.560957397618, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.0935392473763, 421.17206994533404, 434.1555674213654, 436.91480161368537, 455.8085604046298, 17177.227024783882, 20932.16271349146, 22601.982910769013, 23077.076916923303, 23275.650865236992, 22551.0, 22567.322980310575, 23270.767292327113, 23355.649665255034, 23631.881840590817, 5894.58737888348, 5928.6739229342475, 6117.143599896018, 6167.0, 6167.321594624587, 3613.8193090345508, 3805.2009078093633, 5986.221791167164, 6125.203723515937, 6127.325994140641, 5753.252077229954, 6010.729517171738, 6037.72830222641, 6051.243594550681, 6052.031674932012, 5791.739371728282, 5960.582759206857, 6021.789237376693, 6026.457618814327, 6027.156198132265, 5316.840494785162, 5341.1187154119525, 5346.331708536433, 5353.491418315292, 5356.705381204032, 25211.369715757253, 26194.9522019119, 27222.183334499998, 27599.586006209924, 27856.721432785616, 1952.2191123550551, 1996.0818023709112, 2006.147387360366, 2013.788552202027, 2017.243533674872, 16098.953568018042, 16355.754663680054, 16482.34070637173, 16643.50069497917, 16997.0, 1477.7666000961365, 1557.7273977054083, 1586.8016497937758, 1595.1147113352085, 1645.2678558041675, 139045.0, 142816.0, 144537.83193252215, 145653.0, 147076.0, 11165.0, 12213.0, 12299.877001229874, 12303.630891073282, 12392.62822115338, 13191.40638671262, 14410.593389087888, 15597.0, 15627.218639068002, 15631.218439078228, 8689.391742578086, 8692.52191129494, 8815.426997245158, 8922.330825188006, 8976.41653292534, 8328.70849520276, 8365.456245344034, 8372.246497815226, 8535.615897284637, 8539.530325832015, 7431.405487560982, 7545.584992825337, 7571.167171611119, 7727.49771264866, 7744.4578879477895, 2165.8917054147228, 2167.6098302305422, 2189.9452513687283, 2194.2539536557647, 2350.365401341628, 911.6581282019242, 913.2465715784505, 916.5600511754262, 917.8256131335025, 950.083169741203, 19802.504937376394, 20565.485862853548, 20635.380938571867, 20646.0, 20682.68975965362, 937.6155776131753, 945.281585994649, 974.7758015656439, 974.8294048541619, 974.848898420746, 9552.37909535878, 10243.436610986468, 10513.632022879316, 10688.786224275627, 10713.87504312036, 662022.0, 708842.0, 712252.0, 730253.0, 733927.0, 151176.9999999978, 155213.87660945827, 159398.60902086477, 160313.0, 160530.0, 932.230909499679, 1144.4392247798519, 1192.0820967854704, 1195.9162858599814, 1195.9760804783855, 17718.20268087965, 17756.644867102845, 17800.020998845186, 17901.462956111336, 18184.90907545454, 58502.41497584969, 58839.41160588511, 59754.80490390081, 60107.0, 60723.78552428838, 82264.0, 82600.5037583536, 84034.15965840263, 86318.13681863101, 88118.55940720256, 8231.670733170846, 8382.832343352977, 8471.788205294917, 8715.520646364257, 8936.642534298555, 13182.736345273233, 13443.86556134426, 13467.393967271692, 13530.323483825572, 13626.795598065839, 11368.658940231806, 11503.252288601214, 11719.88280117188, 11802.94098529502, 11991.6209635893, 10346.844797328196, 10632.096271816983, 10787.352758834495, 11056.834147487954, 11308.208425410143, 11478.426078695864, 11898.76202475963, 11940.820887686865, 12030.879691202976, 12070.0, 7341.412686985135, 8424.618188300983, 8554.401191916506, 8674.436161649473, 8729.297786931487, 9524.523773811421, 11367.602133925273, 11745.765084698087, 11774.82337764917, 11979.880201198224], "value": 10189.180625174005, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1376.8691974262445, 1437.7771445425956, 1456.169983109628, 1463.9707205855889, 1464.00447695567, 10289.434081125535, 10673.3595984241, 10673.78652426951, 10706.304090234149, 10710.143188544916, 10427.478626068703, 10719.78560428791, 10840.970107839763, 10851.969062939028, 10854.403007834566, 2423.39415146213, 2480.5038992201544, 2481.8883150258193, 2482.143660437155, 2484.8633325167107, 1957.50083728649, 2268.990299316805, 2337.235347580563, 2406.711194656644, 2475.393528585499, 2098.6327392706294, 2124.2671278408925, 2341.672165896776, 2466.3094333586623, 2468.4816188600416, 2414.3360575841607, 2449.595816690244, 2449.669294645236, 2451.840630359021, 2453.7668921452523, 2339.976600233993, 2394.8682822444757, 2397.113068164773, 2398.59223931932, 2398.6521954316527, 10396.0, 10605.992430719069, 10622.893771062269, 10822.350658960488, 10844.0, 6159.3224745277985, 6390.968045159791, 6404.935950640481, 6406.423421892052, 6409.775657851953, 3292.7530435217454, 3732.533433320835, 3853.4374640863794, 4092.3247664135383, 4092.386142078682, 1125.3135587291745, 1177.375990724917, 1198.9400529973466, 1201.4533387308754, 1226.7055906582445, 14747.557573272712, 14951.028183168059, 15059.945803793738, 15086.54740357791, 15095.924520377437, 4794.448638406628, 5374.48942350476, 5676.375598684142, 5777.913331300034, 5785.363610002896, 12665.683357916127, 12701.936490317674, 12702.618921432371, 12711.872881271069, 12748.426320815586, 6426.196725409324, 6431.196100487439, 6431.2604050533855, 6436.259830119502, 6445.033245013192, 5639.956608027528, 5791.681457519877, 5820.708964551755, 5883.823485295448, 5909.2908850938165, 4289.206496798102, 4427.46870375557, 4458.464984201916, 4501.7974191161475, 4508.526604706524, 1235.092207227687, 1367.705943222212, 1395.2744572822075, 1412.6609613692845, 1433.6200906759716, 582.2373136025122, 646.8318237258253, 739.5156172706886, 745.1765798792364, 786.6578038553248, 5993.670348130808, 6210.4100110489435, 6251.593646412968, 6313.463355614732, 6338.461230795434, 663.389681493018, 802.9718959836378, 864.6368525219423, 869.3349587565517, 922.6401703335705, 3397.9830100849335, 3790.5261842269715, 3790.6019867914024, 3860.4595356650093, 3938.0548668319398, 13176.60470185896, 13717.794233086512, 13731.794023089662, 13751.0, 13770.036097473081, 13634.181949082888, 13658.0, 13676.794848077287, 13691.726165476635, 13705.794413083811, 9148.176664100263, 9918.752031199134, 9929.3545919515, 9954.253430992705, 10082.596696132367, 6008.128821320972, 6071.119687645355, 6170.043643235305, 6196.690165491617, 6242.438180563771, 12032.398380081137, 12331.260124392566, 12368.876311236772, 12387.62837114888, 12402.503899844267, 12319.568815091428, 12445.751084978068, 12483.188592741442, 12486.375681216085, 12524.812127817908, 8625.784355391166, 8932.687355942511, 8943.955280223818, 8952.28381729447, 8954.194122528743, 9787.80424391494, 9881.555330010311, 9912.35569687968, 9977.20182385393, 9996.400215987063, 8373.539455330016, 8468.618912148844, 8507.532085735344, 8563.61463734146, 8715.30277577805, 8186.222308880647, 8250.711225107332, 8339.291160251447, 8365.456245344034, 8423.578821059045, 7052.365287123978, 7105.360517553445, 7137.500374973702, 7144.714211431485, 7379.372753316028, 4407.862186839445, 5541.695206763667, 5715.257016587983, 5719.428057194247, 5784.0, 4728.668993170445, 5535.778568857361, 5645.83062508109, 5683.5506945728575, 5873.73568189424], "value": 5091.846216447731, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.7823914455605, 388.93193691104057, 396.72229439392413, 401.5121627222924, 401.70273997242043, 5952.2262105926275, 6053.818385448438, 6072.5406210484825, 6119.3880611938785, 6125.9387406125925, 4647.488776234612, 5464.562834973202, 5830.970845145779, 5865.266841644794, 5982.162497250389, 3302.9760774160027, 4268.935965960513, 4369.890752731191, 4529.569690879362, 4543.522930092342, 3718.4236443351188, 4530.569595888385, 4530.569595888385, 4545.045540436512, 4571.2457444521615, 3476.0106308574605, 3508.7193024557973, 3983.9243404280974, 4255.1915136124035, 4514.593686568208, 3697.352963231424, 4300.290452075404, 4530.773461326937, 4531.9546804531865, 4545.36364908913, 3196.4246435641576, 3739.915424526911, 3866.8259928303296, 3870.9225815483533, 3880.844766209348, 4256.36154576816, 4803.063402636487, 5506.421825708283, 5894.351621321651, 5948.375420580864, 4728.621710263171, 4750.762461876927, 5274.235235890813, 5313.256144139824, 5319.9202011969855, 5393.649412788195, 6419.711112999927, 6437.517186211053, 6450.612963222222, 6512.055751915946, 4301.354796780508, 4340.392345071693, 4354.54277300882, 4357.535759071546, 4365.301551751704, 5267.894642107175, 5666.5183459406, 6378.744850205986, 6536.901946470807, 6912.896306555405, 3663.6519530644546, 4098.200850834119, 4401.471823381215, 4407.074514352022, 4415.955840441555, 3731.3395538985433, 4459.130469558405, 4493.528179541101, 4498.370228167993, 4501.48232953208, 4221.894452638709, 4898.657094003385, 4931.852044438672, 5029.547340739351, 5354.892902141935, 6523.18460192476, 6532.366908272932, 6551.0, 6551.344865513411, 6577.276499585042, 1473.7931514892186, 1520.247477498623, 1521.4674863797582, 1529.2277399913196, 1533.394309247841, 862.6549380247826, 989.2778271861438, 1013.0932815130417, 1019.4698756646502, 1028.1158203944601, 6137.066823950441, 6174.5060395168275, 6193.225846769154, 6204.255489341308, 6221.191245138193, 5562.7774889004795, 6220.471259942956, 6713.2279787824045, 6713.462922966151, 6725.630090345078, 5264.9736751315995, 5451.2640793493165, 6157.815265541952, 6158.445739883342, 6159.0, 3396.524486571882, 3427.0123328469926, 3448.77582957107, 3518.82405879705, 3522.6125126236093, 6246.187995560639, 6599.0, 6788.626625535643, 6835.0, 6916.783695581578, 6530.379613936669, 6921.826954326081, 6928.16861976566, 6971.930280697128, 6981.755638552626, 6034.909476357858, 6774.356436138658, 6848.520603557703, 6857.485688573277, 6866.141732283465, 5320.430473010394, 6671.733130674913, 6673.866522669617, 6707.692000060137, 6709.865802684017, 6041.818745437644, 6077.544184186292, 6376.489880809431, 6515.967420162869, 6539.607623542603, 5816.70916454184, 6063.423974722395, 6491.805245842632, 6566.540342176002, 6842.315768423118, 4143.3370660694145, 4360.324149756792, 4365.4979677337515, 4393.143337049245, 4394.780260987001, 5345.358556973188, 6615.503837212132, 6625.382973725448, 6707.597544147172, 6717.328267173243, 6307.716152773228, 6398.392152745481, 6417.839554011187, 6440.6813547122065, 6468.741250350122, 5379.373738584735, 5414.269073675004, 6237.220347456568, 6428.164338636135, 6448.645324507197, 5726.4273572642405, 6243.250809902841, 6277.96861015692, 6309.873802524015, 6344.714487847966, 5142.740028692994, 5647.435256474319, 6415.967920160556, 6426.485881129405, 6447.2263328400895, 5158.948410515847, 5523.033469142803, 6216.968915155395, 6296.048225050249, 6317.526185536195], "value": 4622.625138313489, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [652.9542931994761, 681.3391010719604, 694.2190036209265, 721.4120491799183, 735.6285076036601, 14418.35117419716, 14638.341274642667, 14668.339924703387, 15007.624809379771, 15625.0, 14561.78157327641, 14890.25548722565, 14916.701665966673, 15072.849271507283, 15102.244887755623, 928.9581968811402, 931.2363861633463, 952.2191802721771, 955.3933252384751, 957.0812020460367, 962.2638681408728, 967.1150896929337, 967.4292167621102, 967.4582233948976, 968.1528662420383, 1012.5291739341205, 1013.4780587997187, 1013.6857574152, 1013.9645112421067, 1019.8623185869886, 1008.4100801031406, 1012.185190921308, 1012.6556970629985, 1013.022433351819, 1014.6398028699779, 750.2235186581917, 750.3172113376801, 751.0124186694479, 752.2815710995985, 752.5221484357472, 15480.612984675361, 15519.301631426613, 16381.935174213639, 16777.66444671112, 16994.915025424794, 12696.492140314376, 14097.36561854719, 14602.415903363853, 14731.705365892729, 14758.55724328272, 11677.883221167764, 11833.230839995373, 12010.63968080959, 12074.215176013531, 12187.573434929824, 3009.5034319337283, 3064.601601791775, 3098.6281646202374, 3100.604837702712, 3123.81257124571, 107127.0, 112261.75476490424, 113480.0, 115884.0, 118271.0, 10531.789364212673, 11208.775824483464, 11270.887291126985, 11302.717432064264, 11631.360275184947, 25037.0, 25136.74863251344, 25165.62251566228, 25179.99280028815, 25183.37041573975, 7702.768916932501, 7706.152323244439, 7729.227077292338, 7775.183605721431, 7919.643616037177, 3982.2433737589763, 4073.1726621551725, 4129.855455059059, 4130.194612050681, 4135.152293779768, 3995.320795464778, 4087.1825634873144, 4307.6338511226895, 4475.462944446687, 4566.109608626352, 815.8245977114949, 823.8970128733908, 832.4214670803855, 839.4669384940485, 842.5618678287378, 558.1627558662007, 560.5851669764345, 561.926949496571, 568.4330824078855, 570.5264630356794, 8338.458000229966, 8859.689910853089, 8941.59762810675, 9124.224440922464, 9487.193588544913, 553.9695316757536, 562.7411390760215, 564.7261078376984, 568.5508448325859, 588.5585810642018, 3936.3701807710627, 4050.432939388488, 4236.894077648083, 4406.669499787528, 4428.601425871687, 68801.0, 79566.20433795587, 83019.0, 90781.0, 91327.54336228277, 83987.5800620993, 85112.0, 85547.57226213829, 86383.0, 87544.0, 74196.0, 74197.6290118546, 75756.62121689356, 76978.61510692634, 77302.61348693221, 7630.923690763243, 8480.787980300542, 8622.870656940277, 8686.78283042929, 8788.604512797066, 32660.530276137157, 33473.0, 33795.99999999902, 33932.45506587065, 34437.827810860785, 33616.66383336135, 43740.781296093315, 45204.28774273554, 45207.773961129984, 59429.0, 11864.822027669765, 12154.513819447124, 12399.194052386567, 12484.750304994031, 12587.811182832082, 12484.687882803002, 12500.24998500093, 12503.312317822607, 12505.812412813633, 12645.367731613198, 11737.226566955507, 11927.582534611247, 12039.939800300943, 12063.758724825278, 12219.144659874079, 11740.412979350827, 11968.640940771791, 11987.34069626179, 12101.515939362327, 12524.248545087325, 11642.88357116418, 11740.647780566596, 11764.529418823493, 11890.643280701246, 12164.695882603006, 7238.746643867439, 7986.081600616004, 8198.631061602124, 8547.316214702685, 8853.911460885567, 7645.617719114133, 8089.635966381645, 8564.74305770828, 8756.781080472785, 10128.84806727885], "value": 7133.412035433739, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1594.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 123.0, 123.0, 124.0, 125.0, 132.0, 123.0, 123.0, 124.0, 123.0], "value": 123.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 118.0, 120.0, 164.0, 122.0, 118.0, 119.0, 115.0, 145.0, 118.0], "value": 129.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [212.0, 213.0, 215.0, 222.0, 213.0, 212.0, 222.0, 220.0, 216.0, 212.0], "value": 215.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 66.0, 65.0, 96.0, 67.0, 64.0, 66.0, 63.0, 82.0, 61.0], "value": 71.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 78.0, 94.0, 77.0, 76.0, 74.0, 90.0, 75.0, 74.0, 92.0], "value": 82.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 93.0, 93.0, 94.0, 93.0, 93.0, 93.0, 92.0, 92.0], "value": 92.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 102.0, 100.0, 102.0, 100.0, 101.0, 102.0, 100.0, 101.0, 121.0], "value": 103.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 102.0, 101.0, 102.0, 103.0, 102.0, 99.0, 99.0, 102.0, 103.0], "value": 101.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 64.0, 65.0, 65.0, 65.0, 65.0, 65.0, 65.0, 65.0], "value": 64.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 65.0, 62.0, 73.0, 70.0, 64.0, 63.0, 65.0, 64.0, 65.0], "value": 65.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 89.0, 92.0, 103.0, 118.0, 117.0, 91.0, 91.0, 89.0, 92.0], "value": 97.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 173.0, 144.0, 162.0, 151.0, 155.0, 151.0, 149.0, 205.0, 167.0], "value": 162.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [206.0, 202.0, 199.0, 193.0, 204.0, 204.0, 212.0, 202.0, 201.0, 201.0], "value": 202.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 75.0, 73.0, 75.0, 84.0, 107.0, 107.0, 75.0, 73.0, 74.0], "value": 81.5, "unit": "score"}]}]} 18:51:07 INFO - Return code: 0 18:51:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:51:07 ERROR - # TBPL SUCCESS # 18:51:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:51:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:51:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:51:07 INFO - 2017-03-27 18:51:07.547 screenresolution[1655:9818] starting screenresolution argv=screenresolution get 18:51:07 INFO - 2017-03-27 18:51:07.550 screenresolution[1655:9818] Display 0: 1600x1200x32@60 18:51:07 INFO - 2017-03-27 18:51:07.557 screenresolution[1656:9821] starting screenresolution argv=screenresolution list 18:51:07 INFO - Available Modes on Display 0 18:51:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:51:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:51:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:51:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:51:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:51:07 INFO - Intel Iris: 18:51:07 INFO - Chipset Model: Intel Iris 18:51:07 INFO - Type: GPU 18:51:07 INFO - Bus: Built-In 18:51:07 INFO - VRAM (Dynamic, Max): 1536 MB 18:51:07 INFO - Vendor: Intel (0x8086) 18:51:07 INFO - Device ID: 0x0a2e 18:51:07 INFO - Revision ID: 0x0009 18:51:07 INFO - Displays: 18:51:07 INFO - Display: 18:51:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:51:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:51:07 INFO - Display Serial Number: 1600x1200 60 18:51:07 INFO - Main Display: Yes 18:51:07 INFO - Mirror: Off 18:51:07 INFO - Online: Yes 18:51:07 INFO - Rotation: Supported 18:51:07 INFO - Return code: 0 18:51:07 INFO - Running post-action listener: _resource_record_post_action 18:51:07 INFO - [mozharness: 2017-03-28 01:51:07.792567Z] Finished run-tests step (success) 18:51:07 INFO - Running post-run listener: _resource_record_post_run 18:51:07 INFO - Total resource usage - Wall time: 1206s; CPU: 53.0%; Read bytes: 38093824; Write bytes: 516333568; Read time: 239; Write time: 1596 18:51:07 INFO - TinderboxPrint: CPU usage
53.4% 18:51:07 INFO - TinderboxPrint: I/O read bytes / time
38,093,824 / 239 18:51:07 INFO - TinderboxPrint: I/O write bytes / time
516,333,568 / 1,596 18:51:07 INFO - TinderboxPrint: CPU idle
2,246.7 (46.6%) 18:51:07 INFO - TinderboxPrint: CPU system
960.4 (19.9%) 18:51:07 INFO - TinderboxPrint: CPU user
1,618.6 (33.5%) 18:51:07 INFO - TinderboxPrint: Swap in / out
249,548,800 / 0 18:51:07 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 212716032; Write bytes: 215615488; Read time: 17007; Write time: 472 18:51:07 INFO - run-tests - Wall time: 1186s; CPU: 53.0%; Read bytes: 34276352; Write bytes: 299112448; Read time: 216; Write time: 1116 18:51:08 INFO - Running post-run listener: _upload_blobber_files 18:51:08 INFO - Blob upload gear active. 18:51:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:51:08 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 18:51:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:51:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:51:08 INFO - (blobuploader) - INFO - Open directory for files ... 18:51:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:51:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:51:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:51:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:51:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:51:09 INFO - (blobuploader) - INFO - Done attempting. 18:51:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:51:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:51:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:51:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:51:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:51:10 INFO - (blobuploader) - INFO - Done attempting. 18:51:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:51:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:51:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:51:11 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:51:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:51:11 INFO - (blobuploader) - INFO - Done attempting. 18:51:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:51:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:51:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:51:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:51:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:51:13 INFO - (blobuploader) - INFO - Done attempting. 18:51:13 INFO - (blobuploader) - INFO - Iteration through files over. 18:51:13 INFO - Return code: 0 18:51:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:51:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:51:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7c0b3bf9e0916d77f15b3307f337060cd9b2c35a8d659afcfa9224cb2b99e7e402888508d43c2f7ecee31afbcea3a95ab7812bb470f5533a4c6d2ae2011f778", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92caec6477cc7c174a11ff781ac5fd4400103332d1d591802246fa72d7e07415fc5bdce1a0d78376f38c54c10ba8b188ff2fc5ad637251d7d000af81cb17a2f5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82e0dc95ab9fd80d22e5cb8ec6b0ee570c035cc78a4dbd5e3bf1e91901d118bba3fd28ec33017bd39fe963975dfcbc0b168c0b6f353d3dcff0f1040a10fa33c0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5bb94654318ca2f04b3b17d377ee9804c6dc73858213159ba1d60c7d7fa316beac7369780b16893680cacc9447830d3a6ee6e858b33bcd0dd3521edc51329620"} 18:51:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:51:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:51:13 INFO - Contents: 18:51:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7c0b3bf9e0916d77f15b3307f337060cd9b2c35a8d659afcfa9224cb2b99e7e402888508d43c2f7ecee31afbcea3a95ab7812bb470f5533a4c6d2ae2011f778", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92caec6477cc7c174a11ff781ac5fd4400103332d1d591802246fa72d7e07415fc5bdce1a0d78376f38c54c10ba8b188ff2fc5ad637251d7d000af81cb17a2f5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82e0dc95ab9fd80d22e5cb8ec6b0ee570c035cc78a4dbd5e3bf1e91901d118bba3fd28ec33017bd39fe963975dfcbc0b168c0b6f353d3dcff0f1040a10fa33c0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5bb94654318ca2f04b3b17d377ee9804c6dc73858213159ba1d60c7d7fa316beac7369780b16893680cacc9447830d3a6ee6e858b33bcd0dd3521edc51329620"} 18:51:13 INFO - Running post-run listener: copy_logs_to_upload_dir 18:51:13 INFO - Copying logs to upload dir... 18:51:13 INFO - mkdir: /builds/slave/test/build/upload/logs 18:51:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1288.995197 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2017-03-27 18:51:13.855259) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 18:51:13.862799) ========= 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.pQjRK4ieYx/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.XVs6KzkZ09/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7c0b3bf9e0916d77f15b3307f337060cd9b2c35a8d659afcfa9224cb2b99e7e402888508d43c2f7ecee31afbcea3a95ab7812bb470f5533a4c6d2ae2011f778", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92caec6477cc7c174a11ff781ac5fd4400103332d1d591802246fa72d7e07415fc5bdce1a0d78376f38c54c10ba8b188ff2fc5ad637251d7d000af81cb17a2f5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82e0dc95ab9fd80d22e5cb8ec6b0ee570c035cc78a4dbd5e3bf1e91901d118bba3fd28ec33017bd39fe963975dfcbc0b168c0b6f353d3dcff0f1040a10fa33c0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5bb94654318ca2f04b3b17d377ee9804c6dc73858213159ba1d60c7d7fa316beac7369780b16893680cacc9447830d3a6ee6e858b33bcd0dd3521edc51329620"} build_url:https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011250 build_url: 'https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7c0b3bf9e0916d77f15b3307f337060cd9b2c35a8d659afcfa9224cb2b99e7e402888508d43c2f7ecee31afbcea3a95ab7812bb470f5533a4c6d2ae2011f778", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92caec6477cc7c174a11ff781ac5fd4400103332d1d591802246fa72d7e07415fc5bdce1a0d78376f38c54c10ba8b188ff2fc5ad637251d7d000af81cb17a2f5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82e0dc95ab9fd80d22e5cb8ec6b0ee570c035cc78a4dbd5e3bf1e91901d118bba3fd28ec33017bd39fe963975dfcbc0b168c0b6f353d3dcff0f1040a10fa33c0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5bb94654318ca2f04b3b17d377ee9804c6dc73858213159ba1d60c7d7fa316beac7369780b16893680cacc9447830d3a6ee6e858b33bcd0dd3521edc51329620"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 18:51:13.904955) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:51:13.905363) ========= 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.pQjRK4ieYx/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.XVs6KzkZ09/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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.005137 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:51:13.958814) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 18:51:13.959333) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 18:51:13.959836) ========= ========= Total master_lag: 0.21 =========