builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0302 starttime: 1472623526.39 results: success (0) buildid: 20160830211321 builduid: a8e5a145d850432a994de8a6fb4ce53a revision: 56cc74607c72f390645dd048fe874e437bc5e4e5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 23:05:26.393597) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 23:05:26.406677) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 23:05:26.406990) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 23:05:26.433098) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 23:05:26.433641) ========= 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.QJxvBomwCY/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.S7TB73yaNv/Listeners TMPDIR=/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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-30 23:05:26-- 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% 19.3M=0.001s 2016-08-30 23:05:26 (19.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.557417 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 23:05:27.015678) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 23:05:27.015997) ========= 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.QJxvBomwCY/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.S7TB73yaNv/Listeners TMPDIR=/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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.025432 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 23:05:27.065805) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-30 23:05:27.066174) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 56cc74607c72f390645dd048fe874e437bc5e4e5 --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 56cc74607c72f390645dd048fe874e437bc5e4e5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QJxvBomwCY/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.S7TB73yaNv/Listeners TMPDIR=/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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-30 23:05:27,156 truncating revision to first 12 chars 2016-08-30 23:05:27,157 Setting DEBUG logging. 2016-08-30 23:05:27,157 attempt 1/10 2016-08-30 23:05:27,157 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/56cc74607c72?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-30 23:05:28,167 unpacking tar archive at: autoland-56cc74607c72/testing/mozharness/ program finished with exit code 0 elapsedTime=1.269342 ========= master_lag: 0.28 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-30 23:05:28.617136) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 23:05:28.617507) ========= script_repo_revision: 56cc74607c72f390645dd048fe874e437bc5e4e5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 23:05:28.617961) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 23:05:28.618277) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 23:05:28.725972) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-30 23:05:28.726519) ========= /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.QJxvBomwCY/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.S7TB73yaNv/Listeners TMPDIR=/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 23:05:28 INFO - MultiFileLogger online at 20160830 23:05:28 in /builds/slave/test 23:05:28 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 23:05:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:05:28 INFO - {'append_to_log': False, 23:05:28 INFO - 'base_work_dir': '/builds/slave/test', 23:05:28 INFO - 'blob_upload_branch': 'Autoland', 23:05:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:05:28 INFO - 'branch': 'Autoland', 23:05:28 INFO - 'buildbot_json_path': 'buildprops.json', 23:05:28 INFO - 'config_files': ('talos/mac_config.py',), 23:05:28 INFO - 'default_actions': ('clobber', 23:05:28 INFO - 'read-buildbot-config', 23:05:28 INFO - 'download-and-extract', 23:05:28 INFO - 'populate-webroot', 23:05:28 INFO - 'create-virtualenv', 23:05:28 INFO - 'install', 23:05:28 INFO - 'run-tests'), 23:05:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:05:28 INFO - 'download_minidump_stackwalk': True, 23:05:28 INFO - 'download_symbols': 'ondemand', 23:05:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:05:28 INFO - 'tooltool.py': '/tools/tooltool.py', 23:05:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:05:28 INFO - '/tools/misc-python/virtualenv.py')}, 23:05:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:05:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:05:28 INFO - 'installer_path': 'installer.exe', 23:05:28 INFO - 'log_level': 'info', 23:05:28 INFO - 'log_name': 'talos', 23:05:28 INFO - 'log_to_console': True, 23:05:28 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:05:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:05:28 INFO - 'opt_config_files': (), 23:05:28 INFO - 'pip_index': False, 23:05:28 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:05:28 INFO - 'cmd': ('bash', 23:05:28 INFO - '-c', 23:05:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:05:28 INFO - 'enabled': True, 23:05:28 INFO - 'halt_on_failure': False, 23:05:28 INFO - 'name': 'check_screen_resolution'},), 23:05:28 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:05:28 INFO - 'cmd': ('bash', 23:05:28 INFO - '-c', 23:05:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:05:28 INFO - 'enabled': True, 23:05:28 INFO - 'halt_on_failure': False, 23:05:28 INFO - 'name': 'check_screen_resolution'},), 23:05:28 INFO - 'run_cmd_checks_enabled': True, 23:05:28 INFO - 'sps_profile': False, 23:05:28 INFO - 'sps_profile_interval': 0, 23:05:28 INFO - 'suite': 'dromaeojs', 23:05:28 INFO - 'system_bits': '32', 23:05:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:05:28 INFO - 'title': 't-yosemite-r7-0302', 23:05:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:05:28 INFO - 'use_talos_json': True, 23:05:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:05:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:05:28 INFO - 'work_dir': 'build'} 23:05:28 INFO - [mozharness: 2016-08-31 06:05:28.921522Z] Running clobber step. 23:05:28 INFO - Running pre-action listener: _resource_record_pre_action 23:05:28 INFO - Running main action method: clobber 23:05:28 INFO - rmtree: /builds/slave/test/build 23:05:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:05:30 INFO - Running post-action listener: _resource_record_post_action 23:05:30 INFO - [mozharness: 2016-08-31 06:05:30.979632Z] Finished clobber step (success) 23:05:30 INFO - [mozharness: 2016-08-31 06:05:30.979825Z] Running read-buildbot-config step. 23:05:30 INFO - Running pre-action listener: _resource_record_pre_action 23:05:30 INFO - Running main action method: read_buildbot_config 23:05:30 INFO - Using buildbot properties: 23:05:30 INFO - { 23:05:30 INFO - "project": "", 23:05:30 INFO - "product": "firefox", 23:05:30 INFO - "script_repo_revision": "production", 23:05:30 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 23:05:30 INFO - "repository": "", 23:05:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 23:05:30 INFO - "buildid": "20160830211321", 23:05:30 INFO - "pgo_build": "False", 23:05:30 INFO - "basedir": "/builds/slave/test", 23:05:30 INFO - "buildnumber": 146, 23:05:30 INFO - "slavename": "t-yosemite-r7-0302", 23:05:30 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 23:05:30 INFO - "platform": "yosemite_r7", 23:05:30 INFO - "scheduler": "tests-autoland-macosx64-talos", 23:05:30 INFO - "branch": "autoland", 23:05:30 INFO - "repo_path": "integration/autoland", 23:05:30 INFO - "revision": "56cc74607c72f390645dd048fe874e437bc5e4e5", 23:05:30 INFO - "stage_platform": "macosx64", 23:05:30 INFO - "builduid": "a8e5a145d850432a994de8a6fb4ce53a", 23:05:30 INFO - "slavebuilddir": "test" 23:05:30 INFO - } 23:05:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/PkpPGRf1RCyoSOLJMoj7_g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 23:05:30 INFO - Running post-action listener: _resource_record_post_action 23:05:30 INFO - [mozharness: 2016-08-31 06:05:30.985856Z] Finished read-buildbot-config step (success) 23:05:30 INFO - [mozharness: 2016-08-31 06:05:30.986097Z] Running download-and-extract step. 23:05:30 INFO - Running pre-action listener: _resource_record_pre_action 23:05:30 INFO - Running main action method: download_and_extract 23:05:30 INFO - mkdir: /builds/slave/test/build/tests 23:05:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:05:30 INFO - trying https://queue.taskcluster.net/v1/task/PkpPGRf1RCyoSOLJMoj7_g/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 23:05:30 INFO - Downloading https://queue.taskcluster.net/v1/task/PkpPGRf1RCyoSOLJMoj7_g/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 23:05:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PkpPGRf1RCyoSOLJMoj7_g/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 23:05:32 INFO - Downloaded 1199 bytes. 23:05:32 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 23:05:32 INFO - Using the following test package requirements: 23:05:32 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 23:05:32 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:05:32 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 23:05:32 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:05:32 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 23:05:32 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:05:32 INFO - u'jsshell-mac.zip'], 23:05:32 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:05:32 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 23:05:32 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 23:05:32 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:05:32 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 23:05:32 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:05:32 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 23:05:32 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:05:32 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 23:05:32 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:05:32 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 23:05:32 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 23:05:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:05:32 INFO - trying https://queue.taskcluster.net/v1/task/PkpPGRf1RCyoSOLJMoj7_g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 23:05:32 INFO - Downloading https://queue.taskcluster.net/v1/task/PkpPGRf1RCyoSOLJMoj7_g/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 23:05:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PkpPGRf1RCyoSOLJMoj7_g/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 23:05:33 INFO - Downloaded 16390826 bytes. 23:05:33 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 23:05:40 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 23:05:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:05:40 INFO - trying https://queue.taskcluster.net/v1/task/PkpPGRf1RCyoSOLJMoj7_g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 23:05:40 INFO - Downloading https://queue.taskcluster.net/v1/task/PkpPGRf1RCyoSOLJMoj7_g/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 23:05:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PkpPGRf1RCyoSOLJMoj7_g/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 23:05:41 INFO - Downloaded 16390826 bytes. 23:05:41 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 23:05:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:05:48 INFO - trying https://queue.taskcluster.net/v1/task/PkpPGRf1RCyoSOLJMoj7_g/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 23:05:48 INFO - Downloading https://queue.taskcluster.net/v1/task/PkpPGRf1RCyoSOLJMoj7_g/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 23:05:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PkpPGRf1RCyoSOLJMoj7_g/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 23:05:48 INFO - Downloaded 14413998 bytes. 23:05:48 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 23:05:49 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')]} 23:05:49 INFO - trying https://queue.taskcluster.net/v1/task/PkpPGRf1RCyoSOLJMoj7_g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 23:05:49 INFO - Downloading https://queue.taskcluster.net/v1/task/PkpPGRf1RCyoSOLJMoj7_g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 23:05:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PkpPGRf1RCyoSOLJMoj7_g/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 23:05:52 INFO - Downloaded 111563246 bytes. 23:05:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PkpPGRf1RCyoSOLJMoj7_g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 23:05:52 INFO - mkdir: /builds/slave/test/properties 23:05:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:05:52 INFO - Writing to file /builds/slave/test/properties/build_url 23:05:52 INFO - Contents: 23:05:52 INFO - build_url:https://queue.taskcluster.net/v1/task/PkpPGRf1RCyoSOLJMoj7_g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 23:05:53 INFO - Running post-action listener: _resource_record_post_action 23:05:53 INFO - Running post-action listener: set_extra_try_arguments 23:05:53 INFO - [mozharness: 2016-08-31 06:05:53.022839Z] Finished download-and-extract step (success) 23:05:53 INFO - [mozharness: 2016-08-31 06:05:53.022967Z] Running populate-webroot step. 23:05:53 INFO - Running pre-action listener: _resource_record_pre_action 23:05:53 INFO - Running main action method: populate_webroot 23:05:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:05:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:05:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:05:53 INFO - '--tppagecycles', 23:05:53 INFO - '7'], 23:05:53 INFO - 'tests': ['tsvgm']}}, 23:05:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:05:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:05:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:05:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:05:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:05:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:05:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:05:53 INFO - 'talos_options': ['--disable-e10s'], 23:05:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:05:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:05:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:05:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:05:53 INFO - 'talos_options': ['--disable-e10s'], 23:05:53 INFO - 'tests': ['damp', 'tps']}, 23:05:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:05:53 INFO - 'tests': ['damp', 'tps']}, 23:05:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:05:53 INFO - 'tests': ['dromaeo_dom']}, 23:05:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:05:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:05:53 INFO - 'tests': ['basic_compositor_video']}, 23:05:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:05:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:05:53 INFO - 'tests': ['a11yr', 23:05:53 INFO - 'ts_paint', 23:05:53 INFO - 'tpaint', 23:05:53 INFO - 'sessionrestore', 23:05:53 INFO - 'sessionrestore_no_auto_restore', 23:05:53 INFO - 'tabpaint']}, 23:05:53 INFO - 'other-e10s': {'tests': ['a11yr', 23:05:53 INFO - 'ts_paint', 23:05:53 INFO - 'tpaint', 23:05:53 INFO - 'sessionrestore', 23:05:53 INFO - 'sessionrestore_no_auto_restore', 23:05:53 INFO - 'tabpaint']}, 23:05:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:05:53 INFO - 'ts_paint', 23:05:53 INFO - 'tpaint', 23:05:53 INFO - 'sessionrestore', 23:05:53 INFO - 'sessionrestore_no_auto_restore', 23:05:53 INFO - 'tabpaint']}, 23:05:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:05:53 INFO - 'ts_paint', 23:05:53 INFO - 'tpaint', 23:05:53 INFO - 'sessionrestore', 23:05:53 INFO - 'sessionrestore_no_auto_restore', 23:05:53 INFO - 'tabpaint']}, 23:05:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:05:53 INFO - 'tests': ['a11yr', 23:05:53 INFO - 'ts_paint', 23:05:53 INFO - 'tpaint', 23:05:53 INFO - 'sessionrestore', 23:05:53 INFO - 'sessionrestore_no_auto_restore', 23:05:53 INFO - 'tabpaint']}, 23:05:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:05:53 INFO - 'tests': ['a11yr', 23:05:53 INFO - 'ts_paint', 23:05:53 INFO - 'tpaint', 23:05:53 INFO - 'sessionrestore', 23:05:53 INFO - 'sessionrestore_no_auto_restore', 23:05:53 INFO - 'tabpaint']}, 23:05:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:05:53 INFO - 'tests': ['tsvgx', 23:05:53 INFO - 'tsvgr_opacity', 23:05:53 INFO - 'tart', 23:05:53 INFO - 'tscrollx', 23:05:53 INFO - 'cart']}, 23:05:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:05:53 INFO - 'tsvgr_opacity', 23:05:53 INFO - 'tart', 23:05:53 INFO - 'tscrollx', 23:05:53 INFO - 'cart']}, 23:05:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:05:53 INFO - 'talos_options': ['--disable-e10s'], 23:05:53 INFO - 'tests': ['tp5o']}, 23:05:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:05:53 INFO - 'tests': ['tp5o']}, 23:05:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:05:53 INFO - 'talos_options': ['--disable-e10s', 23:05:53 INFO - '--xperf_path', 23:05:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:05:53 INFO - 'tests': ['tp5n']}, 23:05:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:05:53 INFO - 'talos_options': ['--xperf_path', 23:05:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:05:53 INFO - 'tests': ['tp5n']}}, 23:05:53 INFO - 'talos.zip': {'path': '', 23:05:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:05:53 INFO - Running post-action listener: _resource_record_post_action 23:05:53 INFO - [mozharness: 2016-08-31 06:05:53.042262Z] Finished populate-webroot step (success) 23:05:53 INFO - [mozharness: 2016-08-31 06:05:53.042396Z] Running create-virtualenv step. 23:05:53 INFO - Running pre-action listener: _resource_record_pre_action 23:05:53 INFO - Running main action method: create_virtualenv 23:05:53 INFO - Creating virtualenv /builds/slave/test/build/venv 23:05:53 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 23:05:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:05:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:05:53 INFO - Using real prefix '/tools/python27' 23:05:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:05:53 INFO - Installing distribute.............................................................................................................................................................................................done. 23:05:56 INFO - Installing pip.................done. 23:05:56 INFO - Return code: 0 23:05:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:05:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:05:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:05:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:05:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:05:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:05:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a1c6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a25b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc730cb3e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a12ad30>, '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 0x109e8fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a122b20>, '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.QJxvBomwCY/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.S7TB73yaNv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 23:05:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:05:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:05:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QJxvBomwCY/Render', 23:05:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:05:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:05:56 INFO - 'HOME': '/Users/cltbld', 23:05:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:05:56 INFO - 'LOGNAME': 'cltbld', 23:05:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:05:56 INFO - 'MOZ_NO_REMOTE': '1', 23:05:56 INFO - 'NO_EM_RESTART': '1', 23:05:56 INFO - 'PAGER': '/bin/cat', 23:05:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:05:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:05:56 INFO - 'PWD': '/builds/slave/test', 23:05:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:05:56 INFO - 'SHELL': '/bin/bash', 23:05:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S7TB73yaNv/Listeners', 23:05:56 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 23:05:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:05:56 INFO - 'USER': 'cltbld', 23:05:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:05:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:05:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:05:56 INFO - 'XPC_FLAGS': '0x0', 23:05:56 INFO - 'XPC_SERVICE_NAME': '0', 23:05:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:05:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:05:57 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 23:05:57 INFO - Cleaning up... 23:05:57 INFO - Return code: 0 23:05:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:05:57 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')]} 23:05:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:05:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:05:57 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')]} 23:05:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:05:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:05:57 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 0x10a1c6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a25b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc730cb3e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a12ad30>, '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 0x109e8fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a122b20>, '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.QJxvBomwCY/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.S7TB73yaNv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 23:05:57 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 23:05:57 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 23:05:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QJxvBomwCY/Render', 23:05:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:05:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:05:57 INFO - 'HOME': '/Users/cltbld', 23:05:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:05:57 INFO - 'LOGNAME': 'cltbld', 23:05:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:05:57 INFO - 'MOZ_NO_REMOTE': '1', 23:05:57 INFO - 'NO_EM_RESTART': '1', 23:05:57 INFO - 'PAGER': '/bin/cat', 23:05:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:05:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:05:57 INFO - 'PWD': '/builds/slave/test', 23:05:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:05:57 INFO - 'SHELL': '/bin/bash', 23:05:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S7TB73yaNv/Listeners', 23:05:57 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 23:05:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:05:57 INFO - 'USER': 'cltbld', 23:05:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:05:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:05:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:05:57 INFO - 'XPC_FLAGS': '0x0', 23:05:57 INFO - 'XPC_SERVICE_NAME': '0', 23:05:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:05:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:05:57 INFO - Downloading/unpacking psutil>=3.1.1 23:05:57 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 23:05:57 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 23:05:57 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 23:05:57 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 23:05:57 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 23:05:57 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 23:06:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:06:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:06:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:06:00 INFO - Installing collected packages: psutil 23:06:00 INFO - Running setup.py install for psutil 23:06:00 INFO - building 'psutil._psutil_osx' extension 23:06:00 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 23:06:01 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 23:06:01 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 23:06:01 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 23:06:01 INFO - building 'psutil._psutil_posix' extension 23:06:01 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 23:06:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:06:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:06:01 INFO - ^ 23:06:01 INFO - 1 warning generated. 23:06:01 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 23:06:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:06:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:06:01 INFO - Successfully installed psutil 23:06:01 INFO - Cleaning up... 23:06:01 INFO - Return code: 0 23:06:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:06: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')]} 23:06:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:06:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06: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')]} 23:06:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:06:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06:01 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 0x10a1c6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a25b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc730cb3e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a12ad30>, '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 0x109e8fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a122b20>, '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.QJxvBomwCY/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.S7TB73yaNv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 23:06:01 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 23:06:01 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 23:06:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QJxvBomwCY/Render', 23:06:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:06:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:06:01 INFO - 'HOME': '/Users/cltbld', 23:06:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:06:01 INFO - 'LOGNAME': 'cltbld', 23:06:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:06:01 INFO - 'MOZ_NO_REMOTE': '1', 23:06:01 INFO - 'NO_EM_RESTART': '1', 23:06:01 INFO - 'PAGER': '/bin/cat', 23:06:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:06:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:06:01 INFO - 'PWD': '/builds/slave/test', 23:06:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:06:01 INFO - 'SHELL': '/bin/bash', 23:06:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S7TB73yaNv/Listeners', 23:06:01 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 23:06:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:06:01 INFO - 'USER': 'cltbld', 23:06:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:06:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:06:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:06:01 INFO - 'XPC_FLAGS': '0x0', 23:06:01 INFO - 'XPC_SERVICE_NAME': '0', 23:06:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:06:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:06:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:06:01 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 23:06:01 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 23:06:01 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 23:06:01 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 23:06:01 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 23:06:01 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 23:06:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:06:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:06:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:06:04 INFO - Installing collected packages: mozsystemmonitor 23:06:04 INFO - Running setup.py install for mozsystemmonitor 23:06:04 INFO - Successfully installed mozsystemmonitor 23:06:04 INFO - Cleaning up... 23:06:04 INFO - Return code: 0 23:06:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:06:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:06:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:06:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:06:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:06:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a1c6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a25b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc730cb3e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a12ad30>, '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 0x109e8fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a122b20>, '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.QJxvBomwCY/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.S7TB73yaNv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 23:06:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:06:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:06:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QJxvBomwCY/Render', 23:06:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:06:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:06:04 INFO - 'HOME': '/Users/cltbld', 23:06:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:06:04 INFO - 'LOGNAME': 'cltbld', 23:06:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:06:04 INFO - 'MOZ_NO_REMOTE': '1', 23:06:04 INFO - 'NO_EM_RESTART': '1', 23:06:04 INFO - 'PAGER': '/bin/cat', 23:06:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:06:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:06:04 INFO - 'PWD': '/builds/slave/test', 23:06:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:06:04 INFO - 'SHELL': '/bin/bash', 23:06:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S7TB73yaNv/Listeners', 23:06:04 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 23:06:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:06:04 INFO - 'USER': 'cltbld', 23:06:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:06:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:06:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:06:04 INFO - 'XPC_FLAGS': '0x0', 23:06:04 INFO - 'XPC_SERVICE_NAME': '0', 23:06:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:06:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:06:04 INFO - Downloading/unpacking jsonschema==2.5.1 23:06:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:06:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:06:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:06:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:06:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:06:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:06:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:06:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:06:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:06:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:06:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:06:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:06:07 INFO - Downloading functools32-3.2.3-2.tar.gz 23:06:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:06:07 INFO - warning: no files found matching '*.txt' 23:06:07 INFO - no previously-included directories found matching 'build' 23:06:07 INFO - no previously-included directories found matching 'dist' 23:06:07 INFO - no previously-included directories found matching '.git*' 23:06:07 INFO - Installing collected packages: jsonschema, functools32 23:06:07 INFO - Running setup.py install for functools32 23:06:07 INFO - warning: no files found matching '*.txt' 23:06:07 INFO - no previously-included directories found matching 'build' 23:06:07 INFO - no previously-included directories found matching 'dist' 23:06:07 INFO - no previously-included directories found matching '.git*' 23:06:07 INFO - Successfully installed jsonschema functools32 23:06:07 INFO - Cleaning up... 23:06:08 INFO - Return code: 0 23:06:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:06:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:06:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:06:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:06:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:06:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06:08 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 0x10a1c6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a25b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc730cb3e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a12ad30>, '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 0x109e8fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a122b20>, '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.QJxvBomwCY/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.S7TB73yaNv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 23:06:08 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 23:06:08 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 23:06:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QJxvBomwCY/Render', 23:06:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:06:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:06:08 INFO - 'HOME': '/Users/cltbld', 23:06:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:06:08 INFO - 'LOGNAME': 'cltbld', 23:06:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:06:08 INFO - 'MOZ_NO_REMOTE': '1', 23:06:08 INFO - 'NO_EM_RESTART': '1', 23:06:08 INFO - 'PAGER': '/bin/cat', 23:06:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:06:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:06:08 INFO - 'PWD': '/builds/slave/test', 23:06:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:06:08 INFO - 'SHELL': '/bin/bash', 23:06:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S7TB73yaNv/Listeners', 23:06:08 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 23:06:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:06:08 INFO - 'USER': 'cltbld', 23:06:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:06:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:06:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:06:08 INFO - 'XPC_FLAGS': '0x0', 23:06:08 INFO - 'XPC_SERVICE_NAME': '0', 23:06:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:06:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:06:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:06:08 INFO - Cleaning up... 23:06:08 INFO - Return code: 0 23:06:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:06:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:06:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:06:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:06:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:06:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06:08 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 0x10a1c6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a25b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc730cb3e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a12ad30>, '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 0x109e8fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a122b20>, '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.QJxvBomwCY/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.S7TB73yaNv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 23:06:08 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 23:06:08 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 23:06:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QJxvBomwCY/Render', 23:06:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:06:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:06:08 INFO - 'HOME': '/Users/cltbld', 23:06:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:06:08 INFO - 'LOGNAME': 'cltbld', 23:06:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:06:08 INFO - 'MOZ_NO_REMOTE': '1', 23:06:08 INFO - 'NO_EM_RESTART': '1', 23:06:08 INFO - 'PAGER': '/bin/cat', 23:06:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:06:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:06:08 INFO - 'PWD': '/builds/slave/test', 23:06:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:06:08 INFO - 'SHELL': '/bin/bash', 23:06:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S7TB73yaNv/Listeners', 23:06:08 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 23:06:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:06:08 INFO - 'USER': 'cltbld', 23:06:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:06:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:06:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:06:08 INFO - 'XPC_FLAGS': '0x0', 23:06:08 INFO - 'XPC_SERVICE_NAME': '0', 23:06:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:06:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:06:08 INFO - Downloading/unpacking blobuploader==1.2.4 23:06:08 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 23:06:08 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 23:06:08 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 23:06:08 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 23:06:08 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 23:06:08 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 23:06:10 INFO - Downloading blobuploader-1.2.4.tar.gz 23:06:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:06:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:06:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:06:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:06:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:06:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:06:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:06:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:06:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:06:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:06:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:06:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:06:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:06:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:06:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:06:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:06:12 INFO - Downloading docopt-0.6.1.tar.gz 23:06:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:06:12 INFO - Installing collected packages: blobuploader, requests, docopt 23:06:12 INFO - Running setup.py install for blobuploader 23:06:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:06:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:06:12 INFO - Running setup.py install for requests 23:06:12 INFO - Running setup.py install for docopt 23:06:13 INFO - Successfully installed blobuploader requests docopt 23:06:13 INFO - Cleaning up... 23:06:13 INFO - Return code: 0 23:06:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:06:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:06:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:06:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:06:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:06:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06:13 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 0x10a1c6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a25b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc730cb3e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a12ad30>, '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 0x109e8fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a122b20>, '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.QJxvBomwCY/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.S7TB73yaNv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 23:06:13 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 23:06:13 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 23:06:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QJxvBomwCY/Render', 23:06:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:06:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:06:13 INFO - 'HOME': '/Users/cltbld', 23:06:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:06:13 INFO - 'LOGNAME': 'cltbld', 23:06:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:06:13 INFO - 'MOZ_NO_REMOTE': '1', 23:06:13 INFO - 'NO_EM_RESTART': '1', 23:06:13 INFO - 'PAGER': '/bin/cat', 23:06:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:06:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:06:13 INFO - 'PWD': '/builds/slave/test', 23:06:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:06:13 INFO - 'SHELL': '/bin/bash', 23:06:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S7TB73yaNv/Listeners', 23:06:13 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 23:06:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:06:13 INFO - 'USER': 'cltbld', 23:06:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:06:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:06:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:06:13 INFO - 'XPC_FLAGS': '0x0', 23:06:13 INFO - 'XPC_SERVICE_NAME': '0', 23:06:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:06:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:06:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:06:13 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-SLIyur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:06:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:06:13 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-f83FKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:06:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:06:13 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-j_yhmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:06:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:06:13 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-Y354qz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:06:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:06:13 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-FM8vfM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:06:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:06:13 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-AXQVXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:06:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:06:14 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-glX4ko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:06:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:06:14 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-AFvjZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:06:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:06:14 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-mhfQdJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:06:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:06:14 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-gluBCG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:06:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:06:14 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-23KUeg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:06:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:06:14 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-0NxUsD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:06:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:06:14 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-r1S0FY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:06:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:06:14 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-4YdJjK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:06:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:06:14 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-a8BcoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:06:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:06:14 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-aUjPJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:06:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:06:15 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-gV2Gyr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:06:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:06:15 INFO - Running setup.py install for manifestparser 23:06:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:06:15 INFO - Running setup.py install for mozcrash 23:06:15 INFO - Running setup.py install for mozdebug 23:06:15 INFO - Running setup.py install for mozdevice 23:06:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:06:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:06:15 INFO - Running setup.py install for mozfile 23:06:15 INFO - Running setup.py install for mozhttpd 23:06:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:06:16 INFO - Running setup.py install for mozinfo 23:06:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:06:16 INFO - Running setup.py install for mozInstall 23:06:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:06:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:06:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:06:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:06:16 INFO - Running setup.py install for mozleak 23:06:16 INFO - Running setup.py install for mozlog 23:06:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:06:16 INFO - Running setup.py install for moznetwork 23:06:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:06:16 INFO - Running setup.py install for mozprocess 23:06:16 INFO - Running setup.py install for mozprofile 23:06:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:06:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:06:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:06:17 INFO - Running setup.py install for mozrunner 23:06:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:06:17 INFO - Running setup.py install for mozscreenshot 23:06:17 INFO - Running setup.py install for moztest 23:06:17 INFO - Running setup.py install for mozversion 23:06:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:06:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:06:17 INFO - Cleaning up... 23:06:17 INFO - Return code: 0 23:06:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:06:17 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')]} 23:06:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:06:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06:17 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')]} 23:06:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:06:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06:17 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 0x10a1c6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a25b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc730cb3e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a12ad30>, '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 0x109e8fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a122b20>, '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.QJxvBomwCY/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.S7TB73yaNv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 23:06:17 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 23:06:17 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 23:06:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QJxvBomwCY/Render', 23:06:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:06:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:06:17 INFO - 'HOME': '/Users/cltbld', 23:06:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:06:17 INFO - 'LOGNAME': 'cltbld', 23:06:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:06:17 INFO - 'MOZ_NO_REMOTE': '1', 23:06:17 INFO - 'NO_EM_RESTART': '1', 23:06:17 INFO - 'PAGER': '/bin/cat', 23:06:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:06:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:06:17 INFO - 'PWD': '/builds/slave/test', 23:06:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:06:17 INFO - 'SHELL': '/bin/bash', 23:06:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S7TB73yaNv/Listeners', 23:06:17 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 23:06:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:06:17 INFO - 'USER': 'cltbld', 23:06:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:06:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:06:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:06:17 INFO - 'XPC_FLAGS': '0x0', 23:06:17 INFO - 'XPC_SERVICE_NAME': '0', 23:06:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:06:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:06:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:06:18 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-lbZJc6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:06:18 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)) 23:06:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:06:18 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-0zEOHA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:06:18 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)) 23:06:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:06:18 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-9D56P9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:06:18 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)) 23:06:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:06:18 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-ILtfgr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:06:18 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)) 23:06:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:06:18 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-WahYtk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:06:18 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)) 23:06:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:06:18 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-TJTXyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:06:18 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)) 23:06:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:06:18 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-vJUWlI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:06:18 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)) 23:06:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:06:18 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-UF637a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:06:18 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)) 23:06:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:06:18 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-6le1kT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:06:18 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)) 23:06:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:06:18 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-rcymEF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:06:19 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)) 23:06:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:06:19 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-PRky_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:06:19 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)) 23:06:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:06:19 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-6LXwcG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:06:19 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)) 23:06:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:06:19 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-q01nAU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:06:19 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)) 23:06:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:06:19 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-mmHC0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:06:19 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)) 23:06:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:06:19 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-MKxkOg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:06:19 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)) 23:06:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:06:19 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-cErNaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:06:19 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)) 23:06:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:06:19 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-JTfrPa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:06:19 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)) 23:06:19 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)) 23:06:19 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)) 23:06:19 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)) 23:06:19 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)) 23:06:19 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)) 23:06:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:06:19 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 23:06:19 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 23:06:19 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 23:06:19 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 23:06:19 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 23:06:19 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 23:06:22 INFO - Downloading blessings-1.6.tar.gz 23:06:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:06:22 INFO - Installing collected packages: blessings 23:06:22 INFO - Running setup.py install for blessings 23:06:22 INFO - Successfully installed blessings 23:06:22 INFO - Cleaning up... 23:06:22 INFO - Return code: 0 23:06:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:06:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:06:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:06:22 INFO - Reading from file tmpfile_stdout 23:06:22 INFO - Current package versions: 23:06:22 INFO - blessings == 1.6 23:06:22 INFO - blobuploader == 1.2.4 23:06:22 INFO - docopt == 0.6.1 23:06:22 INFO - functools32 == 3.2.3-2 23:06:22 INFO - jsonschema == 2.5.1 23:06:22 INFO - manifestparser == 1.1 23:06:22 INFO - mozInstall == 1.12 23:06:22 INFO - mozcrash == 0.17 23:06:22 INFO - mozdebug == 0.1 23:06:22 INFO - mozdevice == 0.48 23:06:22 INFO - mozfile == 1.2 23:06:22 INFO - mozhttpd == 0.7 23:06:22 INFO - mozinfo == 0.9 23:06:22 INFO - mozleak == 0.1 23:06:22 INFO - mozlog == 3.3 23:06:22 INFO - moznetwork == 0.27 23:06:22 INFO - mozprocess == 0.23 23:06:22 INFO - mozprofile == 0.28 23:06:22 INFO - mozrunner == 6.12 23:06:22 INFO - mozscreenshot == 0.1 23:06:22 INFO - mozsystemmonitor == 0.3 23:06:22 INFO - moztest == 0.7 23:06:22 INFO - mozversion == 1.4 23:06:22 INFO - psutil == 3.1.1 23:06:22 INFO - requests == 1.2.3 23:06:22 INFO - wsgiref == 0.1.2 23:06:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:06:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:06:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:06:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:06:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:06:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06:22 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 0x10a1c6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a25b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc730cb3e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a12ad30>, '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 0x109e8fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a122b20>, '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.QJxvBomwCY/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.S7TB73yaNv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 23:06:22 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 23:06:22 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 23:06:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QJxvBomwCY/Render', 23:06:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:06:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:06:22 INFO - 'HOME': '/Users/cltbld', 23:06:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:06:22 INFO - 'LOGNAME': 'cltbld', 23:06:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:06:22 INFO - 'MOZ_NO_REMOTE': '1', 23:06:22 INFO - 'NO_EM_RESTART': '1', 23:06:22 INFO - 'PAGER': '/bin/cat', 23:06:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:06:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:06:22 INFO - 'PWD': '/builds/slave/test', 23:06:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:06:22 INFO - 'SHELL': '/bin/bash', 23:06:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S7TB73yaNv/Listeners', 23:06:22 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 23:06:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:06:22 INFO - 'USER': 'cltbld', 23:06:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:06:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:06:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:06:22 INFO - 'XPC_FLAGS': '0x0', 23:06:22 INFO - 'XPC_SERVICE_NAME': '0', 23:06:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:06:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:06:23 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)) 23:06:23 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)) 23:06:23 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)) 23:06:23 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)) 23:06:23 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)) 23:06:23 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)) 23:06:23 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)) 23:06:23 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)) 23:06:23 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)) 23:06:23 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)) 23:06:23 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)) 23:06:23 INFO - Cleaning up... 23:06:23 INFO - Return code: 0 23:06:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:06:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:06:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:06:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:06:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:06:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a1c6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a25b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc730cb3e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a12ad30>, '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 0x109e8fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a122b20>, '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.QJxvBomwCY/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.S7TB73yaNv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 23:06:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 23:06:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:06:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QJxvBomwCY/Render', 23:06:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:06:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:06:23 INFO - 'HOME': '/Users/cltbld', 23:06:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:06:23 INFO - 'LOGNAME': 'cltbld', 23:06:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:06:23 INFO - 'MOZ_NO_REMOTE': '1', 23:06:23 INFO - 'NO_EM_RESTART': '1', 23:06:23 INFO - 'PAGER': '/bin/cat', 23:06:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:06:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:06:23 INFO - 'PWD': '/builds/slave/test', 23:06:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:06:23 INFO - 'SHELL': '/bin/bash', 23:06:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S7TB73yaNv/Listeners', 23:06:23 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 23:06:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:06:23 INFO - 'USER': 'cltbld', 23:06:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:06:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:06:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:06:23 INFO - 'XPC_FLAGS': '0x0', 23:06:23 INFO - 'XPC_SERVICE_NAME': '0', 23:06:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:06:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:06:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:06:23 INFO - Cleaning up... 23:06:23 INFO - Return code: 0 23:06:23 INFO - Running post-action listener: _resource_record_post_action 23:06:23 INFO - Running post-action listener: _start_resource_monitoring 23:06:23 INFO - Starting resource monitoring. 23:06:23 INFO - [mozharness: 2016-08-31 06:06:23.518551Z] Finished create-virtualenv step (success) 23:06:23 INFO - [mozharness: 2016-08-31 06:06:23.519511Z] Running install step. 23:06:23 INFO - Running pre-action listener: _resource_record_pre_action 23:06:23 INFO - Running main action method: install 23:06:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:06:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:06:23 INFO - Reading from file tmpfile_stdout 23:06:23 INFO - Detecting whether we're running mozinstall >=1.0... 23:06:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:06:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:06:23 INFO - Reading from file tmpfile_stdout 23:06:23 INFO - Output received: 23:06:23 INFO - Usage: mozinstall [options] installer 23:06:23 INFO - Options: 23:06:23 INFO - -h, --help show this help message and exit 23:06:23 INFO - -d DEST, --destination=DEST 23:06:23 INFO - Directory to install application into. [default: 23:06:23 INFO - "/builds/slave/test"] 23:06:23 INFO - --app=APP Application being installed. [default: firefox] 23:06:23 INFO - mkdir: /builds/slave/test/build/application 23:06:23 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'] 23:06:23 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 23:06:57 INFO - Reading from file tmpfile_stdout 23:06:57 INFO - Output received: 23:06:57 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 23:06:57 INFO - Running post-action listener: _resource_record_post_action 23:06:57 INFO - [mozharness: 2016-08-31 06:06:57.734731Z] Finished install step (success) 23:06:57 INFO - [mozharness: 2016-08-31 06:06:57.734916Z] Running run-tests step. 23:06:57 INFO - Running pre-action listener: _resource_record_pre_action 23:06:57 INFO - Running main action method: run_tests 23:06:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:06:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:06:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:06:57 INFO - 2016-08-30 23:06:57.749 screenresolution[1693:8045] starting screenresolution argv=screenresolution get 23:06:57 INFO - 2016-08-30 23:06:57.752 screenresolution[1693:8045] Display 0: 1600x1200x32@60 23:06:57 INFO - 2016-08-30 23:06:57.757 screenresolution[1694:8047] starting screenresolution argv=screenresolution list 23:06:57 INFO - Available Modes on Display 0 23:06:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:06:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:06:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:06:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:06:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:06:57 INFO - Intel Iris: 23:06:57 INFO - Chipset Model: Intel Iris 23:06:57 INFO - Type: GPU 23:06:57 INFO - Bus: Built-In 23:06:57 INFO - VRAM (Dynamic, Max): 1536 MB 23:06:57 INFO - Vendor: Intel (0x8086) 23:06:57 INFO - Device ID: 0x0a2e 23:06:57 INFO - Revision ID: 0x0009 23:06:57 INFO - Displays: 23:06:57 INFO - Display: 23:06:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:06:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:06:57 INFO - Display Serial Number: 1600x1200 60 23:06:57 INFO - Main Display: Yes 23:06:57 INFO - Mirror: Off 23:06:57 INFO - Online: Yes 23:06:57 INFO - Rotation: Supported 23:06:57 INFO - Return code: 0 23:06:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:06:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:06:57 INFO - Python 2.7.3 23:06:57 INFO - Return code: 0 23:06:57 INFO - grabbing minidump binary from tooltool 23:06:57 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')]} 23:06:57 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 0x10a12ad30>, '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 0x109e8fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a122b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:06:57 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 23:06:57 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 23:06:57 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 23:06:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:06:58 INFO - Return code: 0 23:06:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:06:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:06:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:06:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:06:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:06:58 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/PkpPGRf1RCyoSOLJMoj7_g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0302', '--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 23:06:58 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/PkpPGRf1RCyoSOLJMoj7_g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0302 --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 23:06:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QJxvBomwCY/Render', 23:06:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:06:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:06:58 INFO - 'HOME': '/Users/cltbld', 23:06:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:06:58 INFO - 'LOGNAME': 'cltbld', 23:06:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:06:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:06:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:06:58 INFO - 'MOZ_NO_REMOTE': '1', 23:06:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:06:58 INFO - 'NO_EM_RESTART': '1', 23:06:58 INFO - 'PAGER': '/bin/cat', 23:06:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:06:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:06:58 INFO - 'PWD': '/builds/slave/test', 23:06:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:06:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:06:58 INFO - 'SHELL': '/bin/bash', 23:06:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S7TB73yaNv/Listeners', 23:06:58 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 23:06:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:06:58 INFO - 'USER': 'cltbld', 23:06:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:06:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:06:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:06:58 INFO - 'XPC_FLAGS': '0x0', 23:06:58 INFO - 'XPC_SERVICE_NAME': '0', 23:06:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:06:58 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/PkpPGRf1RCyoSOLJMoj7_g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0302', '--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 23:06:58 INFO - mozversion application_buildid: 20160830211321 23:06:58 INFO - mozversion application_changeset: 56cc74607c72f390645dd048fe874e437bc5e4e5 23:06:58 INFO - mozversion application_display_name: Nightly 23:06:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:06:58 INFO - mozversion application_name: Firefox 23:06:58 INFO - mozversion application_remotingname: firefox 23:06:58 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 23:06:58 INFO - mozversion application_vendor: Mozilla 23:06:58 INFO - mozversion application_version: 51.0a1 23:06:58 INFO - mozversion platform_buildid: 20160830211321 23:06:58 INFO - mozversion platform_changeset: 56cc74607c72f390645dd048fe874e437bc5e4e5 23:06:58 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 23:06:58 INFO - mozversion platform_version: 51.0a1 23:06:58 INFO - using testdate: 1472623618 23:06:58 INFO - actual date: 1472623618 23:06:58 INFO - starting webserver on 'localhost:49248' 23:06:58 INFO - SUITE-START | Running 2 tests 23:06:58 INFO - TEST-START | dromaeo_css 23:06:58 INFO - operating with platform_type : mac_ 23:06:58 INFO - Initialising browser for dromaeo_css test... 23:06:58 INFO - TEST-INFO | started process 1704 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/tmpFR6u76/profile http://localhost:49248/getInfo.html) 23:07:00 INFO - PROCESS | 1704 | __metrics Screen width/height:1600/1200 23:07:00 INFO - PROCESS | 1704 | colorDepth:24 23:07:00 INFO - PROCESS | 1704 | Browser inner width/height: 1024/689 23:07:00 INFO - PROCESS | 1704 | __metrics 23:07:00 INFO - PROCESS | 1704 | 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" 23:07:00 INFO - PROCESS | 1704 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:07:01 INFO - TEST-INFO | 1704: exit 0 23:07:01 INFO - Browser initialized. 23:07:01 INFO - Running cycle 1/1 for dromaeo_css test... 23:07:01 INFO - TEST-INFO | started process 1707 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/tmpFR6u76/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:07:13 INFO - PROCESS | 1707 | RSS: Main: 234749952 23:07:13 INFO - PROCESS | 1707 | 23:08:04 INFO - PROCESS | 1707 | 1472623684921 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] 23:10:16 INFO - PROCESS | 1707 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49248/tests/dromaeo/cssquery-ext.html) 23:10:18 INFO - PROCESS | 1707 | RSS: Main: 354291712 23:10:18 INFO - PROCESS | 1707 | 23:13:21 INFO - PROCESS | 1707 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-ext.html (next: http://localhost:49248/tests/dromaeo/cssquery-jquery.html) 23:13:21 INFO - PROCESS | 1707 | RSS: Main: 370929664 23:13:21 INFO - PROCESS | 1707 | 23:16:24 INFO - PROCESS | 1707 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49248/tests/dromaeo/cssquery-mootools.html) 23:16:24 INFO - PROCESS | 1707 | RSS: Main: 342818816 23:16:24 INFO - PROCESS | 1707 | 23:19:27 INFO - PROCESS | 1707 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49248/tests/dromaeo/cssquery-prototype.html) 23:19:27 INFO - PROCESS | 1707 | RSS: Main: 339881984 23:19:27 INFO - PROCESS | 1707 | 23:22:31 INFO - PROCESS | 1707 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49248/tests/dromaeo/cssquery-yui.html) 23:22:31 INFO - PROCESS | 1707 | RSS: Main: 324386816 23:22:31 INFO - PROCESS | 1707 | 23:25:35 INFO - PROCESS | 1707 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-yui.html (next: http://localhost:49248/tests/dromaeo/cssquery-dojo.html) 23:25:35 INFO - PROCESS | 1707 | RSS: Main: 542302208 23:25:35 INFO - PROCESS | 1707 | 23:25:35 INFO - PROCESS | 1707 | __start_tp_report 23:25:35 INFO - PROCESS | 1707 | _x_x_mozilla_page_load 23:25:35 INFO - PROCESS | 1707 | _x_x_mozilla_page_load_details 23:25:35 INFO - PROCESS | 1707 | |i|pagename|runs| 23:25:35 INFO - PROCESS | 1707 | |0;dojo.html;2781.888724451022;3331.2338162222686;3344.8996530104105;3369.6461871503475;3401.4387626041703;5462.371827239868;5533.778648854041;5562.833115006546;5615.4384561543875;5641.802536911209;5552.9167062494;5578.163275508676;5684.37471878093;5692.37383887773;5706.714664266791;5030.170021946375;5112.718800465973;5117.309163262949;5138.254953031808;5288.867778305554;5208.817691380802;5222;5225.608079394042;5242.370915490146;5266.973665131669;5035.320231768701;5069.518395752398;5079.923801142986;5122.513361230677;5193.298904647881;5125.487451254882;5129.435762066169;5135.101357262464;5142.717150556718;5144.768485418165;5054.140796064641;5074.847754567369;5102.6428150029515;5112.437631860493;5117.334746482933;5408.0806262935;5554.055810512182;5599.440055994409;5633.014222511044;5710.71446427677;5822.82531524055;5897.764089436418;5899.793507227227;5904.409559044061;5912.201852749909;4894.2658601210105;5976.043832986701;5985.670788106652;5999.910001349983;6014.669193194372;5548.112302031655;5568.331800183963;5584.21820945068;5605.775768969195;5686.829395118153;6274.592151510137;6293.37066293367;6319.873602528016;6331.905021424683;6335.809925702236;5955.434233747787;5970.970145149333;5974.4324289192455;5987.341392446828;5993.520518358521;5945.3757355477055;5990.460858522754;6124.289394097934;6124.663143527059;6158.445739883432;5124.795008199705;6027.427394397525;6030.6080104793045;6080.635161890301;6131.233595800525;6060.909086363708;6071.696415179224;6075.179850720185;6081.695915204222;6096.420840020191;4754.667173297905;4786.44955830077;4793.424789025339;4810.42274927004;4829.203181475087;4352.281873490839;4384.0355121873135;4401.383806267125;4421;4437.245668236407;5658.179563963285;5686.97156514223;5694.117411801178;5726.856828579235;5735.426457354231;5908.379620139824;6014.368491308437;6218.5647004710145;6248.625082495065;6257.624542527463;5595.776168953278;5637.154426836008;5645.23789288449;5668.178114173422;5694.629849059798;5294.41761406245;5313.707746073926;5319.202119682079;5326.760295786699;5338.039152952506;6163.722632481459;6288.496920246369;6310.368963103653;6351.96824015877;6374.394432528902;6063.333033366414;6116.113163591343;6128.325884152829;6144.1398204250545;6154.138420621206;6313.1477250572425;6321.747130114744;6345.333739957423;6347.39699728525;6359.20509936258;5810.5351571875;5948.315943666361;5956.553258505716;5960.165576819162;5961.165436838933;6050.7882224123705;6078.088286757022;6103.786367477117;6144.969275153596;6169.629822210682;5744.4829965303325;5904.409559044061;5917.378675239038;5923.348431672426;5926.585139040226;5749.252597162342;5883.294004719461;5933.1396947443245;5936.495397891227;5944.73248703818;5780.913286300793;5812.94187058124;5829.7085145743395;5847.590668653153;5891.1752354669525;5955.851103722442;5956.106584012259;5958.582899197015;5970.820875373745;5983.880322393441;5485.710857948458;5509.338879334506;5616.522595579421;5787.8553036174435;5800.332961709458 23:25:35 INFO - PROCESS | 1707 | |1;ext.html;526.6234642230805;527.1486549223005;536.8040665157218;537.9004884096443;558.4610850529239;25885.094021709243;31421;41376.96557586058;41457.378139327935;43430;43099;43355;43405.34891976623;43517;43565;8613.440126391795;9512.381695189808;9718.465484398355;9725.367851089692;9744.75638109046;8683.34874884383;8829.984551776532;8839;8862.29101671868;11734.706632334175;11787.233829801078;12010.399480025964;12099.153059285854;12103.515859365614;12109.273443593369;11955.521779128825;12004.2197257178;12030.51877924882;12037.999999999956;12042.157049006573;11771.764564708743;11857.94071029648;11858.991985681227;11860.22908510944;11861.347625880573;39917.40123898144;40154;40194.79902600498;40273.798631006954;40374.59625403738;3217.678232176787;3338.7996720196866;3438.82805859706;3758.5677647070665;3855.325318069327;24024.399390015216;31228.531572026437;31409.8429507851;31460.842695786603;31706.207344816332;3201.375731732313;3225.8387080646107;3232.7090561849313;3237.5592861176788;3309.387763263804;435464;439443;439884;442801;447381;33155;59655;59910.70044649749;59987.40012599818;60154;33701.66298337034;35150;35189.64810351915;35221.64778352235;35279.4708079379;14198.787018194735;14343.497977570734;14381.28093595316;14397.928010359881;14399;12139.514419423302;12247.326397048248;12260.26438413698;12267.693307667201;12323.322217277957;7189.712411503481;7331.486795924341;7618.085829700472;7746.109197442253;7761.8447631047065;3190.617125944855;3254.5606343143845;3255.9348813023607;3282.3599398117617;3314.1548905029545;871.5816408124009;888.1118881118881;944.1313991128178;983.4640121133999;988.5749127875083;29486.705132948395;29958.55062174069;29988.55017174744;31030;31043.223919402197;1031.4842578710645;1039.73486760877;1054.404261592198;1095.8739744929435;1156.8727439981594;12777.105602607728;15039.398424063134;15509.302081406364;15658.373665053497;15938;667886;673512;674504;675218;675291;58219.70890145522;58629.41370586325;58663;58676.7066164675;58821.411785881595;1295.7149427126017;1335.2522587351114;1351.3243378310844;1363.9590812275649;1367.9179249244878;27092.45815083727;28668;29687.85156074206;30245;30263.09210723682;51264.48735512597;51270.74364628153;51397.68710407966;51419;51454.74272628613;569503;569933;570197;572251;573363;23200.651990219823;23717.52564948726;25650.871745641154;26361;26751.464970700865;43161;46595.534044660475;46975;47083.529164707914;47319;38644.80677596594;39002;39100.413493797045;39932.401013984236;40511.392329115675;38344.864821628405;38380.42429363618;38439.6156038436;39680.801595991834;40211.99470013273;38217.61782382252;38457.23085538329;38537.421938670384;38925.83222503329;39029.6097039026;27672.72327276782;32660;33672;34190.65809341974;34596.962091137306;20644.948634840843;24576;25056.74943250617;25397.238082857544;25736.61395079113 23:25:35 INFO - PROCESS | 1707 | |2;jquery.html;348.2495222794877;357.54056037991154;383.95200599924993;387.34345284742363;410.0609604006823;15650.217489125542;15839.287232074556;20718.896405517953;20829.06269217885;20923.163073477062;19545.41363759086;20143.89208593527;20213.898930505326;21691.457713557167;22504;5729.914051289234;6693.665316734144;6889.7588584399555;6979.267176946399;7040.788776336718;4659.953400466003;6807.693653785567;6906.896396554056;6916.827079323032;6928.896066559006;7430.702771889118;7938.563379014152;7960.6019699015105;7998.080220774598;8080.959595202045;7781.813458300636;7801.3758899288205;7921.6435260412845;7965.203479652047;7989.5605741684185;7197.424206063554;7578.166401695809;7647.34997525211;7676.155622881479;7691.730789422343;28487.28781780472;28748.425031499253;28868.566971495446;29123;29297.85351073231;2069.7412823397076;2270.1146552844402;2318.327684971356;2333.5916214662366;2421.5156968606175;13560.932195339059;13941.442342306296;15506.302216400289;15539.5338139856;17183.742243866353;1775.2277759174801;1947.6883698608156;2050.9077091530917;2065.514604068043;2089.4254080127935;127667.72332276744;143895;144515.27742361327;148370;151261;18106.637867242844;19942.50143746418;20448.38654840357;20815.37553873386;21435.249766258105;34067.82966085154;34145;34189.316213675585;34244;34288.48567271493;8782.110954861466;10504.212184086224;10585.788284234426;10657.467126643793;10897.945510272399;8678.609462574073;8841.16008979146;8861.64553417856;8883.022867484571;9049.185573298435;8217.917820821835;9231.399959002643;9617.855732164024;9683.564239609093;9715.46564938935;3161.8893338733033;3602.441621548664;3686.8340924658455;3686.9631303687156;3804.6493494809497;814.2427542385639;817.9427440079137;855.2936890902689;932.379967321737;985.8521221816642;16596;17138.742918856227;19332.613347732968;20839.47901302449;20951.580968380546;908.8545832666741;917.9127982841619;924.4453328003144;978.9902100978899;998.1116806042628;9825.901740982641;10366.844497332546;10646.520906559223;10826.512806923707;10905.127589792797;527436;559986;567131;570536;588715;128076.35961820131;129058;134375;134635.65364346426;135117;952.3857112162477;961.077365728908;985.1773768903046;987.7382493639192;988.1847475832467;17333.306667733148;17772.911135444756;17810.465686029438;17902.194401251716;17943.910280448516;51824.481755181965;52525.737371314426;53237.7338113307;53569.73215133899;54333.72833135809;78040.60979695065;78197.60901195457;78246.43507129939;79125.60437197777;79919.20080799118;6641.734330626721;6969.745445819801;7021.964890175516;8087.959560202161;8229.670813167406;11365.659030229106;12844.807327889903;12916.28960407149;13062.34688265602;13072.738545229233;11124.054455371384;11174.832377514507;11318.377489238172;11355.943220283845;11484.368359740294;8528.530930798866;10950.507227174956;11008.669739907815;11235.4382280884;11445.656630301104;10492;11032.669019929415;11378.08975281992;11475.770484590428;11607.477663505331;6751.831204219738;7996.84006319882;8119.025716914008;8286.171382861907;8412.453190542596;8088.191180881865;9822.55798489084;11028;11315.377654229096;11335.886641133813 23:25:35 INFO - PROCESS | 1707 | |3;mootools.html;1341.9334225293048;1462.7147706197288;1498.4905132255035;1520.3538496139138;1770.5396596884814;7715.2670496302835;8514.91485085149;8832.514211718353;8837.999999999993;8945.865812012826;8693.608787604557;8776.473411595309;8781.56092195391;8795.824083518326;8808.91191088089;2350.8824558772035;2381.6070348392495;2387.438951846315;2387.5941090014653;2392.964105538418;2324.5815753164425;2371.845830021052;2373.0389192377115;2374.4657452073234;2378.3935096550354;2338.976610233893;2361.7047869016374;2376.1089591403224;2377.548265829487;2380.8095352371856;2311.6185829338137;2336.7780060894274;2336.8481048731946;2338.590746619335;2355.3758254062514;2306.1351993002622;2335.5328934213226;2335.953280934372;2340.4382948092507;2343.988280058606;8576.828463430697;8734.563271836383;8825.911740882573;8832.514211718353;8840.1601847825;5187;5385.299911011543;5390.6226564140525;5400.756965936543;5404.000259951882;3506.7194624429985;3519.6304388039143;3588.5334906462253;3590.838412271454;3619.3847046002243;1067.0983019348637;1068.7595291059527;1080.206048554312;1082.3830416662506;1090.514720949178;11425.600103996403;11498.712532186679;11575.421228938603;11580.478878450405;11581.478833452431;4354.433923589913;4402.713823601455;4560.475545312265;4569.13186494565;4585.28928016158;9032.8645070324;9046.095390461032;9046.411983221069;9049.457032578066;9049.954750226207;5169.534741873174;5171.456997015259;5177;5182.404023537266;5183.611229157828;4181.770002649822;4316.830123281684;4367.213901497697;4522.977385113053;4865.245886887538;3728.9813550932613;3751.0247335692916;3773.9998900291444;3783.1866148778145;3798.981005094957;1129.1644183304295;1150.4535345710888;1150.810116330796;1163.668354518958;1169.181572898974;843.8734189871445;864.6714248585497;874.8206617643368;892;921.5254144115786;5772.884542309214;5781.624194427349;5998.880022399615;6055.394460553997;6271.686415679198;906.0531744327194;910;912.4570880326195;922.1838672774509;966.6665000574791;3624.5469316335457;3838.961610383916;3858.421236814444;3895.6299151580556;4023.0344717267644;9183.448993060438;10378.532966016548;10811.729706757395;10818.729531761768;10830.837537436944;10752.301100428447;10766.084882784895;10773.676789696321;10788.568457261621;10796.136309095253;7645.311921927053;8005.679772809022;8019.35845132388;8055.838883222303;8274.710385136492;5827.59206855533;5854;5995.550333724901;6013.9097913532205;6079.3008803988105;9909.752256193653;10004.649837255662;10025.749356266151;10041.849372259276;10076.798464030824;9222.769430764285;9530.61877524919;9983.051610097029;9992.300538962203;10190.082892539707;6931.826704332432;7907.090684571348;8062.072861620754;8172.42793004484;8186.386021048326;7971.641276142472;8125.3905957052275;8222.054463736747;8269.421140520106;8330.916690833257;6695.665216739241;7315.341619254293;7344.853102937805;7351.448641352027;7355.5586664800285;7241.456890733321;7250.383717384081;7314.232005639544;7317.26827317264;7319.670614822239;5937.9406205937385;6827.89758153638;6965.373116419547;6972.372486476039;6978.755743548952;4767.547083027107;4876.634252431011;4930.926036109389;4971.453140154508;5150.639455238097;4913.729744864067;4954.702717836942;5043.167877300204;5054.140796064751;5140.4088529818055 23:25:35 INFO - PROCESS | 1707 | |4;prototype.html;266.58279792125325;299.88454445038667;315.243415802075;315.2732950548985;316.41305378522856;4940.308356830046;5184.429712731601;5205.921911171326;5238.18808084747;5250.606204534661;4304.526502084768;4486.214912390326;5017.315736081197;5125.256837758523;5142.254373115896;4009.5789942056176;4023.5171779386515;4040.2929487339743;4068.593140685937;4072.6741860651077;3878.45701601776;4052.53395859477;4066.125782956664;4075.8369665213354;4088;3280.9821959494857;3313.8840140595084;3978.8806335809827;3991.840326386941;4009.2983727847654;3962.346212874872;3963.7423567468168;3989.501312335958;3996.5004374453047;4032.495938007749;3044.9086527404215;3360.6471320511237;3508.5438892944007;3512.929741405183;3515.982420087909;4202.700879809366;4616.492185859553;4996.275540046673;5077;5091.581498788083;3891.7664940103687;3919.8824035278985;4586.564276393738;4604.401427814396;4611.262198048296;4785.545373189541;5081.339425874649;5496.395396506381;5502;5531.1790986596725;3493.4410494320787;3731.626837316274;3873.3028054950096;3883.3398322284997;3890.5525864525657;4782.545658162469;4968.87577810554;5797.275340582427;5810.912836307417;5826.242588463558;3720.897692803557;3960.089179488734;3971.6624086952356;3982.2234664240195;3984.7609143451486;3106.9067927962196;3774.490443790053;3940.9802950985063;3949.328614135604;3993.7803420812133;4363.38912552243;4530.365748795171;4538.411555955455;4563.95436045642;4624.1445332613575;4688.4373875134515;4802.639802014863;5479.972600136974;5503.477169668875;5521.812810245817;1276.7382686549236;1322.385090932721;1329.7168232655533;1336.659151916255;1349.757043732118;690.6063543780098;773.2190487607556;828.2752591482453;835.3609488741118;844.3282301043845;4215.283401821697;4307.8492252771;4313.439252897103;4344.174606824757;4358.738475691405;4037.6567991720444;4065.4918135233097;4341.891452713644;4658.557437043475;4753.928691069636;3632.418812989909;4276.87169384919;4291;4299.892502687458;4336.6773134193745;2095.476130967258;2129.8828564429105;2216.1689366487567;2496.8377055491337;2500.3624075860903;4151.6886233532605;4807.81719903686;4877.195787285923;4899.187300698693;4904.896398310351;4255.468066491689;4786.234202527578;4790.94599188178;4812.807487700453;4839.540243676845;4616.545034042166;4726.976365118152;4873.610111191097;5391.514763671288;5780.682062486603;5669.3480249770155;5677.006523858226;5702.2016917630735;5719.084946408555;5741.569382296261;4756.667033307635;5299.6290259681455;5467.097649546987;5620.690862002629;5650.180723794944;5823.922574323764;5832.591718579828;5835.241418615553;5899.056151015817;5911.645301281765;3302.488114342281;3523.894283171509;3690.630936906288;3724.2923844469465;3751.8499260030385;5053.140966035856;5538.91691624634;5545.223668686306;5548.583856210719;5559.8888022240135;4503.864884053483;5132.79468821243;5492.176173573996;5550.972245138749;5576.693281869536;4611.677182597186;5285.577153827608;5466.972665136649;5503.724813759376;5601.663900166003;4411.669124815588;5345.743750218618;5392.379876314274;5403.918941215807;5405.837824865261;4838.685485443435;5135.303965418186;5534.928114896271;5546.223528705904;5578.079616863172;4574.428196475441;5348.759305831325;5360.16917377807;5363.383210930793;5385.138377859524 23:25:35 INFO - PROCESS | 1707 | |5;yui.html;649.8602800397916;696.5507247825153;700.3591713582073;739.2275072549186;766.6243540665076;12417.254964702124;15811.288492017859;15816.36734530619;15832.920835395822;15849.286782094805;14226.288685565733;14293.428262869498;14317;14357.210353430582;14749.705005899876;688.5008368932534;712.3232928717713;848.1730312944891;877.8814859993915;881.1056777370974;856.1609622569886;879.3273146043282;890.261083300862;893.620211410148;896.5024411451642;936.1387523478417;936.9812603747955;938.6620816506053;940.5861420974784;943.42451104826;730.3791776989569;899.8110396816611;926.4533924984291;929.2565947242213;931.6040682709821;701.9926405607969;703.1035429827011;705.3898377903129;706.2407911495163;707.6603230449361;14831.48089816862;14929.402823887032;14936;14946.32741526634;14965.782356793234;12564.680708525657;13405.128666636734;13693;14142.36359363831;14259.14445133285;12359.38203089842;12563;12593.93703031488;12893.871061289454;13083.803742943768;3582.5342705448124;4520.593146616788;4548.749818759967;4736.739479328636;4759.595434388081;103814.48092759488;106446.46776766221;114285;115714.42142789316;116595.4170229152;10968.23222374442;11087.55649774;11104.611338603272;11302.830457543143;11424.657260282205;21624.56750864974;21689.674654879873;21715.891420542797;21734.45663858416;21777.78222217789;7969;8018.438709290293;8262.380321475914;8268.586570671443;8327.417080804407;4324.870253892325;4465.553444655508;4535.070310586322;4539.0921815637;4715.4577223619035;3513.7013353864695;3664.8167591620313;3667.9633203668154;3670.210904655512;3677.191017976041;655.4625207330039;659.6207180871019;661.7253839656489;674.814426032845;677.0352248046514;563.6111083352556;613.9048447490646;638.3807706524664;656.9343065693488;657.103054330835;8869.778255543533;9333.90666093344;9440.622375104918;9520.666776662922;9551.522423878778;581.9592628516048;625.5050429473562;668.3951024322995;676.1885737115481;680.6732768271257;3997.540282867449;4301.408478862841;4488.644266497073;4590.634388405324;4700.905981880412;94498.1723653663;94718;95464.52267738756;142562.57437425494;165468.60488793324;135037.3248133753;139406.3029684845;141587;143087.2845635786;147943.2602836979;78451.60774196092;89183.55408222918;116837.41581292039;120354.15041894141;122623;8849.336299777671;8930.910690893008;8939.59771810283;9035.503047332197;9070.274378049871;59336.10995834979;61064.694676526335;64301;66489.66755166193;67073;88176.55911720658;91493.54253228691;93745.06254937637;95710.5214473951;96714;11152.386618736047;11457.484413201259;11672.358020308964;11782.116341274268;12507.187032842836;12159.635210943687;12370.257784532598;12484.937575312066;12529;12705.745885082062;11797.29216247028;12071.215371000857;12389.62831115068;12505;12610.621681349574;11078;11201.943990280322;12101.334426606621;12425.689357766129;12548.498060077494;9820.705378838646;10834.187435942178;11529.884701152881;11992.880071199177;12164.40912678137;9313.34806563534;9696.210584913712;9707.757306067404;9708.951455242961;9718.562664680248;8520.488770673779;8959.462432254086;9781.364211326241;9811.754706132404;9840.212782977487 23:25:35 INFO - PROCESS | 1707 | __end_tp_report 23:25:35 INFO - PROCESS | 1707 | __start_cc_report 23:25:35 INFO - PROCESS | 1707 | _x_x_mozilla_cycle_collect,1159 23:25:35 INFO - PROCESS | 1707 | __end_cc_report 23:25:35 INFO - PROCESS | 1707 | __startTimestamp1472624735331__endTimestamp 23:25:35 INFO - PROCESS | 1707 | 23:25:35 INFO - PROCESS | 1707 | ------- Summary: start ------- 23:25:35 INFO - PROCESS | 1707 | Number of tests: 6 23:25:35 INFO - PROCESS | 1707 | 23:25:35 INFO - PROCESS | 1707 | [#0] dojo.html Cycles:165 Average:5615.08 Median:5780.91 stddev:626.58 (10.8%) stddev-sans-first:587.76 23:25:35 INFO - PROCESS | 1707 | Values: 2781.9 3331.2 3344.9 3369.6 3401.4 5462.4 5533.8 5562.8 5615.4 5641.8 5552.9 5578.2 5684.4 5692.4 5706.7 5030.2 5112.7 5117.3 5138.3 5288.9 5208.8 5222.0 5225.6 5242.4 5267.0 5035.3 5069.5 5079.9 5122.5 5193.3 5125.5 5129.4 5135.1 5142.7 5144.8 5054.1 5074.8 5102.6 5112.4 5117.3 5408.1 5554.1 5599.4 5633.0 5710.7 5822.8 5897.8 5899.8 5904.4 5912.2 4894.3 5976.0 5985.7 5999.9 6014.7 5548.1 5568.3 5584.2 5605.8 5686.8 6274.6 6293.4 6319.9 6331.9 6335.8 5955.4 5971.0 5974.4 5987.3 5993.5 5945.4 5990.5 6124.3 6124.7 6158.4 5124.8 6027.4 6030.6 6080.6 6131.2 6060.9 6071.7 6075.2 6081.7 6096.4 4754.7 4786.4 4793.4 4810.4 4829.2 4352.3 4384.0 4401.4 4421.0 4437.2 5658.2 5687.0 5694.1 5726.9 5735.4 5908.4 6014.4 6218.6 6248.6 6257.6 5595.8 5637.2 5645.2 5668.2 5694.6 5294.4 5313.7 5319.2 5326.8 5338.0 6163.7 6288.5 6310.4 6352.0 6374.4 6063.3 6116.1 6128.3 6144.1 6154.1 6313.1 6321.7 6345.3 6347.4 6359.2 5810.5 5948.3 5956.6 5960.2 5961.2 6050.8 6078.1 6103.8 6145.0 6169.6 5744.5 5904.4 5917.4 5923.3 5926.6 5749.3 5883.3 5933.1 5936.5 5944.7 5780.9 5812.9 5829.7 5847.6 5891.2 5955.9 5956.1 5958.6 5970.8 5983.9 5485.7 5509.3 5616.5 5787.9 5800.3 23:25:35 INFO - PROCESS | 1707 | 23:25:35 INFO - PROCESS | 1707 | [#1] ext.html Cycles:180 Average:68265.33 Median:35250.56 stddev:152665.87 (433.1%) stddev-sans-first:153009.42 23:25:35 INFO - PROCESS | 1707 | Values: 526.6 527.1 536.8 537.9 558.5 25885.1 31421.0 41377.0 41457.4 43430.0 43099.0 43355.0 43405.3 43517.0 43565.0 8613.4 9512.4 9718.5 9725.4 9744.8 8683.3 8830.0 8839.0 8862.3 11734.7 11787.2 12010.4 12099.2 12103.5 12109.3 11955.5 12004.2 12030.5 12038.0 12042.2 11771.8 11857.9 11859.0 11860.2 11861.3 39917.4 40154.0 40194.8 40273.8 40374.6 3217.7 3338.8 3438.8 3758.6 3855.3 24024.4 31228.5 31409.8 31460.8 31706.2 3201.4 3225.8 3232.7 3237.6 3309.4 435464.0 439443.0 439884.0 442801.0 447381.0 33155.0 59655.0 59910.7 59987.4 60154.0 33701.7 35150.0 35189.6 35221.6 35279.5 14198.8 14343.5 14381.3 14397.9 14399.0 12139.5 12247.3 12260.3 12267.7 12323.3 7189.7 7331.5 7618.1 7746.1 7761.8 3190.6 3254.6 3255.9 3282.4 3314.2 871.6 888.1 944.1 983.5 988.6 29486.7 29958.6 29988.6 31030.0 31043.2 1031.5 1039.7 1054.4 1095.9 1156.9 12777.1 15039.4 15509.3 15658.4 15938.0 667886.0 673512.0 674504.0 675218.0 675291.0 58219.7 58629.4 58663.0 58676.7 58821.4 1295.7 1335.3 1351.3 1364.0 1367.9 27092.5 28668.0 29687.9 30245.0 30263.1 51264.5 51270.7 51397.7 51419.0 51454.7 569503.0 569933.0 570197.0 572251.0 573363.0 23200.7 23717.5 25650.9 26361.0 26751.5 43161.0 46595.5 46975.0 47083.5 47319.0 38644.8 39002.0 39100.4 39932.4 40511.4 38344.9 38380.4 38439.6 39680.8 40212.0 38217.6 38457.2 38537.4 38925.8 39029.6 27672.7 32660.0 33672.0 34190.7 34597.0 20644.9 24576.0 25056.7 25397.2 25736.6 23:25:35 INFO - PROCESS | 1707 | 23:25:35 INFO - PROCESS | 1707 | [#2] jquery.html Cycles:180 Average:36180.94 Median:17318.37 stddev:94926.00 (548.1%) stddev-sans-first:95154.17 23:25:35 INFO - PROCESS | 1707 | Values: 348.2 357.5 384.0 387.3 410.1 15650.2 15839.3 20718.9 20829.1 20923.2 19545.4 20143.9 20213.9 21691.5 22504.0 5729.9 6693.7 6889.8 6979.3 7040.8 4660.0 6807.7 6906.9 6916.8 6928.9 7430.7 7938.6 7960.6 7998.1 8081.0 7781.8 7801.4 7921.6 7965.2 7989.6 7197.4 7578.2 7647.3 7676.2 7691.7 28487.3 28748.4 28868.6 29123.0 29297.9 2069.7 2270.1 2318.3 2333.6 2421.5 13560.9 13941.4 15506.3 15539.5 17183.7 1775.2 1947.7 2050.9 2065.5 2089.4 127667.7 143895.0 144515.3 148370.0 151261.0 18106.6 19942.5 20448.4 20815.4 21435.2 34067.8 34145.0 34189.3 34244.0 34288.5 8782.1 10504.2 10585.8 10657.5 10897.9 8678.6 8841.2 8861.6 8883.0 9049.2 8217.9 9231.4 9617.9 9683.6 9715.5 3161.9 3602.4 3686.8 3687.0 3804.6 814.2 817.9 855.3 932.4 985.9 16596.0 17138.7 19332.6 20839.5 20951.6 908.9 917.9 924.4 979.0 998.1 9825.9 10366.8 10646.5 10826.5 10905.1 527436.0 559986.0 567131.0 570536.0 588715.0 128076.4 129058.0 134375.0 134635.7 135117.0 952.4 961.1 985.2 987.7 988.2 17333.3 17772.9 17810.5 17902.2 17943.9 51824.5 52525.7 53237.7 53569.7 54333.7 78040.6 78197.6 78246.4 79125.6 79919.2 6641.7 6969.7 7022.0 8088.0 8229.7 11365.7 12844.8 12916.3 13062.3 13072.7 11124.1 11174.8 11318.4 11355.9 11484.4 8528.5 10950.5 11008.7 11235.4 11445.7 10492.0 11032.7 11378.1 11475.8 11607.5 6751.8 7996.8 8119.0 8286.2 8412.5 8088.2 9822.6 11028.0 11315.4 11335.9 23:25:35 INFO - PROCESS | 1707 | 23:25:35 INFO - PROCESS | 1707 | [#3] mootools.html Cycles:180 Average:5633.55 Median:5048.65 stddev:3155.51 (62.5%) stddev-sans-first:3147.87 23:25:35 INFO - PROCESS | 1707 | Values: 1341.9 1462.7 1498.5 1520.4 1770.5 7715.3 8514.9 8832.5 8838.0 8945.9 8693.6 8776.5 8781.6 8795.8 8808.9 2350.9 2381.6 2387.4 2387.6 2393.0 2324.6 2371.8 2373.0 2374.5 2378.4 2339.0 2361.7 2376.1 2377.5 2380.8 2311.6 2336.8 2336.8 2338.6 2355.4 2306.1 2335.5 2336.0 2340.4 2344.0 8576.8 8734.6 8825.9 8832.5 8840.2 5187.0 5385.3 5390.6 5400.8 5404.0 3506.7 3519.6 3588.5 3590.8 3619.4 1067.1 1068.8 1080.2 1082.4 1090.5 11425.6 11498.7 11575.4 11580.5 11581.5 4354.4 4402.7 4560.5 4569.1 4585.3 9032.9 9046.1 9046.4 9049.5 9050.0 5169.5 5171.5 5177.0 5182.4 5183.6 4181.8 4316.8 4367.2 4523.0 4865.2 3729.0 3751.0 3774.0 3783.2 3799.0 1129.2 1150.5 1150.8 1163.7 1169.2 843.9 864.7 874.8 892.0 921.5 5772.9 5781.6 5998.9 6055.4 6271.7 906.1 910.0 912.5 922.2 966.7 3624.5 3839.0 3858.4 3895.6 4023.0 9183.4 10378.5 10811.7 10818.7 10830.8 10752.3 10766.1 10773.7 10788.6 10796.1 7645.3 8005.7 8019.4 8055.8 8274.7 5827.6 5854.0 5995.6 6013.9 6079.3 9909.8 10004.6 10025.7 10041.8 10076.8 9222.8 9530.6 9983.1 9992.3 10190.1 6931.8 7907.1 8062.1 8172.4 8186.4 7971.6 8125.4 8222.1 8269.4 8330.9 6695.7 7315.3 7344.9 7351.4 7355.6 7241.5 7250.4 7314.2 7317.3 7319.7 5937.9 6827.9 6965.4 6972.4 6978.8 4767.5 4876.6 4930.9 4971.5 5150.6 4913.7 4954.7 5043.2 5054.1 5140.4 23:25:35 INFO - PROCESS | 1707 | 23:25:35 INFO - PROCESS | 1707 | [#4] prototype.html Cycles:180 Average:4290.40 Median:4614.08 stddev:1326.42 (28.7%) stddev-sans-first:1295.30 23:25:35 INFO - PROCESS | 1707 | Values: 266.6 299.9 315.2 315.3 316.4 4940.3 5184.4 5205.9 5238.2 5250.6 4304.5 4486.2 5017.3 5125.3 5142.3 4009.6 4023.5 4040.3 4068.6 4072.7 3878.5 4052.5 4066.1 4075.8 4088.0 3281.0 3313.9 3978.9 3991.8 4009.3 3962.3 3963.7 3989.5 3996.5 4032.5 3044.9 3360.6 3508.5 3512.9 3516.0 4202.7 4616.5 4996.3 5077.0 5091.6 3891.8 3919.9 4586.6 4604.4 4611.3 4785.5 5081.3 5496.4 5502.0 5531.2 3493.4 3731.6 3873.3 3883.3 3890.6 4782.5 4968.9 5797.3 5810.9 5826.2 3720.9 3960.1 3971.7 3982.2 3984.8 3106.9 3774.5 3941.0 3949.3 3993.8 4363.4 4530.4 4538.4 4564.0 4624.1 4688.4 4802.6 5480.0 5503.5 5521.8 1276.7 1322.4 1329.7 1336.7 1349.8 690.6 773.2 828.3 835.4 844.3 4215.3 4307.8 4313.4 4344.2 4358.7 4037.7 4065.5 4341.9 4658.6 4753.9 3632.4 4276.9 4291.0 4299.9 4336.7 2095.5 2129.9 2216.2 2496.8 2500.4 4151.7 4807.8 4877.2 4899.2 4904.9 4255.5 4786.2 4790.9 4812.8 4839.5 4616.5 4727.0 4873.6 5391.5 5780.7 5669.3 5677.0 5702.2 5719.1 5741.6 4756.7 5299.6 5467.1 5620.7 5650.2 5823.9 5832.6 5835.2 5899.1 5911.6 3302.5 3523.9 3690.6 3724.3 3751.8 5053.1 5538.9 5545.2 5548.6 5559.9 4503.9 5132.8 5492.2 5551.0 5576.7 4611.7 5285.6 5467.0 5503.7 5601.7 4411.7 5345.7 5392.4 5403.9 5405.8 4838.7 5135.3 5534.9 5546.2 5578.1 4574.4 5348.8 5360.2 5363.4 5385.1 23:25:35 INFO - PROCESS | 1707 | 23:25:35 INFO - PROCESS | 1707 | [#5] yui.html Cycles:180 Average:24061.65 Median:4708.18 stddev:38680.88 (821.6%) stddev-sans-first:38749.45 23:25:35 INFO - PROCESS | 1707 | Values: 649.9 696.6 700.4 739.2 766.6 12417.3 15811.3 15816.4 15832.9 15849.3 14226.3 14293.4 14317.0 14357.2 14749.7 688.5 712.3 848.2 877.9 881.1 856.2 879.3 890.3 893.6 896.5 936.1 937.0 938.7 940.6 943.4 730.4 899.8 926.5 929.3 931.6 702.0 703.1 705.4 706.2 707.7 14831.5 14929.4 14936.0 14946.3 14965.8 12564.7 13405.1 13693.0 14142.4 14259.1 12359.4 12563.0 12593.9 12893.9 13083.8 3582.5 4520.6 4548.7 4736.7 4759.6 103814.5 106446.5 114285.0 115714.4 116595.4 10968.2 11087.6 11104.6 11302.8 11424.7 21624.6 21689.7 21715.9 21734.5 21777.8 7969.0 8018.4 8262.4 8268.6 8327.4 4324.9 4465.6 4535.1 4539.1 4715.5 3513.7 3664.8 3668.0 3670.2 3677.2 655.5 659.6 661.7 674.8 677.0 563.6 613.9 638.4 656.9 657.1 8869.8 9333.9 9440.6 9520.7 9551.5 582.0 625.5 668.4 676.2 680.7 3997.5 4301.4 4488.6 4590.6 4700.9 94498.2 94718.0 95464.5 142562.6 165468.6 135037.3 139406.3 141587.0 143087.3 147943.3 78451.6 89183.6 116837.4 120354.2 122623.0 8849.3 8930.9 8939.6 9035.5 9070.3 59336.1 61064.7 64301.0 66489.7 67073.0 88176.6 91493.5 93745.1 95710.5 96714.0 11152.4 11457.5 11672.4 11782.1 12507.2 12159.6 12370.3 12484.9 12529.0 12705.7 11797.3 12071.2 12389.6 12505.0 12610.6 11078.0 11201.9 12101.3 12425.7 12548.5 9820.7 10834.2 11529.9 11992.9 12164.4 9313.3 9696.2 9707.8 9709.0 9718.6 8520.5 8959.5 9781.4 9811.8 9840.2 23:25:35 INFO - PROCESS | 1707 | -------- Summary: end -------- 23:25:35 INFO - PROCESS | 1707 | 23:25:35 INFO - TEST-INFO | 1707: exit 0 23:25:35 INFO - TEST-OK | dromaeo_css | took 1117625ms 23:25:35 INFO - TEST-START | kraken 23:25:35 INFO - operating with platform_type : mac_ 23:25:35 INFO - Initialising browser for kraken test... 23:25:35 INFO - TEST-INFO | started process 1824 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/tmpcI_j7C/profile http://localhost:49248/getInfo.html) 23:25:38 INFO - PROCESS | 1824 | __metrics Screen width/height:1600/1200 23:25:38 INFO - PROCESS | 1824 | colorDepth:24 23:25:38 INFO - PROCESS | 1824 | Browser inner width/height: 1024/689 23:25:38 INFO - PROCESS | 1824 | __metrics 23:25:38 INFO - PROCESS | 1824 | 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" 23:25:38 INFO - PROCESS | 1824 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:25:39 INFO - TEST-INFO | 1824: exit 0 23:25:39 INFO - Browser initialized. 23:25:39 INFO - Running cycle 1/1 for kraken test... 23:25:39 INFO - TEST-INFO | started process 1825 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/tmpcI_j7C/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:25:50 INFO - PROCESS | 1825 | RSS: Main: 234627072 23:25:50 INFO - PROCESS | 1825 | 23:25:53 INFO - PROCESS | 1825 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection) 23:25:54 INFO - PROCESS | 1825 | RSS: Main: 292114432 23:25:54 INFO - PROCESS | 1825 | 23:25:57 INFO - PROCESS | 1825 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-dft) 23:25:57 INFO - PROCESS | 1825 | RSS: Main: 288358400 23:25:57 INFO - PROCESS | 1825 | 23:26:01 INFO - PROCESS | 1825 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-fft) 23:26:01 INFO - PROCESS | 1825 | RSS: Main: 378556416 23:26:01 INFO - PROCESS | 1825 | 23:26:04 INFO - PROCESS | 1825 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator) 23:26:04 INFO - PROCESS | 1825 | RSS: Main: 281845760 23:26:04 INFO - PROCESS | 1825 | 23:26:06 INFO - PROCESS | 1825 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:26:07 INFO - PROCESS | 1825 | RSS: Main: 285167616 23:26:07 INFO - PROCESS | 1825 | 23:26:10 INFO - PROCESS | 1825 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom) 23:26:10 INFO - PROCESS | 1825 | RSS: Main: 321830912 23:26:10 INFO - PROCESS | 1825 | 23:26:13 INFO - PROCESS | 1825 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate) 23:26:14 INFO - PROCESS | 1825 | RSS: Main: 323633152 23:26:14 INFO - PROCESS | 1825 | 23:26:17 INFO - PROCESS | 1825 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial) 23:26:17 INFO - PROCESS | 1825 | RSS: Main: 323633152 23:26:17 INFO - PROCESS | 1825 | 23:26:19 INFO - PROCESS | 1825 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:26:19 INFO - PROCESS | 1825 | RSS: Main: 297455616 23:26:19 INFO - PROCESS | 1825 | 23:26:21 INFO - PROCESS | 1825 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:26:22 INFO - PROCESS | 1825 | RSS: Main: 302858240 23:26:22 INFO - PROCESS | 1825 | 23:26:24 INFO - PROCESS | 1825 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:26:25 INFO - PROCESS | 1825 | RSS: Main: 301928448 23:26:25 INFO - PROCESS | 1825 | 23:26:28 INFO - PROCESS | 1825 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:26:28 INFO - PROCESS | 1825 | RSS: Main: 321830912 23:26:28 INFO - PROCESS | 1825 | 23:26:31 INFO - PROCESS | 1825 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:26:32 INFO - PROCESS | 1825 | RSS: Main: 317820928 23:26:32 INFO - PROCESS | 1825 | 23:26:34 INFO - PROCESS | 1825 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49248/tests/kraken/driver.html?testName=ai-astar) 23:26:34 INFO - PROCESS | 1825 | RSS: Main: 361160704 23:26:34 INFO - PROCESS | 1825 | 23:26:34 INFO - PROCESS | 1825 | __start_tp_report 23:26:34 INFO - PROCESS | 1825 | _x_x_mozilla_page_load 23:26:34 INFO - PROCESS | 1825 | _x_x_mozilla_page_load_details 23:26:34 INFO - PROCESS | 1825 | |i|pagename|runs| 23:26:34 INFO - PROCESS | 1825 | |0;ai-astar;112;112;133;112;115;113;112;113;115;158 23:26:34 INFO - PROCESS | 1825 | |1;audio-beat-detection;176;141;134;163;110;130;135;130;184;107 23:26:34 INFO - PROCESS | 1825 | |2;audio-dft;184;183;182;182;185;186;178;181;185;184 23:26:34 INFO - PROCESS | 1825 | |3;audio-fft;76;74;81;155;57;58;58;68;140;57 23:26:34 INFO - PROCESS | 1825 | |4;audio-oscillator;85;82;132;70;72;88;113;68;70;116 23:26:34 INFO - PROCESS | 1825 | |5;imaging-gaussian-blur;87;83;87;110;136;102;86;84;86;84 23:26:34 INFO - PROCESS | 1825 | |6;imaging-darkroom;92;92;92;111;151;104;91;92;92;91 23:26:34 INFO - PROCESS | 1825 | |7;imaging-desaturate;84;89;84;87;83;84;84;83;84;85 23:26:34 INFO - PROCESS | 1825 | |8;json-parse-financial;53;52;56;54;53;54;55;53;53;53 23:26:34 INFO - PROCESS | 1825 | |9;json-stringify-tinderbox;66;65;70;76;75;72;69;70;69;71 23:26:34 INFO - PROCESS | 1825 | |10;stanford-crypto-aes;86;86;84;88;85;85;84;88;88;90 23:26:34 INFO - PROCESS | 1825 | |11;stanford-crypto-ccm;138;139;136;137;139;138;144;145;194;132 23:26:34 INFO - PROCESS | 1825 | |12;stanford-crypto-pbkdf2;170;169;169;167;169;169;169;174;174;234 23:26:34 INFO - PROCESS | 1825 | |13;stanford-crypto-sha256-iterative;67;69;68;69;67;68;66;67;67;66 23:26:34 INFO - PROCESS | 1825 | __end_tp_report 23:26:34 INFO - PROCESS | 1825 | __start_cc_report 23:26:34 INFO - PROCESS | 1825 | _x_x_mozilla_cycle_collect,2092 23:26:34 INFO - PROCESS | 1825 | __end_cc_report 23:26:34 INFO - PROCESS | 1825 | __startTimestamp1472624794390__endTimestamp 23:26:34 INFO - PROCESS | 1825 | 23:26:34 INFO - PROCESS | 1825 | ------- Summary: start ------- 23:26:34 INFO - PROCESS | 1825 | Number of tests: 14 23:26:34 INFO - PROCESS | 1825 | 23:26:34 INFO - PROCESS | 1825 | [#0] ai-astar Cycles:10 Average:119.50 Median:114.00 stddev:14.96 (13.1%) stddev-sans-first:15.62 23:26:34 INFO - PROCESS | 1825 | Values: 112.0 112.0 133.0 112.0 115.0 113.0 112.0 113.0 115.0 158.0 23:26:34 INFO - PROCESS | 1825 | 23:26:34 INFO - PROCESS | 1825 | [#1] audio-beat-detection Cycles:10 Average:141.00 Median:138.00 stddev:25.82 (18.7%) stddev-sans-first:24.09 23:26:34 INFO - PROCESS | 1825 | Values: 176.0 141.0 134.0 163.0 110.0 130.0 135.0 130.0 184.0 107.0 23:26:34 INFO - PROCESS | 1825 | 23:26:34 INFO - PROCESS | 1825 | [#2] audio-dft Cycles:10 Average:183.00 Median:184.00 stddev:2.36 (1.3%) stddev-sans-first:2.47 23:26:34 INFO - PROCESS | 1825 | Values: 184.0 183.0 182.0 182.0 185.0 186.0 178.0 181.0 185.0 184.0 23:26:34 INFO - PROCESS | 1825 | 23:26:34 INFO - PROCESS | 1825 | [#3] audio-fft Cycles:10 Average:82.40 Median:63.00 stddev:35.58 (56.5%) stddev-sans-first:37.66 23:26:34 INFO - PROCESS | 1825 | Values: 76.0 74.0 81.0 155.0 57.0 58.0 58.0 68.0 140.0 57.0 23:26:34 INFO - PROCESS | 1825 | 23:26:34 INFO - PROCESS | 1825 | [#4] audio-oscillator Cycles:10 Average:89.60 Median:71.00 stddev:22.78 (32.1%) stddev-sans-first:24.10 23:26:34 INFO - PROCESS | 1825 | Values: 85.0 82.0 132.0 70.0 72.0 88.0 113.0 68.0 70.0 116.0 23:26:34 INFO - PROCESS | 1825 | 23:26:34 INFO - PROCESS | 1825 | [#5] imaging-gaussian-blur Cycles:10 Average:94.50 Median:85.00 stddev:17.09 (20.1%) stddev-sans-first:17.91 23:26:34 INFO - PROCESS | 1825 | Values: 87.0 83.0 87.0 110.0 136.0 102.0 86.0 84.0 86.0 84.0 23:26:34 INFO - PROCESS | 1825 | 23:26:34 INFO - PROCESS | 1825 | [#6] imaging-darkroom Cycles:10 Average:100.80 Median:92.00 stddev:18.90 (20.5%) stddev-sans-first:19.77 23:26:34 INFO - PROCESS | 1825 | Values: 92.0 92.0 92.0 111.0 151.0 104.0 91.0 92.0 92.0 91.0 23:26:34 INFO - PROCESS | 1825 | 23:26:34 INFO - PROCESS | 1825 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:84.00 stddev:1.89 (2.2%) stddev-sans-first:1.99 23:26:34 INFO - PROCESS | 1825 | Values: 84.0 89.0 84.0 87.0 83.0 84.0 84.0 83.0 84.0 85.0 23:26:34 INFO - PROCESS | 1825 | 23:26:34 INFO - PROCESS | 1825 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:53.50 stddev:1.17 (2.2%) stddev-sans-first:1.22 23:26:34 INFO - PROCESS | 1825 | Values: 53.0 52.0 56.0 54.0 53.0 54.0 55.0 53.0 53.0 53.0 23:26:34 INFO - PROCESS | 1825 | 23:26:34 INFO - PROCESS | 1825 | [#9] json-stringify-tinderbox Cycles:10 Average:70.30 Median:70.50 stddev:3.47 (4.9%) stddev-sans-first:3.31 23:26:34 INFO - PROCESS | 1825 | Values: 66.0 65.0 70.0 76.0 75.0 72.0 69.0 70.0 69.0 71.0 23:26:34 INFO - PROCESS | 1825 | 23:26:34 INFO - PROCESS | 1825 | [#10] stanford-crypto-aes Cycles:10 Average:86.40 Median:87.00 stddev:2.01 (2.3%) stddev-sans-first:2.13 23:26:34 INFO - PROCESS | 1825 | Values: 86.0 86.0 84.0 88.0 85.0 85.0 84.0 88.0 88.0 90.0 23:26:34 INFO - PROCESS | 1825 | 23:26:34 INFO - PROCESS | 1825 | [#11] stanford-crypto-ccm Cycles:10 Average:144.20 Median:139.00 stddev:17.89 (12.9%) stddev-sans-first:18.83 23:26:34 INFO - PROCESS | 1825 | Values: 138.0 139.0 136.0 137.0 139.0 138.0 144.0 145.0 194.0 132.0 23:26:34 INFO - PROCESS | 1825 | 23:26:34 INFO - PROCESS | 1825 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.40 Median:169.50 stddev:20.36 (12.0%) stddev-sans-first:21.47 23:26:34 INFO - PROCESS | 1825 | Values: 170.0 169.0 169.0 167.0 169.0 169.0 169.0 174.0 174.0 234.0 23:26:34 INFO - PROCESS | 1825 | 23:26:34 INFO - PROCESS | 1825 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:67.50 stddev:1.07 (1.6%) stddev-sans-first:1.13 23:26:34 INFO - PROCESS | 1825 | Values: 67.0 69.0 68.0 69.0 67.0 68.0 66.0 67.0 67.0 66.0 23:26:34 INFO - PROCESS | 1825 | -------- Summary: end -------- 23:26:34 INFO - PROCESS | 1825 | 23:26:35 INFO - TEST-INFO | 1825: exit 0 23:26:35 INFO - TEST-OK | kraken | took 59250ms 23:26:35 INFO - SUITE-END | took 1176s 23:26:35 INFO - Completed test suite (00:19:37) 23:26:35 INFO - javascript benchmark 23:26:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2781.888724451022, 3331.2338162222686, 3344.8996530104105, 3369.6461871503475, 3401.4387626041703, 5462.371827239868, 5533.778648854041, 5562.833115006546, 5615.4384561543875, 5641.802536911209, 5552.9167062494, 5578.163275508676, 5684.37471878093, 5692.37383887773, 5706.714664266791, 5030.170021946375, 5112.718800465973, 5117.309163262949, 5138.254953031808, 5288.867778305554, 5208.817691380802, 5222.0, 5225.608079394042, 5242.370915490146, 5266.973665131669, 5035.320231768701, 5069.518395752398, 5079.923801142986, 5122.513361230677, 5193.298904647881, 5125.487451254882, 5129.435762066169, 5135.101357262464, 5142.717150556718, 5144.768485418165, 5054.140796064641, 5074.847754567369, 5102.6428150029515, 5112.437631860493, 5117.334746482933, 5408.0806262935, 5554.055810512182, 5599.440055994409, 5633.014222511044, 5710.71446427677, 5822.82531524055, 5897.764089436418, 5899.793507227227, 5904.409559044061, 5912.201852749909, 4894.2658601210105, 5976.043832986701, 5985.670788106652, 5999.910001349983, 6014.669193194372, 5548.112302031655, 5568.331800183963, 5584.21820945068, 5605.775768969195, 5686.829395118153, 6274.592151510137, 6293.37066293367, 6319.873602528016, 6331.905021424683, 6335.809925702236, 5955.434233747787, 5970.970145149333, 5974.4324289192455, 5987.341392446828, 5993.520518358521, 5945.3757355477055, 5990.460858522754, 6124.289394097934, 6124.663143527059, 6158.445739883432, 5124.795008199705, 6027.427394397525, 6030.6080104793045, 6080.635161890301, 6131.233595800525, 6060.909086363708, 6071.696415179224, 6075.179850720185, 6081.695915204222, 6096.420840020191, 4754.667173297905, 4786.44955830077, 4793.424789025339, 4810.42274927004, 4829.203181475087, 4352.281873490839, 4384.0355121873135, 4401.383806267125, 4421.0, 4437.245668236407, 5658.179563963285, 5686.97156514223, 5694.117411801178, 5726.856828579235, 5735.426457354231, 5908.379620139824, 6014.368491308437, 6218.5647004710145, 6248.625082495065, 6257.624542527463, 5595.776168953278, 5637.154426836008, 5645.23789288449, 5668.178114173422, 5694.629849059798, 5294.41761406245, 5313.707746073926, 5319.202119682079, 5326.760295786699, 5338.039152952506, 6163.722632481459, 6288.496920246369, 6310.368963103653, 6351.96824015877, 6374.394432528902, 6063.333033366414, 6116.113163591343, 6128.325884152829, 6144.1398204250545, 6154.138420621206, 6313.1477250572425, 6321.747130114744, 6345.333739957423, 6347.39699728525, 6359.20509936258, 5810.5351571875, 5948.315943666361, 5956.553258505716, 5960.165576819162, 5961.165436838933, 6050.7882224123705, 6078.088286757022, 6103.786367477117, 6144.969275153596, 6169.629822210682, 5744.4829965303325, 5904.409559044061, 5917.378675239038, 5923.348431672426, 5926.585139040226, 5749.252597162342, 5883.294004719461, 5933.1396947443245, 5936.495397891227, 5944.73248703818, 5780.913286300793, 5812.94187058124, 5829.7085145743395, 5847.590668653153, 5891.1752354669525, 5955.851103722442, 5956.106584012259, 5958.582899197015, 5970.820875373745, 5983.880322393441, 5485.710857948458, 5509.338879334506, 5616.522595579421, 5787.8553036174435, 5800.332961709458], "value": 5574.96767302435, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [526.6234642230805, 527.1486549223005, 536.8040665157218, 537.9004884096443, 558.4610850529239, 25885.094021709243, 31421.0, 41376.96557586058, 41457.378139327935, 43430.0, 43099.0, 43355.0, 43405.34891976623, 43517.0, 43565.0, 8613.440126391795, 9512.381695189808, 9718.465484398355, 9725.367851089692, 9744.75638109046, 8683.34874884383, 8829.984551776532, 8839.0, 8862.29101671868, 11734.706632334175, 11787.233829801078, 12010.399480025964, 12099.153059285854, 12103.515859365614, 12109.273443593369, 11955.521779128825, 12004.2197257178, 12030.51877924882, 12037.999999999956, 12042.157049006573, 11771.764564708743, 11857.94071029648, 11858.991985681227, 11860.22908510944, 11861.347625880573, 39917.40123898144, 40154.0, 40194.79902600498, 40273.798631006954, 40374.59625403738, 3217.678232176787, 3338.7996720196866, 3438.82805859706, 3758.5677647070665, 3855.325318069327, 24024.399390015216, 31228.531572026437, 31409.8429507851, 31460.842695786603, 31706.207344816332, 3201.375731732313, 3225.8387080646107, 3232.7090561849313, 3237.5592861176788, 3309.387763263804, 435464.0, 439443.0, 439884.0, 442801.0, 447381.0, 33155.0, 59655.0, 59910.70044649749, 59987.40012599818, 60154.0, 33701.66298337034, 35150.0, 35189.64810351915, 35221.64778352235, 35279.4708079379, 14198.787018194735, 14343.497977570734, 14381.28093595316, 14397.928010359881, 14399.0, 12139.514419423302, 12247.326397048248, 12260.26438413698, 12267.693307667201, 12323.322217277957, 7189.712411503481, 7331.486795924341, 7618.085829700472, 7746.109197442253, 7761.8447631047065, 3190.617125944855, 3254.5606343143845, 3255.9348813023607, 3282.3599398117617, 3314.1548905029545, 871.5816408124009, 888.1118881118881, 944.1313991128178, 983.4640121133999, 988.5749127875083, 29486.705132948395, 29958.55062174069, 29988.55017174744, 31030.0, 31043.223919402197, 1031.4842578710645, 1039.73486760877, 1054.404261592198, 1095.8739744929435, 1156.8727439981594, 12777.105602607728, 15039.398424063134, 15509.302081406364, 15658.373665053497, 15938.0, 667886.0, 673512.0, 674504.0, 675218.0, 675291.0, 58219.70890145522, 58629.41370586325, 58663.0, 58676.7066164675, 58821.411785881595, 1295.7149427126017, 1335.2522587351114, 1351.3243378310844, 1363.9590812275649, 1367.9179249244878, 27092.45815083727, 28668.0, 29687.85156074206, 30245.0, 30263.09210723682, 51264.48735512597, 51270.74364628153, 51397.68710407966, 51419.0, 51454.74272628613, 569503.0, 569933.0, 570197.0, 572251.0, 573363.0, 23200.651990219823, 23717.52564948726, 25650.871745641154, 26361.0, 26751.464970700865, 43161.0, 46595.534044660475, 46975.0, 47083.529164707914, 47319.0, 38644.80677596594, 39002.0, 39100.413493797045, 39932.401013984236, 40511.392329115675, 38344.864821628405, 38380.42429363618, 38439.6156038436, 39680.801595991834, 40211.99470013273, 38217.61782382252, 38457.23085538329, 38537.421938670384, 38925.83222503329, 39029.6097039026, 27672.72327276782, 32660.0, 33672.0, 34190.65809341974, 34596.962091137306, 20644.948634840843, 24576.0, 25056.74943250617, 25397.238082857544, 25736.61395079113], "value": 18796.927834305796, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.2495222794877, 357.54056037991154, 383.95200599924993, 387.34345284742363, 410.0609604006823, 15650.217489125542, 15839.287232074556, 20718.896405517953, 20829.06269217885, 20923.163073477062, 19545.41363759086, 20143.89208593527, 20213.898930505326, 21691.457713557167, 22504.0, 5729.914051289234, 6693.665316734144, 6889.7588584399555, 6979.267176946399, 7040.788776336718, 4659.953400466003, 6807.693653785567, 6906.896396554056, 6916.827079323032, 6928.896066559006, 7430.702771889118, 7938.563379014152, 7960.6019699015105, 7998.080220774598, 8080.959595202045, 7781.813458300636, 7801.3758899288205, 7921.6435260412845, 7965.203479652047, 7989.5605741684185, 7197.424206063554, 7578.166401695809, 7647.34997525211, 7676.155622881479, 7691.730789422343, 28487.28781780472, 28748.425031499253, 28868.566971495446, 29123.0, 29297.85351073231, 2069.7412823397076, 2270.1146552844402, 2318.327684971356, 2333.5916214662366, 2421.5156968606175, 13560.932195339059, 13941.442342306296, 15506.302216400289, 15539.5338139856, 17183.742243866353, 1775.2277759174801, 1947.6883698608156, 2050.9077091530917, 2065.514604068043, 2089.4254080127935, 127667.72332276744, 143895.0, 144515.27742361327, 148370.0, 151261.0, 18106.637867242844, 19942.50143746418, 20448.38654840357, 20815.37553873386, 21435.249766258105, 34067.82966085154, 34145.0, 34189.316213675585, 34244.0, 34288.48567271493, 8782.110954861466, 10504.212184086224, 10585.788284234426, 10657.467126643793, 10897.945510272399, 8678.609462574073, 8841.16008979146, 8861.64553417856, 8883.022867484571, 9049.185573298435, 8217.917820821835, 9231.399959002643, 9617.855732164024, 9683.564239609093, 9715.46564938935, 3161.8893338733033, 3602.441621548664, 3686.8340924658455, 3686.9631303687156, 3804.6493494809497, 814.2427542385639, 817.9427440079137, 855.2936890902689, 932.379967321737, 985.8521221816642, 16596.0, 17138.742918856227, 19332.613347732968, 20839.47901302449, 20951.580968380546, 908.8545832666741, 917.9127982841619, 924.4453328003144, 978.9902100978899, 998.1116806042628, 9825.901740982641, 10366.844497332546, 10646.520906559223, 10826.512806923707, 10905.127589792797, 527436.0, 559986.0, 567131.0, 570536.0, 588715.0, 128076.35961820131, 129058.0, 134375.0, 134635.65364346426, 135117.0, 952.3857112162477, 961.077365728908, 985.1773768903046, 987.7382493639192, 988.1847475832467, 17333.306667733148, 17772.911135444756, 17810.465686029438, 17902.194401251716, 17943.910280448516, 51824.481755181965, 52525.737371314426, 53237.7338113307, 53569.73215133899, 54333.72833135809, 78040.60979695065, 78197.60901195457, 78246.43507129939, 79125.60437197777, 79919.20080799118, 6641.734330626721, 6969.745445819801, 7021.964890175516, 8087.959560202161, 8229.670813167406, 11365.659030229106, 12844.807327889903, 12916.28960407149, 13062.34688265602, 13072.738545229233, 11124.054455371384, 11174.832377514507, 11318.377489238172, 11355.943220283845, 11484.368359740294, 8528.530930798866, 10950.507227174956, 11008.669739907815, 11235.4382280884, 11445.656630301104, 10492.0, 11032.669019929415, 11378.08975281992, 11475.770484590428, 11607.477663505331, 6751.831204219738, 7996.84006319882, 8119.025716914008, 8286.171382861907, 8412.453190542596, 8088.191180881865, 9822.55798489084, 11028.0, 11315.377654229096, 11335.886641133813], "value": 10807.868497829862, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1341.9334225293048, 1462.7147706197288, 1498.4905132255035, 1520.3538496139138, 1770.5396596884814, 7715.2670496302835, 8514.91485085149, 8832.514211718353, 8837.999999999993, 8945.865812012826, 8693.608787604557, 8776.473411595309, 8781.56092195391, 8795.824083518326, 8808.91191088089, 2350.8824558772035, 2381.6070348392495, 2387.438951846315, 2387.5941090014653, 2392.964105538418, 2324.5815753164425, 2371.845830021052, 2373.0389192377115, 2374.4657452073234, 2378.3935096550354, 2338.976610233893, 2361.7047869016374, 2376.1089591403224, 2377.548265829487, 2380.8095352371856, 2311.6185829338137, 2336.7780060894274, 2336.8481048731946, 2338.590746619335, 2355.3758254062514, 2306.1351993002622, 2335.5328934213226, 2335.953280934372, 2340.4382948092507, 2343.988280058606, 8576.828463430697, 8734.563271836383, 8825.911740882573, 8832.514211718353, 8840.1601847825, 5187.0, 5385.299911011543, 5390.6226564140525, 5400.756965936543, 5404.000259951882, 3506.7194624429985, 3519.6304388039143, 3588.5334906462253, 3590.838412271454, 3619.3847046002243, 1067.0983019348637, 1068.7595291059527, 1080.206048554312, 1082.3830416662506, 1090.514720949178, 11425.600103996403, 11498.712532186679, 11575.421228938603, 11580.478878450405, 11581.478833452431, 4354.433923589913, 4402.713823601455, 4560.475545312265, 4569.13186494565, 4585.28928016158, 9032.8645070324, 9046.095390461032, 9046.411983221069, 9049.457032578066, 9049.954750226207, 5169.534741873174, 5171.456997015259, 5177.0, 5182.404023537266, 5183.611229157828, 4181.770002649822, 4316.830123281684, 4367.213901497697, 4522.977385113053, 4865.245886887538, 3728.9813550932613, 3751.0247335692916, 3773.9998900291444, 3783.1866148778145, 3798.981005094957, 1129.1644183304295, 1150.4535345710888, 1150.810116330796, 1163.668354518958, 1169.181572898974, 843.8734189871445, 864.6714248585497, 874.8206617643368, 892.0, 921.5254144115786, 5772.884542309214, 5781.624194427349, 5998.880022399615, 6055.394460553997, 6271.686415679198, 906.0531744327194, 910.0, 912.4570880326195, 922.1838672774509, 966.6665000574791, 3624.5469316335457, 3838.961610383916, 3858.421236814444, 3895.6299151580556, 4023.0344717267644, 9183.448993060438, 10378.532966016548, 10811.729706757395, 10818.729531761768, 10830.837537436944, 10752.301100428447, 10766.084882784895, 10773.676789696321, 10788.568457261621, 10796.136309095253, 7645.311921927053, 8005.679772809022, 8019.35845132388, 8055.838883222303, 8274.710385136492, 5827.59206855533, 5854.0, 5995.550333724901, 6013.9097913532205, 6079.3008803988105, 9909.752256193653, 10004.649837255662, 10025.749356266151, 10041.849372259276, 10076.798464030824, 9222.769430764285, 9530.61877524919, 9983.051610097029, 9992.300538962203, 10190.082892539707, 6931.826704332432, 7907.090684571348, 8062.072861620754, 8172.42793004484, 8186.386021048326, 7971.641276142472, 8125.3905957052275, 8222.054463736747, 8269.421140520106, 8330.916690833257, 6695.665216739241, 7315.341619254293, 7344.853102937805, 7351.448641352027, 7355.5586664800285, 7241.456890733321, 7250.383717384081, 7314.232005639544, 7317.26827317264, 7319.670614822239, 5937.9406205937385, 6827.89758153638, 6965.373116419547, 6972.372486476039, 6978.755743548952, 4767.547083027107, 4876.634252431011, 4930.926036109389, 4971.453140154508, 5150.639455238097, 4913.729744864067, 4954.702717836942, 5043.167877300204, 5054.140796064751, 5140.4088529818055], "value": 4520.892786761277, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [266.58279792125325, 299.88454445038667, 315.243415802075, 315.2732950548985, 316.41305378522856, 4940.308356830046, 5184.429712731601, 5205.921911171326, 5238.18808084747, 5250.606204534661, 4304.526502084768, 4486.214912390326, 5017.315736081197, 5125.256837758523, 5142.254373115896, 4009.5789942056176, 4023.5171779386515, 4040.2929487339743, 4068.593140685937, 4072.6741860651077, 3878.45701601776, 4052.53395859477, 4066.125782956664, 4075.8369665213354, 4088.0, 3280.9821959494857, 3313.8840140595084, 3978.8806335809827, 3991.840326386941, 4009.2983727847654, 3962.346212874872, 3963.7423567468168, 3989.501312335958, 3996.5004374453047, 4032.495938007749, 3044.9086527404215, 3360.6471320511237, 3508.5438892944007, 3512.929741405183, 3515.982420087909, 4202.700879809366, 4616.492185859553, 4996.275540046673, 5077.0, 5091.581498788083, 3891.7664940103687, 3919.8824035278985, 4586.564276393738, 4604.401427814396, 4611.262198048296, 4785.545373189541, 5081.339425874649, 5496.395396506381, 5502.0, 5531.1790986596725, 3493.4410494320787, 3731.626837316274, 3873.3028054950096, 3883.3398322284997, 3890.5525864525657, 4782.545658162469, 4968.87577810554, 5797.275340582427, 5810.912836307417, 5826.242588463558, 3720.897692803557, 3960.089179488734, 3971.6624086952356, 3982.2234664240195, 3984.7609143451486, 3106.9067927962196, 3774.490443790053, 3940.9802950985063, 3949.328614135604, 3993.7803420812133, 4363.38912552243, 4530.365748795171, 4538.411555955455, 4563.95436045642, 4624.1445332613575, 4688.4373875134515, 4802.639802014863, 5479.972600136974, 5503.477169668875, 5521.812810245817, 1276.7382686549236, 1322.385090932721, 1329.7168232655533, 1336.659151916255, 1349.757043732118, 690.6063543780098, 773.2190487607556, 828.2752591482453, 835.3609488741118, 844.3282301043845, 4215.283401821697, 4307.8492252771, 4313.439252897103, 4344.174606824757, 4358.738475691405, 4037.6567991720444, 4065.4918135233097, 4341.891452713644, 4658.557437043475, 4753.928691069636, 3632.418812989909, 4276.87169384919, 4291.0, 4299.892502687458, 4336.6773134193745, 2095.476130967258, 2129.8828564429105, 2216.1689366487567, 2496.8377055491337, 2500.3624075860903, 4151.6886233532605, 4807.81719903686, 4877.195787285923, 4899.187300698693, 4904.896398310351, 4255.468066491689, 4786.234202527578, 4790.94599188178, 4812.807487700453, 4839.540243676845, 4616.545034042166, 4726.976365118152, 4873.610111191097, 5391.514763671288, 5780.682062486603, 5669.3480249770155, 5677.006523858226, 5702.2016917630735, 5719.084946408555, 5741.569382296261, 4756.667033307635, 5299.6290259681455, 5467.097649546987, 5620.690862002629, 5650.180723794944, 5823.922574323764, 5832.591718579828, 5835.241418615553, 5899.056151015817, 5911.645301281765, 3302.488114342281, 3523.894283171509, 3690.630936906288, 3724.2923844469465, 3751.8499260030385, 5053.140966035856, 5538.91691624634, 5545.223668686306, 5548.583856210719, 5559.8888022240135, 4503.864884053483, 5132.79468821243, 5492.176173573996, 5550.972245138749, 5576.693281869536, 4611.677182597186, 5285.577153827608, 5466.972665136649, 5503.724813759376, 5601.663900166003, 4411.669124815588, 5345.743750218618, 5392.379876314274, 5403.918941215807, 5405.837824865261, 4838.685485443435, 5135.303965418186, 5534.928114896271, 5546.223528705904, 5578.079616863172, 4574.428196475441, 5348.759305831325, 5360.16917377807, 5363.383210930793, 5385.138377859524], "value": 3870.1074517138754, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [649.8602800397916, 696.5507247825153, 700.3591713582073, 739.2275072549186, 766.6243540665076, 12417.254964702124, 15811.288492017859, 15816.36734530619, 15832.920835395822, 15849.286782094805, 14226.288685565733, 14293.428262869498, 14317.0, 14357.210353430582, 14749.705005899876, 688.5008368932534, 712.3232928717713, 848.1730312944891, 877.8814859993915, 881.1056777370974, 856.1609622569886, 879.3273146043282, 890.261083300862, 893.620211410148, 896.5024411451642, 936.1387523478417, 936.9812603747955, 938.6620816506053, 940.5861420974784, 943.42451104826, 730.3791776989569, 899.8110396816611, 926.4533924984291, 929.2565947242213, 931.6040682709821, 701.9926405607969, 703.1035429827011, 705.3898377903129, 706.2407911495163, 707.6603230449361, 14831.48089816862, 14929.402823887032, 14936.0, 14946.32741526634, 14965.782356793234, 12564.680708525657, 13405.128666636734, 13693.0, 14142.36359363831, 14259.14445133285, 12359.38203089842, 12563.0, 12593.93703031488, 12893.871061289454, 13083.803742943768, 3582.5342705448124, 4520.593146616788, 4548.749818759967, 4736.739479328636, 4759.595434388081, 103814.48092759488, 106446.46776766221, 114285.0, 115714.42142789316, 116595.4170229152, 10968.23222374442, 11087.55649774, 11104.611338603272, 11302.830457543143, 11424.657260282205, 21624.56750864974, 21689.674654879873, 21715.891420542797, 21734.45663858416, 21777.78222217789, 7969.0, 8018.438709290293, 8262.380321475914, 8268.586570671443, 8327.417080804407, 4324.870253892325, 4465.553444655508, 4535.070310586322, 4539.0921815637, 4715.4577223619035, 3513.7013353864695, 3664.8167591620313, 3667.9633203668154, 3670.210904655512, 3677.191017976041, 655.4625207330039, 659.6207180871019, 661.7253839656489, 674.814426032845, 677.0352248046514, 563.6111083352556, 613.9048447490646, 638.3807706524664, 656.9343065693488, 657.103054330835, 8869.778255543533, 9333.90666093344, 9440.622375104918, 9520.666776662922, 9551.522423878778, 581.9592628516048, 625.5050429473562, 668.3951024322995, 676.1885737115481, 680.6732768271257, 3997.540282867449, 4301.408478862841, 4488.644266497073, 4590.634388405324, 4700.905981880412, 94498.1723653663, 94718.0, 95464.52267738756, 142562.57437425494, 165468.60488793324, 135037.3248133753, 139406.3029684845, 141587.0, 143087.2845635786, 147943.2602836979, 78451.60774196092, 89183.55408222918, 116837.41581292039, 120354.15041894141, 122623.0, 8849.336299777671, 8930.910690893008, 8939.59771810283, 9035.503047332197, 9070.274378049871, 59336.10995834979, 61064.694676526335, 64301.0, 66489.66755166193, 67073.0, 88176.55911720658, 91493.54253228691, 93745.06254937637, 95710.5214473951, 96714.0, 11152.386618736047, 11457.484413201259, 11672.358020308964, 11782.116341274268, 12507.187032842836, 12159.635210943687, 12370.257784532598, 12484.937575312066, 12529.0, 12705.745885082062, 11797.29216247028, 12071.215371000857, 12389.62831115068, 12505.0, 12610.621681349574, 11078.0, 11201.943990280322, 12101.334426606621, 12425.689357766129, 12548.498060077494, 9820.705378838646, 10834.187435942178, 11529.884701152881, 11992.880071199177, 12164.40912678137, 9313.34806563534, 9696.210584913712, 9707.757306067404, 9708.951455242961, 9718.562664680248, 8520.488770673779, 8959.462432254086, 9781.364211326241, 9811.754706132404, 9840.212782977487], "value": 7635.955126647074, "unit": "score"}], "name": "dromaeo_css", "value": 7299.97784855274, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 133.0, 112.0, 115.0, 113.0, 112.0, 113.0, 115.0, 158.0], "value": 119.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 141.0, 134.0, 163.0, 110.0, 130.0, 135.0, 130.0, 184.0, 107.0], "value": 141.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 182.0, 182.0, 185.0, 186.0, 178.0, 181.0, 185.0, 184.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 81.0, 155.0, 57.0, 58.0, 58.0, 68.0, 140.0, 57.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 132.0, 70.0, 72.0, 88.0, 113.0, 68.0, 70.0, 116.0], "value": 89.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 87.0, 110.0, 136.0, 102.0, 86.0, 84.0, 86.0, 84.0], "value": 94.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 92.0, 111.0, 151.0, 104.0, 91.0, 92.0, 92.0, 91.0], "value": 100.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 89.0, 84.0, 87.0, 83.0, 84.0, 84.0, 83.0, 84.0, 85.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 56.0, 54.0, 53.0, 54.0, 55.0, 53.0, 53.0, 53.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 70.0, 76.0, 75.0, 72.0, 69.0, 70.0, 69.0, 71.0], "value": 70.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 84.0, 88.0, 85.0, 85.0, 84.0, 88.0, 88.0, 90.0], "value": 86.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 139.0, 136.0, 137.0, 139.0, 138.0, 144.0, 145.0, 194.0, 132.0], "value": 144.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 169.0, 169.0, 167.0, 169.0, 169.0, 169.0, 174.0, 174.0, 234.0], "value": 176.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 68.0, 69.0, 67.0, 68.0, 66.0, 67.0, 67.0, 66.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1493.8000000000002, "alertThreshold": 2.0}]} 23:26:35 INFO - Return code: 0 23:26:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:26:35 INFO - # TBPL SUCCESS # 23:26:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:26:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:26:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:26:35 INFO - 2016-08-30 23:26:35.691 screenresolution[1835:10322] starting screenresolution argv=screenresolution get 23:26:35 INFO - 2016-08-30 23:26:35.693 screenresolution[1835:10322] Display 0: 1600x1200x32@60 23:26:35 INFO - 2016-08-30 23:26:35.699 screenresolution[1836:10325] starting screenresolution argv=screenresolution list 23:26:35 INFO - Available Modes on Display 0 23:26:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:26:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:26:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:26:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:26:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:26:35 INFO - Intel Iris: 23:26:35 INFO - Chipset Model: Intel Iris 23:26:35 INFO - Type: GPU 23:26:35 INFO - Bus: Built-In 23:26:35 INFO - VRAM (Dynamic, Max): 1536 MB 23:26:35 INFO - Vendor: Intel (0x8086) 23:26:35 INFO - Device ID: 0x0a2e 23:26:35 INFO - Revision ID: 0x0009 23:26:35 INFO - Displays: 23:26:35 INFO - Display: 23:26:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:26:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:26:35 INFO - Display Serial Number: 1600x1200 60 23:26:35 INFO - Main Display: Yes 23:26:35 INFO - Mirror: Off 23:26:35 INFO - Online: Yes 23:26:35 INFO - Rotation: Supported 23:26:35 INFO - Return code: 0 23:26:35 INFO - Running post-action listener: _resource_record_post_action 23:26:35 INFO - [mozharness: 2016-08-31 06:26:35.933390Z] Finished run-tests step (success) 23:26:35 INFO - Running post-run listener: _resource_record_post_run 23:26:35 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 31898624; Write bytes: 708228608; Read time: 193; Write time: 2124 23:26:35 INFO - TinderboxPrint: CPU usage
53.8% 23:26:35 INFO - TinderboxPrint: I/O read bytes / time
31,898,624 / 193 23:26:35 INFO - TinderboxPrint: I/O write bytes / time
708,228,608 / 2,124 23:26:35 INFO - TinderboxPrint: CPU idle
2,238.8 (46.2%) 23:26:35 INFO - TinderboxPrint: CPU system
974.3 (20.1%) 23:26:35 INFO - TinderboxPrint: CPU user
1,632.4 (33.7%) 23:26:35 INFO - TinderboxPrint: Swap in / out
433,586,176 / 0 23:26:35 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 405015040; Write bytes: 399947776; Read time: 29626; Write time: 886 23:26:36 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 29117440; Write bytes: 297606656; Read time: 168; Write time: 1205 23:26:36 INFO - Running post-run listener: _upload_blobber_files 23:26:36 INFO - Blob upload gear active. 23:26:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:26:36 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 23:26:36 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'] 23:26:36 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 23:26:36 INFO - (blobuploader) - INFO - Open directory for files ... 23:26:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:26:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:26:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:26:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:26:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:26:37 INFO - (blobuploader) - INFO - Done attempting. 23:26:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:26:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:26:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:26:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:26:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:26:38 INFO - (blobuploader) - INFO - Done attempting. 23:26:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:26:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:26:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:26:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:26:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:26:41 INFO - (blobuploader) - INFO - Done attempting. 23:26:41 INFO - (blobuploader) - INFO - Iteration through files over. 23:26:41 INFO - Return code: 0 23:26:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:26:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:26:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b5af52b11e492828853dff489e3498efab81bc3d509dcfceb7cbda5836d1c1790a91e8d183f30b302cec832f158eb10645df766490a4bd18a43f1bb872e0abe5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f3be5b788c6abff93a2d54bcd789d88f3aad4960c4faee42c54aa1b1c7493be4216a2ed33e642701c7c309f9da05e4faee36387bd6827eaf6ad2d8ed2fe6eae3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0e1816fded3cc31660376ac7ca1cedc1d83b4c02bdc8b26bb5336cc34f5956e20f7be10e088d98507a86528b3692dcd4aadb0cc6041e293a6ecd4f6faa0d1e29"} 23:26:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:26:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:26:41 INFO - Contents: 23:26:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b5af52b11e492828853dff489e3498efab81bc3d509dcfceb7cbda5836d1c1790a91e8d183f30b302cec832f158eb10645df766490a4bd18a43f1bb872e0abe5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f3be5b788c6abff93a2d54bcd789d88f3aad4960c4faee42c54aa1b1c7493be4216a2ed33e642701c7c309f9da05e4faee36387bd6827eaf6ad2d8ed2fe6eae3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0e1816fded3cc31660376ac7ca1cedc1d83b4c02bdc8b26bb5336cc34f5956e20f7be10e088d98507a86528b3692dcd4aadb0cc6041e293a6ecd4f6faa0d1e29"} 23:26:41 INFO - Running post-run listener: copy_logs_to_upload_dir 23:26:41 INFO - Copying logs to upload dir... 23:26:41 INFO - mkdir: /builds/slave/test/build/upload/logs 23:26:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.462045 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-30 23:26:41.227425) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 23:26:41.231422) ========= 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.QJxvBomwCY/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.S7TB73yaNv/Listeners TMPDIR=/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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/b5af52b11e492828853dff489e3498efab81bc3d509dcfceb7cbda5836d1c1790a91e8d183f30b302cec832f158eb10645df766490a4bd18a43f1bb872e0abe5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f3be5b788c6abff93a2d54bcd789d88f3aad4960c4faee42c54aa1b1c7493be4216a2ed33e642701c7c309f9da05e4faee36387bd6827eaf6ad2d8ed2fe6eae3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0e1816fded3cc31660376ac7ca1cedc1d83b4c02bdc8b26bb5336cc34f5956e20f7be10e088d98507a86528b3692dcd4aadb0cc6041e293a6ecd4f6faa0d1e29"} build_url:https://queue.taskcluster.net/v1/task/PkpPGRf1RCyoSOLJMoj7_g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011285 build_url: 'https://queue.taskcluster.net/v1/task/PkpPGRf1RCyoSOLJMoj7_g/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/b5af52b11e492828853dff489e3498efab81bc3d509dcfceb7cbda5836d1c1790a91e8d183f30b302cec832f158eb10645df766490a4bd18a43f1bb872e0abe5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f3be5b788c6abff93a2d54bcd789d88f3aad4960c4faee42c54aa1b1c7493be4216a2ed33e642701c7c309f9da05e4faee36387bd6827eaf6ad2d8ed2fe6eae3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0e1816fded3cc31660376ac7ca1cedc1d83b4c02bdc8b26bb5336cc34f5956e20f7be10e088d98507a86528b3692dcd4aadb0cc6041e293a6ecd4f6faa0d1e29"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 23:26:41.264685) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 23:26:41.265012) ========= 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.QJxvBomwCY/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.S7TB73yaNv/Listeners TMPDIR=/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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.005128 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 23:26:41.313579) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 23:26:41.313866) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 23:26:41.314187) ========= ========= Total master_lag: 0.43 =========