builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0233 starttime: 1471363859.24 results: success (0) buildid: 20160816064524 builduid: c740452496e542e1ba837ffba19bae79 revision: 174ca5f954197eda591348e87b1d8abb3404013e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 09:10:59.244828) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 09:10:59.245260) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 09:10:59.245534) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 09:10:59.268964) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 09:10:59.269246) ========= 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.hhLpyb1K6w/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.S0wNs5DtCi/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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-16 09:10:59-- 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% 8.10M=0.001s 2016-08-16 09:10:59 (8.10 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.128052 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 09:10:59.419020) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 09:10:59.419329) ========= 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.hhLpyb1K6w/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.S0wNs5DtCi/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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.020953 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 09:10:59.467323) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 09:10:59.467655) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 174ca5f954197eda591348e87b1d8abb3404013e --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 174ca5f954197eda591348e87b1d8abb3404013e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hhLpyb1K6w/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.S0wNs5DtCi/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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-16 09:10:59,549 truncating revision to first 12 chars 2016-08-16 09:10:59,549 Setting DEBUG logging. 2016-08-16 09:10:59,549 attempt 1/10 2016-08-16 09:10:59,550 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/174ca5f95419?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-16 09:10:59,884 unpacking tar archive at: autoland-174ca5f95419/testing/mozharness/ program finished with exit code 0 elapsedTime=0.600651 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 09:11:00.090501) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 09:11:00.090830) ========= script_repo_revision: 174ca5f954197eda591348e87b1d8abb3404013e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 09:11:00.091224) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 09:11:00.091499) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 09:11:00.109760) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-16 09:11:00.110038) ========= /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.hhLpyb1K6w/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.S0wNs5DtCi/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:11:00 INFO - MultiFileLogger online at 20160816 09:11:00 in /builds/slave/test 09:11:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 09:11:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:11:00 INFO - {'append_to_log': False, 09:11:00 INFO - 'base_work_dir': '/builds/slave/test', 09:11:00 INFO - 'blob_upload_branch': 'Autoland', 09:11:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:11:00 INFO - 'branch': 'Autoland', 09:11:00 INFO - 'buildbot_json_path': 'buildprops.json', 09:11:00 INFO - 'config_files': ('talos/mac_config.py',), 09:11:00 INFO - 'default_actions': ('clobber', 09:11:00 INFO - 'read-buildbot-config', 09:11:00 INFO - 'download-and-extract', 09:11:00 INFO - 'populate-webroot', 09:11:00 INFO - 'create-virtualenv', 09:11:00 INFO - 'install', 09:11:00 INFO - 'run-tests'), 09:11:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:11:00 INFO - 'download_minidump_stackwalk': True, 09:11:00 INFO - 'download_symbols': 'ondemand', 09:11:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:11:00 INFO - 'tooltool.py': '/tools/tooltool.py', 09:11:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:11:00 INFO - '/tools/misc-python/virtualenv.py')}, 09:11:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:11:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:11:00 INFO - 'installer_path': 'installer.exe', 09:11:00 INFO - 'log_level': 'info', 09:11:00 INFO - 'log_name': 'talos', 09:11:00 INFO - 'log_to_console': True, 09:11:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:11:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:11:00 INFO - 'opt_config_files': (), 09:11:00 INFO - 'pip_index': False, 09:11:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:11:00 INFO - 'cmd': ('bash', 09:11:00 INFO - '-c', 09:11:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:11:00 INFO - 'enabled': True, 09:11:00 INFO - 'halt_on_failure': False, 09:11:00 INFO - 'name': 'check_screen_resolution'},), 09:11:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:11:00 INFO - 'cmd': ('bash', 09:11:00 INFO - '-c', 09:11:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:11:00 INFO - 'enabled': True, 09:11:00 INFO - 'halt_on_failure': False, 09:11:00 INFO - 'name': 'check_screen_resolution'},), 09:11:00 INFO - 'run_cmd_checks_enabled': True, 09:11:00 INFO - 'sps_profile': False, 09:11:00 INFO - 'sps_profile_interval': 0, 09:11:00 INFO - 'suite': 'dromaeojs', 09:11:00 INFO - 'system_bits': '32', 09:11:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:11:00 INFO - 'title': 't-yosemite-r7-0233', 09:11:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:11:00 INFO - 'use_talos_json': True, 09:11:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:11:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:11:00 INFO - 'work_dir': 'build'} 09:11:00 INFO - [mozharness: 2016-08-16 16:11:00.304028Z] Running clobber step. 09:11:00 INFO - Running pre-action listener: _resource_record_pre_action 09:11:00 INFO - Running main action method: clobber 09:11:00 INFO - rmtree: /builds/slave/test/build 09:11:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:11:01 INFO - Running post-action listener: _resource_record_post_action 09:11:01 INFO - [mozharness: 2016-08-16 16:11:01.738448Z] Finished clobber step (success) 09:11:01 INFO - [mozharness: 2016-08-16 16:11:01.738601Z] Running read-buildbot-config step. 09:11:01 INFO - Running pre-action listener: _resource_record_pre_action 09:11:01 INFO - Running main action method: read_buildbot_config 09:11:01 INFO - Using buildbot properties: 09:11:01 INFO - { 09:11:01 INFO - "project": "", 09:11:01 INFO - "product": "firefox", 09:11:01 INFO - "script_repo_revision": "production", 09:11:01 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 09:11:01 INFO - "repository": "", 09:11:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 09:11:01 INFO - "buildid": "20160816064524", 09:11:01 INFO - "pgo_build": "False", 09:11:01 INFO - "basedir": "/builds/slave/test", 09:11:01 INFO - "buildnumber": 119, 09:11:01 INFO - "slavename": "t-yosemite-r7-0233", 09:11:01 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 09:11:01 INFO - "platform": "yosemite_r7", 09:11:01 INFO - "scheduler": "tests-autoland-macosx64-talos", 09:11:01 INFO - "branch": "autoland", 09:11:01 INFO - "repo_path": "integration/autoland", 09:11:01 INFO - "revision": "174ca5f954197eda591348e87b1d8abb3404013e", 09:11:01 INFO - "stage_platform": "macosx64", 09:11:01 INFO - "builduid": "c740452496e542e1ba837ffba19bae79", 09:11:01 INFO - "slavebuilddir": "test" 09:11:01 INFO - } 09:11:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/SdYeoaMeQ1WRpL3V7jRRRw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 09:11:01 INFO - Running post-action listener: _resource_record_post_action 09:11:01 INFO - [mozharness: 2016-08-16 16:11:01.741991Z] Finished read-buildbot-config step (success) 09:11:01 INFO - [mozharness: 2016-08-16 16:11:01.742122Z] Running download-and-extract step. 09:11:01 INFO - Running pre-action listener: _resource_record_pre_action 09:11:01 INFO - Running main action method: download_and_extract 09:11:01 INFO - mkdir: /builds/slave/test/build/tests 09:11:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:01 INFO - trying https://queue.taskcluster.net/v1/task/SdYeoaMeQ1WRpL3V7jRRRw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 09:11:01 INFO - Downloading https://queue.taskcluster.net/v1/task/SdYeoaMeQ1WRpL3V7jRRRw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 09:11:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SdYeoaMeQ1WRpL3V7jRRRw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 09:11:03 INFO - Downloaded 1199 bytes. 09:11:03 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 09:11:03 INFO - Using the following test package requirements: 09:11:03 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:11:03 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:11:03 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 09:11:03 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:11:03 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 09:11:03 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:11:03 INFO - u'jsshell-mac.zip'], 09:11:03 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:11:03 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 09:11:03 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:11:03 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:11:03 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 09:11:03 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:11:03 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 09:11:03 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:11:03 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 09:11:03 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:11:03 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 09:11:03 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:11:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:03 INFO - trying https://queue.taskcluster.net/v1/task/SdYeoaMeQ1WRpL3V7jRRRw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:11:03 INFO - Downloading https://queue.taskcluster.net/v1/task/SdYeoaMeQ1WRpL3V7jRRRw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:11:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SdYeoaMeQ1WRpL3V7jRRRw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:11:03 INFO - Downloaded 16333628 bytes. 09:11:03 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 09:11:10 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:11:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:10 INFO - trying https://queue.taskcluster.net/v1/task/SdYeoaMeQ1WRpL3V7jRRRw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:11:10 INFO - Downloading https://queue.taskcluster.net/v1/task/SdYeoaMeQ1WRpL3V7jRRRw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:11:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SdYeoaMeQ1WRpL3V7jRRRw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:11:11 INFO - Downloaded 16333628 bytes. 09:11:11 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 09:11:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:18 INFO - trying https://queue.taskcluster.net/v1/task/SdYeoaMeQ1WRpL3V7jRRRw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 09:11:18 INFO - Downloading https://queue.taskcluster.net/v1/task/SdYeoaMeQ1WRpL3V7jRRRw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 09:11:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SdYeoaMeQ1WRpL3V7jRRRw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:11:19 INFO - Downloaded 14413719 bytes. 09:11:19 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 09:11:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:19 INFO - trying https://queue.taskcluster.net/v1/task/SdYeoaMeQ1WRpL3V7jRRRw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:11:19 INFO - Downloading https://queue.taskcluster.net/v1/task/SdYeoaMeQ1WRpL3V7jRRRw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 09:11:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SdYeoaMeQ1WRpL3V7jRRRw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 09:11:22 INFO - Downloaded 110996800 bytes. 09:11:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SdYeoaMeQ1WRpL3V7jRRRw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:11:22 INFO - mkdir: /builds/slave/test/properties 09:11:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:11:22 INFO - Writing to file /builds/slave/test/properties/build_url 09:11:22 INFO - Contents: 09:11:22 INFO - build_url:https://queue.taskcluster.net/v1/task/SdYeoaMeQ1WRpL3V7jRRRw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:11:23 INFO - Running post-action listener: _resource_record_post_action 09:11:23 INFO - Running post-action listener: set_extra_try_arguments 09:11:23 INFO - [mozharness: 2016-08-16 16:11:23.375430Z] Finished download-and-extract step (success) 09:11:23 INFO - [mozharness: 2016-08-16 16:11:23.375557Z] Running populate-webroot step. 09:11:23 INFO - Running pre-action listener: _resource_record_pre_action 09:11:23 INFO - Running main action method: populate_webroot 09:11:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:11:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:11:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:11:23 INFO - '--tppagecycles', 09:11:23 INFO - '7'], 09:11:23 INFO - 'tests': ['tsvgm']}}, 09:11:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:11:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:11:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:11:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:11:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:11:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:11:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:11:23 INFO - 'talos_options': ['--disable-e10s'], 09:11:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:11:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:11:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:11:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:11:23 INFO - 'talos_options': ['--disable-e10s'], 09:11:23 INFO - 'tests': ['damp', 'tps']}, 09:11:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:11:23 INFO - 'tests': ['damp', 'tps']}, 09:11:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:11:23 INFO - 'tests': ['dromaeo_dom']}, 09:11:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:11:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:11:23 INFO - 'tests': ['basic_compositor_video']}, 09:11:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:11:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:11:23 INFO - 'tests': ['a11yr', 09:11:23 INFO - 'ts_paint', 09:11:23 INFO - 'tpaint', 09:11:23 INFO - 'sessionrestore', 09:11:23 INFO - 'sessionrestore_no_auto_restore', 09:11:23 INFO - 'tabpaint']}, 09:11:23 INFO - 'other-e10s': {'tests': ['a11yr', 09:11:23 INFO - 'ts_paint', 09:11:23 INFO - 'tpaint', 09:11:23 INFO - 'sessionrestore', 09:11:23 INFO - 'sessionrestore_no_auto_restore', 09:11:23 INFO - 'tabpaint']}, 09:11:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:11:23 INFO - 'ts_paint', 09:11:23 INFO - 'tpaint', 09:11:23 INFO - 'sessionrestore', 09:11:23 INFO - 'sessionrestore_no_auto_restore', 09:11:23 INFO - 'tabpaint']}, 09:11:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:11:23 INFO - 'ts_paint', 09:11:23 INFO - 'tpaint', 09:11:23 INFO - 'sessionrestore', 09:11:23 INFO - 'sessionrestore_no_auto_restore', 09:11:23 INFO - 'tabpaint']}, 09:11:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:11:23 INFO - 'tests': ['a11yr', 09:11:23 INFO - 'ts_paint', 09:11:23 INFO - 'tpaint', 09:11:23 INFO - 'sessionrestore', 09:11:23 INFO - 'sessionrestore_no_auto_restore', 09:11:23 INFO - 'tabpaint']}, 09:11:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:11:23 INFO - 'tests': ['a11yr', 09:11:23 INFO - 'ts_paint', 09:11:23 INFO - 'tpaint', 09:11:23 INFO - 'sessionrestore', 09:11:23 INFO - 'sessionrestore_no_auto_restore', 09:11:23 INFO - 'tabpaint']}, 09:11:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:11:23 INFO - 'tests': ['tsvgx', 09:11:23 INFO - 'tsvgr_opacity', 09:11:23 INFO - 'tart', 09:11:23 INFO - 'tscrollx', 09:11:23 INFO - 'cart']}, 09:11:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:11:23 INFO - 'tsvgr_opacity', 09:11:23 INFO - 'tart', 09:11:23 INFO - 'tscrollx', 09:11:23 INFO - 'cart']}, 09:11:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:11:23 INFO - 'talos_options': ['--disable-e10s'], 09:11:23 INFO - 'tests': ['tp5o']}, 09:11:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:11:23 INFO - 'tests': ['tp5o']}, 09:11:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:11:23 INFO - 'talos_options': ['--disable-e10s', 09:11:23 INFO - '--xperf_path', 09:11:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:11:23 INFO - 'tests': ['tp5n']}, 09:11:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:11:23 INFO - 'talos_options': ['--xperf_path', 09:11:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:11:23 INFO - 'tests': ['tp5n']}}, 09:11:23 INFO - 'talos.zip': {'path': '', 09:11:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:11:23 INFO - Running post-action listener: _resource_record_post_action 09:11:23 INFO - [mozharness: 2016-08-16 16:11:23.394279Z] Finished populate-webroot step (success) 09:11:23 INFO - [mozharness: 2016-08-16 16:11:23.394411Z] Running create-virtualenv step. 09:11:23 INFO - Running pre-action listener: _resource_record_pre_action 09:11:23 INFO - Running main action method: create_virtualenv 09:11:23 INFO - Creating virtualenv /builds/slave/test/build/venv 09:11:23 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:11:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:11:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:11:23 INFO - Using real prefix '/tools/python27' 09:11:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:11:24 INFO - Installing distribute.............................................................................................................................................................................................done. 09:11:27 INFO - Installing pip.................done. 09:11:27 INFO - Return code: 0 09:11:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:11:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:27 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 0x10c7e2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c877618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdf0f00340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c746d30>, '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 0x10c4abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c73eb20>, '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.hhLpyb1K6w/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.S0wNs5DtCi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:11:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:11:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:11:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hhLpyb1K6w/Render', 09:11:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:11:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:11:27 INFO - 'HOME': '/Users/cltbld', 09:11:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:11:27 INFO - 'LOGNAME': 'cltbld', 09:11:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:27 INFO - 'MOZ_NO_REMOTE': '1', 09:11:27 INFO - 'NO_EM_RESTART': '1', 09:11:27 INFO - 'PAGER': '/bin/cat', 09:11:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:11:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:27 INFO - 'PWD': '/builds/slave/test', 09:11:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:11:27 INFO - 'SHELL': '/bin/bash', 09:11:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0wNs5DtCi/Listeners', 09:11:27 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 09:11:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:11:27 INFO - 'USER': 'cltbld', 09:11:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:11:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:11:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:27 INFO - 'XPC_FLAGS': '0x0', 09:11:27 INFO - 'XPC_SERVICE_NAME': '0', 09:11:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:11:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:27 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:11:27 INFO - Cleaning up... 09:11:27 INFO - Return code: 0 09:11:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:11:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:27 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 0x10c7e2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c877618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdf0f00340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c746d30>, '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 0x10c4abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c73eb20>, '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.hhLpyb1K6w/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.S0wNs5DtCi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:11:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:11:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:11:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hhLpyb1K6w/Render', 09:11:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:11:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:11:27 INFO - 'HOME': '/Users/cltbld', 09:11:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:11:27 INFO - 'LOGNAME': 'cltbld', 09:11:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:27 INFO - 'MOZ_NO_REMOTE': '1', 09:11:27 INFO - 'NO_EM_RESTART': '1', 09:11:27 INFO - 'PAGER': '/bin/cat', 09:11:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:11:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:27 INFO - 'PWD': '/builds/slave/test', 09:11:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:11:27 INFO - 'SHELL': '/bin/bash', 09:11:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0wNs5DtCi/Listeners', 09:11:27 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 09:11:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:11:27 INFO - 'USER': 'cltbld', 09:11:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:11:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:11:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:27 INFO - 'XPC_FLAGS': '0x0', 09:11:27 INFO - 'XPC_SERVICE_NAME': '0', 09:11:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:11:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:27 INFO - Downloading/unpacking psutil>=3.1.1 09:11:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:11:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:11:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:11:30 INFO - Installing collected packages: psutil 09:11:30 INFO - Running setup.py install for psutil 09:11:30 INFO - building 'psutil._psutil_osx' extension 09:11:30 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:11:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:11:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:11:31 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:11:31 INFO - building 'psutil._psutil_posix' extension 09:11:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:11:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:11:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:11:31 INFO - ^ 09:11:31 INFO - 1 warning generated. 09:11:31 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:11:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:11:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:11:31 INFO - Successfully installed psutil 09:11:31 INFO - Cleaning up... 09:11:31 INFO - Return code: 0 09:11:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:11:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:31 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 0x10c7e2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c877618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdf0f00340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c746d30>, '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 0x10c4abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c73eb20>, '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.hhLpyb1K6w/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.S0wNs5DtCi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:11:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:11:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:11:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hhLpyb1K6w/Render', 09:11:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:11:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:11:31 INFO - 'HOME': '/Users/cltbld', 09:11:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:11:31 INFO - 'LOGNAME': 'cltbld', 09:11:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:31 INFO - 'MOZ_NO_REMOTE': '1', 09:11:31 INFO - 'NO_EM_RESTART': '1', 09:11:31 INFO - 'PAGER': '/bin/cat', 09:11:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:11:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:31 INFO - 'PWD': '/builds/slave/test', 09:11:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:11:31 INFO - 'SHELL': '/bin/bash', 09:11:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0wNs5DtCi/Listeners', 09:11:31 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 09:11:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:11:31 INFO - 'USER': 'cltbld', 09:11:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:11:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:11:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:31 INFO - 'XPC_FLAGS': '0x0', 09:11:31 INFO - 'XPC_SERVICE_NAME': '0', 09:11:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:11:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:11: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 09:11: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 09:11: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 09:11: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 09:11: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 09:11: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 09:11:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:11:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:11:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:11:34 INFO - Installing collected packages: mozsystemmonitor 09:11:34 INFO - Running setup.py install for mozsystemmonitor 09:11:34 INFO - Successfully installed mozsystemmonitor 09:11:34 INFO - Cleaning up... 09:11:34 INFO - Return code: 0 09:11:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:34 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 0x10c7e2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c877618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdf0f00340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c746d30>, '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 0x10c4abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c73eb20>, '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.hhLpyb1K6w/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.S0wNs5DtCi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:11:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:11:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:11:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hhLpyb1K6w/Render', 09:11:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:11:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:11:34 INFO - 'HOME': '/Users/cltbld', 09:11:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:11:34 INFO - 'LOGNAME': 'cltbld', 09:11:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:34 INFO - 'MOZ_NO_REMOTE': '1', 09:11:34 INFO - 'NO_EM_RESTART': '1', 09:11:34 INFO - 'PAGER': '/bin/cat', 09:11:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:11:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:34 INFO - 'PWD': '/builds/slave/test', 09:11:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:11:34 INFO - 'SHELL': '/bin/bash', 09:11:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0wNs5DtCi/Listeners', 09:11:34 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 09:11:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:11:34 INFO - 'USER': 'cltbld', 09:11:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:11:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:11:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:34 INFO - 'XPC_FLAGS': '0x0', 09:11:34 INFO - 'XPC_SERVICE_NAME': '0', 09:11:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:11:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:34 INFO - Downloading/unpacking jsonschema==2.5.1 09:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:11:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:11:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:37 INFO - Downloading functools32-3.2.3-2.tar.gz 09:11:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:11:37 INFO - warning: no files found matching '*.txt' 09:11:37 INFO - no previously-included directories found matching 'build' 09:11:37 INFO - no previously-included directories found matching 'dist' 09:11:37 INFO - no previously-included directories found matching '.git*' 09:11:37 INFO - Installing collected packages: jsonschema, functools32 09:11:37 INFO - Running setup.py install for functools32 09:11:38 INFO - warning: no files found matching '*.txt' 09:11:38 INFO - no previously-included directories found matching 'build' 09:11:38 INFO - no previously-included directories found matching 'dist' 09:11:38 INFO - no previously-included directories found matching '.git*' 09:11:38 INFO - Successfully installed jsonschema functools32 09:11:38 INFO - Cleaning up... 09:11:38 INFO - Return code: 0 09:11:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:11:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:38 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 0x10c7e2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c877618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdf0f00340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c746d30>, '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 0x10c4abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c73eb20>, '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.hhLpyb1K6w/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.S0wNs5DtCi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:11:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:11:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:11:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hhLpyb1K6w/Render', 09:11:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:11:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:11:38 INFO - 'HOME': '/Users/cltbld', 09:11:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:11:38 INFO - 'LOGNAME': 'cltbld', 09:11:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:38 INFO - 'MOZ_NO_REMOTE': '1', 09:11:38 INFO - 'NO_EM_RESTART': '1', 09:11:38 INFO - 'PAGER': '/bin/cat', 09:11:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:11:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:38 INFO - 'PWD': '/builds/slave/test', 09:11:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:11:38 INFO - 'SHELL': '/bin/bash', 09:11:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0wNs5DtCi/Listeners', 09:11:38 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 09:11:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:11:38 INFO - 'USER': 'cltbld', 09:11:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:11:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:11:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:38 INFO - 'XPC_FLAGS': '0x0', 09:11:38 INFO - 'XPC_SERVICE_NAME': '0', 09:11:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:11:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:11:38 INFO - Cleaning up... 09:11:38 INFO - Return code: 0 09:11:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:11:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:38 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 0x10c7e2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c877618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdf0f00340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c746d30>, '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 0x10c4abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c73eb20>, '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.hhLpyb1K6w/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.S0wNs5DtCi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:11:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:11:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:11:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hhLpyb1K6w/Render', 09:11:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:11:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:11:38 INFO - 'HOME': '/Users/cltbld', 09:11:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:11:38 INFO - 'LOGNAME': 'cltbld', 09:11:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:38 INFO - 'MOZ_NO_REMOTE': '1', 09:11:38 INFO - 'NO_EM_RESTART': '1', 09:11:38 INFO - 'PAGER': '/bin/cat', 09:11:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:11:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:38 INFO - 'PWD': '/builds/slave/test', 09:11:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:11:38 INFO - 'SHELL': '/bin/bash', 09:11:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0wNs5DtCi/Listeners', 09:11:38 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 09:11:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:11:38 INFO - 'USER': 'cltbld', 09:11:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:11:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:11:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:38 INFO - 'XPC_FLAGS': '0x0', 09:11:38 INFO - 'XPC_SERVICE_NAME': '0', 09:11:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:11:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:38 INFO - Downloading/unpacking blobuploader==1.2.4 09:11: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 09:11: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 09:11: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 09:11: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 09:11: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 09:11: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 09:11:41 INFO - Downloading blobuploader-1.2.4.tar.gz 09:11:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:11:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:11:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:11:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:11:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:42 INFO - Downloading docopt-0.6.1.tar.gz 09:11:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:11:42 INFO - Installing collected packages: blobuploader, requests, docopt 09:11:42 INFO - Running setup.py install for blobuploader 09:11:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:11:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:11:42 INFO - Running setup.py install for requests 09:11:42 INFO - Running setup.py install for docopt 09:11:43 INFO - Successfully installed blobuploader requests docopt 09:11:43 INFO - Cleaning up... 09:11:43 INFO - Return code: 0 09:11:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:11:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:43 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 0x10c7e2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c877618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdf0f00340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c746d30>, '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 0x10c4abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c73eb20>, '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.hhLpyb1K6w/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.S0wNs5DtCi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:11:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:11:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:11:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hhLpyb1K6w/Render', 09:11:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:11:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:11:43 INFO - 'HOME': '/Users/cltbld', 09:11:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:11:43 INFO - 'LOGNAME': 'cltbld', 09:11:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:43 INFO - 'MOZ_NO_REMOTE': '1', 09:11:43 INFO - 'NO_EM_RESTART': '1', 09:11:43 INFO - 'PAGER': '/bin/cat', 09:11:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:11:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:43 INFO - 'PWD': '/builds/slave/test', 09:11:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:11:43 INFO - 'SHELL': '/bin/bash', 09:11:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0wNs5DtCi/Listeners', 09:11:43 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 09:11:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:11:43 INFO - 'USER': 'cltbld', 09:11:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:11:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:11:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:43 INFO - 'XPC_FLAGS': '0x0', 09:11:43 INFO - 'XPC_SERVICE_NAME': '0', 09:11:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:11:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:11:43 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-webepD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:11:43 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-ud5Ysz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:11:43 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-_EEzfp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:11:43 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-xXJk2r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:11:43 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-ykmzAO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:11:44 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-EkoYLV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:11:44 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-SCNrDv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:11:44 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-wjeHD1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:11:44 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-FAeNI_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:11:44 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-ipqOmy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:11:44 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-UiTcjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:11:44 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-an9Aej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:11:44 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-ShA5sW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:11:44 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-OguNLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:44 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-uMmNnG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:11:45 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-vn6JbW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:11:45 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-n6cINT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:11:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:11:45 INFO - Running setup.py install for manifestparser 09:11:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:11:45 INFO - Running setup.py install for mozcrash 09:11:45 INFO - Running setup.py install for mozdebug 09:11:45 INFO - Running setup.py install for mozdevice 09:11:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:11:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:11:45 INFO - Running setup.py install for mozfile 09:11:45 INFO - Running setup.py install for mozhttpd 09:11:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:11:46 INFO - Running setup.py install for mozinfo 09:11:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:11:46 INFO - Running setup.py install for mozInstall 09:11:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:11:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:11:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:11:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:11:46 INFO - Running setup.py install for mozleak 09:11:46 INFO - Running setup.py install for mozlog 09:11:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:11:46 INFO - Running setup.py install for moznetwork 09:11:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:11:46 INFO - Running setup.py install for mozprocess 09:11:47 INFO - Running setup.py install for mozprofile 09:11:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:11:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:11:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:11:47 INFO - Running setup.py install for mozrunner 09:11:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:11:47 INFO - Running setup.py install for mozscreenshot 09:11:47 INFO - Running setup.py install for moztest 09:11:47 INFO - Running setup.py install for mozversion 09:11:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:11:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:11:47 INFO - Cleaning up... 09:11:47 INFO - Return code: 0 09:11:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:11:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:47 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 0x10c7e2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c877618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdf0f00340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c746d30>, '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 0x10c4abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c73eb20>, '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.hhLpyb1K6w/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.S0wNs5DtCi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:11:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:11:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:11:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hhLpyb1K6w/Render', 09:11:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:11:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:11:47 INFO - 'HOME': '/Users/cltbld', 09:11:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:11:47 INFO - 'LOGNAME': 'cltbld', 09:11:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:47 INFO - 'MOZ_NO_REMOTE': '1', 09:11:47 INFO - 'NO_EM_RESTART': '1', 09:11:47 INFO - 'PAGER': '/bin/cat', 09:11:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:11:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:47 INFO - 'PWD': '/builds/slave/test', 09:11:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:11:47 INFO - 'SHELL': '/bin/bash', 09:11:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0wNs5DtCi/Listeners', 09:11:47 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 09:11:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:11:47 INFO - 'USER': 'cltbld', 09:11:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:11:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:11:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:47 INFO - 'XPC_FLAGS': '0x0', 09:11:47 INFO - 'XPC_SERVICE_NAME': '0', 09:11:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:11:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:11:48 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-S0goFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:11:48 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:11:48 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-yP1vy0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:11:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:11:48 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-rEOiyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:11:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:11:48 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-wCG4Wx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:11:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:11:48 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-ZM1ZtV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:11:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:11:48 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-pyxI3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:11:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:11:48 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-XzrhFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:11:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:11:48 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-OQUypT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:11:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:11:48 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-c0QRyc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:11:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:11:49 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-qCqH0l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:11:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:11:49 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-yIT9Qh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:11:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:11:49 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-z_eGlu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:11:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:11:49 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-ZRQ2bV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:11:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:11:49 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-7xjRX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:11:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:49 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-WbtqO3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:11:49 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-IO_j__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:11:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:11:49 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-RagqDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:11:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:11:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:11:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:11:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:11:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:11:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:11:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:11:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:52 INFO - Downloading blessings-1.6.tar.gz 09:11:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:11:52 INFO - Installing collected packages: blessings 09:11:52 INFO - Running setup.py install for blessings 09:11:53 INFO - Successfully installed blessings 09:11:53 INFO - Cleaning up... 09:11:53 INFO - Return code: 0 09:11:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:11:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:11:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:11:53 INFO - Reading from file tmpfile_stdout 09:11:53 INFO - Current package versions: 09:11:53 INFO - blessings == 1.6 09:11:53 INFO - blobuploader == 1.2.4 09:11:53 INFO - docopt == 0.6.1 09:11:53 INFO - functools32 == 3.2.3-2 09:11:53 INFO - jsonschema == 2.5.1 09:11:53 INFO - manifestparser == 1.1 09:11:53 INFO - mozInstall == 1.12 09:11:53 INFO - mozcrash == 0.17 09:11:53 INFO - mozdebug == 0.1 09:11:53 INFO - mozdevice == 0.48 09:11:53 INFO - mozfile == 1.2 09:11:53 INFO - mozhttpd == 0.7 09:11:53 INFO - mozinfo == 0.9 09:11:53 INFO - mozleak == 0.1 09:11:53 INFO - mozlog == 3.3 09:11:53 INFO - moznetwork == 0.27 09:11:53 INFO - mozprocess == 0.23 09:11:53 INFO - mozprofile == 0.28 09:11:53 INFO - mozrunner == 6.12 09:11:53 INFO - mozscreenshot == 0.1 09:11:53 INFO - mozsystemmonitor == 0.3 09:11:53 INFO - moztest == 0.7 09:11:53 INFO - mozversion == 1.4 09:11:53 INFO - psutil == 3.1.1 09:11:53 INFO - requests == 1.2.3 09:11:53 INFO - wsgiref == 0.1.2 09:11:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:11:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:53 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 0x10c7e2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c877618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdf0f00340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c746d30>, '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 0x10c4abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c73eb20>, '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.hhLpyb1K6w/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.S0wNs5DtCi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:11:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:11:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:11:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hhLpyb1K6w/Render', 09:11:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:11:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:11:53 INFO - 'HOME': '/Users/cltbld', 09:11:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:11:53 INFO - 'LOGNAME': 'cltbld', 09:11:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:53 INFO - 'MOZ_NO_REMOTE': '1', 09:11:53 INFO - 'NO_EM_RESTART': '1', 09:11:53 INFO - 'PAGER': '/bin/cat', 09:11:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:11:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:53 INFO - 'PWD': '/builds/slave/test', 09:11:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:11:53 INFO - 'SHELL': '/bin/bash', 09:11:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0wNs5DtCi/Listeners', 09:11:53 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 09:11:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:11:53 INFO - 'USER': 'cltbld', 09:11:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:11:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:11:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:53 INFO - 'XPC_FLAGS': '0x0', 09:11:53 INFO - 'XPC_SERVICE_NAME': '0', 09:11:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:11:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:11:54 INFO - Cleaning up... 09:11:54 INFO - Return code: 0 09:11:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:11:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:54 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 0x10c7e2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c877618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdf0f00340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c746d30>, '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 0x10c4abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c73eb20>, '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.hhLpyb1K6w/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.S0wNs5DtCi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:11:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:11:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:11:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hhLpyb1K6w/Render', 09:11:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:11:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:11:54 INFO - 'HOME': '/Users/cltbld', 09:11:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:11:54 INFO - 'LOGNAME': 'cltbld', 09:11:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:54 INFO - 'MOZ_NO_REMOTE': '1', 09:11:54 INFO - 'NO_EM_RESTART': '1', 09:11:54 INFO - 'PAGER': '/bin/cat', 09:11:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:11:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:54 INFO - 'PWD': '/builds/slave/test', 09:11:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:11:54 INFO - 'SHELL': '/bin/bash', 09:11:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0wNs5DtCi/Listeners', 09:11:54 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 09:11:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:11:54 INFO - 'USER': 'cltbld', 09:11:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:11:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:11:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:54 INFO - 'XPC_FLAGS': '0x0', 09:11:54 INFO - 'XPC_SERVICE_NAME': '0', 09:11:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:11:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:11:54 INFO - Cleaning up... 09:11:54 INFO - Return code: 0 09:11:54 INFO - Running post-action listener: _resource_record_post_action 09:11:54 INFO - Running post-action listener: _start_resource_monitoring 09:11:54 INFO - Starting resource monitoring. 09:11:54 INFO - [mozharness: 2016-08-16 16:11:54.493307Z] Finished create-virtualenv step (success) 09:11:54 INFO - [mozharness: 2016-08-16 16:11:54.494285Z] Running install step. 09:11:54 INFO - Running pre-action listener: _resource_record_pre_action 09:11:54 INFO - Running main action method: install 09:11:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:11:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:11:54 INFO - Reading from file tmpfile_stdout 09:11:54 INFO - Detecting whether we're running mozinstall >=1.0... 09:11:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:11:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:11:54 INFO - Reading from file tmpfile_stdout 09:11:54 INFO - Output received: 09:11:54 INFO - Usage: mozinstall [options] installer 09:11:54 INFO - Options: 09:11:54 INFO - -h, --help show this help message and exit 09:11:54 INFO - -d DEST, --destination=DEST 09:11:54 INFO - Directory to install application into. [default: 09:11:54 INFO - "/builds/slave/test"] 09:11:54 INFO - --app=APP Application being installed. [default: firefox] 09:11:54 INFO - mkdir: /builds/slave/test/build/application 09:11:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:11:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:12:28 INFO - Reading from file tmpfile_stdout 09:12:28 INFO - Output received: 09:12:28 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:12:28 INFO - Running post-action listener: _resource_record_post_action 09:12:28 INFO - [mozharness: 2016-08-16 16:12:28.663221Z] Finished install step (success) 09:12:28 INFO - [mozharness: 2016-08-16 16:12:28.663392Z] Running run-tests step. 09:12:28 INFO - Running pre-action listener: _resource_record_pre_action 09:12:28 INFO - Running main action method: run_tests 09:12:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:12:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:12:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:12:28 INFO - 2016-08-16 09:12:28.677 screenresolution[1917:18224] starting screenresolution argv=screenresolution get 09:12:28 INFO - 2016-08-16 09:12:28.680 screenresolution[1917:18224] Display 0: 1600x1200x32@60 09:12:28 INFO - 2016-08-16 09:12:28.685 screenresolution[1918:18226] starting screenresolution argv=screenresolution list 09:12:28 INFO - Available Modes on Display 0 09:12:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:12:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:12:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:12:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:12:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:12:28 INFO - Intel Iris: 09:12:28 INFO - Chipset Model: Intel Iris 09:12:28 INFO - Type: GPU 09:12:28 INFO - Bus: Built-In 09:12:28 INFO - VRAM (Dynamic, Max): 1536 MB 09:12:28 INFO - Vendor: Intel (0x8086) 09:12:28 INFO - Device ID: 0x0a2e 09:12:28 INFO - Revision ID: 0x0009 09:12:28 INFO - Displays: 09:12:28 INFO - Display: 09:12:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:12:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:12:28 INFO - Display Serial Number: 1600x1200 60 09:12:28 INFO - Main Display: Yes 09:12:28 INFO - Mirror: Off 09:12:28 INFO - Online: Yes 09:12:28 INFO - Rotation: Supported 09:12:28 INFO - Return code: 0 09:12:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:12:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:12:28 INFO - Python 2.7.3 09:12:28 INFO - Return code: 0 09:12:28 INFO - grabbing minidump binary from tooltool 09:12: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')]} 09:12:28 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 0x10c746d30>, '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 0x10c4abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c73eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:12:28 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:12:28 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:12:28 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:12:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:12:29 INFO - Return code: 0 09:12:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:12:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:12:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:12:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:12:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:12:29 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/SdYeoaMeQ1WRpL3V7jRRRw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0233', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:12:29 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/SdYeoaMeQ1WRpL3V7jRRRw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0233 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:12:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hhLpyb1K6w/Render', 09:12:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:12:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:12:29 INFO - 'HOME': '/Users/cltbld', 09:12:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:12:29 INFO - 'LOGNAME': 'cltbld', 09:12:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:12:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:12:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:29 INFO - 'MOZ_NO_REMOTE': '1', 09:12:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:12:29 INFO - 'NO_EM_RESTART': '1', 09:12:29 INFO - 'PAGER': '/bin/cat', 09:12:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:12:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:29 INFO - 'PWD': '/builds/slave/test', 09:12:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:12:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:12:29 INFO - 'SHELL': '/bin/bash', 09:12:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0wNs5DtCi/Listeners', 09:12:29 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 09:12:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:12:29 INFO - 'USER': 'cltbld', 09:12:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:12:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:12:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:29 INFO - 'XPC_FLAGS': '0x0', 09:12:29 INFO - 'XPC_SERVICE_NAME': '0', 09:12:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:12:29 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/SdYeoaMeQ1WRpL3V7jRRRw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0233', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:12:29 INFO - mozversion application_buildid: 20160816064524 09:12:29 INFO - mozversion application_changeset: 174ca5f954197eda591348e87b1d8abb3404013e 09:12:29 INFO - mozversion application_display_name: Nightly 09:12:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:12:29 INFO - mozversion application_name: Firefox 09:12:29 INFO - mozversion application_remotingname: firefox 09:12:29 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 09:12:29 INFO - mozversion application_vendor: Mozilla 09:12:29 INFO - mozversion application_version: 51.0a1 09:12:29 INFO - mozversion platform_buildid: 20160816064524 09:12:29 INFO - mozversion platform_changeset: 174ca5f954197eda591348e87b1d8abb3404013e 09:12:29 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 09:12:29 INFO - mozversion platform_version: 51.0a1 09:12:29 INFO - using testdate: 1471363949 09:12:29 INFO - actual date: 1471363949 09:12:29 INFO - starting webserver on 'localhost:52579' 09:12:29 INFO - SUITE-START | Running 2 tests 09:12:29 INFO - TEST-START | dromaeo_css 09:12:29 INFO - operating with platform_type : mac_ 09:12:29 INFO - Initialising browser for dromaeo_css test... 09:12:29 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpr4MhUQ/profile http://localhost:52579/getInfo.html) 09:12:31 INFO - PROCESS | 1928 | __metrics Screen width/height:1600/1200 09:12:31 INFO - PROCESS | 1928 | colorDepth:24 09:12:31 INFO - PROCESS | 1928 | Browser inner width/height: 1024/689 09:12:31 INFO - PROCESS | 1928 | __metrics 09:12:31 INFO - PROCESS | 1928 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:12:31 INFO - PROCESS | 1928 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:12:32 INFO - TEST-INFO | 1928: exit 0 09:12:32 INFO - Browser initialized. 09:12:32 INFO - Running cycle 1/1 for dromaeo_css test... 09:12:32 INFO - TEST-INFO | started process 1930 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpr4MhUQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:12:44 INFO - PROCESS | 1930 | RSS: Main: 228335616 09:12:44 INFO - PROCESS | 1930 | 09:13:36 INFO - PROCESS | 1930 | 1471364016366 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:15:48 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:52579/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52579/tests/dromaeo/cssquery-ext.html) 09:15:49 INFO - PROCESS | 1930 | RSS: Main: 355643392 09:15:49 INFO - PROCESS | 1930 | 09:18:52 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:52579/tests/dromaeo/cssquery-ext.html (next: http://localhost:52579/tests/dromaeo/cssquery-jquery.html) 09:18:53 INFO - PROCESS | 1930 | RSS: Main: 372908032 09:18:53 INFO - PROCESS | 1930 | 09:21:55 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:52579/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52579/tests/dromaeo/cssquery-mootools.html) 09:21:56 INFO - PROCESS | 1930 | RSS: Main: 347250688 09:21:56 INFO - PROCESS | 1930 | 09:24:58 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:52579/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52579/tests/dromaeo/cssquery-prototype.html) 09:24:59 INFO - PROCESS | 1930 | RSS: Main: 337055744 09:24:59 INFO - PROCESS | 1930 | 09:28:02 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:52579/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52579/tests/dromaeo/cssquery-yui.html) 09:28:03 INFO - PROCESS | 1930 | RSS: Main: 329281536 09:28:03 INFO - PROCESS | 1930 | 09:31:06 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:52579/tests/dromaeo/cssquery-yui.html (next: http://localhost:52579/tests/dromaeo/cssquery-dojo.html) 09:31:07 INFO - PROCESS | 1930 | RSS: Main: 378937344 09:31:07 INFO - PROCESS | 1930 | 09:31:07 INFO - PROCESS | 1930 | __start_tp_report 09:31:07 INFO - PROCESS | 1930 | _x_x_mozilla_page_load 09:31:07 INFO - PROCESS | 1930 | _x_x_mozilla_page_load_details 09:31:07 INFO - PROCESS | 1930 | |i|pagename|runs| 09:31:07 INFO - PROCESS | 1930 | |0;dojo.html;3313.5692359993195;3354.127926739048;3355.429576971913;3383.4417321142014;3416.6070901846297;5562.916556251649;5612.971935140324;5677.17680936264;5707.629004114735;5716.456936591017;5662.943370566293;5722.256106706132;5722.856928576788;5725.141228815669;5728.828135155942;5169.663971841827;5199.038177937094;5204.34945631796;5235.58115350771;5285.339332583427;5248.5276325130735;5255.894882102356;5257.185136303879;5266.8683282917855;5267.499587539178;5172.948270517284;5207.531322181003;5216.374035115772;5222.477752224785;5262.552683021948;5270.156774916014;5291.735413229361;5293.708846013467;5297.841064768063;5326.2543243945875;5071.7210553419545;5097.158968770148;5108.591312694975;5203.557697595709;5213.2701421800975;4652.976735116303;5558.471945165203;5618.213450116987;5622.128570071645;5684.090545512699;4607.976960115211;6177.722002509853;6196.473299769525;6213.471854892339;6223.657698826562;5201.505856943584;6114.996965385497;6188.597741146811;6250.624962502265;6258.061290806416;4841.346418233564;5864;5877;5884.205632239678;5892.145638882338;6228.5951413158;6242.563020588606;6243;6252.843678908063;6257.061440783918;5881.3530511643685;6102.877942441126;6104.511639068864;6109.327973922865;6116.5718399711595;6195.535334849814;6205.565610407228;6209.348018457997;6219.782307619302;6228.003519436868;6173.043178307369;6176.135341052256;6178.073289006595;6197.008478643395;6207.472364848948;6053.394660534;6103.549811064039;6120.540959428061;6128.785492507741;6215.906761398583;4926.926096108561;4935.284383764406;4956.702597844069;4965.528274813887;5018.397792264952;4461.776911154429;4462.286034234507;4463.062756821039;4463.575960283768;4524.457065152203;5571.582131340085;5620.409856965041;5628.437156284339;5677.432256774289;5687.60186786921;6069.757209711651;6133.946794548733;6170.876582468325;6180.165677633552;6202.503799696014;5733.111367738007;5741.971290143606;5749.252597162342;5760.193572899798;5773.9895518284975;5402.18967154922;5422.783088676409;5432.75552600134;5434.701091440009;5448.863778405493;6116.847078823065;6189.876202476015;6194.38056194377;6247.468965137832;6253.280872699698;6238.438540531374;6248.250209974832;6283.497320214463;6293.150424692608;6295.748170073146;6208.937910620836;6262.53031022666;6270.435660790551;6280.497560195265;6283.96858015707;5910.497607703222;6207.534434917309;6214.564980451325;6226.719797609027;6229.345918678474;6145.432914606926;6186.690665466798;6190.164327815867;6253.499720022478;6277.497800175884;5217.713025783618;6082.9695851520455;6098.146259523582;6100.115483254992;6103;5922.911156332572;6044.57687961856;6070.605410648293;6077.20996270482;6093.36019717941;5832.591718579659;5877.294724633071;5918.467337939606;5942.702864856654;5953.613015154001;6008.188894499187;6069.423404776539;6072.271327440735;6072.908906366496;6091.847703807263;5766.509846662912;5810.564207684356;5864;5868.882622347614;5900.763969441346 09:31:07 INFO - PROCESS | 1930 | |1;ext.html;505.9544640982311;513.9820106296279;522.1515038063147;525.3879230696236;528.9523942845143;30005.399892002173;30292.788288468462;36430;42184.15631687365;42663.78668106663;42720.786396067975;43066;43101.56898431022;43196.78401607987;43364;9747.268954828398;9914.90085099147;9931.900680993205;9932.801343973117;9935.751606209831;8960.059193784657;9190.494522801244;12079.637610871689;12321.568745093924;12324.5686400976;12179;12265.325407102606;12272.570460033901;12285.14004019719;12310.015198784076;12233.694157646041;12302.876971230262;12303.384830758516;12306.876931230707;12356.44396002173;10182.592696292213;11969.162158648898;12013.759724805543;12047.93976030123;12056.578019769353;40604.59395406038;40833.387499187535;40841.59158408437;40881.59118408807;40929.976750581176;2999.685033071518;3415.8804441844663;3710.9814450927843;3838.5777564467885;4224;31314.686853131407;32404.513932291033;32448;32544.6745532544;32621.83689081563;3150.5746724192168;3189.6012998375204;3336.2993771308056;3374.9493757593655;3422.9828850855833;448738;451173;452441;454422;454451;48929.26606100911;57288.42711572831;57592;57618.13572796327;57688;60179.69910150509;61492;61611.691941540004;61616.075758863655;61621.38378616246;15612.3755049799;15825.446109386115;15829.604259893595;15840.287187076608;15880.20598970047;11112.999830015337;11467.598634047768;12346.567870124503;12358.320292384005;12397.132200745862;15881.047137171807;16259.918700406659;16326.591835204215;16517.917410412872;16599.66800664004;5389.407165211824;6506.31683673217;6527;6588.308227636125;6618.933810661928;691.7371398868398;883.1565854608833;933.803901180746;954.441651633802;1106.551846502168;27608.723912760615;29944.550831737542;30066;30368.696313037028;30977.380452390826;985.8767654043245;1041.422010784007;1060.347886050089;1073.958260487326;1138.9316641001567;14872.479463218735;15339.769903451457;15636.92181539085;15666.295016724274;16090.597735056717;643665;649434.7528262328;649793;650107;651912;58770;58949;59045.40954590485;59074.40925590689;59227;1212.8302037714727;1250.612310183864;1290.251654040673;1339.2031741114022;1363.8022486739367;30016;31477.8426107868;31526;31686.732530698515;32317.51523727193;49230.50769492259;49666.751666241435;49681.75159124181;49815.75092124516;49986;516494;519365;519624;520174;520895;26371.868140659175;26746;27048.864755676095;27139.86430067837;40285.798571006955;48033;48050.27924581204;48207.95119188459;48931;48935.53193404204;39442.605573943896;39613.80193099016;39737.80786576407;39902.60097399105;40909.181816362914;32462.675373245966;38898.416523752734;39330;39925;40508;37585;38864;39014.804925975186;39259.01852453712;41012.79493602513;31294.530582040832;34628.82052609091;34891.65108348884;35306.76426325066;35420.46869297014;25391.492170156864;25423;25829.741702582734;25953.87023064948;26155.215343539723 09:31:07 INFO - PROCESS | 1930 | |2;jquery.html;303.49165148376466;371.01495529369515;379.3626707132018;382.0830007980846;404.7470331043098;15868.682626347467;16773.916130419348;20792.48018799531;20915.895420522913;20960.790392096093;20437;20457.74964753876;21088.789112108876;21109.47226319339;21190.940452977327;5482.451754824516;5683;5684.4315568443035;5751.741171647286;6130;3887.7667339959553;6092.664903430332;6180.814575562717;6196.039613859859;6210.037444196134;5852.356240813507;6175.258968923713;6212.130301757735;6242.563020588561;6266.7179976900925;6223.595466294699;6223.813285601439;6285.340039295854;6842.384185423286;6909.585424874524;6058.3032951210735;6064.727087281083;6067.392141082617;6068.21113255274;6069.696515174223;24877.62683559748;25874.22377328683;25950.610740838903;26312.736872631413;26452.338691532674;2184.6613774864923;2211.82305415568;2248.718910136233;2343.2970108967243;2924.4249726177354;8324.417290789646;9622.278329125274;10107.241956853266;10586.841197382046;10589.470526473722;1888.3673969220329;1889.102676228794;1890.4612185527196;1922.6923692209318;1936.4481122880052;116842;119875;121413.39293303477;126277;126782;9268.304877134107;11266.830997535044;11311.773764524663;11543.307401555934;11704.239224450383;12825.935870320589;13794.034417590872;14510.347034383478;14557.490487832874;14571;8306.460080094774;8439.620217090123;8517.276031537389;8529.701460448978;8605.913940860637;8283.29591984676;8286.875696864552;8333.083360830304;8463.280621147149;8521.29574085173;4499.235130027903;4499.752513611717;4503.819847206075;4549.545045495424;4592.2422800238255;1818.6453641539772;1885.4532185666003;1913.435536516731;1919.251491918153;1927.267638297466;850.4174640371366;897.3001059173855;960.3325688646323;1020.1940467031111;1034.3897100710617;17276.481705548853;19516.609667806566;20468.897655511628;20491.69262461064;20518.89740551288;901.7024381953939;927.1794461901266;932.8507438809758;972.2902281334524;975.9365641233297;9958.203343732628;11010.623672040994;11207.495662695199;11288.943555282336;11307.265027773168;618997;621155;622977;631275;640607;128313;139502;144137;147325.15986725804;148688.61802437832;962.5139304651107;983.5033308179324;1026.3636545341787;1027.0961553832578;1038.4703801061362;13884.652883677989;18337.323982384405;19294.90352548228;19429.61140777205;19592.706109408085;52854.73572632112;55854;56878.715606421705;57040;58175.70912145412;76365;79746.20253797388;80267.59866200632;81285.74857005653;83281;7788.532688038736;7991.560464174294;8214.753557393287;8353.874691879506;8511.914880851113;9405.200557952377;12498.437570309175;13027.413766380347;13305.933470332586;13409.530666426628;11145.49845256949;11179.72050698739;11411.657650270505;11444.771104578029;11531.538738450368;11150.832737508781;11226.943865280622;11242.83135752948;11447.885521144683;11546.42267886619;10642.467876605984;11332.490037948148;11417.429128543374;11482.598109066143;11700.718359919734;8113.687599034483;8122.065962414398;8144.633491492778;8200.54896980648;8205.876911846208;8232.094469608219;9549.188318992963;11073.280236784905;11102.444877756241;11263.774724505627 09:31:07 INFO - PROCESS | 1930 | |3;mootools.html;1371.4514194322276;1528.4421186267011;1558.072946596775;1704.5312539051758;1847.8244566766161;7957.442978991466;8771.342149338801;8885.555722213887;8996.145366190218;9078.455292682444;8798.560071996406;8889;8889.59996800144;8896.37725359225;8901.287896968242;2381.868997205153;2410.4214988402746;2414.0947144820693;2414.2033129067368;2420.0924653254942;2359.4455303003865;2403.67550380699;2411.2645643078854;2416.4079800448912;2416.540857237128;2371.4190023444194;2388.36708272308;2395.3053614451787;2410.337157281744;2415.9007651518605;2357.752435994213;2379.9881000594974;2389.330987323552;2393.126508824284;2398.760123987596;2088;2268.194790849258;2342.2973108067513;2363.1256435118944;2371.9288421347387;8824.426412283245;9031.909680903173;9043.909560904438;9051.683191088347;9052.77368065797;5240.7379631018675;5433.592480563973;5436.130219164915;5493.121100623881;5493.285872836545;3709.3879509880835;3716.0524066362905;3780.262848744496;3782.546094468654;3790.8104594770152;1100.5432745410644;1113.6158025481195;1116.6370929447962;1122.1135303110532;1138.9145814063895;10083.495825208784;12035.09736769737;12153.210041347373;12167.817482737766;12171.96538294246;4421.9336709949375;4551.203539380661;4704;4729.290606409066;4730.858074257778;9305.069493050642;9382.90617093834;9393.671221507215;9430.3398762086;9459.479728614991;5274.129768588216;5275.20871869215;5275.894482110336;5284.207368894697;5285.973570132125;4333.458317710286;4409.316555933835;4426.756528390905;4539;4950.925736113961;3796.297684928242;3822.197338558878;3858.2283543291455;3893.2992061429213;3893.785841778673;1169.830374595679;1175.9647210583696;1176.582313278791;1189.0547015122825;1190.6725650446197;862.3403096631082;869.6347534035721;903.5437104262305;920.332758749901;946.9621215151317;5718;5802.738876750556;6024.638521688713;6070.271567411939;6221.813345599639;930.1582068228264;941.7975135345933;945.5036106044245;982.6560703753629;999.9750006249902;3886.8639597613947;3919.2161567686576;3945.3884647879627;4060.4315395844606;4069.593040695907;8813.294936405073;10972.341659500455;11012.834807477895;11017.228793984345;11068.225224234226;10951.83572246417;10962.506687199095;10974.561017559368;10975.890241097486;10990.835137472945;7890.8816367754525;8185.959070204611;8323.084460709317;8344.499330040217;8355.791105222417;5854.414558544111;6074.848128796815;6117.3576774439825;6154.107654390178;6160.106784516131;8477.915220847712;9793.461359625004;10114;10208.438535880301;10280;9376.109269619375;10036.799264014533;10175;10346.896531034894;10479.738006549593;7218.530795498275;7564.357029652541;7728.111267204119;7804;7916.445848790529;8365.665373384996;8484.278836298738;8535.0184728757;8542.82914341722;8581.742547723577;7608.353289970414;7613.771586852404;7631.961840190986;7684.731034413769;7794.961025194838;7465.552066876005;7515.248475152441;7519.548827070394;7569.621518924142;7573.242675732383;7042.683079261548;7115.359617634438;7148.0350152728715;7218.314260145278;7227.168875579166;4864.781084851119;5013.047520971004;5029.321041659476;5033.421156566895;5051.696898186121;4177.248095342868;5000.2499625056535;5076.847694569169;5108.693478391308;5278.736063196901 09:31:07 INFO - PROCESS | 1930 | |4;prototype.html;276.9293830073331;296.3110767465643;306.96316442026955;312.1836397819702;313.6659457677575;5074.467180945998;5077.365329333833;5131.4611965743525;5152.201408781645;5152.458991805863;4312.331588603772;4856.611471082314;4875.853724388265;5059.443461219272;5073.985202959404;2902.129361191634;3883.98058009711;3947.907608967245;3950.8222130004074;3966.7619942803526;3342.7660063795543;3931.2727145478034;3935.3900145477355;3937.744046636974;3984.7011474139413;3684.6315368463074;3891.2606604745006;3904.4533765272886;3928.7839168845703;3932.6600672599757;3233.2401885556874;3528.5060091587197;3899.3566061599804;3914.9804250978705;3929.489166408377;3112.7354174895163;3370.208001119735;3373.881914133018;3375.885957633975;3384.4415671414185;5005.823631446608;5006.599472042264;5027.436007636095;5043.243513472972;5066.569341605968;3516.4000379827116;4383.044719502929;4395.516493185747;4416.933745993812;4482.8206871725415;4396.71421357614;5253.44838791929;5354.223637572531;5361.865953351158;5373.059714549938;3683.7605555638797;3712.9814350928073;3715.3281023539516;3738.345774500081;3743.7753734776;5214.01867290431;5591.9720401397735;5595.356533998602;5643.730160713848;5662.631928924607;3284.310294838063;3705.40713485841;3724.0875985383755;3732.7573707709457;3750.4374343848094;3469.7224222062173;3774.848336448198;3775.7356985010783;3787;3799.7530160539477;4221.261279276115;4224.619784219373;4362.411074504965;4411.779411029435;4421.491528474202;4704.458987216514;5388.407275199725;5396.91904621431;5402.702851343214;5404.621676482609;1268.1883594499527;1289.7033682253134;1289.7807372746656;1293.5537239652306;1297.6301754000065;735.8086897406713;774.3031271855263;818.8976377952756;842.7808769719794;889.2796834563894;4461.2117426608365;4743;4964.726940018334;5030.647854650212;5057.949420505748;4943.406791185009;5451.454854514596;5468.7539060742365;5499.202615620713;5502.339719233718;3902.902427439337;4788.976055119771;4945.307656928033;5057.963117560891;5074.594032477393;2462.581361168606;2905.3898681276746;2917.868695908689;2923.2399576110365;2927.458420192271;5754.482096611241;5762.971185144047;5791;5795.768169273266;5799.956007918531;3904.141088960424;5578.358488773762;5758.481736643722;5773.942260577341;5784.681842498703;5005.246065450911;5771.451712087261;5778.688524590113;5811.564132690065;5861.443162899518;4853.684510506806;5296.894062118659;5511.448855114457;5536;5580.414056524007;5493.862653433536;5499.965012945236;5506;5522.018110220436;5558.388577256418;5033.496650334937;5751.884962300815;5776.913346299725;5816.243888294494;5818.534517238695;3495.790252584853;3730.5896351401866;3750;3757.3988161893967;3770.7549009314307;5491.148871924858;5518.117101263779;5527.668339899619;5530.585206109477;5532.889342213052;5388.488093631099;5420.512153906167;5480.342358916955;5491.780328786804;5511.04357952921;4284.2930916398445;4681.953180468152;5318.228856815817;5379.784808607612;5494.6263434141465;4700.848292168441;4991.401031876198;5046.369203849519;5299.576033917355;5307.9469205309;4764.023375208005;4901.485344038824;5277.947220527745;5280.234366016983;5346.919796203138;4820.927686084641;5232.947670523246;5257.316548848626;5382.30030096085;5398.946010540001 09:31:07 INFO - PROCESS | 1930 | |5;yui.html;745.9925400745992;761.8362052158785;772.9343005844503;823.5552801487197;861.4615865084322;15423.845761542381;16261.349546018175;16307.673846523092;16314.102724350156;16330.918345408256;12083.939580302087;12100.636980890587;12107.878921210786;12118.272903625788;12120.151589388746;674.1741366825648;702.5257950883159;833.1002517281322;845.8392905347995;864.360373323739;866.5883705239992;867.6269204242174;870.2341939093589;872.1148034744739;872.7032808844991;909.1136142261308;911.3210658059754;912.548288597142;912.8128733609627;915.3775432705758;704.2148004974467;727.8071311102509;731.1372580355179;903;906.3383729877161;541.5072284221379;542.6147435320927;648.1282674802384;671.1308855032696;675.3820254467206;15030.473933412388;15390;15395.153266570334;15414.075155490595;15424.768628470581;14125.364358603887;14411.063280886709;14450.783238251435;14469;14595.78106328406;11236.66290011301;13307.800382994166;13344.2660653664;13363.398647060905;13665.08688783571;4600.125976064537;4716.882077948045;4745.905081898342;4816.686915350491;4900.681455705368;107291.46354268257;109598;110145.44927275392;110245;110249;11078.501467433985;11139.610113645984;11224.672584689977;11246.156538259664;11313;22565.548689026455;22575.77424225737;22578.09687612477;22603.43491412728;22606.3045271602;7764;7903.525788452711;7926.365890728728;7959.681612735541;7967.56178410193;4350.891227719332;4389.40742999691;4414.534590346562;4449.7775111244955;4543.045960348298;3490.127468132967;3567.254079810579;3601.9099522512147;3604.98197509016;3611.476335931275;548.9478499542537;630.8139098965817;636.8025911967305;650.8633187030682;675.6689222281046;591.73371982608;596.1683451585014;603.8490377405649;633.5280216238931;635.5138319185827;8841.955790221007;9131.77170570741;9216.21662158724;9223.815523689489;9248.537573121317;502.2591677275989;600.5285850607253;624.4286477872787;627.8336240896166;638.8115505925763;4327.32926396409;4451.310046942729;4451.5103338632725;4457.598816106566;4526.841560545365;89474.10525894788;128167;128550.71449285388;134626;134678;131743.02385464226;132976;136531;138769.30615346858;139743;116220.41889790667;120712;122380;123420;125926.59247927874;7405.370543503863;7413.555186688816;8235.805808157904;8705.782355441164;8744.825103498022;49901.75049124731;65858;66635.66682166558;67283.99074013991;68009.39315288592;98406.50796746257;101815.38200016975;103478.96521034994;105089.47455262675;106704;11408.543658253577;11703.531858725555;12042.759144816879;12122.636320910387;12147.817782733442;12640.494380224687;12674.873251267738;12836.807447888476;13353.79969300442;13419.194848309133;11899.107566932687;11956.402179891145;12323.75352492963;12404.131710780159;12438.502459901503;11006.669799906016;11197.832032519682;12060.939695301468;12118.818217726564;12427.316497592357;9184.954075229849;11729.237599556001;11767.705807354885;11823.527058917547;11880.287182769061;9818.852717209105;10175.389476631426;10273.84589231177;10407.895921040692;10467.424291664032;9594.376365536218;10195.388276703421;10221.9488902555;10271.229657775846;10278.33190842593 09:31:07 INFO - PROCESS | 1930 | __end_tp_report 09:31:07 INFO - PROCESS | 1930 | __start_cc_report 09:31:07 INFO - PROCESS | 1930 | _x_x_mozilla_cycle_collect,1124 09:31:07 INFO - PROCESS | 1930 | __end_cc_report 09:31:07 INFO - PROCESS | 1930 | __startTimestamp1471365067213__endTimestamp 09:31:07 INFO - PROCESS | 1930 | 09:31:07 INFO - PROCESS | 1930 | ------- Summary: start ------- 09:31:07 INFO - PROCESS | 1930 | Number of tests: 6 09:31:07 INFO - PROCESS | 1930 | 09:31:07 INFO - PROCESS | 1930 | [#0] dojo.html Cycles:165 Average:5695.62 Median:5881.35 stddev:630.32 (10.7%) stddev-sans-first:603.91 09:31:07 INFO - PROCESS | 1930 | Values: 3313.6 3354.1 3355.4 3383.4 3416.6 5562.9 5613.0 5677.2 5707.6 5716.5 5662.9 5722.3 5722.9 5725.1 5728.8 5169.7 5199.0 5204.3 5235.6 5285.3 5248.5 5255.9 5257.2 5266.9 5267.5 5172.9 5207.5 5216.4 5222.5 5262.6 5270.2 5291.7 5293.7 5297.8 5326.3 5071.7 5097.2 5108.6 5203.6 5213.3 4653.0 5558.5 5618.2 5622.1 5684.1 4608.0 6177.7 6196.5 6213.5 6223.7 5201.5 6115.0 6188.6 6250.6 6258.1 4841.3 5864.0 5877.0 5884.2 5892.1 6228.6 6242.6 6243.0 6252.8 6257.1 5881.4 6102.9 6104.5 6109.3 6116.6 6195.5 6205.6 6209.3 6219.8 6228.0 6173.0 6176.1 6178.1 6197.0 6207.5 6053.4 6103.5 6120.5 6128.8 6215.9 4926.9 4935.3 4956.7 4965.5 5018.4 4461.8 4462.3 4463.1 4463.6 4524.5 5571.6 5620.4 5628.4 5677.4 5687.6 6069.8 6133.9 6170.9 6180.2 6202.5 5733.1 5742.0 5749.3 5760.2 5774.0 5402.2 5422.8 5432.8 5434.7 5448.9 6116.8 6189.9 6194.4 6247.5 6253.3 6238.4 6248.3 6283.5 6293.2 6295.7 6208.9 6262.5 6270.4 6280.5 6284.0 5910.5 6207.5 6214.6 6226.7 6229.3 6145.4 6186.7 6190.2 6253.5 6277.5 5217.7 6083.0 6098.1 6100.1 6103.0 5922.9 6044.6 6070.6 6077.2 6093.4 5832.6 5877.3 5918.5 5942.7 5953.6 6008.2 6069.4 6072.3 6072.9 6091.8 5766.5 5810.6 5864.0 5868.9 5900.8 09:31:07 INFO - PROCESS | 1930 | 09:31:07 INFO - PROCESS | 1930 | [#1] ext.html Cycles:180 Average:68029.03 Median:21351.29 stddev:145772.32 (682.7%) stddev-sans-first:146093.09 09:31:07 INFO - PROCESS | 1930 | Values: 506.0 514.0 522.2 525.4 529.0 30005.4 30292.8 36430.0 42184.2 42663.8 42720.8 43066.0 43101.6 43196.8 43364.0 9747.3 9914.9 9931.9 9932.8 9935.8 8960.1 9190.5 12079.6 12321.6 12324.6 12179.0 12265.3 12272.6 12285.1 12310.0 12233.7 12302.9 12303.4 12306.9 12356.4 10182.6 11969.2 12013.8 12047.9 12056.6 40604.6 40833.4 40841.6 40881.6 40930.0 2999.7 3415.9 3711.0 3838.6 4224.0 31314.7 32404.5 32448.0 32544.7 32621.8 3150.6 3189.6 3336.3 3374.9 3423.0 448738.0 451173.0 452441.0 454422.0 454451.0 48929.3 57288.4 57592.0 57618.1 57688.0 60179.7 61492.0 61611.7 61616.1 61621.4 15612.4 15825.4 15829.6 15840.3 15880.2 11113.0 11467.6 12346.6 12358.3 12397.1 15881.0 16259.9 16326.6 16517.9 16599.7 5389.4 6506.3 6527.0 6588.3 6618.9 691.7 883.2 933.8 954.4 1106.6 27608.7 29944.6 30066.0 30368.7 30977.4 985.9 1041.4 1060.3 1074.0 1138.9 14872.5 15339.8 15636.9 15666.3 16090.6 643665.0 649434.8 649793.0 650107.0 651912.0 58770.0 58949.0 59045.4 59074.4 59227.0 1212.8 1250.6 1290.3 1339.2 1363.8 30016.0 31477.8 31526.0 31686.7 32317.5 49230.5 49666.8 49681.8 49815.8 49986.0 516494.0 519365.0 519624.0 520174.0 520895.0 26371.9 26746.0 27048.9 27139.9 40285.8 48033.0 48050.3 48208.0 48931.0 48935.5 39442.6 39613.8 39737.8 39902.6 40909.2 32462.7 38898.4 39330.0 39925.0 40508.0 37585.0 38864.0 39014.8 39259.0 41012.8 31294.5 34628.8 34891.7 35306.8 35420.5 25391.5 25423.0 25829.7 25953.9 26155.2 09:31:07 INFO - PROCESS | 1930 | 09:31:07 INFO - PROCESS | 1930 | [#2] jquery.html Cycles:180 Average:36403.40 Median:26382.54 stddev:104909.34 (397.6%) stddev-sans-first:105168.62 09:31:07 INFO - PROCESS | 1930 | Values: 303.5 371.0 379.4 382.1 404.7 15868.7 16773.9 20792.5 20915.9 20960.8 20437.0 20457.7 21088.8 21109.5 21190.9 5482.5 5683.0 5684.4 5751.7 6130.0 3887.8 6092.7 6180.8 6196.0 6210.0 5852.4 6175.3 6212.1 6242.6 6266.7 6223.6 6223.8 6285.3 6842.4 6909.6 6058.3 6064.7 6067.4 6068.2 6069.7 24877.6 25874.2 25950.6 26312.7 26452.3 2184.7 2211.8 2248.7 2343.3 2924.4 8324.4 9622.3 10107.2 10586.8 10589.5 1888.4 1889.1 1890.5 1922.7 1936.4 116842.0 119875.0 121413.4 126277.0 126782.0 9268.3 11266.8 11311.8 11543.3 11704.2 12825.9 13794.0 14510.3 14557.5 14571.0 8306.5 8439.6 8517.3 8529.7 8605.9 8283.3 8286.9 8333.1 8463.3 8521.3 4499.2 4499.8 4503.8 4549.5 4592.2 1818.6 1885.5 1913.4 1919.3 1927.3 850.4 897.3 960.3 1020.2 1034.4 17276.5 19516.6 20468.9 20491.7 20518.9 901.7 927.2 932.9 972.3 975.9 9958.2 11010.6 11207.5 11288.9 11307.3 618997.0 621155.0 622977.0 631275.0 640607.0 128313.0 139502.0 144137.0 147325.2 148688.6 962.5 983.5 1026.4 1027.1 1038.5 13884.7 18337.3 19294.9 19429.6 19592.7 52854.7 55854.0 56878.7 57040.0 58175.7 76365.0 79746.2 80267.6 81285.7 83281.0 7788.5 7991.6 8214.8 8353.9 8511.9 9405.2 12498.4 13027.4 13305.9 13409.5 11145.5 11179.7 11411.7 11444.8 11531.5 11150.8 11226.9 11242.8 11447.9 11546.4 10642.5 11332.5 11417.4 11482.6 11700.7 8113.7 8122.1 8144.6 8200.5 8205.9 8232.1 9549.2 11073.3 11102.4 11263.8 09:31:07 INFO - PROCESS | 1930 | 09:31:07 INFO - PROCESS | 1930 | [#3] mootools.html Cycles:180 Average:5747.13 Median:5031.37 stddev:3217.45 (63.9%) stddev-sans-first:3209.67 09:31:07 INFO - PROCESS | 1930 | Values: 1371.5 1528.4 1558.1 1704.5 1847.8 7957.4 8771.3 8885.6 8996.1 9078.5 8798.6 8889.0 8889.6 8896.4 8901.3 2381.9 2410.4 2414.1 2414.2 2420.1 2359.4 2403.7 2411.3 2416.4 2416.5 2371.4 2388.4 2395.3 2410.3 2415.9 2357.8 2380.0 2389.3 2393.1 2398.8 2088.0 2268.2 2342.3 2363.1 2371.9 8824.4 9031.9 9043.9 9051.7 9052.8 5240.7 5433.6 5436.1 5493.1 5493.3 3709.4 3716.1 3780.3 3782.5 3790.8 1100.5 1113.6 1116.6 1122.1 1138.9 10083.5 12035.1 12153.2 12167.8 12172.0 4421.9 4551.2 4704.0 4729.3 4730.9 9305.1 9382.9 9393.7 9430.3 9459.5 5274.1 5275.2 5275.9 5284.2 5286.0 4333.5 4409.3 4426.8 4539.0 4950.9 3796.3 3822.2 3858.2 3893.3 3893.8 1169.8 1176.0 1176.6 1189.1 1190.7 862.3 869.6 903.5 920.3 947.0 5718.0 5802.7 6024.6 6070.3 6221.8 930.2 941.8 945.5 982.7 1000.0 3886.9 3919.2 3945.4 4060.4 4069.6 8813.3 10972.3 11012.8 11017.2 11068.2 10951.8 10962.5 10974.6 10975.9 10990.8 7890.9 8186.0 8323.1 8344.5 8355.8 5854.4 6074.8 6117.4 6154.1 6160.1 8477.9 9793.5 10114.0 10208.4 10280.0 9376.1 10036.8 10175.0 10346.9 10479.7 7218.5 7564.4 7728.1 7804.0 7916.4 8365.7 8484.3 8535.0 8542.8 8581.7 7608.4 7613.8 7632.0 7684.7 7795.0 7465.6 7515.2 7519.5 7569.6 7573.2 7042.7 7115.4 7148.0 7218.3 7227.2 4864.8 5013.0 5029.3 5033.4 5051.7 4177.2 5000.2 5076.8 5108.7 5278.7 09:31:07 INFO - PROCESS | 1930 | 09:31:07 INFO - PROCESS | 1930 | [#4] prototype.html Cycles:180 Average:4379.06 Median:4998.32 stddev:1361.43 (27.2%) stddev-sans-first:1329.98 09:31:07 INFO - PROCESS | 1930 | Values: 276.9 296.3 307.0 312.2 313.7 5074.5 5077.4 5131.5 5152.2 5152.5 4312.3 4856.6 4875.9 5059.4 5074.0 2902.1 3884.0 3947.9 3950.8 3966.8 3342.8 3931.3 3935.4 3937.7 3984.7 3684.6 3891.3 3904.5 3928.8 3932.7 3233.2 3528.5 3899.4 3915.0 3929.5 3112.7 3370.2 3373.9 3375.9 3384.4 5005.8 5006.6 5027.4 5043.2 5066.6 3516.4 4383.0 4395.5 4416.9 4482.8 4396.7 5253.4 5354.2 5361.9 5373.1 3683.8 3713.0 3715.3 3738.3 3743.8 5214.0 5592.0 5595.4 5643.7 5662.6 3284.3 3705.4 3724.1 3732.8 3750.4 3469.7 3774.8 3775.7 3787.0 3799.8 4221.3 4224.6 4362.4 4411.8 4421.5 4704.5 5388.4 5396.9 5402.7 5404.6 1268.2 1289.7 1289.8 1293.6 1297.6 735.8 774.3 818.9 842.8 889.3 4461.2 4743.0 4964.7 5030.6 5057.9 4943.4 5451.5 5468.8 5499.2 5502.3 3902.9 4789.0 4945.3 5058.0 5074.6 2462.6 2905.4 2917.9 2923.2 2927.5 5754.5 5763.0 5791.0 5795.8 5800.0 3904.1 5578.4 5758.5 5773.9 5784.7 5005.2 5771.5 5778.7 5811.6 5861.4 4853.7 5296.9 5511.4 5536.0 5580.4 5493.9 5500.0 5506.0 5522.0 5558.4 5033.5 5751.9 5776.9 5816.2 5818.5 3495.8 3730.6 3750.0 3757.4 3770.8 5491.1 5518.1 5527.7 5530.6 5532.9 5388.5 5420.5 5480.3 5491.8 5511.0 4284.3 4682.0 5318.2 5379.8 5494.6 4700.8 4991.4 5046.4 5299.6 5307.9 4764.0 4901.5 5277.9 5280.2 5346.9 4820.9 5232.9 5257.3 5382.3 5398.9 09:31:07 INFO - PROCESS | 1930 | 09:31:07 INFO - PROCESS | 1930 | [#5] yui.html Cycles:180 Average:24752.11 Median:3528.69 stddev:39657.57 (1123.9%) stddev-sans-first:39727.86 09:31:07 INFO - PROCESS | 1930 | Values: 746.0 761.8 772.9 823.6 861.5 15423.8 16261.3 16307.7 16314.1 16330.9 12083.9 12100.6 12107.9 12118.3 12120.2 674.2 702.5 833.1 845.8 864.4 866.6 867.6 870.2 872.1 872.7 909.1 911.3 912.5 912.8 915.4 704.2 727.8 731.1 903.0 906.3 541.5 542.6 648.1 671.1 675.4 15030.5 15390.0 15395.2 15414.1 15424.8 14125.4 14411.1 14450.8 14469.0 14595.8 11236.7 13307.8 13344.3 13363.4 13665.1 4600.1 4716.9 4745.9 4816.7 4900.7 107291.5 109598.0 110145.4 110245.0 110249.0 11078.5 11139.6 11224.7 11246.2 11313.0 22565.5 22575.8 22578.1 22603.4 22606.3 7764.0 7903.5 7926.4 7959.7 7967.6 4350.9 4389.4 4414.5 4449.8 4543.0 3490.1 3567.3 3601.9 3605.0 3611.5 548.9 630.8 636.8 650.9 675.7 591.7 596.2 603.8 633.5 635.5 8842.0 9131.8 9216.2 9223.8 9248.5 502.3 600.5 624.4 627.8 638.8 4327.3 4451.3 4451.5 4457.6 4526.8 89474.1 128167.0 128550.7 134626.0 134678.0 131743.0 132976.0 136531.0 138769.3 139743.0 116220.4 120712.0 122380.0 123420.0 125926.6 7405.4 7413.6 8235.8 8705.8 8744.8 49901.8 65858.0 66635.7 67284.0 68009.4 98406.5 101815.4 103479.0 105089.5 106704.0 11408.5 11703.5 12042.8 12122.6 12147.8 12640.5 12674.9 12836.8 13353.8 13419.2 11899.1 11956.4 12323.8 12404.1 12438.5 11006.7 11197.8 12060.9 12118.8 12427.3 9185.0 11729.2 11767.7 11823.5 11880.3 9818.9 10175.4 10273.8 10407.9 10467.4 9594.4 10195.4 10221.9 10271.2 10278.3 09:31:07 INFO - PROCESS | 1930 | -------- Summary: end -------- 09:31:07 INFO - PROCESS | 1930 | 09:31:07 INFO - TEST-INFO | 1930: exit 0 09:31:07 INFO - TEST-OK | dromaeo_css | took 1118596ms 09:31:07 INFO - TEST-START | kraken 09:31:07 INFO - operating with platform_type : mac_ 09:31:07 INFO - Initialising browser for kraken test... 09:31:07 INFO - TEST-INFO | started process 1934 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpoadPzc/profile http://localhost:52579/getInfo.html) 09:31:10 INFO - PROCESS | 1934 | __metrics Screen width/height:1600/1200 09:31:10 INFO - PROCESS | 1934 | colorDepth:24 09:31:10 INFO - PROCESS | 1934 | Browser inner width/height: 1024/689 09:31:10 INFO - PROCESS | 1934 | __metrics 09:31:10 INFO - PROCESS | 1934 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:31:10 INFO - PROCESS | 1934 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:31:11 INFO - TEST-INFO | 1934: exit 0 09:31:11 INFO - Browser initialized. 09:31:11 INFO - Running cycle 1/1 for kraken test... 09:31:11 INFO - TEST-INFO | started process 1935 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpoadPzc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:31:22 INFO - PROCESS | 1935 | RSS: Main: 228405248 09:31:22 INFO - PROCESS | 1935 | 09:31:25 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52579/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52579/tests/kraken/driver.html?testName=audio-beat-detection) 09:31:26 INFO - PROCESS | 1935 | RSS: Main: 286146560 09:31:26 INFO - PROCESS | 1935 | 09:31:29 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52579/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52579/tests/kraken/driver.html?testName=audio-dft) 09:31:29 INFO - PROCESS | 1935 | RSS: Main: 292196352 09:31:29 INFO - PROCESS | 1935 | 09:31:33 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52579/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52579/tests/kraken/driver.html?testName=audio-fft) 09:31:34 INFO - PROCESS | 1935 | RSS: Main: 383193088 09:31:34 INFO - PROCESS | 1935 | 09:31:36 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52579/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52579/tests/kraken/driver.html?testName=audio-oscillator) 09:31:36 INFO - PROCESS | 1935 | RSS: Main: 287178752 09:31:36 INFO - PROCESS | 1935 | 09:31:38 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52579/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52579/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:31:39 INFO - PROCESS | 1935 | RSS: Main: 291069952 09:31:39 INFO - PROCESS | 1935 | 09:31:42 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52579/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52579/tests/kraken/driver.html?testName=imaging-darkroom) 09:31:42 INFO - PROCESS | 1935 | RSS: Main: 329408512 09:31:42 INFO - PROCESS | 1935 | 09:31:45 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52579/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52579/tests/kraken/driver.html?testName=imaging-desaturate) 09:31:46 INFO - PROCESS | 1935 | RSS: Main: 329637888 09:31:46 INFO - PROCESS | 1935 | 09:31:49 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52579/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52579/tests/kraken/driver.html?testName=json-parse-financial) 09:31:49 INFO - PROCESS | 1935 | RSS: Main: 329666560 09:31:49 INFO - PROCESS | 1935 | 09:31:51 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52579/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52579/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:31:51 INFO - PROCESS | 1935 | RSS: Main: 300498944 09:31:51 INFO - PROCESS | 1935 | 09:31:53 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52579/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52579/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:31:54 INFO - PROCESS | 1935 | RSS: Main: 307396608 09:31:54 INFO - PROCESS | 1935 | 09:31:56 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52579/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52579/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:31:57 INFO - PROCESS | 1935 | RSS: Main: 302424064 09:31:57 INFO - PROCESS | 1935 | 09:32:00 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52579/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52579/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:32:00 INFO - PROCESS | 1935 | RSS: Main: 310796288 09:32:00 INFO - PROCESS | 1935 | 09:32:03 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52579/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52579/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:32:04 INFO - PROCESS | 1935 | RSS: Main: 312074240 09:32:04 INFO - PROCESS | 1935 | 09:32:06 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52579/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52579/tests/kraken/driver.html?testName=ai-astar) 09:32:06 INFO - PROCESS | 1935 | RSS: Main: 355160064 09:32:06 INFO - PROCESS | 1935 | 09:32:06 INFO - PROCESS | 1935 | __start_tp_report 09:32:06 INFO - PROCESS | 1935 | _x_x_mozilla_page_load 09:32:06 INFO - PROCESS | 1935 | _x_x_mozilla_page_load_details 09:32:06 INFO - PROCESS | 1935 | |i|pagename|runs| 09:32:06 INFO - PROCESS | 1935 | |0;ai-astar;102;112;113;110;107;113;112;112;112;111 09:32:06 INFO - PROCESS | 1935 | |1;audio-beat-detection;142;108;107;165;107;107;128;106;139;115 09:32:06 INFO - PROCESS | 1935 | |2;audio-dft;185;182;185;181;183;181;181;183;179;184 09:32:06 INFO - PROCESS | 1935 | |3;audio-fft;79;57;61;87;59;57;67;61;81;56 09:32:06 INFO - PROCESS | 1935 | |4;audio-oscillator;86;88;94;70;71;68;96;71;67;104 09:32:06 INFO - PROCESS | 1935 | |5;imaging-gaussian-blur;88;85;88;85;87;84;88;84;87;84 09:32:06 INFO - PROCESS | 1935 | |6;imaging-darkroom;94;93;94;94;129;111;92;93;92;91 09:32:06 INFO - PROCESS | 1935 | |7;imaging-desaturate;87;84;103;135;134;85;84;84;85;86 09:32:06 INFO - PROCESS | 1935 | |8;json-parse-financial;55;54;56;53;54;57;55;53;55;53 09:32:06 INFO - PROCESS | 1935 | |9;json-stringify-tinderbox;63;64;78;74;73;71;69;69;69;70 09:32:06 INFO - PROCESS | 1935 | |10;stanford-crypto-aes;97;98;85;87;82;84;86;86;88;82 09:32:06 INFO - PROCESS | 1935 | |11;stanford-crypto-ccm;142;149;153;133;140;148;151;147;158;137 09:32:06 INFO - PROCESS | 1935 | |12;stanford-crypto-pbkdf2;170;166;170;172;222;183;170;169;167;167 09:32:06 INFO - PROCESS | 1935 | |13;stanford-crypto-sha256-iterative;67;67;68;65;66;62;62;62;86;86 09:32:06 INFO - PROCESS | 1935 | __end_tp_report 09:32:06 INFO - PROCESS | 1935 | __start_cc_report 09:32:06 INFO - PROCESS | 1935 | _x_x_mozilla_cycle_collect,2073 09:32:06 INFO - PROCESS | 1935 | __end_cc_report 09:32:06 INFO - PROCESS | 1935 | __startTimestamp1471365126494__endTimestamp 09:32:06 INFO - PROCESS | 1935 | 09:32:06 INFO - PROCESS | 1935 | ------- Summary: start ------- 09:32:06 INFO - PROCESS | 1935 | Number of tests: 14 09:32:06 INFO - PROCESS | 1935 | 09:32:06 INFO - PROCESS | 1935 | [#0] ai-astar Cycles:10 Average:110.40 Median:112.00 stddev:3.44 (3.1%) stddev-sans-first:1.87 09:32:06 INFO - PROCESS | 1935 | Values: 102.0 112.0 113.0 110.0 107.0 113.0 112.0 112.0 112.0 111.0 09:32:06 INFO - PROCESS | 1935 | 09:32:06 INFO - PROCESS | 1935 | [#1] audio-beat-detection Cycles:10 Average:122.40 Median:121.50 stddev:20.41 (16.8%) stddev-sans-first:20.38 09:32:06 INFO - PROCESS | 1935 | Values: 142.0 108.0 107.0 165.0 107.0 107.0 128.0 106.0 139.0 115.0 09:32:06 INFO - PROCESS | 1935 | 09:32:06 INFO - PROCESS | 1935 | [#2] audio-dft Cycles:10 Average:182.40 Median:183.00 stddev:1.96 (1.1%) stddev-sans-first:1.83 09:32:06 INFO - PROCESS | 1935 | Values: 185.0 182.0 185.0 181.0 183.0 181.0 181.0 183.0 179.0 184.0 09:32:06 INFO - PROCESS | 1935 | 09:32:06 INFO - PROCESS | 1935 | [#3] audio-fft Cycles:10 Average:66.50 Median:64.00 stddev:11.52 (18.0%) stddev-sans-first:11.30 09:32:06 INFO - PROCESS | 1935 | Values: 79.0 57.0 61.0 87.0 59.0 57.0 67.0 61.0 81.0 56.0 09:32:06 INFO - PROCESS | 1935 | 09:32:06 INFO - PROCESS | 1935 | [#4] audio-oscillator Cycles:10 Average:81.50 Median:78.50 stddev:13.66 (17.4%) stddev-sans-first:14.40 09:32:06 INFO - PROCESS | 1935 | Values: 86.0 88.0 94.0 70.0 71.0 68.0 96.0 71.0 67.0 104.0 09:32:06 INFO - PROCESS | 1935 | 09:32:06 INFO - PROCESS | 1935 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:87.00 stddev:1.76 (2.0%) stddev-sans-first:1.72 09:32:06 INFO - PROCESS | 1935 | Values: 88.0 85.0 88.0 85.0 87.0 84.0 88.0 84.0 87.0 84.0 09:32:06 INFO - PROCESS | 1935 | 09:32:06 INFO - PROCESS | 1935 | [#6] imaging-darkroom Cycles:10 Average:98.30 Median:93.00 stddev:12.24 (13.2%) stddev-sans-first:12.88 09:32:06 INFO - PROCESS | 1935 | Values: 94.0 93.0 94.0 94.0 129.0 111.0 92.0 93.0 92.0 91.0 09:32:06 INFO - PROCESS | 1935 | 09:32:06 INFO - PROCESS | 1935 | [#7] imaging-desaturate Cycles:10 Average:96.70 Median:84.50 stddev:20.72 (24.5%) stddev-sans-first:21.68 09:32:06 INFO - PROCESS | 1935 | Values: 87.0 84.0 103.0 135.0 134.0 85.0 84.0 84.0 85.0 86.0 09:32:06 INFO - PROCESS | 1935 | 09:32:06 INFO - PROCESS | 1935 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:55.00 stddev:1.35 (2.5%) stddev-sans-first:1.42 09:32:06 INFO - PROCESS | 1935 | Values: 55.0 54.0 56.0 53.0 54.0 57.0 55.0 53.0 55.0 53.0 09:32:06 INFO - PROCESS | 1935 | 09:32:06 INFO - PROCESS | 1935 | [#9] json-stringify-tinderbox Cycles:10 Average:70.00 Median:70.50 stddev:4.45 (6.3%) stddev-sans-first:3.93 09:32:06 INFO - PROCESS | 1935 | Values: 63.0 64.0 78.0 74.0 73.0 71.0 69.0 69.0 69.0 70.0 09:32:06 INFO - PROCESS | 1935 | 09:32:06 INFO - PROCESS | 1935 | [#10] stanford-crypto-aes Cycles:10 Average:87.50 Median:86.50 stddev:5.62 (6.5%) stddev-sans-first:4.80 09:32:06 INFO - PROCESS | 1935 | Values: 97.0 98.0 85.0 87.0 82.0 84.0 86.0 86.0 88.0 82.0 09:32:06 INFO - PROCESS | 1935 | 09:32:06 INFO - PROCESS | 1935 | [#11] stanford-crypto-ccm Cycles:10 Average:145.80 Median:148.50 stddev:7.70 (5.2%) stddev-sans-first:8.04 09:32:06 INFO - PROCESS | 1935 | Values: 142.0 149.0 153.0 133.0 140.0 148.0 151.0 147.0 158.0 137.0 09:32:06 INFO - PROCESS | 1935 | 09:32:06 INFO - PROCESS | 1935 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.60 Median:170.00 stddev:16.99 (10.0%) stddev-sans-first:17.90 09:32:06 INFO - PROCESS | 1935 | Values: 170.0 166.0 170.0 172.0 222.0 183.0 170.0 169.0 167.0 167.0 09:32:06 INFO - PROCESS | 1935 | 09:32:06 INFO - PROCESS | 1935 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:67.00 stddev:9.18 (13.7%) stddev-sans-first:9.71 09:32:06 INFO - PROCESS | 1935 | Values: 67.0 67.0 68.0 65.0 66.0 62.0 62.0 62.0 86.0 86.0 09:32:06 INFO - PROCESS | 1935 | -------- Summary: end -------- 09:32:06 INFO - PROCESS | 1935 | 09:32:07 INFO - TEST-INFO | 1935: exit 0 09:32:07 INFO - TEST-OK | kraken | took 59554ms 09:32:07 INFO - SUITE-END | took 1178s 09:32:07 INFO - Completed test suite (00:19:38) 09:32:07 INFO - javascript benchmark 09:32:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3313.5692359993195, 3354.127926739048, 3355.429576971913, 3383.4417321142014, 3416.6070901846297, 5562.916556251649, 5612.971935140324, 5677.17680936264, 5707.629004114735, 5716.456936591017, 5662.943370566293, 5722.256106706132, 5722.856928576788, 5725.141228815669, 5728.828135155942, 5169.663971841827, 5199.038177937094, 5204.34945631796, 5235.58115350771, 5285.339332583427, 5248.5276325130735, 5255.894882102356, 5257.185136303879, 5266.8683282917855, 5267.499587539178, 5172.948270517284, 5207.531322181003, 5216.374035115772, 5222.477752224785, 5262.552683021948, 5270.156774916014, 5291.735413229361, 5293.708846013467, 5297.841064768063, 5326.2543243945875, 5071.7210553419545, 5097.158968770148, 5108.591312694975, 5203.557697595709, 5213.2701421800975, 4652.976735116303, 5558.471945165203, 5618.213450116987, 5622.128570071645, 5684.090545512699, 4607.976960115211, 6177.722002509853, 6196.473299769525, 6213.471854892339, 6223.657698826562, 5201.505856943584, 6114.996965385497, 6188.597741146811, 6250.624962502265, 6258.061290806416, 4841.346418233564, 5864.0, 5877.0, 5884.205632239678, 5892.145638882338, 6228.5951413158, 6242.563020588606, 6243.0, 6252.843678908063, 6257.061440783918, 5881.3530511643685, 6102.877942441126, 6104.511639068864, 6109.327973922865, 6116.5718399711595, 6195.535334849814, 6205.565610407228, 6209.348018457997, 6219.782307619302, 6228.003519436868, 6173.043178307369, 6176.135341052256, 6178.073289006595, 6197.008478643395, 6207.472364848948, 6053.394660534, 6103.549811064039, 6120.540959428061, 6128.785492507741, 6215.906761398583, 4926.926096108561, 4935.284383764406, 4956.702597844069, 4965.528274813887, 5018.397792264952, 4461.776911154429, 4462.286034234507, 4463.062756821039, 4463.575960283768, 4524.457065152203, 5571.582131340085, 5620.409856965041, 5628.437156284339, 5677.432256774289, 5687.60186786921, 6069.757209711651, 6133.946794548733, 6170.876582468325, 6180.165677633552, 6202.503799696014, 5733.111367738007, 5741.971290143606, 5749.252597162342, 5760.193572899798, 5773.9895518284975, 5402.18967154922, 5422.783088676409, 5432.75552600134, 5434.701091440009, 5448.863778405493, 6116.847078823065, 6189.876202476015, 6194.38056194377, 6247.468965137832, 6253.280872699698, 6238.438540531374, 6248.250209974832, 6283.497320214463, 6293.150424692608, 6295.748170073146, 6208.937910620836, 6262.53031022666, 6270.435660790551, 6280.497560195265, 6283.96858015707, 5910.497607703222, 6207.534434917309, 6214.564980451325, 6226.719797609027, 6229.345918678474, 6145.432914606926, 6186.690665466798, 6190.164327815867, 6253.499720022478, 6277.497800175884, 5217.713025783618, 6082.9695851520455, 6098.146259523582, 6100.115483254992, 6103.0, 5922.911156332572, 6044.57687961856, 6070.605410648293, 6077.20996270482, 6093.36019717941, 5832.591718579659, 5877.294724633071, 5918.467337939606, 5942.702864856654, 5953.613015154001, 6008.188894499187, 6069.423404776539, 6072.271327440735, 6072.908906366496, 6091.847703807263, 5766.509846662912, 5810.564207684356, 5864.0, 5868.882622347614, 5900.763969441346], "value": 5657.6358801476745, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.9544640982311, 513.9820106296279, 522.1515038063147, 525.3879230696236, 528.9523942845143, 30005.399892002173, 30292.788288468462, 36430.0, 42184.15631687365, 42663.78668106663, 42720.786396067975, 43066.0, 43101.56898431022, 43196.78401607987, 43364.0, 9747.268954828398, 9914.90085099147, 9931.900680993205, 9932.801343973117, 9935.751606209831, 8960.059193784657, 9190.494522801244, 12079.637610871689, 12321.568745093924, 12324.5686400976, 12179.0, 12265.325407102606, 12272.570460033901, 12285.14004019719, 12310.015198784076, 12233.694157646041, 12302.876971230262, 12303.384830758516, 12306.876931230707, 12356.44396002173, 10182.592696292213, 11969.162158648898, 12013.759724805543, 12047.93976030123, 12056.578019769353, 40604.59395406038, 40833.387499187535, 40841.59158408437, 40881.59118408807, 40929.976750581176, 2999.685033071518, 3415.8804441844663, 3710.9814450927843, 3838.5777564467885, 4224.0, 31314.686853131407, 32404.513932291033, 32448.0, 32544.6745532544, 32621.83689081563, 3150.5746724192168, 3189.6012998375204, 3336.2993771308056, 3374.9493757593655, 3422.9828850855833, 448738.0, 451173.0, 452441.0, 454422.0, 454451.0, 48929.26606100911, 57288.42711572831, 57592.0, 57618.13572796327, 57688.0, 60179.69910150509, 61492.0, 61611.691941540004, 61616.075758863655, 61621.38378616246, 15612.3755049799, 15825.446109386115, 15829.604259893595, 15840.287187076608, 15880.20598970047, 11112.999830015337, 11467.598634047768, 12346.567870124503, 12358.320292384005, 12397.132200745862, 15881.047137171807, 16259.918700406659, 16326.591835204215, 16517.917410412872, 16599.66800664004, 5389.407165211824, 6506.31683673217, 6527.0, 6588.308227636125, 6618.933810661928, 691.7371398868398, 883.1565854608833, 933.803901180746, 954.441651633802, 1106.551846502168, 27608.723912760615, 29944.550831737542, 30066.0, 30368.696313037028, 30977.380452390826, 985.8767654043245, 1041.422010784007, 1060.347886050089, 1073.958260487326, 1138.9316641001567, 14872.479463218735, 15339.769903451457, 15636.92181539085, 15666.295016724274, 16090.597735056717, 643665.0, 649434.7528262328, 649793.0, 650107.0, 651912.0, 58770.0, 58949.0, 59045.40954590485, 59074.40925590689, 59227.0, 1212.8302037714727, 1250.612310183864, 1290.251654040673, 1339.2031741114022, 1363.8022486739367, 30016.0, 31477.8426107868, 31526.0, 31686.732530698515, 32317.51523727193, 49230.50769492259, 49666.751666241435, 49681.75159124181, 49815.75092124516, 49986.0, 516494.0, 519365.0, 519624.0, 520174.0, 520895.0, 26371.868140659175, 26746.0, 27048.864755676095, 27139.86430067837, 40285.798571006955, 48033.0, 48050.27924581204, 48207.95119188459, 48931.0, 48935.53193404204, 39442.605573943896, 39613.80193099016, 39737.80786576407, 39902.60097399105, 40909.181816362914, 32462.675373245966, 38898.416523752734, 39330.0, 39925.0, 40508.0, 37585.0, 38864.0, 39014.804925975186, 39259.01852453712, 41012.79493602513, 31294.530582040832, 34628.82052609091, 34891.65108348884, 35306.76426325066, 35420.46869297014, 25391.492170156864, 25423.0, 25829.741702582734, 25953.87023064948, 26155.215343539723], "value": 20211.62918841303, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.49165148376466, 371.01495529369515, 379.3626707132018, 382.0830007980846, 404.7470331043098, 15868.682626347467, 16773.916130419348, 20792.48018799531, 20915.895420522913, 20960.790392096093, 20437.0, 20457.74964753876, 21088.789112108876, 21109.47226319339, 21190.940452977327, 5482.451754824516, 5683.0, 5684.4315568443035, 5751.741171647286, 6130.0, 3887.7667339959553, 6092.664903430332, 6180.814575562717, 6196.039613859859, 6210.037444196134, 5852.356240813507, 6175.258968923713, 6212.130301757735, 6242.563020588561, 6266.7179976900925, 6223.595466294699, 6223.813285601439, 6285.340039295854, 6842.384185423286, 6909.585424874524, 6058.3032951210735, 6064.727087281083, 6067.392141082617, 6068.21113255274, 6069.696515174223, 24877.62683559748, 25874.22377328683, 25950.610740838903, 26312.736872631413, 26452.338691532674, 2184.6613774864923, 2211.82305415568, 2248.718910136233, 2343.2970108967243, 2924.4249726177354, 8324.417290789646, 9622.278329125274, 10107.241956853266, 10586.841197382046, 10589.470526473722, 1888.3673969220329, 1889.102676228794, 1890.4612185527196, 1922.6923692209318, 1936.4481122880052, 116842.0, 119875.0, 121413.39293303477, 126277.0, 126782.0, 9268.304877134107, 11266.830997535044, 11311.773764524663, 11543.307401555934, 11704.239224450383, 12825.935870320589, 13794.034417590872, 14510.347034383478, 14557.490487832874, 14571.0, 8306.460080094774, 8439.620217090123, 8517.276031537389, 8529.701460448978, 8605.913940860637, 8283.29591984676, 8286.875696864552, 8333.083360830304, 8463.280621147149, 8521.29574085173, 4499.235130027903, 4499.752513611717, 4503.819847206075, 4549.545045495424, 4592.2422800238255, 1818.6453641539772, 1885.4532185666003, 1913.435536516731, 1919.251491918153, 1927.267638297466, 850.4174640371366, 897.3001059173855, 960.3325688646323, 1020.1940467031111, 1034.3897100710617, 17276.481705548853, 19516.609667806566, 20468.897655511628, 20491.69262461064, 20518.89740551288, 901.7024381953939, 927.1794461901266, 932.8507438809758, 972.2902281334524, 975.9365641233297, 9958.203343732628, 11010.623672040994, 11207.495662695199, 11288.943555282336, 11307.265027773168, 618997.0, 621155.0, 622977.0, 631275.0, 640607.0, 128313.0, 139502.0, 144137.0, 147325.15986725804, 148688.61802437832, 962.5139304651107, 983.5033308179324, 1026.3636545341787, 1027.0961553832578, 1038.4703801061362, 13884.652883677989, 18337.323982384405, 19294.90352548228, 19429.61140777205, 19592.706109408085, 52854.73572632112, 55854.0, 56878.715606421705, 57040.0, 58175.70912145412, 76365.0, 79746.20253797388, 80267.59866200632, 81285.74857005653, 83281.0, 7788.532688038736, 7991.560464174294, 8214.753557393287, 8353.874691879506, 8511.914880851113, 9405.200557952377, 12498.437570309175, 13027.413766380347, 13305.933470332586, 13409.530666426628, 11145.49845256949, 11179.72050698739, 11411.657650270505, 11444.771104578029, 11531.538738450368, 11150.832737508781, 11226.943865280622, 11242.83135752948, 11447.885521144683, 11546.42267886619, 10642.467876605984, 11332.490037948148, 11417.429128543374, 11482.598109066143, 11700.718359919734, 8113.687599034483, 8122.065962414398, 8144.633491492778, 8200.54896980648, 8205.876911846208, 8232.094469608219, 9549.188318992963, 11073.280236784905, 11102.444877756241, 11263.774724505627], "value": 9642.391709112298, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1371.4514194322276, 1528.4421186267011, 1558.072946596775, 1704.5312539051758, 1847.8244566766161, 7957.442978991466, 8771.342149338801, 8885.555722213887, 8996.145366190218, 9078.455292682444, 8798.560071996406, 8889.0, 8889.59996800144, 8896.37725359225, 8901.287896968242, 2381.868997205153, 2410.4214988402746, 2414.0947144820693, 2414.2033129067368, 2420.0924653254942, 2359.4455303003865, 2403.67550380699, 2411.2645643078854, 2416.4079800448912, 2416.540857237128, 2371.4190023444194, 2388.36708272308, 2395.3053614451787, 2410.337157281744, 2415.9007651518605, 2357.752435994213, 2379.9881000594974, 2389.330987323552, 2393.126508824284, 2398.760123987596, 2088.0, 2268.194790849258, 2342.2973108067513, 2363.1256435118944, 2371.9288421347387, 8824.426412283245, 9031.909680903173, 9043.909560904438, 9051.683191088347, 9052.77368065797, 5240.7379631018675, 5433.592480563973, 5436.130219164915, 5493.121100623881, 5493.285872836545, 3709.3879509880835, 3716.0524066362905, 3780.262848744496, 3782.546094468654, 3790.8104594770152, 1100.5432745410644, 1113.6158025481195, 1116.6370929447962, 1122.1135303110532, 1138.9145814063895, 10083.495825208784, 12035.09736769737, 12153.210041347373, 12167.817482737766, 12171.96538294246, 4421.9336709949375, 4551.203539380661, 4704.0, 4729.290606409066, 4730.858074257778, 9305.069493050642, 9382.90617093834, 9393.671221507215, 9430.3398762086, 9459.479728614991, 5274.129768588216, 5275.20871869215, 5275.894482110336, 5284.207368894697, 5285.973570132125, 4333.458317710286, 4409.316555933835, 4426.756528390905, 4539.0, 4950.925736113961, 3796.297684928242, 3822.197338558878, 3858.2283543291455, 3893.2992061429213, 3893.785841778673, 1169.830374595679, 1175.9647210583696, 1176.582313278791, 1189.0547015122825, 1190.6725650446197, 862.3403096631082, 869.6347534035721, 903.5437104262305, 920.332758749901, 946.9621215151317, 5718.0, 5802.738876750556, 6024.638521688713, 6070.271567411939, 6221.813345599639, 930.1582068228264, 941.7975135345933, 945.5036106044245, 982.6560703753629, 999.9750006249902, 3886.8639597613947, 3919.2161567686576, 3945.3884647879627, 4060.4315395844606, 4069.593040695907, 8813.294936405073, 10972.341659500455, 11012.834807477895, 11017.228793984345, 11068.225224234226, 10951.83572246417, 10962.506687199095, 10974.561017559368, 10975.890241097486, 10990.835137472945, 7890.8816367754525, 8185.959070204611, 8323.084460709317, 8344.499330040217, 8355.791105222417, 5854.414558544111, 6074.848128796815, 6117.3576774439825, 6154.107654390178, 6160.106784516131, 8477.915220847712, 9793.461359625004, 10114.0, 10208.438535880301, 10280.0, 9376.109269619375, 10036.799264014533, 10175.0, 10346.896531034894, 10479.738006549593, 7218.530795498275, 7564.357029652541, 7728.111267204119, 7804.0, 7916.445848790529, 8365.665373384996, 8484.278836298738, 8535.0184728757, 8542.82914341722, 8581.742547723577, 7608.353289970414, 7613.771586852404, 7631.961840190986, 7684.731034413769, 7794.961025194838, 7465.552066876005, 7515.248475152441, 7519.548827070394, 7569.621518924142, 7573.242675732383, 7042.683079261548, 7115.359617634438, 7148.0350152728715, 7218.314260145278, 7227.168875579166, 4864.781084851119, 5013.047520971004, 5029.321041659476, 5033.421156566895, 5051.696898186121, 4177.248095342868, 5000.2499625056535, 5076.847694569169, 5108.693478391308, 5278.736063196901], "value": 4617.243769738156, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [276.9293830073331, 296.3110767465643, 306.96316442026955, 312.1836397819702, 313.6659457677575, 5074.467180945998, 5077.365329333833, 5131.4611965743525, 5152.201408781645, 5152.458991805863, 4312.331588603772, 4856.611471082314, 4875.853724388265, 5059.443461219272, 5073.985202959404, 2902.129361191634, 3883.98058009711, 3947.907608967245, 3950.8222130004074, 3966.7619942803526, 3342.7660063795543, 3931.2727145478034, 3935.3900145477355, 3937.744046636974, 3984.7011474139413, 3684.6315368463074, 3891.2606604745006, 3904.4533765272886, 3928.7839168845703, 3932.6600672599757, 3233.2401885556874, 3528.5060091587197, 3899.3566061599804, 3914.9804250978705, 3929.489166408377, 3112.7354174895163, 3370.208001119735, 3373.881914133018, 3375.885957633975, 3384.4415671414185, 5005.823631446608, 5006.599472042264, 5027.436007636095, 5043.243513472972, 5066.569341605968, 3516.4000379827116, 4383.044719502929, 4395.516493185747, 4416.933745993812, 4482.8206871725415, 4396.71421357614, 5253.44838791929, 5354.223637572531, 5361.865953351158, 5373.059714549938, 3683.7605555638797, 3712.9814350928073, 3715.3281023539516, 3738.345774500081, 3743.7753734776, 5214.01867290431, 5591.9720401397735, 5595.356533998602, 5643.730160713848, 5662.631928924607, 3284.310294838063, 3705.40713485841, 3724.0875985383755, 3732.7573707709457, 3750.4374343848094, 3469.7224222062173, 3774.848336448198, 3775.7356985010783, 3787.0, 3799.7530160539477, 4221.261279276115, 4224.619784219373, 4362.411074504965, 4411.779411029435, 4421.491528474202, 4704.458987216514, 5388.407275199725, 5396.91904621431, 5402.702851343214, 5404.621676482609, 1268.1883594499527, 1289.7033682253134, 1289.7807372746656, 1293.5537239652306, 1297.6301754000065, 735.8086897406713, 774.3031271855263, 818.8976377952756, 842.7808769719794, 889.2796834563894, 4461.2117426608365, 4743.0, 4964.726940018334, 5030.647854650212, 5057.949420505748, 4943.406791185009, 5451.454854514596, 5468.7539060742365, 5499.202615620713, 5502.339719233718, 3902.902427439337, 4788.976055119771, 4945.307656928033, 5057.963117560891, 5074.594032477393, 2462.581361168606, 2905.3898681276746, 2917.868695908689, 2923.2399576110365, 2927.458420192271, 5754.482096611241, 5762.971185144047, 5791.0, 5795.768169273266, 5799.956007918531, 3904.141088960424, 5578.358488773762, 5758.481736643722, 5773.942260577341, 5784.681842498703, 5005.246065450911, 5771.451712087261, 5778.688524590113, 5811.564132690065, 5861.443162899518, 4853.684510506806, 5296.894062118659, 5511.448855114457, 5536.0, 5580.414056524007, 5493.862653433536, 5499.965012945236, 5506.0, 5522.018110220436, 5558.388577256418, 5033.496650334937, 5751.884962300815, 5776.913346299725, 5816.243888294494, 5818.534517238695, 3495.790252584853, 3730.5896351401866, 3750.0, 3757.3988161893967, 3770.7549009314307, 5491.148871924858, 5518.117101263779, 5527.668339899619, 5530.585206109477, 5532.889342213052, 5388.488093631099, 5420.512153906167, 5480.342358916955, 5491.780328786804, 5511.04357952921, 4284.2930916398445, 4681.953180468152, 5318.228856815817, 5379.784808607612, 5494.6263434141465, 4700.848292168441, 4991.401031876198, 5046.369203849519, 5299.576033917355, 5307.9469205309, 4764.023375208005, 4901.485344038824, 5277.947220527745, 5280.234366016983, 5346.919796203138, 4820.927686084641, 5232.947670523246, 5257.316548848626, 5382.30030096085, 5398.946010540001], "value": 3945.134596499637, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [745.9925400745992, 761.8362052158785, 772.9343005844503, 823.5552801487197, 861.4615865084322, 15423.845761542381, 16261.349546018175, 16307.673846523092, 16314.102724350156, 16330.918345408256, 12083.939580302087, 12100.636980890587, 12107.878921210786, 12118.272903625788, 12120.151589388746, 674.1741366825648, 702.5257950883159, 833.1002517281322, 845.8392905347995, 864.360373323739, 866.5883705239992, 867.6269204242174, 870.2341939093589, 872.1148034744739, 872.7032808844991, 909.1136142261308, 911.3210658059754, 912.548288597142, 912.8128733609627, 915.3775432705758, 704.2148004974467, 727.8071311102509, 731.1372580355179, 903.0, 906.3383729877161, 541.5072284221379, 542.6147435320927, 648.1282674802384, 671.1308855032696, 675.3820254467206, 15030.473933412388, 15390.0, 15395.153266570334, 15414.075155490595, 15424.768628470581, 14125.364358603887, 14411.063280886709, 14450.783238251435, 14469.0, 14595.78106328406, 11236.66290011301, 13307.800382994166, 13344.2660653664, 13363.398647060905, 13665.08688783571, 4600.125976064537, 4716.882077948045, 4745.905081898342, 4816.686915350491, 4900.681455705368, 107291.46354268257, 109598.0, 110145.44927275392, 110245.0, 110249.0, 11078.501467433985, 11139.610113645984, 11224.672584689977, 11246.156538259664, 11313.0, 22565.548689026455, 22575.77424225737, 22578.09687612477, 22603.43491412728, 22606.3045271602, 7764.0, 7903.525788452711, 7926.365890728728, 7959.681612735541, 7967.56178410193, 4350.891227719332, 4389.40742999691, 4414.534590346562, 4449.7775111244955, 4543.045960348298, 3490.127468132967, 3567.254079810579, 3601.9099522512147, 3604.98197509016, 3611.476335931275, 548.9478499542537, 630.8139098965817, 636.8025911967305, 650.8633187030682, 675.6689222281046, 591.73371982608, 596.1683451585014, 603.8490377405649, 633.5280216238931, 635.5138319185827, 8841.955790221007, 9131.77170570741, 9216.21662158724, 9223.815523689489, 9248.537573121317, 502.2591677275989, 600.5285850607253, 624.4286477872787, 627.8336240896166, 638.8115505925763, 4327.32926396409, 4451.310046942729, 4451.5103338632725, 4457.598816106566, 4526.841560545365, 89474.10525894788, 128167.0, 128550.71449285388, 134626.0, 134678.0, 131743.02385464226, 132976.0, 136531.0, 138769.30615346858, 139743.0, 116220.41889790667, 120712.0, 122380.0, 123420.0, 125926.59247927874, 7405.370543503863, 7413.555186688816, 8235.805808157904, 8705.782355441164, 8744.825103498022, 49901.75049124731, 65858.0, 66635.66682166558, 67283.99074013991, 68009.39315288592, 98406.50796746257, 101815.38200016975, 103478.96521034994, 105089.47455262675, 106704.0, 11408.543658253577, 11703.531858725555, 12042.759144816879, 12122.636320910387, 12147.817782733442, 12640.494380224687, 12674.873251267738, 12836.807447888476, 13353.79969300442, 13419.194848309133, 11899.107566932687, 11956.402179891145, 12323.75352492963, 12404.131710780159, 12438.502459901503, 11006.669799906016, 11197.832032519682, 12060.939695301468, 12118.818217726564, 12427.316497592357, 9184.954075229849, 11729.237599556001, 11767.705807354885, 11823.527058917547, 11880.287182769061, 9818.852717209105, 10175.389476631426, 10273.84589231177, 10407.895921040692, 10467.424291664032, 9594.376365536218, 10195.388276703421, 10221.9488902555, 10271.229657775846, 10278.33190842593], "value": 7629.3909024547975, "unit": "score"}], "name": "dromaeo_css", "value": 7315.330518633805, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 113.0, 110.0, 107.0, 113.0, 112.0, 112.0, 112.0, 111.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 108.0, 107.0, 165.0, 107.0, 107.0, 128.0, 106.0, 139.0, 115.0], "value": 122.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 182.0, 185.0, 181.0, 183.0, 181.0, 181.0, 183.0, 179.0, 184.0], "value": 182.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 57.0, 61.0, 87.0, 59.0, 57.0, 67.0, 61.0, 81.0, 56.0], "value": 66.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 94.0, 70.0, 71.0, 68.0, 96.0, 71.0, 67.0, 104.0], "value": 81.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 85.0, 87.0, 84.0, 88.0, 84.0, 87.0, 84.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 94.0, 129.0, 111.0, 92.0, 93.0, 92.0, 91.0], "value": 98.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 103.0, 135.0, 134.0, 85.0, 84.0, 84.0, 85.0, 86.0], "value": 96.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 56.0, 53.0, 54.0, 57.0, 55.0, 53.0, 55.0, 53.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 78.0, 74.0, 73.0, 71.0, 69.0, 69.0, 69.0, 70.0], "value": 70.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 85.0, 87.0, 82.0, 84.0, 86.0, 86.0, 88.0, 82.0], "value": 87.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 149.0, 153.0, 133.0, 140.0, 148.0, 151.0, 147.0, 158.0, 137.0], "value": 145.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 166.0, 170.0, 172.0, 222.0, 183.0, 170.0, 169.0, 167.0, 167.0], "value": 175.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 65.0, 66.0, 62.0, 62.0, 62.0, 86.0, 86.0], "value": 69.1, "unit": "score"}], "name": "kraken", "value": 1446.6999999999998, "alertThreshold": 2.0}]} 09:32:07 INFO - Return code: 0 09:32:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:32:07 INFO - # TBPL SUCCESS # 09:32:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:32:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:32:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:32:07 INFO - 2016-08-16 09:32:07.774 screenresolution[1939:20269] starting screenresolution argv=screenresolution get 09:32:07 INFO - 2016-08-16 09:32:07.777 screenresolution[1939:20269] Display 0: 1600x1200x32@60 09:32:07 INFO - 2016-08-16 09:32:07.783 screenresolution[1940:20272] starting screenresolution argv=screenresolution list 09:32:07 INFO - Available Modes on Display 0 09:32:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:32:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:32:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:32:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:32:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:32:08 INFO - Intel Iris: 09:32:08 INFO - Chipset Model: Intel Iris 09:32:08 INFO - Type: GPU 09:32:08 INFO - Bus: Built-In 09:32:08 INFO - VRAM (Dynamic, Max): 1536 MB 09:32:08 INFO - Vendor: Intel (0x8086) 09:32:08 INFO - Device ID: 0x0a2e 09:32:08 INFO - Revision ID: 0x0009 09:32:08 INFO - Displays: 09:32:08 INFO - Display: 09:32:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:32:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:32:08 INFO - Display Serial Number: 1600x1200 60 09:32:08 INFO - Main Display: Yes 09:32:08 INFO - Mirror: Off 09:32:08 INFO - Online: Yes 09:32:08 INFO - Rotation: Supported 09:32:08 INFO - Return code: 0 09:32:08 INFO - Running post-action listener: _resource_record_post_action 09:32:08 INFO - [mozharness: 2016-08-16 16:32:08.022385Z] Finished run-tests step (success) 09:32:08 INFO - Running post-run listener: _resource_record_post_run 09:32:08 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 974848; Write bytes: 705854976; Read time: 8; Write time: 1945 09:32:08 INFO - TinderboxPrint: CPU usage
53.3% 09:32:08 INFO - TinderboxPrint: I/O read bytes / time
974,848 / 8 09:32:08 INFO - TinderboxPrint: I/O write bytes / time
705,854,976 / 1,945 09:32:08 INFO - TinderboxPrint: CPU idle
2,268.4 (46.7%) 09:32:08 INFO - TinderboxPrint: CPU system
962.4 (19.8%) 09:32:08 INFO - TinderboxPrint: CPU user
1,622.8 (33.4%) 09:32:08 INFO - TinderboxPrint: Swap in / out
405,606,400 / 0 09:32:08 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 405643776; Write bytes: 409210368; Read time: 29819; Write time: 829 09:32:08 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 909312; Write bytes: 295153664; Read time: 6; Write time: 1105 09:32:08 INFO - Running post-run listener: _upload_blobber_files 09:32:08 INFO - Blob upload gear active. 09:32:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:32:08 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:32:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:32:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:32:08 INFO - (blobuploader) - INFO - Open directory for files ... 09:32:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:32:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:32:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:32:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:32:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:32:10 INFO - (blobuploader) - INFO - Done attempting. 09:32:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:32:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:32:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:32:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:32:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:32:11 INFO - (blobuploader) - INFO - Done attempting. 09:32:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:32:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:32:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:32:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:32:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:32:13 INFO - (blobuploader) - INFO - Done attempting. 09:32:13 INFO - (blobuploader) - INFO - Iteration through files over. 09:32:13 INFO - Return code: 0 09:32:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:32:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:32:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/45423523cb308640857cd4cd2b6f2d583268b2f812f4cfe1cc9df181a02862986ea632923a3397aeeb77f48e1ed8d046df53f50230265aad91d10489519435a3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/70b9342a48fda5df278002a4b25ad7d0c14c0a559450d0b91b6a706798f1336e0358024c208a1d7f95267dd45eb98eff51460fe529625c3752d9f43f1838d87b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1212337ec607c250658b2a0d2e40f6d87af04c531c9888c8f11661ef25b086d06097f3f28f1e97a8f2dd34d94e63ddf33043739a133f5506bfeac05a8b42d739"} 09:32:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:32:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:32:13 INFO - Contents: 09:32:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/45423523cb308640857cd4cd2b6f2d583268b2f812f4cfe1cc9df181a02862986ea632923a3397aeeb77f48e1ed8d046df53f50230265aad91d10489519435a3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/70b9342a48fda5df278002a4b25ad7d0c14c0a559450d0b91b6a706798f1336e0358024c208a1d7f95267dd45eb98eff51460fe529625c3752d9f43f1838d87b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1212337ec607c250658b2a0d2e40f6d87af04c531c9888c8f11661ef25b086d06097f3f28f1e97a8f2dd34d94e63ddf33043739a133f5506bfeac05a8b42d739"} 09:32:13 INFO - Running post-run listener: copy_logs_to_upload_dir 09:32:13 INFO - Copying logs to upload dir... 09:32:13 INFO - mkdir: /builds/slave/test/build/upload/logs 09:32:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.844393 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-16 09:32:13.991071) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 09:32:13.995037) ========= 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.hhLpyb1K6w/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.S0wNs5DtCi/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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/45423523cb308640857cd4cd2b6f2d583268b2f812f4cfe1cc9df181a02862986ea632923a3397aeeb77f48e1ed8d046df53f50230265aad91d10489519435a3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/70b9342a48fda5df278002a4b25ad7d0c14c0a559450d0b91b6a706798f1336e0358024c208a1d7f95267dd45eb98eff51460fe529625c3752d9f43f1838d87b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1212337ec607c250658b2a0d2e40f6d87af04c531c9888c8f11661ef25b086d06097f3f28f1e97a8f2dd34d94e63ddf33043739a133f5506bfeac05a8b42d739"} build_url:https://queue.taskcluster.net/v1/task/SdYeoaMeQ1WRpL3V7jRRRw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011180 build_url: 'https://queue.taskcluster.net/v1/task/SdYeoaMeQ1WRpL3V7jRRRw/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/45423523cb308640857cd4cd2b6f2d583268b2f812f4cfe1cc9df181a02862986ea632923a3397aeeb77f48e1ed8d046df53f50230265aad91d10489519435a3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/70b9342a48fda5df278002a4b25ad7d0c14c0a559450d0b91b6a706798f1336e0358024c208a1d7f95267dd45eb98eff51460fe529625c3752d9f43f1838d87b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1212337ec607c250658b2a0d2e40f6d87af04c531c9888c8f11661ef25b086d06097f3f28f1e97a8f2dd34d94e63ddf33043739a133f5506bfeac05a8b42d739"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 09:32:14.028077) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 09:32:14.028393) ========= 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.hhLpyb1K6w/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.S0wNs5DtCi/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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.005189 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 09:32:14.077702) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 09:32:14.078018) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 09:32:14.078334) ========= ========= Total master_lag: 0.17 =========