builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0258 starttime: 1471474485.84 results: success (0) buildid: 20160817125015 builduid: 3fc1c3fbc4134d86aeedd25d747da207 revision: 6d6f82046da48820aca4a866fa0184234783ed7d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-17 15:54:45.837870) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-17 15:54:45.838639) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-17 15:54:45.839360) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-17 15:54:46.387767) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 15:54:46.388368) ========= 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.l39pVwyRBI/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.hgrEww7IpI/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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-17 15:54:46-- 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% 7.06M=0.002s 2016-08-17 15:54:46 (7.06 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.112445 ========= master_lag: 0.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 15:54:46.587162) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 15:54:46.587516) ========= 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.l39pVwyRBI/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.hgrEww7IpI/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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.021260 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 15:54:46.648931) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-08-17 15:54:46.649332) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 6d6f82046da48820aca4a866fa0184234783ed7d --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 6d6f82046da48820aca4a866fa0184234783ed7d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.l39pVwyRBI/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.hgrEww7IpI/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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-17 15:54:46,737 truncating revision to first 12 chars 2016-08-17 15:54:46,737 Setting DEBUG logging. 2016-08-17 15:54:46,737 attempt 1/10 2016-08-17 15:54:46,737 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/6d6f82046da4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-17 15:54:48,749 unpacking tar archive at: autoland-6d6f82046da4/testing/mozharness/ program finished with exit code 0 elapsedTime=2.285127 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-08-17 15:54:48.963780) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-17 15:54:48.964495) ========= script_repo_revision: 6d6f82046da48820aca4a866fa0184234783ed7d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-17 15:54:48.965311) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-17 15:54:48.965831) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-17 15:54:48.990086) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2016-08-17 15:54:48.990594) ========= /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.l39pVwyRBI/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.hgrEww7IpI/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 15:54:49 INFO - MultiFileLogger online at 20160817 15:54:49 in /builds/slave/test 15:54:49 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 15:54:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:54:49 INFO - {'append_to_log': False, 15:54:49 INFO - 'base_work_dir': '/builds/slave/test', 15:54:49 INFO - 'blob_upload_branch': 'Autoland', 15:54:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:54:49 INFO - 'branch': 'Autoland', 15:54:49 INFO - 'buildbot_json_path': 'buildprops.json', 15:54:49 INFO - 'config_files': ('talos/mac_config.py',), 15:54:49 INFO - 'default_actions': ('clobber', 15:54:49 INFO - 'read-buildbot-config', 15:54:49 INFO - 'download-and-extract', 15:54:49 INFO - 'populate-webroot', 15:54:49 INFO - 'create-virtualenv', 15:54:49 INFO - 'install', 15:54:49 INFO - 'run-tests'), 15:54:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:54:49 INFO - 'download_minidump_stackwalk': True, 15:54:49 INFO - 'download_symbols': 'ondemand', 15:54:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:54:49 INFO - 'tooltool.py': '/tools/tooltool.py', 15:54:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:54:49 INFO - '/tools/misc-python/virtualenv.py')}, 15:54:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:54:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:54:49 INFO - 'installer_path': 'installer.exe', 15:54:49 INFO - 'log_level': 'info', 15:54:49 INFO - 'log_name': 'talos', 15:54:49 INFO - 'log_to_console': True, 15:54:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:54:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:54:49 INFO - 'opt_config_files': (), 15:54:49 INFO - 'pip_index': False, 15:54:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:54:49 INFO - 'cmd': ('bash', 15:54:49 INFO - '-c', 15:54:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:54:49 INFO - 'enabled': True, 15:54:49 INFO - 'halt_on_failure': False, 15:54:49 INFO - 'name': 'check_screen_resolution'},), 15:54:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:54:49 INFO - 'cmd': ('bash', 15:54:49 INFO - '-c', 15:54:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:54:49 INFO - 'enabled': True, 15:54:49 INFO - 'halt_on_failure': False, 15:54:49 INFO - 'name': 'check_screen_resolution'},), 15:54:49 INFO - 'run_cmd_checks_enabled': True, 15:54:49 INFO - 'sps_profile': False, 15:54:49 INFO - 'sps_profile_interval': 0, 15:54:49 INFO - 'suite': 'dromaeojs', 15:54:49 INFO - 'system_bits': '32', 15:54:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:54:49 INFO - 'title': 't-yosemite-r7-0258', 15:54:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:54:49 INFO - 'use_talos_json': True, 15:54:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:54:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:54:49 INFO - 'work_dir': 'build'} 15:54:49 INFO - [mozharness: 2016-08-17 22:54:49.198507Z] Running clobber step. 15:54:49 INFO - Running pre-action listener: _resource_record_pre_action 15:54:49 INFO - Running main action method: clobber 15:54:49 INFO - rmtree: /builds/slave/test/build 15:54:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:54:50 INFO - Running post-action listener: _resource_record_post_action 15:54:50 INFO - [mozharness: 2016-08-17 22:54:50.252795Z] Finished clobber step (success) 15:54:50 INFO - [mozharness: 2016-08-17 22:54:50.252924Z] Running read-buildbot-config step. 15:54:50 INFO - Running pre-action listener: _resource_record_pre_action 15:54:50 INFO - Running main action method: read_buildbot_config 15:54:50 INFO - Using buildbot properties: 15:54:50 INFO - { 15:54:50 INFO - "project": "", 15:54:50 INFO - "product": "firefox", 15:54:50 INFO - "script_repo_revision": "production", 15:54:50 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 15:54:50 INFO - "repository": "", 15:54:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 15:54:50 INFO - "buildid": "20160817125015", 15:54:50 INFO - "pgo_build": "False", 15:54:50 INFO - "basedir": "/builds/slave/test", 15:54:50 INFO - "buildnumber": 131, 15:54:50 INFO - "slavename": "t-yosemite-r7-0258", 15:54:50 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 15:54:50 INFO - "platform": "yosemite_r7", 15:54:50 INFO - "scheduler": "tests-autoland-macosx64-talos-7-3600", 15:54:50 INFO - "branch": "autoland", 15:54:50 INFO - "repo_path": "integration/autoland", 15:54:50 INFO - "revision": "6d6f82046da48820aca4a866fa0184234783ed7d", 15:54:50 INFO - "stage_platform": "macosx64", 15:54:50 INFO - "builduid": "3fc1c3fbc4134d86aeedd25d747da207", 15:54:50 INFO - "slavebuilddir": "test" 15:54:50 INFO - } 15:54:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/CfxpWI7-SOiEtAB8H_6i1w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 15:54:50 INFO - Running post-action listener: _resource_record_post_action 15:54:50 INFO - [mozharness: 2016-08-17 22:54:50.256262Z] Finished read-buildbot-config step (success) 15:54:50 INFO - [mozharness: 2016-08-17 22:54:50.256367Z] Running download-and-extract step. 15:54:50 INFO - Running pre-action listener: _resource_record_pre_action 15:54:50 INFO - Running main action method: download_and_extract 15:54:50 INFO - mkdir: /builds/slave/test/build/tests 15:54: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')]} 15:54:50 INFO - trying https://queue.taskcluster.net/v1/task/CfxpWI7-SOiEtAB8H_6i1w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 15:54:50 INFO - Downloading https://queue.taskcluster.net/v1/task/CfxpWI7-SOiEtAB8H_6i1w/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 15:54:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CfxpWI7-SOiEtAB8H_6i1w/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 15:54:51 INFO - Downloaded 1199 bytes. 15:54:51 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 15:54:51 INFO - Using the following test package requirements: 15:54:51 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 15:54:51 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:54:51 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 15:54:51 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:54:51 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 15:54:51 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:54:51 INFO - u'jsshell-mac.zip'], 15:54:51 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:54:51 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 15:54:51 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 15:54:51 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:54:51 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 15:54:51 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:54:51 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 15:54:51 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:54:51 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 15:54:51 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:54:51 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 15:54:51 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:54:51 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')]} 15:54:51 INFO - trying https://queue.taskcluster.net/v1/task/CfxpWI7-SOiEtAB8H_6i1w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 15:54:51 INFO - Downloading https://queue.taskcluster.net/v1/task/CfxpWI7-SOiEtAB8H_6i1w/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 15:54:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CfxpWI7-SOiEtAB8H_6i1w/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 15:54:52 INFO - Downloaded 16338683 bytes. 15:54:52 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 15:54:59 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 15:54: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')]} 15:54:59 INFO - trying https://queue.taskcluster.net/v1/task/CfxpWI7-SOiEtAB8H_6i1w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 15:54:59 INFO - Downloading https://queue.taskcluster.net/v1/task/CfxpWI7-SOiEtAB8H_6i1w/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 15:54:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CfxpWI7-SOiEtAB8H_6i1w/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 15:55:00 INFO - Downloaded 16338683 bytes. 15:55:00 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 15:55:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:06 INFO - trying https://queue.taskcluster.net/v1/task/CfxpWI7-SOiEtAB8H_6i1w/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 15:55:06 INFO - Downloading https://queue.taskcluster.net/v1/task/CfxpWI7-SOiEtAB8H_6i1w/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 15:55:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CfxpWI7-SOiEtAB8H_6i1w/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 15:55:07 INFO - Downloaded 14413719 bytes. 15:55:07 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 15:55:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:08 INFO - trying https://queue.taskcluster.net/v1/task/CfxpWI7-SOiEtAB8H_6i1w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:55:08 INFO - Downloading https://queue.taskcluster.net/v1/task/CfxpWI7-SOiEtAB8H_6i1w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 15:55:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CfxpWI7-SOiEtAB8H_6i1w/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 15:55:18 INFO - Downloaded 111304373 bytes. 15:55:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CfxpWI7-SOiEtAB8H_6i1w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:55:18 INFO - mkdir: /builds/slave/test/properties 15:55:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:55:18 INFO - Writing to file /builds/slave/test/properties/build_url 15:55:18 INFO - Contents: 15:55:18 INFO - build_url:https://queue.taskcluster.net/v1/task/CfxpWI7-SOiEtAB8H_6i1w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:55:20 INFO - Running post-action listener: _resource_record_post_action 15:55:20 INFO - Running post-action listener: set_extra_try_arguments 15:55:20 INFO - [mozharness: 2016-08-17 22:55:20.012579Z] Finished download-and-extract step (success) 15:55:20 INFO - [mozharness: 2016-08-17 22:55:20.012736Z] Running populate-webroot step. 15:55:20 INFO - Running pre-action listener: _resource_record_pre_action 15:55:20 INFO - Running main action method: populate_webroot 15:55:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:55:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:55:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:55:20 INFO - '--tppagecycles', 15:55:20 INFO - '7'], 15:55:20 INFO - 'tests': ['tsvgm']}}, 15:55:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:55:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:55:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:55:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:55:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:55:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:55:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:20 INFO - 'talos_options': ['--disable-e10s'], 15:55:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:55:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:55:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:20 INFO - 'talos_options': ['--disable-e10s'], 15:55:20 INFO - 'tests': ['damp', 'tps']}, 15:55:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:20 INFO - 'tests': ['damp', 'tps']}, 15:55:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:55:20 INFO - 'tests': ['dromaeo_dom']}, 15:55:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:55:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:55:20 INFO - 'tests': ['basic_compositor_video']}, 15:55:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:55:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:55:20 INFO - 'tests': ['a11yr', 15:55:20 INFO - 'ts_paint', 15:55:20 INFO - 'tpaint', 15:55:20 INFO - 'sessionrestore', 15:55:20 INFO - 'sessionrestore_no_auto_restore', 15:55:20 INFO - 'tabpaint']}, 15:55:20 INFO - 'other-e10s': {'tests': ['a11yr', 15:55:20 INFO - 'ts_paint', 15:55:20 INFO - 'tpaint', 15:55:20 INFO - 'sessionrestore', 15:55:20 INFO - 'sessionrestore_no_auto_restore', 15:55:20 INFO - 'tabpaint']}, 15:55:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:55:20 INFO - 'ts_paint', 15:55:20 INFO - 'tpaint', 15:55:20 INFO - 'sessionrestore', 15:55:20 INFO - 'sessionrestore_no_auto_restore', 15:55:20 INFO - 'tabpaint']}, 15:55:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:55:20 INFO - 'ts_paint', 15:55:20 INFO - 'tpaint', 15:55:20 INFO - 'sessionrestore', 15:55:20 INFO - 'sessionrestore_no_auto_restore', 15:55:20 INFO - 'tabpaint']}, 15:55:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:55:20 INFO - 'tests': ['a11yr', 15:55:20 INFO - 'ts_paint', 15:55:20 INFO - 'tpaint', 15:55:20 INFO - 'sessionrestore', 15:55:20 INFO - 'sessionrestore_no_auto_restore', 15:55:20 INFO - 'tabpaint']}, 15:55:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:55:20 INFO - 'tests': ['a11yr', 15:55:20 INFO - 'ts_paint', 15:55:20 INFO - 'tpaint', 15:55:20 INFO - 'sessionrestore', 15:55:20 INFO - 'sessionrestore_no_auto_restore', 15:55:20 INFO - 'tabpaint']}, 15:55:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:55:20 INFO - 'tests': ['tsvgx', 15:55:20 INFO - 'tsvgr_opacity', 15:55:20 INFO - 'tart', 15:55:20 INFO - 'tscrollx', 15:55:20 INFO - 'cart']}, 15:55:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:55:20 INFO - 'tsvgr_opacity', 15:55:20 INFO - 'tart', 15:55:20 INFO - 'tscrollx', 15:55:20 INFO - 'cart']}, 15:55:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:20 INFO - 'talos_options': ['--disable-e10s'], 15:55:20 INFO - 'tests': ['tp5o']}, 15:55:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:20 INFO - 'tests': ['tp5o']}, 15:55:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:20 INFO - 'talos_options': ['--disable-e10s', 15:55:20 INFO - '--xperf_path', 15:55:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:55:20 INFO - 'tests': ['tp5n']}, 15:55:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:20 INFO - 'talos_options': ['--xperf_path', 15:55:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:55:20 INFO - 'tests': ['tp5n']}}, 15:55:20 INFO - 'talos.zip': {'path': '', 15:55:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:55:20 INFO - Running post-action listener: _resource_record_post_action 15:55:20 INFO - [mozharness: 2016-08-17 22:55:20.031598Z] Finished populate-webroot step (success) 15:55:20 INFO - [mozharness: 2016-08-17 22:55:20.031728Z] Running create-virtualenv step. 15:55:20 INFO - Running pre-action listener: _resource_record_pre_action 15:55:20 INFO - Running main action method: create_virtualenv 15:55:20 INFO - Creating virtualenv /builds/slave/test/build/venv 15:55:20 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 15:55:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:55:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:55:20 INFO - Using real prefix '/tools/python27' 15:55:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:55:20 INFO - Installing distribute.............................................................................................................................................................................................done. 15:55:23 INFO - Installing pip.................done. 15:55:23 INFO - Return code: 0 15:55:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:55:23 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')]} 15:55:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:23 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')]} 15:55:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:23 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 0x109cbca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d51618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff049c95590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c20d30>, '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 0x109985db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c18b20>, '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.l39pVwyRBI/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.hgrEww7IpI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 15:55:23 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 15:55:23 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 15:55:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l39pVwyRBI/Render', 15:55:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:23 INFO - 'HOME': '/Users/cltbld', 15:55:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:23 INFO - 'LOGNAME': 'cltbld', 15:55:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:23 INFO - 'MOZ_NO_REMOTE': '1', 15:55:23 INFO - 'NO_EM_RESTART': '1', 15:55:23 INFO - 'PAGER': '/bin/cat', 15:55:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:23 INFO - 'PWD': '/builds/slave/test', 15:55:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:23 INFO - 'SHELL': '/bin/bash', 15:55:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hgrEww7IpI/Listeners', 15:55:23 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 15:55:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:23 INFO - 'USER': 'cltbld', 15:55:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:23 INFO - 'XPC_FLAGS': '0x0', 15:55:23 INFO - 'XPC_SERVICE_NAME': '0', 15:55:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:23 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 15:55:23 INFO - Cleaning up... 15:55:23 INFO - Return code: 0 15:55:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:55:23 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')]} 15:55:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:23 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')]} 15:55:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:23 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 0x109cbca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d51618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff049c95590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c20d30>, '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 0x109985db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c18b20>, '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.l39pVwyRBI/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.hgrEww7IpI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 15:55:23 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 15:55:23 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 15:55:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l39pVwyRBI/Render', 15:55:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:23 INFO - 'HOME': '/Users/cltbld', 15:55:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:23 INFO - 'LOGNAME': 'cltbld', 15:55:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:23 INFO - 'MOZ_NO_REMOTE': '1', 15:55:23 INFO - 'NO_EM_RESTART': '1', 15:55:23 INFO - 'PAGER': '/bin/cat', 15:55:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:23 INFO - 'PWD': '/builds/slave/test', 15:55:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:23 INFO - 'SHELL': '/bin/bash', 15:55:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hgrEww7IpI/Listeners', 15:55:23 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 15:55:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:23 INFO - 'USER': 'cltbld', 15:55:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:23 INFO - 'XPC_FLAGS': '0x0', 15:55:23 INFO - 'XPC_SERVICE_NAME': '0', 15:55:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:24 INFO - Downloading/unpacking psutil>=3.1.1 15:55:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:55:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:55:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:55:28 INFO - Installing collected packages: psutil 15:55:28 INFO - Running setup.py install for psutil 15:55:28 INFO - building 'psutil._psutil_osx' extension 15:55:28 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 15:55:28 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 15:55:28 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 15:55:28 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 15:55:28 INFO - building 'psutil._psutil_posix' extension 15:55:28 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 15:55:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:55:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:55:28 INFO - ^ 15:55:28 INFO - 1 warning generated. 15:55:28 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 15:55:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:55:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:55:28 INFO - Successfully installed psutil 15:55:28 INFO - Cleaning up... 15:55:28 INFO - Return code: 0 15:55:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:55:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109cbca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d51618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff049c95590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c20d30>, '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 0x109985db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c18b20>, '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.l39pVwyRBI/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.hgrEww7IpI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 15:55:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:55:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:55:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l39pVwyRBI/Render', 15:55:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:28 INFO - 'HOME': '/Users/cltbld', 15:55:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:28 INFO - 'LOGNAME': 'cltbld', 15:55:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:28 INFO - 'MOZ_NO_REMOTE': '1', 15:55:28 INFO - 'NO_EM_RESTART': '1', 15:55:28 INFO - 'PAGER': '/bin/cat', 15:55:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:28 INFO - 'PWD': '/builds/slave/test', 15:55:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:28 INFO - 'SHELL': '/bin/bash', 15:55:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hgrEww7IpI/Listeners', 15:55:28 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 15:55:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:28 INFO - 'USER': 'cltbld', 15:55:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:28 INFO - 'XPC_FLAGS': '0x0', 15:55:28 INFO - 'XPC_SERVICE_NAME': '0', 15:55:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:55:29 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 15:55:29 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 15:55:29 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 15:55:29 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 15:55:29 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 15:55:29 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 15:55:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:55:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:55:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:55:32 INFO - Installing collected packages: mozsystemmonitor 15:55:32 INFO - Running setup.py install for mozsystemmonitor 15:55:32 INFO - Successfully installed mozsystemmonitor 15:55:32 INFO - Cleaning up... 15:55:32 INFO - Return code: 0 15:55:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:55:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109cbca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d51618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff049c95590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c20d30>, '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 0x109985db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c18b20>, '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.l39pVwyRBI/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.hgrEww7IpI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 15:55:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:55:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:55:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l39pVwyRBI/Render', 15:55:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:32 INFO - 'HOME': '/Users/cltbld', 15:55:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:32 INFO - 'LOGNAME': 'cltbld', 15:55:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:32 INFO - 'MOZ_NO_REMOTE': '1', 15:55:32 INFO - 'NO_EM_RESTART': '1', 15:55:32 INFO - 'PAGER': '/bin/cat', 15:55:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:32 INFO - 'PWD': '/builds/slave/test', 15:55:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:32 INFO - 'SHELL': '/bin/bash', 15:55:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hgrEww7IpI/Listeners', 15:55:32 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 15:55:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:32 INFO - 'USER': 'cltbld', 15:55:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:32 INFO - 'XPC_FLAGS': '0x0', 15:55:32 INFO - 'XPC_SERVICE_NAME': '0', 15:55:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:32 INFO - Downloading/unpacking jsonschema==2.5.1 15:55:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:55:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:55:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:35 INFO - Downloading functools32-3.2.3-2.tar.gz 15:55:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:55:35 INFO - warning: no files found matching '*.txt' 15:55:35 INFO - no previously-included directories found matching 'build' 15:55:35 INFO - no previously-included directories found matching 'dist' 15:55:35 INFO - no previously-included directories found matching '.git*' 15:55:35 INFO - Installing collected packages: jsonschema, functools32 15:55:35 INFO - Running setup.py install for functools32 15:55:35 INFO - warning: no files found matching '*.txt' 15:55:35 INFO - no previously-included directories found matching 'build' 15:55:35 INFO - no previously-included directories found matching 'dist' 15:55:35 INFO - no previously-included directories found matching '.git*' 15:55:35 INFO - Successfully installed jsonschema functools32 15:55:35 INFO - Cleaning up... 15:55:35 INFO - Return code: 0 15:55:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:55:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:35 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 0x109cbca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d51618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff049c95590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c20d30>, '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 0x109985db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c18b20>, '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.l39pVwyRBI/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.hgrEww7IpI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 15:55:35 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 15:55:35 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 15:55:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l39pVwyRBI/Render', 15:55:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:35 INFO - 'HOME': '/Users/cltbld', 15:55:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:35 INFO - 'LOGNAME': 'cltbld', 15:55:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:35 INFO - 'MOZ_NO_REMOTE': '1', 15:55:35 INFO - 'NO_EM_RESTART': '1', 15:55:35 INFO - 'PAGER': '/bin/cat', 15:55:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:35 INFO - 'PWD': '/builds/slave/test', 15:55:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:35 INFO - 'SHELL': '/bin/bash', 15:55:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hgrEww7IpI/Listeners', 15:55:35 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 15:55:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:35 INFO - 'USER': 'cltbld', 15:55:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:35 INFO - 'XPC_FLAGS': '0x0', 15:55:35 INFO - 'XPC_SERVICE_NAME': '0', 15:55:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:55:36 INFO - Cleaning up... 15:55:36 INFO - Return code: 0 15:55:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:55:36 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')]} 15:55:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:36 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')]} 15:55:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:36 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 0x109cbca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d51618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff049c95590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c20d30>, '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 0x109985db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c18b20>, '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.l39pVwyRBI/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.hgrEww7IpI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 15:55:36 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 15:55:36 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 15:55:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l39pVwyRBI/Render', 15:55:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:36 INFO - 'HOME': '/Users/cltbld', 15:55:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:36 INFO - 'LOGNAME': 'cltbld', 15:55:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:36 INFO - 'MOZ_NO_REMOTE': '1', 15:55:36 INFO - 'NO_EM_RESTART': '1', 15:55:36 INFO - 'PAGER': '/bin/cat', 15:55:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:36 INFO - 'PWD': '/builds/slave/test', 15:55:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:36 INFO - 'SHELL': '/bin/bash', 15:55:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hgrEww7IpI/Listeners', 15:55:36 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 15:55:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:36 INFO - 'USER': 'cltbld', 15:55:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:36 INFO - 'XPC_FLAGS': '0x0', 15:55:36 INFO - 'XPC_SERVICE_NAME': '0', 15:55:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:36 INFO - Downloading/unpacking blobuploader==1.2.4 15:55:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:38 INFO - Downloading blobuploader-1.2.4.tar.gz 15:55:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:55:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:55:38 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 15:55:38 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 15:55:38 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 15:55:38 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 15:55:38 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 15:55:38 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 15:55:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:55:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:55:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:39 INFO - Downloading docopt-0.6.1.tar.gz 15:55:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:55:40 INFO - Installing collected packages: blobuploader, requests, docopt 15:55:40 INFO - Running setup.py install for blobuploader 15:55:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:55:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:55:40 INFO - Running setup.py install for requests 15:55:40 INFO - Running setup.py install for docopt 15:55:40 INFO - Successfully installed blobuploader requests docopt 15:55:40 INFO - Cleaning up... 15:55:40 INFO - Return code: 0 15:55:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:55:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:40 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 0x109cbca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d51618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff049c95590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c20d30>, '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 0x109985db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c18b20>, '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.l39pVwyRBI/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.hgrEww7IpI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 15:55:40 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 15:55:40 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 15:55:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l39pVwyRBI/Render', 15:55:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:40 INFO - 'HOME': '/Users/cltbld', 15:55:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:40 INFO - 'LOGNAME': 'cltbld', 15:55:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:40 INFO - 'MOZ_NO_REMOTE': '1', 15:55:40 INFO - 'NO_EM_RESTART': '1', 15:55:40 INFO - 'PAGER': '/bin/cat', 15:55:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:40 INFO - 'PWD': '/builds/slave/test', 15:55:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:40 INFO - 'SHELL': '/bin/bash', 15:55:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hgrEww7IpI/Listeners', 15:55:40 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 15:55:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:40 INFO - 'USER': 'cltbld', 15:55:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:40 INFO - 'XPC_FLAGS': '0x0', 15:55:40 INFO - 'XPC_SERVICE_NAME': '0', 15:55:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:55:41 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-vzeGSg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:55:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:55:41 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-bl86vw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:55:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:55:41 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-eujGhS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:55:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:55:41 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-g_9ZmU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:55:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:55:41 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-VOGwqm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:55:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:55:41 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-UOfQTw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:55:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:55:41 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-sIKr6o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:55:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:55:41 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-_o7k1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:55:42 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-ld3qHi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:55:42 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-CZ_8nn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:55:42 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-kBB2nv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:55:42 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-cRrub1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:55:42 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-F9FqNJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:55:42 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-4wU78e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:55:42 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-PeNoR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:55:42 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-7LiQF8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:55:42 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-6NdHS7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:55:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:55:42 INFO - Running setup.py install for manifestparser 15:55:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:55:43 INFO - Running setup.py install for mozcrash 15:55:43 INFO - Running setup.py install for mozdebug 15:55:43 INFO - Running setup.py install for mozdevice 15:55:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:55:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:55:43 INFO - Running setup.py install for mozfile 15:55:43 INFO - Running setup.py install for mozhttpd 15:55:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:55:43 INFO - Running setup.py install for mozinfo 15:55:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:55:43 INFO - Running setup.py install for mozInstall 15:55:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:55:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:55:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:55:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:55:44 INFO - Running setup.py install for mozleak 15:55:44 INFO - Running setup.py install for mozlog 15:55:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:55:44 INFO - Running setup.py install for moznetwork 15:55:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:55:44 INFO - Running setup.py install for mozprocess 15:55:44 INFO - Running setup.py install for mozprofile 15:55:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:55:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:55:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:55:44 INFO - Running setup.py install for mozrunner 15:55:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:55:45 INFO - Running setup.py install for mozscreenshot 15:55:45 INFO - Running setup.py install for moztest 15:55:45 INFO - Running setup.py install for mozversion 15:55:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:55:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:55:45 INFO - Cleaning up... 15:55:45 INFO - Return code: 0 15:55:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:55:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:45 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 0x109cbca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d51618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff049c95590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c20d30>, '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 0x109985db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c18b20>, '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.l39pVwyRBI/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.hgrEww7IpI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 15:55:45 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 15:55:45 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 15:55:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l39pVwyRBI/Render', 15:55:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:45 INFO - 'HOME': '/Users/cltbld', 15:55:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:45 INFO - 'LOGNAME': 'cltbld', 15:55:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:45 INFO - 'MOZ_NO_REMOTE': '1', 15:55:45 INFO - 'NO_EM_RESTART': '1', 15:55:45 INFO - 'PAGER': '/bin/cat', 15:55:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:45 INFO - 'PWD': '/builds/slave/test', 15:55:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:45 INFO - 'SHELL': '/bin/bash', 15:55:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hgrEww7IpI/Listeners', 15:55:45 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 15:55:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:45 INFO - 'USER': 'cltbld', 15:55:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:45 INFO - 'XPC_FLAGS': '0x0', 15:55:45 INFO - 'XPC_SERVICE_NAME': '0', 15:55:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:55:45 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-CxxtrL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:55:45 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)) 15:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:55:45 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-PoO6Zh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:55:46 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)) 15:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:55:46 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-eKuVoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:55:46 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)) 15:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:55:46 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-AAP_zf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:55:46 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)) 15:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:55:46 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-lLnbMw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:55:46 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)) 15:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:55:46 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-j5zClI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:55:46 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)) 15:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:55:46 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-GOi9PG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:55:46 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)) 15:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:55:46 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-yTVYEW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:55:46 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)) 15:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:55:46 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-5IFoo_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:55:46 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)) 15:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:55:46 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-nBPJ5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:55:46 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)) 15:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:55:46 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-mPL3Ta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:55:46 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)) 15:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:55:46 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-ydkk0x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:55:47 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)) 15:55:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:55:47 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-LoG8xB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:55:47 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)) 15:55:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:55:47 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-w7BQ1j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:55:47 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)) 15:55:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:55:47 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-b5QsyG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:55:47 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)) 15:55:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:55:47 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-AbSnK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:55:47 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)) 15:55:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:55:47 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-NbXEMh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:55:47 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)) 15:55:47 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)) 15:55:47 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)) 15:55:47 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)) 15:55:47 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)) 15:55:47 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)) 15:55:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:55:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:50 INFO - Downloading blessings-1.6.tar.gz 15:55:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:55:50 INFO - Installing collected packages: blessings 15:55:50 INFO - Running setup.py install for blessings 15:55:50 INFO - Successfully installed blessings 15:55:50 INFO - Cleaning up... 15:55:50 INFO - Return code: 0 15:55:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:55:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:55:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:55:50 INFO - Reading from file tmpfile_stdout 15:55:50 INFO - Current package versions: 15:55:50 INFO - blessings == 1.6 15:55:50 INFO - blobuploader == 1.2.4 15:55:50 INFO - docopt == 0.6.1 15:55:50 INFO - functools32 == 3.2.3-2 15:55:50 INFO - jsonschema == 2.5.1 15:55:50 INFO - manifestparser == 1.1 15:55:50 INFO - mozInstall == 1.12 15:55:50 INFO - mozcrash == 0.17 15:55:50 INFO - mozdebug == 0.1 15:55:50 INFO - mozdevice == 0.48 15:55:50 INFO - mozfile == 1.2 15:55:50 INFO - mozhttpd == 0.7 15:55:50 INFO - mozinfo == 0.9 15:55:50 INFO - mozleak == 0.1 15:55:50 INFO - mozlog == 3.3 15:55:50 INFO - moznetwork == 0.27 15:55:50 INFO - mozprocess == 0.23 15:55:50 INFO - mozprofile == 0.28 15:55:50 INFO - mozrunner == 6.12 15:55:50 INFO - mozscreenshot == 0.1 15:55:50 INFO - mozsystemmonitor == 0.3 15:55:50 INFO - moztest == 0.7 15:55:50 INFO - mozversion == 1.4 15:55:50 INFO - psutil == 3.1.1 15:55:50 INFO - requests == 1.2.3 15:55:50 INFO - wsgiref == 0.1.2 15:55:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:55: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')]} 15:55:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55: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')]} 15:55:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:50 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 0x109cbca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d51618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff049c95590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c20d30>, '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 0x109985db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c18b20>, '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.l39pVwyRBI/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.hgrEww7IpI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 15:55:50 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 15:55:50 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 15:55:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l39pVwyRBI/Render', 15:55:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:50 INFO - 'HOME': '/Users/cltbld', 15:55:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:50 INFO - 'LOGNAME': 'cltbld', 15:55:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:50 INFO - 'MOZ_NO_REMOTE': '1', 15:55:50 INFO - 'NO_EM_RESTART': '1', 15:55:50 INFO - 'PAGER': '/bin/cat', 15:55:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:50 INFO - 'PWD': '/builds/slave/test', 15:55:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:50 INFO - 'SHELL': '/bin/bash', 15:55:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hgrEww7IpI/Listeners', 15:55:50 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 15:55:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:50 INFO - 'USER': 'cltbld', 15:55:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:50 INFO - 'XPC_FLAGS': '0x0', 15:55:50 INFO - 'XPC_SERVICE_NAME': '0', 15:55:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:50 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)) 15:55:50 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)) 15:55:50 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)) 15:55:50 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)) 15:55:50 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)) 15:55:50 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)) 15:55:50 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)) 15:55:50 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)) 15:55:50 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)) 15:55:50 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)) 15:55:50 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)) 15:55:50 INFO - Cleaning up... 15:55:50 INFO - Return code: 0 15:55:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:55: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')]} 15:55:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55: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')]} 15:55:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109cbca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d51618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff049c95590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c20d30>, '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 0x109985db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c18b20>, '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.l39pVwyRBI/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.hgrEww7IpI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 15:55:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:55:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:55:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l39pVwyRBI/Render', 15:55:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:50 INFO - 'HOME': '/Users/cltbld', 15:55:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:50 INFO - 'LOGNAME': 'cltbld', 15:55:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:50 INFO - 'MOZ_NO_REMOTE': '1', 15:55:50 INFO - 'NO_EM_RESTART': '1', 15:55:50 INFO - 'PAGER': '/bin/cat', 15:55:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:50 INFO - 'PWD': '/builds/slave/test', 15:55:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:50 INFO - 'SHELL': '/bin/bash', 15:55:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hgrEww7IpI/Listeners', 15:55:50 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 15:55:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:50 INFO - 'USER': 'cltbld', 15:55:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:50 INFO - 'XPC_FLAGS': '0x0', 15:55:50 INFO - 'XPC_SERVICE_NAME': '0', 15:55:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:55:51 INFO - Cleaning up... 15:55:51 INFO - Return code: 0 15:55:51 INFO - Running post-action listener: _resource_record_post_action 15:55:51 INFO - Running post-action listener: _start_resource_monitoring 15:55:51 INFO - Starting resource monitoring. 15:55:51 INFO - [mozharness: 2016-08-17 22:55:51.246974Z] Finished create-virtualenv step (success) 15:55:51 INFO - [mozharness: 2016-08-17 22:55:51.247984Z] Running install step. 15:55:51 INFO - Running pre-action listener: _resource_record_pre_action 15:55:51 INFO - Running main action method: install 15:55:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:55:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:55:51 INFO - Reading from file tmpfile_stdout 15:55:51 INFO - Detecting whether we're running mozinstall >=1.0... 15:55:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:55:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:55:51 INFO - Reading from file tmpfile_stdout 15:55:51 INFO - Output received: 15:55:51 INFO - Usage: mozinstall [options] installer 15:55:51 INFO - Options: 15:55:51 INFO - -h, --help show this help message and exit 15:55:51 INFO - -d DEST, --destination=DEST 15:55:51 INFO - Directory to install application into. [default: 15:55:51 INFO - "/builds/slave/test"] 15:55:51 INFO - --app=APP Application being installed. [default: firefox] 15:55:51 INFO - mkdir: /builds/slave/test/build/application 15:55:51 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'] 15:55:51 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 15:56:25 INFO - Reading from file tmpfile_stdout 15:56:25 INFO - Output received: 15:56:25 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:56:25 INFO - Running post-action listener: _resource_record_post_action 15:56:25 INFO - [mozharness: 2016-08-17 22:56:25.406278Z] Finished install step (success) 15:56:25 INFO - [mozharness: 2016-08-17 22:56:25.406426Z] Running run-tests step. 15:56:25 INFO - Running pre-action listener: _resource_record_pre_action 15:56:25 INFO - Running main action method: run_tests 15:56:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:56:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:56:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:56:25 INFO - 2016-08-17 15:56:25.421 screenresolution[2102:13155] starting screenresolution argv=screenresolution get 15:56:25 INFO - 2016-08-17 15:56:25.423 screenresolution[2102:13155] Display 0: 1600x1200x32@60 15:56:25 INFO - 2016-08-17 15:56:25.429 screenresolution[2103:13157] starting screenresolution argv=screenresolution list 15:56:25 INFO - Available Modes on Display 0 15:56:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:56:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:56:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:56:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:56:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:56:25 INFO - Intel Iris: 15:56:25 INFO - Chipset Model: Intel Iris 15:56:25 INFO - Type: GPU 15:56:25 INFO - Bus: Built-In 15:56:25 INFO - VRAM (Dynamic, Max): 1536 MB 15:56:25 INFO - Vendor: Intel (0x8086) 15:56:25 INFO - Device ID: 0x0a2e 15:56:25 INFO - Revision ID: 0x0009 15:56:25 INFO - Displays: 15:56:25 INFO - Display: 15:56:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:56:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:56:25 INFO - Display Serial Number: 1600x1200 60 15:56:25 INFO - Main Display: Yes 15:56:25 INFO - Mirror: Off 15:56:25 INFO - Online: Yes 15:56:25 INFO - Rotation: Supported 15:56:25 INFO - Return code: 0 15:56:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:56:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:56:25 INFO - Python 2.7.3 15:56:25 INFO - Return code: 0 15:56:25 INFO - grabbing minidump binary from tooltool 15:56:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:56:25 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 0x109c20d30>, '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 0x109985db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c18b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:56:25 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 15:56:25 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 15:56:25 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 15:56:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:56:25 INFO - Return code: 0 15:56:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:56:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:56:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:56:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:56:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:56:25 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/CfxpWI7-SOiEtAB8H_6i1w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0258', '--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 15:56:25 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/CfxpWI7-SOiEtAB8H_6i1w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0258 --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 15:56:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l39pVwyRBI/Render', 15:56:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:56:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:56:25 INFO - 'HOME': '/Users/cltbld', 15:56:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:56:25 INFO - 'LOGNAME': 'cltbld', 15:56:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:56:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:56:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:25 INFO - 'MOZ_NO_REMOTE': '1', 15:56:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:56:25 INFO - 'NO_EM_RESTART': '1', 15:56:25 INFO - 'PAGER': '/bin/cat', 15:56:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:56:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:25 INFO - 'PWD': '/builds/slave/test', 15:56:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:56:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:56:25 INFO - 'SHELL': '/bin/bash', 15:56:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hgrEww7IpI/Listeners', 15:56:25 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 15:56:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:56:25 INFO - 'USER': 'cltbld', 15:56:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:56:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:56:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:25 INFO - 'XPC_FLAGS': '0x0', 15:56:25 INFO - 'XPC_SERVICE_NAME': '0', 15:56:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:56:25 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/CfxpWI7-SOiEtAB8H_6i1w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0258', '--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 15:56:26 INFO - mozversion application_buildid: 20160817125015 15:56:26 INFO - mozversion application_changeset: 6d6f82046da48820aca4a866fa0184234783ed7d 15:56:26 INFO - mozversion application_display_name: Nightly 15:56:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:56:26 INFO - mozversion application_name: Firefox 15:56:26 INFO - mozversion application_remotingname: firefox 15:56:26 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 15:56:26 INFO - mozversion application_vendor: Mozilla 15:56:26 INFO - mozversion application_version: 51.0a1 15:56:26 INFO - mozversion platform_buildid: 20160817125015 15:56:26 INFO - mozversion platform_changeset: 6d6f82046da48820aca4a866fa0184234783ed7d 15:56:26 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 15:56:26 INFO - mozversion platform_version: 51.0a1 15:56:26 INFO - using testdate: 1471474586 15:56:26 INFO - actual date: 1471474586 15:56:26 INFO - starting webserver on 'localhost:49572' 15:56:26 INFO - SUITE-START | Running 2 tests 15:56:26 INFO - TEST-START | dromaeo_css 15:56:26 INFO - operating with platform_type : mac_ 15:56:26 INFO - Initialising browser for dromaeo_css test... 15:56:26 INFO - TEST-INFO | started process 2113 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmpySnEb1/profile http://localhost:49572/getInfo.html) 15:56:28 INFO - PROCESS | 2113 | __metrics Screen width/height:1600/1200 15:56:28 INFO - PROCESS | 2113 | colorDepth:24 15:56:28 INFO - PROCESS | 2113 | Browser inner width/height: 1024/689 15:56:28 INFO - PROCESS | 2113 | __metrics 15:56:28 INFO - PROCESS | 2113 | 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" 15:56:28 INFO - PROCESS | 2113 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:56:29 INFO - TEST-INFO | 2113: exit 0 15:56:29 INFO - Browser initialized. 15:56:29 INFO - Running cycle 1/1 for dromaeo_css test... 15:56:29 INFO - TEST-INFO | started process 2115 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmpySnEb1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:56:41 INFO - PROCESS | 2115 | RSS: Main: 228261888 15:56:41 INFO - PROCESS | 2115 | 15:57:33 INFO - PROCESS | 2115 | 1471474653293 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] 15:59:45 INFO - PROCESS | 2115 | Cycle 1(1): loaded http://localhost:49572/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49572/tests/dromaeo/cssquery-ext.html) 15:59:46 INFO - PROCESS | 2115 | RSS: Main: 344858624 15:59:46 INFO - PROCESS | 2115 | 16:02:49 INFO - PROCESS | 2115 | Cycle 1(1): loaded http://localhost:49572/tests/dromaeo/cssquery-ext.html (next: http://localhost:49572/tests/dromaeo/cssquery-jquery.html) 16:02:50 INFO - PROCESS | 2115 | RSS: Main: 370077696 16:02:50 INFO - PROCESS | 2115 | 16:05:52 INFO - PROCESS | 2115 | Cycle 1(1): loaded http://localhost:49572/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49572/tests/dromaeo/cssquery-mootools.html) 16:05:53 INFO - PROCESS | 2115 | RSS: Main: 341954560 16:05:53 INFO - PROCESS | 2115 | 16:08:55 INFO - PROCESS | 2115 | Cycle 1(1): loaded http://localhost:49572/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49572/tests/dromaeo/cssquery-prototype.html) 16:08:56 INFO - PROCESS | 2115 | RSS: Main: 344186880 16:08:56 INFO - PROCESS | 2115 | 16:11:59 INFO - PROCESS | 2115 | Cycle 1(1): loaded http://localhost:49572/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49572/tests/dromaeo/cssquery-yui.html) 16:12:00 INFO - PROCESS | 2115 | RSS: Main: 328761344 16:12:00 INFO - PROCESS | 2115 | 16:15:03 INFO - PROCESS | 2115 | Cycle 1(1): loaded http://localhost:49572/tests/dromaeo/cssquery-yui.html (next: http://localhost:49572/tests/dromaeo/cssquery-dojo.html) 16:15:03 INFO - PROCESS | 2115 | RSS: Main: 377405440 16:15:03 INFO - PROCESS | 2115 | 16:15:03 INFO - PROCESS | 2115 | __start_tp_report 16:15:03 INFO - PROCESS | 2115 | _x_x_mozilla_page_load 16:15:03 INFO - PROCESS | 2115 | _x_x_mozilla_page_load_details 16:15:03 INFO - PROCESS | 2115 | |i|pagename|runs| 16:15:03 INFO - PROCESS | 2115 | |0;dojo.html;3264.983675081625;3281.064896504495;3290.819004954728;3300.1914530939925;3312.1554003729048;5619.241402410673;5653.406392328803;5722.971385143079;5766.394528574502;5791.652500849947;5669.546436285098;5754.798582049629;5810.738516766745;5868.853278668035;5876.353601103886;5160.483951604847;5230.189320655304;5236.266922630834;5263.210518422248;5304.840854774363;5295.0468915595175;5313.1764576490705;5319.867003324909;5331.280277162572;5339.652922560041;5162.535371816517;5213.087709650834;5216.426193118754;5238.4499627539135;5270.736463176826;5243.161094224905;5244.790208391698;5248.370195576517;5259.631825772198;5265.578753699733;5174.068667639824;5193.506616871391;5195.350581177353;5208.270842082112;5249.632525723201;4729.456112547066;5616.9157462638095;5653.236813030229;5806.970965145189;5809.477147056743;6170.413810687977;6263.060540918853;6267.780627678009;6268.373162683741;6270.46701030413;6111.92601248044;6194.411530904602;6331.2085989251345;6331.96834015827;6383.361663833626;5781.971090144522;5836.883262334814;5841.620294680833;5881.3530511643685;5900.675462849585;6357.332480089616;6365;6365.49076073913;6366.172397588284;6372.968135159294;6018.939810601925;6073.392660733891;6201.503879689614;6213.03697926822;6214.751409943552;6210.472109870621;6257.217847769029;6307.274663413767;6322.589031712924;6340.461060809791;6200.844978875564;6204.689765511706;6214.720337584729;6220.844478887973;6222;6098.674287528808;6206.317305096436;6242.843928901723;6245.469135123474;6270.843228919222;4554.863354099382;4918.508149185053;4936.284238785428;4939.135651261015;4940.061388336205;4406.383106365111;4423.469183697976;4444.1111777643955;4448.065906159743;4515.88710282239;5703.8859222816145;5730.7421166047625;5734.741936612862;5743.827685169452;5795.594308398456;6145.692715364214;6251.249850018027;6274.874502509924;6330.525210609223;6341.936580634227;5711.7429715662065;5759.884802303931;5761.971190144022;5762.855928601734;5802.1296805479515;5430.511253987082;5433.456654334614;5435.755391007414;5450.182472629058;5454.481824226692;6231.594946328474;6255.655938923403;6326.145970293951;6327.367263273636;6367.07677386767;4893.73084480357;6314.62112273265;6339.809805705836;6342.270638876588;6347.492200624031;6245.094461303176;6280.340564240689;6323.873522529616;6328.6519241442165;6328.9050664240995;6036.154938308729;6120.510359171345;6217.720202590985;6222.688865556614;6243.219597550306;6063.393660633901;6133.089845874549;6143.112737013596;6197.225346831646;6223.626582404889;6067.089936509559;6081.391860813883;6087.086936959491;6088.8782224356155;6097.115918191926;6015.037593984942;6057.1519987200945;6063.6664983426335;6063.757449702139;6069.787557435468;5735.8566035849435;5864.618799778001;5870.471657550841;5893.086571581412;5932.851678708067;6132.702084519407;6148.692565371624;6149.0161574149715;6157.230346206724;6163.8150855474405;5703.515201207943;5810.883782324415;5931.6441013539325;5954;5991.910121348096 16:15:03 INFO - PROCESS | 2115 | |1;ext.html;477.3245857112186;488.5895847488109;496.4415033087776;512.7666911555243;520.0716720653633;30134.24664383389;32264.516032259504;37956.81021594891;41657.37513937286;44108.67673969778;42052.78973605135;42597.574024259746;42605.57394426063;42682.78658606703;42688.35967460491;8748.8687669685;9664.613415463391;9868.703938881845;9926.553305101252;9946.90053099467;8764.43031202973;8947.373683842101;8965.103489651015;8965.506897120657;9185.540722963859;11706.122040846927;11982.340971246578;12011.399430028463;12022.939885300604;12070.517179312817;11830.171887967846;11922.225055371373;11926.165168438212;11927.522899084026;11941.880581194164;11598.246114002563;11756.647300580997;11834.644960651194;11844.170908036442;11862.703432414259;40497.79751101226;40717.796411018055;40747.59252407467;40841.59158408437;40950.59049409498;3510.2628448025944;3652.5616925969052;3860.8455661773496;4090.3046482098116;4251.469470990441;30692.846535767178;31156.844215779;31868.840655796805;32000.19999500008;32210.677893221004;3301.6203136639356;3302.3065156317202;3401.727861771052;3435.278591495789;3456.3605732939486;359969;366255;370622;371593;376527.11736441415;33384.33231335408;61106;61607.07589386163;61640.075398869056;61660.07509887355;34048.659513405044;35424.645753542136;35495.64504354924;35551.64448355535;35648.82175589157;14621.926890365692;14775.039622424509;14807.851921480647;14827.92586037063;14832.11007339563;12444.502219911283;12516.3741812909;12550.623481295575;12553.121281510206;12594.307313097685;6473.41739243461;7247.927520724831;7394.001809755614;7821.921780782233;7862.135165131831;3218.356328734216;3249.9350012999607;3252.3820474109843;3283.343331333695;3294.9670503295138;859.2138193552829;933.6835062561755;966.6036924860773;995.4922989275522;1058.772363941756;29422.705772941998;30387.696123038928;30509.23726906845;30854.845725771676;31225.30824269325;1037.4138611684375;1073.6725298783947;1090.1224514266091;1110.6779034080025;1164.988350116505;15003.549893503212;15492.612684682974;15739.44911928077;15889.28498217583;16195.352185912432;501219;621727;629393;632165.8391708012;636646;58621;59310.11034834481;59328.40671593315;59454.81090378255;59467.702661486415;1334.8264725585611;1368.9110207836459;1382.2881216173678;1384.2801743093535;1384.7368999889989;25684.101056462932;27790.5831412533;28953.565696514146;29968.700312996592;30803.229919252197;52355;57035;58107.837843243746;58279.00651921797;62720;517732;567393;569981;577705;581146.094269526;32810.50784238191;35654.465183022796;43228;43698.78150609354;44225.55774442214;42141.36787948122;47444.52555474401;48467.27299090446;49086.75456622694;49334.75332623314;39182.41226381664;39323.803380982914;40610;41015.7949210264;41075;39818.606348777656;39974.800126000344;40252.78609676496;41035.794821026895;41390.37914431225;38816.80591597024;38835.41746873856;39029.21941561096;39400.605993939695;39474.40788388119;32925.17687057747;34847.12882177864;35480.82259588686;35893.28213435769;35942.921712348674;25318.98724051091;25602.61596076023;25714.485710286062;25900.740992590585;26310.73689263083 16:15:03 INFO - PROCESS | 2115 | |2;jquery.html;352.75131032622005;371.39462675838394;383.42869125003745;385.0566113023093;407.76961017025377;15246.695066098686;15686.52940411788;21133.577328453423;22023.008964596593;22029.228976985767;20779.896100519512;21064.789352106513;21071.2625058123;21104.261350852725;21112.577748445023;5762.1068734346245;5868.325142608602;5922.851928701795;6096.573239873211;6103.42017508336;5973.253343332083;6072.544559158058;6080.665563394012;6080.969595152018;6089.360617135203;5752.36723960364;5957.612755170921;6021.126936594192;6030.185924900148;6034.4568988791;5931.110333449974;6010.609310394854;6011.729472173763;6014.548908831812;6015.428534289235;5004.2493625956395;5166.664166829144;5233.476652334736;5234.136367499358;5238.60710446718;22995.655065174036;25342.606156661375;26507;26589.867050664816;26751.732482675314;2224.810891074261;2282.132789539981;2493.6882889638796;2510.661060756811;3064.9291470403427;15544.98390933256;15804.051756894509;15883.841161588467;15967.760483592756;16166;1742.3379115935863;1808;1870.51366644672;1917.7698676158811;1926;109303;110466;112806.43596782045;117916.41041794822;118272;10721.892781072245;10896.78206435867;10975.341479511255;11426.143039272089;11561.42192890352;13991.16053036821;14005.509807156905;14006.509772157926;14019.088759230617;14057.85942140586;7928.8810667840025;8180.854680344756;8553.486790792573;8715.520646364384;8819.206271435472;6931.4108300795;8514.914850851535;8590;8700;8703.956480217685;6561.507886908501;6679.064930909676;6680.532362734659;6692.564983276072;6697.196336439659;1805.8645601579933;1842.2999260281276;1844.2530775036132;1857.9781120383734;1880.6332765110672;797.9441439099207;964.5755867418454;980.7940332530106;1003.5584342889106;1010.3079390617304;22917.31248062561;22922.885385572965;23097.653535196987;23185.420364490685;23191.53616927652;884.2351366068433;914.1087439746194;938.8732521109699;952.1858810716876;995.66147509847;11504.25222360544;11907.226030308002;11947.044236461063;12023.27860328383;12090.879091209152;594012;597947;607806.9609652011;608019;612263;108423.45788271008;108847.45576272227;110515;134133;137649;430.39529461107196;445.1875327527276;453.99773001135446;457.59045654139356;461.342586813789;10973.615923442641;11462.713432164262;11509.827352589551;11603.767924641628;11847.822282665938;50432.24351634802;52125.478745212065;54077.72961135169;56523.43476565346;59284.40715592961;78265;85361.57319213364;85616.57191714001;86293.56853215945;88923;5760.798372056958;5779.075347944308;7611.543307401574;8190.221928916982;8580.270676992526;12318.938405307916;13042.478300868239;13114.540991065267;13624.386902589606;13682.452701891812;11181.888181118085;11226.607068752555;11257.943710281395;11477.253978491372;11626.593069242535;11252.774944500901;11316.434178290889;11616.883831161918;11632.011279041377;11637.01085407716;11543.653690389301;11562.47968841421;11892.286462812259;11921.582744603897;11966.760664786829;8097.311728503143;8449.577521123796;8477.40658153948;8595.140485951355;8599.054104048675;10798.406087665255;11346.184610462287;11657.475413606579;11708.004819590431;11720 16:15:03 INFO - PROCESS | 2115 | |3;mootools.html;1187.9168458207919;1407.1416435974054;1519.5669234268237;1692.6276219231772;1874.568849164692;7678.731244406447;8147.470414423058;8412.20084092011;8648.697295594655;8650.740477785661;8503.362247831406;8592.828143437127;8608.784780380494;8631.30949524038;8642.69750558729;2358.8230882683824;2380.0836677879056;2384.1297926256966;2385.5109702510945;2386.200622791367;2344.824138189634;2365.3377054424786;2385.6183010718287;2394.449276666368;2395.8442701224367;2354.835161538693;2380.095563685797;2386.474975505395;2390.4501964548163;2397;2335.0426325206586;2355.3993731598416;2366.7514910934447;2369.3839601703507;2370.727366352857;2322.674825524428;2344.191254017361;2354.564405584972;2358.13927916311;2360.799332056777;8671.826563468696;8876;8876.733697989072;8877.023527411979;8893.421927574687;5190.974045129788;5216.295800067019;5338.759755810997;5353.223782551547;5360.303160589137;3267.9019629411155;3520.9647903520895;3682.502862113634;3688.2807852468777;3691.5570131584113;1093.2511229807528;1105.4693747001404;1122.81473556863;1130.0338210829786;1134.5915470430639;11457.828132578017;11638.534458621645;11645.94177029118;11656.184067115306;11665.76668466626;4452.198604251266;4734.124187025412;4906.9263961040615;4963;4998.875028124326;8256.298214651702;8833.514156721441;9105.681301154427;9214.170724634814;9217.907820921839;5294.7617357219015;5302.284191634157;5303.204519322133;5306.6550674206055;5307.840764777063;4126.834926602963;4451.443569553806;4881.487443818419;4969.503049695001;4992.975035124801;3276.1318250663594;3728.496652951817;3746.456763769238;3805.980970095132;3855.248226595787;1159.4434671357797;1169.8537682789652;1177.5113327968968;1183.5206741269799;1189.304257009659;781.4412694923158;858.5063588436674;893.4282059481936;920.7744102694749;936.2697096264924;5401.783928642889;5654.236678048411;5716.542676585863;5934.228550288435;6084.239470066242;909.5679552212646;928.6703220356811;937.3953799799092;942.9575669094908;946.6084855262752;2949.9970010196635;3738.158914244273;3847.403652433821;3871.322518559241;3888.3389823730035;8397.916020839713;10550.894491055145;10779;10783.352998819939;10909.399983000852;10883.401412922365;10886.564537418415;10888;10899.509522071527;10900;6617.338266173344;7927.920720792834;8342.541160236246;8344.249017588325;8367.539785311745;5651.632643878134;5704.629199102045;5725.856853578527;5737.020727848885;5808.390119037609;10077.143442807443;10164.949175254373;10290.845637315297;10302.948485257526;10360.222983276435;9960.950195248977;10337.896621033693;10371.481425928523;10450.634227801991;10471.162307015573;7007.264237255014;7113.502054856111;7150.141982962078;7156.928430715626;7160.964195179199;7670.961645191738;7783.649735761791;8286.46138001028;8334.87497687523;8348.833023339621;6787.490938179558;7262.419006479575;7297.16082650502;7537.585432801248;7581.924180758122;6612.80969425508;7120.038794762848;7299.671514781741;7322.487425880137;7463.626818658937;6955.617441040791;6968.477364197807;7039.331263529956;7042.401395881408;7045.682944267418;4810.518948105301;4832.323474713473;4883.536064073907;4905.068037072945;4977.303177555217;4654.418197725285;4686.6016388605985;4743.335932969456;4799;4923.630727695366 16:15:03 INFO - PROCESS | 2115 | |4;prototype.html;294.37445428464514;300.65124455631474;310.1888561411908;313.72078849823674;314.55804594544657;5015.849524514261;5023.397192336915;5253.658512196709;5276.314079169712;5305.602079844012;5024.698518088917;5196.766145523451;5232.613357460276;5288.2596436498925;5310.654807437514;2921.897733579325;2987.5668028135906;3182.8408579570987;3189.8197666863293;3921.9410759095026;3712.721545884056;3721.967069548049;4057.411675307079;4078.632923036926;4078.632923036926;3199.6881278675746;3495.9898058616272;3735.62643735627;3943.5662077171633;4012.2978478766245;3670.2292518570866;3826.770393776382;3971.5829837866895;3977.622125898035;4012.558618551957;3186.8406579670923;3504.6869066416043;3514.2620049789575;3523.612402635708;3526.924288092131;3740.9438858417147;4886.364772579542;5036.269740887587;5048;5091.974540127313;3987.3819557968563;4066.410370496291;4518.774061296922;4523.36672865799;4531.5921567058795;5259.0270799901755;5497.845452454989;5537.916931246034;5541.5289700375115;5560.555155587584;3466.636003219651;3575.499430079765;3788.299164654548;3797;3799.3351163546267;4777.965991595362;5251.6061295403;5774.82675519735;5784.971075144639;5840.386759390245;3123.406552754969;3839.7120215983914;3868.3810590305416;3869.07142285855;3874.2445223181207;3614.2229420674157;3831.578526362098;3887.319719049155;3927.4501569780255;3939.2712348215673;4481.619062379734;4489.6755457140225;4509.729416235036;4511.233090374644;4516.728996260234;5228.816991405282;5530.562053866024;5543.1408131739645;5549.5837812164245;5574;1236.2829558855842;1272.7327261275236;1304.7148558669703;1305.9608211753662;1328.6811165320253;730.3828265115968;792.1919641965163;811.3306522119275;813.2477458351002;818.5375262976519;3702.352088384576;4172.77049762266;4317.697761156752;4344.913101737948;4345.67407444443;4311.655067594585;4396.4284642996845;4601.7699115044115;4687.835925742651;4758.762721692316;4283.464566929134;4286.485621725413;4308.9353659695125;4320.416743739618;4328.515544986316;2165.956680866374;2369.478714682767;2513.924582262535;2516.4463817960386;2825.6044153818484;5227.353383684127;5590.385057643656;5819.79630712923;5928.251165906068;5938.495227905676;4915.361003069578;5157.994191132768;5173.586113110942;5699.943000569941;5876.823695289148;4801.95198048022;4814.664887529615;4906.8773280668265;5067.493250674903;5537.58468114893;4564.014653625683;5639.828665963715;5688.525491411249;5735.627184233012;5756.42435756421;4912.164931961646;5122.026814905156;5426.945730542802;5607.102863541813;5627.549796016391;5546.419270507886;5839.445252701156;6002.909956350571;6004.519638429003;6011.338752737282;3362.69735723786;3971.3447281199433;4029.6776257899883;4030.697697672628;4038.051058001309;4267.975685835439;5616.876624674999;5718.628289161191;5729.770809167587;5736.42635736423;4430.712003719748;4655.534446555317;5102.617303702312;5579.944200558105;5586.273784408001;5355.839324820262;5561.110222364402;5580.079286917613;5588.944110559005;5595.258323421575;5413.945860541502;5467.9453205467435;5482.780688772405;5486.972565137149;5490.670559766427;5107.744612769272;5568.359638641608;5572;5580.693061881636;5595.440455954372;4521.773911304356;5195.29863468442;5457.372402173801;5490.396056433869;5530.889382212414 16:15:03 INFO - PROCESS | 2115 | |5;yui.html;670.9060731497591;689.6792991259064;694.9270326615705;701.551007355293;713.8001359619309;15357.616059598502;16484.01095934245;16484.423045193435;16498.752518712216;16504.83495165048;14705.485308014222;14752.483663071795;14862.554123376289;14904.776428353556;14912.627184320398;872.4852337121097;878.3587980774007;879.0111124984393;880.3793325705394;881.5195718333491;692.2973182220051;889.4440974391006;889.7597648634865;895.4000819450983;896.4576431259093;742.2651574940796;902.1159263921361;934.444005816538;939.8214339275515;940.4545363689078;913.2465715784438;914.5564401265381;916.2807196350833;917.3807679816131;921.7741653294919;686.2519853359862;690.1132045321788;690.3545185251755;691.6714560583682;691.7371398868398;11931.105167112415;12395.690107747288;12425.565105221363;12432;12445.25328480294;13687.863121368859;14667.339969701388;15035.323410446555;15055.924720376437;15209.011414258148;10644.787104257872;11778.705532361673;12003.759924801543;12057.155999080069;12081.57714479989;3827.942580861289;3872.9031774205587;3900.258950799339;3901.6293452122004;3969.8412063517426;95835;106949.46525267324;107608.99999999921;107619;107781;11278.548858045586;11407.771844563229;11418.885811141949;11427.942860285812;11909.52361905514;22853.885730571572;22864;22889;22922.72777261206;22934.541309174056;7954.960225198837;8034.236747508978;8258.42191046633;8263.669453221924;8281.29608983243;4279.893002674896;4440.245158323093;4490;4520.2089634314525;4563.931541026887;3636.381815091388;3687.8340474678703;3743.2700623378178;3755.7183211259266;3761.680257178116;674.231376231097;678.6878036103448;683.2108914204102;683.9213490448562;699.9825004374932;567.3815541060264;596.0939372154302;605.479287812481;614.1310046284547;622.5175488995974;8779.780505487286;8812.339074569432;9060.81878362429;9285.48929808867;9382.95308523453;509.297169905528;564.2692712936757;592.720987801901;610.081826850587;655.1712084213474;4240.236757383639;4477.70894891831;4492.415985921874;4502.909941801211;4563.817447302137;72172.91740623895;89329;89823;133811;145177;121746.3912680431;127386.08920866194;129233.70766292405;131501;131657;105135;105624.9437505615;109346;109454.99999999841;111634;8639.395242333228;8728.91271087281;8731.21419072261;8837.337199710177;8932.642694292155;59184;59846.10230846628;60533.09200362086;61482.38517614767;64556.70886582148;70542.64728676324;86180;88024.59877006253;90965.09034909566;92315.53842230747;12139.696507587381;12247.203931744409;12500.562480313145;12994.220346779224;13304.46782128704;14116.294185290899;14182;14476.855231447971;14634.098062115176;15046.54860354191;13135.934320328337;13224.272665003517;13318.200907945555;13611.387487562886;13856.653583660489;12894.41975111141;13372.465101396225;13514;13558.93220533891;13564.728705426034;12679.175853569488;12919.160254583421;13025.869741302466;13027.804582931074;13164.60506184816;10708.571657133627;11122.44387780624;11189.88810111921;11439.256448330832;11482.827757583476;8973.865392018994;10862.587863577684;10926.672199834018;11461.48423320936;11620.954114129769 16:15:03 INFO - PROCESS | 2115 | __end_tp_report 16:15:03 INFO - PROCESS | 2115 | __start_cc_report 16:15:03 INFO - PROCESS | 2115 | _x_x_mozilla_cycle_collect,1188 16:15:03 INFO - PROCESS | 2115 | __end_cc_report 16:15:03 INFO - PROCESS | 2115 | __startTimestamp1471475703834__endTimestamp 16:15:03 INFO - PROCESS | 2115 | 16:15:03 INFO - PROCESS | 2115 | ------- Summary: start ------- 16:15:03 INFO - PROCESS | 2115 | Number of tests: 6 16:15:03 INFO - PROCESS | 2115 | 16:15:03 INFO - PROCESS | 2115 | [#0] dojo.html Cycles:165 Average:5747.23 Median:5931.64 stddev:654.28 (11.0%) stddev-sans-first:626.64 16:15:03 INFO - PROCESS | 2115 | Values: 3265.0 3281.1 3290.8 3300.2 3312.2 5619.2 5653.4 5723.0 5766.4 5791.7 5669.5 5754.8 5810.7 5868.9 5876.4 5160.5 5230.2 5236.3 5263.2 5304.8 5295.0 5313.2 5319.9 5331.3 5339.7 5162.5 5213.1 5216.4 5238.4 5270.7 5243.2 5244.8 5248.4 5259.6 5265.6 5174.1 5193.5 5195.4 5208.3 5249.6 4729.5 5616.9 5653.2 5807.0 5809.5 6170.4 6263.1 6267.8 6268.4 6270.5 6111.9 6194.4 6331.2 6332.0 6383.4 5782.0 5836.9 5841.6 5881.4 5900.7 6357.3 6365.0 6365.5 6366.2 6373.0 6018.9 6073.4 6201.5 6213.0 6214.8 6210.5 6257.2 6307.3 6322.6 6340.5 6200.8 6204.7 6214.7 6220.8 6222.0 6098.7 6206.3 6242.8 6245.5 6270.8 4554.9 4918.5 4936.3 4939.1 4940.1 4406.4 4423.5 4444.1 4448.1 4515.9 5703.9 5730.7 5734.7 5743.8 5795.6 6145.7 6251.2 6274.9 6330.5 6341.9 5711.7 5759.9 5762.0 5762.9 5802.1 5430.5 5433.5 5435.8 5450.2 5454.5 6231.6 6255.7 6326.1 6327.4 6367.1 4893.7 6314.6 6339.8 6342.3 6347.5 6245.1 6280.3 6323.9 6328.7 6328.9 6036.2 6120.5 6217.7 6222.7 6243.2 6063.4 6133.1 6143.1 6197.2 6223.6 6067.1 6081.4 6087.1 6088.9 6097.1 6015.0 6057.2 6063.7 6063.8 6069.8 5735.9 5864.6 5870.5 5893.1 5932.9 6132.7 6148.7 6149.0 6157.2 6163.8 5703.5 5810.9 5931.6 5954.0 5991.9 16:15:03 INFO - PROCESS | 2115 | 16:15:03 INFO - PROCESS | 2115 | [#1] ext.html Cycles:180 Average:65059.36 Median:371107.50 stddev:139799.93 (37.7%) stddev-sans-first:140108.02 16:15:03 INFO - PROCESS | 2115 | Values: 477.3 488.6 496.4 512.8 520.1 30134.2 32264.5 37956.8 41657.4 44108.7 42052.8 42597.6 42605.6 42682.8 42688.4 8748.9 9664.6 9868.7 9926.6 9946.9 8764.4 8947.4 8965.1 8965.5 9185.5 11706.1 11982.3 12011.4 12022.9 12070.5 11830.2 11922.2 11926.2 11927.5 11941.9 11598.2 11756.6 11834.6 11844.2 11862.7 40497.8 40717.8 40747.6 40841.6 40950.6 3510.3 3652.6 3860.8 4090.3 4251.5 30692.8 31156.8 31868.8 32000.2 32210.7 3301.6 3302.3 3401.7 3435.3 3456.4 359969.0 366255.0 370622.0 371593.0 376527.1 33384.3 61106.0 61607.1 61640.1 61660.1 34048.7 35424.6 35495.6 35551.6 35648.8 14621.9 14775.0 14807.9 14827.9 14832.1 12444.5 12516.4 12550.6 12553.1 12594.3 6473.4 7247.9 7394.0 7821.9 7862.1 3218.4 3249.9 3252.4 3283.3 3295.0 859.2 933.7 966.6 995.5 1058.8 29422.7 30387.7 30509.2 30854.8 31225.3 1037.4 1073.7 1090.1 1110.7 1165.0 15003.5 15492.6 15739.4 15889.3 16195.4 501219.0 621727.0 629393.0 632165.8 636646.0 58621.0 59310.1 59328.4 59454.8 59467.7 1334.8 1368.9 1382.3 1384.3 1384.7 25684.1 27790.6 28953.6 29968.7 30803.2 52355.0 57035.0 58107.8 58279.0 62720.0 517732.0 567393.0 569981.0 577705.0 581146.1 32810.5 35654.5 43228.0 43698.8 44225.6 42141.4 47444.5 48467.3 49086.8 49334.8 39182.4 39323.8 40610.0 41015.8 41075.0 39818.6 39974.8 40252.8 41035.8 41390.4 38816.8 38835.4 39029.2 39400.6 39474.4 32925.2 34847.1 35480.8 35893.3 35942.9 25319.0 25602.6 25714.5 25900.7 26310.7 16:15:03 INFO - PROCESS | 2115 | 16:15:03 INFO - PROCESS | 2115 | [#2] jquery.html Cycles:180 Average:35186.22 Median:2502.17 stddev:100566.02 (4019.1%) stddev-sans-first:100814.12 16:15:03 INFO - PROCESS | 2115 | Values: 352.8 371.4 383.4 385.1 407.8 15246.7 15686.5 21133.6 22023.0 22029.2 20779.9 21064.8 21071.3 21104.3 21112.6 5762.1 5868.3 5922.9 6096.6 6103.4 5973.3 6072.5 6080.7 6081.0 6089.4 5752.4 5957.6 6021.1 6030.2 6034.5 5931.1 6010.6 6011.7 6014.5 6015.4 5004.2 5166.7 5233.5 5234.1 5238.6 22995.7 25342.6 26507.0 26589.9 26751.7 2224.8 2282.1 2493.7 2510.7 3064.9 15545.0 15804.1 15883.8 15967.8 16166.0 1742.3 1808.0 1870.5 1917.8 1926.0 109303.0 110466.0 112806.4 117916.4 118272.0 10721.9 10896.8 10975.3 11426.1 11561.4 13991.2 14005.5 14006.5 14019.1 14057.9 7928.9 8180.9 8553.5 8715.5 8819.2 6931.4 8514.9 8590.0 8700.0 8704.0 6561.5 6679.1 6680.5 6692.6 6697.2 1805.9 1842.3 1844.3 1858.0 1880.6 797.9 964.6 980.8 1003.6 1010.3 22917.3 22922.9 23097.7 23185.4 23191.5 884.2 914.1 938.9 952.2 995.7 11504.3 11907.2 11947.0 12023.3 12090.9 594012.0 597947.0 607807.0 608019.0 612263.0 108423.5 108847.5 110515.0 134133.0 137649.0 430.4 445.2 454.0 457.6 461.3 10973.6 11462.7 11509.8 11603.8 11847.8 50432.2 52125.5 54077.7 56523.4 59284.4 78265.0 85361.6 85616.6 86293.6 88923.0 5760.8 5779.1 7611.5 8190.2 8580.3 12318.9 13042.5 13114.5 13624.4 13682.5 11181.9 11226.6 11257.9 11477.3 11626.6 11252.8 11316.4 11616.9 11632.0 11637.0 11543.7 11562.5 11892.3 11921.6 11966.8 8097.3 8449.6 8477.4 8595.1 8599.1 10798.4 11346.2 11657.5 11708.0 11720.0 16:15:03 INFO - PROCESS | 2115 | 16:15:03 INFO - PROCESS | 2115 | [#3] mootools.html Cycles:180 Average:5617.20 Median:4906.00 stddev:3163.33 (64.5%) stddev-sans-first:3154.69 16:15:03 INFO - PROCESS | 2115 | Values: 1187.9 1407.1 1519.6 1692.6 1874.6 7678.7 8147.5 8412.2 8648.7 8650.7 8503.4 8592.8 8608.8 8631.3 8642.7 2358.8 2380.1 2384.1 2385.5 2386.2 2344.8 2365.3 2385.6 2394.4 2395.8 2354.8 2380.1 2386.5 2390.5 2397.0 2335.0 2355.4 2366.8 2369.4 2370.7 2322.7 2344.2 2354.6 2358.1 2360.8 8671.8 8876.0 8876.7 8877.0 8893.4 5191.0 5216.3 5338.8 5353.2 5360.3 3267.9 3521.0 3682.5 3688.3 3691.6 1093.3 1105.5 1122.8 1130.0 1134.6 11457.8 11638.5 11645.9 11656.2 11665.8 4452.2 4734.1 4906.9 4963.0 4998.9 8256.3 8833.5 9105.7 9214.2 9217.9 5294.8 5302.3 5303.2 5306.7 5307.8 4126.8 4451.4 4881.5 4969.5 4993.0 3276.1 3728.5 3746.5 3806.0 3855.2 1159.4 1169.9 1177.5 1183.5 1189.3 781.4 858.5 893.4 920.8 936.3 5401.8 5654.2 5716.5 5934.2 6084.2 909.6 928.7 937.4 943.0 946.6 2950.0 3738.2 3847.4 3871.3 3888.3 8397.9 10550.9 10779.0 10783.4 10909.4 10883.4 10886.6 10888.0 10899.5 10900.0 6617.3 7927.9 8342.5 8344.2 8367.5 5651.6 5704.6 5725.9 5737.0 5808.4 10077.1 10164.9 10290.8 10302.9 10360.2 9961.0 10337.9 10371.5 10450.6 10471.2 7007.3 7113.5 7150.1 7156.9 7161.0 7671.0 7783.6 8286.5 8334.9 8348.8 6787.5 7262.4 7297.2 7537.6 7581.9 6612.8 7120.0 7299.7 7322.5 7463.6 6955.6 6968.5 7039.3 7042.4 7045.7 4810.5 4832.3 4883.5 4905.1 4977.3 4654.4 4686.6 4743.3 4799.0 4923.6 16:15:03 INFO - PROCESS | 2115 | 16:15:03 INFO - PROCESS | 2115 | [#4] prototype.html Cycles:180 Average:4351.26 Median:4768.36 stddev:1382.21 (29.0%) stddev-sans-first:1352.13 16:15:03 INFO - PROCESS | 2115 | Values: 294.4 300.7 310.2 313.7 314.6 5015.8 5023.4 5253.7 5276.3 5305.6 5024.7 5196.8 5232.6 5288.3 5310.7 2921.9 2987.6 3182.8 3189.8 3921.9 3712.7 3722.0 4057.4 4078.6 4078.6 3199.7 3496.0 3735.6 3943.6 4012.3 3670.2 3826.8 3971.6 3977.6 4012.6 3186.8 3504.7 3514.3 3523.6 3526.9 3740.9 4886.4 5036.3 5048.0 5092.0 3987.4 4066.4 4518.8 4523.4 4531.6 5259.0 5497.8 5537.9 5541.5 5560.6 3466.6 3575.5 3788.3 3797.0 3799.3 4778.0 5251.6 5774.8 5785.0 5840.4 3123.4 3839.7 3868.4 3869.1 3874.2 3614.2 3831.6 3887.3 3927.5 3939.3 4481.6 4489.7 4509.7 4511.2 4516.7 5228.8 5530.6 5543.1 5549.6 5574.0 1236.3 1272.7 1304.7 1306.0 1328.7 730.4 792.2 811.3 813.2 818.5 3702.4 4172.8 4317.7 4344.9 4345.7 4311.7 4396.4 4601.8 4687.8 4758.8 4283.5 4286.5 4308.9 4320.4 4328.5 2166.0 2369.5 2513.9 2516.4 2825.6 5227.4 5590.4 5819.8 5928.3 5938.5 4915.4 5158.0 5173.6 5699.9 5876.8 4802.0 4814.7 4906.9 5067.5 5537.6 4564.0 5639.8 5688.5 5735.6 5756.4 4912.2 5122.0 5426.9 5607.1 5627.5 5546.4 5839.4 6002.9 6004.5 6011.3 3362.7 3971.3 4029.7 4030.7 4038.1 4268.0 5616.9 5718.6 5729.8 5736.4 4430.7 4655.5 5102.6 5579.9 5586.3 5355.8 5561.1 5580.1 5588.9 5595.3 5413.9 5467.9 5482.8 5487.0 5490.7 5107.7 5568.4 5572.0 5580.7 5595.4 4521.8 5195.3 5457.4 5490.4 5530.9 16:15:03 INFO - PROCESS | 2115 | 16:15:03 INFO - PROCESS | 2115 | [#5] yui.html Cycles:180 Average:23247.60 Median:3850.42 stddev:35878.77 (931.8%) stddev-sans-first:35939.37 16:15:03 INFO - PROCESS | 2115 | Values: 670.9 689.7 694.9 701.6 713.8 15357.6 16484.0 16484.4 16498.8 16504.8 14705.5 14752.5 14862.6 14904.8 14912.6 872.5 878.4 879.0 880.4 881.5 692.3 889.4 889.8 895.4 896.5 742.3 902.1 934.4 939.8 940.5 913.2 914.6 916.3 917.4 921.8 686.3 690.1 690.4 691.7 691.7 11931.1 12395.7 12425.6 12432.0 12445.3 13687.9 14667.3 15035.3 15055.9 15209.0 10644.8 11778.7 12003.8 12057.2 12081.6 3827.9 3872.9 3900.3 3901.6 3969.8 95835.0 106949.5 107609.0 107619.0 107781.0 11278.5 11407.8 11418.9 11427.9 11909.5 22853.9 22864.0 22889.0 22922.7 22934.5 7955.0 8034.2 8258.4 8263.7 8281.3 4279.9 4440.2 4490.0 4520.2 4563.9 3636.4 3687.8 3743.3 3755.7 3761.7 674.2 678.7 683.2 683.9 700.0 567.4 596.1 605.5 614.1 622.5 8779.8 8812.3 9060.8 9285.5 9383.0 509.3 564.3 592.7 610.1 655.2 4240.2 4477.7 4492.4 4502.9 4563.8 72172.9 89329.0 89823.0 133811.0 145177.0 121746.4 127386.1 129233.7 131501.0 131657.0 105135.0 105624.9 109346.0 109455.0 111634.0 8639.4 8728.9 8731.2 8837.3 8932.6 59184.0 59846.1 60533.1 61482.4 64556.7 70542.6 86180.0 88024.6 90965.1 92315.5 12139.7 12247.2 12500.6 12994.2 13304.5 14116.3 14182.0 14476.9 14634.1 15046.5 13135.9 13224.3 13318.2 13611.4 13856.7 12894.4 13372.5 13514.0 13558.9 13564.7 12679.2 12919.2 13025.9 13027.8 13164.6 10708.6 11122.4 11189.9 11439.3 11482.8 8973.9 10862.6 10926.7 11461.5 11621.0 16:15:03 INFO - PROCESS | 2115 | -------- Summary: end -------- 16:15:03 INFO - PROCESS | 2115 | 16:15:04 INFO - TEST-INFO | 2115: exit 0 16:15:04 INFO - TEST-OK | dromaeo_css | took 1118441ms 16:15:04 INFO - TEST-START | kraken 16:15:04 INFO - operating with platform_type : mac_ 16:15:04 INFO - Initialising browser for kraken test... 16:15:04 INFO - TEST-INFO | started process 2231 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmp9URVV9/profile http://localhost:49572/getInfo.html) 16:15:06 INFO - PROCESS | 2231 | __metrics Screen width/height:1600/1200 16:15:06 INFO - PROCESS | 2231 | colorDepth:24 16:15:06 INFO - PROCESS | 2231 | Browser inner width/height: 1024/689 16:15:06 INFO - PROCESS | 2231 | __metrics 16:15:06 INFO - PROCESS | 2231 | 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" 16:15:06 INFO - PROCESS | 2231 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:15:07 INFO - TEST-INFO | 2231: exit 0 16:15:07 INFO - Browser initialized. 16:15:07 INFO - Running cycle 1/1 for kraken test... 16:15:07 INFO - TEST-INFO | started process 2233 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmp9URVV9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:15:19 INFO - PROCESS | 2233 | RSS: Main: 228249600 16:15:19 INFO - PROCESS | 2233 | 16:15:22 INFO - PROCESS | 2233 | Cycle 1(1): loaded http://localhost:49572/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49572/tests/kraken/driver.html?testName=audio-beat-detection) 16:15:23 INFO - PROCESS | 2233 | RSS: Main: 284643328 16:15:23 INFO - PROCESS | 2233 | 16:15:25 INFO - PROCESS | 2233 | Cycle 1(1): loaded http://localhost:49572/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49572/tests/kraken/driver.html?testName=audio-dft) 16:15:26 INFO - PROCESS | 2233 | RSS: Main: 293322752 16:15:26 INFO - PROCESS | 2233 | 16:15:30 INFO - PROCESS | 2233 | Cycle 1(1): loaded http://localhost:49572/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49572/tests/kraken/driver.html?testName=audio-fft) 16:15:30 INFO - PROCESS | 2233 | RSS: Main: 386744320 16:15:30 INFO - PROCESS | 2233 | 16:15:32 INFO - PROCESS | 2233 | Cycle 1(1): loaded http://localhost:49572/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49572/tests/kraken/driver.html?testName=audio-oscillator) 16:15:33 INFO - PROCESS | 2233 | RSS: Main: 289083392 16:15:33 INFO - PROCESS | 2233 | 16:15:35 INFO - PROCESS | 2233 | Cycle 1(1): loaded http://localhost:49572/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49572/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:15:36 INFO - PROCESS | 2233 | RSS: Main: 293081088 16:15:36 INFO - PROCESS | 2233 | 16:15:38 INFO - PROCESS | 2233 | Cycle 1(1): loaded http://localhost:49572/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49572/tests/kraken/driver.html?testName=imaging-darkroom) 16:15:39 INFO - PROCESS | 2233 | RSS: Main: 331210752 16:15:39 INFO - PROCESS | 2233 | 16:15:42 INFO - PROCESS | 2233 | Cycle 1(1): loaded http://localhost:49572/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49572/tests/kraken/driver.html?testName=imaging-desaturate) 16:15:43 INFO - PROCESS | 2233 | RSS: Main: 330199040 16:15:43 INFO - PROCESS | 2233 | 16:15:45 INFO - PROCESS | 2233 | Cycle 1(1): loaded http://localhost:49572/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49572/tests/kraken/driver.html?testName=json-parse-financial) 16:15:46 INFO - PROCESS | 2233 | RSS: Main: 329674752 16:15:46 INFO - PROCESS | 2233 | 16:15:48 INFO - PROCESS | 2233 | Cycle 1(1): loaded http://localhost:49572/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49572/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:15:48 INFO - PROCESS | 2233 | RSS: Main: 300437504 16:15:48 INFO - PROCESS | 2233 | 16:15:50 INFO - PROCESS | 2233 | Cycle 1(1): loaded http://localhost:49572/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49572/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:15:51 INFO - PROCESS | 2233 | RSS: Main: 307920896 16:15:51 INFO - PROCESS | 2233 | 16:15:53 INFO - PROCESS | 2233 | Cycle 1(1): loaded http://localhost:49572/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49572/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:15:54 INFO - PROCESS | 2233 | RSS: Main: 301486080 16:15:54 INFO - PROCESS | 2233 | 16:15:57 INFO - PROCESS | 2233 | Cycle 1(1): loaded http://localhost:49572/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49572/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:15:57 INFO - PROCESS | 2233 | RSS: Main: 312393728 16:15:57 INFO - PROCESS | 2233 | 16:16:00 INFO - PROCESS | 2233 | Cycle 1(1): loaded http://localhost:49572/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49572/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:16:01 INFO - PROCESS | 2233 | RSS: Main: 314769408 16:16:01 INFO - PROCESS | 2233 | 16:16:03 INFO - PROCESS | 2233 | Cycle 1(1): loaded http://localhost:49572/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49572/tests/kraken/driver.html?testName=ai-astar) 16:16:03 INFO - PROCESS | 2233 | RSS: Main: 358313984 16:16:03 INFO - PROCESS | 2233 | 16:16:03 INFO - PROCESS | 2233 | __start_tp_report 16:16:03 INFO - PROCESS | 2233 | _x_x_mozilla_page_load 16:16:03 INFO - PROCESS | 2233 | _x_x_mozilla_page_load_details 16:16:03 INFO - PROCESS | 2233 | |i|pagename|runs| 16:16:03 INFO - PROCESS | 2233 | |0;ai-astar;103;116;160;168;112;114;108;114;111;112 16:16:03 INFO - PROCESS | 2233 | |1;audio-beat-detection;143;110;109;166;106;106;127;104;157;138 16:16:03 INFO - PROCESS | 2233 | |2;audio-dft;183;186;185;185;184;184;185;183;182;182 16:16:03 INFO - PROCESS | 2233 | |3;audio-fft;78;57;66;79;58;70;91;90;110;63 16:16:03 INFO - PROCESS | 2233 | |4;audio-oscillator;83;68;103;70;82;68;95;69;67;104 16:16:03 INFO - PROCESS | 2233 | |5;imaging-gaussian-blur;88;84;88;85;88;85;88;87;89;84 16:16:03 INFO - PROCESS | 2233 | |6;imaging-darkroom;93;92;95;93;94;136;150;116;93;93 16:16:03 INFO - PROCESS | 2233 | |7;imaging-desaturate;92;91;94;87;89;92;128;93;88;91 16:16:03 INFO - PROCESS | 2233 | |8;json-parse-financial;55;51;52;51;52;55;52;52;54;52 16:16:03 INFO - PROCESS | 2233 | |9;json-stringify-tinderbox;63;71;77;75;73;69;70;69;69;70 16:16:03 INFO - PROCESS | 2233 | |10;stanford-crypto-aes;83;83;85;82;84;83;83;84;88;86 16:16:03 INFO - PROCESS | 2233 | |11;stanford-crypto-ccm;143;147;157;150;135;142;144;152;223;138 16:16:03 INFO - PROCESS | 2233 | |12;stanford-crypto-pbkdf2;170;168;165;167;178;235;187;179;228;201 16:16:03 INFO - PROCESS | 2233 | |13;stanford-crypto-sha256-iterative;67;68;70;84;85;84;67;68;67;67 16:16:03 INFO - PROCESS | 2233 | __end_tp_report 16:16:03 INFO - PROCESS | 2233 | __start_cc_report 16:16:03 INFO - PROCESS | 2233 | _x_x_mozilla_cycle_collect,2023 16:16:03 INFO - PROCESS | 2233 | __end_cc_report 16:16:03 INFO - PROCESS | 2233 | __startTimestamp1471475763640__endTimestamp 16:16:03 INFO - PROCESS | 2233 | 16:16:03 INFO - PROCESS | 2233 | ------- Summary: start ------- 16:16:03 INFO - PROCESS | 2233 | Number of tests: 14 16:16:03 INFO - PROCESS | 2233 | 16:16:03 INFO - PROCESS | 2233 | [#0] ai-astar Cycles:10 Average:121.80 Median:114.00 stddev:22.61 (19.8%) stddev-sans-first:22.94 16:16:03 INFO - PROCESS | 2233 | Values: 103.0 116.0 160.0 168.0 112.0 114.0 108.0 114.0 111.0 112.0 16:16:03 INFO - PROCESS | 2233 | 16:16:03 INFO - PROCESS | 2233 | [#1] audio-beat-detection Cycles:10 Average:126.60 Median:132.50 stddev:23.14 (17.5%) stddev-sans-first:23.77 16:16:03 INFO - PROCESS | 2233 | Values: 143.0 110.0 109.0 166.0 106.0 106.0 127.0 104.0 157.0 138.0 16:16:03 INFO - PROCESS | 2233 | 16:16:03 INFO - PROCESS | 2233 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.50 stddev:1.37 (0.7%) stddev-sans-first:1.41 16:16:03 INFO - PROCESS | 2233 | Values: 183.0 186.0 185.0 185.0 184.0 184.0 185.0 183.0 182.0 182.0 16:16:03 INFO - PROCESS | 2233 | 16:16:03 INFO - PROCESS | 2233 | [#3] audio-fft Cycles:10 Average:76.20 Median:74.00 stddev:16.93 (22.9%) stddev-sans-first:17.94 16:16:03 INFO - PROCESS | 2233 | Values: 78.0 57.0 66.0 79.0 58.0 70.0 91.0 90.0 110.0 63.0 16:16:03 INFO - PROCESS | 2233 | 16:16:03 INFO - PROCESS | 2233 | [#4] audio-oscillator Cycles:10 Average:80.90 Median:69.50 stddev:14.96 (21.5%) stddev-sans-first:15.84 16:16:03 INFO - PROCESS | 2233 | Values: 83.0 68.0 103.0 70.0 82.0 68.0 95.0 69.0 67.0 104.0 16:16:03 INFO - PROCESS | 2233 | 16:16:03 INFO - PROCESS | 2233 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:88.00 stddev:1.90 (2.2%) stddev-sans-first:1.94 16:16:03 INFO - PROCESS | 2233 | Values: 88.0 84.0 88.0 85.0 88.0 85.0 88.0 87.0 89.0 84.0 16:16:03 INFO - PROCESS | 2233 | 16:16:03 INFO - PROCESS | 2233 | [#6] imaging-darkroom Cycles:10 Average:105.50 Median:93.00 stddev:21.27 (22.9%) stddev-sans-first:22.07 16:16:03 INFO - PROCESS | 2233 | Values: 93.0 92.0 95.0 93.0 94.0 136.0 150.0 116.0 93.0 93.0 16:16:03 INFO - PROCESS | 2233 | 16:16:03 INFO - PROCESS | 2233 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:91.50 stddev:11.97 (13.1%) stddev-sans-first:12.67 16:16:03 INFO - PROCESS | 2233 | Values: 92.0 91.0 94.0 87.0 89.0 92.0 128.0 93.0 88.0 91.0 16:16:03 INFO - PROCESS | 2233 | 16:16:03 INFO - PROCESS | 2233 | [#8] json-parse-financial Cycles:10 Average:52.60 Median:52.00 stddev:1.51 (2.9%) stddev-sans-first:1.32 16:16:03 INFO - PROCESS | 2233 | Values: 55.0 51.0 52.0 51.0 52.0 55.0 52.0 52.0 54.0 52.0 16:16:03 INFO - PROCESS | 2233 | 16:16:03 INFO - PROCESS | 2233 | [#9] json-stringify-tinderbox Cycles:10 Average:70.60 Median:70.50 stddev:3.84 (5.4%) stddev-sans-first:2.92 16:16:03 INFO - PROCESS | 2233 | Values: 63.0 71.0 77.0 75.0 73.0 69.0 70.0 69.0 69.0 70.0 16:16:03 INFO - PROCESS | 2233 | 16:16:03 INFO - PROCESS | 2233 | [#10] stanford-crypto-aes Cycles:10 Average:84.10 Median:84.00 stddev:1.79 (2.1%) stddev-sans-first:1.86 16:16:03 INFO - PROCESS | 2233 | Values: 83.0 83.0 85.0 82.0 84.0 83.0 83.0 84.0 88.0 86.0 16:16:03 INFO - PROCESS | 2233 | 16:16:03 INFO - PROCESS | 2233 | [#11] stanford-crypto-ccm Cycles:10 Average:153.10 Median:148.50 stddev:25.41 (17.1%) stddev-sans-first:26.69 16:16:03 INFO - PROCESS | 2233 | Values: 143.0 147.0 157.0 150.0 135.0 142.0 144.0 152.0 223.0 138.0 16:16:03 INFO - PROCESS | 2233 | 16:16:03 INFO - PROCESS | 2233 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.80 Median:183.00 stddev:25.50 (13.9%) stddev-sans-first:26.22 16:16:03 INFO - PROCESS | 2233 | Values: 170.0 168.0 165.0 167.0 178.0 235.0 187.0 179.0 228.0 201.0 16:16:03 INFO - PROCESS | 2233 | 16:16:03 INFO - PROCESS | 2233 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.70 Median:69.00 stddev:8.08 (11.7%) stddev-sans-first:8.31 16:16:03 INFO - PROCESS | 2233 | Values: 67.0 68.0 70.0 84.0 85.0 84.0 67.0 68.0 67.0 67.0 16:16:03 INFO - PROCESS | 2233 | -------- Summary: end -------- 16:16:03 INFO - PROCESS | 2233 | 16:16:04 INFO - TEST-INFO | 2233: exit 0 16:16:04 INFO - TEST-OK | kraken | took 60029ms 16:16:04 INFO - SUITE-END | took 1178s 16:16:04 INFO - Completed test suite (00:19:38) 16:16:04 INFO - javascript benchmark 16:16:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3264.983675081625, 3281.064896504495, 3290.819004954728, 3300.1914530939925, 3312.1554003729048, 5619.241402410673, 5653.406392328803, 5722.971385143079, 5766.394528574502, 5791.652500849947, 5669.546436285098, 5754.798582049629, 5810.738516766745, 5868.853278668035, 5876.353601103886, 5160.483951604847, 5230.189320655304, 5236.266922630834, 5263.210518422248, 5304.840854774363, 5295.0468915595175, 5313.1764576490705, 5319.867003324909, 5331.280277162572, 5339.652922560041, 5162.535371816517, 5213.087709650834, 5216.426193118754, 5238.4499627539135, 5270.736463176826, 5243.161094224905, 5244.790208391698, 5248.370195576517, 5259.631825772198, 5265.578753699733, 5174.068667639824, 5193.506616871391, 5195.350581177353, 5208.270842082112, 5249.632525723201, 4729.456112547066, 5616.9157462638095, 5653.236813030229, 5806.970965145189, 5809.477147056743, 6170.413810687977, 6263.060540918853, 6267.780627678009, 6268.373162683741, 6270.46701030413, 6111.92601248044, 6194.411530904602, 6331.2085989251345, 6331.96834015827, 6383.361663833626, 5781.971090144522, 5836.883262334814, 5841.620294680833, 5881.3530511643685, 5900.675462849585, 6357.332480089616, 6365.0, 6365.49076073913, 6366.172397588284, 6372.968135159294, 6018.939810601925, 6073.392660733891, 6201.503879689614, 6213.03697926822, 6214.751409943552, 6210.472109870621, 6257.217847769029, 6307.274663413767, 6322.589031712924, 6340.461060809791, 6200.844978875564, 6204.689765511706, 6214.720337584729, 6220.844478887973, 6222.0, 6098.674287528808, 6206.317305096436, 6242.843928901723, 6245.469135123474, 6270.843228919222, 4554.863354099382, 4918.508149185053, 4936.284238785428, 4939.135651261015, 4940.061388336205, 4406.383106365111, 4423.469183697976, 4444.1111777643955, 4448.065906159743, 4515.88710282239, 5703.8859222816145, 5730.7421166047625, 5734.741936612862, 5743.827685169452, 5795.594308398456, 6145.692715364214, 6251.249850018027, 6274.874502509924, 6330.525210609223, 6341.936580634227, 5711.7429715662065, 5759.884802303931, 5761.971190144022, 5762.855928601734, 5802.1296805479515, 5430.511253987082, 5433.456654334614, 5435.755391007414, 5450.182472629058, 5454.481824226692, 6231.594946328474, 6255.655938923403, 6326.145970293951, 6327.367263273636, 6367.07677386767, 4893.73084480357, 6314.62112273265, 6339.809805705836, 6342.270638876588, 6347.492200624031, 6245.094461303176, 6280.340564240689, 6323.873522529616, 6328.6519241442165, 6328.9050664240995, 6036.154938308729, 6120.510359171345, 6217.720202590985, 6222.688865556614, 6243.219597550306, 6063.393660633901, 6133.089845874549, 6143.112737013596, 6197.225346831646, 6223.626582404889, 6067.089936509559, 6081.391860813883, 6087.086936959491, 6088.8782224356155, 6097.115918191926, 6015.037593984942, 6057.1519987200945, 6063.6664983426335, 6063.757449702139, 6069.787557435468, 5735.8566035849435, 5864.618799778001, 5870.471657550841, 5893.086571581412, 5932.851678708067, 6132.702084519407, 6148.692565371624, 6149.0161574149715, 6157.230346206724, 6163.8150855474405, 5703.515201207943, 5810.883782324415, 5931.6441013539325, 5954.0, 5991.910121348096], "value": 5704.4068751070545, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [477.3245857112186, 488.5895847488109, 496.4415033087776, 512.7666911555243, 520.0716720653633, 30134.24664383389, 32264.516032259504, 37956.81021594891, 41657.37513937286, 44108.67673969778, 42052.78973605135, 42597.574024259746, 42605.57394426063, 42682.78658606703, 42688.35967460491, 8748.8687669685, 9664.613415463391, 9868.703938881845, 9926.553305101252, 9946.90053099467, 8764.43031202973, 8947.373683842101, 8965.103489651015, 8965.506897120657, 9185.540722963859, 11706.122040846927, 11982.340971246578, 12011.399430028463, 12022.939885300604, 12070.517179312817, 11830.171887967846, 11922.225055371373, 11926.165168438212, 11927.522899084026, 11941.880581194164, 11598.246114002563, 11756.647300580997, 11834.644960651194, 11844.170908036442, 11862.703432414259, 40497.79751101226, 40717.796411018055, 40747.59252407467, 40841.59158408437, 40950.59049409498, 3510.2628448025944, 3652.5616925969052, 3860.8455661773496, 4090.3046482098116, 4251.469470990441, 30692.846535767178, 31156.844215779, 31868.840655796805, 32000.19999500008, 32210.677893221004, 3301.6203136639356, 3302.3065156317202, 3401.727861771052, 3435.278591495789, 3456.3605732939486, 359969.0, 366255.0, 370622.0, 371593.0, 376527.11736441415, 33384.33231335408, 61106.0, 61607.07589386163, 61640.075398869056, 61660.07509887355, 34048.659513405044, 35424.645753542136, 35495.64504354924, 35551.64448355535, 35648.82175589157, 14621.926890365692, 14775.039622424509, 14807.851921480647, 14827.92586037063, 14832.11007339563, 12444.502219911283, 12516.3741812909, 12550.623481295575, 12553.121281510206, 12594.307313097685, 6473.41739243461, 7247.927520724831, 7394.001809755614, 7821.921780782233, 7862.135165131831, 3218.356328734216, 3249.9350012999607, 3252.3820474109843, 3283.343331333695, 3294.9670503295138, 859.2138193552829, 933.6835062561755, 966.6036924860773, 995.4922989275522, 1058.772363941756, 29422.705772941998, 30387.696123038928, 30509.23726906845, 30854.845725771676, 31225.30824269325, 1037.4138611684375, 1073.6725298783947, 1090.1224514266091, 1110.6779034080025, 1164.988350116505, 15003.549893503212, 15492.612684682974, 15739.44911928077, 15889.28498217583, 16195.352185912432, 501219.0, 621727.0, 629393.0, 632165.8391708012, 636646.0, 58621.0, 59310.11034834481, 59328.40671593315, 59454.81090378255, 59467.702661486415, 1334.8264725585611, 1368.9110207836459, 1382.2881216173678, 1384.2801743093535, 1384.7368999889989, 25684.101056462932, 27790.5831412533, 28953.565696514146, 29968.700312996592, 30803.229919252197, 52355.0, 57035.0, 58107.837843243746, 58279.00651921797, 62720.0, 517732.0, 567393.0, 569981.0, 577705.0, 581146.094269526, 32810.50784238191, 35654.465183022796, 43228.0, 43698.78150609354, 44225.55774442214, 42141.36787948122, 47444.52555474401, 48467.27299090446, 49086.75456622694, 49334.75332623314, 39182.41226381664, 39323.803380982914, 40610.0, 41015.7949210264, 41075.0, 39818.606348777656, 39974.800126000344, 40252.78609676496, 41035.794821026895, 41390.37914431225, 38816.80591597024, 38835.41746873856, 39029.21941561096, 39400.605993939695, 39474.40788388119, 32925.17687057747, 34847.12882177864, 35480.82259588686, 35893.28213435769, 35942.921712348674, 25318.98724051091, 25602.61596076023, 25714.485710286062, 25900.740992590585, 26310.73689263083], "value": 19193.002934642882, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.75131032622005, 371.39462675838394, 383.42869125003745, 385.0566113023093, 407.76961017025377, 15246.695066098686, 15686.52940411788, 21133.577328453423, 22023.008964596593, 22029.228976985767, 20779.896100519512, 21064.789352106513, 21071.2625058123, 21104.261350852725, 21112.577748445023, 5762.1068734346245, 5868.325142608602, 5922.851928701795, 6096.573239873211, 6103.42017508336, 5973.253343332083, 6072.544559158058, 6080.665563394012, 6080.969595152018, 6089.360617135203, 5752.36723960364, 5957.612755170921, 6021.126936594192, 6030.185924900148, 6034.4568988791, 5931.110333449974, 6010.609310394854, 6011.729472173763, 6014.548908831812, 6015.428534289235, 5004.2493625956395, 5166.664166829144, 5233.476652334736, 5234.136367499358, 5238.60710446718, 22995.655065174036, 25342.606156661375, 26507.0, 26589.867050664816, 26751.732482675314, 2224.810891074261, 2282.132789539981, 2493.6882889638796, 2510.661060756811, 3064.9291470403427, 15544.98390933256, 15804.051756894509, 15883.841161588467, 15967.760483592756, 16166.0, 1742.3379115935863, 1808.0, 1870.51366644672, 1917.7698676158811, 1926.0, 109303.0, 110466.0, 112806.43596782045, 117916.41041794822, 118272.0, 10721.892781072245, 10896.78206435867, 10975.341479511255, 11426.143039272089, 11561.42192890352, 13991.16053036821, 14005.509807156905, 14006.509772157926, 14019.088759230617, 14057.85942140586, 7928.8810667840025, 8180.854680344756, 8553.486790792573, 8715.520646364384, 8819.206271435472, 6931.4108300795, 8514.914850851535, 8590.0, 8700.0, 8703.956480217685, 6561.507886908501, 6679.064930909676, 6680.532362734659, 6692.564983276072, 6697.196336439659, 1805.8645601579933, 1842.2999260281276, 1844.2530775036132, 1857.9781120383734, 1880.6332765110672, 797.9441439099207, 964.5755867418454, 980.7940332530106, 1003.5584342889106, 1010.3079390617304, 22917.31248062561, 22922.885385572965, 23097.653535196987, 23185.420364490685, 23191.53616927652, 884.2351366068433, 914.1087439746194, 938.8732521109699, 952.1858810716876, 995.66147509847, 11504.25222360544, 11907.226030308002, 11947.044236461063, 12023.27860328383, 12090.879091209152, 594012.0, 597947.0, 607806.9609652011, 608019.0, 612263.0, 108423.45788271008, 108847.45576272227, 110515.0, 134133.0, 137649.0, 430.39529461107196, 445.1875327527276, 453.99773001135446, 457.59045654139356, 461.342586813789, 10973.615923442641, 11462.713432164262, 11509.827352589551, 11603.767924641628, 11847.822282665938, 50432.24351634802, 52125.478745212065, 54077.72961135169, 56523.43476565346, 59284.40715592961, 78265.0, 85361.57319213364, 85616.57191714001, 86293.56853215945, 88923.0, 5760.798372056958, 5779.075347944308, 7611.543307401574, 8190.221928916982, 8580.270676992526, 12318.938405307916, 13042.478300868239, 13114.540991065267, 13624.386902589606, 13682.452701891812, 11181.888181118085, 11226.607068752555, 11257.943710281395, 11477.253978491372, 11626.593069242535, 11252.774944500901, 11316.434178290889, 11616.883831161918, 11632.011279041377, 11637.01085407716, 11543.653690389301, 11562.47968841421, 11892.286462812259, 11921.582744603897, 11966.760664786829, 8097.311728503143, 8449.577521123796, 8477.40658153948, 8595.140485951355, 8599.054104048675, 10798.406087665255, 11346.184610462287, 11657.475413606579, 11708.004819590431, 11720.0], "value": 9530.6738800391, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1187.9168458207919, 1407.1416435974054, 1519.5669234268237, 1692.6276219231772, 1874.568849164692, 7678.731244406447, 8147.470414423058, 8412.20084092011, 8648.697295594655, 8650.740477785661, 8503.362247831406, 8592.828143437127, 8608.784780380494, 8631.30949524038, 8642.69750558729, 2358.8230882683824, 2380.0836677879056, 2384.1297926256966, 2385.5109702510945, 2386.200622791367, 2344.824138189634, 2365.3377054424786, 2385.6183010718287, 2394.449276666368, 2395.8442701224367, 2354.835161538693, 2380.095563685797, 2386.474975505395, 2390.4501964548163, 2397.0, 2335.0426325206586, 2355.3993731598416, 2366.7514910934447, 2369.3839601703507, 2370.727366352857, 2322.674825524428, 2344.191254017361, 2354.564405584972, 2358.13927916311, 2360.799332056777, 8671.826563468696, 8876.0, 8876.733697989072, 8877.023527411979, 8893.421927574687, 5190.974045129788, 5216.295800067019, 5338.759755810997, 5353.223782551547, 5360.303160589137, 3267.9019629411155, 3520.9647903520895, 3682.502862113634, 3688.2807852468777, 3691.5570131584113, 1093.2511229807528, 1105.4693747001404, 1122.81473556863, 1130.0338210829786, 1134.5915470430639, 11457.828132578017, 11638.534458621645, 11645.94177029118, 11656.184067115306, 11665.76668466626, 4452.198604251266, 4734.124187025412, 4906.9263961040615, 4963.0, 4998.875028124326, 8256.298214651702, 8833.514156721441, 9105.681301154427, 9214.170724634814, 9217.907820921839, 5294.7617357219015, 5302.284191634157, 5303.204519322133, 5306.6550674206055, 5307.840764777063, 4126.834926602963, 4451.443569553806, 4881.487443818419, 4969.503049695001, 4992.975035124801, 3276.1318250663594, 3728.496652951817, 3746.456763769238, 3805.980970095132, 3855.248226595787, 1159.4434671357797, 1169.8537682789652, 1177.5113327968968, 1183.5206741269799, 1189.304257009659, 781.4412694923158, 858.5063588436674, 893.4282059481936, 920.7744102694749, 936.2697096264924, 5401.783928642889, 5654.236678048411, 5716.542676585863, 5934.228550288435, 6084.239470066242, 909.5679552212646, 928.6703220356811, 937.3953799799092, 942.9575669094908, 946.6084855262752, 2949.9970010196635, 3738.158914244273, 3847.403652433821, 3871.322518559241, 3888.3389823730035, 8397.916020839713, 10550.894491055145, 10779.0, 10783.352998819939, 10909.399983000852, 10883.401412922365, 10886.564537418415, 10888.0, 10899.509522071527, 10900.0, 6617.338266173344, 7927.920720792834, 8342.541160236246, 8344.249017588325, 8367.539785311745, 5651.632643878134, 5704.629199102045, 5725.856853578527, 5737.020727848885, 5808.390119037609, 10077.143442807443, 10164.949175254373, 10290.845637315297, 10302.948485257526, 10360.222983276435, 9960.950195248977, 10337.896621033693, 10371.481425928523, 10450.634227801991, 10471.162307015573, 7007.264237255014, 7113.502054856111, 7150.141982962078, 7156.928430715626, 7160.964195179199, 7670.961645191738, 7783.649735761791, 8286.46138001028, 8334.87497687523, 8348.833023339621, 6787.490938179558, 7262.419006479575, 7297.16082650502, 7537.585432801248, 7581.924180758122, 6612.80969425508, 7120.038794762848, 7299.671514781741, 7322.487425880137, 7463.626818658937, 6955.617441040791, 6968.477364197807, 7039.331263529956, 7042.401395881408, 7045.682944267418, 4810.518948105301, 4832.323474713473, 4883.536064073907, 4905.068037072945, 4977.303177555217, 4654.418197725285, 4686.6016388605985, 4743.335932969456, 4799.0, 4923.630727695366], "value": 4516.141999481177, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.37445428464514, 300.65124455631474, 310.1888561411908, 313.72078849823674, 314.55804594544657, 5015.849524514261, 5023.397192336915, 5253.658512196709, 5276.314079169712, 5305.602079844012, 5024.698518088917, 5196.766145523451, 5232.613357460276, 5288.2596436498925, 5310.654807437514, 2921.897733579325, 2987.5668028135906, 3182.8408579570987, 3189.8197666863293, 3921.9410759095026, 3712.721545884056, 3721.967069548049, 4057.411675307079, 4078.632923036926, 4078.632923036926, 3199.6881278675746, 3495.9898058616272, 3735.62643735627, 3943.5662077171633, 4012.2978478766245, 3670.2292518570866, 3826.770393776382, 3971.5829837866895, 3977.622125898035, 4012.558618551957, 3186.8406579670923, 3504.6869066416043, 3514.2620049789575, 3523.612402635708, 3526.924288092131, 3740.9438858417147, 4886.364772579542, 5036.269740887587, 5048.0, 5091.974540127313, 3987.3819557968563, 4066.410370496291, 4518.774061296922, 4523.36672865799, 4531.5921567058795, 5259.0270799901755, 5497.845452454989, 5537.916931246034, 5541.5289700375115, 5560.555155587584, 3466.636003219651, 3575.499430079765, 3788.299164654548, 3797.0, 3799.3351163546267, 4777.965991595362, 5251.6061295403, 5774.82675519735, 5784.971075144639, 5840.386759390245, 3123.406552754969, 3839.7120215983914, 3868.3810590305416, 3869.07142285855, 3874.2445223181207, 3614.2229420674157, 3831.578526362098, 3887.319719049155, 3927.4501569780255, 3939.2712348215673, 4481.619062379734, 4489.6755457140225, 4509.729416235036, 4511.233090374644, 4516.728996260234, 5228.816991405282, 5530.562053866024, 5543.1408131739645, 5549.5837812164245, 5574.0, 1236.2829558855842, 1272.7327261275236, 1304.7148558669703, 1305.9608211753662, 1328.6811165320253, 730.3828265115968, 792.1919641965163, 811.3306522119275, 813.2477458351002, 818.5375262976519, 3702.352088384576, 4172.77049762266, 4317.697761156752, 4344.913101737948, 4345.67407444443, 4311.655067594585, 4396.4284642996845, 4601.7699115044115, 4687.835925742651, 4758.762721692316, 4283.464566929134, 4286.485621725413, 4308.9353659695125, 4320.416743739618, 4328.515544986316, 2165.956680866374, 2369.478714682767, 2513.924582262535, 2516.4463817960386, 2825.6044153818484, 5227.353383684127, 5590.385057643656, 5819.79630712923, 5928.251165906068, 5938.495227905676, 4915.361003069578, 5157.994191132768, 5173.586113110942, 5699.943000569941, 5876.823695289148, 4801.95198048022, 4814.664887529615, 4906.8773280668265, 5067.493250674903, 5537.58468114893, 4564.014653625683, 5639.828665963715, 5688.525491411249, 5735.627184233012, 5756.42435756421, 4912.164931961646, 5122.026814905156, 5426.945730542802, 5607.102863541813, 5627.549796016391, 5546.419270507886, 5839.445252701156, 6002.909956350571, 6004.519638429003, 6011.338752737282, 3362.69735723786, 3971.3447281199433, 4029.6776257899883, 4030.697697672628, 4038.051058001309, 4267.975685835439, 5616.876624674999, 5718.628289161191, 5729.770809167587, 5736.42635736423, 4430.712003719748, 4655.534446555317, 5102.617303702312, 5579.944200558105, 5586.273784408001, 5355.839324820262, 5561.110222364402, 5580.079286917613, 5588.944110559005, 5595.258323421575, 5413.945860541502, 5467.9453205467435, 5482.780688772405, 5486.972565137149, 5490.670559766427, 5107.744612769272, 5568.359638641608, 5572.0, 5580.693061881636, 5595.440455954372, 4521.773911304356, 5195.29863468442, 5457.372402173801, 5490.396056433869, 5530.889382212414], "value": 3912.7913653760224, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [670.9060731497591, 689.6792991259064, 694.9270326615705, 701.551007355293, 713.8001359619309, 15357.616059598502, 16484.01095934245, 16484.423045193435, 16498.752518712216, 16504.83495165048, 14705.485308014222, 14752.483663071795, 14862.554123376289, 14904.776428353556, 14912.627184320398, 872.4852337121097, 878.3587980774007, 879.0111124984393, 880.3793325705394, 881.5195718333491, 692.2973182220051, 889.4440974391006, 889.7597648634865, 895.4000819450983, 896.4576431259093, 742.2651574940796, 902.1159263921361, 934.444005816538, 939.8214339275515, 940.4545363689078, 913.2465715784438, 914.5564401265381, 916.2807196350833, 917.3807679816131, 921.7741653294919, 686.2519853359862, 690.1132045321788, 690.3545185251755, 691.6714560583682, 691.7371398868398, 11931.105167112415, 12395.690107747288, 12425.565105221363, 12432.0, 12445.25328480294, 13687.863121368859, 14667.339969701388, 15035.323410446555, 15055.924720376437, 15209.011414258148, 10644.787104257872, 11778.705532361673, 12003.759924801543, 12057.155999080069, 12081.57714479989, 3827.942580861289, 3872.9031774205587, 3900.258950799339, 3901.6293452122004, 3969.8412063517426, 95835.0, 106949.46525267324, 107608.99999999921, 107619.0, 107781.0, 11278.548858045586, 11407.771844563229, 11418.885811141949, 11427.942860285812, 11909.52361905514, 22853.885730571572, 22864.0, 22889.0, 22922.72777261206, 22934.541309174056, 7954.960225198837, 8034.236747508978, 8258.42191046633, 8263.669453221924, 8281.29608983243, 4279.893002674896, 4440.245158323093, 4490.0, 4520.2089634314525, 4563.931541026887, 3636.381815091388, 3687.8340474678703, 3743.2700623378178, 3755.7183211259266, 3761.680257178116, 674.231376231097, 678.6878036103448, 683.2108914204102, 683.9213490448562, 699.9825004374932, 567.3815541060264, 596.0939372154302, 605.479287812481, 614.1310046284547, 622.5175488995974, 8779.780505487286, 8812.339074569432, 9060.81878362429, 9285.48929808867, 9382.95308523453, 509.297169905528, 564.2692712936757, 592.720987801901, 610.081826850587, 655.1712084213474, 4240.236757383639, 4477.70894891831, 4492.415985921874, 4502.909941801211, 4563.817447302137, 72172.91740623895, 89329.0, 89823.0, 133811.0, 145177.0, 121746.3912680431, 127386.08920866194, 129233.70766292405, 131501.0, 131657.0, 105135.0, 105624.9437505615, 109346.0, 109454.99999999841, 111634.0, 8639.395242333228, 8728.91271087281, 8731.21419072261, 8837.337199710177, 8932.642694292155, 59184.0, 59846.10230846628, 60533.09200362086, 61482.38517614767, 64556.70886582148, 70542.64728676324, 86180.0, 88024.59877006253, 90965.09034909566, 92315.53842230747, 12139.696507587381, 12247.203931744409, 12500.562480313145, 12994.220346779224, 13304.46782128704, 14116.294185290899, 14182.0, 14476.855231447971, 14634.098062115176, 15046.54860354191, 13135.934320328337, 13224.272665003517, 13318.200907945555, 13611.387487562886, 13856.653583660489, 12894.41975111141, 13372.465101396225, 13514.0, 13558.93220533891, 13564.728705426034, 12679.175853569488, 12919.160254583421, 13025.869741302466, 13027.804582931074, 13164.60506184816, 10708.571657133627, 11122.44387780624, 11189.88810111921, 11439.256448330832, 11482.827757583476, 8973.865392018994, 10862.587863577684, 10926.672199834018, 11461.48423320936, 11620.954114129769], "value": 7680.671404215945, "unit": "score"}], "name": "dromaeo_css", "value": 7219.873697996848, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 116.0, 160.0, 168.0, 112.0, 114.0, 108.0, 114.0, 111.0, 112.0], "value": 121.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 110.0, 109.0, 166.0, 106.0, 106.0, 127.0, 104.0, 157.0, 138.0], "value": 126.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 186.0, 185.0, 185.0, 184.0, 184.0, 185.0, 183.0, 182.0, 182.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 57.0, 66.0, 79.0, 58.0, 70.0, 91.0, 90.0, 110.0, 63.0], "value": 76.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 68.0, 103.0, 70.0, 82.0, 68.0, 95.0, 69.0, 67.0, 104.0], "value": 80.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 88.0, 85.0, 88.0, 85.0, 88.0, 87.0, 89.0, 84.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 95.0, 93.0, 94.0, 136.0, 150.0, 116.0, 93.0, 93.0], "value": 105.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 94.0, 87.0, 89.0, 92.0, 128.0, 93.0, 88.0, 91.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 51.0, 52.0, 51.0, 52.0, 55.0, 52.0, 52.0, 54.0, 52.0], "value": 52.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 71.0, 77.0, 75.0, 73.0, 69.0, 70.0, 69.0, 69.0, 70.0], "value": 70.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 85.0, 82.0, 84.0, 83.0, 83.0, 84.0, 88.0, 86.0], "value": 84.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 147.0, 157.0, 150.0, 135.0, 142.0, 144.0, 152.0, 223.0, 138.0], "value": 153.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 168.0, 165.0, 167.0, 178.0, 235.0, 187.0, 179.0, 228.0, 201.0], "value": 187.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 70.0, 84.0, 85.0, 84.0, 67.0, 68.0, 67.0, 67.0], "value": 72.7, "unit": "score"}], "name": "kraken", "value": 1496.8999999999999, "alertThreshold": 2.0}]} 16:16:04 INFO - Return code: 0 16:16:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:16:04 INFO - # TBPL SUCCESS # 16:16:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:16:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:16:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:16:04 INFO - 2016-08-17 16:16:04.937 screenresolution[2242:15407] starting screenresolution argv=screenresolution get 16:16:04 INFO - 2016-08-17 16:16:04.940 screenresolution[2242:15407] Display 0: 1600x1200x32@60 16:16:04 INFO - 2016-08-17 16:16:04.946 screenresolution[2243:15410] starting screenresolution argv=screenresolution list 16:16:04 INFO - Available Modes on Display 0 16:16:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:16:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:16:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:16:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:16:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:16:05 INFO - Intel Iris: 16:16:05 INFO - Chipset Model: Intel Iris 16:16:05 INFO - Type: GPU 16:16:05 INFO - Bus: Built-In 16:16:05 INFO - VRAM (Dynamic, Max): 1536 MB 16:16:05 INFO - Vendor: Intel (0x8086) 16:16:05 INFO - Device ID: 0x0a2e 16:16:05 INFO - Revision ID: 0x0009 16:16:05 INFO - Displays: 16:16:05 INFO - Display: 16:16:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:16:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:16:05 INFO - Display Serial Number: 1600x1200 60 16:16:05 INFO - Main Display: Yes 16:16:05 INFO - Mirror: Off 16:16:05 INFO - Online: Yes 16:16:05 INFO - Rotation: Supported 16:16:05 INFO - Return code: 0 16:16:05 INFO - Running post-action listener: _resource_record_post_action 16:16:05 INFO - [mozharness: 2016-08-17 23:16:05.182123Z] Finished run-tests step (success) 16:16:05 INFO - Running post-run listener: _resource_record_post_run 16:16:05 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 1097728; Write bytes: 708518912; Read time: 12; Write time: 1995 16:16:05 INFO - TinderboxPrint: CPU usage
53.8% 16:16:05 INFO - TinderboxPrint: I/O read bytes / time
1,097,728 / 12 16:16:05 INFO - TinderboxPrint: I/O write bytes / time
708,518,912 / 1,995 16:16:05 INFO - TinderboxPrint: CPU idle
2,241.0 (46.2%) 16:16:05 INFO - TinderboxPrint: CPU system
978.6 (20.2%) 16:16:05 INFO - TinderboxPrint: CPU user
1,633.9 (33.7%) 16:16:05 INFO - TinderboxPrint: Swap in / out
407,015,424 / 0 16:16:05 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 405936640; Write bytes: 403609600; Read time: 30057; Write time: 822 16:16:05 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 1097728; Write bytes: 299195392; Read time: 12; Write time: 1144 16:16:05 INFO - Running post-run listener: _upload_blobber_files 16:16:05 INFO - Blob upload gear active. 16:16:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:16:05 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:16:05 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'] 16:16:05 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 16:16:06 INFO - (blobuploader) - INFO - Open directory for files ... 16:16:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:16:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:16:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:16:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:16:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:16:07 INFO - (blobuploader) - INFO - Done attempting. 16:16:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:16:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:16:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:16:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:16:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:16:08 INFO - (blobuploader) - INFO - Done attempting. 16:16:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:16:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:16:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:16:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:16:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:16:10 INFO - (blobuploader) - INFO - Done attempting. 16:16:10 INFO - (blobuploader) - INFO - Iteration through files over. 16:16:10 INFO - Return code: 0 16:16:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:16:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:16:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c1f332ec70673e615190bb030daffedace7c0da8144b3cc3adb43d53f45d8acbb500c3d9cdea7abe83d57c95e096867a7d085bd777f7ddf7ead623cd67a89296", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/54923f88c6dab727e01db66159d663b3b04f599ddcae7ee10bf13259a1917ce842d38e174267c7357c997b6f717f625980e7cc323d899f29f81d88e9a19a033b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b5c16873b529613a94d4728adc3a09c502e493e26edff03e01a7140cf1dfd2af357e0e1f85f5ac6c84a1ebbdb52a8ef93a9db31f5a4682b3fcb12401dd5a9287"} 16:16:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:16:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:16:10 INFO - Contents: 16:16:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c1f332ec70673e615190bb030daffedace7c0da8144b3cc3adb43d53f45d8acbb500c3d9cdea7abe83d57c95e096867a7d085bd777f7ddf7ead623cd67a89296", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/54923f88c6dab727e01db66159d663b3b04f599ddcae7ee10bf13259a1917ce842d38e174267c7357c997b6f717f625980e7cc323d899f29f81d88e9a19a033b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b5c16873b529613a94d4728adc3a09c502e493e26edff03e01a7140cf1dfd2af357e0e1f85f5ac6c84a1ebbdb52a8ef93a9db31f5a4682b3fcb12401dd5a9287"} 16:16:10 INFO - Running post-run listener: copy_logs_to_upload_dir 16:16:10 INFO - Copying logs to upload dir... 16:16:10 INFO - mkdir: /builds/slave/test/build/upload/logs 16:16:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.617383 ========= master_lag: 2.21 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2016-08-17 16:16:12.817335) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-17 16:16:12.821167) ========= 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.l39pVwyRBI/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.hgrEww7IpI/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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/c1f332ec70673e615190bb030daffedace7c0da8144b3cc3adb43d53f45d8acbb500c3d9cdea7abe83d57c95e096867a7d085bd777f7ddf7ead623cd67a89296", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/54923f88c6dab727e01db66159d663b3b04f599ddcae7ee10bf13259a1917ce842d38e174267c7357c997b6f717f625980e7cc323d899f29f81d88e9a19a033b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b5c16873b529613a94d4728adc3a09c502e493e26edff03e01a7140cf1dfd2af357e0e1f85f5ac6c84a1ebbdb52a8ef93a9db31f5a4682b3fcb12401dd5a9287"} build_url:https://queue.taskcluster.net/v1/task/CfxpWI7-SOiEtAB8H_6i1w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011226 build_url: 'https://queue.taskcluster.net/v1/task/CfxpWI7-SOiEtAB8H_6i1w/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/c1f332ec70673e615190bb030daffedace7c0da8144b3cc3adb43d53f45d8acbb500c3d9cdea7abe83d57c95e096867a7d085bd777f7ddf7ead623cd67a89296", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/54923f88c6dab727e01db66159d663b3b04f599ddcae7ee10bf13259a1917ce842d38e174267c7357c997b6f717f625980e7cc323d899f29f81d88e9a19a033b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b5c16873b529613a94d4728adc3a09c502e493e26edff03e01a7140cf1dfd2af357e0e1f85f5ac6c84a1ebbdb52a8ef93a9db31f5a4682b3fcb12401dd5a9287"}' ========= master_lag: 0.63 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-17 16:16:13.467243) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 16:16:13.467596) ========= 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.l39pVwyRBI/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.hgrEww7IpI/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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.005246 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 16:16:13.496631) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-17 16:16:13.500082) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-17 16:16:13.500753) ========= ========= Total master_lag: 3.02 =========