builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0171 starttime: 1491521310.25 results: success (0) buildid: 20170406150234 builduid: ecf8f37b228948dd8444ef2fb52226c0 revision: 422bd63b18bc5b11482255aaaef1826285309233 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 16:28:30.248747) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 16:28:30.249218) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 16:28:30.249496) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 16:28:30.276524) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 16:28:30.276928) ========= 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.rEdkF6diQK/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.k7jgUoQ7Ix/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-06 16:28:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 200M=0s 2017-04-06 16:28:30 (200 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.116536 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 16:28:30.417425) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 16:28:30.417748) ========= 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.rEdkF6diQK/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.k7jgUoQ7Ix/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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.027657 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 16:28:30.466470) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-06 16:28:30.466829) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 422bd63b18bc5b11482255aaaef1826285309233 --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 422bd63b18bc5b11482255aaaef1826285309233 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rEdkF6diQK/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.k7jgUoQ7Ix/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-06 16:28:30,569 truncating revision to first 12 chars 2017-04-06 16:28:30,569 Setting DEBUG logging. 2017-04-06 16:28:30,569 attempt 1/10 2017-04-06 16:28:30,569 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/422bd63b18bc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-06 16:28:32,116 unpacking tar archive at: mozilla-central-422bd63b18bc/testing/mozharness/ program finished with exit code 0 elapsedTime=1.819174 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-06 16:28:32.309882) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 16:28:32.310339) ========= script_repo_revision: 422bd63b18bc5b11482255aaaef1826285309233 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 16:28:32.310835) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 16:28:32.311147) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 16:28:32.331671) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-04-06 16:28:32.332390) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rEdkF6diQK/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.k7jgUoQ7Ix/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 16:28:32 INFO - MultiFileLogger online at 20170406 16:28:32 in /builds/slave/test 16:28:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:28:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:28:32 INFO - {'append_to_log': False, 16:28:32 INFO - 'base_work_dir': '/builds/slave/test', 16:28:32 INFO - 'blob_upload_branch': 'Firefox', 16:28:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:28:32 INFO - 'branch': 'Firefox', 16:28:32 INFO - 'buildbot_json_path': 'buildprops.json', 16:28:32 INFO - 'config_files': ('talos/mac_config.py',), 16:28:32 INFO - 'default_actions': ('clobber', 16:28:32 INFO - 'read-buildbot-config', 16:28:32 INFO - 'download-and-extract', 16:28:32 INFO - 'populate-webroot', 16:28:32 INFO - 'create-virtualenv', 16:28:32 INFO - 'install', 16:28:32 INFO - 'run-tests'), 16:28:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:28:32 INFO - 'download_minidump_stackwalk': True, 16:28:32 INFO - 'download_symbols': 'ondemand', 16:28:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:28:32 INFO - 'tooltool.py': '/tools/tooltool.py', 16:28:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:28:32 INFO - '/tools/misc-python/virtualenv.py')}, 16:28:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:28:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:28:32 INFO - 'gecko_profile': False, 16:28:32 INFO - 'gecko_profile_interval': 0, 16:28:32 INFO - 'installer_path': 'installer.exe', 16:28:32 INFO - 'log_level': 'info', 16:28:32 INFO - 'log_name': 'talos', 16:28:32 INFO - 'log_to_console': True, 16:28:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:28:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:28:32 INFO - 'opt_config_files': (), 16:28:32 INFO - 'pip_index': False, 16:28:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:28:32 INFO - 'cmd': ('bash', 16:28:32 INFO - '-c', 16:28:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:28:32 INFO - 'enabled': True, 16:28:32 INFO - 'halt_on_failure': False, 16:28:32 INFO - 'name': 'check_screen_resolution'},), 16:28:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:28:32 INFO - 'cmd': ('bash', 16:28:32 INFO - '-c', 16:28:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:28:32 INFO - 'enabled': True, 16:28:32 INFO - 'halt_on_failure': False, 16:28:32 INFO - 'name': 'check_screen_resolution'},), 16:28:32 INFO - 'run_cmd_checks_enabled': True, 16:28:32 INFO - 'suite': 'dromaeojs-e10s', 16:28:32 INFO - 'system_bits': '32', 16:28:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:28:32 INFO - 'title': 't-yosemite-r7-0171', 16:28:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:28:32 INFO - 'use_talos_json': True, 16:28:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:28:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:28:32 INFO - 'work_dir': 'build'} 16:28:32 INFO - [mozharness: 2017-04-06 23:28:32.549876Z] Running clobber step. 16:28:32 INFO - Running pre-action listener: _resource_record_pre_action 16:28:32 INFO - Running main action method: clobber 16:28:32 INFO - rmtree: /builds/slave/test/build 16:28:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:28:35 INFO - Running post-action listener: _resource_record_post_action 16:28:35 INFO - [mozharness: 2017-04-06 23:28:35.102428Z] Finished clobber step (success) 16:28:35 INFO - [mozharness: 2017-04-06 23:28:35.102560Z] Running read-buildbot-config step. 16:28:35 INFO - Running pre-action listener: _resource_record_pre_action 16:28:35 INFO - Running main action method: read_buildbot_config 16:28:35 INFO - Using buildbot properties: 16:28:35 INFO - { 16:28:35 INFO - "project": "", 16:28:35 INFO - "product": "firefox", 16:28:35 INFO - "script_repo_revision": "production", 16:28:35 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 16:28:35 INFO - "repository": "", 16:28:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 16:28:35 INFO - "buildid": "20170406150234", 16:28:35 INFO - "pgo_build": "False", 16:28:35 INFO - "basedir": "/builds/slave/test", 16:28:35 INFO - "buildnumber": 139, 16:28:35 INFO - "slavename": "t-yosemite-r7-0171", 16:28:35 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 16:28:35 INFO - "platform": "yosemite_r7", 16:28:35 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 16:28:35 INFO - "branch": "mozilla-central", 16:28:35 INFO - "repo_path": "mozilla-central", 16:28:35 INFO - "revision": "422bd63b18bc5b11482255aaaef1826285309233", 16:28:35 INFO - "stage_platform": "macosx64", 16:28:35 INFO - "builduid": "ecf8f37b228948dd8444ef2fb52226c0", 16:28:35 INFO - "slavebuilddir": "test" 16:28:35 INFO - } 16:28:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 16:28:35 INFO - Running post-action listener: _resource_record_post_action 16:28:35 INFO - [mozharness: 2017-04-06 23:28:35.105951Z] Finished read-buildbot-config step (success) 16:28:35 INFO - [mozharness: 2017-04-06 23:28:35.106041Z] Running download-and-extract step. 16:28:35 INFO - Running pre-action listener: _resource_record_pre_action 16:28:35 INFO - Running main action method: download_and_extract 16:28:35 INFO - mkdir: /builds/slave/test/build/tests 16:28:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:28:35 INFO - trying https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 16:28:35 INFO - Downloading https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/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 16:28:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/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 16:28:36 INFO - Downloaded 1325 bytes. 16:28:36 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 16:28:36 INFO - Using the following test package requirements: 16:28:36 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:28:36 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 16:28:36 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:28:36 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:28:36 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 16:28:36 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:28:36 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 16:28:36 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:28:36 INFO - u'jsshell-mac.zip'], 16:28:36 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:28:36 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 16:28:36 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:28:36 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:28:36 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 16:28:36 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:28:36 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 16:28:36 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:28:36 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 16:28:36 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:28:36 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 16:28:36 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:28:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:28:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:28:36 INFO - Fetch https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:28:37 INFO - Expected file size: 52518495 16:28:37 INFO - Obtained file size: 52518495 16:28:53 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:28:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:28:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:28:53 INFO - Fetch https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:28:55 INFO - Expected file size: 52518495 16:28:55 INFO - Obtained file size: 52518495 16:29:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 16:29:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:29:10 INFO - Fetch https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 16:29:12 INFO - Expected file size: 14404138 16:29:12 INFO - Obtained file size: 14404138 16:29:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:12 INFO - trying https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:29:12 INFO - Downloading https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 16:29:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/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 16:29:14 INFO - Downloaded 70891149 bytes. 16:29:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:29:14 INFO - mkdir: /builds/slave/test/properties 16:29:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:29:14 INFO - Writing to file /builds/slave/test/properties/build_url 16:29:14 INFO - Contents: 16:29:14 INFO - build_url:https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:29:15 INFO - Running post-action listener: _resource_record_post_action 16:29:15 INFO - Running post-action listener: set_extra_try_arguments 16:29:15 INFO - [mozharness: 2017-04-06 23:29:15.287140Z] Finished download-and-extract step (success) 16:29:15 INFO - [mozharness: 2017-04-06 23:29:15.287296Z] Running populate-webroot step. 16:29:15 INFO - Running pre-action listener: _resource_record_pre_action 16:29:15 INFO - Running main action method: populate_webroot 16:29:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:29:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:29:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:29:15 INFO - '--tppagecycles', 16:29:15 INFO - '7'], 16:29:15 INFO - 'tests': ['tsvgm']}}, 16:29:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:29:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:29:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:29:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:29:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:29:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:29:15 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:29:15 INFO - 'talos_options': ['--disable-e10s'], 16:29:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:29:15 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:29:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:29:15 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:29:15 INFO - 'talos_options': ['--disable-e10s'], 16:29:15 INFO - 'tests': ['damp', 'tps']}, 16:29:15 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:29:15 INFO - 'tests': ['damp', 'tps']}, 16:29:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:29:15 INFO - 'tests': ['dromaeo_dom']}, 16:29:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:29:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:29:15 INFO - 'tests': ['basic_compositor_video']}, 16:29:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:29:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:29:15 INFO - 'tests': ['a11yr', 16:29:15 INFO - 'ts_paint', 16:29:15 INFO - 'tpaint', 16:29:15 INFO - 'sessionrestore', 16:29:15 INFO - 'sessionrestore_no_auto_restore', 16:29:15 INFO - 'tabpaint']}, 16:29:15 INFO - 'other-e10s': {'tests': ['a11yr', 16:29:15 INFO - 'ts_paint', 16:29:15 INFO - 'tpaint', 16:29:15 INFO - 'sessionrestore', 16:29:15 INFO - 'sessionrestore_no_auto_restore', 16:29:15 INFO - 'tabpaint']}, 16:29:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:29:15 INFO - 'ts_paint', 16:29:15 INFO - 'tpaint', 16:29:15 INFO - 'sessionrestore', 16:29:15 INFO - 'sessionrestore_no_auto_restore', 16:29:15 INFO - 'tabpaint']}, 16:29:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:29:15 INFO - 'ts_paint', 16:29:15 INFO - 'tpaint', 16:29:15 INFO - 'sessionrestore', 16:29:15 INFO - 'sessionrestore_no_auto_restore', 16:29:15 INFO - 'tabpaint']}, 16:29:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:29:15 INFO - 'tests': ['a11yr', 16:29:15 INFO - 'ts_paint', 16:29:15 INFO - 'tpaint', 16:29:15 INFO - 'sessionrestore', 16:29:15 INFO - 'sessionrestore_no_auto_restore', 16:29:15 INFO - 'tabpaint']}, 16:29:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:29:15 INFO - 'tests': ['a11yr', 16:29:15 INFO - 'ts_paint', 16:29:15 INFO - 'tpaint', 16:29:15 INFO - 'sessionrestore', 16:29:15 INFO - 'sessionrestore_no_auto_restore', 16:29:15 INFO - 'tabpaint']}, 16:29:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:29:15 INFO - 'tests': ['tsvgx', 16:29:15 INFO - 'tsvgr_opacity', 16:29:15 INFO - 'tart', 16:29:15 INFO - 'tscrollx', 16:29:15 INFO - 'cart', 16:29:15 INFO - 'tsvg_static']}, 16:29:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:29:15 INFO - 'tsvgr_opacity', 16:29:15 INFO - 'tart', 16:29:15 INFO - 'tscrollx', 16:29:15 INFO - 'cart', 16:29:15 INFO - 'tsvg_static']}, 16:29:15 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:29:15 INFO - 'talos_options': ['--disable-e10s'], 16:29:15 INFO - 'tests': ['tp5o']}, 16:29:15 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:29:15 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:29:15 INFO - 'talos_options': ['--disable-e10s', 16:29:15 INFO - '--xperf_path', 16:29:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:29:15 INFO - 'tests': ['tp5n']}, 16:29:15 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:29:15 INFO - 'talos_options': ['--xperf_path', 16:29:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:29:15 INFO - 'tests': ['tp5n']}}, 16:29:15 INFO - 'talos.zip': {'path': '', 16:29:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:29:15 INFO - Running post-action listener: _resource_record_post_action 16:29:15 INFO - [mozharness: 2017-04-06 23:29:15.305370Z] Finished populate-webroot step (success) 16:29:15 INFO - [mozharness: 2017-04-06 23:29:15.305495Z] Running create-virtualenv step. 16:29:15 INFO - Running pre-action listener: _resource_record_pre_action 16:29:15 INFO - Running main action method: create_virtualenv 16:29:15 INFO - Creating virtualenv /builds/slave/test/build/venv 16:29:15 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:29:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:29:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:29:15 INFO - Using real prefix '/tools/python27' 16:29:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:29:16 INFO - Installing distribute.............................................................................................................................................................................................done. 16:29:19 INFO - Installing pip.................done. 16:29:19 INFO - Return code: 0 16:29:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:29:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:29:19 INFO - Reading from file tmpfile_stdout 16:29:19 INFO - Output received: 16:29:19 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 16:29:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:29:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:19 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 0x10aeb9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aebc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba82d7d0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac97d30>, '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 0x10abbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac8cbe8>, '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.rEdkF6diQK/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.k7jgUoQ7Ix/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:29:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:29:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:29:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rEdkF6diQK/Render', 16:29:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:19 INFO - 'HOME': '/Users/cltbld', 16:29:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:19 INFO - 'LOGNAME': 'cltbld', 16:29:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:19 INFO - 'MOZ_NO_REMOTE': '1', 16:29:19 INFO - 'NO_EM_RESTART': '1', 16:29:19 INFO - 'PAGER': '/bin/cat', 16:29:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:19 INFO - 'PWD': '/builds/slave/test', 16:29:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:19 INFO - 'SHELL': '/bin/bash', 16:29:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k7jgUoQ7Ix/Listeners', 16:29:19 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 16:29:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:19 INFO - 'USER': 'cltbld', 16:29:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:19 INFO - 'XPC_FLAGS': '0x0', 16:29:19 INFO - 'XPC_SERVICE_NAME': '0', 16:29:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:19 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:29:19 INFO - Cleaning up... 16:29:19 INFO - Return code: 0 16:29:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:29:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:19 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 0x10aeb9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aebc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba82d7d0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac97d30>, '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 0x10abbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac8cbe8>, '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.rEdkF6diQK/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.k7jgUoQ7Ix/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:29:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:29:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:29:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rEdkF6diQK/Render', 16:29:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:19 INFO - 'HOME': '/Users/cltbld', 16:29:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:19 INFO - 'LOGNAME': 'cltbld', 16:29:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:19 INFO - 'MOZ_NO_REMOTE': '1', 16:29:19 INFO - 'NO_EM_RESTART': '1', 16:29:19 INFO - 'PAGER': '/bin/cat', 16:29:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:19 INFO - 'PWD': '/builds/slave/test', 16:29:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:19 INFO - 'SHELL': '/bin/bash', 16:29:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k7jgUoQ7Ix/Listeners', 16:29:19 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 16:29:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:19 INFO - 'USER': 'cltbld', 16:29:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:19 INFO - 'XPC_FLAGS': '0x0', 16:29:19 INFO - 'XPC_SERVICE_NAME': '0', 16:29:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:19 INFO - Downloading/unpacking psutil>=3.1.1 16:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:29:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:29:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:29:23 INFO - Installing collected packages: psutil 16:29:23 INFO - Running setup.py install for psutil 16:29:23 INFO - building 'psutil._psutil_osx' extension 16:29:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 16:29:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 16:29:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 16:29:24 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 16:29:24 INFO - building 'psutil._psutil_posix' extension 16:29:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 16:29:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:29:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:29:24 INFO - ^ 16:29:24 INFO - 1 warning generated. 16:29:24 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 16:29:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:29:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:29:24 INFO - Successfully installed psutil 16:29:24 INFO - Cleaning up... 16:29:24 INFO - Return code: 0 16:29:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:24 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 0x10aeb9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aebc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba82d7d0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac97d30>, '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 0x10abbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac8cbe8>, '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.rEdkF6diQK/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.k7jgUoQ7Ix/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:29:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:29:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:29:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rEdkF6diQK/Render', 16:29:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:24 INFO - 'HOME': '/Users/cltbld', 16:29:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:24 INFO - 'LOGNAME': 'cltbld', 16:29:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:24 INFO - 'MOZ_NO_REMOTE': '1', 16:29:24 INFO - 'NO_EM_RESTART': '1', 16:29:24 INFO - 'PAGER': '/bin/cat', 16:29:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:24 INFO - 'PWD': '/builds/slave/test', 16:29:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:24 INFO - 'SHELL': '/bin/bash', 16:29:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k7jgUoQ7Ix/Listeners', 16:29:24 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 16:29:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:24 INFO - 'USER': 'cltbld', 16:29:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:24 INFO - 'XPC_FLAGS': '0x0', 16:29:24 INFO - 'XPC_SERVICE_NAME': '0', 16:29:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:29:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:29:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:29:27 INFO - Installing collected packages: mozsystemmonitor 16:29:27 INFO - Running setup.py install for mozsystemmonitor 16:29:27 INFO - Successfully installed mozsystemmonitor 16:29:27 INFO - Cleaning up... 16:29:27 INFO - Return code: 0 16:29:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:29:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10aeb9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aebc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba82d7d0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac97d30>, '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 0x10abbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac8cbe8>, '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.rEdkF6diQK/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.k7jgUoQ7Ix/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:29:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:29:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:29:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rEdkF6diQK/Render', 16:29:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:28 INFO - 'HOME': '/Users/cltbld', 16:29:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:28 INFO - 'LOGNAME': 'cltbld', 16:29:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:28 INFO - 'MOZ_NO_REMOTE': '1', 16:29:28 INFO - 'NO_EM_RESTART': '1', 16:29:28 INFO - 'PAGER': '/bin/cat', 16:29:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:28 INFO - 'PWD': '/builds/slave/test', 16:29:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:28 INFO - 'SHELL': '/bin/bash', 16:29:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k7jgUoQ7Ix/Listeners', 16:29:28 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 16:29:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:28 INFO - 'USER': 'cltbld', 16:29:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:28 INFO - 'XPC_FLAGS': '0x0', 16:29:28 INFO - 'XPC_SERVICE_NAME': '0', 16:29:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:28 INFO - Downloading/unpacking jsonschema==2.5.1 16:29:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:29:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:32 INFO - Downloading functools32-3.2.3-2.tar.gz 16:29:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:29:32 INFO - warning: no files found matching '*.txt' 16:29:32 INFO - no previously-included directories found matching 'build' 16:29:32 INFO - no previously-included directories found matching 'dist' 16:29:32 INFO - no previously-included directories found matching '.git*' 16:29:32 INFO - Installing collected packages: jsonschema, functools32 16:29:32 INFO - Running setup.py install for functools32 16:29:32 INFO - warning: no files found matching '*.txt' 16:29:32 INFO - no previously-included directories found matching 'build' 16:29:32 INFO - no previously-included directories found matching 'dist' 16:29:32 INFO - no previously-included directories found matching '.git*' 16:29:32 INFO - Successfully installed jsonschema functools32 16:29:32 INFO - Cleaning up... 16:29:32 INFO - Return code: 0 16:29:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:29: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')]} 16:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29: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')]} 16:29:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29: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', '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 0x10aeb9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aebc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba82d7d0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac97d30>, '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 0x10abbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac8cbe8>, '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.rEdkF6diQK/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.k7jgUoQ7Ix/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:29: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:29: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 functools32==3.2.3-2 16:29:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rEdkF6diQK/Render', 16:29:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:32 INFO - 'HOME': '/Users/cltbld', 16:29:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:32 INFO - 'LOGNAME': 'cltbld', 16:29:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:32 INFO - 'MOZ_NO_REMOTE': '1', 16:29:32 INFO - 'NO_EM_RESTART': '1', 16:29:32 INFO - 'PAGER': '/bin/cat', 16:29:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:32 INFO - 'PWD': '/builds/slave/test', 16:29:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:32 INFO - 'SHELL': '/bin/bash', 16:29:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k7jgUoQ7Ix/Listeners', 16:29:32 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 16:29:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:32 INFO - 'USER': 'cltbld', 16:29:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:32 INFO - 'XPC_FLAGS': '0x0', 16:29:32 INFO - 'XPC_SERVICE_NAME': '0', 16:29:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:29:32 INFO - Cleaning up... 16:29:32 INFO - Return code: 0 16:29:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:29: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')]} 16:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29: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')]} 16:29:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29: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', '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 0x10aeb9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aebc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba82d7d0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac97d30>, '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 0x10abbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac8cbe8>, '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.rEdkF6diQK/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.k7jgUoQ7Ix/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:29: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:29: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 blobuploader==1.2.4 16:29:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rEdkF6diQK/Render', 16:29:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:32 INFO - 'HOME': '/Users/cltbld', 16:29:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:32 INFO - 'LOGNAME': 'cltbld', 16:29:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:32 INFO - 'MOZ_NO_REMOTE': '1', 16:29:32 INFO - 'NO_EM_RESTART': '1', 16:29:32 INFO - 'PAGER': '/bin/cat', 16:29:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:32 INFO - 'PWD': '/builds/slave/test', 16:29:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:32 INFO - 'SHELL': '/bin/bash', 16:29:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k7jgUoQ7Ix/Listeners', 16:29:32 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 16:29:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:32 INFO - 'USER': 'cltbld', 16:29:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:32 INFO - 'XPC_FLAGS': '0x0', 16:29:32 INFO - 'XPC_SERVICE_NAME': '0', 16:29:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:33 INFO - Downloading/unpacking blobuploader==1.2.4 16:29:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:35 INFO - Downloading blobuploader-1.2.4.tar.gz 16:29:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:29:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:29:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:29:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:29:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:36 INFO - Downloading docopt-0.6.1.tar.gz 16:29:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:29:36 INFO - Installing collected packages: blobuploader, requests, docopt 16:29:36 INFO - Running setup.py install for blobuploader 16:29:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:29:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:29:37 INFO - Running setup.py install for requests 16:29:37 INFO - Running setup.py install for docopt 16:29:37 INFO - Successfully installed blobuploader requests docopt 16:29:37 INFO - Cleaning up... 16:29:37 INFO - Return code: 0 16:29:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:29: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')]} 16:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29: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')]} 16:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:37 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 0x10aeb9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aebc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba82d7d0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac97d30>, '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 0x10abbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac8cbe8>, '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.rEdkF6diQK/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.k7jgUoQ7Ix/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:29:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:29:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:29:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rEdkF6diQK/Render', 16:29:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:37 INFO - 'HOME': '/Users/cltbld', 16:29:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:37 INFO - 'LOGNAME': 'cltbld', 16:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:37 INFO - 'MOZ_NO_REMOTE': '1', 16:29:37 INFO - 'NO_EM_RESTART': '1', 16:29:37 INFO - 'PAGER': '/bin/cat', 16:29:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:37 INFO - 'PWD': '/builds/slave/test', 16:29:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:37 INFO - 'SHELL': '/bin/bash', 16:29:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k7jgUoQ7Ix/Listeners', 16:29:37 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 16:29:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:37 INFO - 'USER': 'cltbld', 16:29:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:37 INFO - 'XPC_FLAGS': '0x0', 16:29:37 INFO - 'XPC_SERVICE_NAME': '0', 16:29:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:29:38 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-Q5Liwd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:29:38 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-OGoN9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:29:38 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-EIf3av-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:29:38 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-NUes0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:29:38 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-IN2tmg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:29:38 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-1sYD9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:29:38 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-WJasrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:29:38 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-TTQk7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:29:38 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-OeIE6z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:29:39 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-iu5QtJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:29:39 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-M3Ou6t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:29:39 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-pFyrFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:29:39 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-dhiAbJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:29:39 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-I1ANAz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:39 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-UjX4ZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:29:39 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-863TYO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:29:39 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-Mq8Sk8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:29:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:29:39 INFO - Running setup.py install for manifestparser 16:29:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:29:40 INFO - Running setup.py install for mozcrash 16:29:40 INFO - Running setup.py install for mozdebug 16:29:40 INFO - Running setup.py install for mozdevice 16:29:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:29:40 INFO - Running setup.py install for mozfile 16:29:40 INFO - Running setup.py install for mozhttpd 16:29:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:29:40 INFO - Running setup.py install for mozinfo 16:29:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:29:40 INFO - Running setup.py install for mozInstall 16:29:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:29:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:29:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:29:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:29:41 INFO - Running setup.py install for mozleak 16:29:41 INFO - Running setup.py install for mozlog 16:29:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:29:41 INFO - Running setup.py install for moznetwork 16:29:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:29:41 INFO - Running setup.py install for mozprocess 16:29:41 INFO - Running setup.py install for mozprofile 16:29:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:29:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:29:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:29:41 INFO - Running setup.py install for mozrunner 16:29:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:29:42 INFO - Running setup.py install for mozscreenshot 16:29:42 INFO - Running setup.py install for moztest 16:29:42 INFO - Running setup.py install for mozversion 16:29:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:29:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:29:42 INFO - Cleaning up... 16:29:42 INFO - Return code: 0 16:29:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:29:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:42 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 0x10aeb9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aebc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba82d7d0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac97d30>, '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 0x10abbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac8cbe8>, '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.rEdkF6diQK/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.k7jgUoQ7Ix/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:29:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:29:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:29:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rEdkF6diQK/Render', 16:29:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:42 INFO - 'HOME': '/Users/cltbld', 16:29:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:42 INFO - 'LOGNAME': 'cltbld', 16:29:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:42 INFO - 'MOZ_NO_REMOTE': '1', 16:29:42 INFO - 'NO_EM_RESTART': '1', 16:29:42 INFO - 'PAGER': '/bin/cat', 16:29:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:42 INFO - 'PWD': '/builds/slave/test', 16:29:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:42 INFO - 'SHELL': '/bin/bash', 16:29:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k7jgUoQ7Ix/Listeners', 16:29:42 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 16:29:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:42 INFO - 'USER': 'cltbld', 16:29:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:42 INFO - 'XPC_FLAGS': '0x0', 16:29:42 INFO - 'XPC_SERVICE_NAME': '0', 16:29:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:29:42 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-3SQ11E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:29:42 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-u4DZq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:29:43 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-YLU2SD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:29:43 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-F32Knb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:29:43 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-m1J2RK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:29:43 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-VYBZFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:29:43 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-QLF6wP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:29:43 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-4dI0rW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:29:43 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-eOSWmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:29:43 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-4xjg3E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:29:43 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-hQu_3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:29:43 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-KKVWIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:29:44 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-by7iOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:29:44 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-B5NZOU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:44 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-EVqlwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:29:44 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-C6EpyV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:29:44 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-M3im39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:29:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:47 INFO - Downloading blessings-1.6.tar.gz 16:29:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:29:47 INFO - Installing collected packages: blessings 16:29:47 INFO - Running setup.py install for blessings 16:29:47 INFO - Successfully installed blessings 16:29:47 INFO - Cleaning up... 16:29:47 INFO - Return code: 0 16:29:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:29:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:29:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:29:47 INFO - Reading from file tmpfile_stdout 16:29:47 INFO - Current package versions: 16:29:47 INFO - blessings == 1.6 16:29:47 INFO - blobuploader == 1.2.4 16:29:47 INFO - docopt == 0.6.1 16:29:47 INFO - functools32 == 3.2.3-2 16:29:47 INFO - jsonschema == 2.5.1 16:29:47 INFO - manifestparser == 1.1 16:29:47 INFO - mozInstall == 1.12 16:29:47 INFO - mozcrash == 1.0 16:29:47 INFO - mozdebug == 0.1 16:29:47 INFO - mozdevice == 0.50 16:29:47 INFO - mozfile == 1.2 16:29:47 INFO - mozhttpd == 0.7 16:29:47 INFO - mozinfo == 0.9 16:29:47 INFO - mozleak == 0.1 16:29:47 INFO - mozlog == 3.4 16:29:47 INFO - moznetwork == 0.27 16:29:47 INFO - mozprocess == 0.25 16:29:47 INFO - mozprofile == 0.28 16:29:47 INFO - mozrunner == 6.13 16:29:47 INFO - mozscreenshot == 0.1 16:29:47 INFO - mozsystemmonitor == 0.3 16:29:47 INFO - moztest == 0.8 16:29:47 INFO - mozversion == 1.4 16:29:47 INFO - psutil == 3.1.1 16:29:47 INFO - requests == 1.2.3 16:29:47 INFO - wsgiref == 0.1.2 16:29:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16: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')]} 16:29:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16: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')]} 16:29:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:47 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 0x10aeb9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aebc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba82d7d0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac97d30>, '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 0x10abbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac8cbe8>, '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.rEdkF6diQK/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.k7jgUoQ7Ix/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:29:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:29:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:29:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rEdkF6diQK/Render', 16:29:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:47 INFO - 'HOME': '/Users/cltbld', 16:29:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:47 INFO - 'LOGNAME': 'cltbld', 16:29:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:47 INFO - 'MOZ_NO_REMOTE': '1', 16:29:47 INFO - 'NO_EM_RESTART': '1', 16:29:47 INFO - 'PAGER': '/bin/cat', 16:29:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:47 INFO - 'PWD': '/builds/slave/test', 16:29:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:47 INFO - 'SHELL': '/bin/bash', 16:29:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k7jgUoQ7Ix/Listeners', 16:29:47 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 16:29:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:47 INFO - 'USER': 'cltbld', 16:29:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:47 INFO - 'XPC_FLAGS': '0x0', 16:29:47 INFO - 'XPC_SERVICE_NAME': '0', 16:29:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:29:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:29:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:29:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:29:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:29:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:29:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:29:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:29:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:29:48 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:29:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:29:48 INFO - Cleaning up... 16:29:48 INFO - Return code: 0 16:29:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:29:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:48 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 0x10aeb9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aebc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba82d7d0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac97d30>, '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 0x10abbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac8cbe8>, '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.rEdkF6diQK/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.k7jgUoQ7Ix/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:29:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:29:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:29:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rEdkF6diQK/Render', 16:29:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:48 INFO - 'HOME': '/Users/cltbld', 16:29:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:48 INFO - 'LOGNAME': 'cltbld', 16:29:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:48 INFO - 'MOZ_NO_REMOTE': '1', 16:29:48 INFO - 'NO_EM_RESTART': '1', 16:29:48 INFO - 'PAGER': '/bin/cat', 16:29:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:48 INFO - 'PWD': '/builds/slave/test', 16:29:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:48 INFO - 'SHELL': '/bin/bash', 16:29:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k7jgUoQ7Ix/Listeners', 16:29:48 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 16:29:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:48 INFO - 'USER': 'cltbld', 16:29:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:48 INFO - 'XPC_FLAGS': '0x0', 16:29:48 INFO - 'XPC_SERVICE_NAME': '0', 16:29:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:29:48 INFO - Cleaning up... 16:29:48 INFO - Return code: 0 16:29:48 INFO - Running post-action listener: _resource_record_post_action 16:29:48 INFO - Running post-action listener: _start_resource_monitoring 16:29:48 INFO - Starting resource monitoring. 16:29:48 INFO - [mozharness: 2017-04-06 23:29:48.496636Z] Finished create-virtualenv step (success) 16:29:48 INFO - [mozharness: 2017-04-06 23:29:48.497670Z] Running install step. 16:29:48 INFO - Running pre-action listener: _resource_record_pre_action 16:29:48 INFO - Running main action method: install 16:29:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:29:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:29:48 INFO - Reading from file tmpfile_stdout 16:29:48 INFO - Detecting whether we're running mozinstall >=1.0... 16:29:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:29:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:29:48 INFO - Reading from file tmpfile_stdout 16:29:48 INFO - Output received: 16:29:48 INFO - Usage: mozinstall [options] installer 16:29:48 INFO - Options: 16:29:48 INFO - -h, --help show this help message and exit 16:29:48 INFO - -d DEST, --destination=DEST 16:29:48 INFO - Directory to install application into. [default: 16:29:48 INFO - "/builds/slave/test"] 16:29:48 INFO - --app=APP Application being installed. [default: firefox] 16:29:48 INFO - mkdir: /builds/slave/test/build/application 16:29:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:29:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:30:08 INFO - Reading from file tmpfile_stdout 16:30:08 INFO - Output received: 16:30:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:30:08 INFO - Running post-action listener: _resource_record_post_action 16:30:08 INFO - [mozharness: 2017-04-06 23:30:08.443089Z] Finished install step (success) 16:30:08 INFO - [mozharness: 2017-04-06 23:30:08.443240Z] Running run-tests step. 16:30:08 INFO - Running pre-action listener: _resource_record_pre_action 16:30:08 INFO - Running main action method: run_tests 16:30:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:30:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:30:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:30:08 INFO - 2017-04-06 16:30:08.458 screenresolution[1624:7333] starting screenresolution argv=screenresolution get 16:30:08 INFO - 2017-04-06 16:30:08.460 screenresolution[1624:7333] Display 0: 1600x1200x32@60 16:30:08 INFO - 2017-04-06 16:30:08.466 screenresolution[1625:7336] starting screenresolution argv=screenresolution list 16:30:08 INFO - Available Modes on Display 0 16:30:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:30:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:30:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:30:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:30:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:30:08 INFO - Intel Iris: 16:30:08 INFO - Chipset Model: Intel Iris 16:30:08 INFO - Type: GPU 16:30:08 INFO - Bus: Built-In 16:30:08 INFO - VRAM (Dynamic, Max): 1536 MB 16:30:08 INFO - Vendor: Intel (0x8086) 16:30:08 INFO - Device ID: 0x0a2e 16:30:08 INFO - Revision ID: 0x0009 16:30:08 INFO - Displays: 16:30:08 INFO - Display: 16:30:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:30:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:30:08 INFO - Display Serial Number: 1600x1200 60 16:30:08 INFO - Main Display: Yes 16:30:08 INFO - Mirror: Off 16:30:08 INFO - Online: Yes 16:30:08 INFO - Rotation: Supported 16:30:08 INFO - Return code: 0 16:30:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:30:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:30:08 INFO - Python 2.7.3 16:30:08 INFO - Return code: 0 16:30:08 INFO - grabbing minidump binary from tooltool 16:30:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:30:08 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 0x10ac97d30>, '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 0x10abbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac8cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:30:08 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:30:08 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 16:30:08 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:30:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:30:08 INFO - Return code: 0 16:30:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:30:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:30:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:30:08 INFO - ENV: RUST_BACKTRACE is now 1 16:30:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:30:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:30:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0171', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 16:30:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0171 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 16:30:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rEdkF6diQK/Render', 16:30:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:30:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:30:08 INFO - 'HOME': '/Users/cltbld', 16:30:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:30:08 INFO - 'LOGNAME': 'cltbld', 16:30:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:30:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:30:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:08 INFO - 'MOZ_NO_REMOTE': '1', 16:30:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:30:08 INFO - 'NO_EM_RESTART': '1', 16:30:08 INFO - 'PAGER': '/bin/cat', 16:30:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:30:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:08 INFO - 'PWD': '/builds/slave/test', 16:30:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:30:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:30:08 INFO - 'RUST_BACKTRACE': '1', 16:30:08 INFO - 'SHELL': '/bin/bash', 16:30:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k7jgUoQ7Ix/Listeners', 16:30:08 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 16:30:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:30:08 INFO - 'USER': 'cltbld', 16:30:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:30:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:30:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:08 INFO - 'XPC_FLAGS': '0x0', 16:30:08 INFO - 'XPC_SERVICE_NAME': '0', 16:30:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:30:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0171', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 16:30:09 INFO - mozversion application_buildid: 20170406150234 16:30:09 INFO - mozversion application_changeset: 422bd63b18bc5b11482255aaaef1826285309233 16:30:09 INFO - mozversion application_display_name: Nightly 16:30:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:30:09 INFO - mozversion application_name: Firefox 16:30:09 INFO - mozversion application_remotingname: firefox 16:30:09 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:30:09 INFO - mozversion application_vendor: Mozilla 16:30:09 INFO - mozversion application_version: 55.0a1 16:30:09 INFO - mozversion platform_buildid: 20170406150234 16:30:09 INFO - mozversion platform_changeset: 422bd63b18bc5b11482255aaaef1826285309233 16:30:09 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:30:09 INFO - mozversion platform_version: 55.0a1 16:30:09 INFO - using testdate: 1491521409 16:30:09 INFO - actual date: 1491521409 16:30:09 INFO - starting webserver on 'localhost:49248' 16:30:09 INFO - SUITE-START | Running 2 tests 16:30:09 INFO - TEST-START | dromaeo_css 16:30:09 INFO - operating with platform_type : mac_ 16:30:09 INFO - Initialising browser for dromaeo_css test... 16:30:09 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmpK_XrNB/profile http://localhost:49248/getInfo.html) 16:30:12 INFO - PID 1635 | 2017-04-06 16:30:12.241 plugin-container[1637:7522] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f37, name = 'com.apple.tsm.portname' 16:30:12 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:30:12 INFO - PID 1635 | 2017-04-06 16:30:12.244 plugin-container[1637:7522] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x905b, name = 'com.apple.CFPasteboardClient' 16:30:12 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:30:12 INFO - PID 1635 | 2017-04-06 16:30:12.244 plugin-container[1637:7522] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:30:12 INFO - PID 1635 | __metrics Screen width/height:1600/1200 16:30:12 INFO - PID 1635 | colorDepth:24 16:30:12 INFO - PID 1635 | Browser inner width/height: 1024/689 16:30:12 INFO - PID 1635 | __metrics 16:30:12 ERROR - PID 1635 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 16:30:13 INFO - TEST-INFO | 1635: exit 0 16:30:13 INFO - Browser initialized. 16:30:13 INFO - Running cycle 1/1 for dromaeo_css test... 16:30:13 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmpK_XrNB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:30:15 INFO - PID 1638 | 2017-04-06 16:30:15.969 plugin-container[1639:7645] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4b, name = 'com.apple.tsm.portname' 16:30:15 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:30:15 INFO - PID 1638 | 2017-04-06 16:30:15.972 plugin-container[1639:7645] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9017, name = 'com.apple.CFPasteboardClient' 16:30:15 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:30:15 INFO - PID 1638 | 2017-04-06 16:30:15.972 plugin-container[1639:7645] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:30:25 INFO - PID 1638 | RSS: Main: 233852928 16:30:25 INFO - PID 1638 | 16:33:28 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49248/tests/dromaeo/cssquery-ext.html) 16:33:30 INFO - PID 1638 | RSS: Main: 252743680 16:33:30 INFO - PID 1638 | 16:35:13 INFO - PID 1638 | ************************* 16:35:13 INFO - PID 1638 | A coding exception was thrown and uncaught in a Task. 16:35:13 INFO - PID 1638 | 16:35:13 ERROR - PID 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:35:13 INFO - PID 1638 | Full stack: 16:35:13 INFO - PID 1638 | ************************* 16:35:13 INFO - PID 1638 | ************************* 16:35:13 INFO - PID 1638 | A coding exception was thrown and uncaught in a Task. 16:35:13 INFO - PID 1638 | 16:35:13 ERROR - PID 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:35:13 INFO - PID 1638 | Full stack: 16:35:13 INFO - PID 1638 | ************************* 16:35:13 INFO - PID 1638 | ************************* 16:35:13 INFO - PID 1638 | A coding exception was thrown and uncaught in a Task. 16:35:13 INFO - PID 1638 | 16:35:13 ERROR - PID 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:35:13 INFO - PID 1638 | Full stack: 16:35:13 INFO - PID 1638 | ************************* 16:35:13 INFO - PID 1638 | ************************* 16:35:13 INFO - PID 1638 | A coding exception was thrown and uncaught in a Task. 16:35:13 INFO - PID 1638 | 16:35:13 ERROR - PID 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:35:13 INFO - PID 1638 | Full stack: 16:35:13 INFO - PID 1638 | ************************* 16:36:33 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-ext.html (next: http://localhost:49248/tests/dromaeo/cssquery-jquery.html) 16:36:33 INFO - PID 1638 | RSS: Main: 260911104 16:36:33 INFO - PID 1638 | 16:39:35 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49248/tests/dromaeo/cssquery-mootools.html) 16:39:36 INFO - PID 1638 | RSS: Main: 271482880 16:39:36 INFO - PID 1638 | 16:42:37 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49248/tests/dromaeo/cssquery-prototype.html) 16:42:38 INFO - PID 1638 | RSS: Main: 264597504 16:42:38 INFO - PID 1638 | 16:45:41 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49248/tests/dromaeo/cssquery-yui.html) 16:45:42 INFO - PID 1638 | RSS: Main: 274440192 16:45:42 INFO - PID 1638 | 16:48:44 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-yui.html (next: http://localhost:49248/tests/dromaeo/cssquery-dojo.html) 16:48:45 INFO - PID 1638 | RSS: Main: 268943360 16:48:45 INFO - PID 1638 | 16:48:45 INFO - PID 1638 | __start_tp_report 16:48:45 INFO - PID 1638 | _x_x_mozilla_page_load 16:48:45 INFO - PID 1638 | _x_x_mozilla_page_load_details 16:48:45 INFO - PID 1638 | |i|pagename|runs| 16:48:45 INFO - PID 1638 | |0;dojo.html;2785.7632101271392;3098.2409309719123;3145.7259809777047;3619.1133172372765;3671;5593.888122237553;5853.326867410249;6134.314131579348;6211.689415529228;6281.685915704208;6150.169727086842;6176.907346389797;6197.473214776749;6251.437370636641;6264.874702505958;4869.634777391692;5275.208718692188;5559.305086864142;5627.493525582697;5699.886002279952;5429.185622156669;5540;5622.971885140589;5631.1271752878365;5703.17303990921;4808.942032752789;5374.677519348813;5436.592255580847;5546.611737178399;5560.944390556083;5274.868128296785;5379.273798037254;5453.809116680897;5462.481064298885;5471.945280547184;5451.509364157245;5461.754221060062;5468.699221542814;5507.008738427082;5527.474889885454;6038.667873266969;6144.354842741537;6183.752649893999;6211.47202487789;6232.0963460298;6483.675816209171;6509.707063182168;6738.8315292117595;6751.122354093985;6751.223609284946;6759.121314229119;6840.623765692885;6860.965695171542;6868.278830722752;6869.622170780605;6274.592151510183;6303.3066362700065;6312.652804095773;6312.873742525124;6323.272823625342;6724;7019.92980070203;7024.508284420143;7041;7044.682989265495;6452.22573291208;6560.934390656128;6619.900701489481;6668.833279167962;6696.129503164558;6828.453723702092;6854.5201835872;6861.210960739579;6867.896981545281;6880.8967865482055;6690.966545167243;6703.463722902156;6744.730210791611;6759.256481787;6767;6497.447716944018;6536.673166341759;6605.504587155982;6659;6675.833104172434;5201.7919283229;5211;5212.869678258074;5221.660592061504;5283.788648454096;4659.2312268476;4664.346991421204;4666.859994200179;4668.5564871337165;4709.481956984729;5270.341207349082;5279.630425870152;6158.876822463526;6180.134781130645;6356.682165891687;6807.183138023469;6831.6242606657115;6848.794536163923;6859.416949559343;6874.312568743086;6388.840278993062;6427.871442571122;6429.164208652845;6435.549511534241;6446.129772480749;5842.094475356326;5957.821265362046;6075.635461872302;6087.786927457606;6106.6336019838955;6888.207856096613;6991.440684745106;7047.788566343018;7104.680289386886;7106.253843346581;5389.595780316414;6316.842078948063;6369.777057802955;6602.141721576164;6840.452763778985;6784.728610855511;6839.692213850488;7040.507164498641;7125.928740712527;7135.108111486064;5715.8856822864145;6757.290484499057;6776.93223067763;6809.761658341934;6831.59010459374;6590.94544872818;6681;6740.460763139035;6768.120144381199;6789.355011273921;5602.971985140048;6558.376954189344;6665.533412661067;6666.966665166643;6674.032265321598;6250.781097685907;6616.584946494591;6622.536422450382;6626.171728533934;6651.866962660622;6119.388061194023;6215.223097112861;6273.372662733873;6378.075179098912;6393.680315984275;6574.671266436563;6593.736250549924;6600.702968366531;6609;6622.139121914121;6310.652914089769;6361.982082866719;6363.745450181941;6367.87264254703;6398.87202255943 16:48:45 INFO - PID 1638 | |1;ext.html;769.8999130113084;1107.7396811749238;1111.8332250162473;1276.8978481721463;1283.0441321215696;32800.50799238013;36043;36271.99999999996;37042.814785926064;37121.958292293915;28973.855130724318;36873.89378318648;37341.43987840185;37341.626583734156;37408.625913740856;5260.842174734745;6035.396460353973;7755.728549500768;7941.047074351057;7943.880841787378;7294.307040831112;8331.083580806137;8513.95743021284;8518.87221691675;8548.145185481464;8589.35579831512;8597.699080532182;8631.611577478998;8640;8662.003869554987;8580.528070956096;8589.184027517407;8620.956895215546;8638.524881131536;8652.524111173883;7775.727849525239;8169.5098294102545;8534.317254619615;8536.44513106646;8601.526916019617;37178.628213717784;37392;37423;37451.81274093612;37506.249875002344;3491.162121090945;3492.1095120744303;3525.6826885580163;3528.700060494861;3570.1431656402538;26888;26915.73084269152;29645;29717.70282297171;29865.701342986726;3109.720125188744;3146;3160.352127813793;3190.5533225348236;3190.6171259448784;272747;274883;274898;274994;275647.999999998;39325.21349572993;40131;47341.816454588225;48578;48654.513454864995;49719;49786.50213497819;50003.749981249865;50073.74815629639;50134;14303.928480357532;14304.928475357556;14426.422943082369;14439.422423102958;14448.711025779425;11558.826617600742;11614.76770464586;11684.532618695326;11686.415679216176;11723.824142637868;10362;12459.875401245872;12492.000639948965;12525.749485010248;12539.68333325006;4790.592799612072;4928.901421971541;5196.428392876781;5251.36983561975;5252.133397989365;965.35803690545;965.584798536622;989.4904124375952;1000.8798944126606;1024.5082360467018;20230.595388092155;23848.284551463483;24154.637680434807;24162.275131746075;24189.00881354592;1083.8807731149568;1087.423665457309;1093.639099097296;1115.682030621269;1115.7712668902855;11763.588369395557;12344.938275308567;12428.689282768004;12438;12482.687932801753;365218;367172;367580;368554;368830;51486;51642.22536661953;51655.7417212919;51764.22353664698;51803.7409812956;1481.126135580013;1490.880729541634;1519.1037288000132;1523.2231561903288;1529.9541013769606;20607.381778546667;22461.43846403853;23798.048078077376;23849.880750596138;23875.76124238805;37321.44017839789;37326.813365933;37333.6266637341;37388.813055934545;37405.43891841679;319034;323059.38584299816;324918;325655;326160;31946.68053319437;32017.6798232024;32678.673213267564;33203.50194747032;33423.66576334303;42372.94067648333;42590;43057.354139687304;43362.56637433585;43447;27410.314742131606;28993;29155.708442916148;29582.260443489085;29906.551401728557;24667.753322466546;25730.485390292462;28912;29795.80816767305;29799.70200298056;35109.82445087758;35758;35944.64055359413;35979.28041439209;36172;22971;27173;30010.499475026598;30161;30671.846640766653;18026.90986545059;23253.18613848507;23541.882290588437;23620.291391258288;23736.050557977487 16:48:45 INFO - PID 1638 | |2;jquery.html;420.2540490629133;433.3067092651756;437.3704339565697;446.069944166413;461.73450266096984;20445.48886277844;22047.559048819036;22746.54506909865;22949.541009179808;22959.19642812502;21782.564348713015;22462.775372246273;22500.54998900021;22728.772712272912;23417.180398686007;5973.492253158487;6058.121572372003;6244.843878903019;6260.718267677966;6279.811605651815;6022.307434645006;6134.723937422805;6134.938650613503;6159.38406159385;6174.5060395168275;5242.475752424746;5891.204687367193;6071.332153463116;6091.725872335734;6149.631022138686;4844.418669759616;5473.768402109533;6032.758689652409;6082.756689732428;6095.817125486243;4451.866444006685;5397.4062853086125;5417.83746487606;5451.672899626035;5539.446055394468;21370.46573835651;22581;28051.15846524587;28098.297542561395;28308.71691283102;1891.366392258595;2230.721159855018;2231.4421394651176;2245.8989345479495;2259.8757068361233;17501.824981750146;17826.821731782646;17870.374536891148;18397.3560925367;18565.62868742631;2083.7707852136255;2103.968440473394;2151.4406254373976;2152.7847215278503;2156.8598041127434;127183;151026;152077;152400.2379988104;159635;12571.622851314476;13045.217286962812;13099.279539625222;13169.73660526784;13230.20618762858;16362.83637163637;16642.334306627843;16658.6668266634;16662.250198741087;16665.666686666442;8665.653373864974;8892.8666070009;8958.238549723215;8970.730878073668;9089;8275.793105172419;8428.704995325133;8431.831363372698;8562.700305489278;8622.180892815173;8011.439199255996;8122.431429799975;8153.5923203839575;8164.856530235902;8168.509889406655;2242.2488466363625;2254.0983606557506;2254.526549424639;2264.705588273514;2425.4057755849576;807.4791759315326;925.9398139120935;940.9717708468756;941.0154836214713;975.331897650112;16247.837521624633;20170.69743953842;20311;20794.896025520076;20891.37325880226;966.4636126949611;979.637534112383;993.6174572789528;1000.7698229407277;1007.385494848142;8741.562921854009;9349.158575728217;10899.455027248607;10947.945260273647;10963.671089867157;720609;727968;735954;737617;738455;117897.41051294688;147134.52865471423;148806;152807.23596381946;154101;1197.706561892342;1223.5044806853239;1253.724180680249;1254.9121561490608;1257;17765.822341776417;18256.99586522754;20518.97405129708;21487.033083511593;21619.81091040008;56058.439415605324;56940.576485586535;61649.69175154275;62429.127126186286;63081;77888.61055694685;78527;78921.60539197267;80929.59535202286;81276;7370.520916140434;8695.695650652196;8910.821783564423;8994.370394072352;9017.143371379709;9851.605935762776;13872;13881.37533811001;14229.928850355682;14254.216018119103;11552.306861588331;11573.942130289295;11810.173287869766;11982.64052078439;12317.199382040139;11125.221234513507;11166.888331116585;11168.162387821108;11372.31766094037;11507.194496385508;11871.109666774853;12048.277103373826;12139.453724582592;12178.45196966121;12248.877511224773;8054.476459030145;8588.26999705007;8588.840506531787;8849.4247873888;8849.601767920587;11099.722506937069;11664.008559272557;11692.0061794745;11943.283402995849;12053 16:48:45 INFO - PID 1638 | |3;mootools.html;1295.3976400973545;1451.4266864588487;1465.9706805863882;1467.6844478437133;1489.493572185457;9382.530873456326;10103.595856165754;11219.102471802256;11436.942815285933;12208.511659533619;9820.607175712963;11583.884161158387;11797.646070617895;11810.996065334435;11850.407479626027;2178.9237376691817;2484.925452236436;2520.6345079963394;2552.310876063462;2561.2060261318957;2467.9876600617063;2471.122751423246;2516.207394670682;2521.8612976286295;2523.949521009588;2469.826832254683;2486.365976675945;2492.675952126221;2513.28371414147;2515.0317127905796;2461.2616215135477;2465.593177125772;2486.6643003194517;2487.61441976493;2517.748225177486;2422.733499315074;2507.7241503434607;2524.5203411351786;2528.8229823912334;2533.138732830828;11978.640640780703;12066.034717222601;12080;12102.697432564168;12131.696707582292;6540.803775886731;6750.93249067508;6755.189377274758;6759;6767.849465590862;4098.8360465581345;4347.760903134888;4419.381286619876;4434.4678638563255;4442.400275962736;1215.9574414895437;1239.7892358299111;1277.1060257819563;1293.780057390246;1300.024981264052;15177.950976618236;15637;16757.49727508177;16870.325186992504;16930.915345423193;5617.9438205618235;5694.9430505695245;5891.882162356729;5934.287885453773;5945.554083443759;12464.626061218178;12482;12501.624951251477;12521.499140034297;12551.3096779678;6650.567713098633;6667.233268174125;6694;6703.966480167665;6717.529772915849;6033.215681961317;6192.133101365813;6200.038993955944;6235.96882015587;6237.313895471494;4435.3125265583885;4527.456705195397;4579.244424669892;4623.167829790603;4643.628509719214;1290.9225446473242;1308.7305313845554;1310.0829419406455;1325.8806707396382;1334.2594859852875;764.7323436797077;779.283059585183;794.4835856693196;797.7766225456766;817.8323443694028;5374.435684253175;6258.186435763321;6410.743570257231;6420.839479012969;6513;561.9943800561942;564.5173376762891;575.2550447170923;582.670791003082;594.6283572767021;4206.305959516707;4312.288472402019;4381.38660587518;4445.51099379068;4483.3723278741;14849.40602375893;15316.081035137928;15375.077495350315;15485.225738713245;15504.689906202038;11869.821952670716;12348.629541113782;13436.664083397798;14441.78337324941;15038.54884353471;10796.352218866894;11064.889351106385;11073.778524429466;11092.611758588411;11106.222564420414;5411.02601531728;5473.452654734495;7200.135983681782;7239.855202895807;7287.635618219174;13064.869351306745;13067.738645227233;13100.672483187996;13334.933325333312;13539.390727417092;13893.235872027135;13898.513552026036;13900.860991389956;13924.443022278996;13984;9464.958854526134;9506;9679.419234845931;9680.85478717834;9702.75743106428;10716.785664286826;10775.784484310427;10850.837237441287;10852.565897364017;10881.619143329945;9009.414388064755;9192.402493837879;9241.445513268956;9312.860307095534;9318.974912759726;9061.682841100794;9082.273418126666;9163.450192988175;9165.77085572866;9195.448273103635;8157.021157461143;8261.628226729692;8347.499150051017;8408.327333813158;8422.578871056545;6034.366391528826;6171.783987560414;6241.563090583615;6241.656708881055;6281.246250449975;6093.238345206849;6179.752809887555;6286.8428289291305;6339.636978049742;6367.140436041075 16:48:45 INFO - PID 1638 | |4;prototype.html;376.61208954776583;389.3245219544091;411.2535747618073;412.128348542832;414.0683462210026;6321.494280457564;6387.552871299011;6493.870122597557;6529.738810447582;6537.673116344182;5540;5955.940440595593;6195.6902154892305;6332.132021053789;6403.231612206541;3782.754120982141;4313.051128751669;4576.816927322903;4758;4812.422509298889;3811.637894400041;4302.48370195577;4780.952190478103;4804.87987800306;4839.693282813656;3642.3990041643096;4729.432468103815;4748.35897153885;4779.251930140166;4811.615070794345;4192.685548583838;4756.952430475685;4778.682339065551;4804.639652026112;4822.156122678517;3231.9353612927844;4103.4460347853255;4200.852970146061;4256.829017201218;4280.015596412812;5349.893002139936;5902.586818922677;6195.504359651262;6200.937990620081;6205.193324867739;5727.195933281003;5744.22452968848;5746.626469279525;5751.884962300773;5759;6507.8698426031215;6721.361470660279;6777.728890844361;6815.693293801791;6821.897671534931;4870.025994801054;4872.3422337984275;4878.853634390938;4891.951080489185;4906.8773280668265;6698.631575263358;6781.694823732993;6872.553284036521;7118.9644051779405;7255.746048888316;4958.652894297436;4963.305137280783;4972.925406118911;4976.676516026447;4994.700317980933;5080.618953578496;5089.440161582223;5090.338256026767;5103.311053007871;5129.8717532061255;5462.6995515247045;5631.436856314336;5679.148127780866;5685.687287199244;5740.311162660508;6712.858814001534;6846.7945961621235;6849.212340580897;6851.212110607344;6895.689693963784;1486.9256537173098;1547.7214101461846;1706.2748331958867;1711.3345051646102;1725.240894006633;1161.1639619473965;1187.281694574787;1187.7980743273665;1192.070185255502;1202.8135638975973;5519.475649813261;6464.062710906892;6478.190226221723;6511.902321465182;6512.185976752906;6143.56995010345;6277.686115694289;6504.024396340492;6521.11964884744;6686.331366863372;5667.744951477193;6390.7443702251385;6391.58454700443;6444.77549265638;6515.446187074057;3650.4889315495852;3674.577423596267;3694.3560115748774;3701.7408781385584;3704.944425833615;6730.461563074942;6742.696578653972;6911;7254.600996945219;7321.3410793027815;5751.85620359486;5886.323072846678;5903.35063143054;7185.748498802517;7269.16698303593;6958.111469645015;7264.256578421247;7298.635068246673;7308.232635573188;7350.669219885118;5962.2249107615735;6851.486138539729;7031.910053941648;7063.611501367474;7079.787606371817;6477.481801455967;6482.902756458751;6791.762288319885;6872.793816185523;6944.930550694428;6191.349908259748;7286.344229019413;7322.340989310987;7338.926610734038;7355.963220184079;4446.710963787344;4908.9754551227015;4963.578095861748;4968.27959945813;4987;6984.498332858459;7000.124984376953;7001.184354526897;7022.6488675565;7057.6471176439945;5663.2859231026605;6251.749930002749;6765.391114799691;6768.357006084414;6781.660916954034;5604.3835178131185;6183.907241391293;6691.397774200345;6769.221539523018;6787.966060169668;5581.49766521015;6268.373162683695;6634.369734875179;6642.4021838034805;6644.568103073478;5092.516210959953;5916.645001299936;6254.718537665725;6673.9633466773475;6783.59298442095;5816.662167701368;6058.27300723916;6374.171357723466;6513.902291465726;6608.257573107299 16:48:45 INFO - PID 1638 | |5;yui.html;735.6468894930433;780.3016300411135;837.363603661218;839.0309192882223;842.3429724814642;14182.57452276432;15247.847521524782;15963.840361596394;16056.598585035365;16291.918540407281;15055.021423607457;15086.924565377185;15266.770998435033;16910.74633880493;17743.73384399235;956.46437994723;973.6884197056945;978.9510524473783;980.0248752491245;981.0434826044585;955.1642312976146;979.7697541077841;991.1179050644931;991.4051569058544;992.0525897767615;1028.3521381529663;1044.8223801953648;1053.3837704942619;1057.5611121384616;1061.1457776489922;860.2257967828941;986.57577241786;1048.0462778871229;1049.01916707878;1049.0191670787838;758.2569082299323;773.0414286285022;777.1956025513623;778.1906816910406;778.404520541786;16077.839221607752;16401.42595009169;16523.92594481355;16709.66580668392;16879.24043418037;14598.635034124125;14683.034096606172;14818.555443336609;14858.851411485855;15518.534443966699;13212.735745285137;13215.273159976197;13294.667633309246;13369.598912032654;13383.598492045157;3348.7990720556845;3392.8133952632597;3397.473391624302;3403.3193361327835;3405.3359594879007;86800;111612;113503;113698.86301136966;113881;11284.317132502672;11349.31904085741;11395.322099306657;11428.485718142703;11475.540978360772;20828.47928801792;24351.26946191617;24635.137770178317;24695.135670251457;24727.87636061808;7963.203679631991;8029.197080291924;8037.156098609562;8099.959500202579;8234.958825205835;4183.184279065553;4206.915861682749;4230.906424636338;4274.829006839754;4296.849610263688;4103.261412945639;4162.438070860395;4188.916221675549;4207.548395803443;4214.831406743696;907.1019690506351;941.7692665297046;947.0482165423705;952.2049089010616;954.2985905359556;608.5253502268238;610.9236345456818;611.8623309755269;641.8491654461197;643.5688088980395;8978.685745998859;8987.220145074116;10153.289269751194;10298.092249205403;10449.111825494918;598.6916737879995;606.6390497653889;618.3352895637208;624.4036944717784;631.6525910749069;4636.721796692142;4772.308015337757;4810.302506136591;4810.494898035656;4828.95171048292;105751.41372879413;107067.46466267618;107343;108991.45504272587;109690;77150.84273735898;97761.51119244358;98613;99795.50102249443;99934;75808.4514929095;76121.61939190268;76432.73888980538;76681.6165919178;77826.44347113026;9503.76240593963;9631.181349585364;9688.321817472992;9706.6117355305;9709.417434953926;42116.78941605272;42531.7873410631;42718;43286.78356608197;43878.12243755171;50276;50389;50865.49134508608;51319.23021154611;51373;11822.34977076269;11886.108541859569;11915.344656044;11932.28406295625;12297.508099675913;12537.059720520814;12540.623781286577;12547.498100075894;12626.684332891384;13128.60614181576;11700.648980530597;11704.824427633423;11953.22304050234;12285.324307163191;12332.445039973401;11010.119190464904;11709.297442153498;12143.696407589527;12258.938705306773;12325.075619328765;11619.5352185915;11862.822057669315;12037.217580857216;12166.904978551973;12244.081693872817;8383.790405239917;8680.305575554066;8909.331800114887;8975.84459375459;9075.773105672148;9431.386959847589;9482.241420686467;9492.762680933032;9502.61989520439;9517.666881659108 16:48:45 INFO - PID 1638 | __end_tp_report 16:48:45 INFO - PID 1638 | __start_cc_report 16:48:45 INFO - PID 1638 | _x_x_mozilla_cycle_collect,427 16:48:45 INFO - PID 1638 | __end_cc_report 16:48:45 INFO - PID 1638 | __startTimestamp1491522525022__endTimestamp 16:48:45 INFO - PID 1638 | 16:48:45 INFO - PID 1638 | ------- Summary: start ------- 16:48:45 INFO - PID 1638 | Number of tests: 6 16:48:45 INFO - PID 1638 | 16:48:45 INFO - PID 1638 | [#0] dojo.html Cycles:165 Average:6160.38 Median:6369.78 stddev:801.03 (12.6%) stddev-sans-first:758.49 16:48:45 INFO - PID 1638 | Values: 2785.8 3098.2 3145.7 3619.1 3671.0 5593.9 5853.3 6134.3 6211.7 6281.7 6150.2 6176.9 6197.5 6251.4 6264.9 4869.6 5275.2 5559.3 5627.5 5699.9 5429.2 5540.0 5623.0 5631.1 5703.2 4808.9 5374.7 5436.6 5546.6 5560.9 5274.9 5379.3 5453.8 5462.5 5471.9 5451.5 5461.8 5468.7 5507.0 5527.5 6038.7 6144.4 6183.8 6211.5 6232.1 6483.7 6509.7 6738.8 6751.1 6751.2 6759.1 6840.6 6861.0 6868.3 6869.6 6274.6 6303.3 6312.7 6312.9 6323.3 6724.0 7019.9 7024.5 7041.0 7044.7 6452.2 6560.9 6619.9 6668.8 6696.1 6828.5 6854.5 6861.2 6867.9 6880.9 6691.0 6703.5 6744.7 6759.3 6767.0 6497.4 6536.7 6605.5 6659.0 6675.8 5201.8 5211.0 5212.9 5221.7 5283.8 4659.2 4664.3 4666.9 4668.6 4709.5 5270.3 5279.6 6158.9 6180.1 6356.7 6807.2 6831.6 6848.8 6859.4 6874.3 6388.8 6427.9 6429.2 6435.5 6446.1 5842.1 5957.8 6075.6 6087.8 6106.6 6888.2 6991.4 7047.8 7104.7 7106.3 5389.6 6316.8 6369.8 6602.1 6840.5 6784.7 6839.7 7040.5 7125.9 7135.1 5715.9 6757.3 6776.9 6809.8 6831.6 6590.9 6681.0 6740.5 6768.1 6789.4 5603.0 6558.4 6665.5 6667.0 6674.0 6250.8 6616.6 6622.5 6626.2 6651.9 6119.4 6215.2 6273.4 6378.1 6393.7 6574.7 6593.7 6600.7 6609.0 6622.1 6310.7 6362.0 6363.7 6367.9 6398.9 16:48:45 INFO - PID 1638 | 16:48:45 INFO - PID 1638 | [#1] ext.html Cycles:180 Average:46106.35 Median:33002.00 stddev:85443.33 (258.9%) stddev-sans-first:85615.21 16:48:45 INFO - PID 1638 | Values: 769.9 1107.7 1111.8 1276.9 1283.0 32800.5 36043.0 36272.0 37042.8 37122.0 28973.9 36873.9 37341.4 37341.6 37408.6 5260.8 6035.4 7755.7 7941.0 7943.9 7294.3 8331.1 8514.0 8518.9 8548.1 8589.4 8597.7 8631.6 8640.0 8662.0 8580.5 8589.2 8621.0 8638.5 8652.5 7775.7 8169.5 8534.3 8536.4 8601.5 37178.6 37392.0 37423.0 37451.8 37506.2 3491.2 3492.1 3525.7 3528.7 3570.1 26888.0 26915.7 29645.0 29717.7 29865.7 3109.7 3146.0 3160.4 3190.6 3190.6 272747.0 274883.0 274898.0 274994.0 275648.0 39325.2 40131.0 47341.8 48578.0 48654.5 49719.0 49786.5 50003.7 50073.7 50134.0 14303.9 14304.9 14426.4 14439.4 14448.7 11558.8 11614.8 11684.5 11686.4 11723.8 10362.0 12459.9 12492.0 12525.7 12539.7 4790.6 4928.9 5196.4 5251.4 5252.1 965.4 965.6 989.5 1000.9 1024.5 20230.6 23848.3 24154.6 24162.3 24189.0 1083.9 1087.4 1093.6 1115.7 1115.8 11763.6 12344.9 12428.7 12438.0 12482.7 365218.0 367172.0 367580.0 368554.0 368830.0 51486.0 51642.2 51655.7 51764.2 51803.7 1481.1 1490.9 1519.1 1523.2 1530.0 20607.4 22461.4 23798.0 23849.9 23875.8 37321.4 37326.8 37333.6 37388.8 37405.4 319034.0 323059.4 324918.0 325655.0 326160.0 31946.7 32017.7 32678.7 33203.5 33423.7 42372.9 42590.0 43057.4 43362.6 43447.0 27410.3 28993.0 29155.7 29582.3 29906.6 24667.8 25730.5 28912.0 29795.8 29799.7 35109.8 35758.0 35944.6 35979.3 36172.0 22971.0 27173.0 30010.5 30161.0 30671.8 18026.9 23253.2 23541.9 23620.3 23736.1 16:48:45 INFO - PID 1638 | 16:48:45 INFO - PID 1638 | [#2] jquery.html Cycles:180 Average:40953.61 Median:22737.66 stddev:122057.30 (536.8%) stddev-sans-first:122361.75 16:48:45 INFO - PID 1638 | Values: 420.3 433.3 437.4 446.1 461.7 20445.5 22047.6 22746.5 22949.5 22959.2 21782.6 22462.8 22500.5 22728.8 23417.2 5973.5 6058.1 6244.8 6260.7 6279.8 6022.3 6134.7 6134.9 6159.4 6174.5 5242.5 5891.2 6071.3 6091.7 6149.6 4844.4 5473.8 6032.8 6082.8 6095.8 4451.9 5397.4 5417.8 5451.7 5539.4 21370.5 22581.0 28051.2 28098.3 28308.7 1891.4 2230.7 2231.4 2245.9 2259.9 17501.8 17826.8 17870.4 18397.4 18565.6 2083.8 2104.0 2151.4 2152.8 2156.9 127183.0 151026.0 152077.0 152400.2 159635.0 12571.6 13045.2 13099.3 13169.7 13230.2 16362.8 16642.3 16658.7 16662.3 16665.7 8665.7 8892.9 8958.2 8970.7 9089.0 8275.8 8428.7 8431.8 8562.7 8622.2 8011.4 8122.4 8153.6 8164.9 8168.5 2242.2 2254.1 2254.5 2264.7 2425.4 807.5 925.9 941.0 941.0 975.3 16247.8 20170.7 20311.0 20794.9 20891.4 966.5 979.6 993.6 1000.8 1007.4 8741.6 9349.2 10899.5 10947.9 10963.7 720609.0 727968.0 735954.0 737617.0 738455.0 117897.4 147134.5 148806.0 152807.2 154101.0 1197.7 1223.5 1253.7 1254.9 1257.0 17765.8 18257.0 20519.0 21487.0 21619.8 56058.4 56940.6 61649.7 62429.1 63081.0 77888.6 78527.0 78921.6 80929.6 81276.0 7370.5 8695.7 8910.8 8994.4 9017.1 9851.6 13872.0 13881.4 14229.9 14254.2 11552.3 11573.9 11810.2 11982.6 12317.2 11125.2 11166.9 11168.2 11372.3 11507.2 11871.1 12048.3 12139.5 12178.5 12248.9 8054.5 8588.3 8588.8 8849.4 8849.6 11099.7 11664.0 11692.0 11943.3 12053.0 16:48:45 INFO - PID 1638 | 16:48:45 INFO - PID 1638 | [#3] mootools.html Cycles:180 Average:7187.43 Median:2542.72 stddev:4507.54 (177.3%) stddev-sans-first:4498.44 16:48:45 INFO - PID 1638 | Values: 1295.4 1451.4 1466.0 1467.7 1489.5 9382.5 10103.6 11219.1 11436.9 12208.5 9820.6 11583.9 11797.6 11811.0 11850.4 2178.9 2484.9 2520.6 2552.3 2561.2 2468.0 2471.1 2516.2 2521.9 2523.9 2469.8 2486.4 2492.7 2513.3 2515.0 2461.3 2465.6 2486.7 2487.6 2517.7 2422.7 2507.7 2524.5 2528.8 2533.1 11978.6 12066.0 12080.0 12102.7 12131.7 6540.8 6750.9 6755.2 6759.0 6767.8 4098.8 4347.8 4419.4 4434.5 4442.4 1216.0 1239.8 1277.1 1293.8 1300.0 15178.0 15637.0 16757.5 16870.3 16930.9 5617.9 5694.9 5891.9 5934.3 5945.6 12464.6 12482.0 12501.6 12521.5 12551.3 6650.6 6667.2 6694.0 6704.0 6717.5 6033.2 6192.1 6200.0 6236.0 6237.3 4435.3 4527.5 4579.2 4623.2 4643.6 1290.9 1308.7 1310.1 1325.9 1334.3 764.7 779.3 794.5 797.8 817.8 5374.4 6258.2 6410.7 6420.8 6513.0 562.0 564.5 575.3 582.7 594.6 4206.3 4312.3 4381.4 4445.5 4483.4 14849.4 15316.1 15375.1 15485.2 15504.7 11869.8 12348.6 13436.7 14441.8 15038.5 10796.4 11064.9 11073.8 11092.6 11106.2 5411.0 5473.5 7200.1 7239.9 7287.6 13064.9 13067.7 13100.7 13334.9 13539.4 13893.2 13898.5 13900.9 13924.4 13984.0 9465.0 9506.0 9679.4 9680.9 9702.8 10716.8 10775.8 10850.8 10852.6 10881.6 9009.4 9192.4 9241.4 9312.9 9319.0 9061.7 9082.3 9163.5 9165.8 9195.4 8157.0 8261.6 8347.5 8408.3 8422.6 6034.4 6171.8 6241.6 6241.7 6281.2 6093.2 6179.8 6286.8 6339.6 6367.1 16:48:45 INFO - PID 1638 | 16:48:45 INFO - PID 1638 | [#4] prototype.html Cycles:180 Average:5505.21 Median:6010.25 stddev:1670.20 (27.8%) stddev-sans-first:1629.93 16:48:45 INFO - PID 1638 | Values: 376.6 389.3 411.3 412.1 414.1 6321.5 6387.6 6493.9 6529.7 6537.7 5540.0 5955.9 6195.7 6332.1 6403.2 3782.8 4313.1 4576.8 4758.0 4812.4 3811.6 4302.5 4781.0 4804.9 4839.7 3642.4 4729.4 4748.4 4779.3 4811.6 4192.7 4757.0 4778.7 4804.6 4822.2 3231.9 4103.4 4200.9 4256.8 4280.0 5349.9 5902.6 6195.5 6200.9 6205.2 5727.2 5744.2 5746.6 5751.9 5759.0 6507.9 6721.4 6777.7 6815.7 6821.9 4870.0 4872.3 4878.9 4892.0 4906.9 6698.6 6781.7 6872.6 7119.0 7255.7 4958.7 4963.3 4972.9 4976.7 4994.7 5080.6 5089.4 5090.3 5103.3 5129.9 5462.7 5631.4 5679.1 5685.7 5740.3 6712.9 6846.8 6849.2 6851.2 6895.7 1486.9 1547.7 1706.3 1711.3 1725.2 1161.2 1187.3 1187.8 1192.1 1202.8 5519.5 6464.1 6478.2 6511.9 6512.2 6143.6 6277.7 6504.0 6521.1 6686.3 5667.7 6390.7 6391.6 6444.8 6515.4 3650.5 3674.6 3694.4 3701.7 3704.9 6730.5 6742.7 6911.0 7254.6 7321.3 5751.9 5886.3 5903.4 7185.7 7269.2 6958.1 7264.3 7298.6 7308.2 7350.7 5962.2 6851.5 7031.9 7063.6 7079.8 6477.5 6482.9 6791.8 6872.8 6944.9 6191.3 7286.3 7322.3 7338.9 7356.0 4446.7 4909.0 4963.6 4968.3 4987.0 6984.5 7000.1 7001.2 7022.6 7057.6 5663.3 6251.7 6765.4 6768.4 6781.7 5604.4 6183.9 6691.4 6769.2 6788.0 5581.5 6268.4 6634.4 6642.4 6644.6 5092.5 5916.6 6254.7 6674.0 6783.6 5816.7 6058.3 6374.2 6513.9 6608.3 16:48:45 INFO - PID 1638 | 16:48:45 INFO - PID 1638 | [#5] yui.html Cycles:180 Average:20077.46 Median:23162.17 stddev:29563.52 (127.6%) stddev-sans-first:29610.78 16:48:45 INFO - PID 1638 | Values: 735.6 780.3 837.4 839.0 842.3 14182.6 15247.8 15963.8 16056.6 16291.9 15055.0 15086.9 15266.8 16910.7 17743.7 956.5 973.7 979.0 980.0 981.0 955.2 979.8 991.1 991.4 992.1 1028.4 1044.8 1053.4 1057.6 1061.1 860.2 986.6 1048.0 1049.0 1049.0 758.3 773.0 777.2 778.2 778.4 16077.8 16401.4 16523.9 16709.7 16879.2 14598.6 14683.0 14818.6 14858.9 15518.5 13212.7 13215.3 13294.7 13369.6 13383.6 3348.8 3392.8 3397.5 3403.3 3405.3 86800.0 111612.0 113503.0 113698.9 113881.0 11284.3 11349.3 11395.3 11428.5 11475.5 20828.5 24351.3 24635.1 24695.1 24727.9 7963.2 8029.2 8037.2 8100.0 8235.0 4183.2 4206.9 4230.9 4274.8 4296.8 4103.3 4162.4 4188.9 4207.5 4214.8 907.1 941.8 947.0 952.2 954.3 608.5 610.9 611.9 641.8 643.6 8978.7 8987.2 10153.3 10298.1 10449.1 598.7 606.6 618.3 624.4 631.7 4636.7 4772.3 4810.3 4810.5 4829.0 105751.4 107067.5 107343.0 108991.5 109690.0 77150.8 97761.5 98613.0 99795.5 99934.0 75808.5 76121.6 76432.7 76681.6 77826.4 9503.8 9631.2 9688.3 9706.6 9709.4 42116.8 42531.8 42718.0 43286.8 43878.1 50276.0 50389.0 50865.5 51319.2 51373.0 11822.3 11886.1 11915.3 11932.3 12297.5 12537.1 12540.6 12547.5 12626.7 13128.6 11700.6 11704.8 11953.2 12285.3 12332.4 11010.1 11709.3 12143.7 12258.9 12325.1 11619.5 11862.8 12037.2 12166.9 12244.1 8383.8 8680.3 8909.3 8975.8 9075.8 9431.4 9482.2 9492.8 9502.6 9517.7 16:48:45 INFO - PID 1638 | -------- Summary: end -------- 16:48:45 INFO - PID 1638 | 16:48:45 INFO - TEST-INFO | 1638: exit 0 16:48:45 INFO - TEST-OK | dromaeo_css | took 1116644ms 16:48:45 INFO - TEST-START | kraken 16:48:45 INFO - operating with platform_type : mac_ 16:48:45 INFO - Initialising browser for kraken test... 16:48:45 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmpqsgCXl/profile http://localhost:49248/getInfo.html) 16:48:48 INFO - PID 1649 | 2017-04-06 16:48:48.546 plugin-container[1650:10079] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 16:48:48 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:48:48 INFO - PID 1649 | 2017-04-06 16:48:48.551 plugin-container[1650:10079] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 16:48:48 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:48:48 INFO - PID 1649 | 2017-04-06 16:48:48.551 plugin-container[1650:10079] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:48:48 INFO - PID 1649 | __metrics Screen width/height:1600/1200 16:48:48 INFO - PID 1649 | colorDepth:24 16:48:48 INFO - PID 1649 | Browser inner width/height: 1024/689 16:48:48 INFO - PID 1649 | __metrics 16:48:48 ERROR - PID 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 16:48:49 INFO - TEST-INFO | 1649: exit 0 16:48:49 INFO - Browser initialized. 16:48:49 INFO - Running cycle 1/1 for kraken test... 16:48:49 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmpqsgCXl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:48:52 INFO - PID 1651 | 2017-04-06 16:48:52.383 plugin-container[1652:10207] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 16:48:52 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:48:52 INFO - PID 1651 | 2017-04-06 16:48:52.384 plugin-container[1652:10207] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 16:48:52 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:48:52 INFO - PID 1651 | 2017-04-06 16:48:52.384 plugin-container[1652:10207] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:49:01 INFO - PID 1651 | RSS: Main: 234323968 16:49:01 INFO - PID 1651 | 16:49:04 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection) 16:49:05 INFO - PID 1651 | RSS: Main: 248659968 16:49:05 INFO - PID 1651 | 16:49:08 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-dft) 16:49:09 INFO - PID 1651 | RSS: Main: 257335296 16:49:09 INFO - PID 1651 | 16:49:12 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-fft) 16:49:13 INFO - PID 1651 | RSS: Main: 259678208 16:49:13 INFO - PID 1651 | 16:49:15 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator) 16:49:16 INFO - PID 1651 | RSS: Main: 261754880 16:49:16 INFO - PID 1651 | 16:49:18 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:49:18 INFO - PID 1651 | RSS: Main: 262098944 16:49:18 INFO - PID 1651 | 16:49:21 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom) 16:49:22 INFO - PID 1651 | RSS: Main: 267755520 16:49:22 INFO - PID 1651 | 16:49:25 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate) 16:49:26 INFO - PID 1651 | RSS: Main: 268902400 16:49:26 INFO - PID 1651 | 16:49:29 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial) 16:49:30 INFO - PID 1651 | RSS: Main: 268668928 16:49:30 INFO - PID 1651 | 16:49:32 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:49:32 INFO - PID 1651 | RSS: Main: 267943936 16:49:32 INFO - PID 1651 | 16:49:34 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:49:35 INFO - PID 1651 | RSS: Main: 267821056 16:49:35 INFO - PID 1651 | 16:49:37 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:49:38 INFO - PID 1651 | RSS: Main: 267866112 16:49:38 INFO - PID 1651 | 16:49:41 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:49:42 INFO - PID 1651 | RSS: Main: 268402688 16:49:42 INFO - PID 1651 | 16:49:45 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:49:45 INFO - PID 1651 | RSS: Main: 267907072 16:49:45 INFO - PID 1651 | 16:49:47 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49248/tests/kraken/driver.html?testName=ai-astar) 16:49:47 INFO - PID 1651 | RSS: Main: 271192064 16:49:47 INFO - PID 1651 | 16:49:47 INFO - PID 1651 | __start_tp_report 16:49:47 INFO - PID 1651 | _x_x_mozilla_page_load 16:49:47 INFO - PID 1651 | _x_x_mozilla_page_load_details 16:49:47 INFO - PID 1651 | |i|pagename|runs| 16:49:47 INFO - PID 1651 | |0;ai-astar;92;101;158;181;184;122;145;148;120;122 16:49:47 INFO - PID 1651 | |1;audio-beat-detection;191;114;116;157;116;116;117;115;165;142 16:49:47 INFO - PID 1651 | |2;audio-dft;204;222;220;246;314;233;207;217;209;208 16:49:47 INFO - PID 1651 | |3;audio-fft;83;64;51;109;88;93;94;87;79;57 16:49:47 INFO - PID 1651 | |4;audio-oscillator;93;75;72;73;106;60;105;105;59;69 16:49:47 INFO - PID 1651 | |5;imaging-gaussian-blur;118;90;107;90;92;91;90;134;89;89 16:49:47 INFO - PID 1651 | |6;imaging-darkroom;99;100;103;132;161;99;165;161;101;108 16:49:47 INFO - PID 1651 | |7;imaging-desaturate;103;102;101;103;106;165;145;102;100;101 16:49:47 INFO - PID 1651 | |8;json-parse-financial;51;52;60;58;83;83;83;82;82;59 16:49:47 INFO - PID 1651 | |9;json-stringify-tinderbox;60;60;64;68;63;60;60;60;59;60 16:49:47 INFO - PID 1651 | |10;stanford-crypto-aes;86;86;89;89;88;100;88;83;111;126 16:49:47 INFO - PID 1651 | |11;stanford-crypto-ccm;170;171;167;161;147;158;159;149;206;151 16:49:47 INFO - PID 1651 | |12;stanford-crypto-pbkdf2;196;196;193;198;193;194;197;193;202;193 16:49:47 INFO - PID 1651 | |13;stanford-crypto-sha256-iterative;71;90;98;102;80;83;69;72;72;69 16:49:47 INFO - PID 1651 | __end_tp_report 16:49:47 INFO - PID 1651 | __start_cc_report 16:49:47 INFO - PID 1651 | _x_x_mozilla_cycle_collect,1028 16:49:47 INFO - PID 1651 | __end_cc_report 16:49:47 INFO - PID 1651 | __startTimestamp1491522587930__endTimestamp 16:49:47 INFO - PID 1651 | 16:49:47 INFO - PID 1651 | ------- Summary: start ------- 16:49:47 INFO - PID 1651 | Number of tests: 14 16:49:47 INFO - PID 1651 | 16:49:47 INFO - PID 1651 | [#0] ai-astar Cycles:10 Average:137.30 Median:153.00 stddev:31.32 (20.5%) stddev-sans-first:28.61 16:49:47 INFO - PID 1651 | Values: 92.0 101.0 158.0 181.0 184.0 122.0 145.0 148.0 120.0 122.0 16:49:47 INFO - PID 1651 | 16:49:47 INFO - PID 1651 | [#1] audio-beat-detection Cycles:10 Average:134.90 Median:129.50 stddev:27.52 (21.3%) stddev-sans-first:20.37 16:49:47 INFO - PID 1651 | Values: 191.0 114.0 116.0 157.0 116.0 116.0 117.0 115.0 165.0 142.0 16:49:47 INFO - PID 1651 | 16:49:47 INFO - PID 1651 | [#2] audio-dft Cycles:10 Average:228.00 Median:221.00 stddev:32.90 (14.9%) stddev-sans-first:33.73 16:49:47 INFO - PID 1651 | Values: 204.0 222.0 220.0 246.0 314.0 233.0 207.0 217.0 209.0 208.0 16:49:47 INFO - PID 1651 | 16:49:47 INFO - PID 1651 | [#3] audio-fft Cycles:10 Average:80.50 Median:85.00 stddev:18.11 (21.3%) stddev-sans-first:19.19 16:49:47 INFO - PID 1651 | Values: 83.0 64.0 51.0 109.0 88.0 93.0 94.0 87.0 79.0 57.0 16:49:47 INFO - PID 1651 | 16:49:47 INFO - PID 1651 | [#4] audio-oscillator Cycles:10 Average:81.70 Median:70.50 stddev:18.76 (26.6%) stddev-sans-first:19.44 16:49:47 INFO - PID 1651 | Values: 93.0 75.0 72.0 73.0 106.0 60.0 105.0 105.0 59.0 69.0 16:49:47 INFO - PID 1651 | 16:49:47 INFO - PID 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:99.00 Median:90.00 stddev:15.66 (17.4%) stddev-sans-first:15.02 16:49:47 INFO - PID 1651 | Values: 118.0 90.0 107.0 90.0 92.0 91.0 90.0 134.0 89.0 89.0 16:49:47 INFO - PID 1651 | 16:49:47 INFO - PID 1651 | [#6] imaging-darkroom Cycles:10 Average:122.90 Median:161.00 stddev:28.91 (18.0%) stddev-sans-first:29.34 16:49:47 INFO - PID 1651 | Values: 99.0 100.0 103.0 132.0 161.0 99.0 165.0 161.0 101.0 108.0 16:49:47 INFO - PID 1651 | 16:49:47 INFO - PID 1651 | [#7] imaging-desaturate Cycles:10 Average:112.80 Median:103.00 stddev:22.79 (22.1%) stddev-sans-first:23.90 16:49:47 INFO - PID 1651 | Values: 103.0 102.0 101.0 103.0 106.0 165.0 145.0 102.0 100.0 101.0 16:49:47 INFO - PID 1651 | 16:49:47 INFO - PID 1651 | [#8] json-parse-financial Cycles:10 Average:69.30 Median:82.00 stddev:14.30 (17.4%) stddev-sans-first:13.55 16:49:47 INFO - PID 1651 | Values: 51.0 52.0 60.0 58.0 83.0 83.0 83.0 82.0 82.0 59.0 16:49:47 INFO - PID 1651 | 16:49:47 INFO - PID 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:61.40 Median:60.00 stddev:2.80 (4.7%) stddev-sans-first:2.92 16:49:47 INFO - PID 1651 | Values: 60.0 60.0 64.0 68.0 63.0 60.0 60.0 60.0 59.0 60.0 16:49:47 INFO - PID 1651 | 16:49:47 INFO - PID 1651 | [#10] stanford-crypto-aes Cycles:10 Average:94.60 Median:87.00 stddev:13.81 (15.9%) stddev-sans-first:14.29 16:49:47 INFO - PID 1651 | Values: 86.0 86.0 89.0 89.0 88.0 100.0 88.0 83.0 111.0 126.0 16:49:47 INFO - PID 1651 | 16:49:47 INFO - PID 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:163.90 Median:164.00 stddev:17.03 (10.4%) stddev-sans-first:17.92 16:49:47 INFO - PID 1651 | Values: 170.0 171.0 167.0 161.0 147.0 158.0 159.0 149.0 206.0 151.0 16:49:47 INFO - PID 1651 | 16:49:47 INFO - PID 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.50 Median:196.00 stddev:2.95 (1.5%) stddev-sans-first:3.13 16:49:47 INFO - PID 1651 | Values: 196.0 196.0 193.0 198.0 193.0 194.0 197.0 193.0 202.0 193.0 16:49:47 INFO - PID 1651 | 16:49:47 INFO - PID 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.60 Median:76.00 stddev:12.31 (16.2%) stddev-sans-first:12.56 16:49:47 INFO - PID 1651 | Values: 71.0 90.0 98.0 102.0 80.0 83.0 69.0 72.0 72.0 69.0 16:49:47 INFO - PID 1651 | -------- Summary: end -------- 16:49:47 INFO - PID 1651 | 16:49:48 INFO - TEST-INFO | 1651: exit 0 16:49:49 INFO - TEST-OK | kraken | took 63318ms 16:49:49 INFO - SUITE-END | took 1179s 16:49:49 INFO - Completed test suite (00:19:40) 16:49:49 INFO - javascript benchmark 16:49:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7786.147247845299, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2785.7632101271392, 3098.2409309719123, 3145.7259809777047, 3619.1133172372765, 3671.0, 5593.888122237553, 5853.326867410249, 6134.314131579348, 6211.689415529228, 6281.685915704208, 6150.169727086842, 6176.907346389797, 6197.473214776749, 6251.437370636641, 6264.874702505958, 4869.634777391692, 5275.208718692188, 5559.305086864142, 5627.493525582697, 5699.886002279952, 5429.185622156669, 5540.0, 5622.971885140589, 5631.1271752878365, 5703.17303990921, 4808.942032752789, 5374.677519348813, 5436.592255580847, 5546.611737178399, 5560.944390556083, 5274.868128296785, 5379.273798037254, 5453.809116680897, 5462.481064298885, 5471.945280547184, 5451.509364157245, 5461.754221060062, 5468.699221542814, 5507.008738427082, 5527.474889885454, 6038.667873266969, 6144.354842741537, 6183.752649893999, 6211.47202487789, 6232.0963460298, 6483.675816209171, 6509.707063182168, 6738.8315292117595, 6751.122354093985, 6751.223609284946, 6759.121314229119, 6840.623765692885, 6860.965695171542, 6868.278830722752, 6869.622170780605, 6274.592151510183, 6303.3066362700065, 6312.652804095773, 6312.873742525124, 6323.272823625342, 6724.0, 7019.92980070203, 7024.508284420143, 7041.0, 7044.682989265495, 6452.22573291208, 6560.934390656128, 6619.900701489481, 6668.833279167962, 6696.129503164558, 6828.453723702092, 6854.5201835872, 6861.210960739579, 6867.896981545281, 6880.8967865482055, 6690.966545167243, 6703.463722902156, 6744.730210791611, 6759.256481787, 6767.0, 6497.447716944018, 6536.673166341759, 6605.504587155982, 6659.0, 6675.833104172434, 5201.7919283229, 5211.0, 5212.869678258074, 5221.660592061504, 5283.788648454096, 4659.2312268476, 4664.346991421204, 4666.859994200179, 4668.5564871337165, 4709.481956984729, 5270.341207349082, 5279.630425870152, 6158.876822463526, 6180.134781130645, 6356.682165891687, 6807.183138023469, 6831.6242606657115, 6848.794536163923, 6859.416949559343, 6874.312568743086, 6388.840278993062, 6427.871442571122, 6429.164208652845, 6435.549511534241, 6446.129772480749, 5842.094475356326, 5957.821265362046, 6075.635461872302, 6087.786927457606, 6106.6336019838955, 6888.207856096613, 6991.440684745106, 7047.788566343018, 7104.680289386886, 7106.253843346581, 5389.595780316414, 6316.842078948063, 6369.777057802955, 6602.141721576164, 6840.452763778985, 6784.728610855511, 6839.692213850488, 7040.507164498641, 7125.928740712527, 7135.108111486064, 5715.8856822864145, 6757.290484499057, 6776.93223067763, 6809.761658341934, 6831.59010459374, 6590.94544872818, 6681.0, 6740.460763139035, 6768.120144381199, 6789.355011273921, 5602.971985140048, 6558.376954189344, 6665.533412661067, 6666.966665166643, 6674.032265321598, 6250.781097685907, 6616.584946494591, 6622.536422450382, 6626.171728533934, 6651.866962660622, 6119.388061194023, 6215.223097112861, 6273.372662733873, 6378.075179098912, 6393.680315984275, 6574.671266436563, 6593.736250549924, 6600.702968366531, 6609.0, 6622.139121914121, 6310.652914089769, 6361.982082866719, 6363.745450181941, 6367.87264254703, 6398.87202255943], "value": 6101.010584167078, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [769.8999130113084, 1107.7396811749238, 1111.8332250162473, 1276.8978481721463, 1283.0441321215696, 32800.50799238013, 36043.0, 36271.99999999996, 37042.814785926064, 37121.958292293915, 28973.855130724318, 36873.89378318648, 37341.43987840185, 37341.626583734156, 37408.625913740856, 5260.842174734745, 6035.396460353973, 7755.728549500768, 7941.047074351057, 7943.880841787378, 7294.307040831112, 8331.083580806137, 8513.95743021284, 8518.87221691675, 8548.145185481464, 8589.35579831512, 8597.699080532182, 8631.611577478998, 8640.0, 8662.003869554987, 8580.528070956096, 8589.184027517407, 8620.956895215546, 8638.524881131536, 8652.524111173883, 7775.727849525239, 8169.5098294102545, 8534.317254619615, 8536.44513106646, 8601.526916019617, 37178.628213717784, 37392.0, 37423.0, 37451.81274093612, 37506.249875002344, 3491.162121090945, 3492.1095120744303, 3525.6826885580163, 3528.700060494861, 3570.1431656402538, 26888.0, 26915.73084269152, 29645.0, 29717.70282297171, 29865.701342986726, 3109.720125188744, 3146.0, 3160.352127813793, 3190.5533225348236, 3190.6171259448784, 272747.0, 274883.0, 274898.0, 274994.0, 275647.999999998, 39325.21349572993, 40131.0, 47341.816454588225, 48578.0, 48654.513454864995, 49719.0, 49786.50213497819, 50003.749981249865, 50073.74815629639, 50134.0, 14303.928480357532, 14304.928475357556, 14426.422943082369, 14439.422423102958, 14448.711025779425, 11558.826617600742, 11614.76770464586, 11684.532618695326, 11686.415679216176, 11723.824142637868, 10362.0, 12459.875401245872, 12492.000639948965, 12525.749485010248, 12539.68333325006, 4790.592799612072, 4928.901421971541, 5196.428392876781, 5251.36983561975, 5252.133397989365, 965.35803690545, 965.584798536622, 989.4904124375952, 1000.8798944126606, 1024.5082360467018, 20230.595388092155, 23848.284551463483, 24154.637680434807, 24162.275131746075, 24189.00881354592, 1083.8807731149568, 1087.423665457309, 1093.639099097296, 1115.682030621269, 1115.7712668902855, 11763.588369395557, 12344.938275308567, 12428.689282768004, 12438.0, 12482.687932801753, 365218.0, 367172.0, 367580.0, 368554.0, 368830.0, 51486.0, 51642.22536661953, 51655.7417212919, 51764.22353664698, 51803.7409812956, 1481.126135580013, 1490.880729541634, 1519.1037288000132, 1523.2231561903288, 1529.9541013769606, 20607.381778546667, 22461.43846403853, 23798.048078077376, 23849.880750596138, 23875.76124238805, 37321.44017839789, 37326.813365933, 37333.6266637341, 37388.813055934545, 37405.43891841679, 319034.0, 323059.38584299816, 324918.0, 325655.0, 326160.0, 31946.68053319437, 32017.6798232024, 32678.673213267564, 33203.50194747032, 33423.66576334303, 42372.94067648333, 42590.0, 43057.354139687304, 43362.56637433585, 43447.0, 27410.314742131606, 28993.0, 29155.708442916148, 29582.260443489085, 29906.551401728557, 24667.753322466546, 25730.485390292462, 28912.0, 29795.80816767305, 29799.70200298056, 35109.82445087758, 35758.0, 35944.64055359413, 35979.28041439209, 36172.0, 22971.0, 27173.0, 30010.499475026598, 30161.0, 30671.846640766653, 18026.90986545059, 23253.18613848507, 23541.882290588437, 23620.291391258288, 23736.050557977487], "value": 17094.497320398037, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [420.2540490629133, 433.3067092651756, 437.3704339565697, 446.069944166413, 461.73450266096984, 20445.48886277844, 22047.559048819036, 22746.54506909865, 22949.541009179808, 22959.19642812502, 21782.564348713015, 22462.775372246273, 22500.54998900021, 22728.772712272912, 23417.180398686007, 5973.492253158487, 6058.121572372003, 6244.843878903019, 6260.718267677966, 6279.811605651815, 6022.307434645006, 6134.723937422805, 6134.938650613503, 6159.38406159385, 6174.5060395168275, 5242.475752424746, 5891.204687367193, 6071.332153463116, 6091.725872335734, 6149.631022138686, 4844.418669759616, 5473.768402109533, 6032.758689652409, 6082.756689732428, 6095.817125486243, 4451.866444006685, 5397.4062853086125, 5417.83746487606, 5451.672899626035, 5539.446055394468, 21370.46573835651, 22581.0, 28051.15846524587, 28098.297542561395, 28308.71691283102, 1891.366392258595, 2230.721159855018, 2231.4421394651176, 2245.8989345479495, 2259.8757068361233, 17501.824981750146, 17826.821731782646, 17870.374536891148, 18397.3560925367, 18565.62868742631, 2083.7707852136255, 2103.968440473394, 2151.4406254373976, 2152.7847215278503, 2156.8598041127434, 127183.0, 151026.0, 152077.0, 152400.2379988104, 159635.0, 12571.622851314476, 13045.217286962812, 13099.279539625222, 13169.73660526784, 13230.20618762858, 16362.83637163637, 16642.334306627843, 16658.6668266634, 16662.250198741087, 16665.666686666442, 8665.653373864974, 8892.8666070009, 8958.238549723215, 8970.730878073668, 9089.0, 8275.793105172419, 8428.704995325133, 8431.831363372698, 8562.700305489278, 8622.180892815173, 8011.439199255996, 8122.431429799975, 8153.5923203839575, 8164.856530235902, 8168.509889406655, 2242.2488466363625, 2254.0983606557506, 2254.526549424639, 2264.705588273514, 2425.4057755849576, 807.4791759315326, 925.9398139120935, 940.9717708468756, 941.0154836214713, 975.331897650112, 16247.837521624633, 20170.69743953842, 20311.0, 20794.896025520076, 20891.37325880226, 966.4636126949611, 979.637534112383, 993.6174572789528, 1000.7698229407277, 1007.385494848142, 8741.562921854009, 9349.158575728217, 10899.455027248607, 10947.945260273647, 10963.671089867157, 720609.0, 727968.0, 735954.0, 737617.0, 738455.0, 117897.41051294688, 147134.52865471423, 148806.0, 152807.23596381946, 154101.0, 1197.706561892342, 1223.5044806853239, 1253.724180680249, 1254.9121561490608, 1257.0, 17765.822341776417, 18256.99586522754, 20518.97405129708, 21487.033083511593, 21619.81091040008, 56058.439415605324, 56940.576485586535, 61649.69175154275, 62429.127126186286, 63081.0, 77888.61055694685, 78527.0, 78921.60539197267, 80929.59535202286, 81276.0, 7370.520916140434, 8695.695650652196, 8910.821783564423, 8994.370394072352, 9017.143371379709, 9851.605935762776, 13872.0, 13881.37533811001, 14229.928850355682, 14254.216018119103, 11552.306861588331, 11573.942130289295, 11810.173287869766, 11982.64052078439, 12317.199382040139, 11125.221234513507, 11166.888331116585, 11168.162387821108, 11372.31766094037, 11507.194496385508, 11871.109666774853, 12048.277103373826, 12139.453724582592, 12178.45196966121, 12248.877511224773, 8054.476459030145, 8588.26999705007, 8588.840506531787, 8849.4247873888, 8849.601767920587, 11099.722506937069, 11664.008559272557, 11692.0061794745, 11943.283402995849, 12053.0], "value": 10507.401381211728, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1295.3976400973545, 1451.4266864588487, 1465.9706805863882, 1467.6844478437133, 1489.493572185457, 9382.530873456326, 10103.595856165754, 11219.102471802256, 11436.942815285933, 12208.511659533619, 9820.607175712963, 11583.884161158387, 11797.646070617895, 11810.996065334435, 11850.407479626027, 2178.9237376691817, 2484.925452236436, 2520.6345079963394, 2552.310876063462, 2561.2060261318957, 2467.9876600617063, 2471.122751423246, 2516.207394670682, 2521.8612976286295, 2523.949521009588, 2469.826832254683, 2486.365976675945, 2492.675952126221, 2513.28371414147, 2515.0317127905796, 2461.2616215135477, 2465.593177125772, 2486.6643003194517, 2487.61441976493, 2517.748225177486, 2422.733499315074, 2507.7241503434607, 2524.5203411351786, 2528.8229823912334, 2533.138732830828, 11978.640640780703, 12066.034717222601, 12080.0, 12102.697432564168, 12131.696707582292, 6540.803775886731, 6750.93249067508, 6755.189377274758, 6759.0, 6767.849465590862, 4098.8360465581345, 4347.760903134888, 4419.381286619876, 4434.4678638563255, 4442.400275962736, 1215.9574414895437, 1239.7892358299111, 1277.1060257819563, 1293.780057390246, 1300.024981264052, 15177.950976618236, 15637.0, 16757.49727508177, 16870.325186992504, 16930.915345423193, 5617.9438205618235, 5694.9430505695245, 5891.882162356729, 5934.287885453773, 5945.554083443759, 12464.626061218178, 12482.0, 12501.624951251477, 12521.499140034297, 12551.3096779678, 6650.567713098633, 6667.233268174125, 6694.0, 6703.966480167665, 6717.529772915849, 6033.215681961317, 6192.133101365813, 6200.038993955944, 6235.96882015587, 6237.313895471494, 4435.3125265583885, 4527.456705195397, 4579.244424669892, 4623.167829790603, 4643.628509719214, 1290.9225446473242, 1308.7305313845554, 1310.0829419406455, 1325.8806707396382, 1334.2594859852875, 764.7323436797077, 779.283059585183, 794.4835856693196, 797.7766225456766, 817.8323443694028, 5374.435684253175, 6258.186435763321, 6410.743570257231, 6420.839479012969, 6513.0, 561.9943800561942, 564.5173376762891, 575.2550447170923, 582.670791003082, 594.6283572767021, 4206.305959516707, 4312.288472402019, 4381.38660587518, 4445.51099379068, 4483.3723278741, 14849.40602375893, 15316.081035137928, 15375.077495350315, 15485.225738713245, 15504.689906202038, 11869.821952670716, 12348.629541113782, 13436.664083397798, 14441.78337324941, 15038.54884353471, 10796.352218866894, 11064.889351106385, 11073.778524429466, 11092.611758588411, 11106.222564420414, 5411.02601531728, 5473.452654734495, 7200.135983681782, 7239.855202895807, 7287.635618219174, 13064.869351306745, 13067.738645227233, 13100.672483187996, 13334.933325333312, 13539.390727417092, 13893.235872027135, 13898.513552026036, 13900.860991389956, 13924.443022278996, 13984.0, 9464.958854526134, 9506.0, 9679.419234845931, 9680.85478717834, 9702.75743106428, 10716.785664286826, 10775.784484310427, 10850.837237441287, 10852.565897364017, 10881.619143329945, 9009.414388064755, 9192.402493837879, 9241.445513268956, 9312.860307095534, 9318.974912759726, 9061.682841100794, 9082.273418126666, 9163.450192988175, 9165.77085572866, 9195.448273103635, 8157.021157461143, 8261.628226729692, 8347.499150051017, 8408.327333813158, 8422.578871056545, 6034.366391528826, 6171.783987560414, 6241.563090583615, 6241.656708881055, 6281.246250449975, 6093.238345206849, 6179.752809887555, 6286.8428289291305, 6339.636978049742, 6367.140436041075], "value": 5384.604039267205, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.61208954776583, 389.3245219544091, 411.2535747618073, 412.128348542832, 414.0683462210026, 6321.494280457564, 6387.552871299011, 6493.870122597557, 6529.738810447582, 6537.673116344182, 5540.0, 5955.940440595593, 6195.6902154892305, 6332.132021053789, 6403.231612206541, 3782.754120982141, 4313.051128751669, 4576.816927322903, 4758.0, 4812.422509298889, 3811.637894400041, 4302.48370195577, 4780.952190478103, 4804.87987800306, 4839.693282813656, 3642.3990041643096, 4729.432468103815, 4748.35897153885, 4779.251930140166, 4811.615070794345, 4192.685548583838, 4756.952430475685, 4778.682339065551, 4804.639652026112, 4822.156122678517, 3231.9353612927844, 4103.4460347853255, 4200.852970146061, 4256.829017201218, 4280.015596412812, 5349.893002139936, 5902.586818922677, 6195.504359651262, 6200.937990620081, 6205.193324867739, 5727.195933281003, 5744.22452968848, 5746.626469279525, 5751.884962300773, 5759.0, 6507.8698426031215, 6721.361470660279, 6777.728890844361, 6815.693293801791, 6821.897671534931, 4870.025994801054, 4872.3422337984275, 4878.853634390938, 4891.951080489185, 4906.8773280668265, 6698.631575263358, 6781.694823732993, 6872.553284036521, 7118.9644051779405, 7255.746048888316, 4958.652894297436, 4963.305137280783, 4972.925406118911, 4976.676516026447, 4994.700317980933, 5080.618953578496, 5089.440161582223, 5090.338256026767, 5103.311053007871, 5129.8717532061255, 5462.6995515247045, 5631.436856314336, 5679.148127780866, 5685.687287199244, 5740.311162660508, 6712.858814001534, 6846.7945961621235, 6849.212340580897, 6851.212110607344, 6895.689693963784, 1486.9256537173098, 1547.7214101461846, 1706.2748331958867, 1711.3345051646102, 1725.240894006633, 1161.1639619473965, 1187.281694574787, 1187.7980743273665, 1192.070185255502, 1202.8135638975973, 5519.475649813261, 6464.062710906892, 6478.190226221723, 6511.902321465182, 6512.185976752906, 6143.56995010345, 6277.686115694289, 6504.024396340492, 6521.11964884744, 6686.331366863372, 5667.744951477193, 6390.7443702251385, 6391.58454700443, 6444.77549265638, 6515.446187074057, 3650.4889315495852, 3674.577423596267, 3694.3560115748774, 3701.7408781385584, 3704.944425833615, 6730.461563074942, 6742.696578653972, 6911.0, 7254.600996945219, 7321.3410793027815, 5751.85620359486, 5886.323072846678, 5903.35063143054, 7185.748498802517, 7269.16698303593, 6958.111469645015, 7264.256578421247, 7298.635068246673, 7308.232635573188, 7350.669219885118, 5962.2249107615735, 6851.486138539729, 7031.910053941648, 7063.611501367474, 7079.787606371817, 6477.481801455967, 6482.902756458751, 6791.762288319885, 6872.793816185523, 6944.930550694428, 6191.349908259748, 7286.344229019413, 7322.340989310987, 7338.926610734038, 7355.963220184079, 4446.710963787344, 4908.9754551227015, 4963.578095861748, 4968.27959945813, 4987.0, 6984.498332858459, 7000.124984376953, 7001.184354526897, 7022.6488675565, 7057.6471176439945, 5663.2859231026605, 6251.749930002749, 6765.391114799691, 6768.357006084414, 6781.660916954034, 5604.3835178131185, 6183.907241391293, 6691.397774200345, 6769.221539523018, 6787.966060169668, 5581.49766521015, 6268.373162683695, 6634.369734875179, 6642.4021838034805, 6644.568103073478, 5092.516210959953, 5916.645001299936, 6254.718537665725, 6673.9633466773475, 6783.59298442095, 5816.662167701368, 6058.27300723916, 6374.171357723466, 6513.902291465726, 6608.257573107299], "value": 4990.011383406759, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [735.6468894930433, 780.3016300411135, 837.363603661218, 839.0309192882223, 842.3429724814642, 14182.57452276432, 15247.847521524782, 15963.840361596394, 16056.598585035365, 16291.918540407281, 15055.021423607457, 15086.924565377185, 15266.770998435033, 16910.74633880493, 17743.73384399235, 956.46437994723, 973.6884197056945, 978.9510524473783, 980.0248752491245, 981.0434826044585, 955.1642312976146, 979.7697541077841, 991.1179050644931, 991.4051569058544, 992.0525897767615, 1028.3521381529663, 1044.8223801953648, 1053.3837704942619, 1057.5611121384616, 1061.1457776489922, 860.2257967828941, 986.57577241786, 1048.0462778871229, 1049.01916707878, 1049.0191670787838, 758.2569082299323, 773.0414286285022, 777.1956025513623, 778.1906816910406, 778.404520541786, 16077.839221607752, 16401.42595009169, 16523.92594481355, 16709.66580668392, 16879.24043418037, 14598.635034124125, 14683.034096606172, 14818.555443336609, 14858.851411485855, 15518.534443966699, 13212.735745285137, 13215.273159976197, 13294.667633309246, 13369.598912032654, 13383.598492045157, 3348.7990720556845, 3392.8133952632597, 3397.473391624302, 3403.3193361327835, 3405.3359594879007, 86800.0, 111612.0, 113503.0, 113698.86301136966, 113881.0, 11284.317132502672, 11349.31904085741, 11395.322099306657, 11428.485718142703, 11475.540978360772, 20828.47928801792, 24351.26946191617, 24635.137770178317, 24695.135670251457, 24727.87636061808, 7963.203679631991, 8029.197080291924, 8037.156098609562, 8099.959500202579, 8234.958825205835, 4183.184279065553, 4206.915861682749, 4230.906424636338, 4274.829006839754, 4296.849610263688, 4103.261412945639, 4162.438070860395, 4188.916221675549, 4207.548395803443, 4214.831406743696, 907.1019690506351, 941.7692665297046, 947.0482165423705, 952.2049089010616, 954.2985905359556, 608.5253502268238, 610.9236345456818, 611.8623309755269, 641.8491654461197, 643.5688088980395, 8978.685745998859, 8987.220145074116, 10153.289269751194, 10298.092249205403, 10449.111825494918, 598.6916737879995, 606.6390497653889, 618.3352895637208, 624.4036944717784, 631.6525910749069, 4636.721796692142, 4772.308015337757, 4810.302506136591, 4810.494898035656, 4828.95171048292, 105751.41372879413, 107067.46466267618, 107343.0, 108991.45504272587, 109690.0, 77150.84273735898, 97761.51119244358, 98613.0, 99795.50102249443, 99934.0, 75808.4514929095, 76121.61939190268, 76432.73888980538, 76681.6165919178, 77826.44347113026, 9503.76240593963, 9631.181349585364, 9688.321817472992, 9706.6117355305, 9709.417434953926, 42116.78941605272, 42531.7873410631, 42718.0, 43286.78356608197, 43878.12243755171, 50276.0, 50389.0, 50865.49134508608, 51319.23021154611, 51373.0, 11822.34977076269, 11886.108541859569, 11915.344656044, 11932.28406295625, 12297.508099675913, 12537.059720520814, 12540.623781286577, 12547.498100075894, 12626.684332891384, 13128.60614181576, 11700.648980530597, 11704.824427633423, 11953.22304050234, 12285.324307163191, 12332.445039973401, 11010.119190464904, 11709.297442153498, 12143.696407589527, 12258.938705306773, 12325.075619328765, 11619.5352185915, 11862.822057669315, 12037.217580857216, 12166.904978551973, 12244.081693872817, 8383.790405239917, 8680.305575554066, 8909.331800114887, 8975.84459375459, 9075.773105672148, 9431.386959847589, 9482.241420686467, 9492.762680933032, 9502.61989520439, 9517.666881659108], "value": 7566.5620772677685, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1662.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 101.0, 158.0, 181.0, 184.0, 122.0, 145.0, 148.0, 120.0, 122.0], "value": 137.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 114.0, 116.0, 157.0, 116.0, 116.0, 117.0, 115.0, 165.0, 142.0], "value": 134.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 222.0, 220.0, 246.0, 314.0, 233.0, 207.0, 217.0, 209.0, 208.0], "value": 228.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 64.0, 51.0, 109.0, 88.0, 93.0, 94.0, 87.0, 79.0, 57.0], "value": 80.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 75.0, 72.0, 73.0, 106.0, 60.0, 105.0, 105.0, 59.0, 69.0], "value": 81.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 90.0, 107.0, 90.0, 92.0, 91.0, 90.0, 134.0, 89.0, 89.0], "value": 99.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 100.0, 103.0, 132.0, 161.0, 99.0, 165.0, 161.0, 101.0, 108.0], "value": 122.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 102.0, 101.0, 103.0, 106.0, 165.0, 145.0, 102.0, 100.0, 101.0], "value": 112.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 60.0, 58.0, 83.0, 83.0, 83.0, 82.0, 82.0, 59.0], "value": 69.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 64.0, 68.0, 63.0, 60.0, 60.0, 60.0, 59.0, 60.0], "value": 61.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 89.0, 89.0, 88.0, 100.0, 88.0, 83.0, 111.0, 126.0], "value": 94.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 171.0, 167.0, 161.0, 147.0, 158.0, 159.0, 149.0, 206.0, 151.0], "value": 163.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 196.0, 193.0, 198.0, 193.0, 194.0, 197.0, 193.0, 202.0, 193.0], "value": 195.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 90.0, 98.0, 102.0, 80.0, 83.0, 69.0, 72.0, 72.0, 69.0], "value": 80.6, "unit": "score"}]}]} 16:49:49 INFO - Return code: 0 16:49:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:49:49 ERROR - # TBPL SUCCESS # 16:49:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:49:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:49:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:49:49 INFO - 2017-04-06 16:49:49.294 screenresolution[1656:10596] starting screenresolution argv=screenresolution get 16:49:49 INFO - 2017-04-06 16:49:49.297 screenresolution[1656:10596] Display 0: 1600x1200x32@60 16:49:49 INFO - 2017-04-06 16:49:49.304 screenresolution[1657:10599] starting screenresolution argv=screenresolution list 16:49:49 INFO - Available Modes on Display 0 16:49:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:49:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:49:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:49:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:49:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:49:49 INFO - Intel Iris: 16:49:49 INFO - Chipset Model: Intel Iris 16:49:49 INFO - Type: GPU 16:49:49 INFO - Bus: Built-In 16:49:49 INFO - VRAM (Dynamic, Max): 1536 MB 16:49:49 INFO - Vendor: Intel (0x8086) 16:49:49 INFO - Device ID: 0x0a2e 16:49:49 INFO - Revision ID: 0x0009 16:49:49 INFO - Displays: 16:49:49 INFO - Display: 16:49:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:49:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:49:49 INFO - Display Serial Number: 1600x1200 60 16:49:49 INFO - Main Display: Yes 16:49:49 INFO - Mirror: Off 16:49:49 INFO - Online: Yes 16:49:49 INFO - Rotation: Supported 16:49:49 INFO - Return code: 0 16:49:49 INFO - Running post-action listener: _resource_record_post_action 16:49:49 INFO - [mozharness: 2017-04-06 23:49:49.546267Z] Finished run-tests step (success) 16:49:49 INFO - Running post-run listener: _resource_record_post_run 16:49:49 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 40720896; Write bytes: 524412928; Read time: 404; Write time: 1941 16:49:49 INFO - TinderboxPrint: CPU usage
54.3% 16:49:49 INFO - TinderboxPrint: I/O read bytes / time
40,720,896 / 404 16:49:49 INFO - TinderboxPrint: I/O write bytes / time
524,412,928 / 1,941 16:49:49 INFO - TinderboxPrint: CPU idle
2,195.5 (45.7%) 16:49:49 INFO - TinderboxPrint: CPU system
963.3 (20.1%) 16:49:49 INFO - TinderboxPrint: CPU user
1,642.8 (34.2%) 16:49:49 INFO - TinderboxPrint: Swap in / out
247,164,928 / 0 16:49:49 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 193036800; Write bytes: 194762752; Read time: 14884; Write time: 404 16:49:49 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 39651840; Write bytes: 312807424; Read time: 386; Write time: 1487 16:49:50 INFO - Running post-run listener: _upload_blobber_files 16:49:50 INFO - Blob upload gear active. 16:49:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:49:50 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 16:49:50 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'] 16:49:50 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 16:49:50 INFO - (blobuploader) - INFO - Open directory for files ... 16:49:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:49:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:49:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:49:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:49:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:49:51 INFO - (blobuploader) - INFO - Done attempting. 16:49:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:49:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:49:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:49:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:49:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:49:52 INFO - (blobuploader) - INFO - Done attempting. 16:49:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:49:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:49:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:49:53 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:49:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:49:53 INFO - (blobuploader) - INFO - Done attempting. 16:49:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:49:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:49:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:49:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:49:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:49:55 INFO - (blobuploader) - INFO - Done attempting. 16:49:55 INFO - (blobuploader) - INFO - Iteration through files over. 16:49:55 INFO - Return code: 0 16:49:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:49:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:49:55 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/373518d19ea09629f31c4ff445ac3f446fa52049c5f3250d305fb5cb5eec8c04c5afd06949a3f3b7a4dc6cfe7eb851357eea5e4b0c4a3126254f91c7e96cb3f8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83e26a142316368a6ecd6a3909dc017a05905876bda356e23986f2c2d676ffc4f72e513d5e3a45f51affc7aa46e8c544633e80baecb7c75dad021563d6d87ba7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/11eea0831b307c6abff7794ddbc43bc59e3b8f12b763dceb9965668ea1dc9ac65e65143e396bf013b8dad2886de1d250ff4a9235e10544cdd94b3ff7d3f1d39c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e78b17a83762c79a09e824bb38ea667fca7238edfe375e07d8c0390762ab5796df805344ebb42edf857d90b3e47708f9ed686714580a15daff38fea355c5739"} 16:49:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:49:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:49:55 INFO - Contents: 16:49:55 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/373518d19ea09629f31c4ff445ac3f446fa52049c5f3250d305fb5cb5eec8c04c5afd06949a3f3b7a4dc6cfe7eb851357eea5e4b0c4a3126254f91c7e96cb3f8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83e26a142316368a6ecd6a3909dc017a05905876bda356e23986f2c2d676ffc4f72e513d5e3a45f51affc7aa46e8c544633e80baecb7c75dad021563d6d87ba7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/11eea0831b307c6abff7794ddbc43bc59e3b8f12b763dceb9965668ea1dc9ac65e65143e396bf013b8dad2886de1d250ff4a9235e10544cdd94b3ff7d3f1d39c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e78b17a83762c79a09e824bb38ea667fca7238edfe375e07d8c0390762ab5796df805344ebb42edf857d90b3e47708f9ed686714580a15daff38fea355c5739"} 16:49:55 INFO - Running post-run listener: copy_logs_to_upload_dir 16:49:55 INFO - Copying logs to upload dir... 16:49:55 INFO - mkdir: /builds/slave/test/build/upload/logs 16:49:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.322508 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-04-06 16:49:55.703425) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 16:49:55.707105) ========= 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.rEdkF6diQK/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.k7jgUoQ7Ix/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/373518d19ea09629f31c4ff445ac3f446fa52049c5f3250d305fb5cb5eec8c04c5afd06949a3f3b7a4dc6cfe7eb851357eea5e4b0c4a3126254f91c7e96cb3f8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83e26a142316368a6ecd6a3909dc017a05905876bda356e23986f2c2d676ffc4f72e513d5e3a45f51affc7aa46e8c544633e80baecb7c75dad021563d6d87ba7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/11eea0831b307c6abff7794ddbc43bc59e3b8f12b763dceb9965668ea1dc9ac65e65143e396bf013b8dad2886de1d250ff4a9235e10544cdd94b3ff7d3f1d39c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e78b17a83762c79a09e824bb38ea667fca7238edfe375e07d8c0390762ab5796df805344ebb42edf857d90b3e47708f9ed686714580a15daff38fea355c5739"} build_url:https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011277 build_url: 'https://queue.taskcluster.net/v1/task/SGKsn_pDSUOM6DisCpQwDg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/373518d19ea09629f31c4ff445ac3f446fa52049c5f3250d305fb5cb5eec8c04c5afd06949a3f3b7a4dc6cfe7eb851357eea5e4b0c4a3126254f91c7e96cb3f8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83e26a142316368a6ecd6a3909dc017a05905876bda356e23986f2c2d676ffc4f72e513d5e3a45f51affc7aa46e8c544633e80baecb7c75dad021563d6d87ba7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/11eea0831b307c6abff7794ddbc43bc59e3b8f12b763dceb9965668ea1dc9ac65e65143e396bf013b8dad2886de1d250ff4a9235e10544cdd94b3ff7d3f1d39c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e78b17a83762c79a09e824bb38ea667fca7238edfe375e07d8c0390762ab5796df805344ebb42edf857d90b3e47708f9ed686714580a15daff38fea355c5739"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 16:49:55.744944) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 16:49:55.745225) ========= 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.rEdkF6diQK/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.k7jgUoQ7Ix/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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.005049 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 16:49:55.794626) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 16:49:55.795361) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 16:49:55.795998) ========= ========= Total master_lag: 0.19 =========