builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0019 starttime: 1472146868.69 results: success (0) buildid: 20160825082726 builduid: a092912335c7445f86c67736e8356bad revision: 91b6e270107a96d5c5c6045b76c59c3aafe18a90 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-25 10:41:08.690032) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-25 10:41:08.690473) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-25 10:41:08.690744) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-25 10:41:08.715060) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 10:41:08.715382) ========= 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.lVv7xIwCMi/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.nb0pUNAauD/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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-25 10:41:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 102M=0s 2016-08-25 10:41:08 (102 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.159862 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 10:41:08.898583) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 10:41:08.898893) ========= 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.lVv7xIwCMi/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.nb0pUNAauD/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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.036912 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 10:41:08.956925) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-25 10:41:08.957329) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 91b6e270107a96d5c5c6045b76c59c3aafe18a90 --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 91b6e270107a96d5c5c6045b76c59c3aafe18a90 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lVv7xIwCMi/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.nb0pUNAauD/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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-25 10:41:09,034 truncating revision to first 12 chars 2016-08-25 10:41:09,034 Setting DEBUG logging. 2016-08-25 10:41:09,034 attempt 1/10 2016-08-25 10:41:09,034 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/91b6e270107a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-25 10:41:10,508 unpacking tar archive at: autoland-91b6e270107a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.725269 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-25 10:41:10.705324) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-25 10:41:10.705679) ========= script_repo_revision: 91b6e270107a96d5c5c6045b76c59c3aafe18a90 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-25 10:41:10.706101) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-25 10:41:10.706369) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-25 10:41:10.725203) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 12 secs) (at 2016-08-25 10:41:10.725536) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.lVv7xIwCMi/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.nb0pUNAauD/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 10:41:10 INFO - MultiFileLogger online at 20160825 10:41:10 in /builds/slave/test 10:41:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 10:41:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:41:10 INFO - {'append_to_log': False, 10:41:10 INFO - 'base_work_dir': '/builds/slave/test', 10:41:10 INFO - 'blob_upload_branch': 'Autoland', 10:41:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:41:10 INFO - 'branch': 'Autoland', 10:41:10 INFO - 'buildbot_json_path': 'buildprops.json', 10:41:10 INFO - 'config_files': ('talos/mac_config.py',), 10:41:10 INFO - 'default_actions': ('clobber', 10:41:10 INFO - 'read-buildbot-config', 10:41:10 INFO - 'download-and-extract', 10:41:10 INFO - 'populate-webroot', 10:41:10 INFO - 'create-virtualenv', 10:41:10 INFO - 'install', 10:41:10 INFO - 'run-tests'), 10:41:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:41:10 INFO - 'download_minidump_stackwalk': True, 10:41:10 INFO - 'download_symbols': 'ondemand', 10:41:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:41:10 INFO - 'tooltool.py': '/tools/tooltool.py', 10:41:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:41:10 INFO - '/tools/misc-python/virtualenv.py')}, 10:41:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:41:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:41:10 INFO - 'installer_path': 'installer.exe', 10:41:10 INFO - 'log_level': 'info', 10:41:10 INFO - 'log_name': 'talos', 10:41:10 INFO - 'log_to_console': True, 10:41:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:41:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:41:10 INFO - 'opt_config_files': (), 10:41:10 INFO - 'pip_index': False, 10:41:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:41:10 INFO - 'cmd': ('bash', 10:41:10 INFO - '-c', 10:41:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:41:10 INFO - 'enabled': True, 10:41:10 INFO - 'halt_on_failure': False, 10:41:10 INFO - 'name': 'check_screen_resolution'},), 10:41:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:41:10 INFO - 'cmd': ('bash', 10:41:10 INFO - '-c', 10:41:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:41:10 INFO - 'enabled': True, 10:41:10 INFO - 'halt_on_failure': False, 10:41:10 INFO - 'name': 'check_screen_resolution'},), 10:41:10 INFO - 'run_cmd_checks_enabled': True, 10:41:10 INFO - 'sps_profile': False, 10:41:10 INFO - 'sps_profile_interval': 0, 10:41:10 INFO - 'suite': 'dromaeojs', 10:41:10 INFO - 'system_bits': '32', 10:41:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:41:10 INFO - 'title': 't-yosemite-r7-0019', 10:41:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:41:10 INFO - 'use_talos_json': True, 10:41:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:41:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:41:10 INFO - 'work_dir': 'build'} 10:41:10 INFO - [mozharness: 2016-08-25 17:41:10.924414Z] Running clobber step. 10:41:10 INFO - Running pre-action listener: _resource_record_pre_action 10:41:10 INFO - Running main action method: clobber 10:41:10 INFO - rmtree: /builds/slave/test/build 10:41:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:41:13 INFO - Running post-action listener: _resource_record_post_action 10:41:13 INFO - [mozharness: 2016-08-25 17:41:13.382623Z] Finished clobber step (success) 10:41:13 INFO - [mozharness: 2016-08-25 17:41:13.382791Z] Running read-buildbot-config step. 10:41:13 INFO - Running pre-action listener: _resource_record_pre_action 10:41:13 INFO - Running main action method: read_buildbot_config 10:41:13 INFO - Using buildbot properties: 10:41:13 INFO - { 10:41:13 INFO - "project": "", 10:41:13 INFO - "product": "firefox", 10:41:13 INFO - "script_repo_revision": "production", 10:41:13 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 10:41:13 INFO - "repository": "", 10:41:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 10:41:13 INFO - "buildid": "20160825082726", 10:41:13 INFO - "pgo_build": "False", 10:41:13 INFO - "basedir": "/builds/slave/test", 10:41:13 INFO - "buildnumber": 200, 10:41:13 INFO - "slavename": "t-yosemite-r7-0019", 10:41:13 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 10:41:13 INFO - "platform": "yosemite_r7", 10:41:13 INFO - "scheduler": "tests-autoland-macosx64-talos", 10:41:13 INFO - "branch": "autoland", 10:41:13 INFO - "repo_path": "integration/autoland", 10:41:13 INFO - "revision": "91b6e270107a96d5c5c6045b76c59c3aafe18a90", 10:41:13 INFO - "stage_platform": "macosx64", 10:41:13 INFO - "builduid": "a092912335c7445f86c67736e8356bad", 10:41:13 INFO - "slavebuilddir": "test" 10:41:13 INFO - } 10:41:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/WQW9A1KOQSegELCb_il-vA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 10:41:13 INFO - Running post-action listener: _resource_record_post_action 10:41:13 INFO - [mozharness: 2016-08-25 17:41:13.386066Z] Finished read-buildbot-config step (success) 10:41:13 INFO - [mozharness: 2016-08-25 17:41:13.386190Z] Running download-and-extract step. 10:41:13 INFO - Running pre-action listener: _resource_record_pre_action 10:41:13 INFO - Running main action method: download_and_extract 10:41:13 INFO - mkdir: /builds/slave/test/build/tests 10:41:13 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')]} 10:41:13 INFO - trying https://queue.taskcluster.net/v1/task/WQW9A1KOQSegELCb_il-vA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 10:41:13 INFO - Downloading https://queue.taskcluster.net/v1/task/WQW9A1KOQSegELCb_il-vA/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 10:41:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WQW9A1KOQSegELCb_il-vA/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 10:41:14 INFO - Downloaded 1199 bytes. 10:41:14 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 10:41:14 INFO - Using the following test package requirements: 10:41:14 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 10:41:14 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:41:14 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 10:41:14 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:41:14 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 10:41:14 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:41:14 INFO - u'jsshell-mac.zip'], 10:41:14 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:41:14 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 10:41:14 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 10:41:14 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:41:14 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 10:41:14 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:41:14 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 10:41:14 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:41:14 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 10:41:14 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:41:14 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 10:41:14 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:41:14 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')]} 10:41:14 INFO - trying https://queue.taskcluster.net/v1/task/WQW9A1KOQSegELCb_il-vA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:41:14 INFO - Downloading https://queue.taskcluster.net/v1/task/WQW9A1KOQSegELCb_il-vA/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 10:41:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WQW9A1KOQSegELCb_il-vA/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 10:41:15 INFO - Downloaded 16346821 bytes. 10:41:15 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 10:41:22 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 10:41:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:22 INFO - trying https://queue.taskcluster.net/v1/task/WQW9A1KOQSegELCb_il-vA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:41:22 INFO - Downloading https://queue.taskcluster.net/v1/task/WQW9A1KOQSegELCb_il-vA/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 10:41:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WQW9A1KOQSegELCb_il-vA/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 10:41:23 INFO - Downloaded 16346821 bytes. 10:41:23 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 10:41:30 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')]} 10:41:30 INFO - trying https://queue.taskcluster.net/v1/task/WQW9A1KOQSegELCb_il-vA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 10:41:30 INFO - Downloading https://queue.taskcluster.net/v1/task/WQW9A1KOQSegELCb_il-vA/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 10:41:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WQW9A1KOQSegELCb_il-vA/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 10:41:31 INFO - Downloaded 14413981 bytes. 10:41:31 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 10:41:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:31 INFO - trying https://queue.taskcluster.net/v1/task/WQW9A1KOQSegELCb_il-vA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:41:31 INFO - Downloading https://queue.taskcluster.net/v1/task/WQW9A1KOQSegELCb_il-vA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 10:41:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WQW9A1KOQSegELCb_il-vA/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 10:41:34 INFO - Downloaded 111514728 bytes. 10:41:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WQW9A1KOQSegELCb_il-vA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:41:34 INFO - mkdir: /builds/slave/test/properties 10:41:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:41:34 INFO - Writing to file /builds/slave/test/properties/build_url 10:41:34 INFO - Contents: 10:41:34 INFO - build_url:https://queue.taskcluster.net/v1/task/WQW9A1KOQSegELCb_il-vA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:41:35 INFO - Running post-action listener: _resource_record_post_action 10:41:35 INFO - Running post-action listener: set_extra_try_arguments 10:41:35 INFO - [mozharness: 2016-08-25 17:41:35.300271Z] Finished download-and-extract step (success) 10:41:35 INFO - [mozharness: 2016-08-25 17:41:35.300384Z] Running populate-webroot step. 10:41:35 INFO - Running pre-action listener: _resource_record_pre_action 10:41:35 INFO - Running main action method: populate_webroot 10:41:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:41:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:41:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:41:35 INFO - '--tppagecycles', 10:41:35 INFO - '7'], 10:41:35 INFO - 'tests': ['tsvgm']}}, 10:41:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:41:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:41:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:41:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:41:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:41:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:41:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:35 INFO - 'talos_options': ['--disable-e10s'], 10:41:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:41:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:41:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:35 INFO - 'talos_options': ['--disable-e10s'], 10:41:35 INFO - 'tests': ['damp', 'tps']}, 10:41:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:35 INFO - 'tests': ['damp', 'tps']}, 10:41:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:41:35 INFO - 'tests': ['dromaeo_dom']}, 10:41:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:41:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:41:35 INFO - 'tests': ['basic_compositor_video']}, 10:41:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:41:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:41:35 INFO - 'tests': ['a11yr', 10:41:35 INFO - 'ts_paint', 10:41:35 INFO - 'tpaint', 10:41:35 INFO - 'sessionrestore', 10:41:35 INFO - 'sessionrestore_no_auto_restore', 10:41:35 INFO - 'tabpaint']}, 10:41:35 INFO - 'other-e10s': {'tests': ['a11yr', 10:41:35 INFO - 'ts_paint', 10:41:35 INFO - 'tpaint', 10:41:35 INFO - 'sessionrestore', 10:41:35 INFO - 'sessionrestore_no_auto_restore', 10:41:35 INFO - 'tabpaint']}, 10:41:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:41:35 INFO - 'ts_paint', 10:41:35 INFO - 'tpaint', 10:41:35 INFO - 'sessionrestore', 10:41:35 INFO - 'sessionrestore_no_auto_restore', 10:41:35 INFO - 'tabpaint']}, 10:41:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:41:35 INFO - 'ts_paint', 10:41:35 INFO - 'tpaint', 10:41:35 INFO - 'sessionrestore', 10:41:35 INFO - 'sessionrestore_no_auto_restore', 10:41:35 INFO - 'tabpaint']}, 10:41:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:41:35 INFO - 'tests': ['a11yr', 10:41:35 INFO - 'ts_paint', 10:41:35 INFO - 'tpaint', 10:41:35 INFO - 'sessionrestore', 10:41:35 INFO - 'sessionrestore_no_auto_restore', 10:41:35 INFO - 'tabpaint']}, 10:41:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:41:35 INFO - 'tests': ['a11yr', 10:41:35 INFO - 'ts_paint', 10:41:35 INFO - 'tpaint', 10:41:35 INFO - 'sessionrestore', 10:41:35 INFO - 'sessionrestore_no_auto_restore', 10:41:35 INFO - 'tabpaint']}, 10:41:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:41:35 INFO - 'tests': ['tsvgx', 10:41:35 INFO - 'tsvgr_opacity', 10:41:35 INFO - 'tart', 10:41:35 INFO - 'tscrollx', 10:41:35 INFO - 'cart']}, 10:41:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:41:35 INFO - 'tsvgr_opacity', 10:41:35 INFO - 'tart', 10:41:35 INFO - 'tscrollx', 10:41:35 INFO - 'cart']}, 10:41:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:35 INFO - 'talos_options': ['--disable-e10s'], 10:41:35 INFO - 'tests': ['tp5o']}, 10:41:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:35 INFO - 'tests': ['tp5o']}, 10:41:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:35 INFO - 'talos_options': ['--disable-e10s', 10:41:35 INFO - '--xperf_path', 10:41:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:41:35 INFO - 'tests': ['tp5n']}, 10:41:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:35 INFO - 'talos_options': ['--xperf_path', 10:41:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:41:35 INFO - 'tests': ['tp5n']}}, 10:41:35 INFO - 'talos.zip': {'path': '', 10:41:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:41:35 INFO - Running post-action listener: _resource_record_post_action 10:41:35 INFO - [mozharness: 2016-08-25 17:41:35.319041Z] Finished populate-webroot step (success) 10:41:35 INFO - [mozharness: 2016-08-25 17:41:35.319171Z] Running create-virtualenv step. 10:41:35 INFO - Running pre-action listener: _resource_record_pre_action 10:41:35 INFO - Running main action method: create_virtualenv 10:41:35 INFO - Creating virtualenv /builds/slave/test/build/venv 10:41:35 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 10:41:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:41:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:41:35 INFO - Using real prefix '/tools/python27' 10:41:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:41:36 INFO - Installing distribute.............................................................................................................................................................................................done. 10:41:38 INFO - Installing pip.................done. 10:41:39 INFO - Return code: 0 10:41:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:41:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f1d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f266618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a5a4b0570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f135d30>, '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 0x10ee9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f12db20>, '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.lVv7xIwCMi/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.nb0pUNAauD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 10:41:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:41:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:41:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lVv7xIwCMi/Render', 10:41:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:39 INFO - 'HOME': '/Users/cltbld', 10:41:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:39 INFO - 'LOGNAME': 'cltbld', 10:41:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:39 INFO - 'MOZ_NO_REMOTE': '1', 10:41:39 INFO - 'NO_EM_RESTART': '1', 10:41:39 INFO - 'PAGER': '/bin/cat', 10:41:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:39 INFO - 'PWD': '/builds/slave/test', 10:41:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:39 INFO - 'SHELL': '/bin/bash', 10:41:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nb0pUNAauD/Listeners', 10:41:39 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 10:41:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:39 INFO - 'USER': 'cltbld', 10:41:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:39 INFO - 'XPC_FLAGS': '0x0', 10:41:39 INFO - 'XPC_SERVICE_NAME': '0', 10:41:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:39 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 10:41:39 INFO - Cleaning up... 10:41:39 INFO - Return code: 0 10:41:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:41:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f1d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f266618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a5a4b0570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f135d30>, '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 0x10ee9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f12db20>, '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.lVv7xIwCMi/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.nb0pUNAauD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 10:41:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:41:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:41:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lVv7xIwCMi/Render', 10:41:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:39 INFO - 'HOME': '/Users/cltbld', 10:41:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:39 INFO - 'LOGNAME': 'cltbld', 10:41:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:39 INFO - 'MOZ_NO_REMOTE': '1', 10:41:39 INFO - 'NO_EM_RESTART': '1', 10:41:39 INFO - 'PAGER': '/bin/cat', 10:41:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:39 INFO - 'PWD': '/builds/slave/test', 10:41:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:39 INFO - 'SHELL': '/bin/bash', 10:41:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nb0pUNAauD/Listeners', 10:41:39 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 10:41:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:39 INFO - 'USER': 'cltbld', 10:41:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:39 INFO - 'XPC_FLAGS': '0x0', 10:41:39 INFO - 'XPC_SERVICE_NAME': '0', 10:41:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:39 INFO - Downloading/unpacking psutil>=3.1.1 10:41:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:41:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:41:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:41:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:41:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:41:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:41:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:41:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:41:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:41:42 INFO - Installing collected packages: psutil 10:41:42 INFO - Running setup.py install for psutil 10:41:43 INFO - building 'psutil._psutil_osx' extension 10:41:43 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 10:41:43 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 10:41:43 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 10:41:43 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 10:41:43 INFO - building 'psutil._psutil_posix' extension 10:41:43 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 10:41:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:41:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:41:43 INFO - ^ 10:41:43 INFO - 1 warning generated. 10:41:43 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 10:41:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:41:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:41:44 INFO - Successfully installed psutil 10:41:44 INFO - Cleaning up... 10:41:44 INFO - Return code: 0 10:41:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:41:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f1d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f266618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a5a4b0570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f135d30>, '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 0x10ee9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f12db20>, '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.lVv7xIwCMi/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.nb0pUNAauD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 10:41:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:41:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:41:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lVv7xIwCMi/Render', 10:41:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:44 INFO - 'HOME': '/Users/cltbld', 10:41:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:44 INFO - 'LOGNAME': 'cltbld', 10:41:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:44 INFO - 'MOZ_NO_REMOTE': '1', 10:41:44 INFO - 'NO_EM_RESTART': '1', 10:41:44 INFO - 'PAGER': '/bin/cat', 10:41:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:44 INFO - 'PWD': '/builds/slave/test', 10:41:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:44 INFO - 'SHELL': '/bin/bash', 10:41:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nb0pUNAauD/Listeners', 10:41:44 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 10:41:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:44 INFO - 'USER': 'cltbld', 10:41:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:44 INFO - 'XPC_FLAGS': '0x0', 10:41:44 INFO - 'XPC_SERVICE_NAME': '0', 10:41:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:41:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:41:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:41:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:41:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:41:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:41:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:41:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:41:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:41:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:41:46 INFO - Installing collected packages: mozsystemmonitor 10:41:46 INFO - Running setup.py install for mozsystemmonitor 10:41:47 INFO - Successfully installed mozsystemmonitor 10:41:47 INFO - Cleaning up... 10:41:47 INFO - Return code: 0 10:41:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:41:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:47 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 0x10f1d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f266618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a5a4b0570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f135d30>, '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 0x10ee9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f12db20>, '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.lVv7xIwCMi/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.nb0pUNAauD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 10:41:47 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 10:41:47 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 10:41:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lVv7xIwCMi/Render', 10:41:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:47 INFO - 'HOME': '/Users/cltbld', 10:41:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:47 INFO - 'LOGNAME': 'cltbld', 10:41:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:47 INFO - 'MOZ_NO_REMOTE': '1', 10:41:47 INFO - 'NO_EM_RESTART': '1', 10:41:47 INFO - 'PAGER': '/bin/cat', 10:41:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:47 INFO - 'PWD': '/builds/slave/test', 10:41:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:47 INFO - 'SHELL': '/bin/bash', 10:41:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nb0pUNAauD/Listeners', 10:41:47 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 10:41:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:47 INFO - 'USER': 'cltbld', 10:41:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:47 INFO - 'XPC_FLAGS': '0x0', 10:41:47 INFO - 'XPC_SERVICE_NAME': '0', 10:41:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:47 INFO - Downloading/unpacking jsonschema==2.5.1 10:41:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:41:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:41:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:41:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:41:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:41:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:41:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:41:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:41:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:41:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:41:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:41:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:41:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:41:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:41:50 INFO - Downloading functools32-3.2.3-2.tar.gz 10:41:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:41:50 INFO - warning: no files found matching '*.txt' 10:41:50 INFO - no previously-included directories found matching 'build' 10:41:50 INFO - no previously-included directories found matching 'dist' 10:41:50 INFO - no previously-included directories found matching '.git*' 10:41:50 INFO - Installing collected packages: jsonschema, functools32 10:41:50 INFO - Running setup.py install for functools32 10:41:50 INFO - warning: no files found matching '*.txt' 10:41:50 INFO - no previously-included directories found matching 'build' 10:41:50 INFO - no previously-included directories found matching 'dist' 10:41:50 INFO - no previously-included directories found matching '.git*' 10:41:50 INFO - Successfully installed jsonschema functools32 10:41:50 INFO - Cleaning up... 10:41:50 INFO - Return code: 0 10:41:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:41: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')]} 10:41:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41: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')]} 10:41:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f1d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f266618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a5a4b0570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f135d30>, '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 0x10ee9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f12db20>, '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.lVv7xIwCMi/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.nb0pUNAauD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 10:41:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:41:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:41:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lVv7xIwCMi/Render', 10:41:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:50 INFO - 'HOME': '/Users/cltbld', 10:41:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:50 INFO - 'LOGNAME': 'cltbld', 10:41:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:50 INFO - 'MOZ_NO_REMOTE': '1', 10:41:50 INFO - 'NO_EM_RESTART': '1', 10:41:50 INFO - 'PAGER': '/bin/cat', 10:41:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:50 INFO - 'PWD': '/builds/slave/test', 10:41:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:50 INFO - 'SHELL': '/bin/bash', 10:41:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nb0pUNAauD/Listeners', 10:41:50 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 10:41:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:50 INFO - 'USER': 'cltbld', 10:41:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:50 INFO - 'XPC_FLAGS': '0x0', 10:41:50 INFO - 'XPC_SERVICE_NAME': '0', 10:41:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:41:50 INFO - Cleaning up... 10:41:50 INFO - Return code: 0 10:41:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:41: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')]} 10:41:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41: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')]} 10:41:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f1d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f266618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a5a4b0570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f135d30>, '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 0x10ee9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f12db20>, '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.lVv7xIwCMi/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.nb0pUNAauD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 10:41:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:41:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:41:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lVv7xIwCMi/Render', 10:41:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:50 INFO - 'HOME': '/Users/cltbld', 10:41:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:50 INFO - 'LOGNAME': 'cltbld', 10:41:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:50 INFO - 'MOZ_NO_REMOTE': '1', 10:41:50 INFO - 'NO_EM_RESTART': '1', 10:41:50 INFO - 'PAGER': '/bin/cat', 10:41:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:50 INFO - 'PWD': '/builds/slave/test', 10:41:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:50 INFO - 'SHELL': '/bin/bash', 10:41:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nb0pUNAauD/Listeners', 10:41:50 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 10:41:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:50 INFO - 'USER': 'cltbld', 10:41:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:50 INFO - 'XPC_FLAGS': '0x0', 10:41:50 INFO - 'XPC_SERVICE_NAME': '0', 10:41:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:51 INFO - Downloading/unpacking blobuploader==1.2.4 10:41:51 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 10:41:51 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 10:41:51 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 10:41:51 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 10:41:51 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 10:41:51 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 10:41:54 INFO - Downloading blobuploader-1.2.4.tar.gz 10:41:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:41:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:41:54 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 10:41:54 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 10:41:54 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 10:41:54 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 10:41:54 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 10:41:54 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 10:41:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:41:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:41:55 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 10:41:55 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 10:41:55 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 10:41:55 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 10:41:55 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 10:41:55 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 10:41:55 INFO - Downloading docopt-0.6.1.tar.gz 10:41:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:41:55 INFO - Installing collected packages: blobuploader, requests, docopt 10:41:55 INFO - Running setup.py install for blobuploader 10:41:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:41:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:41:55 INFO - Running setup.py install for requests 10:41:56 INFO - Running setup.py install for docopt 10:41:56 INFO - Successfully installed blobuploader requests docopt 10:41:56 INFO - Cleaning up... 10:41:56 INFO - Return code: 0 10:41:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:41:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:56 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 0x10f1d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f266618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a5a4b0570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f135d30>, '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 0x10ee9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f12db20>, '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.lVv7xIwCMi/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.nb0pUNAauD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 10:41:56 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 10:41:56 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 10:41:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lVv7xIwCMi/Render', 10:41:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:56 INFO - 'HOME': '/Users/cltbld', 10:41:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:56 INFO - 'LOGNAME': 'cltbld', 10:41:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:56 INFO - 'MOZ_NO_REMOTE': '1', 10:41:56 INFO - 'NO_EM_RESTART': '1', 10:41:56 INFO - 'PAGER': '/bin/cat', 10:41:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:56 INFO - 'PWD': '/builds/slave/test', 10:41:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:56 INFO - 'SHELL': '/bin/bash', 10:41:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nb0pUNAauD/Listeners', 10:41:56 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 10:41:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:56 INFO - 'USER': 'cltbld', 10:41:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:56 INFO - 'XPC_FLAGS': '0x0', 10:41:56 INFO - 'XPC_SERVICE_NAME': '0', 10:41:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:41:56 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-Adhvms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:41:57 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-LC9YjF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:41:57 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-88gPTm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:41:57 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-PalaGD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:41:57 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-ssCgDz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:41:57 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-XAY7uS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:41:57 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-t02DC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:41:57 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-oK9GHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:41:57 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-D15rv1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:41:57 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-NtBKo2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:41:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:41:58 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-aOdEew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:41:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:41:58 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-LtJ5TZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:41:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:41:58 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-7uInUd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:41:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:41:58 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-7d9VUS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:41:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:41:58 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-9k8fDQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:41:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:41:58 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-vHcgrZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:41:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:41:58 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-s4ZwxV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:41:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:41:58 INFO - Running setup.py install for manifestparser 10:41:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:41:58 INFO - Running setup.py install for mozcrash 10:41:59 INFO - Running setup.py install for mozdebug 10:41:59 INFO - Running setup.py install for mozdevice 10:41:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:41:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:41:59 INFO - Running setup.py install for mozfile 10:41:59 INFO - Running setup.py install for mozhttpd 10:41:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:41:59 INFO - Running setup.py install for mozinfo 10:41:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:41:59 INFO - Running setup.py install for mozInstall 10:41:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:41:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:41:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:41:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:41:59 INFO - Running setup.py install for mozleak 10:42:00 INFO - Running setup.py install for mozlog 10:42:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:42:00 INFO - Running setup.py install for moznetwork 10:42:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:42:00 INFO - Running setup.py install for mozprocess 10:42:00 INFO - Running setup.py install for mozprofile 10:42:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:42:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:42:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:42:00 INFO - Running setup.py install for mozrunner 10:42:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:42:00 INFO - Running setup.py install for mozscreenshot 10:42:01 INFO - Running setup.py install for moztest 10:42:01 INFO - Running setup.py install for mozversion 10:42:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:42:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:42:01 INFO - Cleaning up... 10:42:01 INFO - Return code: 0 10:42:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:42: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')]} 10:42:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:42:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42: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')]} 10:42:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:42:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42:01 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 0x10f1d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f266618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a5a4b0570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f135d30>, '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 0x10ee9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f12db20>, '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.lVv7xIwCMi/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.nb0pUNAauD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 10:42:01 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 10:42:01 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 10:42:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lVv7xIwCMi/Render', 10:42:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:42:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:42:01 INFO - 'HOME': '/Users/cltbld', 10:42:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:42:01 INFO - 'LOGNAME': 'cltbld', 10:42:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:42:01 INFO - 'MOZ_NO_REMOTE': '1', 10:42:01 INFO - 'NO_EM_RESTART': '1', 10:42:01 INFO - 'PAGER': '/bin/cat', 10:42:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:42:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:42:01 INFO - 'PWD': '/builds/slave/test', 10:42:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:42:01 INFO - 'SHELL': '/bin/bash', 10:42:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nb0pUNAauD/Listeners', 10:42:01 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 10:42:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:42:01 INFO - 'USER': 'cltbld', 10:42:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:42:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:42:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:42:01 INFO - 'XPC_FLAGS': '0x0', 10:42:01 INFO - 'XPC_SERVICE_NAME': '0', 10:42:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:42:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:42:01 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-wfM4XN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:42:01 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)) 10:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:42:01 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-JjdzJ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:42:01 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)) 10:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:42:01 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-wokg8m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:42:01 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)) 10:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:42:01 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-z39vs0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:42:02 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)) 10:42:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:42:02 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-QiTgNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:42:02 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)) 10:42:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:42:02 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-KZXu1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:42:02 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)) 10:42:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:42:02 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-HoZ70A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:42:02 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)) 10:42:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:42:02 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-4El8M3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:42:02 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)) 10:42:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:42:02 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-QyITm2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:42:02 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)) 10:42:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:42:02 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-xS2_eu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:42:02 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)) 10:42:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:42:02 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-nesMlb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:42:02 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)) 10:42:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:42:02 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-T6zdRs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:42:02 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)) 10:42:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:42:02 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-wT3Tyn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:42:02 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)) 10:42:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:42:02 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-gncNLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:42:03 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)) 10:42:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:42:03 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-v6DoHt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:42:03 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)) 10:42:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:42:03 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-wXYgVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:42:03 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)) 10:42:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:42:03 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-B2tgEL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:42:03 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)) 10:42:03 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)) 10:42:03 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)) 10:42:03 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)) 10:42:03 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)) 10:42:03 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)) 10:42:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:42:03 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 10:42:03 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 10:42:03 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 10:42:03 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 10:42:03 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 10:42:03 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 10:42:05 INFO - Downloading blessings-1.6.tar.gz 10:42:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:42:05 INFO - Installing collected packages: blessings 10:42:05 INFO - Running setup.py install for blessings 10:42:06 INFO - Successfully installed blessings 10:42:06 INFO - Cleaning up... 10:42:06 INFO - Return code: 0 10:42:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:42:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:42:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:42:06 INFO - Reading from file tmpfile_stdout 10:42:06 INFO - Current package versions: 10:42:06 INFO - blessings == 1.6 10:42:06 INFO - blobuploader == 1.2.4 10:42:06 INFO - docopt == 0.6.1 10:42:06 INFO - functools32 == 3.2.3-2 10:42:06 INFO - jsonschema == 2.5.1 10:42:06 INFO - manifestparser == 1.1 10:42:06 INFO - mozInstall == 1.12 10:42:06 INFO - mozcrash == 0.17 10:42:06 INFO - mozdebug == 0.1 10:42:06 INFO - mozdevice == 0.48 10:42:06 INFO - mozfile == 1.2 10:42:06 INFO - mozhttpd == 0.7 10:42:06 INFO - mozinfo == 0.9 10:42:06 INFO - mozleak == 0.1 10:42:06 INFO - mozlog == 3.3 10:42:06 INFO - moznetwork == 0.27 10:42:06 INFO - mozprocess == 0.23 10:42:06 INFO - mozprofile == 0.28 10:42:06 INFO - mozrunner == 6.12 10:42:06 INFO - mozscreenshot == 0.1 10:42:06 INFO - mozsystemmonitor == 0.3 10:42:06 INFO - moztest == 0.7 10:42:06 INFO - mozversion == 1.4 10:42:06 INFO - psutil == 3.1.1 10:42:06 INFO - requests == 1.2.3 10:42:06 INFO - wsgiref == 0.1.2 10:42:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:42:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:42:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:42:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:42:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:42:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42:06 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 0x10f1d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f266618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a5a4b0570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f135d30>, '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 0x10ee9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f12db20>, '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.lVv7xIwCMi/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.nb0pUNAauD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 10:42:06 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 10:42:06 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 10:42:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lVv7xIwCMi/Render', 10:42:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:42:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:42:06 INFO - 'HOME': '/Users/cltbld', 10:42:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:42:06 INFO - 'LOGNAME': 'cltbld', 10:42:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:42:06 INFO - 'MOZ_NO_REMOTE': '1', 10:42:06 INFO - 'NO_EM_RESTART': '1', 10:42:06 INFO - 'PAGER': '/bin/cat', 10:42:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:42:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:42:06 INFO - 'PWD': '/builds/slave/test', 10:42:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:42:06 INFO - 'SHELL': '/bin/bash', 10:42:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nb0pUNAauD/Listeners', 10:42:06 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 10:42:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:42:06 INFO - 'USER': 'cltbld', 10:42:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:42:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:42:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:42:06 INFO - 'XPC_FLAGS': '0x0', 10:42:06 INFO - 'XPC_SERVICE_NAME': '0', 10:42:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:42:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:42:07 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)) 10:42:07 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)) 10:42:07 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)) 10:42:07 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)) 10:42:07 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)) 10:42:07 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)) 10:42:07 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)) 10:42:07 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)) 10:42:07 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)) 10:42:07 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)) 10:42:07 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)) 10:42:07 INFO - Cleaning up... 10:42:07 INFO - Return code: 0 10:42:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:42: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')]} 10:42:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:42:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42: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')]} 10:42:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:42:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f1d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f266618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a5a4b0570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f135d30>, '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 0x10ee9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f12db20>, '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.lVv7xIwCMi/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.nb0pUNAauD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 10:42: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'] in /builds/slave/test/build 10:42: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 10:42:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lVv7xIwCMi/Render', 10:42:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:42:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:42:07 INFO - 'HOME': '/Users/cltbld', 10:42:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:42:07 INFO - 'LOGNAME': 'cltbld', 10:42:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:42:07 INFO - 'MOZ_NO_REMOTE': '1', 10:42:07 INFO - 'NO_EM_RESTART': '1', 10:42:07 INFO - 'PAGER': '/bin/cat', 10:42:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:42:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:42:07 INFO - 'PWD': '/builds/slave/test', 10:42:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:42:07 INFO - 'SHELL': '/bin/bash', 10:42:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nb0pUNAauD/Listeners', 10:42:07 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 10:42:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:42:07 INFO - 'USER': 'cltbld', 10:42:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:42:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:42:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:42:07 INFO - 'XPC_FLAGS': '0x0', 10:42:07 INFO - 'XPC_SERVICE_NAME': '0', 10:42:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:42:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:42:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:42:07 INFO - Cleaning up... 10:42:07 INFO - Return code: 0 10:42:07 INFO - Running post-action listener: _resource_record_post_action 10:42:07 INFO - Running post-action listener: _start_resource_monitoring 10:42:07 INFO - Starting resource monitoring. 10:42:07 INFO - [mozharness: 2016-08-25 17:42:07.495647Z] Finished create-virtualenv step (success) 10:42:07 INFO - [mozharness: 2016-08-25 17:42:07.496618Z] Running install step. 10:42:07 INFO - Running pre-action listener: _resource_record_pre_action 10:42:07 INFO - Running main action method: install 10:42:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:42:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:42:07 INFO - Reading from file tmpfile_stdout 10:42:07 INFO - Detecting whether we're running mozinstall >=1.0... 10:42:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:42:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:42:07 INFO - Reading from file tmpfile_stdout 10:42:07 INFO - Output received: 10:42:07 INFO - Usage: mozinstall [options] installer 10:42:07 INFO - Options: 10:42:07 INFO - -h, --help show this help message and exit 10:42:07 INFO - -d DEST, --destination=DEST 10:42:07 INFO - Directory to install application into. [default: 10:42:07 INFO - "/builds/slave/test"] 10:42:07 INFO - --app=APP Application being installed. [default: firefox] 10:42:07 INFO - mkdir: /builds/slave/test/build/application 10:42:07 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'] 10:42:07 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 10:42:41 INFO - Reading from file tmpfile_stdout 10:42:41 INFO - Output received: 10:42:41 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:42:41 INFO - Running post-action listener: _resource_record_post_action 10:42:41 INFO - [mozharness: 2016-08-25 17:42:41.936081Z] Finished install step (success) 10:42:41 INFO - [mozharness: 2016-08-25 17:42:41.936240Z] Running run-tests step. 10:42:41 INFO - Running pre-action listener: _resource_record_pre_action 10:42:41 INFO - Running main action method: run_tests 10:42:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:42:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:42:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:42:41 INFO - 2016-08-25 10:42:41.950 screenresolution[1632:7535] starting screenresolution argv=screenresolution get 10:42:41 INFO - 2016-08-25 10:42:41.953 screenresolution[1632:7535] Display 0: 1600x1200x32@60 10:42:41 INFO - 2016-08-25 10:42:41.959 screenresolution[1633:7537] starting screenresolution argv=screenresolution list 10:42:41 INFO - Available Modes on Display 0 10:42:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:42:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:42:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:42:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:42:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:42:42 INFO - Intel Iris: 10:42:42 INFO - Chipset Model: Intel Iris 10:42:42 INFO - Type: GPU 10:42:42 INFO - Bus: Built-In 10:42:42 INFO - VRAM (Dynamic, Max): 1536 MB 10:42:42 INFO - Vendor: Intel (0x8086) 10:42:42 INFO - Device ID: 0x0a2e 10:42:42 INFO - Revision ID: 0x0009 10:42:42 INFO - Displays: 10:42:42 INFO - Display: 10:42:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:42:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:42:42 INFO - Display Serial Number: 1600x1200 60 10:42:42 INFO - Main Display: Yes 10:42:42 INFO - Mirror: Off 10:42:42 INFO - Online: Yes 10:42:42 INFO - Rotation: Supported 10:42:42 INFO - Return code: 0 10:42:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:42:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:42:42 INFO - Python 2.7.3 10:42:42 INFO - Return code: 0 10:42:42 INFO - grabbing minidump binary from tooltool 10:42:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:42:42 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 0x10f135d30>, '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 0x10ee9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f12db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:42:42 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 10:42:42 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 10:42:42 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 10:42:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:42:42 INFO - Return code: 0 10:42:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:42:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:42:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:42:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:42:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:42:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WQW9A1KOQSegELCb_il-vA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0019', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 10:42:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WQW9A1KOQSegELCb_il-vA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0019 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 10:42:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lVv7xIwCMi/Render', 10:42:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:42:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:42:42 INFO - 'HOME': '/Users/cltbld', 10:42:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:42:42 INFO - 'LOGNAME': 'cltbld', 10:42:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:42:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:42:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:42:42 INFO - 'MOZ_NO_REMOTE': '1', 10:42:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:42:42 INFO - 'NO_EM_RESTART': '1', 10:42:42 INFO - 'PAGER': '/bin/cat', 10:42:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:42:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:42:42 INFO - 'PWD': '/builds/slave/test', 10:42:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:42:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:42:42 INFO - 'SHELL': '/bin/bash', 10:42:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nb0pUNAauD/Listeners', 10:42:42 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 10:42:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:42:42 INFO - 'USER': 'cltbld', 10:42:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:42:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:42:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:42:42 INFO - 'XPC_FLAGS': '0x0', 10:42:42 INFO - 'XPC_SERVICE_NAME': '0', 10:42:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:42:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WQW9A1KOQSegELCb_il-vA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0019', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 10:42:42 INFO - mozversion application_buildid: 20160825082726 10:42:42 INFO - mozversion application_changeset: 91b6e270107a96d5c5c6045b76c59c3aafe18a90 10:42:42 INFO - mozversion application_display_name: Nightly 10:42:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:42:42 INFO - mozversion application_name: Firefox 10:42:42 INFO - mozversion application_remotingname: firefox 10:42:42 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 10:42:42 INFO - mozversion application_vendor: Mozilla 10:42:42 INFO - mozversion application_version: 51.0a1 10:42:42 INFO - mozversion platform_buildid: 20160825082726 10:42:42 INFO - mozversion platform_changeset: 91b6e270107a96d5c5c6045b76c59c3aafe18a90 10:42:42 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 10:42:42 INFO - mozversion platform_version: 51.0a1 10:42:42 INFO - using testdate: 1472146962 10:42:42 INFO - actual date: 1472146962 10:42:42 INFO - starting webserver on 'localhost:49267' 10:42:42 INFO - SUITE-START | Running 2 tests 10:42:42 INFO - TEST-START | dromaeo_css 10:42:42 INFO - operating with platform_type : mac_ 10:42:42 INFO - Initialising browser for dromaeo_css test... 10:42:42 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmppEpa4b/profile http://localhost:49267/getInfo.html) 10:42:44 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 10:42:44 INFO - PROCESS | 1643 | colorDepth:24 10:42:44 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 10:42:44 INFO - PROCESS | 1643 | __metrics 10:42:45 INFO - PROCESS | 1643 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:42:45 INFO - PROCESS | 1643 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:42:45 INFO - TEST-INFO | 1643: exit 0 10:42:45 INFO - Browser initialized. 10:42:45 INFO - Running cycle 1/1 for dromaeo_css test... 10:42:45 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmppEpa4b/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:42:57 INFO - PROCESS | 1645 | RSS: Main: 232198144 10:42:57 INFO - PROCESS | 1645 | 10:43:48 INFO - PROCESS | 1645 | 1472147028517 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] 10:46:00 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 10:46:01 INFO - PROCESS | 1645 | RSS: Main: 347889664 10:46:01 INFO - PROCESS | 1645 | 10:49:04 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 10:49:05 INFO - PROCESS | 1645 | RSS: Main: 365838336 10:49:05 INFO - PROCESS | 1645 | 10:52:07 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 10:52:08 INFO - PROCESS | 1645 | RSS: Main: 356696064 10:52:08 INFO - PROCESS | 1645 | 10:55:10 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 10:55:11 INFO - PROCESS | 1645 | RSS: Main: 341479424 10:55:11 INFO - PROCESS | 1645 | 10:58:14 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 10:58:15 INFO - PROCESS | 1645 | RSS: Main: 322449408 10:58:15 INFO - PROCESS | 1645 | 11:01:18 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 11:01:19 INFO - PROCESS | 1645 | RSS: Main: 477024256 11:01:19 INFO - PROCESS | 1645 | 11:01:19 INFO - PROCESS | 1645 | __start_tp_report 11:01:19 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 11:01:19 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 11:01:19 INFO - PROCESS | 1645 | |i|pagename|runs| 11:01:19 INFO - PROCESS | 1645 | |0;dojo.html;3307.900762977111;3314.5193946877694;3347.1130150510103;3376.864925402984;3415.026717385544;5551.528120109785;5580.832575022746;5667.971660141693;5669.461401166887;5684;5274.261603375521;5611.971940140304;5688.345840228375;5711.457411545896;5735.139729040646;5072.492750724917;5102.540771330579;5154.329937108189;5179.533841954223;5266.736663166846;4329.480462344523;5050.217216331474;5290.100682883919;5314.548263397616;5317.9468205318035;5102.464241254652;5109.131447653907;5155.175171972504;5164.741762911839;5225.529702326789;5145.382554093495;5150.047241260341;5152.330197074394;5172.250023746573;5206.557442617382;5161.148410512261;5171.991461664977;5214.973925130388;5220.243064755589;5240.397354304266;5565.888682226333;5590.524805391547;5674.3190817101795;5682.232898558683;5743.052396354597;6207.751689932397;6222.471089957359;6224.377562243784;6228.96885515574;6229.563930524865;5174.637775355726;6245.188125543696;6252.562320637558;6274.403931626488;6282.811515654537;5628.437156284421;5686.289213848269;5690.146478028246;5757.596968212185;5758.222639943638;5687.8862422752145;6132.528193233592;6286.088517164985;6288.779892703734;6301.590396624205;6154.415330543591;6162.476189523851;6172.506199504029;6172.876542469216;6173.938260617337;6077.574569780073;6110.680268050674;6139.829471381233;6181.505479561624;6230.688465576703;6113.263474096151;6149.477294430024;6173.104899789595;6189.907151392733;6204.627722336674;6226.439620434183;6245.594036387621;6248.625082495065;6251.74993000284;6256.562040657111;4786.664933454625;4895.437024742129;4940.604751619861;4948.109340318705;4973.32860063894;4382.69321147523;4435.467743870756;4461.955380446219;4508.346289787963;4574.313852922089;5679.914801277984;5715.399883012307;5722.713864306768;5724.313082430135;5734.283214598176;4989.55094041538;6243.313235544087;6263.4362907338555;6293.244810622755;6301.8109456716375;5618.943810561842;5644.153376993484;5698;5713.600047996601;5788.0449725795615;5379.35447746273;5396.163594642758;5397.298351214317;5402.405735369106;5410.431904649955;6242.28213755424;6267.2792628847355;6274.905876411857;6300.495960323163;6303.747850085945;6121.8469538260115;6147.831911936718;6194.132821404917;6223.973044447615;6261.029540421242;6217.285012223652;6275.278342990615;6301.527385446018;6304.779332723333;6320.146780184617;6204.100405441276;6224.688765561795;6225.190725205694;6228.34602366745;6257.937420625917;5938.762449501972;6144.539159562962;6203.31763506005;6243.157173781481;6286.5599408041;6052.334243233153;6067.180930574316;6093;6093.421124993119;6110.877782444415;6064.423879731418;6068.089786532056;6071.210742603434;6074.908876366946;6079.1793107931635;5689.886202276014;5768.221290125947;5835.883282334415;5846.590738648254;5910.852228694317;6014.909776353271;6022.698865056642;6038.124471951454;6049.274087109399;6082.452579267887;5857.912131318119;5871.442212989759;5875.236219291465;5876.1773351729935;5888.17565540816 11:01:19 INFO - PROCESS | 1645 | |1;ext.html;491.90653775782596;496.2481840012381;506.95183957524034;523.6151428699902;565.4910580477572;30433.695663043392;39720.21958462905;40700.796496017516;40793;43468.130637387236;42512.362314565304;42781;42812.14375712484;42893.7855310723;42923.78538107305;8793.384463087586;9676.46779427133;9814.411135331902;9823.312368134233;9844.83413648996;8814.33892458065;8968.551572421386;8987.146221108984;8993.28053755698;11836.644900652995;11816.527338906433;12134.878651213463;12142.510785289038;12178.756424871497;12181.695457613587;11957.222780519238;12034.33811140387;12076.456559454846;12089.214201076946;12090.395480226041;9847.901520984842;9924.80150396988;11908.880911190863;11921.821172682417;11929;39828.402573961415;40017.39973900394;40154.39768403476;40172;40202.59797402047;3238.239013831748;3354.714849237818;3904.9804750976064;4174.853880114181;4263.104748002923;28288.85855570709;28827.84688612453;29920.251993700113;32078.839605802055;32495;2273.30664147435;2472.9134480293283;3009.8344591047485;3036.5748795168693;3064.973233966624;508138;512294;513551;513766;520355.39822301024;58874.705626471594;59593;59607;59712;59868.40131598628;61443.07835382473;61674;62066;62169.02647788166;62217.68891155515;15968.600784980235;16217.837821621868;16249.431269905497;16254;16293.185340732916;12623.558175463999;12682.746345073047;12692.746145077046;12695.301758403191;12704.491820327268;14193.503227386991;14533.709325813636;15827.52517424479;16225.350985960666;16408.67182656364;6236.532260080512;6495.382938620824;6544.0224697762405;7074.646267686698;7158.677859496335;695.6347917343435;830.8421399933993;887.1704955866156;976.5312649928075;1005.6631028605353;26489.33776655599;30164.54753178704;30296.54555181674;30679.87700717451;31088.533671994937;996.9052939970691;1081.534939975818;1094.874089479704;1152.1829127782657;1167.4396289780952;14737.1894545801;15643;15664.843351566566;15673.764893526606;16026.679466410607;627181;628681;629702;632648;634994;50090.749546252766;57344.13983790246;58407;58479;63919.36080639134;1386.4454218312553;1401.2363262022222;1435.992820035935;1436.96407589809;1437.4465830655065;30499.08502744921;31907.840460797546;32224;32408.675913240564;32464.83767581147;50934.74532627313;51506.22740658968;51531;51536;51551.74224128856;585028;589035;589942;590902;591455;38937.44250229959;39863;40102;40130.79607611776;40132.59867401405;47300;47937;48157.75921120512;48266;49246;37422.25155496929;39238.607613923494;39442.211155777295;40242.59757402389;40675.77972660825;37352;38945.2210955785;40050.1989960205;40520.3921941177;40840;38187.2362552753;39204.41193382045;39222.2155556893;39337.606623933396;39553.2089358217;28700.712992870638;30298.394032118795;34586.30827383388;35631.643683562834;35912;25226;25812.22563323103;25883.741162588885;25907.87046064833;26251.606225906246 11:01:19 INFO - PROCESS | 1645 | |2;jquery.html;349.27002564639895;373.3540974114781;375.94548790425387;387.7072810028429;407.86540441654256;15625;16130.951488153261;20953.476163095893;21071.94640267984;21136.682949755766;20990;21349.252776152836;21415.785842141573;21670.3498895033;22048.33854984349;4934.432540257872;5867.295924489067;6077.848053798668;6129.325774164839;6183.752649893999;3986.2824691555506;6023.30731965823;6116.602420842653;6144.938550614482;6445.613263204223;6712.5301228913995;6735.8989615155815;6758.0538724578355;6777.423918966869;6822.181338239393;5857.648541087548;6143.232095988002;6197.256329240475;6224.906626400608;6797.76207832726;5817.534597232211;5993.011153159723;6068.484178844804;6074;6085.452309292141;13346.733065338736;13489.258090805002;13624.863751362458;13624.863751362558;13656.385462654105;2280.4070941555315;2357.5638506876285;2565.34583681162;2731.6721993360925;2910.893860333081;9912.851307230398;9982.90017099827;10031.097201251923;10542.998415150549;10637.680869573926;1723.9051852148127;1827.844633206179;1925.922963081475;1948.230448972662;1966.2921348314596;107387;111550;122088;123151.38424307911;123324;9396.859047114298;11250.324980501196;11404.258723182966;11439.828402573969;11470.770584588428;14297.070690405091;14313.713725725427;14323.069000514934;14324.785128223086;14331.85668143326;8107;8388.748337549761;8413.242808147173;8552.401331906707;8611.569421528899;6763.425108865801;7592.924070759333;8044.632412489891;8305.252527272574;8448.028476725256;4104.486939132608;4406.9779651101535;4456.064226512404;4457.286834106528;4523.027549076898;1685.890417122883;1879.6898511745683;1890.2911408221917;1890.6596812573594;1891.6689579323563;724.8550289941927;849.9065102838683;903.6746771162376;930.2139691960283;1006.3911333643186;19819.603607927762;20783.896080519502;20921.05855236518;21574.568508629738;21630.891845540988;915.4278575890069;949.520492151459;969.14230905649;976.6386437018207;1035.4667346316653;8981.371304008657;10544.841827372595;11066.225364224589;11249.789672681183;11259.831102533468;579196;582183.0890845603;593426;593926;594326;112692.30961535583;125197;132332;133472;135185;924.2836801478774;925.1026504290961;952.552300418802;952.6141912525438;953.7472569769017;18958.810411895705;19236.519087022938;19261.903690481457;19272;19278.614427711087;52446.73776631245;55743.72128139334;56424.71787641035;56612;57132;75717.62141189259;86251;86561.56719216364;87514;87965.1203487957;7697.191794861459;7747.651355688895;7966.721164759206;8040.87938680932;8303.418760686694;10698;12714.936425317814;12736;13116.409761560944;13194;10085.747856303651;10559.683209503728;10838;11226.887731122584;11350.829737553779;10632.84050739255;10952.671419857417;11086.722831929266;11398.25911315795;11442.542298307975;10832.133429325793;11205.319202119746;11369.374684392107;11731.648050558497;11952.58165964187;7868.370530357673;8030.87953680707;8106.310963568163;8164.469309494863;8405.117462666332;8030.357571394157;8340.291075258428;11179.552817887194;11536;11542.711432214262 11:01:19 INFO - PROCESS | 1645 | |3;mootools.html;1364.2292104960702;1520.878329733621;1546.9303881325338;1710.4355562664323;1858.2566973210714;7801.2198780122035;8568.057513673499;8847;8889.977652569949;8956.820863582741;8695.695650652213;8780.297576193905;8793.252573531257;8796.736097917057;8797.648094076229;2373.64395340699;2402.2072716003677;2403.879806009701;2406.0135344508753;2408.3377071305445;2350.635651474024;2383.4041489627593;2396.928092157238;2403.531311394279;2408.722996855358;2364.775346342103;2389.080204121417;2401.8799060047013;2407.3018824540864;2413.2398294537165;2337.637666161748;2349.6827928229727;2352.8235382346393;2354.4702441950594;2389.9522009559887;2321.4892723600783;2345.0971376020184;2349.917752878641;2352.5294941011866;2356.36378177891;8645;8708.782280442976;8777.253933415668;8814.603342849587;8870.467771933705;5282.445343238943;5477.780888764445;5496.7526461308935;5497.147942068986;5507.091329930557;3545.556805399325;3551.6093229744706;3605.387084195693;3623.438367053111;3625.075605720551;1067.3542506783363;1075.9139268858473;1076.5101878645198;1094.3543309447464;1103.3710784852638;11550.53797848085;11657.94171029148;11702.356370399624;11713.004394626467;11723.824142637868;4371.628411584988;4501.572350626685;4642;4671.32265821454;4688.742119183477;9188;9197.862032069525;9201.355905086579;9224.723258302261;9233.722988310361;5262.500062494057;5262.868428289324;5262.973685131627;5272;5276.182191760283;4296.5273819879785;4443.800028998703;4600.148972440109;4890;4917.237828136573;3732.9626703732615;3771.07608635886;3888.4944957155953;3893.805309734502;3902.824372903169;1149.6319379937847;1164.1967042740482;1168.2231316174818;1168.5968340922368;1175.5481979754995;745.8247311881703;806.2300503019602;818.6807145213371;821.5440430560976;937.2501998401252;5676.205331253628;5806.129080637938;6047.183630209865;6067.878642427127;6203.472704820141;427.59592185384514;433.2223658532941;439.925212713833;440.1790660418297;448.09932036606654;3982.7411218270718;4003.4395184674167;4058.301229682287;4084.4894388201474;4098.959010409917;9905.455199963926;10582.20633452494;10743.979321964402;10750.892491075145;10756.354618722902;10333.173346132291;10688.89311106879;10700.250982431155;10708.250422470508;10747.623833165802;8103.716369927024;8287;8300.335973122135;8318.085010648823;8333.708320208885;5727.914081288701;5778.306603207642;5828.300603927386;5904.793332233351;5916.881662366814;10008.14930730896;10015.24885633595;10079.496025198858;10082.596696132072;10100.898991009995;9641.855372169282;10207.693769186937;10223.1821454282;10237.129843963347;10244.282900196915;6579.506537009839;6801.829954251183;7078.362947334765;7167.749128780468;7194.208637050025;8219.178082191733;8345.123762005145;8504.149585041447;8620.65517379323;8650.610722517376;6471.902921456088;7556.206598307319;7676.808079797826;7679.69281228767;7821.374290056896;7512;7515.023047004073;7534.886976695464;7561.357284630868;7637.427192960439;6994;7162.7493037743425;7172.318629730167;7183.748568800067;7185.964070179615;4726;5037.697738135724;5062.746862656779;5101.107306221471;5188.351456067991;4992.176290912104;5025.824096156617;5186.974065129651;5243.134882744457;5298.284731561344 11:01:19 INFO - PROCESS | 1645 | |4;prototype.html;281.65356611368014;289.1426919184617;301.8867924528302;314.4842458368276;318.10136364769534;4784.306275590037;5121.820736274231;5277.762500687469;5304.533391972732;5350.5987050971235;4452.643788496921;4922.9907868886885;5101.515356041179;5160.329157209567;5240.6069544784195;3377.9493307600283;4054.465958775765;4073.8981525461954;4076.8776936691875;4095.7337773044655;3523.859045638171;3900.7074469414765;3994.840206391741;4135.407867970838;4138.255114079465;4008.779517126557;4077.022644017649;4095.3242714952;4097.221527909703;4121.443605113302;3583.283343331344;3892.4550562921213;4097.926555083687;4099.355915434558;4108.198901214264;3587.6053634100226;3598.478220658016;3606.5131207286945;3606.747527673064;3610.656987586175;4499.572540608637;5039.626295091128;5150.27896094547;5157.200633901752;5179.948200517984;4653.278741794993;4687.367205427257;4690.742009189493;4718.056388722235;4732.929006064912;4639.6752227344095;4794.640401969867;5529.557635389159;5589.972050139764;5596.916046259309;3229.838508074587;3525.894223173309;3642.7996460194677;3928.3125453045604;3960.742551734128;5785.363610002896;5802.158686990363;5807.622504537191;5808.390119037483;5834.124881267844;4004.939925901114;4013.3979903014783;4019.7588144711412;4023.9336575807433;4050.5746896576024;3584.946225806615;3653.903828851334;3867.9226415472094;4063.593640635913;4090.8954582262622;4127.318992366286;4201;4573.474050484237;4613.723176609415;4637.837675681335;5069.8479045627955;5282.652923504558;5510.035743744828;5610.990021796034;5622.971885140548;1189.2864281431073;1326.840779106494;1332.580237225271;1363.9454421823016;1366.5285476510587;846.7586737779703;925.9583318750672;929.2937367600672;958.7794807194244;965.3725078698902;4317.309230523101;5102.36220472441;5152.510511501401;5184.455632158569;5192.506711862367;4404.995726944233;4894.325918747588;5496.560275177976;5558.305211848519;5602.327720673546;4933.980206476325;4990.351254336985;5124.795008199705;5127.743612819344;5131.61512886535;1956.513050081948;2195.6926030355444;2369.7630236976165;2432.4216330689524;2485.4590154104208;4125.525564560054;4709.481956984729;4869.56347877377;4879.926416188433;4887.780049897692;3582.972388331855;4271.188474189894;4443.111377724404;4773.95226047742;4785.49752276006;3927.92144157121;4450.488193857748;4514.848713578017;4785.665003449794;4851.733154676456;3981.024648961023;4203.906984184073;4243.68172387066;4676.859694209179;4792.466410748527;3986.242613903349;4031.596840315945;4068.0440096577872;4071.5114186297833;4517.661175411791;4078.7144899856726;4314.633256173135;4708.764561771966;4720.103180395714;4754.337570960135;3274.918127046843;3326.7837590556537;3375.291188850369;3379.30724201543;3406.284680217182;4461.933071004002;4767.404074490689;4769.259220384465;4799.616030717464;5438.700871452108;4801.759912004316;5311.857950540652;5493.725313734377;5494.983428065821;5601.663900166003;4409.5889315420045;5409.80984183479;5447.591430642638;5461.9180712290145;5480.479354461319;5160.587153027824;5382.842688821922;5405.432429594836;5437.537809286255;5472.644278121909;4630.652701047502;4942.728149951788;5480.3971563127225;5507.642003269774;5530.80642177538;5413.079776438094;5432.891342173055;5473.698946557977;5479.013777520085;5508.283923089973 11:01:19 INFO - PROCESS | 1645 | |5;yui.html;680.8910574308112;696.7631005458146;753.3257734327776;754.3286475037216;816.7182322098873;13716.177029378245;15543.455979040737;16274.186290685477;16286.185690715476;16315.265813038412;14522.636934076654;14725.190114543695;14739.926300368483;14753.114813111193;14797.112173269612;705.2735682247268;724.8115489972619;861.7285555050155;875.951822649754;904.4844438669961;888.2716172738357;901.324006994754;901.7925877048283;904.023654453189;904.8235594059161;957.3107362698846;961.8990006049369;963.2967933408615;964.2430691906854;964.8600952861831;751.113685850696;773.5242825662177;774.0169984120135;951.6383774165807;954.3844220477788;571.7398583644432;682.4608559238245;709.2694524639629;710.1868360726938;712.5332906945958;14529.56411307652;14537.345819438046;14538.781918271128;14562.635934101732;14573.979821412506;13627.386767595483;13815.792763108562;14065;14085;14137.929310353382;12911.935440322832;13077.869221307856;13254;13275.601731948056;13552.361396303984;4500.459944806611;4528.50186479487;4750.216214324632;4823.03539292143;4829.251466022772;118720.21919671126;120510.19234711486;121139;121214.78785212123;123988;9628.422294662203;10755.83866242007;11081.889181108085;11399.715007124889;11404.429778511041;21466.677999830015;21470.819104949376;21480.4629884251;21493.570128597654;21515.354539363845;7794.025746781653;8009.879851802228;8034.47775894565;8177.427580069457;8179.631916563769;4030.5163380394542;4046.5751096135073;4163.812628431668;4167.291560434734;4256.4679415073115;3400.931981360359;3459.584849818038;3519.59524654668;3523.841427135785;3536.5579302587175;544.3250369541819;553.8310815201322;562.4684672984073;569.8831739493394;578.0981668597002;507.0998976816181;525.254139122447;596.8567543789459;597.8445604142867;601.1613798750806;7928.603569821601;8159.061707903667;8311.418200725891;8376.664933402717;8621.31029517637;414.8236999275296;529.6795438759572;549.8542886135177;556.9136783798517;583.743153012673;4005.708184059028;4153.6677065834665;4179.1014931789205;4193.853215137517;4330.696851220384;99195.00804992003;99908.00091999132;100909.49545252227;101576;139954;101699.49150254202;121901;122296.2737049453;127640.36179819227;128124;76763.61618191874;104937.47531262448;112712;114384.42807785908;114984;8172.8365432689825;8405.453645513022;8775.473471591726;9407.905920940977;9636.855447168158;58930;60715;61038;62646.37353626588;65655.67172164109;68143;69780;73592.89610655737;82859.17140828514;95244;10791.1367090631;10819.296745711503;10906.236563440483;11146.832797508207;11274.661760147208;11446;11759.745220402292;11896.286222826659;12105.939470302592;12120.636380908234;11145.052670523097;11163.88836111661;11189.496472658588;11221.326720396803;11549.01833344175;11471.770564588829;11678.357690327113;11922.284662920252;11954.76090478203;11982.340971246666;10284.8116984393;10901.509432075418;11280.49237784318;11364.374959377314;11558;8340.708075217339;9231.49226792506;9508.334416590773;9759.268054896052;10163.898361016296;7687.692492300245;8817.559122044;9571.473568953495;9593.520323983634;9869.901300987185 11:01:19 INFO - PROCESS | 1645 | __end_tp_report 11:01:19 INFO - PROCESS | 1645 | __start_cc_report 11:01:19 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,1140 11:01:19 INFO - PROCESS | 1645 | __end_cc_report 11:01:19 INFO - PROCESS | 1645 | __startTimestamp1472148079036__endTimestamp 11:01:19 INFO - PROCESS | 1645 | 11:01:19 INFO - PROCESS | 1645 | ------- Summary: start ------- 11:01:19 INFO - PROCESS | 1645 | Number of tests: 6 11:01:19 INFO - PROCESS | 1645 | 11:01:19 INFO - PROCESS | 1645 | [#0] dojo.html Cycles:165 Average:5698.17 Median:5857.91 stddev:641.81 (11.0%) stddev-sans-first:615.78 11:01:19 INFO - PROCESS | 1645 | Values: 3307.9 3314.5 3347.1 3376.9 3415.0 5551.5 5580.8 5668.0 5669.5 5684.0 5274.3 5612.0 5688.3 5711.5 5735.1 5072.5 5102.5 5154.3 5179.5 5266.7 4329.5 5050.2 5290.1 5314.5 5317.9 5102.5 5109.1 5155.2 5164.7 5225.5 5145.4 5150.0 5152.3 5172.3 5206.6 5161.1 5172.0 5215.0 5220.2 5240.4 5565.9 5590.5 5674.3 5682.2 5743.1 6207.8 6222.5 6224.4 6229.0 6229.6 5174.6 6245.2 6252.6 6274.4 6282.8 5628.4 5686.3 5690.1 5757.6 5758.2 5687.9 6132.5 6286.1 6288.8 6301.6 6154.4 6162.5 6172.5 6172.9 6173.9 6077.6 6110.7 6139.8 6181.5 6230.7 6113.3 6149.5 6173.1 6189.9 6204.6 6226.4 6245.6 6248.6 6251.7 6256.6 4786.7 4895.4 4940.6 4948.1 4973.3 4382.7 4435.5 4462.0 4508.3 4574.3 5679.9 5715.4 5722.7 5724.3 5734.3 4989.6 6243.3 6263.4 6293.2 6301.8 5618.9 5644.2 5698.0 5713.6 5788.0 5379.4 5396.2 5397.3 5402.4 5410.4 6242.3 6267.3 6274.9 6300.5 6303.7 6121.8 6147.8 6194.1 6224.0 6261.0 6217.3 6275.3 6301.5 6304.8 6320.1 6204.1 6224.7 6225.2 6228.3 6257.9 5938.8 6144.5 6203.3 6243.2 6286.6 6052.3 6067.2 6093.0 6093.4 6110.9 6064.4 6068.1 6071.2 6074.9 6079.2 5689.9 5768.2 5835.9 5846.6 5910.9 6014.9 6022.7 6038.1 6049.3 6082.5 5857.9 5871.4 5875.2 5876.2 5888.2 11:01:19 INFO - PROCESS | 1645 | 11:01:19 INFO - PROCESS | 1645 | [#1] ext.html Cycles:180 Average:71505.22 Median:39845.70 stddev:154791.89 (388.5%) stddev-sans-first:155134.30 11:01:19 INFO - PROCESS | 1645 | Values: 491.9 496.2 507.0 523.6 565.5 30433.7 39720.2 40700.8 40793.0 43468.1 42512.4 42781.0 42812.1 42893.8 42923.8 8793.4 9676.5 9814.4 9823.3 9844.8 8814.3 8968.6 8987.1 8993.3 11836.6 11816.5 12134.9 12142.5 12178.8 12181.7 11957.2 12034.3 12076.5 12089.2 12090.4 9847.9 9924.8 11908.9 11921.8 11929.0 39828.4 40017.4 40154.4 40172.0 40202.6 3238.2 3354.7 3905.0 4174.9 4263.1 28288.9 28827.8 29920.3 32078.8 32495.0 2273.3 2472.9 3009.8 3036.6 3065.0 508138.0 512294.0 513551.0 513766.0 520355.4 58874.7 59593.0 59607.0 59712.0 59868.4 61443.1 61674.0 62066.0 62169.0 62217.7 15968.6 16217.8 16249.4 16254.0 16293.2 12623.6 12682.7 12692.7 12695.3 12704.5 14193.5 14533.7 15827.5 16225.4 16408.7 6236.5 6495.4 6544.0 7074.6 7158.7 695.6 830.8 887.2 976.5 1005.7 26489.3 30164.5 30296.5 30679.9 31088.5 996.9 1081.5 1094.9 1152.2 1167.4 14737.2 15643.0 15664.8 15673.8 16026.7 627181.0 628681.0 629702.0 632648.0 634994.0 50090.7 57344.1 58407.0 58479.0 63919.4 1386.4 1401.2 1436.0 1437.0 1437.4 30499.1 31907.8 32224.0 32408.7 32464.8 50934.7 51506.2 51531.0 51536.0 51551.7 585028.0 589035.0 589942.0 590902.0 591455.0 38937.4 39863.0 40102.0 40130.8 40132.6 47300.0 47937.0 48157.8 48266.0 49246.0 37422.3 39238.6 39442.2 40242.6 40675.8 37352.0 38945.2 40050.2 40520.4 40840.0 38187.2 39204.4 39222.2 39337.6 39553.2 28700.7 30298.4 34586.3 35631.6 35912.0 25226.0 25812.2 25883.7 25907.9 26251.6 11:01:19 INFO - PROCESS | 1645 | 11:01:19 INFO - PROCESS | 1645 | [#2] jquery.html Cycles:180 Average:34650.07 Median:1630.08 stddev:98498.32 (6042.5%) stddev-sans-first:98740.96 11:01:19 INFO - PROCESS | 1645 | Values: 349.3 373.4 375.9 387.7 407.9 15625.0 16131.0 20953.5 21071.9 21136.7 20990.0 21349.3 21415.8 21670.3 22048.3 4934.4 5867.3 6077.8 6129.3 6183.8 3986.3 6023.3 6116.6 6144.9 6445.6 6712.5 6735.9 6758.1 6777.4 6822.2 5857.6 6143.2 6197.3 6224.9 6797.8 5817.5 5993.0 6068.5 6074.0 6085.5 13346.7 13489.3 13624.9 13624.9 13656.4 2280.4 2357.6 2565.3 2731.7 2910.9 9912.9 9982.9 10031.1 10543.0 10637.7 1723.9 1827.8 1925.9 1948.2 1966.3 107387.0 111550.0 122088.0 123151.4 123324.0 9396.9 11250.3 11404.3 11439.8 11470.8 14297.1 14313.7 14323.1 14324.8 14331.9 8107.0 8388.7 8413.2 8552.4 8611.6 6763.4 7592.9 8044.6 8305.3 8448.0 4104.5 4407.0 4456.1 4457.3 4523.0 1685.9 1879.7 1890.3 1890.7 1891.7 724.9 849.9 903.7 930.2 1006.4 19819.6 20783.9 20921.1 21574.6 21630.9 915.4 949.5 969.1 976.6 1035.5 8981.4 10544.8 11066.2 11249.8 11259.8 579196.0 582183.1 593426.0 593926.0 594326.0 112692.3 125197.0 132332.0 133472.0 135185.0 924.3 925.1 952.6 952.6 953.7 18958.8 19236.5 19261.9 19272.0 19278.6 52446.7 55743.7 56424.7 56612.0 57132.0 75717.6 86251.0 86561.6 87514.0 87965.1 7697.2 7747.7 7966.7 8040.9 8303.4 10698.0 12714.9 12736.0 13116.4 13194.0 10085.7 10559.7 10838.0 11226.9 11350.8 10632.8 10952.7 11086.7 11398.3 11442.5 10832.1 11205.3 11369.4 11731.6 11952.6 7868.4 8030.9 8106.3 8164.5 8405.1 8030.4 8340.3 11179.6 11536.0 11542.7 11:01:19 INFO - PROCESS | 1645 | 11:01:19 INFO - PROCESS | 1645 | [#3] mootools.html Cycles:180 Average:5682.55 Median:4707.37 stddev:3199.07 (68.0%) stddev-sans-first:3191.58 11:01:19 INFO - PROCESS | 1645 | Values: 1364.2 1520.9 1546.9 1710.4 1858.3 7801.2 8568.1 8847.0 8890.0 8956.8 8695.7 8780.3 8793.3 8796.7 8797.6 2373.6 2402.2 2403.9 2406.0 2408.3 2350.6 2383.4 2396.9 2403.5 2408.7 2364.8 2389.1 2401.9 2407.3 2413.2 2337.6 2349.7 2352.8 2354.5 2390.0 2321.5 2345.1 2349.9 2352.5 2356.4 8645.0 8708.8 8777.3 8814.6 8870.5 5282.4 5477.8 5496.8 5497.1 5507.1 3545.6 3551.6 3605.4 3623.4 3625.1 1067.4 1075.9 1076.5 1094.4 1103.4 11550.5 11657.9 11702.4 11713.0 11723.8 4371.6 4501.6 4642.0 4671.3 4688.7 9188.0 9197.9 9201.4 9224.7 9233.7 5262.5 5262.9 5263.0 5272.0 5276.2 4296.5 4443.8 4600.1 4890.0 4917.2 3733.0 3771.1 3888.5 3893.8 3902.8 1149.6 1164.2 1168.2 1168.6 1175.5 745.8 806.2 818.7 821.5 937.3 5676.2 5806.1 6047.2 6067.9 6203.5 427.6 433.2 439.9 440.2 448.1 3982.7 4003.4 4058.3 4084.5 4099.0 9905.5 10582.2 10744.0 10750.9 10756.4 10333.2 10688.9 10700.3 10708.3 10747.6 8103.7 8287.0 8300.3 8318.1 8333.7 5727.9 5778.3 5828.3 5904.8 5916.9 10008.1 10015.2 10079.5 10082.6 10100.9 9641.9 10207.7 10223.2 10237.1 10244.3 6579.5 6801.8 7078.4 7167.7 7194.2 8219.2 8345.1 8504.1 8620.7 8650.6 6471.9 7556.2 7676.8 7679.7 7821.4 7512.0 7515.0 7534.9 7561.4 7637.4 6994.0 7162.7 7172.3 7183.7 7186.0 4726.0 5037.7 5062.7 5101.1 5188.4 4992.2 5025.8 5187.0 5243.1 5298.3 11:01:19 INFO - PROCESS | 1645 | 11:01:19 INFO - PROCESS | 1645 | [#4] prototype.html Cycles:180 Average:4257.97 Median:4665.07 stddev:1284.03 (27.5%) stddev-sans-first:1252.46 11:01:19 INFO - PROCESS | 1645 | Values: 281.7 289.1 301.9 314.5 318.1 4784.3 5121.8 5277.8 5304.5 5350.6 4452.6 4923.0 5101.5 5160.3 5240.6 3377.9 4054.5 4073.9 4076.9 4095.7 3523.9 3900.7 3994.8 4135.4 4138.3 4008.8 4077.0 4095.3 4097.2 4121.4 3583.3 3892.5 4097.9 4099.4 4108.2 3587.6 3598.5 3606.5 3606.7 3610.7 4499.6 5039.6 5150.3 5157.2 5179.9 4653.3 4687.4 4690.7 4718.1 4732.9 4639.7 4794.6 5529.6 5590.0 5596.9 3229.8 3525.9 3642.8 3928.3 3960.7 5785.4 5802.2 5807.6 5808.4 5834.1 4004.9 4013.4 4019.8 4023.9 4050.6 3584.9 3653.9 3867.9 4063.6 4090.9 4127.3 4201.0 4573.5 4613.7 4637.8 5069.8 5282.7 5510.0 5611.0 5623.0 1189.3 1326.8 1332.6 1363.9 1366.5 846.8 926.0 929.3 958.8 965.4 4317.3 5102.4 5152.5 5184.5 5192.5 4405.0 4894.3 5496.6 5558.3 5602.3 4934.0 4990.4 5124.8 5127.7 5131.6 1956.5 2195.7 2369.8 2432.4 2485.5 4125.5 4709.5 4869.6 4879.9 4887.8 3583.0 4271.2 4443.1 4774.0 4785.5 3927.9 4450.5 4514.8 4785.7 4851.7 3981.0 4203.9 4243.7 4676.9 4792.5 3986.2 4031.6 4068.0 4071.5 4517.7 4078.7 4314.6 4708.8 4720.1 4754.3 3274.9 3326.8 3375.3 3379.3 3406.3 4461.9 4767.4 4769.3 4799.6 5438.7 4801.8 5311.9 5493.7 5495.0 5601.7 4409.6 5409.8 5447.6 5461.9 5480.5 5160.6 5382.8 5405.4 5437.5 5472.6 4630.7 4942.7 5480.4 5507.6 5530.8 5413.1 5432.9 5473.7 5479.0 5508.3 11:01:19 INFO - PROCESS | 1645 | 11:01:19 INFO - PROCESS | 1645 | [#5] yui.html Cycles:180 Average:22843.86 Median:4225.16 stddev:35982.42 (851.6%) stddev-sans-first:36044.88 11:01:19 INFO - PROCESS | 1645 | Values: 680.9 696.8 753.3 754.3 816.7 13716.2 15543.5 16274.2 16286.2 16315.3 14522.6 14725.2 14739.9 14753.1 14797.1 705.3 724.8 861.7 876.0 904.5 888.3 901.3 901.8 904.0 904.8 957.3 961.9 963.3 964.2 964.9 751.1 773.5 774.0 951.6 954.4 571.7 682.5 709.3 710.2 712.5 14529.6 14537.3 14538.8 14562.6 14574.0 13627.4 13815.8 14065.0 14085.0 14137.9 12911.9 13077.9 13254.0 13275.6 13552.4 4500.5 4528.5 4750.2 4823.0 4829.3 118720.2 120510.2 121139.0 121214.8 123988.0 9628.4 10755.8 11081.9 11399.7 11404.4 21466.7 21470.8 21480.5 21493.6 21515.4 7794.0 8009.9 8034.5 8177.4 8179.6 4030.5 4046.6 4163.8 4167.3 4256.5 3400.9 3459.6 3519.6 3523.8 3536.6 544.3 553.8 562.5 569.9 578.1 507.1 525.3 596.9 597.8 601.2 7928.6 8159.1 8311.4 8376.7 8621.3 414.8 529.7 549.9 556.9 583.7 4005.7 4153.7 4179.1 4193.9 4330.7 99195.0 99908.0 100909.5 101576.0 139954.0 101699.5 121901.0 122296.3 127640.4 128124.0 76763.6 104937.5 112712.0 114384.4 114984.0 8172.8 8405.5 8775.5 9407.9 9636.9 58930.0 60715.0 61038.0 62646.4 65655.7 68143.0 69780.0 73592.9 82859.2 95244.0 10791.1 10819.3 10906.2 11146.8 11274.7 11446.0 11759.7 11896.3 12105.9 12120.6 11145.1 11163.9 11189.5 11221.3 11549.0 11471.8 11678.4 11922.3 11954.8 11982.3 10284.8 10901.5 11280.5 11364.4 11558.0 8340.7 9231.5 9508.3 9759.3 10163.9 7687.7 8817.6 9571.5 9593.5 9869.9 11:01:19 INFO - PROCESS | 1645 | -------- Summary: end -------- 11:01:19 INFO - PROCESS | 1645 | 11:01:19 INFO - TEST-INFO | 1645: exit 0 11:01:19 INFO - TEST-OK | dromaeo_css | took 1117118ms 11:01:19 INFO - TEST-START | kraken 11:01:19 INFO - operating with platform_type : mac_ 11:01:19 INFO - Initialising browser for kraken test... 11:01:19 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmp0TVQjD/profile http://localhost:49267/getInfo.html) 11:01:21 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 11:01:21 INFO - PROCESS | 1656 | colorDepth:24 11:01:21 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 11:01:21 INFO - PROCESS | 1656 | __metrics 11:01:22 INFO - PROCESS | 1656 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:01:22 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:01:22 INFO - TEST-INFO | 1656: exit 0 11:01:22 INFO - Browser initialized. 11:01:22 INFO - Running cycle 1/1 for kraken test... 11:01:22 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmp0TVQjD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:01:34 INFO - PROCESS | 1657 | RSS: Main: 232505344 11:01:34 INFO - PROCESS | 1657 | 11:01:37 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 11:01:38 INFO - PROCESS | 1657 | RSS: Main: 290770944 11:01:38 INFO - PROCESS | 1657 | 11:01:40 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 11:01:41 INFO - PROCESS | 1657 | RSS: Main: 285835264 11:01:41 INFO - PROCESS | 1657 | 11:01:44 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 11:01:45 INFO - PROCESS | 1657 | RSS: Main: 374206464 11:01:45 INFO - PROCESS | 1657 | 11:01:47 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 11:01:48 INFO - PROCESS | 1657 | RSS: Main: 277319680 11:01:48 INFO - PROCESS | 1657 | 11:01:50 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:01:50 INFO - PROCESS | 1657 | RSS: Main: 280363008 11:01:50 INFO - PROCESS | 1657 | 11:01:53 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 11:01:54 INFO - PROCESS | 1657 | RSS: Main: 319098880 11:01:54 INFO - PROCESS | 1657 | 11:01:57 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 11:01:57 INFO - PROCESS | 1657 | RSS: Main: 323555328 11:01:57 INFO - PROCESS | 1657 | 11:02:00 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 11:02:01 INFO - PROCESS | 1657 | RSS: Main: 320307200 11:02:01 INFO - PROCESS | 1657 | 11:02:03 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:02:03 INFO - PROCESS | 1657 | RSS: Main: 294199296 11:02:03 INFO - PROCESS | 1657 | 11:02:05 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:02:06 INFO - PROCESS | 1657 | RSS: Main: 299515904 11:02:06 INFO - PROCESS | 1657 | 11:02:08 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:02:09 INFO - PROCESS | 1657 | RSS: Main: 300568576 11:02:09 INFO - PROCESS | 1657 | 11:02:12 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:02:12 INFO - PROCESS | 1657 | RSS: Main: 319209472 11:02:12 INFO - PROCESS | 1657 | 11:02:15 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:02:16 INFO - PROCESS | 1657 | RSS: Main: 315387904 11:02:16 INFO - PROCESS | 1657 | 11:02:18 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 11:02:18 INFO - PROCESS | 1657 | RSS: Main: 358969344 11:02:18 INFO - PROCESS | 1657 | 11:02:18 INFO - PROCESS | 1657 | __start_tp_report 11:02:18 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 11:02:18 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 11:02:18 INFO - PROCESS | 1657 | |i|pagename|runs| 11:02:18 INFO - PROCESS | 1657 | |0;ai-astar;113;113;129;113;113;112;114;112;112;112 11:02:18 INFO - PROCESS | 1657 | |1;audio-beat-detection;147;113;128;153;126;158;155;117;159;108 11:02:18 INFO - PROCESS | 1657 | |2;audio-dft;185;185;183;184;185;184;179;182;183;180 11:02:18 INFO - PROCESS | 1657 | |3;audio-fft;77;76;75;149;58;57;56;70;140;57 11:02:18 INFO - PROCESS | 1657 | |4;audio-oscillator;82;85;132;69;77;90;115;71;84;155 11:02:18 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;87;83;87;88;88;83;87;84;88;84 11:02:18 INFO - PROCESS | 1657 | |6;imaging-darkroom;92;102;150;126;92;93;94;94;92;100 11:02:18 INFO - PROCESS | 1657 | |7;imaging-desaturate;84;87;105;132;132;88;87;86;86;84 11:02:18 INFO - PROCESS | 1657 | |8;json-parse-financial;57;52;56;53;50;55;54;52;51;50 11:02:18 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;66;67;73;76;74;84;100;102;75;70 11:02:18 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;88;85;89;84;82;101;85;85;84;84 11:02:18 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;137;139;142;156;153;144;142;154;189;140 11:02:18 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;172;168;169;169;170;169;183;155;220;151 11:02:18 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;67;65;68;67;67;68;66;68;67;67 11:02:18 INFO - PROCESS | 1657 | __end_tp_report 11:02:18 INFO - PROCESS | 1657 | __start_cc_report 11:02:18 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,2024 11:02:18 INFO - PROCESS | 1657 | __end_cc_report 11:02:18 INFO - PROCESS | 1657 | __startTimestamp1472148138290__endTimestamp 11:02:18 INFO - PROCESS | 1657 | 11:02:18 INFO - PROCESS | 1657 | ------- Summary: start ------- 11:02:18 INFO - PROCESS | 1657 | Number of tests: 14 11:02:18 INFO - PROCESS | 1657 | 11:02:18 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:114.30 Median:113.00 stddev:5.21 (4.6%) stddev-sans-first:5.50 11:02:18 INFO - PROCESS | 1657 | Values: 113.0 113.0 129.0 113.0 113.0 112.0 114.0 112.0 112.0 112.0 11:02:18 INFO - PROCESS | 1657 | 11:02:18 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:136.40 Median:150.00 stddev:20.06 (13.4%) stddev-sans-first:20.90 11:02:18 INFO - PROCESS | 1657 | Values: 147.0 113.0 128.0 153.0 126.0 158.0 155.0 117.0 159.0 108.0 11:02:18 INFO - PROCESS | 1657 | 11:02:18 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:183.00 Median:184.00 stddev:2.11 (1.1%) stddev-sans-first:2.11 11:02:18 INFO - PROCESS | 1657 | Values: 185.0 185.0 183.0 184.0 185.0 184.0 179.0 182.0 183.0 180.0 11:02:18 INFO - PROCESS | 1657 | 11:02:18 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:81.50 Median:64.00 stddev:34.33 (53.6%) stddev-sans-first:36.37 11:02:18 INFO - PROCESS | 1657 | Values: 77.0 76.0 75.0 149.0 58.0 57.0 56.0 70.0 140.0 57.0 11:02:18 INFO - PROCESS | 1657 | 11:02:18 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:96.00 Median:79.50 stddev:28.58 (35.9%) stddev-sans-first:29.86 11:02:18 INFO - PROCESS | 1657 | Values: 82.0 85.0 132.0 69.0 77.0 90.0 115.0 71.0 84.0 155.0 11:02:18 INFO - PROCESS | 1657 | 11:02:18 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:85.90 Median:87.00 stddev:2.13 (2.5%) stddev-sans-first:2.22 11:02:18 INFO - PROCESS | 1657 | Values: 87.0 83.0 87.0 88.0 88.0 83.0 87.0 84.0 88.0 84.0 11:02:18 INFO - PROCESS | 1657 | 11:02:18 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:103.50 Median:92.00 stddev:19.35 (21.0%) stddev-sans-first:20.07 11:02:18 INFO - PROCESS | 1657 | Values: 92.0 102.0 150.0 126.0 92.0 93.0 94.0 94.0 92.0 100.0 11:02:18 INFO - PROCESS | 1657 | 11:02:18 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:97.10 Median:86.00 stddev:19.36 (22.5%) stddev-sans-first:19.95 11:02:18 INFO - PROCESS | 1657 | Values: 84.0 87.0 105.0 132.0 132.0 88.0 87.0 86.0 86.0 84.0 11:02:18 INFO - PROCESS | 1657 | 11:02:18 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:53.00 Median:53.50 stddev:2.45 (4.6%) stddev-sans-first:2.13 11:02:18 INFO - PROCESS | 1657 | Values: 57.0 52.0 56.0 53.0 50.0 55.0 54.0 52.0 51.0 50.0 11:02:18 INFO - PROCESS | 1657 | 11:02:18 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:78.70 Median:73.50 stddev:12.80 (17.4%) stddev-sans-first:12.72 11:02:18 INFO - PROCESS | 1657 | Values: 66.0 67.0 73.0 76.0 74.0 84.0 100.0 102.0 75.0 70.0 11:02:18 INFO - PROCESS | 1657 | 11:02:18 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:86.70 Median:85.00 stddev:5.42 (6.4%) stddev-sans-first:5.73 11:02:18 INFO - PROCESS | 1657 | Values: 88.0 85.0 89.0 84.0 82.0 101.0 85.0 85.0 84.0 84.0 11:02:18 INFO - PROCESS | 1657 | 11:02:18 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:149.60 Median:148.50 stddev:15.40 (10.4%) stddev-sans-first:15.64 11:02:18 INFO - PROCESS | 1657 | Values: 137.0 139.0 142.0 156.0 153.0 144.0 142.0 154.0 189.0 140.0 11:02:18 INFO - PROCESS | 1657 | 11:02:18 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.60 Median:169.50 stddev:18.85 (11.1%) stddev-sans-first:19.99 11:02:18 INFO - PROCESS | 1657 | Values: 172.0 168.0 169.0 169.0 170.0 169.0 183.0 155.0 220.0 151.0 11:02:18 INFO - PROCESS | 1657 | 11:02:18 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:0.94 (1.4%) stddev-sans-first:1.00 11:02:18 INFO - PROCESS | 1657 | Values: 67.0 65.0 68.0 67.0 67.0 68.0 66.0 68.0 67.0 67.0 11:02:18 INFO - PROCESS | 1657 | -------- Summary: end -------- 11:02:18 INFO - PROCESS | 1657 | 11:02:19 INFO - TEST-INFO | 1657: exit 0 11:02:19 INFO - TEST-OK | kraken | took 59470ms 11:02:19 INFO - SUITE-END | took 1176s 11:02:19 INFO - Completed test suite (00:19:36) 11:02:19 INFO - javascript benchmark 11:02:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3307.900762977111, 3314.5193946877694, 3347.1130150510103, 3376.864925402984, 3415.026717385544, 5551.528120109785, 5580.832575022746, 5667.971660141693, 5669.461401166887, 5684.0, 5274.261603375521, 5611.971940140304, 5688.345840228375, 5711.457411545896, 5735.139729040646, 5072.492750724917, 5102.540771330579, 5154.329937108189, 5179.533841954223, 5266.736663166846, 4329.480462344523, 5050.217216331474, 5290.100682883919, 5314.548263397616, 5317.9468205318035, 5102.464241254652, 5109.131447653907, 5155.175171972504, 5164.741762911839, 5225.529702326789, 5145.382554093495, 5150.047241260341, 5152.330197074394, 5172.250023746573, 5206.557442617382, 5161.148410512261, 5171.991461664977, 5214.973925130388, 5220.243064755589, 5240.397354304266, 5565.888682226333, 5590.524805391547, 5674.3190817101795, 5682.232898558683, 5743.052396354597, 6207.751689932397, 6222.471089957359, 6224.377562243784, 6228.96885515574, 6229.563930524865, 5174.637775355726, 6245.188125543696, 6252.562320637558, 6274.403931626488, 6282.811515654537, 5628.437156284421, 5686.289213848269, 5690.146478028246, 5757.596968212185, 5758.222639943638, 5687.8862422752145, 6132.528193233592, 6286.088517164985, 6288.779892703734, 6301.590396624205, 6154.415330543591, 6162.476189523851, 6172.506199504029, 6172.876542469216, 6173.938260617337, 6077.574569780073, 6110.680268050674, 6139.829471381233, 6181.505479561624, 6230.688465576703, 6113.263474096151, 6149.477294430024, 6173.104899789595, 6189.907151392733, 6204.627722336674, 6226.439620434183, 6245.594036387621, 6248.625082495065, 6251.74993000284, 6256.562040657111, 4786.664933454625, 4895.437024742129, 4940.604751619861, 4948.109340318705, 4973.32860063894, 4382.69321147523, 4435.467743870756, 4461.955380446219, 4508.346289787963, 4574.313852922089, 5679.914801277984, 5715.399883012307, 5722.713864306768, 5724.313082430135, 5734.283214598176, 4989.55094041538, 6243.313235544087, 6263.4362907338555, 6293.244810622755, 6301.8109456716375, 5618.943810561842, 5644.153376993484, 5698.0, 5713.600047996601, 5788.0449725795615, 5379.35447746273, 5396.163594642758, 5397.298351214317, 5402.405735369106, 5410.431904649955, 6242.28213755424, 6267.2792628847355, 6274.905876411857, 6300.495960323163, 6303.747850085945, 6121.8469538260115, 6147.831911936718, 6194.132821404917, 6223.973044447615, 6261.029540421242, 6217.285012223652, 6275.278342990615, 6301.527385446018, 6304.779332723333, 6320.146780184617, 6204.100405441276, 6224.688765561795, 6225.190725205694, 6228.34602366745, 6257.937420625917, 5938.762449501972, 6144.539159562962, 6203.31763506005, 6243.157173781481, 6286.5599408041, 6052.334243233153, 6067.180930574316, 6093.0, 6093.421124993119, 6110.877782444415, 6064.423879731418, 6068.089786532056, 6071.210742603434, 6074.908876366946, 6079.1793107931635, 5689.886202276014, 5768.221290125947, 5835.883282334415, 5846.590738648254, 5910.852228694317, 6014.909776353271, 6022.698865056642, 6038.124471951454, 6049.274087109399, 6082.452579267887, 5857.912131318119, 5871.442212989759, 5875.236219291465, 5876.1773351729935, 5888.17565540816], "value": 5657.830596416922, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [491.90653775782596, 496.2481840012381, 506.95183957524034, 523.6151428699902, 565.4910580477572, 30433.695663043392, 39720.21958462905, 40700.796496017516, 40793.0, 43468.130637387236, 42512.362314565304, 42781.0, 42812.14375712484, 42893.7855310723, 42923.78538107305, 8793.384463087586, 9676.46779427133, 9814.411135331902, 9823.312368134233, 9844.83413648996, 8814.33892458065, 8968.551572421386, 8987.146221108984, 8993.28053755698, 11836.644900652995, 11816.527338906433, 12134.878651213463, 12142.510785289038, 12178.756424871497, 12181.695457613587, 11957.222780519238, 12034.33811140387, 12076.456559454846, 12089.214201076946, 12090.395480226041, 9847.901520984842, 9924.80150396988, 11908.880911190863, 11921.821172682417, 11929.0, 39828.402573961415, 40017.39973900394, 40154.39768403476, 40172.0, 40202.59797402047, 3238.239013831748, 3354.714849237818, 3904.9804750976064, 4174.853880114181, 4263.104748002923, 28288.85855570709, 28827.84688612453, 29920.251993700113, 32078.839605802055, 32495.0, 2273.30664147435, 2472.9134480293283, 3009.8344591047485, 3036.5748795168693, 3064.973233966624, 508138.0, 512294.0, 513551.0, 513766.0, 520355.39822301024, 58874.705626471594, 59593.0, 59607.0, 59712.0, 59868.40131598628, 61443.07835382473, 61674.0, 62066.0, 62169.02647788166, 62217.68891155515, 15968.600784980235, 16217.837821621868, 16249.431269905497, 16254.0, 16293.185340732916, 12623.558175463999, 12682.746345073047, 12692.746145077046, 12695.301758403191, 12704.491820327268, 14193.503227386991, 14533.709325813636, 15827.52517424479, 16225.350985960666, 16408.67182656364, 6236.532260080512, 6495.382938620824, 6544.0224697762405, 7074.646267686698, 7158.677859496335, 695.6347917343435, 830.8421399933993, 887.1704955866156, 976.5312649928075, 1005.6631028605353, 26489.33776655599, 30164.54753178704, 30296.54555181674, 30679.87700717451, 31088.533671994937, 996.9052939970691, 1081.534939975818, 1094.874089479704, 1152.1829127782657, 1167.4396289780952, 14737.1894545801, 15643.0, 15664.843351566566, 15673.764893526606, 16026.679466410607, 627181.0, 628681.0, 629702.0, 632648.0, 634994.0, 50090.749546252766, 57344.13983790246, 58407.0, 58479.0, 63919.36080639134, 1386.4454218312553, 1401.2363262022222, 1435.992820035935, 1436.96407589809, 1437.4465830655065, 30499.08502744921, 31907.840460797546, 32224.0, 32408.675913240564, 32464.83767581147, 50934.74532627313, 51506.22740658968, 51531.0, 51536.0, 51551.74224128856, 585028.0, 589035.0, 589942.0, 590902.0, 591455.0, 38937.44250229959, 39863.0, 40102.0, 40130.79607611776, 40132.59867401405, 47300.0, 47937.0, 48157.75921120512, 48266.0, 49246.0, 37422.25155496929, 39238.607613923494, 39442.211155777295, 40242.59757402389, 40675.77972660825, 37352.0, 38945.2210955785, 40050.1989960205, 40520.3921941177, 40840.0, 38187.2362552753, 39204.41193382045, 39222.2155556893, 39337.606623933396, 39553.2089358217, 28700.712992870638, 30298.394032118795, 34586.30827383388, 35631.643683562834, 35912.0, 25226.0, 25812.22563323103, 25883.741162588885, 25907.87046064833, 26251.606225906246], "value": 20423.001031766045, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.27002564639895, 373.3540974114781, 375.94548790425387, 387.7072810028429, 407.86540441654256, 15625.0, 16130.951488153261, 20953.476163095893, 21071.94640267984, 21136.682949755766, 20990.0, 21349.252776152836, 21415.785842141573, 21670.3498895033, 22048.33854984349, 4934.432540257872, 5867.295924489067, 6077.848053798668, 6129.325774164839, 6183.752649893999, 3986.2824691555506, 6023.30731965823, 6116.602420842653, 6144.938550614482, 6445.613263204223, 6712.5301228913995, 6735.8989615155815, 6758.0538724578355, 6777.423918966869, 6822.181338239393, 5857.648541087548, 6143.232095988002, 6197.256329240475, 6224.906626400608, 6797.76207832726, 5817.534597232211, 5993.011153159723, 6068.484178844804, 6074.0, 6085.452309292141, 13346.733065338736, 13489.258090805002, 13624.863751362458, 13624.863751362558, 13656.385462654105, 2280.4070941555315, 2357.5638506876285, 2565.34583681162, 2731.6721993360925, 2910.893860333081, 9912.851307230398, 9982.90017099827, 10031.097201251923, 10542.998415150549, 10637.680869573926, 1723.9051852148127, 1827.844633206179, 1925.922963081475, 1948.230448972662, 1966.2921348314596, 107387.0, 111550.0, 122088.0, 123151.38424307911, 123324.0, 9396.859047114298, 11250.324980501196, 11404.258723182966, 11439.828402573969, 11470.770584588428, 14297.070690405091, 14313.713725725427, 14323.069000514934, 14324.785128223086, 14331.85668143326, 8107.0, 8388.748337549761, 8413.242808147173, 8552.401331906707, 8611.569421528899, 6763.425108865801, 7592.924070759333, 8044.632412489891, 8305.252527272574, 8448.028476725256, 4104.486939132608, 4406.9779651101535, 4456.064226512404, 4457.286834106528, 4523.027549076898, 1685.890417122883, 1879.6898511745683, 1890.2911408221917, 1890.6596812573594, 1891.6689579323563, 724.8550289941927, 849.9065102838683, 903.6746771162376, 930.2139691960283, 1006.3911333643186, 19819.603607927762, 20783.896080519502, 20921.05855236518, 21574.568508629738, 21630.891845540988, 915.4278575890069, 949.520492151459, 969.14230905649, 976.6386437018207, 1035.4667346316653, 8981.371304008657, 10544.841827372595, 11066.225364224589, 11249.789672681183, 11259.831102533468, 579196.0, 582183.0890845603, 593426.0, 593926.0, 594326.0, 112692.30961535583, 125197.0, 132332.0, 133472.0, 135185.0, 924.2836801478774, 925.1026504290961, 952.552300418802, 952.6141912525438, 953.7472569769017, 18958.810411895705, 19236.519087022938, 19261.903690481457, 19272.0, 19278.614427711087, 52446.73776631245, 55743.72128139334, 56424.71787641035, 56612.0, 57132.0, 75717.62141189259, 86251.0, 86561.56719216364, 87514.0, 87965.1203487957, 7697.191794861459, 7747.651355688895, 7966.721164759206, 8040.87938680932, 8303.418760686694, 10698.0, 12714.936425317814, 12736.0, 13116.409761560944, 13194.0, 10085.747856303651, 10559.683209503728, 10838.0, 11226.887731122584, 11350.829737553779, 10632.84050739255, 10952.671419857417, 11086.722831929266, 11398.25911315795, 11442.542298307975, 10832.133429325793, 11205.319202119746, 11369.374684392107, 11731.648050558497, 11952.58165964187, 7868.370530357673, 8030.87953680707, 8106.310963568163, 8164.469309494863, 8405.117462666332, 8030.357571394157, 8340.291075258428, 11179.552817887194, 11536.0, 11542.711432214262], "value": 9426.914177992841, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1364.2292104960702, 1520.878329733621, 1546.9303881325338, 1710.4355562664323, 1858.2566973210714, 7801.2198780122035, 8568.057513673499, 8847.0, 8889.977652569949, 8956.820863582741, 8695.695650652213, 8780.297576193905, 8793.252573531257, 8796.736097917057, 8797.648094076229, 2373.64395340699, 2402.2072716003677, 2403.879806009701, 2406.0135344508753, 2408.3377071305445, 2350.635651474024, 2383.4041489627593, 2396.928092157238, 2403.531311394279, 2408.722996855358, 2364.775346342103, 2389.080204121417, 2401.8799060047013, 2407.3018824540864, 2413.2398294537165, 2337.637666161748, 2349.6827928229727, 2352.8235382346393, 2354.4702441950594, 2389.9522009559887, 2321.4892723600783, 2345.0971376020184, 2349.917752878641, 2352.5294941011866, 2356.36378177891, 8645.0, 8708.782280442976, 8777.253933415668, 8814.603342849587, 8870.467771933705, 5282.445343238943, 5477.780888764445, 5496.7526461308935, 5497.147942068986, 5507.091329930557, 3545.556805399325, 3551.6093229744706, 3605.387084195693, 3623.438367053111, 3625.075605720551, 1067.3542506783363, 1075.9139268858473, 1076.5101878645198, 1094.3543309447464, 1103.3710784852638, 11550.53797848085, 11657.94171029148, 11702.356370399624, 11713.004394626467, 11723.824142637868, 4371.628411584988, 4501.572350626685, 4642.0, 4671.32265821454, 4688.742119183477, 9188.0, 9197.862032069525, 9201.355905086579, 9224.723258302261, 9233.722988310361, 5262.500062494057, 5262.868428289324, 5262.973685131627, 5272.0, 5276.182191760283, 4296.5273819879785, 4443.800028998703, 4600.148972440109, 4890.0, 4917.237828136573, 3732.9626703732615, 3771.07608635886, 3888.4944957155953, 3893.805309734502, 3902.824372903169, 1149.6319379937847, 1164.1967042740482, 1168.2231316174818, 1168.5968340922368, 1175.5481979754995, 745.8247311881703, 806.2300503019602, 818.6807145213371, 821.5440430560976, 937.2501998401252, 5676.205331253628, 5806.129080637938, 6047.183630209865, 6067.878642427127, 6203.472704820141, 427.59592185384514, 433.2223658532941, 439.925212713833, 440.1790660418297, 448.09932036606654, 3982.7411218270718, 4003.4395184674167, 4058.301229682287, 4084.4894388201474, 4098.959010409917, 9905.455199963926, 10582.20633452494, 10743.979321964402, 10750.892491075145, 10756.354618722902, 10333.173346132291, 10688.89311106879, 10700.250982431155, 10708.250422470508, 10747.623833165802, 8103.716369927024, 8287.0, 8300.335973122135, 8318.085010648823, 8333.708320208885, 5727.914081288701, 5778.306603207642, 5828.300603927386, 5904.793332233351, 5916.881662366814, 10008.14930730896, 10015.24885633595, 10079.496025198858, 10082.596696132072, 10100.898991009995, 9641.855372169282, 10207.693769186937, 10223.1821454282, 10237.129843963347, 10244.282900196915, 6579.506537009839, 6801.829954251183, 7078.362947334765, 7167.749128780468, 7194.208637050025, 8219.178082191733, 8345.123762005145, 8504.149585041447, 8620.65517379323, 8650.610722517376, 6471.902921456088, 7556.206598307319, 7676.808079797826, 7679.69281228767, 7821.374290056896, 7512.0, 7515.023047004073, 7534.886976695464, 7561.357284630868, 7637.427192960439, 6994.0, 7162.7493037743425, 7172.318629730167, 7183.748568800067, 7185.964070179615, 4726.0, 5037.697738135724, 5062.746862656779, 5101.107306221471, 5188.351456067991, 4992.176290912104, 5025.824096156617, 5186.974065129651, 5243.134882744457, 5298.284731561344], "value": 4475.4909668464, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [281.65356611368014, 289.1426919184617, 301.8867924528302, 314.4842458368276, 318.10136364769534, 4784.306275590037, 5121.820736274231, 5277.762500687469, 5304.533391972732, 5350.5987050971235, 4452.643788496921, 4922.9907868886885, 5101.515356041179, 5160.329157209567, 5240.6069544784195, 3377.9493307600283, 4054.465958775765, 4073.8981525461954, 4076.8776936691875, 4095.7337773044655, 3523.859045638171, 3900.7074469414765, 3994.840206391741, 4135.407867970838, 4138.255114079465, 4008.779517126557, 4077.022644017649, 4095.3242714952, 4097.221527909703, 4121.443605113302, 3583.283343331344, 3892.4550562921213, 4097.926555083687, 4099.355915434558, 4108.198901214264, 3587.6053634100226, 3598.478220658016, 3606.5131207286945, 3606.747527673064, 3610.656987586175, 4499.572540608637, 5039.626295091128, 5150.27896094547, 5157.200633901752, 5179.948200517984, 4653.278741794993, 4687.367205427257, 4690.742009189493, 4718.056388722235, 4732.929006064912, 4639.6752227344095, 4794.640401969867, 5529.557635389159, 5589.972050139764, 5596.916046259309, 3229.838508074587, 3525.894223173309, 3642.7996460194677, 3928.3125453045604, 3960.742551734128, 5785.363610002896, 5802.158686990363, 5807.622504537191, 5808.390119037483, 5834.124881267844, 4004.939925901114, 4013.3979903014783, 4019.7588144711412, 4023.9336575807433, 4050.5746896576024, 3584.946225806615, 3653.903828851334, 3867.9226415472094, 4063.593640635913, 4090.8954582262622, 4127.318992366286, 4201.0, 4573.474050484237, 4613.723176609415, 4637.837675681335, 5069.8479045627955, 5282.652923504558, 5510.035743744828, 5610.990021796034, 5622.971885140548, 1189.2864281431073, 1326.840779106494, 1332.580237225271, 1363.9454421823016, 1366.5285476510587, 846.7586737779703, 925.9583318750672, 929.2937367600672, 958.7794807194244, 965.3725078698902, 4317.309230523101, 5102.36220472441, 5152.510511501401, 5184.455632158569, 5192.506711862367, 4404.995726944233, 4894.325918747588, 5496.560275177976, 5558.305211848519, 5602.327720673546, 4933.980206476325, 4990.351254336985, 5124.795008199705, 5127.743612819344, 5131.61512886535, 1956.513050081948, 2195.6926030355444, 2369.7630236976165, 2432.4216330689524, 2485.4590154104208, 4125.525564560054, 4709.481956984729, 4869.56347877377, 4879.926416188433, 4887.780049897692, 3582.972388331855, 4271.188474189894, 4443.111377724404, 4773.95226047742, 4785.49752276006, 3927.92144157121, 4450.488193857748, 4514.848713578017, 4785.665003449794, 4851.733154676456, 3981.024648961023, 4203.906984184073, 4243.68172387066, 4676.859694209179, 4792.466410748527, 3986.242613903349, 4031.596840315945, 4068.0440096577872, 4071.5114186297833, 4517.661175411791, 4078.7144899856726, 4314.633256173135, 4708.764561771966, 4720.103180395714, 4754.337570960135, 3274.918127046843, 3326.7837590556537, 3375.291188850369, 3379.30724201543, 3406.284680217182, 4461.933071004002, 4767.404074490689, 4769.259220384465, 4799.616030717464, 5438.700871452108, 4801.759912004316, 5311.857950540652, 5493.725313734377, 5494.983428065821, 5601.663900166003, 4409.5889315420045, 5409.80984183479, 5447.591430642638, 5461.9180712290145, 5480.479354461319, 5160.587153027824, 5382.842688821922, 5405.432429594836, 5437.537809286255, 5472.644278121909, 4630.652701047502, 4942.728149951788, 5480.3971563127225, 5507.642003269774, 5530.80642177538, 5413.079776438094, 5432.891342173055, 5473.698946557977, 5479.013777520085, 5508.283923089973], "value": 3859.8931417544422, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [680.8910574308112, 696.7631005458146, 753.3257734327776, 754.3286475037216, 816.7182322098873, 13716.177029378245, 15543.455979040737, 16274.186290685477, 16286.185690715476, 16315.265813038412, 14522.636934076654, 14725.190114543695, 14739.926300368483, 14753.114813111193, 14797.112173269612, 705.2735682247268, 724.8115489972619, 861.7285555050155, 875.951822649754, 904.4844438669961, 888.2716172738357, 901.324006994754, 901.7925877048283, 904.023654453189, 904.8235594059161, 957.3107362698846, 961.8990006049369, 963.2967933408615, 964.2430691906854, 964.8600952861831, 751.113685850696, 773.5242825662177, 774.0169984120135, 951.6383774165807, 954.3844220477788, 571.7398583644432, 682.4608559238245, 709.2694524639629, 710.1868360726938, 712.5332906945958, 14529.56411307652, 14537.345819438046, 14538.781918271128, 14562.635934101732, 14573.979821412506, 13627.386767595483, 13815.792763108562, 14065.0, 14085.0, 14137.929310353382, 12911.935440322832, 13077.869221307856, 13254.0, 13275.601731948056, 13552.361396303984, 4500.459944806611, 4528.50186479487, 4750.216214324632, 4823.03539292143, 4829.251466022772, 118720.21919671126, 120510.19234711486, 121139.0, 121214.78785212123, 123988.0, 9628.422294662203, 10755.83866242007, 11081.889181108085, 11399.715007124889, 11404.429778511041, 21466.677999830015, 21470.819104949376, 21480.4629884251, 21493.570128597654, 21515.354539363845, 7794.025746781653, 8009.879851802228, 8034.47775894565, 8177.427580069457, 8179.631916563769, 4030.5163380394542, 4046.5751096135073, 4163.812628431668, 4167.291560434734, 4256.4679415073115, 3400.931981360359, 3459.584849818038, 3519.59524654668, 3523.841427135785, 3536.5579302587175, 544.3250369541819, 553.8310815201322, 562.4684672984073, 569.8831739493394, 578.0981668597002, 507.0998976816181, 525.254139122447, 596.8567543789459, 597.8445604142867, 601.1613798750806, 7928.603569821601, 8159.061707903667, 8311.418200725891, 8376.664933402717, 8621.31029517637, 414.8236999275296, 529.6795438759572, 549.8542886135177, 556.9136783798517, 583.743153012673, 4005.708184059028, 4153.6677065834665, 4179.1014931789205, 4193.853215137517, 4330.696851220384, 99195.00804992003, 99908.00091999132, 100909.49545252227, 101576.0, 139954.0, 101699.49150254202, 121901.0, 122296.2737049453, 127640.36179819227, 128124.0, 76763.61618191874, 104937.47531262448, 112712.0, 114384.42807785908, 114984.0, 8172.8365432689825, 8405.453645513022, 8775.473471591726, 9407.905920940977, 9636.855447168158, 58930.0, 60715.0, 61038.0, 62646.37353626588, 65655.67172164109, 68143.0, 69780.0, 73592.89610655737, 82859.17140828514, 95244.0, 10791.1367090631, 10819.296745711503, 10906.236563440483, 11146.832797508207, 11274.661760147208, 11446.0, 11759.745220402292, 11896.286222826659, 12105.939470302592, 12120.636380908234, 11145.052670523097, 11163.88836111661, 11189.496472658588, 11221.326720396803, 11549.01833344175, 11471.770564588829, 11678.357690327113, 11922.284662920252, 11954.76090478203, 11982.340971246666, 10284.8116984393, 10901.509432075418, 11280.49237784318, 11364.374959377314, 11558.0, 8340.708075217339, 9231.49226792506, 9508.334416590773, 9759.268054896052, 10163.898361016296, 7687.692492300245, 8817.559122044, 9571.473568953495, 9593.520323983634, 9869.901300987185], "value": 7355.275821257866, "unit": "score"}], "name": "dromaeo_css", "value": 7192.293367279454, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 129.0, 113.0, 113.0, 112.0, 114.0, 112.0, 112.0, 112.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 113.0, 128.0, 153.0, 126.0, 158.0, 155.0, 117.0, 159.0, 108.0], "value": 136.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 183.0, 184.0, 185.0, 184.0, 179.0, 182.0, 183.0, 180.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 75.0, 149.0, 58.0, 57.0, 56.0, 70.0, 140.0, 57.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 132.0, 69.0, 77.0, 90.0, 115.0, 71.0, 84.0, 155.0], "value": 96.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 87.0, 88.0, 88.0, 83.0, 87.0, 84.0, 88.0, 84.0], "value": 85.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 102.0, 150.0, 126.0, 92.0, 93.0, 94.0, 94.0, 92.0, 100.0], "value": 103.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 105.0, 132.0, 132.0, 88.0, 87.0, 86.0, 86.0, 84.0], "value": 97.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 52.0, 56.0, 53.0, 50.0, 55.0, 54.0, 52.0, 51.0, 50.0], "value": 53.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 73.0, 76.0, 74.0, 84.0, 100.0, 102.0, 75.0, 70.0], "value": 78.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 89.0, 84.0, 82.0, 101.0, 85.0, 85.0, 84.0, 84.0], "value": 86.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 139.0, 142.0, 156.0, 153.0, 144.0, 142.0, 154.0, 189.0, 140.0], "value": 149.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 168.0, 169.0, 169.0, 170.0, 169.0, 183.0, 155.0, 220.0, 151.0], "value": 172.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 68.0, 67.0, 67.0, 68.0, 66.0, 68.0, 67.0, 67.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1505.3, "alertThreshold": 2.0}]} 11:02:19 INFO - Return code: 0 11:02:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:02:19 INFO - # TBPL SUCCESS # 11:02:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:02:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:02:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:02:19 INFO - 2016-08-25 11:02:19.606 screenresolution[1661:9887] starting screenresolution argv=screenresolution get 11:02:19 INFO - 2016-08-25 11:02:19.608 screenresolution[1661:9887] Display 0: 1600x1200x32@60 11:02:19 INFO - 2016-08-25 11:02:19.614 screenresolution[1662:9891] starting screenresolution argv=screenresolution list 11:02:19 INFO - Available Modes on Display 0 11:02:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:02:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:02:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:02:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:02:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:02:19 INFO - Intel Iris: 11:02:19 INFO - Chipset Model: Intel Iris 11:02:19 INFO - Type: GPU 11:02:19 INFO - Bus: Built-In 11:02:19 INFO - VRAM (Dynamic, Max): 1536 MB 11:02:19 INFO - Vendor: Intel (0x8086) 11:02:19 INFO - Device ID: 0x0a2e 11:02:19 INFO - Revision ID: 0x0009 11:02:19 INFO - Displays: 11:02:19 INFO - Display: 11:02:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:02:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:02:19 INFO - Display Serial Number: 1600x1200 60 11:02:19 INFO - Main Display: Yes 11:02:19 INFO - Mirror: Off 11:02:19 INFO - Online: Yes 11:02:19 INFO - Rotation: Supported 11:02:19 INFO - Return code: 0 11:02:19 INFO - Running post-action listener: _resource_record_post_action 11:02:19 INFO - [mozharness: 2016-08-25 18:02:19.850261Z] Finished run-tests step (success) 11:02:19 INFO - Running post-run listener: _resource_record_post_run 11:02:19 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 38745088; Write bytes: 702796288; Read time: 384; Write time: 2253 11:02:19 INFO - TinderboxPrint: CPU usage
53.6% 11:02:19 INFO - TinderboxPrint: I/O read bytes / time
38,745,088 / 384 11:02:19 INFO - TinderboxPrint: I/O write bytes / time
702,796,288 / 2,253 11:02:19 INFO - TinderboxPrint: CPU idle
2,246.0 (46.4%) 11:02:19 INFO - TinderboxPrint: CPU system
969.7 (20.0%) 11:02:19 INFO - TinderboxPrint: CPU user
1,630.0 (33.6%) 11:02:19 INFO - TinderboxPrint: Swap in / out
444,317,696 / 0 11:02:19 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 401566208; Write bytes: 395531776; Read time: 29852; Write time: 715 11:02:19 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 36672512; Write bytes: 293538816; Read time: 357; Write time: 1500 11:02:20 INFO - Running post-run listener: _upload_blobber_files 11:02:20 INFO - Blob upload gear active. 11:02:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:02:20 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 11:02:20 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'] 11:02:20 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 11:02:20 INFO - (blobuploader) - INFO - Open directory for files ... 11:02:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:02:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:02:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:02:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:02:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:02:22 INFO - (blobuploader) - INFO - Done attempting. 11:02:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:02:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:02:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:02:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:02:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:02:23 INFO - (blobuploader) - INFO - Done attempting. 11:02:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:02:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:02:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:02:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:02:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:02:26 INFO - (blobuploader) - INFO - Done attempting. 11:02:26 INFO - (blobuploader) - INFO - Iteration through files over. 11:02:26 INFO - Return code: 0 11:02:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:02:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:02:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e9db249faafa35e4615cdc9e3cdf999a524298685f013e898dcdecdfc40be8a33839cdd725715c27ba136d99536c8ee7f4b59badfd7e05cfae21f50b0f6f9c1a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e98e1a2b8e9e3e056fbd86c16444edf5d87d1f4f692c6d4bb9c0fd95be490b5eb823182e660be7f8effe3fabcf33840a1ae4a96badb6f6d9bf703fcc90534b3e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cd4ed8182873c612ff0a316ef486d7f69898ebc77fa05245a2f13ababc745f54d2032a17648b91d6d86f60185e3961f98d89c2d7a62f6d0973cd9e8e1518230c"} 11:02:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:02:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:02:26 INFO - Contents: 11:02:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e9db249faafa35e4615cdc9e3cdf999a524298685f013e898dcdecdfc40be8a33839cdd725715c27ba136d99536c8ee7f4b59badfd7e05cfae21f50b0f6f9c1a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e98e1a2b8e9e3e056fbd86c16444edf5d87d1f4f692c6d4bb9c0fd95be490b5eb823182e660be7f8effe3fabcf33840a1ae4a96badb6f6d9bf703fcc90534b3e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cd4ed8182873c612ff0a316ef486d7f69898ebc77fa05245a2f13ababc745f54d2032a17648b91d6d86f60185e3961f98d89c2d7a62f6d0973cd9e8e1518230c"} 11:02:26 INFO - Running post-run listener: copy_logs_to_upload_dir 11:02:26 INFO - Copying logs to upload dir... 11:02:26 INFO - mkdir: /builds/slave/test/build/upload/logs 11:02:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.886375 ========= master_lag: 56.66 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 12 secs) (at 2016-08-25 11:03:23.276258) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 20 secs) (at 2016-08-25 11:03:23.279976) ========= 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.lVv7xIwCMi/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.nb0pUNAauD/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e9db249faafa35e4615cdc9e3cdf999a524298685f013e898dcdecdfc40be8a33839cdd725715c27ba136d99536c8ee7f4b59badfd7e05cfae21f50b0f6f9c1a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e98e1a2b8e9e3e056fbd86c16444edf5d87d1f4f692c6d4bb9c0fd95be490b5eb823182e660be7f8effe3fabcf33840a1ae4a96badb6f6d9bf703fcc90534b3e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cd4ed8182873c612ff0a316ef486d7f69898ebc77fa05245a2f13ababc745f54d2032a17648b91d6d86f60185e3961f98d89c2d7a62f6d0973cd9e8e1518230c"} build_url:https://queue.taskcluster.net/v1/task/WQW9A1KOQSegELCb_il-vA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011221 build_url: 'https://queue.taskcluster.net/v1/task/WQW9A1KOQSegELCb_il-vA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e9db249faafa35e4615cdc9e3cdf999a524298685f013e898dcdecdfc40be8a33839cdd725715c27ba136d99536c8ee7f4b59badfd7e05cfae21f50b0f6f9c1a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e98e1a2b8e9e3e056fbd86c16444edf5d87d1f4f692c6d4bb9c0fd95be490b5eb823182e660be7f8effe3fabcf33840a1ae4a96badb6f6d9bf703fcc90534b3e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cd4ed8182873c612ff0a316ef486d7f69898ebc77fa05245a2f13ababc745f54d2032a17648b91d6d86f60185e3961f98d89c2d7a62f6d0973cd9e8e1518230c"}' ========= master_lag: 20.29 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 20 secs) (at 2016-08-25 11:03:43.582302) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 mins, 4 secs) (at 2016-08-25 11:03:43.582699) ========= 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.lVv7xIwCMi/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.nb0pUNAauD/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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.005041 ========= master_lag: 64.19 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 1 mins, 4 secs) (at 2016-08-25 11:04:47.781888) ========= ========= Started reboot skipped (results: 3, elapsed: 3 secs) (at 2016-08-25 11:04:47.782239) ========= ========= Finished reboot skipped (results: 3, elapsed: 3 secs) (at 2016-08-25 11:04:50.985723) ========= ========= Total master_lag: 141.22 =========