builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0382 starttime: 1471917390.15 results: success (0) buildid: 20160822165414 builduid: abc4f9895ecd48dd8c121ec6a94b3232 revision: 65cc7b37cf3f1ced49f6a3d45ab17c82eb18b3c9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 18:56:30.153159) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 18:56:30.153535) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 18:56:30.153823) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 18:56:30.179000) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 18:56:30.179320) ========= 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.BucL5U3T8g/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.Jez0eL2Hgh/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-22 18:56:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.5M=0.001s 2016-08-22 18:56:30 (18.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.116316 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 18:56:30.318538) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 18:56:30.318871) ========= 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.BucL5U3T8g/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.Jez0eL2Hgh/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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.025643 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 18:56:30.367491) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-22 18:56:30.367858) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 65cc7b37cf3f1ced49f6a3d45ab17c82eb18b3c9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 65cc7b37cf3f1ced49f6a3d45ab17c82eb18b3c9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.BucL5U3T8g/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.Jez0eL2Hgh/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-22 18:56:30,450 truncating revision to first 12 chars 2016-08-22 18:56:30,450 Setting DEBUG logging. 2016-08-22 18:56:30,450 attempt 1/10 2016-08-22 18:56:30,451 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/65cc7b37cf3f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-22 18:56:31,938 unpacking tar archive at: autoland-65cc7b37cf3f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.769051 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-22 18:56:32.159944) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 18:56:32.160262) ========= script_repo_revision: 65cc7b37cf3f1ced49f6a3d45ab17c82eb18b3c9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 18:56:32.160639) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 18:56:32.160928) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 18:56:32.180122) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-08-22 18:56:32.279941) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.BucL5U3T8g/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.Jez0eL2Hgh/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 18:56:32 INFO - MultiFileLogger online at 20160822 18:56:32 in /builds/slave/test 18:56:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 18:56:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:56:32 INFO - {'append_to_log': False, 18:56:32 INFO - 'base_work_dir': '/builds/slave/test', 18:56:32 INFO - 'blob_upload_branch': 'Autoland', 18:56:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:56:32 INFO - 'branch': 'Autoland', 18:56:32 INFO - 'buildbot_json_path': 'buildprops.json', 18:56:32 INFO - 'config_files': ('talos/mac_config.py',), 18:56:32 INFO - 'default_actions': ('clobber', 18:56:32 INFO - 'read-buildbot-config', 18:56:32 INFO - 'download-and-extract', 18:56:32 INFO - 'populate-webroot', 18:56:32 INFO - 'create-virtualenv', 18:56:32 INFO - 'install', 18:56:32 INFO - 'run-tests'), 18:56:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:56:32 INFO - 'download_minidump_stackwalk': True, 18:56:32 INFO - 'download_symbols': 'ondemand', 18:56:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:56:32 INFO - 'tooltool.py': '/tools/tooltool.py', 18:56:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:56:32 INFO - '/tools/misc-python/virtualenv.py')}, 18:56:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:56:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:56:32 INFO - 'installer_path': 'installer.exe', 18:56:32 INFO - 'log_level': 'info', 18:56:32 INFO - 'log_name': 'talos', 18:56:32 INFO - 'log_to_console': True, 18:56:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:56:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:56:32 INFO - 'opt_config_files': (), 18:56:32 INFO - 'pip_index': False, 18:56:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:56:32 INFO - 'cmd': ('bash', 18:56:32 INFO - '-c', 18:56:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:56:32 INFO - 'enabled': True, 18:56:32 INFO - 'halt_on_failure': False, 18:56:32 INFO - 'name': 'check_screen_resolution'},), 18:56:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:56:32 INFO - 'cmd': ('bash', 18:56:32 INFO - '-c', 18:56:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:56:32 INFO - 'enabled': True, 18:56:32 INFO - 'halt_on_failure': False, 18:56:32 INFO - 'name': 'check_screen_resolution'},), 18:56:32 INFO - 'run_cmd_checks_enabled': True, 18:56:32 INFO - 'sps_profile': False, 18:56:32 INFO - 'sps_profile_interval': 0, 18:56:32 INFO - 'suite': 'dromaeojs-e10s', 18:56:32 INFO - 'system_bits': '32', 18:56:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:56:32 INFO - 'title': 't-yosemite-r7-0382', 18:56:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:56:32 INFO - 'use_talos_json': True, 18:56:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:56:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:56:32 INFO - 'work_dir': 'build'} 18:56:32 INFO - [mozharness: 2016-08-23 01:56:32.492137Z] Running clobber step. 18:56:32 INFO - Running pre-action listener: _resource_record_pre_action 18:56:32 INFO - Running main action method: clobber 18:56:32 INFO - rmtree: /builds/slave/test/build 18:56:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:56:34 INFO - Running post-action listener: _resource_record_post_action 18:56:34 INFO - [mozharness: 2016-08-23 01:56:34.541303Z] Finished clobber step (success) 18:56:34 INFO - [mozharness: 2016-08-23 01:56:34.541621Z] Running read-buildbot-config step. 18:56:34 INFO - Running pre-action listener: _resource_record_pre_action 18:56:34 INFO - Running main action method: read_buildbot_config 18:56:34 INFO - Using buildbot properties: 18:56:34 INFO - { 18:56:34 INFO - "project": "", 18:56:34 INFO - "product": "firefox", 18:56:34 INFO - "script_repo_revision": "production", 18:56:34 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 18:56:34 INFO - "repository": "", 18:56:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 18:56:34 INFO - "buildid": "20160822165414", 18:56:34 INFO - "pgo_build": "False", 18:56:34 INFO - "basedir": "/builds/slave/test", 18:56:34 INFO - "buildnumber": 151, 18:56:34 INFO - "slavename": "t-yosemite-r7-0382", 18:56:34 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 18:56:34 INFO - "platform": "yosemite_r7", 18:56:34 INFO - "scheduler": "tests-autoland-macosx64-talos-7-3600", 18:56:34 INFO - "branch": "autoland", 18:56:34 INFO - "repo_path": "integration/autoland", 18:56:34 INFO - "revision": "65cc7b37cf3f1ced49f6a3d45ab17c82eb18b3c9", 18:56:34 INFO - "stage_platform": "macosx64", 18:56:34 INFO - "builduid": "abc4f9895ecd48dd8c121ec6a94b3232", 18:56:34 INFO - "slavebuilddir": "test" 18:56:34 INFO - } 18:56:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/FdtP9adVRQqQofju-mdcqg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 18:56:34 INFO - Running post-action listener: _resource_record_post_action 18:56:34 INFO - [mozharness: 2016-08-23 01:56:34.547240Z] Finished read-buildbot-config step (success) 18:56:34 INFO - [mozharness: 2016-08-23 01:56:34.547439Z] Running download-and-extract step. 18:56:34 INFO - Running pre-action listener: _resource_record_pre_action 18:56:34 INFO - Running main action method: download_and_extract 18:56:34 INFO - mkdir: /builds/slave/test/build/tests 18:56:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:34 INFO - trying https://queue.taskcluster.net/v1/task/FdtP9adVRQqQofju-mdcqg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 18:56:34 INFO - Downloading https://queue.taskcluster.net/v1/task/FdtP9adVRQqQofju-mdcqg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 18:56:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FdtP9adVRQqQofju-mdcqg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 18:56:35 INFO - Downloaded 1199 bytes. 18:56:35 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 18:56:35 INFO - Using the following test package requirements: 18:56:35 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:56:35 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:56:35 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 18:56:35 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:56:35 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 18:56:35 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:56:35 INFO - u'jsshell-mac.zip'], 18:56:35 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:56:35 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 18:56:35 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:56:35 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:56:35 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 18:56:35 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:56:35 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 18:56:35 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:56:35 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 18:56:35 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:56:35 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 18:56:35 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:56: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')]} 18:56:35 INFO - trying https://queue.taskcluster.net/v1/task/FdtP9adVRQqQofju-mdcqg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:56:35 INFO - Downloading https://queue.taskcluster.net/v1/task/FdtP9adVRQqQofju-mdcqg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:56:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FdtP9adVRQqQofju-mdcqg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:56:36 INFO - Downloaded 16353649 bytes. 18:56:36 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 18:56:43 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:56:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:43 INFO - trying https://queue.taskcluster.net/v1/task/FdtP9adVRQqQofju-mdcqg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:56:43 INFO - Downloading https://queue.taskcluster.net/v1/task/FdtP9adVRQqQofju-mdcqg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:56:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FdtP9adVRQqQofju-mdcqg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:56:44 INFO - Downloaded 16353649 bytes. 18:56:44 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 18:56:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:50 INFO - trying https://queue.taskcluster.net/v1/task/FdtP9adVRQqQofju-mdcqg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 18:56:50 INFO - Downloading https://queue.taskcluster.net/v1/task/FdtP9adVRQqQofju-mdcqg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 18:56:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FdtP9adVRQqQofju-mdcqg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:56:51 INFO - Downloaded 14413981 bytes. 18:56:51 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 18:56:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:52 INFO - trying https://queue.taskcluster.net/v1/task/FdtP9adVRQqQofju-mdcqg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:56:52 INFO - Downloading https://queue.taskcluster.net/v1/task/FdtP9adVRQqQofju-mdcqg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 18:56:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FdtP9adVRQqQofju-mdcqg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 18:56:54 INFO - Downloaded 111447202 bytes. 18:56:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FdtP9adVRQqQofju-mdcqg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:56:54 INFO - mkdir: /builds/slave/test/properties 18:56:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:56:54 INFO - Writing to file /builds/slave/test/properties/build_url 18:56:54 INFO - Contents: 18:56:54 INFO - build_url:https://queue.taskcluster.net/v1/task/FdtP9adVRQqQofju-mdcqg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:56:55 INFO - Running post-action listener: _resource_record_post_action 18:56:55 INFO - Running post-action listener: set_extra_try_arguments 18:56:55 INFO - [mozharness: 2016-08-23 01:56:55.639100Z] Finished download-and-extract step (success) 18:56:55 INFO - [mozharness: 2016-08-23 01:56:55.639217Z] Running populate-webroot step. 18:56:55 INFO - Running pre-action listener: _resource_record_pre_action 18:56:55 INFO - Running main action method: populate_webroot 18:56:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:56:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:56:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:56:55 INFO - '--tppagecycles', 18:56:55 INFO - '7'], 18:56:55 INFO - 'tests': ['tsvgm']}}, 18:56:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:56:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:56:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:56:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:56:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:56:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:56:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:56:55 INFO - 'talos_options': ['--disable-e10s'], 18:56:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:56:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:56:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:56:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:56:55 INFO - 'talos_options': ['--disable-e10s'], 18:56:55 INFO - 'tests': ['damp', 'tps']}, 18:56:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:56:55 INFO - 'tests': ['damp', 'tps']}, 18:56:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:56:55 INFO - 'tests': ['dromaeo_dom']}, 18:56:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:56:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:56:55 INFO - 'tests': ['basic_compositor_video']}, 18:56:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:56:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:56:55 INFO - 'tests': ['a11yr', 18:56:55 INFO - 'ts_paint', 18:56:55 INFO - 'tpaint', 18:56:55 INFO - 'sessionrestore', 18:56:55 INFO - 'sessionrestore_no_auto_restore', 18:56:55 INFO - 'tabpaint']}, 18:56:55 INFO - 'other-e10s': {'tests': ['a11yr', 18:56:55 INFO - 'ts_paint', 18:56:55 INFO - 'tpaint', 18:56:55 INFO - 'sessionrestore', 18:56:55 INFO - 'sessionrestore_no_auto_restore', 18:56:55 INFO - 'tabpaint']}, 18:56:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:56:55 INFO - 'ts_paint', 18:56:55 INFO - 'tpaint', 18:56:55 INFO - 'sessionrestore', 18:56:55 INFO - 'sessionrestore_no_auto_restore', 18:56:55 INFO - 'tabpaint']}, 18:56:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:56:55 INFO - 'ts_paint', 18:56:55 INFO - 'tpaint', 18:56:55 INFO - 'sessionrestore', 18:56:55 INFO - 'sessionrestore_no_auto_restore', 18:56:55 INFO - 'tabpaint']}, 18:56:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:56:55 INFO - 'tests': ['a11yr', 18:56:55 INFO - 'ts_paint', 18:56:55 INFO - 'tpaint', 18:56:55 INFO - 'sessionrestore', 18:56:55 INFO - 'sessionrestore_no_auto_restore', 18:56:55 INFO - 'tabpaint']}, 18:56:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:56:55 INFO - 'tests': ['a11yr', 18:56:55 INFO - 'ts_paint', 18:56:55 INFO - 'tpaint', 18:56:55 INFO - 'sessionrestore', 18:56:55 INFO - 'sessionrestore_no_auto_restore', 18:56:55 INFO - 'tabpaint']}, 18:56:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:56:55 INFO - 'tests': ['tsvgx', 18:56:55 INFO - 'tsvgr_opacity', 18:56:55 INFO - 'tart', 18:56:55 INFO - 'tscrollx', 18:56:55 INFO - 'cart']}, 18:56:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:56:55 INFO - 'tsvgr_opacity', 18:56:55 INFO - 'tart', 18:56:55 INFO - 'tscrollx', 18:56:55 INFO - 'cart']}, 18:56:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:56:55 INFO - 'talos_options': ['--disable-e10s'], 18:56:55 INFO - 'tests': ['tp5o']}, 18:56:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:56:55 INFO - 'tests': ['tp5o']}, 18:56:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:56:55 INFO - 'talos_options': ['--disable-e10s', 18:56:55 INFO - '--xperf_path', 18:56:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:56:55 INFO - 'tests': ['tp5n']}, 18:56:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:56:55 INFO - 'talos_options': ['--xperf_path', 18:56:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:56:55 INFO - 'tests': ['tp5n']}}, 18:56:55 INFO - 'talos.zip': {'path': '', 18:56:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:56:55 INFO - Running post-action listener: _resource_record_post_action 18:56:55 INFO - [mozharness: 2016-08-23 01:56:55.657940Z] Finished populate-webroot step (success) 18:56:55 INFO - [mozharness: 2016-08-23 01:56:55.658068Z] Running create-virtualenv step. 18:56:55 INFO - Running pre-action listener: _resource_record_pre_action 18:56:55 INFO - Running main action method: create_virtualenv 18:56:55 INFO - Creating virtualenv /builds/slave/test/build/venv 18:56:55 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:56:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:56:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:56:55 INFO - Using real prefix '/tools/python27' 18:56:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:56:56 INFO - Installing distribute.............................................................................................................................................................................................done. 18:56:59 INFO - Installing pip.................done. 18:56:59 INFO - Return code: 0 18:56:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:56:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:59 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 0x107a26a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107abb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f82e017d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10798ad30>, '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 0x1076efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107982b20>, '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.BucL5U3T8g/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.Jez0eL2Hgh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:56:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:56:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:56:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BucL5U3T8g/Render', 18:56:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:59 INFO - 'HOME': '/Users/cltbld', 18:56:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:59 INFO - 'LOGNAME': 'cltbld', 18:56:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:59 INFO - 'MOZ_NO_REMOTE': '1', 18:56:59 INFO - 'NO_EM_RESTART': '1', 18:56:59 INFO - 'PAGER': '/bin/cat', 18:56:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:59 INFO - 'PWD': '/builds/slave/test', 18:56:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:59 INFO - 'SHELL': '/bin/bash', 18:56:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jez0eL2Hgh/Listeners', 18:56:59 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 18:56:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:59 INFO - 'USER': 'cltbld', 18:56:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:59 INFO - 'XPC_FLAGS': '0x0', 18:56:59 INFO - 'XPC_SERVICE_NAME': '0', 18:56:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:59 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:56:59 INFO - Cleaning up... 18:56:59 INFO - Return code: 0 18:56:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:56:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:59 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 0x107a26a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107abb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f82e017d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10798ad30>, '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 0x1076efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107982b20>, '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.BucL5U3T8g/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.Jez0eL2Hgh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:56:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:56:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:56:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BucL5U3T8g/Render', 18:56:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:59 INFO - 'HOME': '/Users/cltbld', 18:56:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:59 INFO - 'LOGNAME': 'cltbld', 18:56:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:59 INFO - 'MOZ_NO_REMOTE': '1', 18:56:59 INFO - 'NO_EM_RESTART': '1', 18:56:59 INFO - 'PAGER': '/bin/cat', 18:56:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:59 INFO - 'PWD': '/builds/slave/test', 18:56:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:59 INFO - 'SHELL': '/bin/bash', 18:56:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jez0eL2Hgh/Listeners', 18:56:59 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 18:56:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:59 INFO - 'USER': 'cltbld', 18:56:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:59 INFO - 'XPC_FLAGS': '0x0', 18:56:59 INFO - 'XPC_SERVICE_NAME': '0', 18:56:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:57:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:57:00 INFO - Downloading/unpacking psutil>=3.1.1 18:57:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:57:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:57:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:57:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:57:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:57:03 INFO - Installing collected packages: psutil 18:57:03 INFO - Running setup.py install for psutil 18:57:03 INFO - building 'psutil._psutil_osx' extension 18:57:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 18:57:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 18:57:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 18:57:03 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 18:57:03 INFO - building 'psutil._psutil_posix' extension 18:57:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 18:57:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:57:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:57:03 INFO - ^ 18:57:04 INFO - 1 warning generated. 18:57:04 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 18:57:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:57:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:57:04 INFO - Successfully installed psutil 18:57:04 INFO - Cleaning up... 18:57:04 INFO - Return code: 0 18:57:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:57:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:57:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:57:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:57:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:57:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:57:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:57:04 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 0x107a26a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107abb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f82e017d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10798ad30>, '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 0x1076efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107982b20>, '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.BucL5U3T8g/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.Jez0eL2Hgh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:57:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:57:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:57:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BucL5U3T8g/Render', 18:57:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:57:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:57:04 INFO - 'HOME': '/Users/cltbld', 18:57:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:57:04 INFO - 'LOGNAME': 'cltbld', 18:57:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:57:04 INFO - 'MOZ_NO_REMOTE': '1', 18:57:04 INFO - 'NO_EM_RESTART': '1', 18:57:04 INFO - 'PAGER': '/bin/cat', 18:57:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:57:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:57:04 INFO - 'PWD': '/builds/slave/test', 18:57:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:57:04 INFO - 'SHELL': '/bin/bash', 18:57:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jez0eL2Hgh/Listeners', 18:57:04 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 18:57:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:57:04 INFO - 'USER': 'cltbld', 18:57:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:57:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:57:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:57:04 INFO - 'XPC_FLAGS': '0x0', 18:57:04 INFO - 'XPC_SERVICE_NAME': '0', 18:57:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:57:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:57:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:57:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:57:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:57:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:57:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:57:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:57:06 INFO - Installing collected packages: mozsystemmonitor 18:57:06 INFO - Running setup.py install for mozsystemmonitor 18:57:07 INFO - Successfully installed mozsystemmonitor 18:57:07 INFO - Cleaning up... 18:57:07 INFO - Return code: 0 18:57:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:57:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:57:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:57:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:57:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:57:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:57:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:57:07 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 0x107a26a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107abb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f82e017d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10798ad30>, '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 0x1076efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107982b20>, '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.BucL5U3T8g/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.Jez0eL2Hgh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:57:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:57:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:57:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BucL5U3T8g/Render', 18:57:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:57:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:57:07 INFO - 'HOME': '/Users/cltbld', 18:57:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:57:07 INFO - 'LOGNAME': 'cltbld', 18:57:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:57:07 INFO - 'MOZ_NO_REMOTE': '1', 18:57:07 INFO - 'NO_EM_RESTART': '1', 18:57:07 INFO - 'PAGER': '/bin/cat', 18:57:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:57:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:57:07 INFO - 'PWD': '/builds/slave/test', 18:57:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:57:07 INFO - 'SHELL': '/bin/bash', 18:57:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jez0eL2Hgh/Listeners', 18:57:07 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 18:57:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:57:07 INFO - 'USER': 'cltbld', 18:57:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:57:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:57:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:57:07 INFO - 'XPC_FLAGS': '0x0', 18:57:07 INFO - 'XPC_SERVICE_NAME': '0', 18:57:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:57:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:57:07 INFO - Downloading/unpacking jsonschema==2.5.1 18:57:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:57:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:57:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:57:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:57:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:57:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:57:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:10 INFO - Downloading functools32-3.2.3-2.tar.gz 18:57:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:57:10 INFO - warning: no files found matching '*.txt' 18:57:10 INFO - no previously-included directories found matching 'build' 18:57:10 INFO - no previously-included directories found matching 'dist' 18:57:10 INFO - no previously-included directories found matching '.git*' 18:57:10 INFO - Installing collected packages: jsonschema, functools32 18:57:10 INFO - Running setup.py install for functools32 18:57:10 INFO - warning: no files found matching '*.txt' 18:57:10 INFO - no previously-included directories found matching 'build' 18:57:10 INFO - no previously-included directories found matching 'dist' 18:57:10 INFO - no previously-included directories found matching '.git*' 18:57:10 INFO - Successfully installed jsonschema functools32 18:57:10 INFO - Cleaning up... 18:57:10 INFO - Return code: 0 18:57:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:57:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:57:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:57:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:57:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:57:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:57:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:57:10 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 0x107a26a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107abb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f82e017d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10798ad30>, '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 0x1076efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107982b20>, '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.BucL5U3T8g/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.Jez0eL2Hgh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:57:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:57:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:57:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BucL5U3T8g/Render', 18:57:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:57:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:57:10 INFO - 'HOME': '/Users/cltbld', 18:57:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:57:10 INFO - 'LOGNAME': 'cltbld', 18:57:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:57:10 INFO - 'MOZ_NO_REMOTE': '1', 18:57:10 INFO - 'NO_EM_RESTART': '1', 18:57:10 INFO - 'PAGER': '/bin/cat', 18:57:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:57:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:57:10 INFO - 'PWD': '/builds/slave/test', 18:57:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:57:10 INFO - 'SHELL': '/bin/bash', 18:57:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jez0eL2Hgh/Listeners', 18:57:10 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 18:57:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:57:10 INFO - 'USER': 'cltbld', 18:57:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:57:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:57:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:57:10 INFO - 'XPC_FLAGS': '0x0', 18:57:10 INFO - 'XPC_SERVICE_NAME': '0', 18:57:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:57:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:57:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:57:10 INFO - Cleaning up... 18:57:10 INFO - Return code: 0 18:57:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:57:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:57:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:57:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:57:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:57:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:57:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:57:10 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 0x107a26a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107abb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f82e017d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10798ad30>, '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 0x1076efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107982b20>, '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.BucL5U3T8g/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.Jez0eL2Hgh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:57:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:57:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:57:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BucL5U3T8g/Render', 18:57:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:57:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:57:10 INFO - 'HOME': '/Users/cltbld', 18:57:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:57:10 INFO - 'LOGNAME': 'cltbld', 18:57:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:57:10 INFO - 'MOZ_NO_REMOTE': '1', 18:57:10 INFO - 'NO_EM_RESTART': '1', 18:57:10 INFO - 'PAGER': '/bin/cat', 18:57:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:57:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:57:10 INFO - 'PWD': '/builds/slave/test', 18:57:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:57:10 INFO - 'SHELL': '/bin/bash', 18:57:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jez0eL2Hgh/Listeners', 18:57:10 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 18:57:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:57:10 INFO - 'USER': 'cltbld', 18:57:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:57:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:57:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:57:10 INFO - 'XPC_FLAGS': '0x0', 18:57:10 INFO - 'XPC_SERVICE_NAME': '0', 18:57:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:57:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:57:11 INFO - Downloading/unpacking blobuploader==1.2.4 18:57:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:57:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:57:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:13 INFO - Downloading blobuploader-1.2.4.tar.gz 18:57:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:57:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:57:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:57:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:57:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:57:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:57:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:57:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:57:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:14 INFO - Downloading docopt-0.6.1.tar.gz 18:57:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:57:14 INFO - Installing collected packages: blobuploader, requests, docopt 18:57:14 INFO - Running setup.py install for blobuploader 18:57:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:57:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:57:14 INFO - Running setup.py install for requests 18:57:15 INFO - Running setup.py install for docopt 18:57:15 INFO - Successfully installed blobuploader requests docopt 18:57:15 INFO - Cleaning up... 18:57:15 INFO - Return code: 0 18:57:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:57:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:57:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:57:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:57:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:57:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:57:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:57:15 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 0x107a26a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107abb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f82e017d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10798ad30>, '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 0x1076efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107982b20>, '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.BucL5U3T8g/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.Jez0eL2Hgh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:57:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:57:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:57:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BucL5U3T8g/Render', 18:57:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:57:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:57:15 INFO - 'HOME': '/Users/cltbld', 18:57:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:57:15 INFO - 'LOGNAME': 'cltbld', 18:57:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:57:15 INFO - 'MOZ_NO_REMOTE': '1', 18:57:15 INFO - 'NO_EM_RESTART': '1', 18:57:15 INFO - 'PAGER': '/bin/cat', 18:57:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:57:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:57:15 INFO - 'PWD': '/builds/slave/test', 18:57:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:57:15 INFO - 'SHELL': '/bin/bash', 18:57:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jez0eL2Hgh/Listeners', 18:57:15 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 18:57:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:57:15 INFO - 'USER': 'cltbld', 18:57:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:57:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:57:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:57:15 INFO - 'XPC_FLAGS': '0x0', 18:57:15 INFO - 'XPC_SERVICE_NAME': '0', 18:57:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:57:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:57:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:57:16 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-djOUUc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:57:16 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-W4Ibcj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:57:16 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-zYxnkv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:57:16 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-bMh78z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:57:16 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-Li6gTU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:57:16 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-EG84pk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:57:16 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-3fSHQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:57:16 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-cdKsBy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:57:16 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-Isi6MD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:57:16 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-usld0O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:57:17 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-tvP4hl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:57:17 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-Ha6tY1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:57:17 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-5bNjYa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:57:17 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-kzYlN3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:57:17 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-iJIGBb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:57:17 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-Oif0C7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:57:17 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-mntbBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:57:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:57:17 INFO - Running setup.py install for manifestparser 18:57:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:57:17 INFO - Running setup.py install for mozcrash 18:57:18 INFO - Running setup.py install for mozdebug 18:57:18 INFO - Running setup.py install for mozdevice 18:57:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:57:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:57:18 INFO - Running setup.py install for mozfile 18:57:18 INFO - Running setup.py install for mozhttpd 18:57:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:57:18 INFO - Running setup.py install for mozinfo 18:57:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:57:18 INFO - Running setup.py install for mozInstall 18:57:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:57:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:57:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:57:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:57:18 INFO - Running setup.py install for mozleak 18:57:19 INFO - Running setup.py install for mozlog 18:57:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:57:19 INFO - Running setup.py install for moznetwork 18:57:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:57:19 INFO - Running setup.py install for mozprocess 18:57:19 INFO - Running setup.py install for mozprofile 18:57:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:57:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:57:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:57:19 INFO - Running setup.py install for mozrunner 18:57:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:57:19 INFO - Running setup.py install for mozscreenshot 18:57:20 INFO - Running setup.py install for moztest 18:57:20 INFO - Running setup.py install for mozversion 18:57:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:57:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:57:20 INFO - Cleaning up... 18:57:20 INFO - Return code: 0 18:57:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:57:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:57:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:57:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:57:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:57:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:57:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:57:20 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 0x107a26a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107abb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f82e017d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10798ad30>, '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 0x1076efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107982b20>, '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.BucL5U3T8g/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.Jez0eL2Hgh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:57:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:57:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:57:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BucL5U3T8g/Render', 18:57:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:57:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:57:20 INFO - 'HOME': '/Users/cltbld', 18:57:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:57:20 INFO - 'LOGNAME': 'cltbld', 18:57:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:57:20 INFO - 'MOZ_NO_REMOTE': '1', 18:57:20 INFO - 'NO_EM_RESTART': '1', 18:57:20 INFO - 'PAGER': '/bin/cat', 18:57:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:57:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:57:20 INFO - 'PWD': '/builds/slave/test', 18:57:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:57:20 INFO - 'SHELL': '/bin/bash', 18:57:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jez0eL2Hgh/Listeners', 18:57:20 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 18:57:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:57:20 INFO - 'USER': 'cltbld', 18:57:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:57:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:57:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:57:20 INFO - 'XPC_FLAGS': '0x0', 18:57:20 INFO - 'XPC_SERVICE_NAME': '0', 18:57:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:57:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:57:20 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-G5BVcP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:57:20 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:57:20 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-aq7aP1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:57:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:57:20 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-IxKt2r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:57:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:57:21 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-44Ahz3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:57:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:57:21 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-LV21u6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:57:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:57:21 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-VnLviC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:57:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:57:21 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-Y0DS1M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:57:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:57:21 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-RQJjUl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:57:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:57:21 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-EggAoI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:57:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:57:21 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-hUa0DF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:57:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:57:21 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-ETu94T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:57:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:57:21 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-7IfAsc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:57:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:57:21 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-C8FDEG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:57:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:57:22 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-z2g743-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:57:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:57:22 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-BbylBW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:57:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:57:22 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-1e4pZq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:57:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:57:22 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-LBXvrU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:57:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:57:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:57:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:57:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:57:22 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:57:22 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:57:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:57:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:57:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:57:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:24 INFO - Downloading blessings-1.6.tar.gz 18:57:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:57:24 INFO - Installing collected packages: blessings 18:57:24 INFO - Running setup.py install for blessings 18:57:25 INFO - Successfully installed blessings 18:57:25 INFO - Cleaning up... 18:57:25 INFO - Return code: 0 18:57:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:57:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:57:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:57:25 INFO - Reading from file tmpfile_stdout 18:57:25 INFO - Current package versions: 18:57:25 INFO - blessings == 1.6 18:57:25 INFO - blobuploader == 1.2.4 18:57:25 INFO - docopt == 0.6.1 18:57:25 INFO - functools32 == 3.2.3-2 18:57:25 INFO - jsonschema == 2.5.1 18:57:25 INFO - manifestparser == 1.1 18:57:25 INFO - mozInstall == 1.12 18:57:25 INFO - mozcrash == 0.17 18:57:25 INFO - mozdebug == 0.1 18:57:25 INFO - mozdevice == 0.48 18:57:25 INFO - mozfile == 1.2 18:57:25 INFO - mozhttpd == 0.7 18:57:25 INFO - mozinfo == 0.9 18:57:25 INFO - mozleak == 0.1 18:57:25 INFO - mozlog == 3.3 18:57:25 INFO - moznetwork == 0.27 18:57:25 INFO - mozprocess == 0.23 18:57:25 INFO - mozprofile == 0.28 18:57:25 INFO - mozrunner == 6.12 18:57:25 INFO - mozscreenshot == 0.1 18:57:25 INFO - mozsystemmonitor == 0.3 18:57:25 INFO - moztest == 0.7 18:57:25 INFO - mozversion == 1.4 18:57:25 INFO - psutil == 3.1.1 18:57:25 INFO - requests == 1.2.3 18:57:25 INFO - wsgiref == 0.1.2 18:57:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:57:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:57:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:57:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:57:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:57:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:57:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:57:25 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 0x107a26a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107abb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f82e017d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10798ad30>, '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 0x1076efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107982b20>, '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.BucL5U3T8g/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.Jez0eL2Hgh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:57:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:57:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:57:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BucL5U3T8g/Render', 18:57:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:57:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:57:25 INFO - 'HOME': '/Users/cltbld', 18:57:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:57:25 INFO - 'LOGNAME': 'cltbld', 18:57:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:57:25 INFO - 'MOZ_NO_REMOTE': '1', 18:57:25 INFO - 'NO_EM_RESTART': '1', 18:57:25 INFO - 'PAGER': '/bin/cat', 18:57:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:57:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:57:25 INFO - 'PWD': '/builds/slave/test', 18:57:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:57:25 INFO - 'SHELL': '/bin/bash', 18:57:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jez0eL2Hgh/Listeners', 18:57:25 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 18:57:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:57:25 INFO - 'USER': 'cltbld', 18:57:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:57:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:57:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:57:25 INFO - 'XPC_FLAGS': '0x0', 18:57:25 INFO - 'XPC_SERVICE_NAME': '0', 18:57:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:57:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:57:26 INFO - Cleaning up... 18:57:26 INFO - Return code: 0 18:57:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:57:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:57:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:57:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:57:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:57:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:57:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:57:26 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 0x107a26a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107abb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f82e017d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10798ad30>, '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 0x1076efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107982b20>, '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.BucL5U3T8g/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.Jez0eL2Hgh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:57:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:57:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:57:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BucL5U3T8g/Render', 18:57:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:57:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:57:26 INFO - 'HOME': '/Users/cltbld', 18:57:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:57:26 INFO - 'LOGNAME': 'cltbld', 18:57:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:57:26 INFO - 'MOZ_NO_REMOTE': '1', 18:57:26 INFO - 'NO_EM_RESTART': '1', 18:57:26 INFO - 'PAGER': '/bin/cat', 18:57:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:57:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:57:26 INFO - 'PWD': '/builds/slave/test', 18:57:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:57:26 INFO - 'SHELL': '/bin/bash', 18:57:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jez0eL2Hgh/Listeners', 18:57:26 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 18:57:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:57:26 INFO - 'USER': 'cltbld', 18:57:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:57:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:57:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:57:26 INFO - 'XPC_FLAGS': '0x0', 18:57:26 INFO - 'XPC_SERVICE_NAME': '0', 18:57:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:57:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:57:26 INFO - Cleaning up... 18:57:26 INFO - Return code: 0 18:57:26 INFO - Running post-action listener: _resource_record_post_action 18:57:26 INFO - Running post-action listener: _start_resource_monitoring 18:57:26 INFO - Starting resource monitoring. 18:57:26 INFO - [mozharness: 2016-08-23 01:57:26.492817Z] Finished create-virtualenv step (success) 18:57:26 INFO - [mozharness: 2016-08-23 01:57:26.493864Z] Running install step. 18:57:26 INFO - Running pre-action listener: _resource_record_pre_action 18:57:26 INFO - Running main action method: install 18:57:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:57:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:57:26 INFO - Reading from file tmpfile_stdout 18:57:26 INFO - Detecting whether we're running mozinstall >=1.0... 18:57:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:57:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:57:26 INFO - Reading from file tmpfile_stdout 18:57:26 INFO - Output received: 18:57:26 INFO - Usage: mozinstall [options] installer 18:57:26 INFO - Options: 18:57:26 INFO - -h, --help show this help message and exit 18:57:26 INFO - -d DEST, --destination=DEST 18:57:26 INFO - Directory to install application into. [default: 18:57:26 INFO - "/builds/slave/test"] 18:57:26 INFO - --app=APP Application being installed. [default: firefox] 18:57:26 INFO - mkdir: /builds/slave/test/build/application 18:57:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:57:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:58:01 INFO - Reading from file tmpfile_stdout 18:58:01 INFO - Output received: 18:58:01 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:58:01 INFO - Running post-action listener: _resource_record_post_action 18:58:01 INFO - [mozharness: 2016-08-23 01:58:01.027124Z] Finished install step (success) 18:58:01 INFO - [mozharness: 2016-08-23 01:58:01.027246Z] Running run-tests step. 18:58:01 INFO - Running pre-action listener: _resource_record_pre_action 18:58:01 INFO - Running main action method: run_tests 18:58:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:58:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:58:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:58:01 INFO - 2016-08-22 18:58:01.041 screenresolution[1654:7574] starting screenresolution argv=screenresolution get 18:58:01 INFO - 2016-08-22 18:58:01.044 screenresolution[1654:7574] Display 0: 1600x1200x32@60 18:58:01 INFO - 2016-08-22 18:58:01.050 screenresolution[1655:7578] starting screenresolution argv=screenresolution list 18:58:01 INFO - Available Modes on Display 0 18:58:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:58:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:58:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:58:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:58:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:58:01 INFO - Intel Iris: 18:58:01 INFO - Chipset Model: Intel Iris 18:58:01 INFO - Type: GPU 18:58:01 INFO - Bus: Built-In 18:58:01 INFO - VRAM (Dynamic, Max): 1536 MB 18:58:01 INFO - Vendor: Intel (0x8086) 18:58:01 INFO - Device ID: 0x0a2e 18:58:01 INFO - Revision ID: 0x0009 18:58:01 INFO - Displays: 18:58:01 INFO - Display: 18:58:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:58:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:58:01 INFO - Display Serial Number: 1600x1200 60 18:58:01 INFO - Main Display: Yes 18:58:01 INFO - Mirror: Off 18:58:01 INFO - Online: Yes 18:58:01 INFO - Rotation: Supported 18:58:01 INFO - Return code: 0 18:58:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:58:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:58:01 INFO - Python 2.7.3 18:58:01 INFO - Return code: 0 18:58:01 INFO - grabbing minidump binary from tooltool 18:58:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:58:01 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 0x10798ad30>, '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 0x1076efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107982b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:58:01 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:58:01 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 18:58:01 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:58:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:58:01 INFO - Return code: 0 18:58:01 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:58:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:58:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:58:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:58:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:58:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FdtP9adVRQqQofju-mdcqg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0382', '--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 18:58:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FdtP9adVRQqQofju-mdcqg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0382 --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 18:58:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BucL5U3T8g/Render', 18:58:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:58:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:58:01 INFO - 'HOME': '/Users/cltbld', 18:58:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:58:01 INFO - 'LOGNAME': 'cltbld', 18:58:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:58:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:58:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:58:01 INFO - 'MOZ_NO_REMOTE': '1', 18:58:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:58:01 INFO - 'NO_EM_RESTART': '1', 18:58:01 INFO - 'PAGER': '/bin/cat', 18:58:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:58:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:58:01 INFO - 'PWD': '/builds/slave/test', 18:58:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:58:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:58:01 INFO - 'SHELL': '/bin/bash', 18:58:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jez0eL2Hgh/Listeners', 18:58:01 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 18:58:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:58:01 INFO - 'USER': 'cltbld', 18:58:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:58:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:58:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:58:01 INFO - 'XPC_FLAGS': '0x0', 18:58:01 INFO - 'XPC_SERVICE_NAME': '0', 18:58:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:58:01 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FdtP9adVRQqQofju-mdcqg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0382', '--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 18:58:01 INFO - mozversion application_buildid: 20160822165414 18:58:01 INFO - mozversion application_changeset: 65cc7b37cf3f1ced49f6a3d45ab17c82eb18b3c9 18:58:01 INFO - mozversion application_display_name: Nightly 18:58:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:58:01 INFO - mozversion application_name: Firefox 18:58:01 INFO - mozversion application_remotingname: firefox 18:58:01 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 18:58:01 INFO - mozversion application_vendor: Mozilla 18:58:01 INFO - mozversion application_version: 51.0a1 18:58:01 INFO - mozversion platform_buildid: 20160822165414 18:58:01 INFO - mozversion platform_changeset: 65cc7b37cf3f1ced49f6a3d45ab17c82eb18b3c9 18:58:01 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 18:58:01 INFO - mozversion platform_version: 51.0a1 18:58:01 INFO - using testdate: 1471917481 18:58:01 INFO - actual date: 1471917481 18:58:01 INFO - starting webserver on 'localhost:49275' 18:58:01 INFO - SUITE-START | Running 2 tests 18:58:01 INFO - TEST-START | dromaeo_css 18:58:01 INFO - operating with platform_type : mac_ 18:58:01 INFO - Initialising browser for dromaeo_css test... 18:58:01 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmpiw5e5p/profile http://localhost:49275/getInfo.html) 18:58:04 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 18:58:04 INFO - PROCESS | 1665 | colorDepth:24 18:58:04 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 18:58:04 INFO - PROCESS | 1665 | __metrics 18:58:04 ERROR - PROCESS | 1665 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2993: TypeError: this.tabs is undefined 18:58:05 INFO - TEST-INFO | 1665: exit 0 18:58:05 INFO - Browser initialized. 18:58:05 INFO - Running cycle 1/1 for dromaeo_css test... 18:58:05 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmpiw5e5p/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:58:17 INFO - PROCESS | 1668 | RSS: Main: 239828992 18:58:17 INFO - PROCESS | 1668 | 18:59:06 INFO - PROCESS | 1668 | 1471917546817 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:01:19 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 19:01:20 INFO - PROCESS | 1668 | RSS: Main: 254840832 19:01:20 INFO - PROCESS | 1668 | 19:04:22 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 19:04:23 INFO - PROCESS | 1668 | RSS: Main: 267661312 19:04:23 INFO - PROCESS | 1668 | 19:07:25 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 19:07:26 INFO - PROCESS | 1668 | RSS: Main: 271876096 19:07:26 INFO - PROCESS | 1668 | 19:10:28 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 19:10:28 INFO - PROCESS | 1668 | RSS: Main: 271433728 19:10:28 INFO - PROCESS | 1668 | 19:13:31 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 19:13:32 INFO - PROCESS | 1668 | RSS: Main: 277569536 19:13:32 INFO - PROCESS | 1668 | 19:16:34 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 19:16:35 INFO - PROCESS | 1668 | RSS: Main: 280371200 19:16:35 INFO - PROCESS | 1668 | 19:16:35 INFO - PROCESS | 1668 | __start_tp_report 19:16:35 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 19:16:35 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 19:16:35 INFO - PROCESS | 1668 | |i|pagename|runs| 19:16:35 INFO - PROCESS | 1668 | |0;dojo.html;3418.179636887147;3442.6557344265575;3446.1040129566313;3453.361128191283;3458.6368431314727;5144.17693169093;5341.786328546854;5694.2312787773635;5792.68140252286;5837.8832423351505;5735.569832262576;5760.646248131686;5788.59773194125;5830.533557315404;5846.503047240979;5238.895222095556;5275.549223963402;5288.762005709733;5338.813141540047;5390.78436862525;3938.1532970411354;5164.6384753067305;5335.919961200585;5425.6707106758895;5446.428125046872;5245.291885595434;5256.553192978601;5275.208718692188;5289.418164001957;5369.838904832822;4479.171353299617;4743.501932297094;4941.678790878617;5334.093204155303;5338.958903013914;5278.445763194848;5290.497402746733;5339.599530035263;5363.222332761767;5384.1923711443205;5635.21107045014;5703.686297253649;5747.08046712524;5807.593468457209;5810.535157187415;5644;5899.908516924383;6279;6321.399467050623;6350.396712312323;6303.936960630381;6311.747530098791;6314.179156709598;6326.778562750282;6336.493080553545;5781.508571771405;5841.766329346863;5865.2961644602065;5925.4370834770625;5994.940050599482;6366.872662546816;6373.808785736435;6380.84047898797;6393.552451328363;6394.200724909387;6216.129741836146;6280.937190628127;6295.968520157461;6301.369863013662;6305.369463053749;6208.534359922934;6227.532935029801;6286.779962701284;6290.308066112724;6370.58591191571;6319.304876463585;6336.714847831767;6337.176167098248;6349.873002539924;6349.936500634934;6312.52656050798;6337.207849018872;6341.207349081365;6358.3641635836975;6409.4231519163495;4947.925781113287;4975.975120124376;5002.374703162105;5034.043531728959;5072.163093089599;4465.486469056035;4510.526394728572;4539.205639013159;4541.8183272669385;4576.222042252825;5887.2052272943465;5937.168796368512;5994.850128746729;5997.010493268562;5999.280086389661;6248.8439638667;6267.905981410283;6273.309935907047;6278.277998030194;6322.178116844872;5005.4744251853035;5903.527717782567;5949.434803693643;5975.492083172979;5986.461218490357;4978.979309241597;5217.739113044333;5582.218489411485;5597.132444471114;5611.410801865745;6292;6325.841853953688;6352.142460767737;6394.1687580614225;6493.967530162414;6261.530385221217;6269.185005949197;6292;6301.684915754102;6317.715702793477;5589.798193388441;5626.381098079126;6296.905546416716;6390.9041364378645;6400.487960963205;6168.876622467616;6221.09794079865;6287.528435367274;6305.684715764101;6351.777687780906;6327.588706734047;6327.7785277516905;6328.114064031131;6333.683315834282;6336.873262534631;6106.3283038866575;6206.131141640264;6247.969085100907;6250.874982500415;6264.060390941395;6079.635221886524;6154.138420621027;6200.658963756856;6207.131001659862;6209.223847019122;4922.4339200992345;5208.296879921314;5557.777688892399;6159.784407545714;6169.58099637077;6226.688665566613;6240.968795155995;6261.7182226799;6267.874642507216;6301.716422761078;5891.970540147272;6031.276246850406;6093.847653808691;6107.725152368065;6107.969460152671 19:16:35 INFO - PROCESS | 1668 | |1;ext.html;467.5815145444827;489.3833769450491;524.3131497737963;530.8964751873386;562.0613575329199;28611.713882861164;35880.820595897014;41685;41749.58250417495;41833.79083104584;41986.95032624186;42593.14813703724;42598;42598.93502662427;42602.573974260245;9734.12392884642;9970.15253703436;9978.950105249463;9993.400395976265;10008.549615267295;9794.90205097947;9907.356021858555;9910.355826871266;9930.45382503962;9931.205503559733;9894.703158905208;9933.403995760278;9955.452450115241;9958.053984871425;9971.152452041586;9886;9942.403455792602;9943.254255930835;9952.850707239397;9956.153726933147;9753.463559504224;9810.754731131707;9823.722916020943;9831.950840245752;9860.704178874574;40039;40703.59296407057;40831.387529187086;40887.59112408868;41051.38422923659;3273.2635157089703;3401.489776533515;3515.2793677296095;3590.730695197871;3829.329867273244;31797.682023179703;32254.677453225402;32623;32774;32833.343333133445;3229.4994275887275;3333.4333163362044;3437.8796742114155;3440.139965008748;3448.9310213795584;498722;503820;504379;506989;508418;59734;59908;60239;60408;60454.69772651108;61950.69024654848;62755.37244627587;62837.68581157065;62927;62992.42518937063;16180.838191618168;16381.918090409472;16411;16417.42539011129;16451.506454806375;12689.17520361192;12827.230366178059;12859;12863.035272354611;12864.614061578168;14388.42446302157;17503.649927001643;17686.292548297923;17693;17760.733588996176;3210.5826242588314;7341.743038993716;7505.924940750522;7510.361619262315;7542;849.4818160921833;952.8237276103943;983.5082458770614;994.5275993902839;1018.5587393837636;29981.850090749842;30530.54204186939;31046.22384440407;31628.05115846528;31658.683413166033;971.9271054670786;1068.647346375694;1100.2023532938715;1112.165875593305;1116.7933932222566;15509.379624815107;15917.761233581505;16226;16271.83728162727;16667.166641667867;431649;559485.2025739927;576054;578812;580939;60257;60432.395676043554;60559.39440605538;61105;61409;1380.6893448973901;1403.8455769865307;1412.3009110490373;1413.985860141406;1415.1438379780084;25122.74877251204;30109.298621409067;30491;30574;31515.36969260556;52906.735466322425;53388.73305633602;53519.859205631335;53649.463505364445;53652.92694146173;603806;608396;608427.1572842681;609514;612844;27050.864745676146;27182.592261116493;28190.577141343307;28317;28799.280017999718;39745;40046.799766000986;43563.34654980241;45925;48460.03079938452;39327;39748.40377394284;40280;41924.580754192066;42457.5754242466;40820.79589602152;40890.1821963565;41406.238312514506;41494.96262593339;41768.373474397304;33617.32765344631;38814.4177837327;38902.027449313995;39554.01114972149;39712.00719982023;34427.82786086053;35547;36255.63744362628;36331;36463.27073458569;25665.61501577515;25726.742732572435;25739.71301434958;26008.34979125537;26409.60385594179 19:16:35 INFO - PROCESS | 1668 | |2;jquery.html;369.2098908336161;373.43984023964055;376.52181729203903;380.38947489279724;398.9666763083613;19091.427257182288;21611.1306903073;21625.134994600216;21767.020484078213;21819.79991100491;19955.800441995576;20167.49581260469;20487.487812804688;20779.480512987182;21039.948002599886;4905.288733133693;4989.251612258154;5030.471800460954;5898.587098903057;6074.271087469509;4117.773522456264;4865.391826021747;5726.284214473191;5876.265466816648;6370.203724534434;6548.214214294291;6704.290405946477;6727.495437842157;6728.865422691543;6733.8989915151315;6630.370114839083;6665.4001139897155;6688.866222675543;6711.798646040626;6720.630365329905;4935.481774413671;5717.828465146053;5774.105013722879;5788.334341550733;5828.038373668382;12625.305608191546;13097.869021309856;13200.933995329962;13212.207267563881;13219.008574356865;2103.8632488888175;2251.8648881067024;2403.8557686538866;2776.9583456248174;2888.508953477914;10039.598416063349;10196.337238079575;10248.180145588334;12132.939335303266;15738.291776870068;1732.6534693061312;1789.2395731814056;1828.6068495273446;1885.9811401885943;1910.1404368034441;120693;127024.99999999907;127997;128833;131653;11068.446577671246;11187.440627968568;11200.775984480428;11211.215214934877;11526.020288275591;11759.941200293944;14135.646608834655;14226.857731422553;14234.003619746518;14258.500952466615;8369.414141010071;8567.785805354792;8573;8629.482231066157;8654.264387527004;8457;8536.786580335418;8587.441816281922;8684;8727.519986400808;4453;4469.910601787946;4518.864434066917;4532.637389008937;4548.589937119504;1909.8185672361103;1910.92356305749;1929.4790406590141;3683.042408973686;3868.139768251102;799.4164260090167;837.5267973594899;914.4376208631644;950.8162337889372;961.2694352292308;20249.08879100444;21007.8949605251;21421.250256240957;21497.602655827635;22042.66935995929;825.6862392290891;918.0635751533398;928.5635751196927;939.9483028433502;941.2705153506088;9988.450635214987;10429.823382750368;10999.395033273087;11108.739570045094;11264.098872090213;468951;597955;601969;613592;614243;102321;126909.36545317399;130435.69564304236;131150;136365;953.6948176583427;964.2237998411347;981.087588542648;987.2891518106951;994.8806143262855;18345.08274586292;18577.349792757188;18600.7209891849;18638.16128274204;18693.345732899284;53361.466385337204;53991.73004134954;55031.44968550263;55347.723261383435;55948.72025640008;79977.20022799697;80363;82102.58948705219;82109.43124550142;83195.58402208192;6303.306636269915;7106.147262328554;7301.196868344372;7369.963150184215;7473.738419155304;9556.56995435221;11273.379964102052;12375.071869609996;12577.622671319508;13030.54393096237;10528.263021588415;10679.786404271716;10816.080633145955;10856.620018299322;11015.228933974866;9503.097205765442;9573.425594464354;10874.456277185951;10891.237613366988;11354.943225283821;8424.452410593292;8782.736517904474;9675.274354423587;9758.268129890144;10361.274710770174;5699.2590963174525;5895.351511333664;6988.056612357267;7024.4029257513685;7125.857482850418;8367.62345694433;9762;9847.852282215916;10354.689359319233;10485.318454300446 19:16:35 INFO - PROCESS | 1668 | |3;mootools.html;1365.174069687839;1453.7019910918264;1622.5375767906148;1808.0146320255467;1814.97277540837;7715.537067775937;8147.959260203698;8371;8736.606852691628;8737.169968852964;8502.659893604265;8609.956950215255;8610.138986101403;8611.26804221642;8626.180512851284;2344.624860022401;2358.96461553076;2359.1153317505937;2361.4332560185517;2363.0311572255378;2300.907963681459;2333.3000099970027;2351.3651314145172;2356.9410764730933;2369.1826319919687;2005.8194762471376;2254.4927391336983;2338.4504641409253;2346.589346864292;2357.693499845018;1922.8846269223752;2303.8853744686767;2326.116075891159;2329.9883500582473;2335.929922102348;2305.8155347572324;2315.143396943142;2316.548273086749;2318.119114736406;2328.7205535335697;8525.360597955178;8632.525211113387;8666.6100025499;8678.088800675965;8709.912900870973;5134.974325128388;5256.710880901549;5276;5279.445658205871;5293.444188360205;3445.5693038370205;3525.312564050011;3605.441156620728;3630.5296354976276;3640.9635903641156;942.9674506415464;943.9103285187896;1055.108768585958;1072.4101744040825;1091.9727006824814;10798.352098874093;11068.667939961813;11087.445627718582;11094.167937404727;11125.666230013032;4463.799129039201;4547.886302842389;4703.2004559225015;4718.716876987323;4724.43306803179;9466;9581.233501319877;9591.616335346647;9619.615215391446;9621.759456013655;5083.949160508348;5093.439721630692;5101.234814777738;5109.821156259513;5115.948840511547;4323.718958267703;4595.747233902168;4685.765711714401;4789.521047895252;4798.616110711135;3779.7165212609166;3854.190619969838;3907.0623050467684;3948.5853985331128;3956.4658771066115;1271.68207948013;1273.5160638957323;1281.7052078022107;1285.6914340558385;1292.3086148910477;864.2481041493941;886.1227384889042;899.8392074224308;900.4552245891273;914.5198770645331;5528.668279903219;5539.972300138554;5634.126710359901;5815.680137592389;5899.852503687356;429.35382249714604;430.5801843202915;433.34348462079976;437.2588462555937;451.3568165364343;3610.223801882555;3883.3301680277186;3921.5490218624655;3928.9607103929166;3995.700322475826;10196.643117491001;10478.685639430829;10523;10549.945005499389;10556;10381.065704086668;10454.163666906628;10517.474126293655;10533.262671612914;10534.420606866543;6088.939110608837;7943.046834379911;7949.920500794918;7978.122406535276;8014.358851292;5537.667739935617;5691.914621280601;5693.800716974886;5716.371199168005;5791.47876691083;8928.419652722552;9604.135627793532;9650.227981761582;9684.95157524208;9738.853917191389;8324.334053275603;8406.621702023545;9583.712488625353;9954.004599539989;9959.452230127123;5402.756875940513;6592.307807680317;7025.859482810418;7132.286771322826;7309.269073092861;6768.255491895986;8072.273495385443;8107.797305067421;8124.146964568635;8157;7287.526310789782;7319.8536029280185;7334.743283985035;7335.046443962251;7431.479796414199;7117.786466406016;7187.281271872771;7188.892166617609;7204.9639751800905;7237.927620723725;6055.45500904903;6699.76550820719;6786.117804685359;6858.862822743417;6882.380585747307;4892.168331383745;4945.4807245238735;4949.802007919643;4959.13215187348;5045.041442125985;3865.265599536079;4609.700369475973;4733.857984260478;4811.879703007453;4892.877678058077 19:16:35 INFO - PROCESS | 1668 | |4;prototype.html;284.28927680798;298.3912817851583;308.8162543286744;314.21603100264844;314.9511825667021;4948.628852836043;5403.864903377417;5610.214569960209;5678.176664383662;5685.441551301915;5482.177673349;5489.478499542547;5646.435356464352;5651.491365777089;5667.489925906668;3519.2052053452794;4101.466809314785;4239.321708526644;4274.850380236692;4291.206126866524;4177.07604676726;4285.378620100084;4302.354646802989;4318.935215971763;4327.242732521796;3439.690427861492;3711.962880371189;3858.7684738915755;4229.238737027334;4261.529772228576;4243.978780106095;4251.2347777400055;4278.850240241608;4284.721493102938;4287.2711639021445;3730.6642402183934;3738.719596030309;3738.8691395801015;3746.3818469952425;3753.230587729509;4919.237518184687;5177.4822517747925;5292.682439053669;5300.2314664373835;5473.28847249859;4202.642775364097;4222.817611068875;4536.909261814745;4859.757012149378;4899.534544218293;5159.535641792219;5649.096144616883;5960.165576819249;5975.760969561213;5997.940020599782;3524.9823750881337;4050.2709512287774;4075.409065685459;4091.3249313863175;4098.774567398792;5382.730863456812;5392.460753924615;6259.561830671855;6264.467520260783;6295.5059941732325;4128.431196145506;4130.91738165235;4154.771487568152;4157.729747566459;4161.271777438936;3739.420389839579;3778.5465744110334;3813.0657988792923;3976.3240249157134;4040.4949381327333;4342.891427714332;4546.2037550384;4771.761411929389;4794.9280760788615;4797.472278049412;4534.79593418297;4922.680025798311;5934.19888315072;5941.22764040672;5997.430244126801;1277.591170825327;1278.7122897348022;1310.6068179546098;1366.1734650536482;1373.2858913364994;880.9691660791841;895.632790555869;910.0035461170008;929.8279818233649;935.2237642756496;4753.952460475421;5233.581313494988;5554.416786237387;5555.555555555627;5578.553715702734;5042.647014708935;5393.487618676219;5783.248177736868;5938.970305148533;6089.351132257482;4200.789960502024;5433.375161856358;5471.699056551928;5507.5593952483705;5571.331440227199;2669.439417722261;2715.5112079825426;2716.3344980479515;2724.910035985582;2739.821876593084;4941.209406494943;5251.737413129328;5308.761105750251;5309.5752339812725;5393.6763794172475;4246;4675.5324467553655;4926.118220840848;5090.10923088458;5259.658122222043;5138.691678499302;5248.139763828723;5326.230360212668;5350.435547031122;5394.921015796856;3981.70137239714;4464.50890402049;4614.377059096979;4891.728150680778;4998.700597725087;4260.892168036271;4470.664700147437;4813.422389313305;5130.97434512825;5137.434882163034;4487.775611219491;4786.282057691375;5163.09645811989;5186.740662966761;5338.172583249602;2931.296488842705;3159.8578063986715;3374.325134972966;3514.8225344509874;3555.2178520725397;3993.9016770388375;4108.650764685035;4236.322188449833;4692.178868697896;5088.669236499763;4774.689645173053;4778.522147785194;4802.758138256732;4908.410990681141;4933.654644174873;4667.9263769332565;4915.926261106158;5000.024995125916;5021.44764075944;5039.3448851649055;3842.366994027583;4438.800253988514;4679.438467383799;4884.951150488591;5009.649324547247;4986.626003049858;5975.7012149391485;5981.282246130492;5992.161097446448;6004.819855404345;5532.446755324436;5856.209411729362;5891.351951285441;5918.915947995869;5949.1076338549565 19:16:35 INFO - PROCESS | 1668 | |5;yui.html;673.5554534007555;710.6269208665453;735.0848193998471;767.202109805802;795.2484901767829;15164.469243576477;16003.519894403187;16012;16076.115813630246;16094.275757590907;14586.270686465687;14683.706325873503;14722.63193420165;14726.410943562272;14810.777838332408;658.4830907737427;811.8498077855616;831.0235473318857;834.1991687979547;835.5404527509845;847.4745657692208;855.1747563601124;855.7518319687283;855.8844555984924;858.8368210040104;905.6739573753443;906.4470672889532;908.736466424736;911.7082533589254;914.6067191107821;857.8670306102533;866.7746385939638;893.4683863101412;905.9909400905973;906.3429013964889;668.7458765669063;670.229236378169;672.0054319607009;672.1128110893623;673.3703986772383;12342.444589993382;15015.699686006219;15016.399344026335;15055.698886022219;15069.397224111022;14558.92720536401;14560.927195364062;14686.192259425727;14706.70586588273;14845.777313340308;11978.880211197864;12160.635180944497;12170.57402990891;12319.69200769988;12502.124851260416;3150.306932474875;3775.528058992626;3993.6206060424215;4005.8798236052967;4044.3529035354495;109018.45490772574;110851.89148108497;110861;111115;112608;11135.276207046516;11262.831057534144;11366.09071274296;11463.31220126795;11682.12384071198;23462.061517539107;23462.06151753945;23465.530689386458;23466.296011119695;23554;8110.067342255598;8193;8226.383021273428;8311.584420778936;8446.239838414573;4310.827566897289;4399.846005389796;4403.185410699031;4404.2512772828695;4416.205083085011;3621.348157331706;3713.053171441265;3725.515682961198;3739.270842185802;3753.887383378503;663.1213641924469;667.6361383046251;670.1723371636026;681.6830173969029;685.2428066986008;531.3119510234255;536.9597280203922;546.0853071105881;638.4349850382353;652.6573548886872;8773.473591584525;9045.747461245279;9367.407540718094;9595.42427454355;9756.653615502744;599.4455129005739;646.5894157210112;671.1778071862028;677.9728711002834;683.1597135523235;4398.812370476613;4557.6581756368405;4709.62323014158;4902.3872015998;5024.171011783088;157671;164738.3526164747;165036.17481912512;166779;168198;143208;146107;146462.73415703594;151653.24173379064;153947.23026385022;117052.24421633683;120676.39661801636;122350;124104;126161;9765.804683906425;10049.648262310784;10068.597256109673;10122.291439599157;10141.949290253797;66842.99735504069;67487;68058;68068.6596567014;70242.64878675574;64921;67115.66442167757;80762.19237807547;88535.55732221298;93964.59053114346;13411.262380569162;13452.5291614793;13456.932715336752;13937.372818223406;14217.004809663224;15058;15183.468578599695;15300.693986120437;15302.846971530142;16159.919200403923;14189.503367382093;14235.50175743844;14570.05294655844;14574.489892853699;14973.176475293963;13521.797173042609;14017.929910350384;14248;14259.64350891236;14369.712605748036;13697.589072327846;13896.86103138996;13957.232352220726;14118.43526258938;14711.485098021518;9147.588358524015;9928.056834600702;11322.071061942426;11599.362035088152;12039.819402708792;9698.6120555177;10253.179745620482;11026.173037022416;11514.769704605693;11826.172167948162 19:16:35 INFO - PROCESS | 1668 | __end_tp_report 19:16:35 INFO - PROCESS | 1668 | __start_cc_report 19:16:35 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,421 19:16:35 INFO - PROCESS | 1668 | __end_cc_report 19:16:35 INFO - PROCESS | 1668 | __startTimestamp1471918595226__endTimestamp 19:16:35 INFO - PROCESS | 1668 | 19:16:35 INFO - PROCESS | 1668 | ------- Summary: start ------- 19:16:35 INFO - PROCESS | 1668 | Number of tests: 6 19:16:35 INFO - PROCESS | 1668 | 19:16:35 INFO - PROCESS | 1668 | [#0] dojo.html Cycles:165 Average:5787.03 Median:5999.28 stddev:676.02 (11.3%) stddev-sans-first:652.06 19:16:35 INFO - PROCESS | 1668 | Values: 3418.2 3442.7 3446.1 3453.4 3458.6 5144.2 5341.8 5694.2 5792.7 5837.9 5735.6 5760.6 5788.6 5830.5 5846.5 5238.9 5275.5 5288.8 5338.8 5390.8 3938.2 5164.6 5335.9 5425.7 5446.4 5245.3 5256.6 5275.2 5289.4 5369.8 4479.2 4743.5 4941.7 5334.1 5339.0 5278.4 5290.5 5339.6 5363.2 5384.2 5635.2 5703.7 5747.1 5807.6 5810.5 5644.0 5899.9 6279.0 6321.4 6350.4 6303.9 6311.7 6314.2 6326.8 6336.5 5781.5 5841.8 5865.3 5925.4 5994.9 6366.9 6373.8 6380.8 6393.6 6394.2 6216.1 6280.9 6296.0 6301.4 6305.4 6208.5 6227.5 6286.8 6290.3 6370.6 6319.3 6336.7 6337.2 6349.9 6349.9 6312.5 6337.2 6341.2 6358.4 6409.4 4947.9 4976.0 5002.4 5034.0 5072.2 4465.5 4510.5 4539.2 4541.8 4576.2 5887.2 5937.2 5994.9 5997.0 5999.3 6248.8 6267.9 6273.3 6278.3 6322.2 5005.5 5903.5 5949.4 5975.5 5986.5 4979.0 5217.7 5582.2 5597.1 5611.4 6292.0 6325.8 6352.1 6394.2 6494.0 6261.5 6269.2 6292.0 6301.7 6317.7 5589.8 5626.4 6296.9 6390.9 6400.5 6168.9 6221.1 6287.5 6305.7 6351.8 6327.6 6327.8 6328.1 6333.7 6336.9 6106.3 6206.1 6248.0 6250.9 6264.1 6079.6 6154.1 6200.7 6207.1 6209.2 4922.4 5208.3 5557.8 6159.8 6169.6 6226.7 6241.0 6261.7 6267.9 6301.7 5892.0 6031.3 6093.8 6107.7 6108.0 19:16:35 INFO - PROCESS | 1668 | 19:16:35 INFO - PROCESS | 1668 | [#1] ext.html Cycles:180 Average:69228.29 Median:41228.81 stddev:148176.17 (359.4%) stddev-sans-first:148501.91 19:16:35 INFO - PROCESS | 1668 | Values: 467.6 489.4 524.3 530.9 562.1 28611.7 35880.8 41685.0 41749.6 41833.8 41987.0 42593.1 42598.0 42598.9 42602.6 9734.1 9970.2 9979.0 9993.4 10008.5 9794.9 9907.4 9910.4 9930.5 9931.2 9894.7 9933.4 9955.5 9958.1 9971.2 9886.0 9942.4 9943.3 9952.9 9956.2 9753.5 9810.8 9823.7 9832.0 9860.7 40039.0 40703.6 40831.4 40887.6 41051.4 3273.3 3401.5 3515.3 3590.7 3829.3 31797.7 32254.7 32623.0 32774.0 32833.3 3229.5 3333.4 3437.9 3440.1 3448.9 498722.0 503820.0 504379.0 506989.0 508418.0 59734.0 59908.0 60239.0 60408.0 60454.7 61950.7 62755.4 62837.7 62927.0 62992.4 16180.8 16381.9 16411.0 16417.4 16451.5 12689.2 12827.2 12859.0 12863.0 12864.6 14388.4 17503.6 17686.3 17693.0 17760.7 3210.6 7341.7 7505.9 7510.4 7542.0 849.5 952.8 983.5 994.5 1018.6 29981.9 30530.5 31046.2 31628.1 31658.7 971.9 1068.6 1100.2 1112.2 1116.8 15509.4 15917.8 16226.0 16271.8 16667.2 431649.0 559485.2 576054.0 578812.0 580939.0 60257.0 60432.4 60559.4 61105.0 61409.0 1380.7 1403.8 1412.3 1414.0 1415.1 25122.7 30109.3 30491.0 30574.0 31515.4 52906.7 53388.7 53519.9 53649.5 53652.9 603806.0 608396.0 608427.2 609514.0 612844.0 27050.9 27182.6 28190.6 28317.0 28799.3 39745.0 40046.8 43563.3 45925.0 48460.0 39327.0 39748.4 40280.0 41924.6 42457.6 40820.8 40890.2 41406.2 41495.0 41768.4 33617.3 38814.4 38902.0 39554.0 39712.0 34427.8 35547.0 36255.6 36331.0 36463.3 25665.6 25726.7 25739.7 26008.3 26409.6 19:16:35 INFO - PROCESS | 1668 | 19:16:35 INFO - PROCESS | 1668 | [#2] jquery.html Cycles:180 Average:34377.77 Median:4461.46 stddev:97643.87 (2188.6%) stddev-sans-first:97884.40 19:16:35 INFO - PROCESS | 1668 | Values: 369.2 373.4 376.5 380.4 399.0 19091.4 21611.1 21625.1 21767.0 21819.8 19955.8 20167.5 20487.5 20779.5 21039.9 4905.3 4989.3 5030.5 5898.6 6074.3 4117.8 4865.4 5726.3 5876.3 6370.2 6548.2 6704.3 6727.5 6728.9 6733.9 6630.4 6665.4 6688.9 6711.8 6720.6 4935.5 5717.8 5774.1 5788.3 5828.0 12625.3 13097.9 13200.9 13212.2 13219.0 2103.9 2251.9 2403.9 2777.0 2888.5 10039.6 10196.3 10248.2 12132.9 15738.3 1732.7 1789.2 1828.6 1886.0 1910.1 120693.0 127025.0 127997.0 128833.0 131653.0 11068.4 11187.4 11200.8 11211.2 11526.0 11759.9 14135.6 14226.9 14234.0 14258.5 8369.4 8567.8 8573.0 8629.5 8654.3 8457.0 8536.8 8587.4 8684.0 8727.5 4453.0 4469.9 4518.9 4532.6 4548.6 1909.8 1910.9 1929.5 3683.0 3868.1 799.4 837.5 914.4 950.8 961.3 20249.1 21007.9 21421.3 21497.6 22042.7 825.7 918.1 928.6 939.9 941.3 9988.5 10429.8 10999.4 11108.7 11264.1 468951.0 597955.0 601969.0 613592.0 614243.0 102321.0 126909.4 130435.7 131150.0 136365.0 953.7 964.2 981.1 987.3 994.9 18345.1 18577.3 18600.7 18638.2 18693.3 53361.5 53991.7 55031.4 55347.7 55948.7 79977.2 80363.0 82102.6 82109.4 83195.6 6303.3 7106.1 7301.2 7370.0 7473.7 9556.6 11273.4 12375.1 12577.6 13030.5 10528.3 10679.8 10816.1 10856.6 11015.2 9503.1 9573.4 10874.5 10891.2 11354.9 8424.5 8782.7 9675.3 9758.3 10361.3 5699.3 5895.4 6988.1 7024.4 7125.9 8367.6 9762.0 9847.9 10354.7 10485.3 19:16:35 INFO - PROCESS | 1668 | 19:16:35 INFO - PROCESS | 1668 | [#3] mootools.html Cycles:180 Average:5495.57 Median:4954.47 stddev:3075.80 (62.1%) stddev-sans-first:3068.77 19:16:35 INFO - PROCESS | 1668 | Values: 1365.2 1453.7 1622.5 1808.0 1815.0 7715.5 8148.0 8371.0 8736.6 8737.2 8502.7 8610.0 8610.1 8611.3 8626.2 2344.6 2359.0 2359.1 2361.4 2363.0 2300.9 2333.3 2351.4 2356.9 2369.2 2005.8 2254.5 2338.5 2346.6 2357.7 1922.9 2303.9 2326.1 2330.0 2335.9 2305.8 2315.1 2316.5 2318.1 2328.7 8525.4 8632.5 8666.6 8678.1 8709.9 5135.0 5256.7 5276.0 5279.4 5293.4 3445.6 3525.3 3605.4 3630.5 3641.0 943.0 943.9 1055.1 1072.4 1092.0 10798.4 11068.7 11087.4 11094.2 11125.7 4463.8 4547.9 4703.2 4718.7 4724.4 9466.0 9581.2 9591.6 9619.6 9621.8 5083.9 5093.4 5101.2 5109.8 5115.9 4323.7 4595.7 4685.8 4789.5 4798.6 3779.7 3854.2 3907.1 3948.6 3956.5 1271.7 1273.5 1281.7 1285.7 1292.3 864.2 886.1 899.8 900.5 914.5 5528.7 5540.0 5634.1 5815.7 5899.9 429.4 430.6 433.3 437.3 451.4 3610.2 3883.3 3921.5 3929.0 3995.7 10196.6 10478.7 10523.0 10549.9 10556.0 10381.1 10454.2 10517.5 10533.3 10534.4 6088.9 7943.0 7949.9 7978.1 8014.4 5537.7 5691.9 5693.8 5716.4 5791.5 8928.4 9604.1 9650.2 9685.0 9738.9 8324.3 8406.6 9583.7 9954.0 9959.5 5402.8 6592.3 7025.9 7132.3 7309.3 6768.3 8072.3 8107.8 8124.1 8157.0 7287.5 7319.9 7334.7 7335.0 7431.5 7117.8 7187.3 7188.9 7205.0 7237.9 6055.5 6699.8 6786.1 6858.9 6882.4 4892.2 4945.5 4949.8 4959.1 5045.0 3865.3 4609.7 4733.9 4811.9 4892.9 19:16:35 INFO - PROCESS | 1668 | 19:16:35 INFO - PROCESS | 1668 | [#4] prototype.html Cycles:180 Average:4437.58 Median:4808.09 stddev:1347.01 (28.0%) stddev-sans-first:1314.22 19:16:35 INFO - PROCESS | 1668 | Values: 284.3 298.4 308.8 314.2 315.0 4948.6 5403.9 5610.2 5678.2 5685.4 5482.2 5489.5 5646.4 5651.5 5667.5 3519.2 4101.5 4239.3 4274.9 4291.2 4177.1 4285.4 4302.4 4318.9 4327.2 3439.7 3712.0 3858.8 4229.2 4261.5 4244.0 4251.2 4278.9 4284.7 4287.3 3730.7 3738.7 3738.9 3746.4 3753.2 4919.2 5177.5 5292.7 5300.2 5473.3 4202.6 4222.8 4536.9 4859.8 4899.5 5159.5 5649.1 5960.2 5975.8 5997.9 3525.0 4050.3 4075.4 4091.3 4098.8 5382.7 5392.5 6259.6 6264.5 6295.5 4128.4 4130.9 4154.8 4157.7 4161.3 3739.4 3778.5 3813.1 3976.3 4040.5 4342.9 4546.2 4771.8 4794.9 4797.5 4534.8 4922.7 5934.2 5941.2 5997.4 1277.6 1278.7 1310.6 1366.2 1373.3 881.0 895.6 910.0 929.8 935.2 4754.0 5233.6 5554.4 5555.6 5578.6 5042.6 5393.5 5783.2 5939.0 6089.4 4200.8 5433.4 5471.7 5507.6 5571.3 2669.4 2715.5 2716.3 2724.9 2739.8 4941.2 5251.7 5308.8 5309.6 5393.7 4246.0 4675.5 4926.1 5090.1 5259.7 5138.7 5248.1 5326.2 5350.4 5394.9 3981.7 4464.5 4614.4 4891.7 4998.7 4260.9 4470.7 4813.4 5131.0 5137.4 4487.8 4786.3 5163.1 5186.7 5338.2 2931.3 3159.9 3374.3 3514.8 3555.2 3993.9 4108.7 4236.3 4692.2 5088.7 4774.7 4778.5 4802.8 4908.4 4933.7 4667.9 4915.9 5000.0 5021.4 5039.3 3842.4 4438.8 4679.4 4885.0 5009.6 4986.6 5975.7 5981.3 5992.2 6004.8 5532.4 5856.2 5891.4 5918.9 5949.1 19:16:35 INFO - PROCESS | 1668 | 19:16:35 INFO - PROCESS | 1668 | [#5] yui.html Cycles:180 Average:26226.67 Median:13352.15 stddev:42893.59 (321.2%) stddev-sans-first:42971.01 19:16:35 INFO - PROCESS | 1668 | Values: 673.6 710.6 735.1 767.2 795.2 15164.5 16003.5 16012.0 16076.1 16094.3 14586.3 14683.7 14722.6 14726.4 14810.8 658.5 811.8 831.0 834.2 835.5 847.5 855.2 855.8 855.9 858.8 905.7 906.4 908.7 911.7 914.6 857.9 866.8 893.5 906.0 906.3 668.7 670.2 672.0 672.1 673.4 12342.4 15015.7 15016.4 15055.7 15069.4 14558.9 14560.9 14686.2 14706.7 14845.8 11978.9 12160.6 12170.6 12319.7 12502.1 3150.3 3775.5 3993.6 4005.9 4044.4 109018.5 110851.9 110861.0 111115.0 112608.0 11135.3 11262.8 11366.1 11463.3 11682.1 23462.1 23462.1 23465.5 23466.3 23554.0 8110.1 8193.0 8226.4 8311.6 8446.2 4310.8 4399.8 4403.2 4404.3 4416.2 3621.3 3713.1 3725.5 3739.3 3753.9 663.1 667.6 670.2 681.7 685.2 531.3 537.0 546.1 638.4 652.7 8773.5 9045.7 9367.4 9595.4 9756.7 599.4 646.6 671.2 678.0 683.2 4398.8 4557.7 4709.6 4902.4 5024.2 157671.0 164738.4 165036.2 166779.0 168198.0 143208.0 146107.0 146462.7 151653.2 153947.2 117052.2 120676.4 122350.0 124104.0 126161.0 9765.8 10049.6 10068.6 10122.3 10141.9 66843.0 67487.0 68058.0 68068.7 70242.6 64921.0 67115.7 80762.2 88535.6 93964.6 13411.3 13452.5 13456.9 13937.4 14217.0 15058.0 15183.5 15300.7 15302.8 16159.9 14189.5 14235.5 14570.1 14574.5 14973.2 13521.8 14017.9 14248.0 14259.6 14369.7 13697.6 13896.9 13957.2 14118.4 14711.5 9147.6 9928.1 11322.1 11599.4 12039.8 9698.6 10253.2 11026.2 11514.8 11826.2 19:16:35 INFO - PROCESS | 1668 | -------- Summary: end -------- 19:16:35 INFO - PROCESS | 1668 | 19:16:35 INFO - TEST-INFO | 1668: exit 0 19:16:35 INFO - TEST-OK | dromaeo_css | took 1114180ms 19:16:35 INFO - TEST-START | kraken 19:16:35 INFO - operating with platform_type : mac_ 19:16:35 INFO - Initialising browser for kraken test... 19:16:35 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmpEdLRO2/profile http://localhost:49275/getInfo.html) 19:16:38 INFO - PROCESS | 1679 | __metrics Screen width/height:1600/1200 19:16:38 INFO - PROCESS | 1679 | colorDepth:24 19:16:38 INFO - PROCESS | 1679 | Browser inner width/height: 1024/689 19:16:38 INFO - PROCESS | 1679 | __metrics 19:16:38 ERROR - PROCESS | 1679 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2993: TypeError: this.tabs is undefined 19:16:39 INFO - TEST-INFO | 1679: exit 0 19:16:39 INFO - Browser initialized. 19:16:39 INFO - Running cycle 1/1 for kraken test... 19:16:39 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmpEdLRO2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:16:51 INFO - PROCESS | 1681 | RSS: Main: 239788032 19:16:51 INFO - PROCESS | 1681 | 19:16:54 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 19:16:55 INFO - PROCESS | 1681 | RSS: Main: 252633088 19:16:55 INFO - PROCESS | 1681 | 19:16:57 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 19:16:58 INFO - PROCESS | 1681 | RSS: Main: 261246976 19:16:58 INFO - PROCESS | 1681 | 19:17:02 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 19:17:02 INFO - PROCESS | 1681 | RSS: Main: 264310784 19:17:02 INFO - PROCESS | 1681 | 19:17:05 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 19:17:05 INFO - PROCESS | 1681 | RSS: Main: 266637312 19:17:05 INFO - PROCESS | 1681 | 19:17:07 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:17:08 INFO - PROCESS | 1681 | RSS: Main: 267182080 19:17:08 INFO - PROCESS | 1681 | 19:17:11 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 19:17:11 INFO - PROCESS | 1681 | RSS: Main: 267284480 19:17:11 INFO - PROCESS | 1681 | 19:17:15 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 19:17:15 INFO - PROCESS | 1681 | RSS: Main: 267599872 19:17:15 INFO - PROCESS | 1681 | 19:17:18 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 19:17:19 INFO - PROCESS | 1681 | RSS: Main: 267620352 19:17:19 INFO - PROCESS | 1681 | 19:17:21 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:17:21 INFO - PROCESS | 1681 | RSS: Main: 267395072 19:17:21 INFO - PROCESS | 1681 | 19:17:23 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:17:24 INFO - PROCESS | 1681 | RSS: Main: 267452416 19:17:24 INFO - PROCESS | 1681 | 19:17:26 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:17:27 INFO - PROCESS | 1681 | RSS: Main: 267816960 19:17:27 INFO - PROCESS | 1681 | 19:17:30 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:17:31 INFO - PROCESS | 1681 | RSS: Main: 268214272 19:17:31 INFO - PROCESS | 1681 | 19:17:34 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:17:34 INFO - PROCESS | 1681 | RSS: Main: 267968512 19:17:34 INFO - PROCESS | 1681 | 19:17:36 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 19:17:36 INFO - PROCESS | 1681 | RSS: Main: 271396864 19:17:36 INFO - PROCESS | 1681 | 19:17:36 INFO - PROCESS | 1681 | __start_tp_report 19:17:36 INFO - PROCESS | 1681 | _x_x_mozilla_page_load 19:17:36 INFO - PROCESS | 1681 | _x_x_mozilla_page_load_details 19:17:36 INFO - PROCESS | 1681 | |i|pagename|runs| 19:17:36 INFO - PROCESS | 1681 | |0;ai-astar;105;118;107;118;119;118;125;118;117;116 19:17:36 INFO - PROCESS | 1681 | |1;audio-beat-detection;168;145;155;161;111;128;131;111;162;111 19:17:36 INFO - PROCESS | 1681 | |2;audio-dft;202;213;227;186;285;277;205;192;232;281 19:17:36 INFO - PROCESS | 1681 | |3;audio-fft;72;114;105;182;64;61;59;63;155;82 19:17:36 INFO - PROCESS | 1681 | |4;audio-oscillator;71;76;130;72;95;108;126;73;72;113 19:17:36 INFO - PROCESS | 1681 | |5;imaging-gaussian-blur;89;86;89;77;137;129;92;85;89;85 19:17:36 INFO - PROCESS | 1681 | |6;imaging-darkroom;97;95;100;150;150;98;150;151;96;95 19:17:36 INFO - PROCESS | 1681 | |7;imaging-desaturate;91;88;149;142;93;92;92;91;118;146 19:17:36 INFO - PROCESS | 1681 | |8;json-parse-financial;56;69;65;59;54;50;81;88;85;57 19:17:36 INFO - PROCESS | 1681 | |9;json-stringify-tinderbox;69;71;68;68;78;78;75;71;70;71 19:17:36 INFO - PROCESS | 1681 | |10;stanford-crypto-aes;85;74;101;113;106;86;87;90;82;79 19:17:36 INFO - PROCESS | 1681 | |11;stanford-crypto-ccm;150;161;136;165;142;145;155;166;169;130 19:17:36 INFO - PROCESS | 1681 | |12;stanford-crypto-pbkdf2;155;223;233;185;172;183;177;211;165;228 19:17:36 INFO - PROCESS | 1681 | |13;stanford-crypto-sha256-iterative;73;80;88;88;87;76;69;68;68;67 19:17:36 INFO - PROCESS | 1681 | __end_tp_report 19:17:36 INFO - PROCESS | 1681 | __start_cc_report 19:17:36 INFO - PROCESS | 1681 | _x_x_mozilla_cycle_collect,1099 19:17:36 INFO - PROCESS | 1681 | __end_cc_report 19:17:36 INFO - PROCESS | 1681 | __startTimestamp1471918656869__endTimestamp 19:17:36 INFO - PROCESS | 1681 | 19:17:36 INFO - PROCESS | 1681 | ------- Summary: start ------- 19:17:36 INFO - PROCESS | 1681 | Number of tests: 14 19:17:36 INFO - PROCESS | 1681 | 19:17:36 INFO - PROCESS | 1681 | [#0] ai-astar Cycles:10 Average:116.10 Median:118.00 stddev:5.86 (5.0%) stddev-sans-first:4.64 19:17:36 INFO - PROCESS | 1681 | Values: 105.0 118.0 107.0 118.0 119.0 118.0 125.0 118.0 117.0 116.0 19:17:36 INFO - PROCESS | 1681 | 19:17:36 INFO - PROCESS | 1681 | [#1] audio-beat-detection Cycles:10 Average:138.30 Median:150.00 stddev:22.80 (15.2%) stddev-sans-first:21.50 19:17:36 INFO - PROCESS | 1681 | Values: 168.0 145.0 155.0 161.0 111.0 128.0 131.0 111.0 162.0 111.0 19:17:36 INFO - PROCESS | 1681 | 19:17:36 INFO - PROCESS | 1681 | [#2] audio-dft Cycles:10 Average:230.00 Median:229.50 stddev:37.90 (16.5%) stddev-sans-first:38.82 19:17:36 INFO - PROCESS | 1681 | Values: 202.0 213.0 227.0 186.0 285.0 277.0 205.0 192.0 232.0 281.0 19:17:36 INFO - PROCESS | 1681 | 19:17:36 INFO - PROCESS | 1681 | [#3] audio-fft Cycles:10 Average:95.70 Median:62.00 stddev:43.15 (69.6%) stddev-sans-first:44.91 19:17:36 INFO - PROCESS | 1681 | Values: 72.0 114.0 105.0 182.0 64.0 61.0 59.0 63.0 155.0 82.0 19:17:36 INFO - PROCESS | 1681 | 19:17:36 INFO - PROCESS | 1681 | [#4] audio-oscillator Cycles:10 Average:93.60 Median:72.00 stddev:23.89 (33.2%) stddev-sans-first:23.90 19:17:36 INFO - PROCESS | 1681 | Values: 71.0 76.0 130.0 72.0 95.0 108.0 126.0 73.0 72.0 113.0 19:17:36 INFO - PROCESS | 1681 | 19:17:36 INFO - PROCESS | 1681 | [#5] imaging-gaussian-blur Cycles:10 Average:95.80 Median:87.50 stddev:20.10 (23.0%) stddev-sans-first:21.17 19:17:36 INFO - PROCESS | 1681 | Values: 89.0 86.0 89.0 77.0 137.0 129.0 92.0 85.0 89.0 85.0 19:17:36 INFO - PROCESS | 1681 | 19:17:36 INFO - PROCESS | 1681 | [#6] imaging-darkroom Cycles:10 Average:118.20 Median:95.00 stddev:27.62 (29.1%) stddev-sans-first:28.21 19:17:36 INFO - PROCESS | 1681 | Values: 97.0 95.0 100.0 150.0 150.0 98.0 150.0 151.0 96.0 95.0 19:17:36 INFO - PROCESS | 1681 | 19:17:36 INFO - PROCESS | 1681 | [#7] imaging-desaturate Cycles:10 Average:110.20 Median:91.00 stddev:25.92 (28.5%) stddev-sans-first:26.55 19:17:36 INFO - PROCESS | 1681 | Values: 91.0 88.0 149.0 142.0 93.0 92.0 92.0 91.0 118.0 146.0 19:17:36 INFO - PROCESS | 1681 | 19:17:36 INFO - PROCESS | 1681 | [#8] json-parse-financial Cycles:10 Average:66.40 Median:67.00 stddev:13.78 (20.6%) stddev-sans-first:14.09 19:17:36 INFO - PROCESS | 1681 | Values: 56.0 69.0 65.0 59.0 54.0 50.0 81.0 88.0 85.0 57.0 19:17:36 INFO - PROCESS | 1681 | 19:17:36 INFO - PROCESS | 1681 | [#9] json-stringify-tinderbox Cycles:10 Average:71.90 Median:71.00 stddev:3.78 (5.3%) stddev-sans-first:3.87 19:17:36 INFO - PROCESS | 1681 | Values: 69.0 71.0 68.0 68.0 78.0 78.0 75.0 71.0 70.0 71.0 19:17:36 INFO - PROCESS | 1681 | 19:17:36 INFO - PROCESS | 1681 | [#10] stanford-crypto-aes Cycles:10 Average:90.30 Median:83.50 stddev:12.45 (14.9%) stddev-sans-first:13.06 19:17:36 INFO - PROCESS | 1681 | Values: 85.0 74.0 101.0 113.0 106.0 86.0 87.0 90.0 82.0 79.0 19:17:36 INFO - PROCESS | 1681 | 19:17:36 INFO - PROCESS | 1681 | [#11] stanford-crypto-ccm Cycles:10 Average:151.90 Median:158.00 stddev:13.49 (8.5%) stddev-sans-first:14.29 19:17:36 INFO - PROCESS | 1681 | Values: 150.0 161.0 136.0 165.0 142.0 145.0 155.0 166.0 169.0 130.0 19:17:36 INFO - PROCESS | 1681 | 19:17:36 INFO - PROCESS | 1681 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.20 Median:198.00 stddev:28.16 (14.2%) stddev-sans-first:26.26 19:17:36 INFO - PROCESS | 1681 | Values: 155.0 223.0 233.0 185.0 172.0 183.0 177.0 211.0 165.0 228.0 19:17:36 INFO - PROCESS | 1681 | 19:17:36 INFO - PROCESS | 1681 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.40 Median:78.00 stddev:8.76 (11.2%) stddev-sans-first:9.20 19:17:36 INFO - PROCESS | 1681 | Values: 73.0 80.0 88.0 88.0 87.0 76.0 69.0 68.0 68.0 67.0 19:17:36 INFO - PROCESS | 1681 | -------- Summary: end -------- 19:17:36 INFO - PROCESS | 1681 | 19:17:37 INFO - TEST-INFO | 1681: exit 0 19:17:37 INFO - TEST-OK | kraken | took 61895ms 19:17:37 INFO - SUITE-END | took 1176s 19:17:38 INFO - Completed test suite (00:19:36) 19:17:38 INFO - javascript benchmark 19:17:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7304.223647083245, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3418.179636887147, 3442.6557344265575, 3446.1040129566313, 3453.361128191283, 3458.6368431314727, 5144.17693169093, 5341.786328546854, 5694.2312787773635, 5792.68140252286, 5837.8832423351505, 5735.569832262576, 5760.646248131686, 5788.59773194125, 5830.533557315404, 5846.503047240979, 5238.895222095556, 5275.549223963402, 5288.762005709733, 5338.813141540047, 5390.78436862525, 3938.1532970411354, 5164.6384753067305, 5335.919961200585, 5425.6707106758895, 5446.428125046872, 5245.291885595434, 5256.553192978601, 5275.208718692188, 5289.418164001957, 5369.838904832822, 4479.171353299617, 4743.501932297094, 4941.678790878617, 5334.093204155303, 5338.958903013914, 5278.445763194848, 5290.497402746733, 5339.599530035263, 5363.222332761767, 5384.1923711443205, 5635.21107045014, 5703.686297253649, 5747.08046712524, 5807.593468457209, 5810.535157187415, 5644.0, 5899.908516924383, 6279.0, 6321.399467050623, 6350.396712312323, 6303.936960630381, 6311.747530098791, 6314.179156709598, 6326.778562750282, 6336.493080553545, 5781.508571771405, 5841.766329346863, 5865.2961644602065, 5925.4370834770625, 5994.940050599482, 6366.872662546816, 6373.808785736435, 6380.84047898797, 6393.552451328363, 6394.200724909387, 6216.129741836146, 6280.937190628127, 6295.968520157461, 6301.369863013662, 6305.369463053749, 6208.534359922934, 6227.532935029801, 6286.779962701284, 6290.308066112724, 6370.58591191571, 6319.304876463585, 6336.714847831767, 6337.176167098248, 6349.873002539924, 6349.936500634934, 6312.52656050798, 6337.207849018872, 6341.207349081365, 6358.3641635836975, 6409.4231519163495, 4947.925781113287, 4975.975120124376, 5002.374703162105, 5034.043531728959, 5072.163093089599, 4465.486469056035, 4510.526394728572, 4539.205639013159, 4541.8183272669385, 4576.222042252825, 5887.2052272943465, 5937.168796368512, 5994.850128746729, 5997.010493268562, 5999.280086389661, 6248.8439638667, 6267.905981410283, 6273.309935907047, 6278.277998030194, 6322.178116844872, 5005.4744251853035, 5903.527717782567, 5949.434803693643, 5975.492083172979, 5986.461218490357, 4978.979309241597, 5217.739113044333, 5582.218489411485, 5597.132444471114, 5611.410801865745, 6292.0, 6325.841853953688, 6352.142460767737, 6394.1687580614225, 6493.967530162414, 6261.530385221217, 6269.185005949197, 6292.0, 6301.684915754102, 6317.715702793477, 5589.798193388441, 5626.381098079126, 6296.905546416716, 6390.9041364378645, 6400.487960963205, 6168.876622467616, 6221.09794079865, 6287.528435367274, 6305.684715764101, 6351.777687780906, 6327.588706734047, 6327.7785277516905, 6328.114064031131, 6333.683315834282, 6336.873262534631, 6106.3283038866575, 6206.131141640264, 6247.969085100907, 6250.874982500415, 6264.060390941395, 6079.635221886524, 6154.138420621027, 6200.658963756856, 6207.131001659862, 6209.223847019122, 4922.4339200992345, 5208.296879921314, 5557.777688892399, 6159.784407545714, 6169.58099637077, 6226.688665566613, 6240.968795155995, 6261.7182226799, 6267.874642507216, 6301.716422761078, 5891.970540147272, 6031.276246850406, 6093.847653808691, 6107.725152368065, 6107.969460152671], "value": 5744.787207269541, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [467.5815145444827, 489.3833769450491, 524.3131497737963, 530.8964751873386, 562.0613575329199, 28611.713882861164, 35880.820595897014, 41685.0, 41749.58250417495, 41833.79083104584, 41986.95032624186, 42593.14813703724, 42598.0, 42598.93502662427, 42602.573974260245, 9734.12392884642, 9970.15253703436, 9978.950105249463, 9993.400395976265, 10008.549615267295, 9794.90205097947, 9907.356021858555, 9910.355826871266, 9930.45382503962, 9931.205503559733, 9894.703158905208, 9933.403995760278, 9955.452450115241, 9958.053984871425, 9971.152452041586, 9886.0, 9942.403455792602, 9943.254255930835, 9952.850707239397, 9956.153726933147, 9753.463559504224, 9810.754731131707, 9823.722916020943, 9831.950840245752, 9860.704178874574, 40039.0, 40703.59296407057, 40831.387529187086, 40887.59112408868, 41051.38422923659, 3273.2635157089703, 3401.489776533515, 3515.2793677296095, 3590.730695197871, 3829.329867273244, 31797.682023179703, 32254.677453225402, 32623.0, 32774.0, 32833.343333133445, 3229.4994275887275, 3333.4333163362044, 3437.8796742114155, 3440.139965008748, 3448.9310213795584, 498722.0, 503820.0, 504379.0, 506989.0, 508418.0, 59734.0, 59908.0, 60239.0, 60408.0, 60454.69772651108, 61950.69024654848, 62755.37244627587, 62837.68581157065, 62927.0, 62992.42518937063, 16180.838191618168, 16381.918090409472, 16411.0, 16417.42539011129, 16451.506454806375, 12689.17520361192, 12827.230366178059, 12859.0, 12863.035272354611, 12864.614061578168, 14388.42446302157, 17503.649927001643, 17686.292548297923, 17693.0, 17760.733588996176, 3210.5826242588314, 7341.743038993716, 7505.924940750522, 7510.361619262315, 7542.0, 849.4818160921833, 952.8237276103943, 983.5082458770614, 994.5275993902839, 1018.5587393837636, 29981.850090749842, 30530.54204186939, 31046.22384440407, 31628.05115846528, 31658.683413166033, 971.9271054670786, 1068.647346375694, 1100.2023532938715, 1112.165875593305, 1116.7933932222566, 15509.379624815107, 15917.761233581505, 16226.0, 16271.83728162727, 16667.166641667867, 431649.0, 559485.2025739927, 576054.0, 578812.0, 580939.0, 60257.0, 60432.395676043554, 60559.39440605538, 61105.0, 61409.0, 1380.6893448973901, 1403.8455769865307, 1412.3009110490373, 1413.985860141406, 1415.1438379780084, 25122.74877251204, 30109.298621409067, 30491.0, 30574.0, 31515.36969260556, 52906.735466322425, 53388.73305633602, 53519.859205631335, 53649.463505364445, 53652.92694146173, 603806.0, 608396.0, 608427.1572842681, 609514.0, 612844.0, 27050.864745676146, 27182.592261116493, 28190.577141343307, 28317.0, 28799.280017999718, 39745.0, 40046.799766000986, 43563.34654980241, 45925.0, 48460.03079938452, 39327.0, 39748.40377394284, 40280.0, 41924.580754192066, 42457.5754242466, 40820.79589602152, 40890.1821963565, 41406.238312514506, 41494.96262593339, 41768.373474397304, 33617.32765344631, 38814.4177837327, 38902.027449313995, 39554.01114972149, 39712.00719982023, 34427.82786086053, 35547.0, 36255.63744362628, 36331.0, 36463.27073458569, 25665.61501577515, 25726.742732572435, 25739.71301434958, 26008.34979125537, 26409.60385594179], "value": 20171.92877672066, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.2098908336161, 373.43984023964055, 376.52181729203903, 380.38947489279724, 398.9666763083613, 19091.427257182288, 21611.1306903073, 21625.134994600216, 21767.020484078213, 21819.79991100491, 19955.800441995576, 20167.49581260469, 20487.487812804688, 20779.480512987182, 21039.948002599886, 4905.288733133693, 4989.251612258154, 5030.471800460954, 5898.587098903057, 6074.271087469509, 4117.773522456264, 4865.391826021747, 5726.284214473191, 5876.265466816648, 6370.203724534434, 6548.214214294291, 6704.290405946477, 6727.495437842157, 6728.865422691543, 6733.8989915151315, 6630.370114839083, 6665.4001139897155, 6688.866222675543, 6711.798646040626, 6720.630365329905, 4935.481774413671, 5717.828465146053, 5774.105013722879, 5788.334341550733, 5828.038373668382, 12625.305608191546, 13097.869021309856, 13200.933995329962, 13212.207267563881, 13219.008574356865, 2103.8632488888175, 2251.8648881067024, 2403.8557686538866, 2776.9583456248174, 2888.508953477914, 10039.598416063349, 10196.337238079575, 10248.180145588334, 12132.939335303266, 15738.291776870068, 1732.6534693061312, 1789.2395731814056, 1828.6068495273446, 1885.9811401885943, 1910.1404368034441, 120693.0, 127024.99999999907, 127997.0, 128833.0, 131653.0, 11068.446577671246, 11187.440627968568, 11200.775984480428, 11211.215214934877, 11526.020288275591, 11759.941200293944, 14135.646608834655, 14226.857731422553, 14234.003619746518, 14258.500952466615, 8369.414141010071, 8567.785805354792, 8573.0, 8629.482231066157, 8654.264387527004, 8457.0, 8536.786580335418, 8587.441816281922, 8684.0, 8727.519986400808, 4453.0, 4469.910601787946, 4518.864434066917, 4532.637389008937, 4548.589937119504, 1909.8185672361103, 1910.92356305749, 1929.4790406590141, 3683.042408973686, 3868.139768251102, 799.4164260090167, 837.5267973594899, 914.4376208631644, 950.8162337889372, 961.2694352292308, 20249.08879100444, 21007.8949605251, 21421.250256240957, 21497.602655827635, 22042.66935995929, 825.6862392290891, 918.0635751533398, 928.5635751196927, 939.9483028433502, 941.2705153506088, 9988.450635214987, 10429.823382750368, 10999.395033273087, 11108.739570045094, 11264.098872090213, 468951.0, 597955.0, 601969.0, 613592.0, 614243.0, 102321.0, 126909.36545317399, 130435.69564304236, 131150.0, 136365.0, 953.6948176583427, 964.2237998411347, 981.087588542648, 987.2891518106951, 994.8806143262855, 18345.08274586292, 18577.349792757188, 18600.7209891849, 18638.16128274204, 18693.345732899284, 53361.466385337204, 53991.73004134954, 55031.44968550263, 55347.723261383435, 55948.72025640008, 79977.20022799697, 80363.0, 82102.58948705219, 82109.43124550142, 83195.58402208192, 6303.306636269915, 7106.147262328554, 7301.196868344372, 7369.963150184215, 7473.738419155304, 9556.56995435221, 11273.379964102052, 12375.071869609996, 12577.622671319508, 13030.54393096237, 10528.263021588415, 10679.786404271716, 10816.080633145955, 10856.620018299322, 11015.228933974866, 9503.097205765442, 9573.425594464354, 10874.456277185951, 10891.237613366988, 11354.943225283821, 8424.452410593292, 8782.736517904474, 9675.274354423587, 9758.268129890144, 10361.274710770174, 5699.2590963174525, 5895.351511333664, 6988.056612357267, 7024.4029257513685, 7125.857482850418, 8367.62345694433, 9762.0, 9847.852282215916, 10354.689359319233, 10485.318454300446], "value": 9351.283399776887, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1365.174069687839, 1453.7019910918264, 1622.5375767906148, 1808.0146320255467, 1814.97277540837, 7715.537067775937, 8147.959260203698, 8371.0, 8736.606852691628, 8737.169968852964, 8502.659893604265, 8609.956950215255, 8610.138986101403, 8611.26804221642, 8626.180512851284, 2344.624860022401, 2358.96461553076, 2359.1153317505937, 2361.4332560185517, 2363.0311572255378, 2300.907963681459, 2333.3000099970027, 2351.3651314145172, 2356.9410764730933, 2369.1826319919687, 2005.8194762471376, 2254.4927391336983, 2338.4504641409253, 2346.589346864292, 2357.693499845018, 1922.8846269223752, 2303.8853744686767, 2326.116075891159, 2329.9883500582473, 2335.929922102348, 2305.8155347572324, 2315.143396943142, 2316.548273086749, 2318.119114736406, 2328.7205535335697, 8525.360597955178, 8632.525211113387, 8666.6100025499, 8678.088800675965, 8709.912900870973, 5134.974325128388, 5256.710880901549, 5276.0, 5279.445658205871, 5293.444188360205, 3445.5693038370205, 3525.312564050011, 3605.441156620728, 3630.5296354976276, 3640.9635903641156, 942.9674506415464, 943.9103285187896, 1055.108768585958, 1072.4101744040825, 1091.9727006824814, 10798.352098874093, 11068.667939961813, 11087.445627718582, 11094.167937404727, 11125.666230013032, 4463.799129039201, 4547.886302842389, 4703.2004559225015, 4718.716876987323, 4724.43306803179, 9466.0, 9581.233501319877, 9591.616335346647, 9619.615215391446, 9621.759456013655, 5083.949160508348, 5093.439721630692, 5101.234814777738, 5109.821156259513, 5115.948840511547, 4323.718958267703, 4595.747233902168, 4685.765711714401, 4789.521047895252, 4798.616110711135, 3779.7165212609166, 3854.190619969838, 3907.0623050467684, 3948.5853985331128, 3956.4658771066115, 1271.68207948013, 1273.5160638957323, 1281.7052078022107, 1285.6914340558385, 1292.3086148910477, 864.2481041493941, 886.1227384889042, 899.8392074224308, 900.4552245891273, 914.5198770645331, 5528.668279903219, 5539.972300138554, 5634.126710359901, 5815.680137592389, 5899.852503687356, 429.35382249714604, 430.5801843202915, 433.34348462079976, 437.2588462555937, 451.3568165364343, 3610.223801882555, 3883.3301680277186, 3921.5490218624655, 3928.9607103929166, 3995.700322475826, 10196.643117491001, 10478.685639430829, 10523.0, 10549.945005499389, 10556.0, 10381.065704086668, 10454.163666906628, 10517.474126293655, 10533.262671612914, 10534.420606866543, 6088.939110608837, 7943.046834379911, 7949.920500794918, 7978.122406535276, 8014.358851292, 5537.667739935617, 5691.914621280601, 5693.800716974886, 5716.371199168005, 5791.47876691083, 8928.419652722552, 9604.135627793532, 9650.227981761582, 9684.95157524208, 9738.853917191389, 8324.334053275603, 8406.621702023545, 9583.712488625353, 9954.004599539989, 9959.452230127123, 5402.756875940513, 6592.307807680317, 7025.859482810418, 7132.286771322826, 7309.269073092861, 6768.255491895986, 8072.273495385443, 8107.797305067421, 8124.146964568635, 8157.0, 7287.526310789782, 7319.8536029280185, 7334.743283985035, 7335.046443962251, 7431.479796414199, 7117.786466406016, 7187.281271872771, 7188.892166617609, 7204.9639751800905, 7237.927620723725, 6055.45500904903, 6699.76550820719, 6786.117804685359, 6858.862822743417, 6882.380585747307, 4892.168331383745, 4945.4807245238735, 4949.802007919643, 4959.13215187348, 5045.041442125985, 3865.265599536079, 4609.700369475973, 4733.857984260478, 4811.879703007453, 4892.877678058077], "value": 4362.807446004586, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [284.28927680798, 298.3912817851583, 308.8162543286744, 314.21603100264844, 314.9511825667021, 4948.628852836043, 5403.864903377417, 5610.214569960209, 5678.176664383662, 5685.441551301915, 5482.177673349, 5489.478499542547, 5646.435356464352, 5651.491365777089, 5667.489925906668, 3519.2052053452794, 4101.466809314785, 4239.321708526644, 4274.850380236692, 4291.206126866524, 4177.07604676726, 4285.378620100084, 4302.354646802989, 4318.935215971763, 4327.242732521796, 3439.690427861492, 3711.962880371189, 3858.7684738915755, 4229.238737027334, 4261.529772228576, 4243.978780106095, 4251.2347777400055, 4278.850240241608, 4284.721493102938, 4287.2711639021445, 3730.6642402183934, 3738.719596030309, 3738.8691395801015, 3746.3818469952425, 3753.230587729509, 4919.237518184687, 5177.4822517747925, 5292.682439053669, 5300.2314664373835, 5473.28847249859, 4202.642775364097, 4222.817611068875, 4536.909261814745, 4859.757012149378, 4899.534544218293, 5159.535641792219, 5649.096144616883, 5960.165576819249, 5975.760969561213, 5997.940020599782, 3524.9823750881337, 4050.2709512287774, 4075.409065685459, 4091.3249313863175, 4098.774567398792, 5382.730863456812, 5392.460753924615, 6259.561830671855, 6264.467520260783, 6295.5059941732325, 4128.431196145506, 4130.91738165235, 4154.771487568152, 4157.729747566459, 4161.271777438936, 3739.420389839579, 3778.5465744110334, 3813.0657988792923, 3976.3240249157134, 4040.4949381327333, 4342.891427714332, 4546.2037550384, 4771.761411929389, 4794.9280760788615, 4797.472278049412, 4534.79593418297, 4922.680025798311, 5934.19888315072, 5941.22764040672, 5997.430244126801, 1277.591170825327, 1278.7122897348022, 1310.6068179546098, 1366.1734650536482, 1373.2858913364994, 880.9691660791841, 895.632790555869, 910.0035461170008, 929.8279818233649, 935.2237642756496, 4753.952460475421, 5233.581313494988, 5554.416786237387, 5555.555555555627, 5578.553715702734, 5042.647014708935, 5393.487618676219, 5783.248177736868, 5938.970305148533, 6089.351132257482, 4200.789960502024, 5433.375161856358, 5471.699056551928, 5507.5593952483705, 5571.331440227199, 2669.439417722261, 2715.5112079825426, 2716.3344980479515, 2724.910035985582, 2739.821876593084, 4941.209406494943, 5251.737413129328, 5308.761105750251, 5309.5752339812725, 5393.6763794172475, 4246.0, 4675.5324467553655, 4926.118220840848, 5090.10923088458, 5259.658122222043, 5138.691678499302, 5248.139763828723, 5326.230360212668, 5350.435547031122, 5394.921015796856, 3981.70137239714, 4464.50890402049, 4614.377059096979, 4891.728150680778, 4998.700597725087, 4260.892168036271, 4470.664700147437, 4813.422389313305, 5130.97434512825, 5137.434882163034, 4487.775611219491, 4786.282057691375, 5163.09645811989, 5186.740662966761, 5338.172583249602, 2931.296488842705, 3159.8578063986715, 3374.325134972966, 3514.8225344509874, 3555.2178520725397, 3993.9016770388375, 4108.650764685035, 4236.322188449833, 4692.178868697896, 5088.669236499763, 4774.689645173053, 4778.522147785194, 4802.758138256732, 4908.410990681141, 4933.654644174873, 4667.9263769332565, 4915.926261106158, 5000.024995125916, 5021.44764075944, 5039.3448851649055, 3842.366994027583, 4438.800253988514, 4679.438467383799, 4884.951150488591, 5009.649324547247, 4986.626003049858, 5975.7012149391485, 5981.282246130492, 5992.161097446448, 6004.819855404345, 5532.446755324436, 5856.209411729362, 5891.351951285441, 5918.915947995869, 5949.1076338549565], "value": 4014.9661985795833, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [673.5554534007555, 710.6269208665453, 735.0848193998471, 767.202109805802, 795.2484901767829, 15164.469243576477, 16003.519894403187, 16012.0, 16076.115813630246, 16094.275757590907, 14586.270686465687, 14683.706325873503, 14722.63193420165, 14726.410943562272, 14810.777838332408, 658.4830907737427, 811.8498077855616, 831.0235473318857, 834.1991687979547, 835.5404527509845, 847.4745657692208, 855.1747563601124, 855.7518319687283, 855.8844555984924, 858.8368210040104, 905.6739573753443, 906.4470672889532, 908.736466424736, 911.7082533589254, 914.6067191107821, 857.8670306102533, 866.7746385939638, 893.4683863101412, 905.9909400905973, 906.3429013964889, 668.7458765669063, 670.229236378169, 672.0054319607009, 672.1128110893623, 673.3703986772383, 12342.444589993382, 15015.699686006219, 15016.399344026335, 15055.698886022219, 15069.397224111022, 14558.92720536401, 14560.927195364062, 14686.192259425727, 14706.70586588273, 14845.777313340308, 11978.880211197864, 12160.635180944497, 12170.57402990891, 12319.69200769988, 12502.124851260416, 3150.306932474875, 3775.528058992626, 3993.6206060424215, 4005.8798236052967, 4044.3529035354495, 109018.45490772574, 110851.89148108497, 110861.0, 111115.0, 112608.0, 11135.276207046516, 11262.831057534144, 11366.09071274296, 11463.31220126795, 11682.12384071198, 23462.061517539107, 23462.06151753945, 23465.530689386458, 23466.296011119695, 23554.0, 8110.067342255598, 8193.0, 8226.383021273428, 8311.584420778936, 8446.239838414573, 4310.827566897289, 4399.846005389796, 4403.185410699031, 4404.2512772828695, 4416.205083085011, 3621.348157331706, 3713.053171441265, 3725.515682961198, 3739.270842185802, 3753.887383378503, 663.1213641924469, 667.6361383046251, 670.1723371636026, 681.6830173969029, 685.2428066986008, 531.3119510234255, 536.9597280203922, 546.0853071105881, 638.4349850382353, 652.6573548886872, 8773.473591584525, 9045.747461245279, 9367.407540718094, 9595.42427454355, 9756.653615502744, 599.4455129005739, 646.5894157210112, 671.1778071862028, 677.9728711002834, 683.1597135523235, 4398.812370476613, 4557.6581756368405, 4709.62323014158, 4902.3872015998, 5024.171011783088, 157671.0, 164738.3526164747, 165036.17481912512, 166779.0, 168198.0, 143208.0, 146107.0, 146462.73415703594, 151653.24173379064, 153947.23026385022, 117052.24421633683, 120676.39661801636, 122350.0, 124104.0, 126161.0, 9765.804683906425, 10049.648262310784, 10068.597256109673, 10122.291439599157, 10141.949290253797, 66842.99735504069, 67487.0, 68058.0, 68068.6596567014, 70242.64878675574, 64921.0, 67115.66442167757, 80762.19237807547, 88535.55732221298, 93964.59053114346, 13411.262380569162, 13452.5291614793, 13456.932715336752, 13937.372818223406, 14217.004809663224, 15058.0, 15183.468578599695, 15300.693986120437, 15302.846971530142, 16159.919200403923, 14189.503367382093, 14235.50175743844, 14570.05294655844, 14574.489892853699, 14973.176475293963, 13521.797173042609, 14017.929910350384, 14248.0, 14259.64350891236, 14369.712605748036, 13697.589072327846, 13896.86103138996, 13957.232352220726, 14118.43526258938, 14711.485098021518, 9147.588358524015, 9928.056834600702, 11322.071061942426, 11599.362035088152, 12039.819402708792, 9698.6120555177, 10253.179745620482, 11026.173037022416, 11514.769704605693, 11826.172167948162], "value": 7999.356095762345, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1648.0000000000005, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 118.0, 107.0, 118.0, 119.0, 118.0, 125.0, 118.0, 117.0, 116.0], "value": 116.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 145.0, 155.0, 161.0, 111.0, 128.0, 131.0, 111.0, 162.0, 111.0], "value": 138.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 213.0, 227.0, 186.0, 285.0, 277.0, 205.0, 192.0, 232.0, 281.0], "value": 230.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 114.0, 105.0, 182.0, 64.0, 61.0, 59.0, 63.0, 155.0, 82.0], "value": 95.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 76.0, 130.0, 72.0, 95.0, 108.0, 126.0, 73.0, 72.0, 113.0], "value": 93.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 77.0, 137.0, 129.0, 92.0, 85.0, 89.0, 85.0], "value": 95.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 95.0, 100.0, 150.0, 150.0, 98.0, 150.0, 151.0, 96.0, 95.0], "value": 118.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 149.0, 142.0, 93.0, 92.0, 92.0, 91.0, 118.0, 146.0], "value": 110.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 69.0, 65.0, 59.0, 54.0, 50.0, 81.0, 88.0, 85.0, 57.0], "value": 66.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 68.0, 68.0, 78.0, 78.0, 75.0, 71.0, 70.0, 71.0], "value": 71.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 74.0, 101.0, 113.0, 106.0, 86.0, 87.0, 90.0, 82.0, 79.0], "value": 90.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 161.0, 136.0, 165.0, 142.0, 145.0, 155.0, 166.0, 169.0, 130.0], "value": 151.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 223.0, 233.0, 185.0, 172.0, 183.0, 177.0, 211.0, 165.0, 228.0], "value": 193.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 80.0, 88.0, 88.0, 87.0, 76.0, 69.0, 68.0, 68.0, 67.0], "value": 76.4, "unit": "score"}]}]} 19:17:38 INFO - Return code: 0 19:17:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:17:38 ERROR - # TBPL SUCCESS # 19:17:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:17:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:17:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:17:38 INFO - 2016-08-22 19:17:38.215 screenresolution[1686:10446] starting screenresolution argv=screenresolution get 19:17:38 INFO - 2016-08-22 19:17:38.217 screenresolution[1686:10446] Display 0: 1600x1200x32@60 19:17:38 INFO - 2016-08-22 19:17:38.223 screenresolution[1687:10449] starting screenresolution argv=screenresolution list 19:17:38 INFO - Available Modes on Display 0 19:17:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:17:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:17:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:17:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:17:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:17:38 INFO - Intel Iris: 19:17:38 INFO - Chipset Model: Intel Iris 19:17:38 INFO - Type: GPU 19:17:38 INFO - Bus: Built-In 19:17:38 INFO - VRAM (Dynamic, Max): 1536 MB 19:17:38 INFO - Vendor: Intel (0x8086) 19:17:38 INFO - Device ID: 0x0a2e 19:17:38 INFO - Revision ID: 0x0009 19:17:38 INFO - Displays: 19:17:38 INFO - Display: 19:17:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:17:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:17:38 INFO - Display Serial Number: 1600x1200 60 19:17:38 INFO - Main Display: Yes 19:17:38 INFO - Mirror: Off 19:17:38 INFO - Online: Yes 19:17:38 INFO - Rotation: Supported 19:17:38 INFO - Return code: 0 19:17:38 INFO - Running post-action listener: _resource_record_post_action 19:17:38 INFO - [mozharness: 2016-08-23 02:17:38.460428Z] Finished run-tests step (success) 19:17:38 INFO - Running post-run listener: _resource_record_post_run 19:17:38 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 37286912; Write bytes: 705449984; Read time: 245; Write time: 2024 19:17:38 INFO - TinderboxPrint: CPU usage
54.6% 19:17:38 INFO - TinderboxPrint: I/O read bytes / time
37,286,912 / 245 19:17:38 INFO - TinderboxPrint: I/O write bytes / time
705,449,984 / 2,024 19:17:38 INFO - TinderboxPrint: CPU idle
2,202.2 (45.4%) 19:17:38 INFO - TinderboxPrint: CPU system
980.4 (20.2%) 19:17:38 INFO - TinderboxPrint: CPU user
1,663.0 (34.3%) 19:17:38 INFO - TinderboxPrint: Swap in / out
442,855,424 / 0 19:17:38 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 397658624; Write bytes: 391993344; Read time: 29639; Write time: 822 19:17:38 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 35152896; Write bytes: 295454720; Read time: 220; Write time: 1155 19:17:38 INFO - Running post-run listener: _upload_blobber_files 19:17:38 INFO - Blob upload gear active. 19:17:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:17:38 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 19:17:38 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:17:38 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:17:39 INFO - (blobuploader) - INFO - Open directory for files ... 19:17:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:17:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:17:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:17:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:17:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:17:40 INFO - (blobuploader) - INFO - Done attempting. 19:17:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:17:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:17:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:17:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:17:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:17:41 INFO - (blobuploader) - INFO - Done attempting. 19:17:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:17:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:17:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:17:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:17:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:17:44 INFO - (blobuploader) - INFO - Done attempting. 19:17:44 INFO - (blobuploader) - INFO - Iteration through files over. 19:17:44 INFO - Return code: 0 19:17:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:17:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:17:44 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a383afe11dae23a781ec53cca60c69d1fac8ecfc48a4635345940d2465e82028da48fd02bbb70eb48dd38282ff0f73b8b07e1c0372d97047e2d423f9c31b111f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/340d22bf52702aebb600598c884e7ef91cc986b024b3743064d2aa2f8338a08f08d4ebd0ae839d158383058e4820e6d806444a0bced95718f73a3a754fcafe65", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f630026441a8d2bed0758d7f563d2a2e8c1646617367e01e6038c074ed52e8fe2f711052101688ce4b778c9ff2335b025ed96ba87bbd4ce450dac6fbd5305daf"} 19:17:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:17:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:17:44 INFO - Contents: 19:17:44 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a383afe11dae23a781ec53cca60c69d1fac8ecfc48a4635345940d2465e82028da48fd02bbb70eb48dd38282ff0f73b8b07e1c0372d97047e2d423f9c31b111f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/340d22bf52702aebb600598c884e7ef91cc986b024b3743064d2aa2f8338a08f08d4ebd0ae839d158383058e4820e6d806444a0bced95718f73a3a754fcafe65", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f630026441a8d2bed0758d7f563d2a2e8c1646617367e01e6038c074ed52e8fe2f711052101688ce4b778c9ff2335b025ed96ba87bbd4ce450dac6fbd5305daf"} 19:17:44 INFO - Running post-run listener: copy_logs_to_upload_dir 19:17:44 INFO - Copying logs to upload dir... 19:17:44 INFO - mkdir: /builds/slave/test/build/upload/logs 19:17:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.789900 ========= master_lag: 0.18 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-08-22 19:17:44.249854) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 19:17:44.253082) ========= 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.BucL5U3T8g/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.Jez0eL2Hgh/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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/Autoland/sha512/a383afe11dae23a781ec53cca60c69d1fac8ecfc48a4635345940d2465e82028da48fd02bbb70eb48dd38282ff0f73b8b07e1c0372d97047e2d423f9c31b111f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/340d22bf52702aebb600598c884e7ef91cc986b024b3743064d2aa2f8338a08f08d4ebd0ae839d158383058e4820e6d806444a0bced95718f73a3a754fcafe65", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f630026441a8d2bed0758d7f563d2a2e8c1646617367e01e6038c074ed52e8fe2f711052101688ce4b778c9ff2335b025ed96ba87bbd4ce450dac6fbd5305daf"} build_url:https://queue.taskcluster.net/v1/task/FdtP9adVRQqQofju-mdcqg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010780 build_url: 'https://queue.taskcluster.net/v1/task/FdtP9adVRQqQofju-mdcqg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a383afe11dae23a781ec53cca60c69d1fac8ecfc48a4635345940d2465e82028da48fd02bbb70eb48dd38282ff0f73b8b07e1c0372d97047e2d423f9c31b111f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/340d22bf52702aebb600598c884e7ef91cc986b024b3743064d2aa2f8338a08f08d4ebd0ae839d158383058e4820e6d806444a0bced95718f73a3a754fcafe65", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f630026441a8d2bed0758d7f563d2a2e8c1646617367e01e6038c074ed52e8fe2f711052101688ce4b778c9ff2335b025ed96ba87bbd4ce450dac6fbd5305daf"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 19:17:44.289433) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 19:17:44.290015) ========= 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.BucL5U3T8g/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.Jez0eL2Hgh/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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.005071 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 19:17:44.339584) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 19:17:44.339937) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 19:17:44.340290) ========= ========= Total master_lag: 0.32 =========