builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0241 starttime: 1471537518.86 results: success (0) buildid: 20160818070530 builduid: b39b5c6c525045ab9ff21708c87de9d1 revision: e6e5569551be46e7200a5b33afdd22e48474ee34 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 09:25:18.861991) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 09:25:18.862387) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 09:25:18.862653) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 09:25:18.887511) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 09:25:18.887804) ========= 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.8UXcdXnY1t/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.d1lTvilxjJ/Listeners TMPDIR=/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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-18 09:25:18-- 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% 57.6M=0s 2016-08-18 09:25:18 (57.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.099331 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 09:25:19.009610) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 09:25:19.009943) ========= 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.8UXcdXnY1t/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.d1lTvilxjJ/Listeners TMPDIR=/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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.026080 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 09:25:19.058648) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-18 09:25:19.059016) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev e6e5569551be46e7200a5b33afdd22e48474ee34 --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 e6e5569551be46e7200a5b33afdd22e48474ee34 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8UXcdXnY1t/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.d1lTvilxjJ/Listeners TMPDIR=/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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-18 09:25:19,141 truncating revision to first 12 chars 2016-08-18 09:25:19,141 Setting DEBUG logging. 2016-08-18 09:25:19,141 attempt 1/10 2016-08-18 09:25:19,142 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/e6e5569551be?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-18 09:25:20,379 unpacking tar archive at: autoland-e6e5569551be/testing/mozharness/ program finished with exit code 0 elapsedTime=1.511985 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-18 09:25:20.593053) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 09:25:20.593369) ========= script_repo_revision: e6e5569551be46e7200a5b33afdd22e48474ee34 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 09:25:20.593753) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 09:25:20.594020) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 09:25:20.612331) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-18 09:25:20.612618) ========= /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.8UXcdXnY1t/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.d1lTvilxjJ/Listeners TMPDIR=/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 09:25:20 INFO - MultiFileLogger online at 20160818 09:25:20 in /builds/slave/test 09:25:20 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 09:25:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:25:20 INFO - {'append_to_log': False, 09:25:20 INFO - 'base_work_dir': '/builds/slave/test', 09:25:20 INFO - 'blob_upload_branch': 'Autoland', 09:25:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:25:20 INFO - 'branch': 'Autoland', 09:25:20 INFO - 'buildbot_json_path': 'buildprops.json', 09:25:20 INFO - 'config_files': ('talos/mac_config.py',), 09:25:20 INFO - 'default_actions': ('clobber', 09:25:20 INFO - 'read-buildbot-config', 09:25:20 INFO - 'download-and-extract', 09:25:20 INFO - 'populate-webroot', 09:25:20 INFO - 'create-virtualenv', 09:25:20 INFO - 'install', 09:25:20 INFO - 'run-tests'), 09:25:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:25:20 INFO - 'download_minidump_stackwalk': True, 09:25:20 INFO - 'download_symbols': 'ondemand', 09:25:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:25:20 INFO - 'tooltool.py': '/tools/tooltool.py', 09:25:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:25:20 INFO - '/tools/misc-python/virtualenv.py')}, 09:25:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:25:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:25:20 INFO - 'installer_path': 'installer.exe', 09:25:20 INFO - 'log_level': 'info', 09:25:20 INFO - 'log_name': 'talos', 09:25:20 INFO - 'log_to_console': True, 09:25:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:25:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:25:20 INFO - 'opt_config_files': (), 09:25:20 INFO - 'pip_index': False, 09:25:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:25:20 INFO - 'cmd': ('bash', 09:25:20 INFO - '-c', 09:25:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:25:20 INFO - 'enabled': True, 09:25:20 INFO - 'halt_on_failure': False, 09:25:20 INFO - 'name': 'check_screen_resolution'},), 09:25:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:25:20 INFO - 'cmd': ('bash', 09:25:20 INFO - '-c', 09:25:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:25:20 INFO - 'enabled': True, 09:25:20 INFO - 'halt_on_failure': False, 09:25:20 INFO - 'name': 'check_screen_resolution'},), 09:25:20 INFO - 'run_cmd_checks_enabled': True, 09:25:20 INFO - 'sps_profile': False, 09:25:20 INFO - 'sps_profile_interval': 0, 09:25:20 INFO - 'suite': 'dromaeojs', 09:25:20 INFO - 'system_bits': '32', 09:25:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:25:20 INFO - 'title': 't-yosemite-r7-0241', 09:25:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:25:20 INFO - 'use_talos_json': True, 09:25:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:25:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:25:20 INFO - 'work_dir': 'build'} 09:25:20 INFO - [mozharness: 2016-08-18 16:25:20.807154Z] Running clobber step. 09:25:20 INFO - Running pre-action listener: _resource_record_pre_action 09:25:20 INFO - Running main action method: clobber 09:25:20 INFO - rmtree: /builds/slave/test/build 09:25:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:25:22 INFO - Running post-action listener: _resource_record_post_action 09:25:22 INFO - [mozharness: 2016-08-18 16:25:22.822376Z] Finished clobber step (success) 09:25:22 INFO - [mozharness: 2016-08-18 16:25:22.822499Z] Running read-buildbot-config step. 09:25:22 INFO - Running pre-action listener: _resource_record_pre_action 09:25:22 INFO - Running main action method: read_buildbot_config 09:25:22 INFO - Using buildbot properties: 09:25:22 INFO - { 09:25:22 INFO - "project": "", 09:25:22 INFO - "product": "firefox", 09:25:22 INFO - "script_repo_revision": "production", 09:25:22 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 09:25:22 INFO - "repository": "", 09:25:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 09:25:22 INFO - "buildid": "20160818070530", 09:25:22 INFO - "pgo_build": "False", 09:25:22 INFO - "basedir": "/builds/slave/test", 09:25:22 INFO - "buildnumber": 140, 09:25:22 INFO - "slavename": "t-yosemite-r7-0241", 09:25:22 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 09:25:22 INFO - "platform": "yosemite_r7", 09:25:22 INFO - "scheduler": "tests-autoland-macosx64-talos", 09:25:22 INFO - "branch": "autoland", 09:25:22 INFO - "repo_path": "integration/autoland", 09:25:22 INFO - "revision": "e6e5569551be46e7200a5b33afdd22e48474ee34", 09:25:22 INFO - "stage_platform": "macosx64", 09:25:22 INFO - "builduid": "b39b5c6c525045ab9ff21708c87de9d1", 09:25:22 INFO - "slavebuilddir": "test" 09:25:22 INFO - } 09:25:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/dSW55GowQuCuwP31t7XnCg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 09:25:22 INFO - Running post-action listener: _resource_record_post_action 09:25:22 INFO - [mozharness: 2016-08-18 16:25:22.825883Z] Finished read-buildbot-config step (success) 09:25:22 INFO - [mozharness: 2016-08-18 16:25:22.825994Z] Running download-and-extract step. 09:25:22 INFO - Running pre-action listener: _resource_record_pre_action 09:25:22 INFO - Running main action method: download_and_extract 09:25:22 INFO - mkdir: /builds/slave/test/build/tests 09:25: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')]} 09:25:22 INFO - trying https://queue.taskcluster.net/v1/task/dSW55GowQuCuwP31t7XnCg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 09:25:22 INFO - Downloading https://queue.taskcluster.net/v1/task/dSW55GowQuCuwP31t7XnCg/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 09:25:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dSW55GowQuCuwP31t7XnCg/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 09:25:24 INFO - Downloaded 1199 bytes. 09:25:24 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 09:25:24 INFO - Using the following test package requirements: 09:25:24 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:25:24 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:25:24 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 09:25:24 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:25:24 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 09:25:24 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:25:24 INFO - u'jsshell-mac.zip'], 09:25:24 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:25:24 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 09:25:24 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:25:24 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:25:24 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 09:25:24 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:25:24 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 09:25:24 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:25:24 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 09:25:24 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:25:24 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 09:25:24 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:25:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:25:24 INFO - trying https://queue.taskcluster.net/v1/task/dSW55GowQuCuwP31t7XnCg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:25:24 INFO - Downloading https://queue.taskcluster.net/v1/task/dSW55GowQuCuwP31t7XnCg/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 09:25:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dSW55GowQuCuwP31t7XnCg/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 09:25:25 INFO - Downloaded 16339538 bytes. 09:25:25 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 09:25:31 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 09:25: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')]} 09:25:31 INFO - trying https://queue.taskcluster.net/v1/task/dSW55GowQuCuwP31t7XnCg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:25:31 INFO - Downloading https://queue.taskcluster.net/v1/task/dSW55GowQuCuwP31t7XnCg/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 09:25:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dSW55GowQuCuwP31t7XnCg/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 09:25:32 INFO - Downloaded 16339538 bytes. 09:25:32 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 09:25: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')]} 09:25:39 INFO - trying https://queue.taskcluster.net/v1/task/dSW55GowQuCuwP31t7XnCg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 09:25:39 INFO - Downloading https://queue.taskcluster.net/v1/task/dSW55GowQuCuwP31t7XnCg/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 09:25:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dSW55GowQuCuwP31t7XnCg/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 09:25:40 INFO - Downloaded 14413981 bytes. 09:25:40 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 09:25:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:25:41 INFO - trying https://queue.taskcluster.net/v1/task/dSW55GowQuCuwP31t7XnCg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:25:41 INFO - Downloading https://queue.taskcluster.net/v1/task/dSW55GowQuCuwP31t7XnCg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 09:25:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dSW55GowQuCuwP31t7XnCg/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 09:25:43 INFO - Downloaded 111266585 bytes. 09:25:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dSW55GowQuCuwP31t7XnCg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:25:43 INFO - mkdir: /builds/slave/test/properties 09:25:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:25:43 INFO - Writing to file /builds/slave/test/properties/build_url 09:25:43 INFO - Contents: 09:25:43 INFO - build_url:https://queue.taskcluster.net/v1/task/dSW55GowQuCuwP31t7XnCg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:25:44 INFO - Running post-action listener: _resource_record_post_action 09:25:44 INFO - Running post-action listener: set_extra_try_arguments 09:25:44 INFO - [mozharness: 2016-08-18 16:25:44.719593Z] Finished download-and-extract step (success) 09:25:44 INFO - [mozharness: 2016-08-18 16:25:44.719752Z] Running populate-webroot step. 09:25:44 INFO - Running pre-action listener: _resource_record_pre_action 09:25:44 INFO - Running main action method: populate_webroot 09:25:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:25:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:25:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:25:44 INFO - '--tppagecycles', 09:25:44 INFO - '7'], 09:25:44 INFO - 'tests': ['tsvgm']}}, 09:25:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:25:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:25:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:25:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:25:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:25:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:25:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:25:44 INFO - 'talos_options': ['--disable-e10s'], 09:25:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:25:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:25:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:25:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:25:44 INFO - 'talos_options': ['--disable-e10s'], 09:25:44 INFO - 'tests': ['damp', 'tps']}, 09:25:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:25:44 INFO - 'tests': ['damp', 'tps']}, 09:25:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:25:44 INFO - 'tests': ['dromaeo_dom']}, 09:25:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:25:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:25:44 INFO - 'tests': ['basic_compositor_video']}, 09:25:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:25:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:25:44 INFO - 'tests': ['a11yr', 09:25:44 INFO - 'ts_paint', 09:25:44 INFO - 'tpaint', 09:25:44 INFO - 'sessionrestore', 09:25:44 INFO - 'sessionrestore_no_auto_restore', 09:25:44 INFO - 'tabpaint']}, 09:25:44 INFO - 'other-e10s': {'tests': ['a11yr', 09:25:44 INFO - 'ts_paint', 09:25:44 INFO - 'tpaint', 09:25:44 INFO - 'sessionrestore', 09:25:44 INFO - 'sessionrestore_no_auto_restore', 09:25:44 INFO - 'tabpaint']}, 09:25:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:25:44 INFO - 'ts_paint', 09:25:44 INFO - 'tpaint', 09:25:44 INFO - 'sessionrestore', 09:25:44 INFO - 'sessionrestore_no_auto_restore', 09:25:44 INFO - 'tabpaint']}, 09:25:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:25:44 INFO - 'ts_paint', 09:25:44 INFO - 'tpaint', 09:25:44 INFO - 'sessionrestore', 09:25:44 INFO - 'sessionrestore_no_auto_restore', 09:25:44 INFO - 'tabpaint']}, 09:25:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:25:44 INFO - 'tests': ['a11yr', 09:25:44 INFO - 'ts_paint', 09:25:44 INFO - 'tpaint', 09:25:44 INFO - 'sessionrestore', 09:25:44 INFO - 'sessionrestore_no_auto_restore', 09:25:44 INFO - 'tabpaint']}, 09:25:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:25:44 INFO - 'tests': ['a11yr', 09:25:44 INFO - 'ts_paint', 09:25:44 INFO - 'tpaint', 09:25:44 INFO - 'sessionrestore', 09:25:44 INFO - 'sessionrestore_no_auto_restore', 09:25:44 INFO - 'tabpaint']}, 09:25:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:25:44 INFO - 'tests': ['tsvgx', 09:25:44 INFO - 'tsvgr_opacity', 09:25:44 INFO - 'tart', 09:25:44 INFO - 'tscrollx', 09:25:44 INFO - 'cart']}, 09:25:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:25:44 INFO - 'tsvgr_opacity', 09:25:44 INFO - 'tart', 09:25:44 INFO - 'tscrollx', 09:25:44 INFO - 'cart']}, 09:25:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:25:44 INFO - 'talos_options': ['--disable-e10s'], 09:25:44 INFO - 'tests': ['tp5o']}, 09:25:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:25:44 INFO - 'tests': ['tp5o']}, 09:25:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:25:44 INFO - 'talos_options': ['--disable-e10s', 09:25:44 INFO - '--xperf_path', 09:25:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:25:44 INFO - 'tests': ['tp5n']}, 09:25:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:25:44 INFO - 'talos_options': ['--xperf_path', 09:25:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:25:44 INFO - 'tests': ['tp5n']}}, 09:25:44 INFO - 'talos.zip': {'path': '', 09:25:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:25:44 INFO - Running post-action listener: _resource_record_post_action 09:25:44 INFO - [mozharness: 2016-08-18 16:25:44.738172Z] Finished populate-webroot step (success) 09:25:44 INFO - [mozharness: 2016-08-18 16:25:44.738282Z] Running create-virtualenv step. 09:25:44 INFO - Running pre-action listener: _resource_record_pre_action 09:25:44 INFO - Running main action method: create_virtualenv 09:25:44 INFO - Creating virtualenv /builds/slave/test/build/venv 09:25:44 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 09:25:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:25:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:25:44 INFO - Using real prefix '/tools/python27' 09:25:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:25:45 INFO - Installing distribute.............................................................................................................................................................................................done. 09:25:48 INFO - Installing pip.................done. 09:25:48 INFO - Return code: 0 09:25:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:25:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:25:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:25:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:25:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:25:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e220a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2b5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5e158cd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e184d30>, '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 0x10dee9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e17cb20>, '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.8UXcdXnY1t/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.d1lTvilxjJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 09:25:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:25:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:25:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8UXcdXnY1t/Render', 09:25:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:25:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:25:48 INFO - 'HOME': '/Users/cltbld', 09:25:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:25:48 INFO - 'LOGNAME': 'cltbld', 09:25:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:25:48 INFO - 'MOZ_NO_REMOTE': '1', 09:25:48 INFO - 'NO_EM_RESTART': '1', 09:25:48 INFO - 'PAGER': '/bin/cat', 09:25:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:25:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:25:48 INFO - 'PWD': '/builds/slave/test', 09:25:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:25:48 INFO - 'SHELL': '/bin/bash', 09:25:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d1lTvilxjJ/Listeners', 09:25:48 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 09:25:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:25:48 INFO - 'USER': 'cltbld', 09:25:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:25:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:25:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:25:48 INFO - 'XPC_FLAGS': '0x0', 09:25:48 INFO - 'XPC_SERVICE_NAME': '0', 09:25:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:25:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:25:48 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 09:25:48 INFO - Cleaning up... 09:25:48 INFO - Return code: 0 09:25:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:25:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:25:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:25:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:25:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:25:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e220a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2b5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5e158cd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e184d30>, '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 0x10dee9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e17cb20>, '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.8UXcdXnY1t/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.d1lTvilxjJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 09:25:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:25:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:25:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8UXcdXnY1t/Render', 09:25:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:25:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:25:48 INFO - 'HOME': '/Users/cltbld', 09:25:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:25:48 INFO - 'LOGNAME': 'cltbld', 09:25:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:25:48 INFO - 'MOZ_NO_REMOTE': '1', 09:25:48 INFO - 'NO_EM_RESTART': '1', 09:25:48 INFO - 'PAGER': '/bin/cat', 09:25:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:25:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:25:48 INFO - 'PWD': '/builds/slave/test', 09:25:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:25:48 INFO - 'SHELL': '/bin/bash', 09:25:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d1lTvilxjJ/Listeners', 09:25:48 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 09:25:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:25:48 INFO - 'USER': 'cltbld', 09:25:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:25:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:25:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:25:48 INFO - 'XPC_FLAGS': '0x0', 09:25:48 INFO - 'XPC_SERVICE_NAME': '0', 09:25:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:25:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:25:48 INFO - Downloading/unpacking psutil>=3.1.1 09:25:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:25:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:25:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:25:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:25:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:25:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:25:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:25:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:25:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:25:52 INFO - Installing collected packages: psutil 09:25:52 INFO - Running setup.py install for psutil 09:25:52 INFO - building 'psutil._psutil_osx' extension 09:25:52 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 09:25:52 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 09:25:52 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 09:25:53 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 09:25:53 INFO - building 'psutil._psutil_posix' extension 09:25:53 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 09:25:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:25:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:25:53 INFO - ^ 09:25:53 INFO - 1 warning generated. 09:25:53 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 09:25:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:25:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:25:53 INFO - Successfully installed psutil 09:25:53 INFO - Cleaning up... 09:25:53 INFO - Return code: 0 09:25:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:25:53 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')]} 09:25:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:25:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:53 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')]} 09:25:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:25:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:53 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 0x10e220a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2b5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5e158cd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e184d30>, '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 0x10dee9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e17cb20>, '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.8UXcdXnY1t/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.d1lTvilxjJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 09:25:53 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 09:25:53 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 09:25:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8UXcdXnY1t/Render', 09:25:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:25:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:25:53 INFO - 'HOME': '/Users/cltbld', 09:25:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:25:53 INFO - 'LOGNAME': 'cltbld', 09:25:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:25:53 INFO - 'MOZ_NO_REMOTE': '1', 09:25:53 INFO - 'NO_EM_RESTART': '1', 09:25:53 INFO - 'PAGER': '/bin/cat', 09:25:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:25:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:25:53 INFO - 'PWD': '/builds/slave/test', 09:25:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:25:53 INFO - 'SHELL': '/bin/bash', 09:25:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d1lTvilxjJ/Listeners', 09:25:53 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 09:25:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:25:53 INFO - 'USER': 'cltbld', 09:25:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:25:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:25:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:25:53 INFO - 'XPC_FLAGS': '0x0', 09:25:53 INFO - 'XPC_SERVICE_NAME': '0', 09:25:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:25:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:25:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:25:53 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 09:25:53 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 09:25:53 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 09:25:53 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 09:25:53 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 09:25:53 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 09:25:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:25:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:25:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:25:56 INFO - Installing collected packages: mozsystemmonitor 09:25:56 INFO - Running setup.py install for mozsystemmonitor 09:25:56 INFO - Successfully installed mozsystemmonitor 09:25:56 INFO - Cleaning up... 09:25:56 INFO - Return code: 0 09:25:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:25: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')]} 09:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:25:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25: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')]} 09:25:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:25:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:56 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 0x10e220a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2b5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5e158cd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e184d30>, '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 0x10dee9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e17cb20>, '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.8UXcdXnY1t/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.d1lTvilxjJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 09:25:56 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 09:25:56 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 09:25:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8UXcdXnY1t/Render', 09:25:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:25:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:25:56 INFO - 'HOME': '/Users/cltbld', 09:25:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:25:56 INFO - 'LOGNAME': 'cltbld', 09:25:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:25:56 INFO - 'MOZ_NO_REMOTE': '1', 09:25:56 INFO - 'NO_EM_RESTART': '1', 09:25:56 INFO - 'PAGER': '/bin/cat', 09:25:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:25:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:25:56 INFO - 'PWD': '/builds/slave/test', 09:25:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:25:56 INFO - 'SHELL': '/bin/bash', 09:25:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d1lTvilxjJ/Listeners', 09:25:56 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 09:25:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:25:56 INFO - 'USER': 'cltbld', 09:25:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:25:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:25:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:25:56 INFO - 'XPC_FLAGS': '0x0', 09:25:56 INFO - 'XPC_SERVICE_NAME': '0', 09:25:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:25:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:25:56 INFO - Downloading/unpacking jsonschema==2.5.1 09:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:25:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:25:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:25:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:25:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:25:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:25:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:25:59 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 09:25:59 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 09:25:59 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 09:25:59 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 09:25:59 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 09:25:59 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 09:25:59 INFO - Downloading functools32-3.2.3-2.tar.gz 09:25:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:25:59 INFO - warning: no files found matching '*.txt' 09:25:59 INFO - no previously-included directories found matching 'build' 09:25:59 INFO - no previously-included directories found matching 'dist' 09:25:59 INFO - no previously-included directories found matching '.git*' 09:25:59 INFO - Installing collected packages: jsonschema, functools32 09:25:59 INFO - Running setup.py install for functools32 09:25:59 INFO - warning: no files found matching '*.txt' 09:25:59 INFO - no previously-included directories found matching 'build' 09:25:59 INFO - no previously-included directories found matching 'dist' 09:25:59 INFO - no previously-included directories found matching '.git*' 09:25:59 INFO - Successfully installed jsonschema functools32 09:25:59 INFO - Cleaning up... 09:25:59 INFO - Return code: 0 09:25:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:25:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:25:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:25:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:25:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e220a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2b5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5e158cd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e184d30>, '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 0x10dee9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e17cb20>, '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.8UXcdXnY1t/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.d1lTvilxjJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 09:25:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:25:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:25:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8UXcdXnY1t/Render', 09:25:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:25:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:25:59 INFO - 'HOME': '/Users/cltbld', 09:25:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:25:59 INFO - 'LOGNAME': 'cltbld', 09:25:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:25:59 INFO - 'MOZ_NO_REMOTE': '1', 09:25:59 INFO - 'NO_EM_RESTART': '1', 09:25:59 INFO - 'PAGER': '/bin/cat', 09:25:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:25:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:25:59 INFO - 'PWD': '/builds/slave/test', 09:25:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:25:59 INFO - 'SHELL': '/bin/bash', 09:25:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d1lTvilxjJ/Listeners', 09:25:59 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 09:25:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:25:59 INFO - 'USER': 'cltbld', 09:25:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:25:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:25:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:25:59 INFO - 'XPC_FLAGS': '0x0', 09:25:59 INFO - 'XPC_SERVICE_NAME': '0', 09:25:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:26:00 INFO - Cleaning up... 09:26:00 INFO - Return code: 0 09:26:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:26:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e220a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2b5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5e158cd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e184d30>, '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 0x10dee9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e17cb20>, '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.8UXcdXnY1t/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.d1lTvilxjJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 09:26:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:26:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:26:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8UXcdXnY1t/Render', 09:26:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:00 INFO - 'HOME': '/Users/cltbld', 09:26:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:00 INFO - 'LOGNAME': 'cltbld', 09:26:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:00 INFO - 'MOZ_NO_REMOTE': '1', 09:26:00 INFO - 'NO_EM_RESTART': '1', 09:26:00 INFO - 'PAGER': '/bin/cat', 09:26:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:00 INFO - 'PWD': '/builds/slave/test', 09:26:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:00 INFO - 'SHELL': '/bin/bash', 09:26:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d1lTvilxjJ/Listeners', 09:26:00 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 09:26:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:00 INFO - 'USER': 'cltbld', 09:26:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:00 INFO - 'XPC_FLAGS': '0x0', 09:26:00 INFO - 'XPC_SERVICE_NAME': '0', 09:26:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:00 INFO - Downloading/unpacking blobuploader==1.2.4 09:26:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:26:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:26:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:02 INFO - Downloading blobuploader-1.2.4.tar.gz 09:26:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:26:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:26:02 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 09:26:02 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 09:26:02 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 09:26:02 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 09:26:02 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 09:26:02 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 09:26:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:26:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:26: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 09:26: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 09:26: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 09:26: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 09:26: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 09:26: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 09:26:04 INFO - Downloading docopt-0.6.1.tar.gz 09:26:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:26:04 INFO - Installing collected packages: blobuploader, requests, docopt 09:26:04 INFO - Running setup.py install for blobuploader 09:26:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:26:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:26:04 INFO - Running setup.py install for requests 09:26:04 INFO - Running setup.py install for docopt 09:26:04 INFO - Successfully installed blobuploader requests docopt 09:26:04 INFO - Cleaning up... 09:26:05 INFO - Return code: 0 09:26:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:26:05 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')]} 09:26:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:05 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')]} 09:26:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:05 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 0x10e220a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2b5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5e158cd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e184d30>, '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 0x10dee9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e17cb20>, '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.8UXcdXnY1t/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.d1lTvilxjJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 09:26:05 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 09:26:05 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 09:26:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8UXcdXnY1t/Render', 09:26:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:05 INFO - 'HOME': '/Users/cltbld', 09:26:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:05 INFO - 'LOGNAME': 'cltbld', 09:26:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:05 INFO - 'MOZ_NO_REMOTE': '1', 09:26:05 INFO - 'NO_EM_RESTART': '1', 09:26:05 INFO - 'PAGER': '/bin/cat', 09:26:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:05 INFO - 'PWD': '/builds/slave/test', 09:26:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:05 INFO - 'SHELL': '/bin/bash', 09:26:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d1lTvilxjJ/Listeners', 09:26:05 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 09:26:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:05 INFO - 'USER': 'cltbld', 09:26:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:05 INFO - 'XPC_FLAGS': '0x0', 09:26:05 INFO - 'XPC_SERVICE_NAME': '0', 09:26:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:26:05 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-4NTztp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:26:05 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-8uFF8C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:26:05 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-xIt8VN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:26:05 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-FEQspz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:26:05 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-Y4TbyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:26:05 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-HRja5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:26:05 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-J2CHtq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:26:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:26:06 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-Nu2N78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:26:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:26:06 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-7eJlVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:26:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:26:06 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-qTX53R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:26:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:26:06 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-qloTvB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:26:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:26:06 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-ZPx6i4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:26:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:26:06 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-_W3TKm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:26:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:26:06 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-TKtx7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:26:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:26:06 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-vd5ytm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:26:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:26:06 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-vlTg9i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:26:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:26:06 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-iu3Suq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:26:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:26:07 INFO - Running setup.py install for manifestparser 09:26:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:26:07 INFO - Running setup.py install for mozcrash 09:26:07 INFO - Running setup.py install for mozdebug 09:26:07 INFO - Running setup.py install for mozdevice 09:26:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:26:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:26:07 INFO - Running setup.py install for mozfile 09:26:07 INFO - Running setup.py install for mozhttpd 09:26:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:26:07 INFO - Running setup.py install for mozinfo 09:26:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:26:08 INFO - Running setup.py install for mozInstall 09:26:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:26:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:26:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:26:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:26:08 INFO - Running setup.py install for mozleak 09:26:08 INFO - Running setup.py install for mozlog 09:26:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:26:08 INFO - Running setup.py install for moznetwork 09:26:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:26:08 INFO - Running setup.py install for mozprocess 09:26:08 INFO - Running setup.py install for mozprofile 09:26:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:26:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:26:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:26:09 INFO - Running setup.py install for mozrunner 09:26:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:26:09 INFO - Running setup.py install for mozscreenshot 09:26:09 INFO - Running setup.py install for moztest 09:26:09 INFO - Running setup.py install for mozversion 09:26:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:26:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:26:09 INFO - Cleaning up... 09:26:09 INFO - Return code: 0 09:26:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:26:09 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')]} 09:26:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:09 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')]} 09:26:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:09 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 0x10e220a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2b5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5e158cd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e184d30>, '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 0x10dee9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e17cb20>, '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.8UXcdXnY1t/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.d1lTvilxjJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 09:26:09 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 09:26:09 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 09:26:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8UXcdXnY1t/Render', 09:26:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:09 INFO - 'HOME': '/Users/cltbld', 09:26:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:09 INFO - 'LOGNAME': 'cltbld', 09:26:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:09 INFO - 'MOZ_NO_REMOTE': '1', 09:26:09 INFO - 'NO_EM_RESTART': '1', 09:26:09 INFO - 'PAGER': '/bin/cat', 09:26:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:09 INFO - 'PWD': '/builds/slave/test', 09:26:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:09 INFO - 'SHELL': '/bin/bash', 09:26:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d1lTvilxjJ/Listeners', 09:26:09 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 09:26:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:09 INFO - 'USER': 'cltbld', 09:26:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:09 INFO - 'XPC_FLAGS': '0x0', 09:26:09 INFO - 'XPC_SERVICE_NAME': '0', 09:26:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:26:09 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-mzxBRt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:26:10 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)) 09:26:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:26:10 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-nTkW55-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:26:10 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)) 09:26:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:26:10 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-_R5Ew9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:26:10 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)) 09:26:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:26:10 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-DqJh5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:26:10 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)) 09:26:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:26:10 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-g2NTss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:26:10 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)) 09:26:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:26:10 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-ltR7sj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:26:10 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)) 09:26:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:26:10 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-lQZpRm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:26:10 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)) 09:26:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:26:10 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-SeYCaU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:26:10 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)) 09:26:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:26:10 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-IYBHt7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:26:10 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)) 09:26:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:26:10 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-7UQSvi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:26:11 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)) 09:26:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:26:11 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-oYDZMJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:26:11 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)) 09:26:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:26:11 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-rjNJHK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:26:11 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)) 09:26:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:26:11 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-eA_ln_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:26:11 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)) 09:26:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:26:11 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-BV73uD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:26:11 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)) 09:26:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:26:11 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-lmUWNC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:26:11 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)) 09:26:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:26:11 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-y1qsJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:26:11 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)) 09:26:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:26:11 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-sw2zd5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:26:11 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)) 09:26:11 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)) 09:26:11 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)) 09:26:11 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)) 09:26:11 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)) 09:26:11 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)) 09:26:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:26:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:26:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:26:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:14 INFO - Downloading blessings-1.6.tar.gz 09:26:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:26:14 INFO - Installing collected packages: blessings 09:26:14 INFO - Running setup.py install for blessings 09:26:14 INFO - Successfully installed blessings 09:26:14 INFO - Cleaning up... 09:26:14 INFO - Return code: 0 09:26:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:26:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:26:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:26:14 INFO - Reading from file tmpfile_stdout 09:26:14 INFO - Current package versions: 09:26:14 INFO - blessings == 1.6 09:26:14 INFO - blobuploader == 1.2.4 09:26:14 INFO - docopt == 0.6.1 09:26:14 INFO - functools32 == 3.2.3-2 09:26:14 INFO - jsonschema == 2.5.1 09:26:14 INFO - manifestparser == 1.1 09:26:14 INFO - mozInstall == 1.12 09:26:14 INFO - mozcrash == 0.17 09:26:14 INFO - mozdebug == 0.1 09:26:14 INFO - mozdevice == 0.48 09:26:14 INFO - mozfile == 1.2 09:26:14 INFO - mozhttpd == 0.7 09:26:14 INFO - mozinfo == 0.9 09:26:14 INFO - mozleak == 0.1 09:26:14 INFO - mozlog == 3.3 09:26:14 INFO - moznetwork == 0.27 09:26:14 INFO - mozprocess == 0.23 09:26:14 INFO - mozprofile == 0.28 09:26:14 INFO - mozrunner == 6.12 09:26:14 INFO - mozscreenshot == 0.1 09:26:14 INFO - mozsystemmonitor == 0.3 09:26:14 INFO - moztest == 0.7 09:26:14 INFO - mozversion == 1.4 09:26:14 INFO - psutil == 3.1.1 09:26:14 INFO - requests == 1.2.3 09:26:14 INFO - wsgiref == 0.1.2 09:26:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:26: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')]} 09:26:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:15 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 0x10e220a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2b5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5e158cd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e184d30>, '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 0x10dee9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e17cb20>, '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.8UXcdXnY1t/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.d1lTvilxjJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 09:26:15 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 09:26:15 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 09:26:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8UXcdXnY1t/Render', 09:26:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:15 INFO - 'HOME': '/Users/cltbld', 09:26:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:15 INFO - 'LOGNAME': 'cltbld', 09:26:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:15 INFO - 'MOZ_NO_REMOTE': '1', 09:26:15 INFO - 'NO_EM_RESTART': '1', 09:26:15 INFO - 'PAGER': '/bin/cat', 09:26:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:15 INFO - 'PWD': '/builds/slave/test', 09:26:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:15 INFO - 'SHELL': '/bin/bash', 09:26:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d1lTvilxjJ/Listeners', 09:26:15 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 09:26:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:15 INFO - 'USER': 'cltbld', 09:26:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:15 INFO - 'XPC_FLAGS': '0x0', 09:26:15 INFO - 'XPC_SERVICE_NAME': '0', 09:26:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:15 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)) 09:26:15 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)) 09:26:15 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)) 09:26:15 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)) 09:26:15 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)) 09:26:15 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)) 09:26:15 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)) 09:26:15 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)) 09:26:15 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)) 09:26:15 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)) 09:26:15 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)) 09:26:15 INFO - Cleaning up... 09:26:15 INFO - Return code: 0 09:26:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:26:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:15 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 0x10e220a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2b5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5e158cd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e184d30>, '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 0x10dee9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e17cb20>, '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.8UXcdXnY1t/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.d1lTvilxjJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 09:26:15 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 09:26:15 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 09:26:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8UXcdXnY1t/Render', 09:26:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:15 INFO - 'HOME': '/Users/cltbld', 09:26:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:15 INFO - 'LOGNAME': 'cltbld', 09:26:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:15 INFO - 'MOZ_NO_REMOTE': '1', 09:26:15 INFO - 'NO_EM_RESTART': '1', 09:26:15 INFO - 'PAGER': '/bin/cat', 09:26:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:15 INFO - 'PWD': '/builds/slave/test', 09:26:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:15 INFO - 'SHELL': '/bin/bash', 09:26:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d1lTvilxjJ/Listeners', 09:26:15 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 09:26:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:15 INFO - 'USER': 'cltbld', 09:26:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:15 INFO - 'XPC_FLAGS': '0x0', 09:26:15 INFO - 'XPC_SERVICE_NAME': '0', 09:26:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:26:15 INFO - Cleaning up... 09:26:15 INFO - Return code: 0 09:26:15 INFO - Running post-action listener: _resource_record_post_action 09:26:15 INFO - Running post-action listener: _start_resource_monitoring 09:26:15 INFO - Starting resource monitoring. 09:26:15 INFO - [mozharness: 2016-08-18 16:26:15.697477Z] Finished create-virtualenv step (success) 09:26:15 INFO - [mozharness: 2016-08-18 16:26:15.698484Z] Running install step. 09:26:15 INFO - Running pre-action listener: _resource_record_pre_action 09:26:15 INFO - Running main action method: install 09:26:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:26:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:26:15 INFO - Reading from file tmpfile_stdout 09:26:15 INFO - Detecting whether we're running mozinstall >=1.0... 09:26:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:26:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:26:16 INFO - Reading from file tmpfile_stdout 09:26:16 INFO - Output received: 09:26:16 INFO - Usage: mozinstall [options] installer 09:26:16 INFO - Options: 09:26:16 INFO - -h, --help show this help message and exit 09:26:16 INFO - -d DEST, --destination=DEST 09:26:16 INFO - Directory to install application into. [default: 09:26:16 INFO - "/builds/slave/test"] 09:26:16 INFO - --app=APP Application being installed. [default: firefox] 09:26:16 INFO - mkdir: /builds/slave/test/build/application 09:26:16 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'] 09:26:16 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 09:26:50 INFO - Reading from file tmpfile_stdout 09:26:50 INFO - Output received: 09:26:50 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:26:50 INFO - Running post-action listener: _resource_record_post_action 09:26:50 INFO - [mozharness: 2016-08-18 16:26:50.446980Z] Finished install step (success) 09:26:50 INFO - [mozharness: 2016-08-18 16:26:50.447113Z] Running run-tests step. 09:26:50 INFO - Running pre-action listener: _resource_record_pre_action 09:26:50 INFO - Running main action method: run_tests 09:26:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:26:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:26:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:26:50 INFO - 2016-08-18 09:26:50.461 screenresolution[1641:7526] starting screenresolution argv=screenresolution get 09:26:50 INFO - 2016-08-18 09:26:50.464 screenresolution[1641:7526] Display 0: 1600x1200x32@60 09:26:50 INFO - 2016-08-18 09:26:50.470 screenresolution[1642:7528] starting screenresolution argv=screenresolution list 09:26:50 INFO - Available Modes on Display 0 09:26:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:26:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:26:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:26:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:26:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:26:50 INFO - Intel Iris: 09:26:50 INFO - Chipset Model: Intel Iris 09:26:50 INFO - Type: GPU 09:26:50 INFO - Bus: Built-In 09:26:50 INFO - VRAM (Dynamic, Max): 1536 MB 09:26:50 INFO - Vendor: Intel (0x8086) 09:26:50 INFO - Device ID: 0x0a2e 09:26:50 INFO - Revision ID: 0x0009 09:26:50 INFO - Displays: 09:26:50 INFO - Display: 09:26:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:26:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:26:50 INFO - Display Serial Number: 1600x1200 60 09:26:50 INFO - Main Display: Yes 09:26:50 INFO - Mirror: Off 09:26:50 INFO - Online: Yes 09:26:50 INFO - Rotation: Supported 09:26:50 INFO - Return code: 0 09:26:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:26:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:26:50 INFO - Python 2.7.3 09:26:50 INFO - Return code: 0 09:26:50 INFO - grabbing minidump binary from tooltool 09:26: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')]} 09:26:50 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 0x10e184d30>, '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 0x10dee9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e17cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:26:50 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 09:26:50 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 09:26:50 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 09:26:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:26:50 INFO - Return code: 0 09:26:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:26:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:26:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:26:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:26:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:26:50 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/dSW55GowQuCuwP31t7XnCg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0241', '--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 09:26:50 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/dSW55GowQuCuwP31t7XnCg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0241 --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 09:26:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8UXcdXnY1t/Render', 09:26:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:50 INFO - 'HOME': '/Users/cltbld', 09:26:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:50 INFO - 'LOGNAME': 'cltbld', 09:26:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:26:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:26:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:50 INFO - 'MOZ_NO_REMOTE': '1', 09:26:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:26:50 INFO - 'NO_EM_RESTART': '1', 09:26:50 INFO - 'PAGER': '/bin/cat', 09:26:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:50 INFO - 'PWD': '/builds/slave/test', 09:26:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:26:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:50 INFO - 'SHELL': '/bin/bash', 09:26:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d1lTvilxjJ/Listeners', 09:26:50 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 09:26:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:50 INFO - 'USER': 'cltbld', 09:26:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:50 INFO - 'XPC_FLAGS': '0x0', 09:26:50 INFO - 'XPC_SERVICE_NAME': '0', 09:26:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:50 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/dSW55GowQuCuwP31t7XnCg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0241', '--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 09:26:51 INFO - mozversion application_buildid: 20160818070530 09:26:51 INFO - mozversion application_changeset: e6e5569551be46e7200a5b33afdd22e48474ee34 09:26:51 INFO - mozversion application_display_name: Nightly 09:26:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:26:51 INFO - mozversion application_name: Firefox 09:26:51 INFO - mozversion application_remotingname: firefox 09:26:51 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 09:26:51 INFO - mozversion application_vendor: Mozilla 09:26:51 INFO - mozversion application_version: 51.0a1 09:26:51 INFO - mozversion platform_buildid: 20160818070530 09:26:51 INFO - mozversion platform_changeset: e6e5569551be46e7200a5b33afdd22e48474ee34 09:26:51 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 09:26:51 INFO - mozversion platform_version: 51.0a1 09:26:51 INFO - using testdate: 1471537611 09:26:51 INFO - actual date: 1471537611 09:26:51 INFO - starting webserver on 'localhost:49262' 09:26:51 INFO - SUITE-START | Running 2 tests 09:26:51 INFO - TEST-START | dromaeo_css 09:26:51 INFO - operating with platform_type : mac_ 09:26:51 INFO - Initialising browser for dromaeo_css test... 09:26:51 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/tmpJwwzlA/profile http://localhost:49262/getInfo.html) 09:26:53 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 09:26:53 INFO - PROCESS | 1652 | colorDepth:24 09:26:53 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 09:26:53 INFO - PROCESS | 1652 | __metrics 09:26:53 INFO - PROCESS | 1652 | 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" 09:26:53 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:26:54 INFO - TEST-INFO | 1652: exit 0 09:26:54 INFO - Browser initialized. 09:26:54 INFO - Running cycle 1/1 for dromaeo_css test... 09:26:54 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/tmpJwwzlA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:27:06 INFO - PROCESS | 1654 | RSS: Main: 228544512 09:27:06 INFO - PROCESS | 1654 | 09:27:57 INFO - PROCESS | 1654 | 1471537677580 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] 09:30:09 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49262/tests/dromaeo/cssquery-ext.html) 09:30:10 INFO - PROCESS | 1654 | RSS: Main: 363659264 09:30:10 INFO - PROCESS | 1654 | 09:33:14 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-ext.html (next: http://localhost:49262/tests/dromaeo/cssquery-jquery.html) 09:33:14 INFO - PROCESS | 1654 | RSS: Main: 371023872 09:33:14 INFO - PROCESS | 1654 | 09:36:17 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49262/tests/dromaeo/cssquery-mootools.html) 09:36:17 INFO - PROCESS | 1654 | RSS: Main: 362430464 09:36:17 INFO - PROCESS | 1654 | 09:39:20 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49262/tests/dromaeo/cssquery-prototype.html) 09:39:20 INFO - PROCESS | 1654 | RSS: Main: 339030016 09:39:20 INFO - PROCESS | 1654 | 09:42:24 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49262/tests/dromaeo/cssquery-yui.html) 09:42:24 INFO - PROCESS | 1654 | RSS: Main: 328384512 09:42:24 INFO - PROCESS | 1654 | 09:45:28 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-yui.html (next: http://localhost:49262/tests/dromaeo/cssquery-dojo.html) 09:45:28 INFO - PROCESS | 1654 | RSS: Main: 375742464 09:45:28 INFO - PROCESS | 1654 | 09:45:28 INFO - PROCESS | 1654 | __start_tp_report 09:45:28 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 09:45:28 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 09:45:28 INFO - PROCESS | 1654 | |i|pagename|runs| 09:45:28 INFO - PROCESS | 1654 | |0;dojo.html;2800.425912687899;3360.479125735511;3363.495475678649;3396.8471418786153;3399.6090449598305;4502.954970450294;5294.682319060859;5658.830235092949;5749.7125143742805;5752.511036561887;5697.145428185774;5712.942870571293;5717.828465146053;5745.454181852718;5760.135979603051;5184.144616138333;5201.739913004335;5238.554722848562;5276.657017293883;5308.601854860882;5234.947650523503;5284.180951952453;5286.286351342558;5294.52349288564;5300.973495132538;5184.222366644995;5228.973855130719;5237.921431178535;5258.526732594066;5278.155495120782;5306.071437498423;5307.230451584518;5322.205568198222;5322.5209731124;5343.278657381243;5129.307543481619;5130.794768209267;5142.871428214287;5257.237700533401;5264.184051472028;5709.200711900337;5718;5723.656580605177;5737.311522617271;5766.913496297559;6287.654179020152;6290.370962903673;6293.842653933688;6295.496360291166;6296.96851515744;6142.314611233224;6193.225846769154;6228.657423841687;6236.719347629368;6267.561270711052;4635.374224479686;5863.091220860773;5887.528997680175;5891.528677705774;5895.67573783446;6293.559450838397;6294.65379404128;6312.652804095819;6313.9368606314265;6314.905276420857;6013.579049466495;6107.340534417217;6184.165137706442;6218.098375735583;6235.251769787655;6337.556371053982;6353.841153971095;6356.841078973062;6358;6361.872762544724;6237.157983672237;6268.09112678659;6284;6288.968555157195;6303.180586523723;6106.946079819618;6180.536459765535;6183.103449999725;6279.560430769893;6320;4906.362172917497;4916.950830491649;4926.8768280793265;4943.258511223274;4956.132676781621;4416.227160246944;4440.4005459263235;4448.421705178371;4472.9776351118035;4521.8191272349395;5841.123831425235;5844.152597873284;5847.56143289255;5849.064149736022;5892.764289428461;6152.138700581922;6214.378562143749;6321.55749097568;6352.205974253218;6367.204099487564;5757.309122905194;5800.4489573490455;5816.185733997244;5825.79609713658;5857.414258574108;5462.863428414242;5464.726763661801;5470.234167216593;5470.781168753285;5473.425290344536;5016.874578135503;6180.258368995749;6259.155014073224;6314.526410519321;6331.398517140864;6338.176037115146;6354.618722876642;6366.363363663597;6367.586106903036;6371.235451745635;6350.4919606432295;6358.809235722936;6366.586171898812;6368.23581170244;6371.171747672773;6110.69446527663;6130.214697154933;6165.475934545614;6266.091416744638;6275.874482510233;6242.719077641426;6285.151504546828;6310.1796766420075;6328.588641738273;6332.841678957879;5357.946420535901;5550.361708403585;6127.172831667668;6167.105769663463;6177.289611694712;5992.970035149797;6065.787697430569;6119.969400152971;6146.231721034871;6158.445739883432;6003.429674180946;6018.668973206516;6031.276246850406;6033.5173186145885;6053.515718742402;6143.09913205109;6147.600405973598;6154.507639388748;6155.784547540815;6184.659843708459;5806.129080637938;5855.033919403421;5883.735231914659;5956.523478121827;5989.431004054608 09:45:28 INFO - PROCESS | 1654 | |1;ext.html;523.0323900783551;543.8694713268815;545.7953267524679;550.6640949021099;555.6499405374614;31003;32166.195845103855;41299.38050929239;42223.57776422235;42346.364804527955;42642.786786066026;43053.784731076375;43065;43176.784116079376;43196.13607727844;9711.611535538588;9901.158401535879;9907.653232136876;9909.306348555603;9914.702558923243;8857.42426742263;9081.18269355758;12170.330631815246;12474.50101995919;12480.750384992294;12269.202501837397;12347.876521234808;12377.690557736083;12383.071269654767;12402.565910193145;11244.887551124466;11461.426928653618;12272;12339.197952133127;12341.753164936741;12155.149139560235;12212.511499540007;12235.755284894341;12238.755224895453;12242.938785306105;33920.982370528924;41115;41296.587034129574;41321.38017929733;41477.170456591004;3399.779014364083;3477.4957631143343;3527.2945410917664;3938.212357528506;4261.616454519108;31312.843435782674;32377.190570235696;32542.674573254437;32581.533830977663;32647.510287345707;2872.1814282929263;3259.559959405474;3389.8983030509125;3444.8277586120844;3456.9654303457146;465598;465725;468413;469604;472771;59786.40213597808;60020;60052;60073.69963150244;60118.79762404727;62389;62499.375006250266;62582.68708656519;62598;62719.686401568615;16487.258073386725;16739.83260167383;16761.7485737714;16768.748468772978;16778.580535486468;11661.416929153509;11926.284422934652;13004.8049279259;13070.934645326903;13079.542216022393;14108;16366.345346186257;16887.493375198763;16936.915315423343;17332.91333543324;7353.904137320837;7474.065741782278;7474.962625186839;7517.548947063194;7583.9620801895635;866.8656358264479;962.6341990043738;1001.5242759689089;1038.2524582300732;1053.2416660004785;26569.33576660599;27140.457190856072;29721;30607.08178754641;31329.530057049164;945.789389581337;1000.7240768020831;1006.9577986783644;1034.943078130695;1045.5399624165343;15629.14039727826;15925.203739812963;15950.76073858893;16150.484137917449;16346.83653163477;605779;609902;611640.9417952881;614586;615541;59339.70330148408;59429;59441.40558594445;59502.404975949685;59562;1258.880406361376;1303.2897071096086;1375.3742047368423;1425.308725268244;1441.978370324446;22096;31241.37517249597;32454.675453245167;33058.00825975225;33101.00696979094;55657;56410.153847691494;56453;56594.434055658916;56666.433335666115;560071;560177;560534;565759;567109;23916.16293429791;24347.878260609294;26686.46627067409;27138.72861271362;40818.591814081476;46820.29769553391;49140.26289605587;50648;51362.74318628533;52550.47449525456;31191.68808311888;39713;39820.2035959285;41488.1702365957;41572.58427415687;39748.60251397449;39958.800205998785;40393.59606404016;41374.58625413707;41477.792611036755;38841;39296.60703392929;39658;39689.20621587495;39772.005699857735;33286.692467997;34761.65238347584;35072.772452964025;35193;35240.82379588085;25173.118940836983;25735.227943161735;25978;26113.347166320993;26625.068122615616 09:45:28 INFO - PROCESS | 1654 | |2;jquery.html;336.65661025753747;369.5823719197305;372.8955892350142;380.52054411441594;402.74828232354776;19677.901610491946;20798.048370809556;21167.68248476274;21732.89133554332;21954;10701.501789749422;11611.883881161186;12011.279323240611;12014.579489717862;12026.759464810699;4829.78265978031;5468.425815289377;5472.480114389146;5478.91781623276;5481.725913704299;3893.0851249956236;4117.485314335708;5340.67955922644;5460.781568737246;7064.717411303567;7037.612931288777;7041.683124259421;7051.717931282768;7068.081149450564;7074.115735533059;5840.824775256728;6133.754649814002;6138.294096178952;6196.597221180609;7040.366367026966;5233.764480598382;5983.880322393527;5990.161377407123;5997.310309314441;6001;12984.350782460839;13383.996200285017;13385.12996655224;13421.194728316235;15006.62483437912;2108.3042595943302;2285.257291380308;2577.201067669029;2631.657884475006;2926.809757365764;9815.558299876522;10147.594096236142;10832.67501974942;15117.622059448491;15740.842591574166;1841.6040551281405;1857.8796391254518;1917.8178073083175;1926.807319268076;1949.639316726396;117319;127323;129528;129795;130231.34884325518;9745.658901938397;10708.73228169302;10785.83821242682;11530.423478826193;11747.941260293645;14455;14458.710825783635;14461.85538144605;14469.49356772508;14486.130832149895;8292.12932642076;8565.95717021411;8570.614322355508;8728.170823771732;8741.256993155654;8511.702090426928;8537.573121343908;8538.786530336667;8673.783155421039;8704.347173961978;4375.934360984587;4440.933385999213;4525.52481989393;4535.795889184995;4646.395968524071;1708.325211541434;1756.6837969165417;1808.7829460464566;1884.538288119392;1924.9037548122537;737.7311025036928;894.8970868350093;901.5987885391069;937.8546325319586;1040.885502594714;21670.458238543848;22827.543449130924;23152;23206.65190022151;23211;886.9955650221837;899.9910000900038;966.6906589891308;967.4969016111583;973.4500007495742;9175.403598766059;11136.387498687487;11226.326420414802;11239.719007024889;11240.887591123985;615730;618376;626646;626766;631026;121944;130479;140681.26253055976;143884.28057859643;145638;832.2509741233059;948.9335746497817;1011.9291649584605;1019.5327307914864;1023.3808545829814;13916.437487833779;17366.65266694634;18914.243430262635;19400.320988765325;19502;57716.711416442646;57777.422225778886;57986.7100664494;57987;59226.815463691346;84367;85075;85394.57302713695;86718;86921.6961745587;6485.124508191334;7170.2471240519;7824.374050075867;8045.879311810445;8304.29413499835;11974.401279935793;13190.274534900673;13208.603741887759;13385.732285354434;13713.725725485236;11011.834822477509;11470.369129697612;11472.31166130035;11493.367864767519;11549.42252887369;11202.38386888729;11370.033547148254;11543;11577.07383409342;11866.525338986345;10027.448490333101;11273;11495.482703278205;11818.231814932002;11848.518935133108;7344;8112.59437028158;8139.959300203461;8156.918430815853;8204.138565450541;9381.577828997575;10463.529141188817;10709.732256693645;10968.890311097106;11314.773704525698 09:45:28 INFO - PROCESS | 1654 | |3;mootools.html;1356.1862882270636;1493.3951749541436;1640.1799100449775;1794.6321004194142;1841.245089513299;8218.46579972302;8751.124887511261;8753.91246087539;8760.912390876088;8762.561871906411;7819.609019549014;8035.3169980551575;8046.396520260974;8153.510789352643;8572.271356934652;2180.934571962836;2217.691561978432;2335.789778919897;2415.3237093613816;2419.8790060496995;2413.3242692046256;2413.54142712884;2415.7342692303832;2418.746031666676;2423.672804171441;2402.783749462548;2418.4921166554955;2438.1222759806456;2442.2917353967327;2455.2388759484616;2233.229535810143;2288.786942920255;2394.437307232799;2402.3633737059695;2416.903323867052;2375.2992867104244;2393.9162129325564;2399.748026457214;2407.843490173133;2409.4337830609793;8646.913530864675;8752.343574231958;8802.603882825224;8805.383623146383;8886.955565222133;5144.897102057976;5302.761375738062;5304.310439642861;5316.0165369406395;5329.653572517813;3229.7416206703642;3327.8502467389026;3609.079684680416;3659.7621154624862;3675.5773086094905;1028.1825948371063;1057.5452555401175;1076.3488089705693;1077.6389909380366;1096.950637221327;11581.420928953603;11628.825567616492;11631.709207269801;11638.70903227426;11648.475818588098;4100.913257986636;4397.758123303185;4577.702449340783;4639.884002899887;4745.217039188564;9879.604815807432;9881.80236395268;9886.258530610234;9890.406575605486;9898.851517227247;5318.521333080042;5323.254744335796;5324.1747529133045;5331.44019877915;5350.892982140414;4759.571638552547;4860.416749990024;4881.194602890483;4891.364122664031;4891.5353041461;3722.4602432647657;3833.8274777634515;3860.6718428933295;3905.941410878839;3986.142979259473;1279.4434421026883;1285.0875878126449;1288.8529209003993;1302.1210682789078;1308.2804457548311;835.320413578189;851.3231980575457;889.1863944490848;900.5722281916037;940.1115945431638;4866.586340161126;5702.230198923092;5887.175795388649;6023.0965355196195;6056.182415373868;903.5617725403174;931.6925414613297;945.239082538563;951.7192428233687;957.9281553883486;3854.768713877176;3859.3632050711317;3886.0867696091395;3966.8413263469715;4045.3325201342036;8456.40805143634;10725.731856703489;10748.247622666338;10768.084712799344;10768.838467422995;10658.73353166177;10658.987396197504;10673.35959842412;10677.14582833387;10696.51865666047;7220.386267167244;8022.318102961313;8115.513069215984;8187.222213889671;8198.344132469389;5927.516138169573;5986.2517185351835;6027.186329845415;6074.119252708421;6143.477804386677;9596.520173991412;9844.556994935389;9845.96617355196;9858.852117218104;9872.901270987486;9083.864516935382;9836.803263934538;9991.450470224207;10060.949695251476;10066.547005384922;7394.260573942563;7473.701051957861;7491.475596708178;7584.2794934481135;7599.088109426904;7431.219721929119;7703;7933.9603301983125;8197.508149511048;8209.302209312274;7443.962780186064;7478.401727861867;7514.098308203051;7530.849383012419;7619.466637335333;7088.858222835617;7354.117505899327;7355.963220184079;7382.815429614303;7419.480636355619;5910.70446477683;6870.27862074475;6970.825729356807;7033.542819716702;7046.1192350956135;4767.141914455431;4985.152524070844;5025.572826309804;5108.591312694901;5222.033923724123;4661.720296782204;4751.928721069117;4965.577925876341;5100.515451032146;5174.767135478988 09:45:28 INFO - PROCESS | 1654 | |4;prototype.html;290.55545016125325;297.0953446754632;305.36484113044867;307.9667714816791;309.2562387458164;5214.400343960446;5271.127235656567;5278.181881808326;5278.340207474066;5291.6031297652635;4527.660425468095;4922.310876477287;5031.823886163985;5129.204973229146;5195.4025287092;3234.805911645297;3245.6916592923635;3912.608739126093;3965.6827453803626;3982.561918188997;3977.8011099445057;3978.6021397860127;4005.959940400588;4019.8794036178815;4022.537408198051;3143.387039527293;3492.9346549302454;3730.850765969358;3764;3912.7088060939823;3956.2087582483473;3968.7420317679257;3971.994142957788;3994.62051105146;3996.6403023728003;3471.4137967214588;3485.605757696916;3509.9649003509894;3520.68313851752;3520.894373168809;4398.560143985608;4498.79755411004;4662.642897114107;4873;4940.851774446736;3772.396416573335;3922.4900762900925;4558.7264764113925;4570.245909424941;4628.768561571942;5455.481729235717;5519.448055194489;5538.556915446755;5558.027345214571;5597.608167428281;3898.532176138853;3902.1610353774076;3905.7892053463515;3929.783861887595;3931.8230679619487;5391.813800963781;5674.432556744375;5693.402192769741;5705.6291341063115;5745.942540574624;3908.804559772;3922.2547715933883;3932.311845427039;3940.330143875548;3965.940510892339;3970.6029397060065;3993.9201215975518;4014.759114453142;4015.8996025099605;4030.879073627796;3882.8835134946;4422.783734473046;4589.796897489224;4622.2835460503675;4625.236835922035;4542.454905411372;5417.4311697272005;5474.644148130438;5478.726063696799;5500.28496295479;1110.9723505757142;1333.746588148268;1356.2337279437086;1357.7284543091223;1359.1233654292926;694.468696524163;712.9465290103159;750.91364493084;763.2988638816327;885.291766586728;4677.2750223715375;4732.668713190113;4911.680740751911;5073.289739436482;5171.663841850268;4602.65480088988;5200.375954885362;5596.712756066127;5597.928766356475;5672.865426914634;4069.5726948670554;4951.851503805894;5142.228665700175;5144.794208231704;5159.458256883048;2405.6872606561387;2457.49621327631;2908.418316336741;2928.3850391417614;2932.9266768330535;5744.712764361765;5756.856078598069;5764.423557644286;5811.360750317462;5816.970915145482;4713;4979.477154898684;5039.899202016012;5126.872607709294;5732.3407808101765;4663.533646635377;4957.677750946177;5330.760115794798;5656.717164141859;5768.74040668171;5503.889922201613;5536.778528858962;5541.9168712470155;5561.190584449665;5703;5089.3638295213095;5252.42223355423;5369.248305237192;5523.861903452445;5555.805546805843;4806.639502037291;5044.672096313728;5790.826275211751;5800.912986305125;5811.372815611636;3107.984460077685;3697.556293244828;3914.745541539791;3919.8236079375924;3942;5226.686398816083;5495.884084627367;5508.3114610673665;5547.362053363753;5622.353429355513;4985.426675932169;5325.760340784596;5396.595255355785;5538.280023596906;5575.470330318613;5476.315460567429;5484.177373394023;5492.995056804318;5493.972530137324;5540.7783688652;4707.844909823738;4875.829345972874;4890.657653964188;5159.971073040602;5286.920696189483;4807.711537307773;5253.789848406021;5482.533984611353;5500.862478438071;5559.24950131727;5341.985022845647;5353.170258609922;5372.301600791872;5372.710549468177;5397.70312632809 09:45:28 INFO - PROCESS | 1654 | |5;yui.html;709.7941596936888;759.7986533568603;798.5727635714894;804.975850724478;809.8015986083407;15463.768043479342;16388.590285242874;16423.753643695338;16431.917840410795;16439.671206575862;14293.49972750954;14714.852851471509;15867.206639667998;15961.920190399062;16060.518184454455;833.1959658929147;843.2368706320759;844.0504432513422;844.4173520270994;844.4342290665238;669.2470970158572;694.711694646721;834.5284914023588;849.1508491508491;856.4861083350003;901.6979311930511;904.9909351073571;905.868649045888;906.4742449379344;907.3059109781022;896.3322324868016;897.1073781587297;897.3897749530316;897.712731925784;898.0615257056392;518.7613697699063;529.4996228564008;535.2212530767763;537.2719964448195;665.354606032148;10499.73750656232;12436;14413.964090054802;14441.422343106264;14446.494372696905;14436.49472268476;14697.77953330701;14906.776398354034;15211.923940380339;15255.847441525553;11984.520619175222;12010.579629713005;12174.208676436092;12246.142770006103;12267.815982760265;3893.9415908761393;3917.784521851297;3923.6664883484937;3938.1926705025403;4053.9594604053877;113836.43081784538;113947;114641;115118.42440787826;115373;11396.715082123013;11461.140414468948;11495.71260718472;11740.58907938218;11824.940875295568;23489.530209395718;23516.76483235146;23539.29382118539;23571.29286121419;23624.763752362258;8095.7166499172245;8105.351571874354;8143.592820358958;8241.670333186725;8245.670173193124;4483.147238867862;4515.164694531522;4534.841280555231;4650.162970665245;4689.179393606105;2958.7189217024347;3708.1100535871196;3754.9061273467837;3755.080005398695;3756.38019726742;556.7967691792467;564.4863174511175;644.5810223354857;649.3409189672486;671.2414971082743;575.5108158065694;595.4968051996057;649.7622030032795;697.8639165362706;699.9545029573163;8841.823163536694;9394.62421503146;9678.370905891235;9697.224222062217;9932.255080868963;548.3173449055951;609.1715267236555;616.7594638091148;669.7019826177402;695.5617960684737;4354.499232588293;4393.560643935581;4415.911681766411;4458.910821783546;4557.977210113928;106988;132955;139831;141697.5830241705;142430;114904.86997730403;120294;121318;122361;123393.76606233823;99498.52758098375;103977;107875.8424831499;108137;110605.44697276462;8190.549519776469;9058.682946096855;9308;9410.247180225702;10010.499475026365;47294.05411891812;59651;60269.0959635597;61921.07118393137;64500.354996451315;86903.69644455212;91243.54378228067;95696.0430395715;96193.07613847824;96951;12573.93713031429;12968.481260749464;12976.545820896223;13163;13676.042677012514;14729.92635036818;14994.175320357048;15071;15392.46126385616;15405.691886162438;11789.233699809485;13451.529196478075;13908.37412316428;14008.019438639198;14173.574792756233;11521;13827.446902124204;13934.79097813554;14136.78794818099;14421.495247666282;11482.885171148515;13733.656658583615;13844.95488924642;13871.167729936236;14176.362063707364;11407.885921140682;11600.709982250512;11625.88374116282;11791.823122652995;11823.290602563873;11436.485358158736;11466.77066458683;11478.196526243082;11493.080553555528;11495.885041149482 09:45:28 INFO - PROCESS | 1654 | __end_tp_report 09:45:28 INFO - PROCESS | 1654 | __start_cc_report 09:45:28 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1220 09:45:28 INFO - PROCESS | 1654 | __end_cc_report 09:45:28 INFO - PROCESS | 1654 | __startTimestamp1471538728562__endTimestamp 09:45:28 INFO - PROCESS | 1654 | 09:45:28 INFO - PROCESS | 1654 | ------- Summary: start ------- 09:45:28 INFO - PROCESS | 1654 | Number of tests: 6 09:45:28 INFO - PROCESS | 1654 | 09:45:28 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5755.03 Median:5989.43 stddev:671.48 (11.2%) stddev-sans-first:632.28 09:45:28 INFO - PROCESS | 1654 | Values: 2800.4 3360.5 3363.5 3396.8 3399.6 4503.0 5294.7 5658.8 5749.7 5752.5 5697.1 5712.9 5717.8 5745.5 5760.1 5184.1 5201.7 5238.6 5276.7 5308.6 5234.9 5284.2 5286.3 5294.5 5301.0 5184.2 5229.0 5237.9 5258.5 5278.2 5306.1 5307.2 5322.2 5322.5 5343.3 5129.3 5130.8 5142.9 5257.2 5264.2 5709.2 5718.0 5723.7 5737.3 5766.9 6287.7 6290.4 6293.8 6295.5 6297.0 6142.3 6193.2 6228.7 6236.7 6267.6 4635.4 5863.1 5887.5 5891.5 5895.7 6293.6 6294.7 6312.7 6313.9 6314.9 6013.6 6107.3 6184.2 6218.1 6235.3 6337.6 6353.8 6356.8 6358.0 6361.9 6237.2 6268.1 6284.0 6289.0 6303.2 6106.9 6180.5 6183.1 6279.6 6320.0 4906.4 4917.0 4926.9 4943.3 4956.1 4416.2 4440.4 4448.4 4473.0 4521.8 5841.1 5844.2 5847.6 5849.1 5892.8 6152.1 6214.4 6321.6 6352.2 6367.2 5757.3 5800.4 5816.2 5825.8 5857.4 5462.9 5464.7 5470.2 5470.8 5473.4 5016.9 6180.3 6259.2 6314.5 6331.4 6338.2 6354.6 6366.4 6367.6 6371.2 6350.5 6358.8 6366.6 6368.2 6371.2 6110.7 6130.2 6165.5 6266.1 6275.9 6242.7 6285.2 6310.2 6328.6 6332.8 5357.9 5550.4 6127.2 6167.1 6177.3 5993.0 6065.8 6120.0 6146.2 6158.4 6003.4 6018.7 6031.3 6033.5 6053.5 6143.1 6147.6 6154.5 6155.8 6184.7 5806.1 5855.0 5883.7 5956.5 5989.4 09:45:28 INFO - PROCESS | 1654 | 09:45:28 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:69136.41 Median:21184.15 stddev:146809.71 (693.0%) stddev-sans-first:147131.16 09:45:28 INFO - PROCESS | 1654 | Values: 523.0 543.9 545.8 550.7 555.6 31003.0 32166.2 41299.4 42223.6 42346.4 42642.8 43053.8 43065.0 43176.8 43196.1 9711.6 9901.2 9907.7 9909.3 9914.7 8857.4 9081.2 12170.3 12474.5 12480.8 12269.2 12347.9 12377.7 12383.1 12402.6 11244.9 11461.4 12272.0 12339.2 12341.8 12155.1 12212.5 12235.8 12238.8 12242.9 33921.0 41115.0 41296.6 41321.4 41477.2 3399.8 3477.5 3527.3 3938.2 4261.6 31312.8 32377.2 32542.7 32581.5 32647.5 2872.2 3259.6 3389.9 3444.8 3457.0 465598.0 465725.0 468413.0 469604.0 472771.0 59786.4 60020.0 60052.0 60073.7 60118.8 62389.0 62499.4 62582.7 62598.0 62719.7 16487.3 16739.8 16761.7 16768.7 16778.6 11661.4 11926.3 13004.8 13070.9 13079.5 14108.0 16366.3 16887.5 16936.9 17332.9 7353.9 7474.1 7475.0 7517.5 7584.0 866.9 962.6 1001.5 1038.3 1053.2 26569.3 27140.5 29721.0 30607.1 31329.5 945.8 1000.7 1007.0 1034.9 1045.5 15629.1 15925.2 15950.8 16150.5 16346.8 605779.0 609902.0 611640.9 614586.0 615541.0 59339.7 59429.0 59441.4 59502.4 59562.0 1258.9 1303.3 1375.4 1425.3 1442.0 22096.0 31241.4 32454.7 33058.0 33101.0 55657.0 56410.2 56453.0 56594.4 56666.4 560071.0 560177.0 560534.0 565759.0 567109.0 23916.2 24347.9 26686.5 27138.7 40818.6 46820.3 49140.3 50648.0 51362.7 52550.5 31191.7 39713.0 39820.2 41488.2 41572.6 39748.6 39958.8 40393.6 41374.6 41477.8 38841.0 39296.6 39658.0 39689.2 39772.0 33286.7 34761.7 35072.8 35193.0 35240.8 25173.1 25735.2 25978.0 26113.3 26625.1 09:45:28 INFO - PROCESS | 1654 | 09:45:28 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:36128.12 Median:23208.83 stddev:104505.81 (450.3%) stddev-sans-first:104764.42 09:45:28 INFO - PROCESS | 1654 | Values: 336.7 369.6 372.9 380.5 402.7 19677.9 20798.0 21167.7 21732.9 21954.0 10701.5 11611.9 12011.3 12014.6 12026.8 4829.8 5468.4 5472.5 5478.9 5481.7 3893.1 4117.5 5340.7 5460.8 7064.7 7037.6 7041.7 7051.7 7068.1 7074.1 5840.8 6133.8 6138.3 6196.6 7040.4 5233.8 5983.9 5990.2 5997.3 6001.0 12984.4 13384.0 13385.1 13421.2 15006.6 2108.3 2285.3 2577.2 2631.7 2926.8 9815.6 10147.6 10832.7 15117.6 15740.8 1841.6 1857.9 1917.8 1926.8 1949.6 117319.0 127323.0 129528.0 129795.0 130231.3 9745.7 10708.7 10785.8 11530.4 11747.9 14455.0 14458.7 14461.9 14469.5 14486.1 8292.1 8566.0 8570.6 8728.2 8741.3 8511.7 8537.6 8538.8 8673.8 8704.3 4375.9 4440.9 4525.5 4535.8 4646.4 1708.3 1756.7 1808.8 1884.5 1924.9 737.7 894.9 901.6 937.9 1040.9 21670.5 22827.5 23152.0 23206.7 23211.0 887.0 900.0 966.7 967.5 973.5 9175.4 11136.4 11226.3 11239.7 11240.9 615730.0 618376.0 626646.0 626766.0 631026.0 121944.0 130479.0 140681.3 143884.3 145638.0 832.3 948.9 1011.9 1019.5 1023.4 13916.4 17366.7 18914.2 19400.3 19502.0 57716.7 57777.4 57986.7 57987.0 59226.8 84367.0 85075.0 85394.6 86718.0 86921.7 6485.1 7170.2 7824.4 8045.9 8304.3 11974.4 13190.3 13208.6 13385.7 13713.7 11011.8 11470.4 11472.3 11493.4 11549.4 11202.4 11370.0 11543.0 11577.1 11866.5 10027.4 11273.0 11495.5 11818.2 11848.5 7344.0 8112.6 8140.0 8156.9 8204.1 9381.6 10463.5 10709.7 10968.9 11314.8 09:45:28 INFO - PROCESS | 1654 | 09:45:28 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5645.80 Median:5159.83 stddev:3134.71 (60.8%) stddev-sans-first:3126.93 09:45:28 INFO - PROCESS | 1654 | Values: 1356.2 1493.4 1640.2 1794.6 1841.2 8218.5 8751.1 8753.9 8760.9 8762.6 7819.6 8035.3 8046.4 8153.5 8572.3 2180.9 2217.7 2335.8 2415.3 2419.9 2413.3 2413.5 2415.7 2418.7 2423.7 2402.8 2418.5 2438.1 2442.3 2455.2 2233.2 2288.8 2394.4 2402.4 2416.9 2375.3 2393.9 2399.7 2407.8 2409.4 8646.9 8752.3 8802.6 8805.4 8887.0 5144.9 5302.8 5304.3 5316.0 5329.7 3229.7 3327.9 3609.1 3659.8 3675.6 1028.2 1057.5 1076.3 1077.6 1097.0 11581.4 11628.8 11631.7 11638.7 11648.5 4100.9 4397.8 4577.7 4639.9 4745.2 9879.6 9881.8 9886.3 9890.4 9898.9 5318.5 5323.3 5324.2 5331.4 5350.9 4759.6 4860.4 4881.2 4891.4 4891.5 3722.5 3833.8 3860.7 3905.9 3986.1 1279.4 1285.1 1288.9 1302.1 1308.3 835.3 851.3 889.2 900.6 940.1 4866.6 5702.2 5887.2 6023.1 6056.2 903.6 931.7 945.2 951.7 957.9 3854.8 3859.4 3886.1 3966.8 4045.3 8456.4 10725.7 10748.2 10768.1 10768.8 10658.7 10659.0 10673.4 10677.1 10696.5 7220.4 8022.3 8115.5 8187.2 8198.3 5927.5 5986.3 6027.2 6074.1 6143.5 9596.5 9844.6 9846.0 9858.9 9872.9 9083.9 9836.8 9991.5 10060.9 10066.5 7394.3 7473.7 7491.5 7584.3 7599.1 7431.2 7703.0 7934.0 8197.5 8209.3 7444.0 7478.4 7514.1 7530.8 7619.5 7088.9 7354.1 7356.0 7382.8 7419.5 5910.7 6870.3 6970.8 7033.5 7046.1 4767.1 4985.2 5025.6 5108.6 5222.0 4661.7 4751.9 4965.6 5100.5 5174.8 09:45:28 INFO - PROCESS | 1654 | 09:45:28 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4422.43 Median:4954.76 stddev:1356.10 (27.4%) stddev-sans-first:1323.97 09:45:28 INFO - PROCESS | 1654 | Values: 290.6 297.1 305.4 308.0 309.3 5214.4 5271.1 5278.2 5278.3 5291.6 4527.7 4922.3 5031.8 5129.2 5195.4 3234.8 3245.7 3912.6 3965.7 3982.6 3977.8 3978.6 4006.0 4019.9 4022.5 3143.4 3492.9 3730.9 3764.0 3912.7 3956.2 3968.7 3972.0 3994.6 3996.6 3471.4 3485.6 3510.0 3520.7 3520.9 4398.6 4498.8 4662.6 4873.0 4940.9 3772.4 3922.5 4558.7 4570.2 4628.8 5455.5 5519.4 5538.6 5558.0 5597.6 3898.5 3902.2 3905.8 3929.8 3931.8 5391.8 5674.4 5693.4 5705.6 5745.9 3908.8 3922.3 3932.3 3940.3 3965.9 3970.6 3993.9 4014.8 4015.9 4030.9 3882.9 4422.8 4589.8 4622.3 4625.2 4542.5 5417.4 5474.6 5478.7 5500.3 1111.0 1333.7 1356.2 1357.7 1359.1 694.5 712.9 750.9 763.3 885.3 4677.3 4732.7 4911.7 5073.3 5171.7 4602.7 5200.4 5596.7 5597.9 5672.9 4069.6 4951.9 5142.2 5144.8 5159.5 2405.7 2457.5 2908.4 2928.4 2932.9 5744.7 5756.9 5764.4 5811.4 5817.0 4713.0 4979.5 5039.9 5126.9 5732.3 4663.5 4957.7 5330.8 5656.7 5768.7 5503.9 5536.8 5541.9 5561.2 5703.0 5089.4 5252.4 5369.2 5523.9 5555.8 4806.6 5044.7 5790.8 5800.9 5811.4 3108.0 3697.6 3914.7 3919.8 3942.0 5226.7 5495.9 5508.3 5547.4 5622.4 4985.4 5325.8 5396.6 5538.3 5575.5 5476.3 5484.2 5493.0 5494.0 5540.8 4707.8 4875.8 4890.7 5160.0 5286.9 4807.7 5253.8 5482.5 5500.9 5559.2 5342.0 5353.2 5372.3 5372.7 5397.7 09:45:28 INFO - PROCESS | 1654 | 09:45:28 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:24300.57 Median:3333.41 stddev:37722.47 (1131.6%) stddev-sans-first:37786.71 09:45:28 INFO - PROCESS | 1654 | Values: 709.8 759.8 798.6 805.0 809.8 15463.8 16388.6 16423.8 16431.9 16439.7 14293.5 14714.9 15867.2 15961.9 16060.5 833.2 843.2 844.1 844.4 844.4 669.2 694.7 834.5 849.2 856.5 901.7 905.0 905.9 906.5 907.3 896.3 897.1 897.4 897.7 898.1 518.8 529.5 535.2 537.3 665.4 10499.7 12436.0 14414.0 14441.4 14446.5 14436.5 14697.8 14906.8 15211.9 15255.8 11984.5 12010.6 12174.2 12246.1 12267.8 3893.9 3917.8 3923.7 3938.2 4054.0 113836.4 113947.0 114641.0 115118.4 115373.0 11396.7 11461.1 11495.7 11740.6 11824.9 23489.5 23516.8 23539.3 23571.3 23624.8 8095.7 8105.4 8143.6 8241.7 8245.7 4483.1 4515.2 4534.8 4650.2 4689.2 2958.7 3708.1 3754.9 3755.1 3756.4 556.8 564.5 644.6 649.3 671.2 575.5 595.5 649.8 697.9 700.0 8841.8 9394.6 9678.4 9697.2 9932.3 548.3 609.2 616.8 669.7 695.6 4354.5 4393.6 4415.9 4458.9 4558.0 106988.0 132955.0 139831.0 141697.6 142430.0 114904.9 120294.0 121318.0 122361.0 123393.8 99498.5 103977.0 107875.8 108137.0 110605.4 8190.5 9058.7 9308.0 9410.2 10010.5 47294.1 59651.0 60269.1 61921.1 64500.4 86903.7 91243.5 95696.0 96193.1 96951.0 12573.9 12968.5 12976.5 13163.0 13676.0 14729.9 14994.2 15071.0 15392.5 15405.7 11789.2 13451.5 13908.4 14008.0 14173.6 11521.0 13827.4 13934.8 14136.8 14421.5 11482.9 13733.7 13845.0 13871.2 14176.4 11407.9 11600.7 11625.9 11791.8 11823.3 11436.5 11466.8 11478.2 11493.1 11495.9 09:45:28 INFO - PROCESS | 1654 | -------- Summary: end -------- 09:45:28 INFO - PROCESS | 1654 | 09:45:29 INFO - TEST-INFO | 1654: exit 0 09:45:29 INFO - TEST-OK | dromaeo_css | took 1118187ms 09:45:29 INFO - TEST-START | kraken 09:45:29 INFO - operating with platform_type : mac_ 09:45:29 INFO - Initialising browser for kraken test... 09:45:29 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/tmp6GK6BW/profile http://localhost:49262/getInfo.html) 09:45:31 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 09:45:31 INFO - PROCESS | 1665 | colorDepth:24 09:45:31 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 09:45:31 INFO - PROCESS | 1665 | __metrics 09:45:31 INFO - PROCESS | 1665 | 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" 09:45:31 INFO - PROCESS | 1665 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:45:32 INFO - TEST-INFO | 1665: exit 0 09:45:32 INFO - Browser initialized. 09:45:32 INFO - Running cycle 1/1 for kraken test... 09:45:32 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/tmp6GK6BW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:45:44 INFO - PROCESS | 1666 | RSS: Main: 228392960 09:45:44 INFO - PROCESS | 1666 | 09:45:47 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49262/tests/kraken/driver.html?testName=audio-beat-detection) 09:45:48 INFO - PROCESS | 1666 | RSS: Main: 285708288 09:45:48 INFO - PROCESS | 1666 | 09:45:50 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49262/tests/kraken/driver.html?testName=audio-dft) 09:45:51 INFO - PROCESS | 1666 | RSS: Main: 293543936 09:45:51 INFO - PROCESS | 1666 | 09:45:54 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49262/tests/kraken/driver.html?testName=audio-fft) 09:45:55 INFO - PROCESS | 1666 | RSS: Main: 386183168 09:45:55 INFO - PROCESS | 1666 | 09:45:57 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49262/tests/kraken/driver.html?testName=audio-oscillator) 09:45:58 INFO - PROCESS | 1666 | RSS: Main: 288137216 09:45:58 INFO - PROCESS | 1666 | 09:46:00 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49262/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:46:00 INFO - PROCESS | 1666 | RSS: Main: 292044800 09:46:00 INFO - PROCESS | 1666 | 09:46:03 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49262/tests/kraken/driver.html?testName=imaging-darkroom) 09:46:04 INFO - PROCESS | 1666 | RSS: Main: 329723904 09:46:04 INFO - PROCESS | 1666 | 09:46:07 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49262/tests/kraken/driver.html?testName=imaging-desaturate) 09:46:08 INFO - PROCESS | 1666 | RSS: Main: 332451840 09:46:08 INFO - PROCESS | 1666 | 09:46:10 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49262/tests/kraken/driver.html?testName=json-parse-financial) 09:46:11 INFO - PROCESS | 1666 | RSS: Main: 332427264 09:46:11 INFO - PROCESS | 1666 | 09:46:13 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49262/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:46:13 INFO - PROCESS | 1666 | RSS: Main: 301436928 09:46:13 INFO - PROCESS | 1666 | 09:46:15 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:46:16 INFO - PROCESS | 1666 | RSS: Main: 307322880 09:46:16 INFO - PROCESS | 1666 | 09:46:18 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:46:19 INFO - PROCESS | 1666 | RSS: Main: 303083520 09:46:19 INFO - PROCESS | 1666 | 09:46:22 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:46:22 INFO - PROCESS | 1666 | RSS: Main: 314789888 09:46:22 INFO - PROCESS | 1666 | 09:46:25 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:46:26 INFO - PROCESS | 1666 | RSS: Main: 316293120 09:46:26 INFO - PROCESS | 1666 | 09:46:27 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49262/tests/kraken/driver.html?testName=ai-astar) 09:46:28 INFO - PROCESS | 1666 | RSS: Main: 359350272 09:46:28 INFO - PROCESS | 1666 | 09:46:28 INFO - PROCESS | 1666 | __start_tp_report 09:46:28 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 09:46:28 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 09:46:28 INFO - PROCESS | 1666 | |i|pagename|runs| 09:46:28 INFO - PROCESS | 1666 | |0;ai-astar;102;113;111;113;109;112;111;113;111;113 09:46:28 INFO - PROCESS | 1666 | |1;audio-beat-detection;147;132;139;182;107;129;127;106;158;106 09:46:28 INFO - PROCESS | 1666 | |2;audio-dft;185;183;186;183;184;184;183;182;179;183 09:46:28 INFO - PROCESS | 1666 | |3;audio-fft;76;58;62;88;74;48;67;77;110;79 09:46:28 INFO - PROCESS | 1666 | |4;audio-oscillator;83;69;105;71;78;69;96;73;68;101 09:46:28 INFO - PROCESS | 1666 | |5;imaging-gaussian-blur;88;85;86;85;88;91;135;117;89;85 09:46:28 INFO - PROCESS | 1666 | |6;imaging-darkroom;93;92;94;94;108;147;123;94;116;150 09:46:28 INFO - PROCESS | 1666 | |7;imaging-desaturate;84;86;86;86;85;85;84;83;85;84 09:46:28 INFO - PROCESS | 1666 | |8;json-parse-financial;54;52;54;53;53;54;53;53;55;53 09:46:28 INFO - PROCESS | 1666 | |9;json-stringify-tinderbox;64;66;72;72;75;70;70;68;69;69 09:46:28 INFO - PROCESS | 1666 | |10;stanford-crypto-aes;86;85;86;84;84;83;79;85;85;89 09:46:28 INFO - PROCESS | 1666 | |11;stanford-crypto-ccm;148;151;146;163;155;149;140;143;161;154 09:46:28 INFO - PROCESS | 1666 | |12;stanford-crypto-pbkdf2;168;168;168;169;168;168;167;167;171;164 09:46:28 INFO - PROCESS | 1666 | |13;stanford-crypto-sha256-iterative;67;68;68;68;68;66;67;67;68;67 09:46:28 INFO - PROCESS | 1666 | __end_tp_report 09:46:28 INFO - PROCESS | 1666 | __start_cc_report 09:46:28 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,2022 09:46:28 INFO - PROCESS | 1666 | __end_cc_report 09:46:28 INFO - PROCESS | 1666 | __startTimestamp1471538788132__endTimestamp 09:46:28 INFO - PROCESS | 1666 | 09:46:28 INFO - PROCESS | 1666 | ------- Summary: start ------- 09:46:28 INFO - PROCESS | 1666 | Number of tests: 14 09:46:28 INFO - PROCESS | 1666 | 09:46:28 INFO - PROCESS | 1666 | [#0] ai-astar Cycles:10 Average:110.80 Median:112.50 stddev:3.36 (3.0%) stddev-sans-first:1.39 09:46:28 INFO - PROCESS | 1666 | Values: 102.0 113.0 111.0 113.0 109.0 112.0 111.0 113.0 111.0 113.0 09:46:28 INFO - PROCESS | 1666 | 09:46:28 INFO - PROCESS | 1666 | [#1] audio-beat-detection Cycles:10 Average:133.30 Median:135.50 stddev:24.59 (18.2%) stddev-sans-first:25.58 09:46:28 INFO - PROCESS | 1666 | Values: 147.0 132.0 139.0 182.0 107.0 129.0 127.0 106.0 158.0 106.0 09:46:28 INFO - PROCESS | 1666 | 09:46:28 INFO - PROCESS | 1666 | [#2] audio-dft Cycles:10 Average:183.20 Median:183.50 stddev:1.87 (1.0%) stddev-sans-first:1.87 09:46:28 INFO - PROCESS | 1666 | Values: 185.0 183.0 186.0 183.0 184.0 184.0 183.0 182.0 179.0 183.0 09:46:28 INFO - PROCESS | 1666 | 09:46:28 INFO - PROCESS | 1666 | [#3] audio-fft Cycles:10 Average:73.90 Median:75.00 stddev:17.18 (22.9%) stddev-sans-first:18.20 09:46:28 INFO - PROCESS | 1666 | Values: 76.0 58.0 62.0 88.0 74.0 48.0 67.0 77.0 110.0 79.0 09:46:28 INFO - PROCESS | 1666 | 09:46:28 INFO - PROCESS | 1666 | [#4] audio-oscillator Cycles:10 Average:81.30 Median:72.00 stddev:14.28 (19.8%) stddev-sans-first:15.13 09:46:28 INFO - PROCESS | 1666 | Values: 83.0 69.0 105.0 71.0 78.0 69.0 96.0 73.0 68.0 101.0 09:46:28 INFO - PROCESS | 1666 | 09:46:28 INFO - PROCESS | 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:94.90 Median:87.00 stddev:17.05 (19.6%) stddev-sans-first:17.90 09:46:28 INFO - PROCESS | 1666 | Values: 88.0 85.0 86.0 85.0 88.0 91.0 135.0 117.0 89.0 85.0 09:46:28 INFO - PROCESS | 1666 | 09:46:28 INFO - PROCESS | 1666 | [#6] imaging-darkroom Cycles:10 Average:111.10 Median:92.50 stddev:22.48 (24.3%) stddev-sans-first:22.87 09:46:28 INFO - PROCESS | 1666 | Values: 93.0 92.0 94.0 94.0 108.0 147.0 123.0 94.0 116.0 150.0 09:46:28 INFO - PROCESS | 1666 | 09:46:28 INFO - PROCESS | 1666 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.00 stddev:1.03 (1.2%) stddev-sans-first:1.05 09:46:28 INFO - PROCESS | 1666 | Values: 84.0 86.0 86.0 86.0 85.0 85.0 84.0 83.0 85.0 84.0 09:46:28 INFO - PROCESS | 1666 | 09:46:28 INFO - PROCESS | 1666 | [#8] json-parse-financial Cycles:10 Average:53.40 Median:53.50 stddev:0.84 (1.6%) stddev-sans-first:0.87 09:46:28 INFO - PROCESS | 1666 | Values: 54.0 52.0 54.0 53.0 53.0 54.0 53.0 53.0 55.0 53.0 09:46:28 INFO - PROCESS | 1666 | 09:46:28 INFO - PROCESS | 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:69.50 Median:70.00 stddev:3.14 (4.5%) stddev-sans-first:2.62 09:46:28 INFO - PROCESS | 1666 | Values: 64.0 66.0 72.0 72.0 75.0 70.0 70.0 68.0 69.0 69.0 09:46:28 INFO - PROCESS | 1666 | 09:46:28 INFO - PROCESS | 1666 | [#10] stanford-crypto-aes Cycles:10 Average:84.60 Median:85.00 stddev:2.55 (3.0%) stddev-sans-first:2.65 09:46:28 INFO - PROCESS | 1666 | Values: 86.0 85.0 86.0 84.0 84.0 83.0 79.0 85.0 85.0 89.0 09:46:28 INFO - PROCESS | 1666 | 09:46:28 INFO - PROCESS | 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:151.00 Median:152.50 stddev:7.39 (4.8%) stddev-sans-first:7.76 09:46:28 INFO - PROCESS | 1666 | Values: 148.0 151.0 146.0 163.0 155.0 149.0 140.0 143.0 161.0 154.0 09:46:28 INFO - PROCESS | 1666 | 09:46:28 INFO - PROCESS | 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.80 Median:168.00 stddev:1.75 (1.0%) stddev-sans-first:1.86 09:46:28 INFO - PROCESS | 1666 | Values: 168.0 168.0 168.0 169.0 168.0 168.0 167.0 167.0 171.0 164.0 09:46:28 INFO - PROCESS | 1666 | 09:46:28 INFO - PROCESS | 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.00 stddev:0.70 (1.0%) stddev-sans-first:0.73 09:46:28 INFO - PROCESS | 1666 | Values: 67.0 68.0 68.0 68.0 68.0 66.0 67.0 67.0 68.0 67.0 09:46:28 INFO - PROCESS | 1666 | -------- Summary: end -------- 09:46:28 INFO - PROCESS | 1666 | 09:46:28 INFO - TEST-INFO | 1666: exit 0 09:46:29 INFO - TEST-OK | kraken | took 59780ms 09:46:29 INFO - SUITE-END | took 1177s 09:46:29 INFO - Completed test suite (00:19:38) 09:46:29 INFO - javascript benchmark 09:46:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2800.425912687899, 3360.479125735511, 3363.495475678649, 3396.8471418786153, 3399.6090449598305, 4502.954970450294, 5294.682319060859, 5658.830235092949, 5749.7125143742805, 5752.511036561887, 5697.145428185774, 5712.942870571293, 5717.828465146053, 5745.454181852718, 5760.135979603051, 5184.144616138333, 5201.739913004335, 5238.554722848562, 5276.657017293883, 5308.601854860882, 5234.947650523503, 5284.180951952453, 5286.286351342558, 5294.52349288564, 5300.973495132538, 5184.222366644995, 5228.973855130719, 5237.921431178535, 5258.526732594066, 5278.155495120782, 5306.071437498423, 5307.230451584518, 5322.205568198222, 5322.5209731124, 5343.278657381243, 5129.307543481619, 5130.794768209267, 5142.871428214287, 5257.237700533401, 5264.184051472028, 5709.200711900337, 5718.0, 5723.656580605177, 5737.311522617271, 5766.913496297559, 6287.654179020152, 6290.370962903673, 6293.842653933688, 6295.496360291166, 6296.96851515744, 6142.314611233224, 6193.225846769154, 6228.657423841687, 6236.719347629368, 6267.561270711052, 4635.374224479686, 5863.091220860773, 5887.528997680175, 5891.528677705774, 5895.67573783446, 6293.559450838397, 6294.65379404128, 6312.652804095819, 6313.9368606314265, 6314.905276420857, 6013.579049466495, 6107.340534417217, 6184.165137706442, 6218.098375735583, 6235.251769787655, 6337.556371053982, 6353.841153971095, 6356.841078973062, 6358.0, 6361.872762544724, 6237.157983672237, 6268.09112678659, 6284.0, 6288.968555157195, 6303.180586523723, 6106.946079819618, 6180.536459765535, 6183.103449999725, 6279.560430769893, 6320.0, 4906.362172917497, 4916.950830491649, 4926.8768280793265, 4943.258511223274, 4956.132676781621, 4416.227160246944, 4440.4005459263235, 4448.421705178371, 4472.9776351118035, 4521.8191272349395, 5841.123831425235, 5844.152597873284, 5847.56143289255, 5849.064149736022, 5892.764289428461, 6152.138700581922, 6214.378562143749, 6321.55749097568, 6352.205974253218, 6367.204099487564, 5757.309122905194, 5800.4489573490455, 5816.185733997244, 5825.79609713658, 5857.414258574108, 5462.863428414242, 5464.726763661801, 5470.234167216593, 5470.781168753285, 5473.425290344536, 5016.874578135503, 6180.258368995749, 6259.155014073224, 6314.526410519321, 6331.398517140864, 6338.176037115146, 6354.618722876642, 6366.363363663597, 6367.586106903036, 6371.235451745635, 6350.4919606432295, 6358.809235722936, 6366.586171898812, 6368.23581170244, 6371.171747672773, 6110.69446527663, 6130.214697154933, 6165.475934545614, 6266.091416744638, 6275.874482510233, 6242.719077641426, 6285.151504546828, 6310.1796766420075, 6328.588641738273, 6332.841678957879, 5357.946420535901, 5550.361708403585, 6127.172831667668, 6167.105769663463, 6177.289611694712, 5992.970035149797, 6065.787697430569, 6119.969400152971, 6146.231721034871, 6158.445739883432, 6003.429674180946, 6018.668973206516, 6031.276246850406, 6033.5173186145885, 6053.515718742402, 6143.09913205109, 6147.600405973598, 6154.507639388748, 6155.784547540815, 6184.659843708459, 5806.129080637938, 5855.033919403421, 5883.735231914659, 5956.523478121827, 5989.431004054608], "value": 5711.032460714151, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [523.0323900783551, 543.8694713268815, 545.7953267524679, 550.6640949021099, 555.6499405374614, 31003.0, 32166.195845103855, 41299.38050929239, 42223.57776422235, 42346.364804527955, 42642.786786066026, 43053.784731076375, 43065.0, 43176.784116079376, 43196.13607727844, 9711.611535538588, 9901.158401535879, 9907.653232136876, 9909.306348555603, 9914.702558923243, 8857.42426742263, 9081.18269355758, 12170.330631815246, 12474.50101995919, 12480.750384992294, 12269.202501837397, 12347.876521234808, 12377.690557736083, 12383.071269654767, 12402.565910193145, 11244.887551124466, 11461.426928653618, 12272.0, 12339.197952133127, 12341.753164936741, 12155.149139560235, 12212.511499540007, 12235.755284894341, 12238.755224895453, 12242.938785306105, 33920.982370528924, 41115.0, 41296.587034129574, 41321.38017929733, 41477.170456591004, 3399.779014364083, 3477.4957631143343, 3527.2945410917664, 3938.212357528506, 4261.616454519108, 31312.843435782674, 32377.190570235696, 32542.674573254437, 32581.533830977663, 32647.510287345707, 2872.1814282929263, 3259.559959405474, 3389.8983030509125, 3444.8277586120844, 3456.9654303457146, 465598.0, 465725.0, 468413.0, 469604.0, 472771.0, 59786.40213597808, 60020.0, 60052.0, 60073.69963150244, 60118.79762404727, 62389.0, 62499.375006250266, 62582.68708656519, 62598.0, 62719.686401568615, 16487.258073386725, 16739.83260167383, 16761.7485737714, 16768.748468772978, 16778.580535486468, 11661.416929153509, 11926.284422934652, 13004.8049279259, 13070.934645326903, 13079.542216022393, 14108.0, 16366.345346186257, 16887.493375198763, 16936.915315423343, 17332.91333543324, 7353.904137320837, 7474.065741782278, 7474.962625186839, 7517.548947063194, 7583.9620801895635, 866.8656358264479, 962.6341990043738, 1001.5242759689089, 1038.2524582300732, 1053.2416660004785, 26569.33576660599, 27140.457190856072, 29721.0, 30607.08178754641, 31329.530057049164, 945.789389581337, 1000.7240768020831, 1006.9577986783644, 1034.943078130695, 1045.5399624165343, 15629.14039727826, 15925.203739812963, 15950.76073858893, 16150.484137917449, 16346.83653163477, 605779.0, 609902.0, 611640.9417952881, 614586.0, 615541.0, 59339.70330148408, 59429.0, 59441.40558594445, 59502.404975949685, 59562.0, 1258.880406361376, 1303.2897071096086, 1375.3742047368423, 1425.308725268244, 1441.978370324446, 22096.0, 31241.37517249597, 32454.675453245167, 33058.00825975225, 33101.00696979094, 55657.0, 56410.153847691494, 56453.0, 56594.434055658916, 56666.433335666115, 560071.0, 560177.0, 560534.0, 565759.0, 567109.0, 23916.16293429791, 24347.878260609294, 26686.46627067409, 27138.72861271362, 40818.591814081476, 46820.29769553391, 49140.26289605587, 50648.0, 51362.74318628533, 52550.47449525456, 31191.68808311888, 39713.0, 39820.2035959285, 41488.1702365957, 41572.58427415687, 39748.60251397449, 39958.800205998785, 40393.59606404016, 41374.58625413707, 41477.792611036755, 38841.0, 39296.60703392929, 39658.0, 39689.20621587495, 39772.005699857735, 33286.692467997, 34761.65238347584, 35072.772452964025, 35193.0, 35240.82379588085, 25173.118940836983, 25735.227943161735, 25978.0, 26113.347166320993, 26625.068122615616], "value": 20647.97064413942, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.65661025753747, 369.5823719197305, 372.8955892350142, 380.52054411441594, 402.74828232354776, 19677.901610491946, 20798.048370809556, 21167.68248476274, 21732.89133554332, 21954.0, 10701.501789749422, 11611.883881161186, 12011.279323240611, 12014.579489717862, 12026.759464810699, 4829.78265978031, 5468.425815289377, 5472.480114389146, 5478.91781623276, 5481.725913704299, 3893.0851249956236, 4117.485314335708, 5340.67955922644, 5460.781568737246, 7064.717411303567, 7037.612931288777, 7041.683124259421, 7051.717931282768, 7068.081149450564, 7074.115735533059, 5840.824775256728, 6133.754649814002, 6138.294096178952, 6196.597221180609, 7040.366367026966, 5233.764480598382, 5983.880322393527, 5990.161377407123, 5997.310309314441, 6001.0, 12984.350782460839, 13383.996200285017, 13385.12996655224, 13421.194728316235, 15006.62483437912, 2108.3042595943302, 2285.257291380308, 2577.201067669029, 2631.657884475006, 2926.809757365764, 9815.558299876522, 10147.594096236142, 10832.67501974942, 15117.622059448491, 15740.842591574166, 1841.6040551281405, 1857.8796391254518, 1917.8178073083175, 1926.807319268076, 1949.639316726396, 117319.0, 127323.0, 129528.0, 129795.0, 130231.34884325518, 9745.658901938397, 10708.73228169302, 10785.83821242682, 11530.423478826193, 11747.941260293645, 14455.0, 14458.710825783635, 14461.85538144605, 14469.49356772508, 14486.130832149895, 8292.12932642076, 8565.95717021411, 8570.614322355508, 8728.170823771732, 8741.256993155654, 8511.702090426928, 8537.573121343908, 8538.786530336667, 8673.783155421039, 8704.347173961978, 4375.934360984587, 4440.933385999213, 4525.52481989393, 4535.795889184995, 4646.395968524071, 1708.325211541434, 1756.6837969165417, 1808.7829460464566, 1884.538288119392, 1924.9037548122537, 737.7311025036928, 894.8970868350093, 901.5987885391069, 937.8546325319586, 1040.885502594714, 21670.458238543848, 22827.543449130924, 23152.0, 23206.65190022151, 23211.0, 886.9955650221837, 899.9910000900038, 966.6906589891308, 967.4969016111583, 973.4500007495742, 9175.403598766059, 11136.387498687487, 11226.326420414802, 11239.719007024889, 11240.887591123985, 615730.0, 618376.0, 626646.0, 626766.0, 631026.0, 121944.0, 130479.0, 140681.26253055976, 143884.28057859643, 145638.0, 832.2509741233059, 948.9335746497817, 1011.9291649584605, 1019.5327307914864, 1023.3808545829814, 13916.437487833779, 17366.65266694634, 18914.243430262635, 19400.320988765325, 19502.0, 57716.711416442646, 57777.422225778886, 57986.7100664494, 57987.0, 59226.815463691346, 84367.0, 85075.0, 85394.57302713695, 86718.0, 86921.6961745587, 6485.124508191334, 7170.2471240519, 7824.374050075867, 8045.879311810445, 8304.29413499835, 11974.401279935793, 13190.274534900673, 13208.603741887759, 13385.732285354434, 13713.725725485236, 11011.834822477509, 11470.369129697612, 11472.31166130035, 11493.367864767519, 11549.42252887369, 11202.38386888729, 11370.033547148254, 11543.0, 11577.07383409342, 11866.525338986345, 10027.448490333101, 11273.0, 11495.482703278205, 11818.231814932002, 11848.518935133108, 7344.0, 8112.59437028158, 8139.959300203461, 8156.918430815853, 8204.138565450541, 9381.577828997575, 10463.529141188817, 10709.732256693645, 10968.890311097106, 11314.773704525698], "value": 9429.151177582999, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1356.1862882270636, 1493.3951749541436, 1640.1799100449775, 1794.6321004194142, 1841.245089513299, 8218.46579972302, 8751.124887511261, 8753.91246087539, 8760.912390876088, 8762.561871906411, 7819.609019549014, 8035.3169980551575, 8046.396520260974, 8153.510789352643, 8572.271356934652, 2180.934571962836, 2217.691561978432, 2335.789778919897, 2415.3237093613816, 2419.8790060496995, 2413.3242692046256, 2413.54142712884, 2415.7342692303832, 2418.746031666676, 2423.672804171441, 2402.783749462548, 2418.4921166554955, 2438.1222759806456, 2442.2917353967327, 2455.2388759484616, 2233.229535810143, 2288.786942920255, 2394.437307232799, 2402.3633737059695, 2416.903323867052, 2375.2992867104244, 2393.9162129325564, 2399.748026457214, 2407.843490173133, 2409.4337830609793, 8646.913530864675, 8752.343574231958, 8802.603882825224, 8805.383623146383, 8886.955565222133, 5144.897102057976, 5302.761375738062, 5304.310439642861, 5316.0165369406395, 5329.653572517813, 3229.7416206703642, 3327.8502467389026, 3609.079684680416, 3659.7621154624862, 3675.5773086094905, 1028.1825948371063, 1057.5452555401175, 1076.3488089705693, 1077.6389909380366, 1096.950637221327, 11581.420928953603, 11628.825567616492, 11631.709207269801, 11638.70903227426, 11648.475818588098, 4100.913257986636, 4397.758123303185, 4577.702449340783, 4639.884002899887, 4745.217039188564, 9879.604815807432, 9881.80236395268, 9886.258530610234, 9890.406575605486, 9898.851517227247, 5318.521333080042, 5323.254744335796, 5324.1747529133045, 5331.44019877915, 5350.892982140414, 4759.571638552547, 4860.416749990024, 4881.194602890483, 4891.364122664031, 4891.5353041461, 3722.4602432647657, 3833.8274777634515, 3860.6718428933295, 3905.941410878839, 3986.142979259473, 1279.4434421026883, 1285.0875878126449, 1288.8529209003993, 1302.1210682789078, 1308.2804457548311, 835.320413578189, 851.3231980575457, 889.1863944490848, 900.5722281916037, 940.1115945431638, 4866.586340161126, 5702.230198923092, 5887.175795388649, 6023.0965355196195, 6056.182415373868, 903.5617725403174, 931.6925414613297, 945.239082538563, 951.7192428233687, 957.9281553883486, 3854.768713877176, 3859.3632050711317, 3886.0867696091395, 3966.8413263469715, 4045.3325201342036, 8456.40805143634, 10725.731856703489, 10748.247622666338, 10768.084712799344, 10768.838467422995, 10658.73353166177, 10658.987396197504, 10673.35959842412, 10677.14582833387, 10696.51865666047, 7220.386267167244, 8022.318102961313, 8115.513069215984, 8187.222213889671, 8198.344132469389, 5927.516138169573, 5986.2517185351835, 6027.186329845415, 6074.119252708421, 6143.477804386677, 9596.520173991412, 9844.556994935389, 9845.96617355196, 9858.852117218104, 9872.901270987486, 9083.864516935382, 9836.803263934538, 9991.450470224207, 10060.949695251476, 10066.547005384922, 7394.260573942563, 7473.701051957861, 7491.475596708178, 7584.2794934481135, 7599.088109426904, 7431.219721929119, 7703.0, 7933.9603301983125, 8197.508149511048, 8209.302209312274, 7443.962780186064, 7478.401727861867, 7514.098308203051, 7530.849383012419, 7619.466637335333, 7088.858222835617, 7354.117505899327, 7355.963220184079, 7382.815429614303, 7419.480636355619, 5910.70446477683, 6870.27862074475, 6970.825729356807, 7033.542819716702, 7046.1192350956135, 4767.141914455431, 4985.152524070844, 5025.572826309804, 5108.591312694901, 5222.033923724123, 4661.720296782204, 4751.928721069117, 4965.577925876341, 5100.515451032146, 5174.767135478988], "value": 4558.01750191772, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [290.55545016125325, 297.0953446754632, 305.36484113044867, 307.9667714816791, 309.2562387458164, 5214.400343960446, 5271.127235656567, 5278.181881808326, 5278.340207474066, 5291.6031297652635, 4527.660425468095, 4922.310876477287, 5031.823886163985, 5129.204973229146, 5195.4025287092, 3234.805911645297, 3245.6916592923635, 3912.608739126093, 3965.6827453803626, 3982.561918188997, 3977.8011099445057, 3978.6021397860127, 4005.959940400588, 4019.8794036178815, 4022.537408198051, 3143.387039527293, 3492.9346549302454, 3730.850765969358, 3764.0, 3912.7088060939823, 3956.2087582483473, 3968.7420317679257, 3971.994142957788, 3994.62051105146, 3996.6403023728003, 3471.4137967214588, 3485.605757696916, 3509.9649003509894, 3520.68313851752, 3520.894373168809, 4398.560143985608, 4498.79755411004, 4662.642897114107, 4873.0, 4940.851774446736, 3772.396416573335, 3922.4900762900925, 4558.7264764113925, 4570.245909424941, 4628.768561571942, 5455.481729235717, 5519.448055194489, 5538.556915446755, 5558.027345214571, 5597.608167428281, 3898.532176138853, 3902.1610353774076, 3905.7892053463515, 3929.783861887595, 3931.8230679619487, 5391.813800963781, 5674.432556744375, 5693.402192769741, 5705.6291341063115, 5745.942540574624, 3908.804559772, 3922.2547715933883, 3932.311845427039, 3940.330143875548, 3965.940510892339, 3970.6029397060065, 3993.9201215975518, 4014.759114453142, 4015.8996025099605, 4030.879073627796, 3882.8835134946, 4422.783734473046, 4589.796897489224, 4622.2835460503675, 4625.236835922035, 4542.454905411372, 5417.4311697272005, 5474.644148130438, 5478.726063696799, 5500.28496295479, 1110.9723505757142, 1333.746588148268, 1356.2337279437086, 1357.7284543091223, 1359.1233654292926, 694.468696524163, 712.9465290103159, 750.91364493084, 763.2988638816327, 885.291766586728, 4677.2750223715375, 4732.668713190113, 4911.680740751911, 5073.289739436482, 5171.663841850268, 4602.65480088988, 5200.375954885362, 5596.712756066127, 5597.928766356475, 5672.865426914634, 4069.5726948670554, 4951.851503805894, 5142.228665700175, 5144.794208231704, 5159.458256883048, 2405.6872606561387, 2457.49621327631, 2908.418316336741, 2928.3850391417614, 2932.9266768330535, 5744.712764361765, 5756.856078598069, 5764.423557644286, 5811.360750317462, 5816.970915145482, 4713.0, 4979.477154898684, 5039.899202016012, 5126.872607709294, 5732.3407808101765, 4663.533646635377, 4957.677750946177, 5330.760115794798, 5656.717164141859, 5768.74040668171, 5503.889922201613, 5536.778528858962, 5541.9168712470155, 5561.190584449665, 5703.0, 5089.3638295213095, 5252.42223355423, 5369.248305237192, 5523.861903452445, 5555.805546805843, 4806.639502037291, 5044.672096313728, 5790.826275211751, 5800.912986305125, 5811.372815611636, 3107.984460077685, 3697.556293244828, 3914.745541539791, 3919.8236079375924, 3942.0, 5226.686398816083, 5495.884084627367, 5508.3114610673665, 5547.362053363753, 5622.353429355513, 4985.426675932169, 5325.760340784596, 5396.595255355785, 5538.280023596906, 5575.470330318613, 5476.315460567429, 5484.177373394023, 5492.995056804318, 5493.972530137324, 5540.7783688652, 4707.844909823738, 4875.829345972874, 4890.657653964188, 5159.971073040602, 5286.920696189483, 4807.711537307773, 5253.789848406021, 5482.533984611353, 5500.862478438071, 5559.24950131727, 5341.985022845647, 5353.170258609922, 5372.301600791872, 5372.710549468177, 5397.70312632809], "value": 3980.925614238501, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.7941596936888, 759.7986533568603, 798.5727635714894, 804.975850724478, 809.8015986083407, 15463.768043479342, 16388.590285242874, 16423.753643695338, 16431.917840410795, 16439.671206575862, 14293.49972750954, 14714.852851471509, 15867.206639667998, 15961.920190399062, 16060.518184454455, 833.1959658929147, 843.2368706320759, 844.0504432513422, 844.4173520270994, 844.4342290665238, 669.2470970158572, 694.711694646721, 834.5284914023588, 849.1508491508491, 856.4861083350003, 901.6979311930511, 904.9909351073571, 905.868649045888, 906.4742449379344, 907.3059109781022, 896.3322324868016, 897.1073781587297, 897.3897749530316, 897.712731925784, 898.0615257056392, 518.7613697699063, 529.4996228564008, 535.2212530767763, 537.2719964448195, 665.354606032148, 10499.73750656232, 12436.0, 14413.964090054802, 14441.422343106264, 14446.494372696905, 14436.49472268476, 14697.77953330701, 14906.776398354034, 15211.923940380339, 15255.847441525553, 11984.520619175222, 12010.579629713005, 12174.208676436092, 12246.142770006103, 12267.815982760265, 3893.9415908761393, 3917.784521851297, 3923.6664883484937, 3938.1926705025403, 4053.9594604053877, 113836.43081784538, 113947.0, 114641.0, 115118.42440787826, 115373.0, 11396.715082123013, 11461.140414468948, 11495.71260718472, 11740.58907938218, 11824.940875295568, 23489.530209395718, 23516.76483235146, 23539.29382118539, 23571.29286121419, 23624.763752362258, 8095.7166499172245, 8105.351571874354, 8143.592820358958, 8241.670333186725, 8245.670173193124, 4483.147238867862, 4515.164694531522, 4534.841280555231, 4650.162970665245, 4689.179393606105, 2958.7189217024347, 3708.1100535871196, 3754.9061273467837, 3755.080005398695, 3756.38019726742, 556.7967691792467, 564.4863174511175, 644.5810223354857, 649.3409189672486, 671.2414971082743, 575.5108158065694, 595.4968051996057, 649.7622030032795, 697.8639165362706, 699.9545029573163, 8841.823163536694, 9394.62421503146, 9678.370905891235, 9697.224222062217, 9932.255080868963, 548.3173449055951, 609.1715267236555, 616.7594638091148, 669.7019826177402, 695.5617960684737, 4354.499232588293, 4393.560643935581, 4415.911681766411, 4458.910821783546, 4557.977210113928, 106988.0, 132955.0, 139831.0, 141697.5830241705, 142430.0, 114904.86997730403, 120294.0, 121318.0, 122361.0, 123393.76606233823, 99498.52758098375, 103977.0, 107875.8424831499, 108137.0, 110605.44697276462, 8190.549519776469, 9058.682946096855, 9308.0, 9410.247180225702, 10010.499475026365, 47294.05411891812, 59651.0, 60269.0959635597, 61921.07118393137, 64500.354996451315, 86903.69644455212, 91243.54378228067, 95696.0430395715, 96193.07613847824, 96951.0, 12573.93713031429, 12968.481260749464, 12976.545820896223, 13163.0, 13676.042677012514, 14729.92635036818, 14994.175320357048, 15071.0, 15392.46126385616, 15405.691886162438, 11789.233699809485, 13451.529196478075, 13908.37412316428, 14008.019438639198, 14173.574792756233, 11521.0, 13827.446902124204, 13934.79097813554, 14136.78794818099, 14421.495247666282, 11482.885171148515, 13733.656658583615, 13844.95488924642, 13871.167729936236, 14176.362063707364, 11407.885921140682, 11600.709982250512, 11625.88374116282, 11791.823122652995, 11823.290602563873, 11436.485358158736, 11466.77066458683, 11478.196526243082, 11493.080553555528, 11495.885041149482], "value": 7806.688305878742, "unit": "score"}], "name": "dromaeo_css", "value": 7348.916970840016, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 113.0, 111.0, 113.0, 109.0, 112.0, 111.0, 113.0, 111.0, 113.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 132.0, 139.0, 182.0, 107.0, 129.0, 127.0, 106.0, 158.0, 106.0], "value": 133.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 186.0, 183.0, 184.0, 184.0, 183.0, 182.0, 179.0, 183.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 58.0, 62.0, 88.0, 74.0, 48.0, 67.0, 77.0, 110.0, 79.0], "value": 73.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 69.0, 105.0, 71.0, 78.0, 69.0, 96.0, 73.0, 68.0, 101.0], "value": 81.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 86.0, 85.0, 88.0, 91.0, 135.0, 117.0, 89.0, 85.0], "value": 94.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 94.0, 94.0, 108.0, 147.0, 123.0, 94.0, 116.0, 150.0], "value": 111.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 86.0, 86.0, 85.0, 85.0, 84.0, 83.0, 85.0, 84.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 54.0, 53.0, 53.0, 54.0, 53.0, 53.0, 55.0, 53.0], "value": 53.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 72.0, 72.0, 75.0, 70.0, 70.0, 68.0, 69.0, 69.0], "value": 69.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 86.0, 84.0, 84.0, 83.0, 79.0, 85.0, 85.0, 89.0], "value": 84.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 151.0, 146.0, 163.0, 155.0, 149.0, 140.0, 143.0, 161.0, 154.0], "value": 151.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 168.0, 168.0, 169.0, 168.0, 168.0, 167.0, 167.0, 171.0, 164.0], "value": 167.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 68.0, 68.0, 66.0, 67.0, 67.0, 68.0, 67.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1467.0, "alertThreshold": 2.0}]} 09:46:29 INFO - Return code: 0 09:46:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:46:29 INFO - # TBPL SUCCESS # 09:46:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:46:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:46:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:46:29 INFO - 2016-08-18 09:46:29.473 screenresolution[1670:10025] starting screenresolution argv=screenresolution get 09:46:29 INFO - 2016-08-18 09:46:29.476 screenresolution[1670:10025] Display 0: 1600x1200x32@60 09:46:29 INFO - 2016-08-18 09:46:29.482 screenresolution[1671:10028] starting screenresolution argv=screenresolution list 09:46:29 INFO - Available Modes on Display 0 09:46:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:46:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:46:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:46:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:46:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:46:29 INFO - Intel Iris: 09:46:29 INFO - Chipset Model: Intel Iris 09:46:29 INFO - Type: GPU 09:46:29 INFO - Bus: Built-In 09:46:29 INFO - VRAM (Dynamic, Max): 1536 MB 09:46:29 INFO - Vendor: Intel (0x8086) 09:46:29 INFO - Device ID: 0x0a2e 09:46:29 INFO - Revision ID: 0x0009 09:46:29 INFO - Displays: 09:46:29 INFO - Display: 09:46:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:46:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:46:29 INFO - Display Serial Number: 1600x1200 60 09:46:29 INFO - Main Display: Yes 09:46:29 INFO - Mirror: Off 09:46:29 INFO - Online: Yes 09:46:29 INFO - Rotation: Supported 09:46:29 INFO - Return code: 0 09:46:29 INFO - Running post-action listener: _resource_record_post_action 09:46:29 INFO - [mozharness: 2016-08-18 16:46:29.714261Z] Finished run-tests step (success) 09:46:29 INFO - Running post-run listener: _resource_record_post_run 09:46:29 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 37225472; Write bytes: 703877120; Read time: 241; Write time: 1918 09:46:29 INFO - TinderboxPrint: CPU usage
53.3% 09:46:29 INFO - TinderboxPrint: I/O read bytes / time
37,225,472 / 241 09:46:29 INFO - TinderboxPrint: I/O write bytes / time
703,877,120 / 1,918 09:46:29 INFO - TinderboxPrint: CPU idle
2,268.1 (46.7%) 09:46:29 INFO - TinderboxPrint: CPU system
963.3 (19.8%) 09:46:29 INFO - TinderboxPrint: CPU user
1,622.2 (33.4%) 09:46:29 INFO - TinderboxPrint: Swap in / out
442,728,448 / 0 09:46:29 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 390552064; Write bytes: 385079296; Read time: 29302; Write time: 759 09:46:29 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 36258816; Write bytes: 295417856; Read time: 228; Write time: 1099 09:46:30 INFO - Running post-run listener: _upload_blobber_files 09:46:30 INFO - Blob upload gear active. 09:46:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:46:30 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 09:46:30 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'] 09:46:30 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 09:46:30 INFO - (blobuploader) - INFO - Open directory for files ... 09:46:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:46:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:46:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:46:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:46:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:46:31 INFO - (blobuploader) - INFO - Done attempting. 09:46:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:46:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:46:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:46:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:46:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:46:32 INFO - (blobuploader) - INFO - Done attempting. 09:46:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:46:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:46:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:46:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:46:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:46:34 INFO - (blobuploader) - INFO - Done attempting. 09:46:34 INFO - (blobuploader) - INFO - Iteration through files over. 09:46:34 INFO - Return code: 0 09:46:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:46:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:46:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/14bff2508f2b7f319f5787f4713209ebf8a0ba2a5c69c4b17757817528203fa38585054e7a742fcce856e5b6c76d1d3e51034e6f49828bb952350ba6c05a4219", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/52ff73f61576f59d6e8e365a44cfa11d2d20d2961b97dee14c33fa3e219eb0d6710eee056a24dae29705d0a777f4b3884275f6b8d8453261c6c80f146d789e33", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4b1f98413d36975537f4abdff83b087c4420d84c11b2a91f9037811172b605632fba27af4e24344d055c58bde7db24f606aa534dcffeb31c2b89c2f81093e2e5"} 09:46:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:46:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:46:34 INFO - Contents: 09:46:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/14bff2508f2b7f319f5787f4713209ebf8a0ba2a5c69c4b17757817528203fa38585054e7a742fcce856e5b6c76d1d3e51034e6f49828bb952350ba6c05a4219", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/52ff73f61576f59d6e8e365a44cfa11d2d20d2961b97dee14c33fa3e219eb0d6710eee056a24dae29705d0a777f4b3884275f6b8d8453261c6c80f146d789e33", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4b1f98413d36975537f4abdff83b087c4420d84c11b2a91f9037811172b605632fba27af4e24344d055c58bde7db24f606aa534dcffeb31c2b89c2f81093e2e5"} 09:46:34 INFO - Running post-run listener: copy_logs_to_upload_dir 09:46:34 INFO - Copying logs to upload dir... 09:46:34 INFO - mkdir: /builds/slave/test/build/upload/logs 09:46:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.202731 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-18 09:46:34.852126) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 09:46:34.857138) ========= 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.8UXcdXnY1t/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.d1lTvilxjJ/Listeners TMPDIR=/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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/14bff2508f2b7f319f5787f4713209ebf8a0ba2a5c69c4b17757817528203fa38585054e7a742fcce856e5b6c76d1d3e51034e6f49828bb952350ba6c05a4219", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/52ff73f61576f59d6e8e365a44cfa11d2d20d2961b97dee14c33fa3e219eb0d6710eee056a24dae29705d0a777f4b3884275f6b8d8453261c6c80f146d789e33", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4b1f98413d36975537f4abdff83b087c4420d84c11b2a91f9037811172b605632fba27af4e24344d055c58bde7db24f606aa534dcffeb31c2b89c2f81093e2e5"} build_url:https://queue.taskcluster.net/v1/task/dSW55GowQuCuwP31t7XnCg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011296 build_url: 'https://queue.taskcluster.net/v1/task/dSW55GowQuCuwP31t7XnCg/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/14bff2508f2b7f319f5787f4713209ebf8a0ba2a5c69c4b17757817528203fa38585054e7a742fcce856e5b6c76d1d3e51034e6f49828bb952350ba6c05a4219", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/52ff73f61576f59d6e8e365a44cfa11d2d20d2961b97dee14c33fa3e219eb0d6710eee056a24dae29705d0a777f4b3884275f6b8d8453261c6c80f146d789e33", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4b1f98413d36975537f4abdff83b087c4420d84c11b2a91f9037811172b605632fba27af4e24344d055c58bde7db24f606aa534dcffeb31c2b89c2f81093e2e5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 09:46:34.889931) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 09:46:34.890255) ========= 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.8UXcdXnY1t/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.d1lTvilxjJ/Listeners TMPDIR=/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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.005143 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 09:46:34.938571) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 09:46:34.938892) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 09:46:34.939227) ========= ========= Total master_lag: 0.17 =========