builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0264 starttime: 1472696096.25 results: success (0) buildid: 20160831165521 builduid: e27379882c0c425db1aaea99fd91c56b revision: c18c990a038f752ffbeea92b1d71b3d5d0039f4f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 19:14:56.250989) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 19:14:56.251506) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 19:14:56.251827) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 19:14:56.277063) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 19:14:56.277700) ========= 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.xxeyNH5Cxn/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.5qqVfGKWQs/Listeners TMPDIR=/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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-31 19:14:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 39.4M=0s 2016-08-31 19:14:56 (39.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.119517 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 19:14:56.420339) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 19:14:56.420687) ========= 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.xxeyNH5Cxn/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.5qqVfGKWQs/Listeners TMPDIR=/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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.026834 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 19:14:56.471741) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-31 19:14:56.472170) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c18c990a038f752ffbeea92b1d71b3d5d0039f4f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev c18c990a038f752ffbeea92b1d71b3d5d0039f4f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xxeyNH5Cxn/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.5qqVfGKWQs/Listeners TMPDIR=/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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-31 19:14:56,562 truncating revision to first 12 chars 2016-08-31 19:14:56,562 Setting DEBUG logging. 2016-08-31 19:14:56,562 attempt 1/10 2016-08-31 19:14:56,562 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c18c990a038f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-31 19:14:58,163 unpacking tar archive at: mozilla-central-c18c990a038f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.886130 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-31 19:14:58.381126) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 19:14:58.381514) ========= script_repo_revision: c18c990a038f752ffbeea92b1d71b3d5d0039f4f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 19:14:58.381957) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 19:14:58.382265) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 19:14:58.401723) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-31 19:14:58.402447) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xxeyNH5Cxn/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.5qqVfGKWQs/Listeners TMPDIR=/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 19:14:58 INFO - MultiFileLogger online at 20160831 19:14:58 in /builds/slave/test 19:14:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 19:14:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:14:58 INFO - {'append_to_log': False, 19:14:58 INFO - 'base_work_dir': '/builds/slave/test', 19:14:58 INFO - 'blob_upload_branch': 'Firefox', 19:14:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:14:58 INFO - 'branch': 'Firefox', 19:14:58 INFO - 'buildbot_json_path': 'buildprops.json', 19:14:58 INFO - 'config_files': ('talos/mac_config.py',), 19:14:58 INFO - 'default_actions': ('clobber', 19:14:58 INFO - 'read-buildbot-config', 19:14:58 INFO - 'download-and-extract', 19:14:58 INFO - 'populate-webroot', 19:14:58 INFO - 'create-virtualenv', 19:14:58 INFO - 'install', 19:14:58 INFO - 'run-tests'), 19:14:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:14:58 INFO - 'download_minidump_stackwalk': True, 19:14:58 INFO - 'download_symbols': 'ondemand', 19:14:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:14:58 INFO - 'tooltool.py': '/tools/tooltool.py', 19:14:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:14:58 INFO - '/tools/misc-python/virtualenv.py')}, 19:14:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:14:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:14:58 INFO - 'installer_path': 'installer.exe', 19:14:58 INFO - 'log_level': 'info', 19:14:58 INFO - 'log_name': 'talos', 19:14:58 INFO - 'log_to_console': True, 19:14:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:14:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:14:58 INFO - 'opt_config_files': (), 19:14:58 INFO - 'pip_index': False, 19:14:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:14:58 INFO - 'cmd': ('bash', 19:14:58 INFO - '-c', 19:14:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:14:58 INFO - 'enabled': True, 19:14:58 INFO - 'halt_on_failure': False, 19:14:58 INFO - 'name': 'check_screen_resolution'},), 19:14:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:14:58 INFO - 'cmd': ('bash', 19:14:58 INFO - '-c', 19:14:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:14:58 INFO - 'enabled': True, 19:14:58 INFO - 'halt_on_failure': False, 19:14:58 INFO - 'name': 'check_screen_resolution'},), 19:14:58 INFO - 'run_cmd_checks_enabled': True, 19:14:58 INFO - 'sps_profile': False, 19:14:58 INFO - 'sps_profile_interval': 0, 19:14:58 INFO - 'suite': 'dromaeojs', 19:14:58 INFO - 'system_bits': '32', 19:14:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:14:58 INFO - 'title': 't-yosemite-r7-0264', 19:14:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:14:58 INFO - 'use_talos_json': True, 19:14:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:14:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:14:58 INFO - 'work_dir': 'build'} 19:14:58 INFO - [mozharness: 2016-09-01 02:14:58.602688Z] Running clobber step. 19:14:58 INFO - Running pre-action listener: _resource_record_pre_action 19:14:58 INFO - Running main action method: clobber 19:14:58 INFO - rmtree: /builds/slave/test/build 19:14:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:15:00 INFO - Running post-action listener: _resource_record_post_action 19:15:00 INFO - [mozharness: 2016-09-01 02:15:00.703511Z] Finished clobber step (success) 19:15:00 INFO - [mozharness: 2016-09-01 02:15:00.703631Z] Running read-buildbot-config step. 19:15:00 INFO - Running pre-action listener: _resource_record_pre_action 19:15:00 INFO - Running main action method: read_buildbot_config 19:15:00 INFO - Using buildbot properties: 19:15:00 INFO - { 19:15:00 INFO - "project": "", 19:15:00 INFO - "product": "firefox", 19:15:00 INFO - "script_repo_revision": "production", 19:15:00 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 19:15:00 INFO - "repository": "", 19:15:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 19:15:00 INFO - "buildid": "20160831165521", 19:15:00 INFO - "pgo_build": "False", 19:15:00 INFO - "basedir": "/builds/slave/test", 19:15:00 INFO - "buildnumber": 11, 19:15:00 INFO - "slavename": "t-yosemite-r7-0264", 19:15:00 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 19:15:00 INFO - "platform": "yosemite_r7", 19:15:00 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 19:15:00 INFO - "branch": "mozilla-central", 19:15:00 INFO - "repo_path": "mozilla-central", 19:15:00 INFO - "revision": "c18c990a038f752ffbeea92b1d71b3d5d0039f4f", 19:15:00 INFO - "stage_platform": "macosx64", 19:15:00 INFO - "builduid": "e27379882c0c425db1aaea99fd91c56b", 19:15:00 INFO - "slavebuilddir": "test" 19:15:00 INFO - } 19:15:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/CKoMrTYcRCaTRi80TknJsA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 19:15:00 INFO - Running post-action listener: _resource_record_post_action 19:15:00 INFO - [mozharness: 2016-09-01 02:15:00.706460Z] Finished read-buildbot-config step (success) 19:15:00 INFO - [mozharness: 2016-09-01 02:15:00.706565Z] Running download-and-extract step. 19:15:00 INFO - Running pre-action listener: _resource_record_pre_action 19:15:00 INFO - Running main action method: download_and_extract 19:15:00 INFO - mkdir: /builds/slave/test/build/tests 19:15:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:00 INFO - trying https://queue.taskcluster.net/v1/task/CKoMrTYcRCaTRi80TknJsA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 19:15:00 INFO - Downloading https://queue.taskcluster.net/v1/task/CKoMrTYcRCaTRi80TknJsA/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 19:15:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CKoMrTYcRCaTRi80TknJsA/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 19:15:01 INFO - Downloaded 1199 bytes. 19:15:01 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 19:15:01 INFO - Using the following test package requirements: 19:15:01 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 19:15:01 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:15:01 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 19:15:01 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:15:01 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 19:15:01 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:15:01 INFO - u'jsshell-mac.zip'], 19:15:01 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:15:01 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 19:15:01 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 19:15:01 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:15:01 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 19:15:01 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:15:01 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 19:15:01 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:15:01 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 19:15:01 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:15:01 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 19:15:01 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:15: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')]} 19:15:01 INFO - trying https://queue.taskcluster.net/v1/task/CKoMrTYcRCaTRi80TknJsA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:15:01 INFO - Downloading https://queue.taskcluster.net/v1/task/CKoMrTYcRCaTRi80TknJsA/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 19:15:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CKoMrTYcRCaTRi80TknJsA/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 19:15:02 INFO - Downloaded 16396643 bytes. 19:15:02 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 19:15:09 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 19:15:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:09 INFO - trying https://queue.taskcluster.net/v1/task/CKoMrTYcRCaTRi80TknJsA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:15:09 INFO - Downloading https://queue.taskcluster.net/v1/task/CKoMrTYcRCaTRi80TknJsA/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 19:15:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CKoMrTYcRCaTRi80TknJsA/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 19:15:10 INFO - Downloaded 16396643 bytes. 19:15:10 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 19:15:16 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')]} 19:15:16 INFO - trying https://queue.taskcluster.net/v1/task/CKoMrTYcRCaTRi80TknJsA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 19:15:16 INFO - Downloading https://queue.taskcluster.net/v1/task/CKoMrTYcRCaTRi80TknJsA/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 19:15:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CKoMrTYcRCaTRi80TknJsA/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 19:15:17 INFO - Downloaded 14413998 bytes. 19:15:17 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 19:15: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')]} 19:15:17 INFO - trying https://queue.taskcluster.net/v1/task/CKoMrTYcRCaTRi80TknJsA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:15:17 INFO - Downloading https://queue.taskcluster.net/v1/task/CKoMrTYcRCaTRi80TknJsA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 19:15:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CKoMrTYcRCaTRi80TknJsA/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 19:15:20 INFO - Downloaded 111573044 bytes. 19:15:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CKoMrTYcRCaTRi80TknJsA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:15:20 INFO - mkdir: /builds/slave/test/properties 19:15:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:15:20 INFO - Writing to file /builds/slave/test/properties/build_url 19:15:20 INFO - Contents: 19:15:20 INFO - build_url:https://queue.taskcluster.net/v1/task/CKoMrTYcRCaTRi80TknJsA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:15:21 INFO - Running post-action listener: _resource_record_post_action 19:15:21 INFO - Running post-action listener: set_extra_try_arguments 19:15:21 INFO - [mozharness: 2016-09-01 02:15:21.455936Z] Finished download-and-extract step (success) 19:15:21 INFO - [mozharness: 2016-09-01 02:15:21.456089Z] Running populate-webroot step. 19:15:21 INFO - Running pre-action listener: _resource_record_pre_action 19:15:21 INFO - Running main action method: populate_webroot 19:15:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:15:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:15:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:15:21 INFO - '--tppagecycles', 19:15:21 INFO - '7'], 19:15:21 INFO - 'tests': ['tsvgm']}}, 19:15:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:15:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:15:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:15:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:15:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:15:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:15:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:15:21 INFO - 'talos_options': ['--disable-e10s'], 19:15:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:15:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:15:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:15:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:15:21 INFO - 'talos_options': ['--disable-e10s'], 19:15:21 INFO - 'tests': ['damp', 'tps']}, 19:15:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:15:21 INFO - 'tests': ['damp', 'tps']}, 19:15:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:15:21 INFO - 'tests': ['dromaeo_dom']}, 19:15:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:15:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:15:21 INFO - 'tests': ['basic_compositor_video']}, 19:15:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:15:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:15:21 INFO - 'tests': ['a11yr', 19:15:21 INFO - 'ts_paint', 19:15:21 INFO - 'tpaint', 19:15:21 INFO - 'sessionrestore', 19:15:21 INFO - 'sessionrestore_no_auto_restore', 19:15:21 INFO - 'tabpaint']}, 19:15:21 INFO - 'other-e10s': {'tests': ['a11yr', 19:15:21 INFO - 'ts_paint', 19:15:21 INFO - 'tpaint', 19:15:21 INFO - 'sessionrestore', 19:15:21 INFO - 'sessionrestore_no_auto_restore', 19:15:21 INFO - 'tabpaint']}, 19:15:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:15:21 INFO - 'ts_paint', 19:15:21 INFO - 'tpaint', 19:15:21 INFO - 'sessionrestore', 19:15:21 INFO - 'sessionrestore_no_auto_restore', 19:15:21 INFO - 'tabpaint']}, 19:15:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:15:21 INFO - 'ts_paint', 19:15:21 INFO - 'tpaint', 19:15:21 INFO - 'sessionrestore', 19:15:21 INFO - 'sessionrestore_no_auto_restore', 19:15:21 INFO - 'tabpaint']}, 19:15:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:15:21 INFO - 'tests': ['a11yr', 19:15:21 INFO - 'ts_paint', 19:15:21 INFO - 'tpaint', 19:15:21 INFO - 'sessionrestore', 19:15:21 INFO - 'sessionrestore_no_auto_restore', 19:15:21 INFO - 'tabpaint']}, 19:15:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:15:21 INFO - 'tests': ['a11yr', 19:15:21 INFO - 'ts_paint', 19:15:21 INFO - 'tpaint', 19:15:21 INFO - 'sessionrestore', 19:15:21 INFO - 'sessionrestore_no_auto_restore', 19:15:21 INFO - 'tabpaint']}, 19:15:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:15:21 INFO - 'tests': ['tsvgx', 19:15:21 INFO - 'tsvgr_opacity', 19:15:21 INFO - 'tart', 19:15:21 INFO - 'tscrollx', 19:15:21 INFO - 'cart']}, 19:15:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:15:21 INFO - 'tsvgr_opacity', 19:15:21 INFO - 'tart', 19:15:21 INFO - 'tscrollx', 19:15:21 INFO - 'cart']}, 19:15:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:15:21 INFO - 'talos_options': ['--disable-e10s'], 19:15:21 INFO - 'tests': ['tp5o']}, 19:15:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:15:21 INFO - 'tests': ['tp5o']}, 19:15:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:15:21 INFO - 'talos_options': ['--disable-e10s', 19:15:21 INFO - '--xperf_path', 19:15:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:15:21 INFO - 'tests': ['tp5n']}, 19:15:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:15:21 INFO - 'talos_options': ['--xperf_path', 19:15:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:15:21 INFO - 'tests': ['tp5n']}}, 19:15:21 INFO - 'talos.zip': {'path': '', 19:15:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:15:21 INFO - Running post-action listener: _resource_record_post_action 19:15:21 INFO - [mozharness: 2016-09-01 02:15:21.474577Z] Finished populate-webroot step (success) 19:15:21 INFO - [mozharness: 2016-09-01 02:15:21.474715Z] Running create-virtualenv step. 19:15:21 INFO - Running pre-action listener: _resource_record_pre_action 19:15:21 INFO - Running main action method: create_virtualenv 19:15:21 INFO - Creating virtualenv /builds/slave/test/build/venv 19:15:21 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 19:15:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:15:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:15:21 INFO - Using real prefix '/tools/python27' 19:15:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:15:22 INFO - Installing distribute.............................................................................................................................................................................................done. 19:15:25 INFO - Installing pip.................done. 19:15:25 INFO - Return code: 0 19:15:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:15:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:25 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 0x108894a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108929618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8214983e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087f8d30>, '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 0x10855ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087f0b20>, '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.xxeyNH5Cxn/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.5qqVfGKWQs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 19:15:25 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 19:15:25 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 19:15:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xxeyNH5Cxn/Render', 19:15:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:25 INFO - 'HOME': '/Users/cltbld', 19:15:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:25 INFO - 'LOGNAME': 'cltbld', 19:15:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:25 INFO - 'MOZ_NO_REMOTE': '1', 19:15:25 INFO - 'NO_EM_RESTART': '1', 19:15:25 INFO - 'PAGER': '/bin/cat', 19:15:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:25 INFO - 'PWD': '/builds/slave/test', 19:15:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:25 INFO - 'SHELL': '/bin/bash', 19:15:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5qqVfGKWQs/Listeners', 19:15:25 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 19:15:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:25 INFO - 'USER': 'cltbld', 19:15:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:25 INFO - 'XPC_FLAGS': '0x0', 19:15:25 INFO - 'XPC_SERVICE_NAME': '0', 19:15:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:25 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 19:15:25 INFO - Cleaning up... 19:15:25 INFO - Return code: 0 19:15:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:15:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:25 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 0x108894a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108929618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8214983e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087f8d30>, '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 0x10855ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087f0b20>, '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.xxeyNH5Cxn/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.5qqVfGKWQs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 19:15:25 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 19:15:25 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 19:15:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xxeyNH5Cxn/Render', 19:15:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:25 INFO - 'HOME': '/Users/cltbld', 19:15:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:25 INFO - 'LOGNAME': 'cltbld', 19:15:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:25 INFO - 'MOZ_NO_REMOTE': '1', 19:15:25 INFO - 'NO_EM_RESTART': '1', 19:15:25 INFO - 'PAGER': '/bin/cat', 19:15:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:25 INFO - 'PWD': '/builds/slave/test', 19:15:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:25 INFO - 'SHELL': '/bin/bash', 19:15:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5qqVfGKWQs/Listeners', 19:15:25 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 19:15:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:25 INFO - 'USER': 'cltbld', 19:15:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:25 INFO - 'XPC_FLAGS': '0x0', 19:15:25 INFO - 'XPC_SERVICE_NAME': '0', 19:15:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:25 INFO - Downloading/unpacking psutil>=3.1.1 19:15:25 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 19:15:25 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 19:15:25 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 19:15:25 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 19:15:25 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 19:15:25 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 19:15:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:15:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:15:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:15:28 INFO - Installing collected packages: psutil 19:15:28 INFO - Running setup.py install for psutil 19:15:29 INFO - building 'psutil._psutil_osx' extension 19:15:29 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 19:15:29 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 19:15:29 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 19:15:29 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 19:15:29 INFO - building 'psutil._psutil_posix' extension 19:15:29 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 19:15:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:15:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:15:29 INFO - ^ 19:15:29 INFO - 1 warning generated. 19:15:29 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 19:15:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:15:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:15:30 INFO - Successfully installed psutil 19:15:30 INFO - Cleaning up... 19:15:30 INFO - Return code: 0 19:15:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:15: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')]} 19:15:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15: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')]} 19:15:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:30 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 0x108894a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108929618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8214983e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087f8d30>, '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 0x10855ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087f0b20>, '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.xxeyNH5Cxn/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.5qqVfGKWQs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 19:15:30 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 19:15:30 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 19:15:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xxeyNH5Cxn/Render', 19:15:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:30 INFO - 'HOME': '/Users/cltbld', 19:15:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:30 INFO - 'LOGNAME': 'cltbld', 19:15:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:30 INFO - 'MOZ_NO_REMOTE': '1', 19:15:30 INFO - 'NO_EM_RESTART': '1', 19:15:30 INFO - 'PAGER': '/bin/cat', 19:15:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:30 INFO - 'PWD': '/builds/slave/test', 19:15:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:30 INFO - 'SHELL': '/bin/bash', 19:15:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5qqVfGKWQs/Listeners', 19:15:30 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 19:15:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:30 INFO - 'USER': 'cltbld', 19:15:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:30 INFO - 'XPC_FLAGS': '0x0', 19:15:30 INFO - 'XPC_SERVICE_NAME': '0', 19:15:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:15:30 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 19:15:30 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 19:15:30 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 19:15:30 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 19:15:30 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 19:15:30 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 19:15:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:15:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:15:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:15:32 INFO - Installing collected packages: mozsystemmonitor 19:15:32 INFO - Running setup.py install for mozsystemmonitor 19:15:33 INFO - Successfully installed mozsystemmonitor 19:15:33 INFO - Cleaning up... 19:15:33 INFO - Return code: 0 19:15:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:15:33 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')]} 19:15:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:33 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')]} 19:15:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:33 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 0x108894a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108929618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8214983e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087f8d30>, '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 0x10855ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087f0b20>, '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.xxeyNH5Cxn/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.5qqVfGKWQs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 19:15:33 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 19:15:33 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 19:15:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xxeyNH5Cxn/Render', 19:15:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:33 INFO - 'HOME': '/Users/cltbld', 19:15:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:33 INFO - 'LOGNAME': 'cltbld', 19:15:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:33 INFO - 'MOZ_NO_REMOTE': '1', 19:15:33 INFO - 'NO_EM_RESTART': '1', 19:15:33 INFO - 'PAGER': '/bin/cat', 19:15:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:33 INFO - 'PWD': '/builds/slave/test', 19:15:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:33 INFO - 'SHELL': '/bin/bash', 19:15:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5qqVfGKWQs/Listeners', 19:15:33 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 19:15:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:33 INFO - 'USER': 'cltbld', 19:15:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:33 INFO - 'XPC_FLAGS': '0x0', 19:15:33 INFO - 'XPC_SERVICE_NAME': '0', 19:15:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:33 INFO - Downloading/unpacking jsonschema==2.5.1 19:15:33 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 19:15:33 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 19:15:33 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 19:15:33 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 19:15:33 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 19:15:33 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 19:15:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:15:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:15:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:36 INFO - Downloading functools32-3.2.3-2.tar.gz 19:15:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:15:36 INFO - warning: no files found matching '*.txt' 19:15:36 INFO - no previously-included directories found matching 'build' 19:15:36 INFO - no previously-included directories found matching 'dist' 19:15:36 INFO - no previously-included directories found matching '.git*' 19:15:36 INFO - Installing collected packages: jsonschema, functools32 19:15:36 INFO - Running setup.py install for functools32 19:15:36 INFO - warning: no files found matching '*.txt' 19:15:36 INFO - no previously-included directories found matching 'build' 19:15:36 INFO - no previously-included directories found matching 'dist' 19:15:36 INFO - no previously-included directories found matching '.git*' 19:15:36 INFO - Successfully installed jsonschema functools32 19:15:36 INFO - Cleaning up... 19:15:36 INFO - Return code: 0 19:15:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:15:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108894a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108929618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8214983e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087f8d30>, '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 0x10855ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087f0b20>, '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.xxeyNH5Cxn/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.5qqVfGKWQs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 19:15:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:15:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:15:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xxeyNH5Cxn/Render', 19:15:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:36 INFO - 'HOME': '/Users/cltbld', 19:15:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:36 INFO - 'LOGNAME': 'cltbld', 19:15:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:36 INFO - 'MOZ_NO_REMOTE': '1', 19:15:36 INFO - 'NO_EM_RESTART': '1', 19:15:36 INFO - 'PAGER': '/bin/cat', 19:15:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:36 INFO - 'PWD': '/builds/slave/test', 19:15:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:36 INFO - 'SHELL': '/bin/bash', 19:15:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5qqVfGKWQs/Listeners', 19:15:36 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 19:15:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:36 INFO - 'USER': 'cltbld', 19:15:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:36 INFO - 'XPC_FLAGS': '0x0', 19:15:36 INFO - 'XPC_SERVICE_NAME': '0', 19:15:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:15:36 INFO - Cleaning up... 19:15:36 INFO - Return code: 0 19:15:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:15:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108894a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108929618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8214983e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087f8d30>, '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 0x10855ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087f0b20>, '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.xxeyNH5Cxn/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.5qqVfGKWQs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 19:15:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:15:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:15:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xxeyNH5Cxn/Render', 19:15:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:36 INFO - 'HOME': '/Users/cltbld', 19:15:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:36 INFO - 'LOGNAME': 'cltbld', 19:15:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:36 INFO - 'MOZ_NO_REMOTE': '1', 19:15:36 INFO - 'NO_EM_RESTART': '1', 19:15:36 INFO - 'PAGER': '/bin/cat', 19:15:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:36 INFO - 'PWD': '/builds/slave/test', 19:15:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:36 INFO - 'SHELL': '/bin/bash', 19:15:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5qqVfGKWQs/Listeners', 19:15:36 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 19:15:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:36 INFO - 'USER': 'cltbld', 19:15:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:36 INFO - 'XPC_FLAGS': '0x0', 19:15:36 INFO - 'XPC_SERVICE_NAME': '0', 19:15:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:37 INFO - Downloading/unpacking blobuploader==1.2.4 19:15:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:39 INFO - Downloading blobuploader-1.2.4.tar.gz 19:15:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:15:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:15:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:15:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:15:40 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 19:15:40 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 19:15:40 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 19:15:40 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 19:15:40 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 19:15:40 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 19:15:40 INFO - Downloading docopt-0.6.1.tar.gz 19:15:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:15:40 INFO - Installing collected packages: blobuploader, requests, docopt 19:15:40 INFO - Running setup.py install for blobuploader 19:15:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:15:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:15:40 INFO - Running setup.py install for requests 19:15:41 INFO - Running setup.py install for docopt 19:15:41 INFO - Successfully installed blobuploader requests docopt 19:15:41 INFO - Cleaning up... 19:15:41 INFO - Return code: 0 19:15:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:15:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:41 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 0x108894a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108929618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8214983e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087f8d30>, '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 0x10855ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087f0b20>, '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.xxeyNH5Cxn/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.5qqVfGKWQs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 19:15:41 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 19:15:41 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 19:15:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xxeyNH5Cxn/Render', 19:15:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:41 INFO - 'HOME': '/Users/cltbld', 19:15:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:41 INFO - 'LOGNAME': 'cltbld', 19:15:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:41 INFO - 'MOZ_NO_REMOTE': '1', 19:15:41 INFO - 'NO_EM_RESTART': '1', 19:15:41 INFO - 'PAGER': '/bin/cat', 19:15:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:41 INFO - 'PWD': '/builds/slave/test', 19:15:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:41 INFO - 'SHELL': '/bin/bash', 19:15:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5qqVfGKWQs/Listeners', 19:15:41 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 19:15:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:41 INFO - 'USER': 'cltbld', 19:15:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:41 INFO - 'XPC_FLAGS': '0x0', 19:15:41 INFO - 'XPC_SERVICE_NAME': '0', 19:15:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:15:41 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-RQ7Z57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:15:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:15:42 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-A9Gn3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:15:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:15:42 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-cQlOqm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:15:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:15:42 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-BteXDS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:15:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:15:42 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-TDGHqe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:15:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:15:42 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-1RQEBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:15:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:15:42 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-Y82tNw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:15:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:15:42 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-z2bGKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:15:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:15:42 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-rPdpqJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:15:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:15:42 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-SQwfcA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:15:43 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-Tu4KUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:15:43 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-uy3wAz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:15:43 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-0O1bEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:15:43 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-VOYj4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:15:43 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-gd_plJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:15:43 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-eObGab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:15:43 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-Ptrarp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:15:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:15:43 INFO - Running setup.py install for manifestparser 19:15:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:15:43 INFO - Running setup.py install for mozcrash 19:15:44 INFO - Running setup.py install for mozdebug 19:15:44 INFO - Running setup.py install for mozdevice 19:15:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:15:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:15:44 INFO - Running setup.py install for mozfile 19:15:44 INFO - Running setup.py install for mozhttpd 19:15:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:15:44 INFO - Running setup.py install for mozinfo 19:15:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:15:44 INFO - Running setup.py install for mozInstall 19:15:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:15:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:15:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:15:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:15:44 INFO - Running setup.py install for mozleak 19:15:45 INFO - Running setup.py install for mozlog 19:15:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:15:45 INFO - Running setup.py install for moznetwork 19:15:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:15:45 INFO - Running setup.py install for mozprocess 19:15:45 INFO - Running setup.py install for mozprofile 19:15:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:15:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:15:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:15:45 INFO - Running setup.py install for mozrunner 19:15:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:15:45 INFO - Running setup.py install for mozscreenshot 19:15:45 INFO - Running setup.py install for moztest 19:15:46 INFO - Running setup.py install for mozversion 19:15:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:15:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:15:46 INFO - Cleaning up... 19:15:46 INFO - Return code: 0 19:15:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:15:46 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')]} 19:15:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:46 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')]} 19:15:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:46 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 0x108894a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108929618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8214983e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087f8d30>, '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 0x10855ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087f0b20>, '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.xxeyNH5Cxn/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.5qqVfGKWQs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 19:15:46 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 19:15:46 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 19:15:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xxeyNH5Cxn/Render', 19:15:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:46 INFO - 'HOME': '/Users/cltbld', 19:15:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:46 INFO - 'LOGNAME': 'cltbld', 19:15:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:46 INFO - 'MOZ_NO_REMOTE': '1', 19:15:46 INFO - 'NO_EM_RESTART': '1', 19:15:46 INFO - 'PAGER': '/bin/cat', 19:15:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:46 INFO - 'PWD': '/builds/slave/test', 19:15:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:46 INFO - 'SHELL': '/bin/bash', 19:15:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5qqVfGKWQs/Listeners', 19:15:46 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 19:15:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:46 INFO - 'USER': 'cltbld', 19:15:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:46 INFO - 'XPC_FLAGS': '0x0', 19:15:46 INFO - 'XPC_SERVICE_NAME': '0', 19:15:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:15:46 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-X1ZBZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:15:46 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)) 19:15:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:15:46 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-mCcXv_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:15:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:15:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:15:46 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-DBcQVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:15:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:15:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:15:46 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-0QNuGr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:15:47 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)) 19:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:15:47 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-mDnluX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:15:47 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)) 19:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:15:47 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-QeIlWD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:15:47 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)) 19:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:15:47 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-gjTOlF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:15:47 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)) 19:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:15:47 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-KWsptN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:15:47 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)) 19:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:15:47 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-UGtFxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:15:47 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)) 19:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:15:47 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-935CEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:15:47 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)) 19:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:15:47 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-j7FrtJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:15:47 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)) 19:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:15:47 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-3OkwYM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:15:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:15:47 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-_4IFgH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:15:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:15:47 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-9IFrn_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:15:48 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)) 19:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:15:48 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-bGtPop-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:15:48 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)) 19:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:15:48 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-6XLcYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:15:48 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)) 19:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:15:48 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-9TjW01-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:15:48 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)) 19:15:48 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)) 19:15:48 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)) 19:15:48 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)) 19:15:48 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)) 19:15:48 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)) 19:15:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:15:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:50 INFO - Downloading blessings-1.6.tar.gz 19:15:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:15:50 INFO - Installing collected packages: blessings 19:15:50 INFO - Running setup.py install for blessings 19:15:51 INFO - Successfully installed blessings 19:15:51 INFO - Cleaning up... 19:15:51 INFO - Return code: 0 19:15:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:15:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:15:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:15:51 INFO - Reading from file tmpfile_stdout 19:15:51 INFO - Current package versions: 19:15:51 INFO - blessings == 1.6 19:15:51 INFO - blobuploader == 1.2.4 19:15:51 INFO - docopt == 0.6.1 19:15:51 INFO - functools32 == 3.2.3-2 19:15:51 INFO - jsonschema == 2.5.1 19:15:51 INFO - manifestparser == 1.1 19:15:51 INFO - mozInstall == 1.12 19:15:51 INFO - mozcrash == 0.17 19:15:51 INFO - mozdebug == 0.1 19:15:51 INFO - mozdevice == 0.48 19:15:51 INFO - mozfile == 1.2 19:15:51 INFO - mozhttpd == 0.7 19:15:51 INFO - mozinfo == 0.9 19:15:51 INFO - mozleak == 0.1 19:15:51 INFO - mozlog == 3.3 19:15:51 INFO - moznetwork == 0.27 19:15:51 INFO - mozprocess == 0.23 19:15:51 INFO - mozprofile == 0.28 19:15:51 INFO - mozrunner == 6.12 19:15:51 INFO - mozscreenshot == 0.1 19:15:51 INFO - mozsystemmonitor == 0.3 19:15:51 INFO - moztest == 0.7 19:15:51 INFO - mozversion == 1.4 19:15:51 INFO - psutil == 3.1.1 19:15:51 INFO - requests == 1.2.3 19:15:51 INFO - wsgiref == 0.1.2 19:15:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:15:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:51 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 0x108894a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108929618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8214983e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087f8d30>, '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 0x10855ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087f0b20>, '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.xxeyNH5Cxn/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.5qqVfGKWQs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 19:15:51 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 19:15:51 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 19:15:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xxeyNH5Cxn/Render', 19:15:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:51 INFO - 'HOME': '/Users/cltbld', 19:15:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:51 INFO - 'LOGNAME': 'cltbld', 19:15:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:51 INFO - 'MOZ_NO_REMOTE': '1', 19:15:51 INFO - 'NO_EM_RESTART': '1', 19:15:51 INFO - 'PAGER': '/bin/cat', 19:15:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:51 INFO - 'PWD': '/builds/slave/test', 19:15:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:51 INFO - 'SHELL': '/bin/bash', 19:15:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5qqVfGKWQs/Listeners', 19:15:51 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 19:15:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:51 INFO - 'USER': 'cltbld', 19:15:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:51 INFO - 'XPC_FLAGS': '0x0', 19:15:51 INFO - 'XPC_SERVICE_NAME': '0', 19:15:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:51 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)) 19:15:51 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)) 19:15:51 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)) 19:15:51 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)) 19:15:51 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)) 19:15:51 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)) 19:15:51 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)) 19:15:51 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)) 19:15:51 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)) 19:15:51 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)) 19:15:51 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)) 19:15:51 INFO - Cleaning up... 19:15:51 INFO - Return code: 0 19:15:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:15:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:51 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 0x108894a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108929618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8214983e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087f8d30>, '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 0x10855ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087f0b20>, '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.xxeyNH5Cxn/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.5qqVfGKWQs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 19:15:51 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 19:15:51 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 19:15:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xxeyNH5Cxn/Render', 19:15:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:51 INFO - 'HOME': '/Users/cltbld', 19:15:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:51 INFO - 'LOGNAME': 'cltbld', 19:15:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:51 INFO - 'MOZ_NO_REMOTE': '1', 19:15:51 INFO - 'NO_EM_RESTART': '1', 19:15:51 INFO - 'PAGER': '/bin/cat', 19:15:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:51 INFO - 'PWD': '/builds/slave/test', 19:15:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:51 INFO - 'SHELL': '/bin/bash', 19:15:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5qqVfGKWQs/Listeners', 19:15:51 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 19:15:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:51 INFO - 'USER': 'cltbld', 19:15:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:51 INFO - 'XPC_FLAGS': '0x0', 19:15:51 INFO - 'XPC_SERVICE_NAME': '0', 19:15:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:15:52 INFO - Cleaning up... 19:15:52 INFO - Return code: 0 19:15:52 INFO - Running post-action listener: _resource_record_post_action 19:15:52 INFO - Running post-action listener: _start_resource_monitoring 19:15:52 INFO - Starting resource monitoring. 19:15:52 INFO - [mozharness: 2016-09-01 02:15:52.084099Z] Finished create-virtualenv step (success) 19:15:52 INFO - [mozharness: 2016-09-01 02:15:52.085134Z] Running install step. 19:15:52 INFO - Running pre-action listener: _resource_record_pre_action 19:15:52 INFO - Running main action method: install 19:15:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:15:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:15:52 INFO - Reading from file tmpfile_stdout 19:15:52 INFO - Detecting whether we're running mozinstall >=1.0... 19:15:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:15:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:15:52 INFO - Reading from file tmpfile_stdout 19:15:52 INFO - Output received: 19:15:52 INFO - Usage: mozinstall [options] installer 19:15:52 INFO - Options: 19:15:52 INFO - -h, --help show this help message and exit 19:15:52 INFO - -d DEST, --destination=DEST 19:15:52 INFO - Directory to install application into. [default: 19:15:52 INFO - "/builds/slave/test"] 19:15:52 INFO - --app=APP Application being installed. [default: firefox] 19:15:52 INFO - mkdir: /builds/slave/test/build/application 19:15:52 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'] 19:15:52 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 19:16:26 INFO - Reading from file tmpfile_stdout 19:16:26 INFO - Output received: 19:16:26 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:16:26 INFO - Running post-action listener: _resource_record_post_action 19:16:26 INFO - [mozharness: 2016-09-01 02:16:26.592947Z] Finished install step (success) 19:16:26 INFO - [mozharness: 2016-09-01 02:16:26.593060Z] Running run-tests step. 19:16:26 INFO - Running pre-action listener: _resource_record_pre_action 19:16:26 INFO - Running main action method: run_tests 19:16:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:16:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:16:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:16:26 INFO - 2016-08-31 19:16:26.607 screenresolution[1626:7343] starting screenresolution argv=screenresolution get 19:16:26 INFO - 2016-08-31 19:16:26.610 screenresolution[1626:7343] Display 0: 1600x1200x32@60 19:16:26 INFO - 2016-08-31 19:16:26.616 screenresolution[1627:7345] starting screenresolution argv=screenresolution list 19:16:26 INFO - Available Modes on Display 0 19:16:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:16:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:16:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:16:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:16:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:16:26 INFO - Intel Iris: 19:16:26 INFO - Chipset Model: Intel Iris 19:16:26 INFO - Type: GPU 19:16:26 INFO - Bus: Built-In 19:16:26 INFO - VRAM (Dynamic, Max): 1536 MB 19:16:26 INFO - Vendor: Intel (0x8086) 19:16:26 INFO - Device ID: 0x0a2e 19:16:26 INFO - Revision ID: 0x0009 19:16:26 INFO - Displays: 19:16:26 INFO - Display: 19:16:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:16:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:16:26 INFO - Display Serial Number: 1600x1200 60 19:16:26 INFO - Main Display: Yes 19:16:26 INFO - Mirror: Off 19:16:26 INFO - Online: Yes 19:16:26 INFO - Rotation: Supported 19:16:26 INFO - Return code: 0 19:16:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:16:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:16:26 INFO - Python 2.7.3 19:16:26 INFO - Return code: 0 19:16:26 INFO - grabbing minidump binary from tooltool 19:16:26 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')]} 19:16:26 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 0x1087f8d30>, '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 0x10855ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087f0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:16:26 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 19:16:26 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 19:16:26 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 19:16:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:16:26 INFO - Return code: 0 19:16:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:16:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:16:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:16:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:16:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:16:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CKoMrTYcRCaTRi80TknJsA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0264', '--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 19:16:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CKoMrTYcRCaTRi80TknJsA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0264 --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 19:16:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xxeyNH5Cxn/Render', 19:16:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:16:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:16:26 INFO - 'HOME': '/Users/cltbld', 19:16:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:16:26 INFO - 'LOGNAME': 'cltbld', 19:16:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:16:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:16:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:16:26 INFO - 'MOZ_NO_REMOTE': '1', 19:16:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:16:26 INFO - 'NO_EM_RESTART': '1', 19:16:26 INFO - 'PAGER': '/bin/cat', 19:16:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:16:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:16:26 INFO - 'PWD': '/builds/slave/test', 19:16:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:16:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:16:26 INFO - 'SHELL': '/bin/bash', 19:16:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5qqVfGKWQs/Listeners', 19:16:26 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 19:16:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:16:26 INFO - 'USER': 'cltbld', 19:16:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:16:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:16:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:16:26 INFO - 'XPC_FLAGS': '0x0', 19:16:26 INFO - 'XPC_SERVICE_NAME': '0', 19:16:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:16:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CKoMrTYcRCaTRi80TknJsA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0264', '--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 19:16:27 INFO - mozversion application_buildid: 20160831165521 19:16:27 INFO - mozversion application_changeset: c18c990a038f752ffbeea92b1d71b3d5d0039f4f 19:16:27 INFO - mozversion application_display_name: Nightly 19:16:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:16:27 INFO - mozversion application_name: Firefox 19:16:27 INFO - mozversion application_remotingname: firefox 19:16:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:16:27 INFO - mozversion application_vendor: Mozilla 19:16:27 INFO - mozversion application_version: 51.0a1 19:16:27 INFO - mozversion platform_buildid: 20160831165521 19:16:27 INFO - mozversion platform_changeset: c18c990a038f752ffbeea92b1d71b3d5d0039f4f 19:16:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:16:27 INFO - mozversion platform_version: 51.0a1 19:16:27 INFO - using testdate: 1472696187 19:16:27 INFO - actual date: 1472696187 19:16:27 INFO - starting webserver on 'localhost:49274' 19:16:27 INFO - SUITE-START | Running 2 tests 19:16:27 INFO - TEST-START | dromaeo_css 19:16:27 INFO - operating with platform_type : mac_ 19:16:27 INFO - Initialising browser for dromaeo_css test... 19:16:27 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/tmp_8IPqQ/profile http://localhost:49274/getInfo.html) 19:16:29 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 19:16:29 INFO - PROCESS | 1637 | colorDepth:24 19:16:29 INFO - PROCESS | 1637 | Browser inner width/height: 1024/689 19:16:29 INFO - PROCESS | 1637 | __metrics 19:16:30 INFO - PROCESS | 1637 | 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" 19:16:30 INFO - PROCESS | 1637 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:16:30 INFO - TEST-INFO | 1637: exit 0 19:16:30 INFO - Browser initialized. 19:16:30 INFO - Running cycle 1/1 for dromaeo_css test... 19:16:30 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/tmp_8IPqQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:16:42 INFO - PROCESS | 1639 | RSS: Main: 234536960 19:16:42 INFO - PROCESS | 1639 | 19:17:33 INFO - PROCESS | 1639 | 1472696253854 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] 19:19:45 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 19:19:47 INFO - PROCESS | 1639 | RSS: Main: 412315648 19:19:47 INFO - PROCESS | 1639 | 19:22:50 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 19:22:51 INFO - PROCESS | 1639 | RSS: Main: 370262016 19:22:51 INFO - PROCESS | 1639 | 19:25:53 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 19:25:54 INFO - PROCESS | 1639 | RSS: Main: 329568256 19:25:54 INFO - PROCESS | 1639 | 19:28:56 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 19:28:57 INFO - PROCESS | 1639 | RSS: Main: 330813440 19:28:57 INFO - PROCESS | 1639 | 19:32:00 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 19:32:01 INFO - PROCESS | 1639 | RSS: Main: 329076736 19:32:01 INFO - PROCESS | 1639 | 19:35:04 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 19:35:04 INFO - PROCESS | 1639 | RSS: Main: 392286208 19:35:04 INFO - PROCESS | 1639 | 19:35:04 INFO - PROCESS | 1639 | __start_tp_report 19:35:04 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 19:35:04 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 19:35:04 INFO - PROCESS | 1639 | |i|pagename|runs| 19:35:04 INFO - PROCESS | 1639 | |0;dojo.html;2820.760235379993;3342.064222017836;3376.3753705564473;3377.0881861897305;3447.052060683312;5667.631603945741;5707.286589176353;5782.017057100293;5807.8257652270395;5822.7088645567765;5757.481826635602;5787.594868359217;5797.043487824504;5813.854653633662;5817.360090390053;5246.475352464751;5261.473852614727;5320.308359913206;5324.227986941891;5370.060239458098;5325.041492531343;5341.65279256849;5346.705931173784;5348.759305831228;5352.090144675395;5281.2342210379475;5305.230741542474;5320.600954928376;5340.599455040868;5357.678539287655;4485.573870482299;5394.433584473652;5396.298481197458;5405.5135037846385;5413.485718856702;5123.077845987721;5142.974285128588;5170.224466330043;5296.390915044781;5318.095923692981;5696.060150075233;5711.228984087136;5733.684647344395;5738.684372359518;5812.82561523155;6236.18929539157;6242.687865606701;6249.7187626556915;6255.968720156416;6257.092721555349;5173.456787037345;6241.469475094623;6272.3100458949475;6318.968405157945;6326.462250708695;5815.41845815415;5821.155932389822;5833.7083145842535;5863.941360586382;5884.293884733774;6248.718807653667;6266.436020758154;6280.874382512324;6281.120643109968;6283.3402492737605;6016.127031385684;6051.8487037823525;6052.0376020041;6181.443670069715;6205.906911396332;6196.380361963767;6245.156903817927;6275.717592708248;6290.685465726695;6294.685265736694;6229.813105606839;6230.6884655767935;6232.189815323979;6252.468540174045;6255.78104766331;6114.388610881752;6134.693265336716;6241.063840423882;6257.561970662101;6264.686765661699;4973.975130124399;5018.899622007612;5022.573081288131;5026.321446604735;5074.695518268915;4448.755318457451;4473.597376236155;4474.105178964221;4477.8432754854075;4503.121891231179;5650.23721797552;5697.743601537941;5755.079187330007;5777;5786.768529258867;5253.44838791929;6229.283632382243;6235.750569977241;6267.780627678009;6292.905606415908;5744.971275143597;5752.683602401824;5762;5762.164486149485;5810.767569297265;5400.513953744104;5411.053065713484;5429.891402172014;5441.5102640762525;5445.319335083114;6206;6245.156903818108;6248.218972628421;6257.655828929453;6303.936960630335;5632.746526406239;6264.937350626617;6299.960506516373;6315.873682526416;6317.842053948688;6273;6280.717367718555;6284.4344009039405;6287.402696743802;6297.905531416941;6142.877142457215;6208.937910621016;6211.254649442096;6223.222097237845;6265.373462653881;5319.468053194804;6094.634321940698;6133.487685122301;6222.097795819672;6279.748810047547;6193.690315484298;6195.752169913153;6202.100695399052;6205.5345849060595;6223.439890409885;6074.696265186812;6133.172021777003;6218.5647004709235;6219.378062193744;6220.129181914445;5874.618149820248;5943.108533719804;5975.168132436606;5980;6068.059450785136;6121.689788751648;6160.599561028519;6161.753529858755;6166.167567378347;6182.319944805978;5895.793647222326;5947.762089516371;5964.5526585505395;6018.21763170785;6043.425874541911 19:35:04 INFO - PROCESS | 1639 | |1;ext.html;471.84193295246104;476.5782282679829;518.3028826228722;533.5518164741617;545.8580769000059;29025.41949161018;40441.59558404415;41476.377854332175;41855.790721046425;43457.7827110864;42955.140897182035;43299.13401731964;43365.78317108418;43385.34921976173;43462;8000.919990800105;9752.512374381271;9952.402855828637;9953.35303205293;9956.950215248913;8719.389642725011;11962.1626486146;12205.57280495183;12232;12462.937685311561;10347.586096556166;12134.332611706353;12213.206141600813;12291.569795057174;12325.938370308137;12075.2151110178;12136.696582585506;12147.817782733222;12163.93918030413;12189.207701499374;11967.86305300995;12048.518059277618;12052.397380130958;12053.397330133546;12059.035277177805;40367.59632403668;40575.59424405748;40675.796621017;40763.7961810192;40775.79612101921;3497.9475307870403;3521.7358698097487;3555.768875023141;3777.905552361213;3947.605239476058;30498.84750576233;31577.684223157703;31728.682713172802;31763.84118079395;31871.203219919455;3187.9203019924457;3248.4640034394297;3323.966760332414;3393.2874096439778;3394.117529442363;501281;501694;502713;503276;517181.4140929309;59735.701321493114;59781.701091494266;59884.80230395368;60355.396446035855;60401.69799150976;63609.68195158995;63711.6814415925;63787.36212637814;63832;63940.36059639344;13834.169949803045;16071.107200703986;16305.918470407572;16307.184640768151;16352.75470867938;11428.542858285575;11663.542057242845;12670;12746.617601471971;12749.298788566532;13650.590482285548;17164.82835171632;17226.483205503853;17444.430001299945;17560.38538651141;6369.9171140906155;6382.138411314506;6730.663466826639;6786.4570834333135;6813.250542440328;850.927671147947;930.9255259579218;964.7009427077628;966.2415004221652;982.9066238707311;25814.483710325687;27820.304492387448;29546.96585619536;29887.252818679706;30769.538456923165;1036.4765793274348;1046.5186014433446;1084.3927400655657;1120.8679233973746;1122.7586068995206;14202.715945681028;15022.624434389008;15591.610209744847;15885.761713574306;15933.920330398274;648731;654963;657172;657344;661662;58051.4194858046;58268.54328641818;58277.70861145667;58404.4159558399;58436;1231.205872212418;1347.5620423362643;1358.3004752552247;1384.349355802771;1390.2840037380756;30215.848920755256;30867.69132308738;31021.379572407976;31434.899778507643;31844.885429009875;49987.50012499828;50415;50478.747606261735;50614;51703.707407315116;508012.459937698;508233;509478;509974;511878;25889.482210355312;26402;26501.33746656349;27408.86295568589;40014.59985400225;46699.76650116728;46968.53031469778;47136;47870.76064619655;48057.75971120122;38558.879261640875;38876.80561597174;39024.41463378108;40267.39598906077;40346;36591.71928982473;38758.80620596879;38848.41727374035;39458.40812387759;40391.788246352655;37400.812995934844;38015.42976855294;38534.22931541409;38605;38695.03262418349;32857.17857053593;33317.50023749694;33988.49017264693;34883.9534813956;34896.65103349036;22567.548649027256;25121.748782511942;25574.23277301684;25727.742722573283;26117.772901535955 19:35:04 INFO - PROCESS | 1639 | |2;jquery.html;348.56429463171037;367.2013370918253;369.3481006024366;377.91119087014545;398.6571548468317;15416.537503874873;16383.853130280884;19474.707879381822;20629.896850515765;20721.274755383547;19096.427107186806;20507.794922050776;21156;21500.032498537563;21506.56986860262;5182.792688292464;5899.380565040663;6010.158577799111;6089.147519347295;6100.389961003909;5987.670678112702;6055.515558755311;6058.939410605882;6062.666553339564;6062.696865156747;5095.363079615048;5684.715764211794;5967.104934259853;5991.460768530831;6022.608530445529;5981.700914954256;6034.30605480371;6037.667928263943;6352.936470635327;6578.407943285079;5609.242752228479;5839.124131380285;5979.1031345298115;5987.072003839412;5990.101484777275;13063.804042939364;14823.851761482356;19994.30048445884;26093.08674196413;26486.47027059448;2210.8452408331423;2254.458929856839;2294.609916314214;2594.597837335216;2701.257154282588;9539.90460095397;10061.647842325483;10071.647492337806;10177.491125443697;10187.286889917708;1709.6922553940285;1792.2203841329062;1807.5210069331638;1874.7158196635348;1878.1454438230571;112769;114825;117103.82896171014;118466;121851.3907430466;8865.778355541033;11000.504977276041;11415;11430.485628146753;11560.479778409992;14275.643108922151;14276.072055316372;14280.571582852532;14297.428102875769;14306.57080287593;7499.737509187153;8150.736635821457;8310.085890552034;8532.914670853335;8634.309255259564;8274.089850116483;8302.381035697981;8442.704505342406;8470.957645211858;8650.567471626393;4353.10761293934;4441.977790111094;4447.08834688888;4525.660575456788;4542.091581683677;1640.8933419327705;1699.9150042498072;1789.997601343252;1882.077781886893;1931.758530183727;824.3158178711655;856.3235044314921;868.5179725252419;872.5689868615476;895.0467751844265;19864.841899011353;20604.690929636068;20668.689969650466;20792.688109678365;20824.1670333185;891.3047822698305;906.8549032154824;936.2977766674994;974.8635191073255;996.3075662414698;10574.682759517227;11080.335179889233;11084.334939903632;11107.777844443066;11162.107031437465;567236;570289;574602.8888988865;586580;592215;118161.81838181509;127332;131332;134124;136955.63044369628;968.7481254873784;1004.1013293102632;1017.4200705597739;1017.6896046705677;1023.9641612543675;18313.359032434335;18711;19327.03364831781;19403.902980485007;19624.607507849476;53879.730601346746;55565.166522501386;56722.43277567172;56811.14783278337;58113.4188658108;81370.372592549;81991;82596.348073037;85193;85896;7749.922500775146;7809.180036096128;7980.284238888667;8088.231617996516;8106.270435660583;11224.7755044897;12661.620151395473;12878.93560532229;13167.670808229488;13199.538016169388;10930.08723473373;10995.670129896116;11063.446827658423;11148.331100134017;11173.49719262619;10287.691369258935;10900.018998290192;10970.286931349438;11136.832947505956;11445.713857153638;11289.887101128883;11360.034397076011;11375.658730238107;11542.249753765978;11674.70813229676;7762.3790096793255;7961.601919903866;7965.800854978672;8062.234087761653;8298.294644955246;8178.059523154675;10554.841677375;10620.89379106199;11338.979491845443;11484.253523520945 19:35:04 INFO - PROCESS | 1639 | |3;mootools.html;1360.5578187089186;1397.82527184102;1494.5740463967768;1693.0349700670622;1702.276532473699;8020.398470114736;8504.999999999984;8515.403921725481;8521.91478085219;8854.33592480565;8820.250278726317;9015.41399809012;9030.277577793777;9098.135677110664;9112.453252804837;2405.256189262779;2438.2685194441683;2438.8048956083558;2439.817013723969;2442.9389265268333;2095.2561840546614;2375.904963801446;2411.5418070566625;2423.587990041697;2433.841800282976;2377.2511658827502;2396.628522579003;2413.3122060212845;2416.142269494331;2416.6979127609047;2347.718273807154;2375.881205939696;2378.5718570657273;2384.4277373430427;2400.3159099656687;2335.1243283768586;2365.266767302142;2393.8324317297797;2395.556821987938;2401.135591187171;8866.290696744245;8992.325575581792;8995.5052472114;8999.415038022507;9005.099490051009;5182.896342073175;5237.659552129099;5346.625736198468;5347.732613369316;5353.919691204596;3567.7680950738118;3645.65366290202;3646.6353364663323;3651.233241019389;3653.2328211075705;963.734972882456;1071.7802850415724;1083.7615724540588;1097.226455348977;1104.8342748587695;11787.94106029473;11829.757875423129;11831.171817972745;11876.287422754576;11879.40602969848;4445.466544014739;4606.769661516911;4698.506656801055;4892.021595680878;4939.827106051271;9268.814623707489;9345.20565751905;9345.299102567335;9353.345265831327;9357.391769535094;5301.284326615934;5302.8939421212135;5305.495977882095;5305.549028332635;5314.5216930476445;4163.937540936889;4435;4561.566651168134;4864.148773964542;4981.452040275567;3754.4180651998986;3765.7363984521367;3802.315583194996;3840.385538313856;3876.8836934891997;1159.8782127876452;1164.9650510484698;1166.393475392791;1176.7822952753597;1184.8518935133109;811.9850187265918;870.6865528409768;875.4747151709051;886.9068747781519;892.5180402582532;5679.744411501492;5800.50695690871;5867.823965281048;5895;6091.116788065706;443.98106345936156;448.78682625752504;454.23234733301274;462.20039331955235;463.1963543252522;3363.882264120744;3717.1636381814415;3959.208158368338;4050.8784736457956;4129.91740165201;9342;10582.047615714622;11052.944735276273;11079.169062320358;11117.666470005914;9929.950350248202;10751.946240268904;11055.72360690989;11055.778884422267;11075.556977720962;8143.18568143181;8309.626066826886;8318.625661845232;8515.276201522816;8517.95741021291;4720.433547974265;5849.678267695147;5880.676562788916;5926.881462370815;6008.218931538872;10047.748806279902;10132.746681333025;10329.845052324072;10339.534720937727;10477.895221047693;10564.841527376942;10566.788664226826;10589.046985771318;10593.682189534326;10612.416317102325;7027.824304392431;7126.35862772353;7128.251533589106;7222.6388680566815;7236.565806051653;8434.746957591282;8523.82952340962;8537.317014638938;8537.53043582609;8545.74362769118;7412.295831895961;7628.618569071636;7640.73257435987;7738.92261077382;7857.096433910174;7361;7483.214262502359;7484.962575187089;7502.212267711811;7531.924680753342;6999.370056694922;7149.213586505376;7153.60655163971;7159.391451726661;7159.64201789898;4936.160852654985;5007.349044624176;5051.090803655377;5051.545360917535;5243.370795504564;4798.47216806144;4991.3511243538105;5107.438181799925;5145.948540514547;5299.814506492255 19:35:04 INFO - PROCESS | 1639 | |4;prototype.html;273.92877851758544;302.64741576063886;305.9449299126157;312.4111050669487;313.07642454758457;5134.922976155361;5141.280220769096;5159.380874295085;5174.818881339153;5214.895702085965;5103.234514822779;5127.179651255799;5132.358455193101;5142.5801841775265;5158.587313014959;3211.8715251389917;3728.3289007978487;3858.59484754101;3875.883723488286;3918.549366822809;3542.8051457169845;3881.340172170738;3892.8053597320163;3904.394818803076;3913.0413048803084;2903.3902880395035;3738.8691395801015;3809.047738065484;3853.9401664542197;3880.185161116169;3221.21080335317;3421.887886436893;3423.832954517087;3665.4318580619915;3710.628937106295;3343.7157841583494;3351.793354392417;3353.0779035765117;3360.1935535471557;3366.663333666638;4910.705357678515;4921.926171107436;4960.603151747851;4962.106820772259;4964.801407943678;3391.7795343302855;4238.554951730055;4390.53899340571;4395.164918665443;4406.581374769392;5191.5846732261325;5213;5239.266502689627;5246.108161612497;5264.868378290574;3178.8410579470933;3346.464565669481;3568;3617.927641447183;3622.4385220290897;4841.830535931261;5207.776172599436;5409.675419474844;5524.447555244444;5613.971930140364;3099.411111888734;3670.4310831821113;3698.1494256320657;3702.796346200931;3710.925781484409;3399.609044959812;3470.930581388358;3703.2593481303848;3768.453574231721;3770.679492243135;4187.350960601112;4362.432883725159;4407.801648925806;4419.337099435046;4426.535213802569;4920.852374428773;5308.548773354231;5335.653182543122;5342.519173274424;5357.4106848246665;1146.4267866066966;1164.2723297938787;1192.5527927027365;1264.6585421936027;1291.8126871603565;676.2392308652766;793.1909452358677;800.2397722163968;803.5540275147349;804.3605333759675;4782.880427989258;4820.6143508519235;4833.226683730586;4849.781759820817;4875;5002.57478114357;5467.371252305956;5474.863128421821;5505;5505.9174112387545;4740;4919.966806970505;4922.556969872657;4938.678985865908;4939;2799.692033876272;2850.9714902850706;2895;2895.5821888723153;2896.0877323642985;5412.377576578744;5527.136120214186;5672.517835983987;5682.943170568242;5716.5140963017775;5289.338832645919;5332.786688532416;5405.972970135124;5455.399906010336;5529.364123125812;5552.639078459887;5593.8321850344555;5605.299337582802;5611.905678392674;5622.184783206494;5214;5403.702796346239;5442.163274891113;5456.117639414396;5464.26232458613;5068.974655126701;5388.73056347189;5409.917181885081;5455;5462.558588089478;4949.703017818798;5623.971880140573;5629.437056294338;5633.774649013993;5755.7409916553015;3586.928261434809;3687.446882967576;3688.6680198782237;3692.4276737105793;3710.981445092865;4476.820927163008;4752.42970843501;5313.256144139901;5522.861928451821;5559.305086864142;4506;5400.650716937987;5420.915816836569;5423;5498.9002199559445;5174.403789366354;5366.570674346121;5401.972990135024;5404.675719456845;5425.538829199562;5233.476652334736;5264.684118952875;5278.419373868948;5289.8677533060445;5297.920531192112;5092.490750924878;5212.471951103787;5221.37343518765;5402.000629883484;5433;5252.369715634149;5257.63196576236;5260.39505456862;5290.81482148123;5403.729813509387 19:35:04 INFO - PROCESS | 1639 | |5;yui.html;712.9536580122292;751.2187325181811;761.8628646843569;790.3637571754736;803.5660743198673;12551.497940082385;16246.512604621865;16254.43109491168;16273.83726162741;16288.26702798374;14486.927565362184;14586.927065364685;14587.197704126269;14618;15014.399424023053;681.0329332348077;706.3184027413545;840.2479780596362;850.5917159763318;870.7257213977576;755.3051192902516;818.7881934736594;857.4169564696004;860.096898256831;863.6027427383411;904.077840602585;905.0768216419249;905.6875377994614;905.7735566108473;906.8050369170627;724.2467833453202;901.5312037740339;902.3999040638;904.9095090490964;905.3119629081913;554.3015800091873;554.8723793527512;557.4453418848232;648.8540078482351;668.886289330815;14692.926535367362;14760.409583616642;14765.557033289018;14773.92613036928;14776.778348324784;13519.66200844977;13987.37056832445;14058.578242652737;14093.577192684235;14100.506482273071;12832.935835320857;12936.611901642966;12946.417411216518;13115.86884131156;13198.406071726795;4552.817887284505;4597.954020459786;4668.929966050512;4684.648651351128;4695.389599352096;106776.4661176697;108446;108721;108865.45567272192;109024;10820.891791082146;10924.2899211551;11165.553377864957;11264.887351126548;11497.310161390344;21467;21576.56846863054;21664.783352166593;21702.348929532138;21709.67435488469;7747.883781743279;7756.1442706671105;7849.529028258323;7912.841743165104;8075.5558444286125;4316.460442444694;4361;4377.382528291718;4392.780301219228;4585.472670642852;3405.8126803025575;3513.631068737797;3539.2213712983103;3540.097275194809;3559;652.8628987912497;668.204836244867;671.2750229751856;671.8689855478135;675.2909445082644;516.8656149401107;552.6324993879106;589.3460544623267;593.3147214966721;618.9876202475925;8609.311255099577;8626.827463450696;8776.912230877737;9146.954265228764;9205.99766557927;561.9241402410704;594.3729365519418;594.5897330841706;604.5556515960766;627.6108812536256;4290.141971605692;4450.35469856869;4458.888527786767;4965.925511117336;5012.181353615829;140690.88963665554;144779;146279;148433.51566484413;148733.25633371764;100869;126263;128257;128951;130255.34872325578;86864;106646.46676766567;108328.45835770929;108915;113822;9088;9633.710988670351;9780.413175209225;9783.755406114904;9955.751106222402;51394.48605513897;51397.74301128471;55729.721351394604;56334.7183264081;60877.39122608717;82849.58575207085;85372.14627853641;87803;88299.11700883166;88762;12448.62654120378;12775.87224127784;12869.806952895528;13147.14543554666;13345.466181353026;14763.778543322074;15127.319270632628;15151.242437877841;15465.922670386575;15742;12249.755004899675;13585.660358491117;13959.232242226775;14259.64350891236;14663.193524356262;13743.381547830171;13758.93120534391;14126.78809817874;14128.717425651224;14395.424183032981;11006.504707288032;13352.132111412728;13465.259410732111;13815;13879.236641984788;10860.565577376816;11765.76468470643;11799.292042477478;11804.2327248732;12051.939740301243;11295.491702873225;11565.248258863146;11576.594819181626;11606.187566870576;11681.88318116842 19:35:04 INFO - PROCESS | 1639 | __end_tp_report 19:35:04 INFO - PROCESS | 1639 | __start_cc_report 19:35:04 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,1820 19:35:04 INFO - PROCESS | 1639 | __end_cc_report 19:35:04 INFO - PROCESS | 1639 | __startTimestamp1472697304553__endTimestamp 19:35:04 INFO - PROCESS | 1639 | 19:35:04 INFO - PROCESS | 1639 | ------- Summary: start ------- 19:35:04 INFO - PROCESS | 1639 | Number of tests: 6 19:35:04 INFO - PROCESS | 1639 | 19:35:04 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5759.93 Median:5947.76 stddev:641.51 (10.8%) stddev-sans-first:600.62 19:35:04 INFO - PROCESS | 1639 | Values: 2820.8 3342.1 3376.4 3377.1 3447.1 5667.6 5707.3 5782.0 5807.8 5822.7 5757.5 5787.6 5797.0 5813.9 5817.4 5246.5 5261.5 5320.3 5324.2 5370.1 5325.0 5341.7 5346.7 5348.8 5352.1 5281.2 5305.2 5320.6 5340.6 5357.7 4485.6 5394.4 5396.3 5405.5 5413.5 5123.1 5143.0 5170.2 5296.4 5318.1 5696.1 5711.2 5733.7 5738.7 5812.8 6236.2 6242.7 6249.7 6256.0 6257.1 5173.5 6241.5 6272.3 6319.0 6326.5 5815.4 5821.2 5833.7 5863.9 5884.3 6248.7 6266.4 6280.9 6281.1 6283.3 6016.1 6051.8 6052.0 6181.4 6205.9 6196.4 6245.2 6275.7 6290.7 6294.7 6229.8 6230.7 6232.2 6252.5 6255.8 6114.4 6134.7 6241.1 6257.6 6264.7 4974.0 5018.9 5022.6 5026.3 5074.7 4448.8 4473.6 4474.1 4477.8 4503.1 5650.2 5697.7 5755.1 5777.0 5786.8 5253.4 6229.3 6235.8 6267.8 6292.9 5745.0 5752.7 5762.0 5762.2 5810.8 5400.5 5411.1 5429.9 5441.5 5445.3 6206.0 6245.2 6248.2 6257.7 6303.9 5632.7 6264.9 6300.0 6315.9 6317.8 6273.0 6280.7 6284.4 6287.4 6297.9 6142.9 6208.9 6211.3 6223.2 6265.4 5319.5 6094.6 6133.5 6222.1 6279.7 6193.7 6195.8 6202.1 6205.5 6223.4 6074.7 6133.2 6218.6 6219.4 6220.1 5874.6 5943.1 5975.2 5980.0 6068.1 6121.7 6160.6 6161.8 6166.2 6182.3 5895.8 5947.8 5964.6 6018.2 6043.4 19:35:04 INFO - PROCESS | 1639 | 19:35:04 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:69570.58 Median:38274.83 stddev:149820.54 (391.4%) stddev-sans-first:150151.00 19:35:04 INFO - PROCESS | 1639 | Values: 471.8 476.6 518.3 533.6 545.9 29025.4 40441.6 41476.4 41855.8 43457.8 42955.1 43299.1 43365.8 43385.3 43462.0 8000.9 9752.5 9952.4 9953.4 9957.0 8719.4 11962.2 12205.6 12232.0 12462.9 10347.6 12134.3 12213.2 12291.6 12325.9 12075.2 12136.7 12147.8 12163.9 12189.2 11967.9 12048.5 12052.4 12053.4 12059.0 40367.6 40575.6 40675.8 40763.8 40775.8 3497.9 3521.7 3555.8 3777.9 3947.6 30498.8 31577.7 31728.7 31763.8 31871.2 3187.9 3248.5 3324.0 3393.3 3394.1 501281.0 501694.0 502713.0 503276.0 517181.4 59735.7 59781.7 59884.8 60355.4 60401.7 63609.7 63711.7 63787.4 63832.0 63940.4 13834.2 16071.1 16305.9 16307.2 16352.8 11428.5 11663.5 12670.0 12746.6 12749.3 13650.6 17164.8 17226.5 17444.4 17560.4 6369.9 6382.1 6730.7 6786.5 6813.3 850.9 930.9 964.7 966.2 982.9 25814.5 27820.3 29547.0 29887.3 30769.5 1036.5 1046.5 1084.4 1120.9 1122.8 14202.7 15022.6 15591.6 15885.8 15933.9 648731.0 654963.0 657172.0 657344.0 661662.0 58051.4 58268.5 58277.7 58404.4 58436.0 1231.2 1347.6 1358.3 1384.3 1390.3 30215.8 30867.7 31021.4 31434.9 31844.9 49987.5 50415.0 50478.7 50614.0 51703.7 508012.5 508233.0 509478.0 509974.0 511878.0 25889.5 26402.0 26501.3 27408.9 40014.6 46699.8 46968.5 47136.0 47870.8 48057.8 38558.9 38876.8 39024.4 40267.4 40346.0 36591.7 38758.8 38848.4 39458.4 40391.8 37400.8 38015.4 38534.2 38605.0 38695.0 32857.2 33317.5 33988.5 34884.0 34896.7 22567.5 25121.7 25574.2 25727.7 26117.8 19:35:04 INFO - PROCESS | 1639 | 19:35:04 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:34501.85 Median:2274.53 stddev:96858.44 (4258.4%) stddev-sans-first:97096.20 19:35:04 INFO - PROCESS | 1639 | Values: 348.6 367.2 369.3 377.9 398.7 15416.5 16383.9 19474.7 20629.9 20721.3 19096.4 20507.8 21156.0 21500.0 21506.6 5182.8 5899.4 6010.2 6089.1 6100.4 5987.7 6055.5 6058.9 6062.7 6062.7 5095.4 5684.7 5967.1 5991.5 6022.6 5981.7 6034.3 6037.7 6352.9 6578.4 5609.2 5839.1 5979.1 5987.1 5990.1 13063.8 14823.9 19994.3 26093.1 26486.5 2210.8 2254.5 2294.6 2594.6 2701.3 9539.9 10061.6 10071.6 10177.5 10187.3 1709.7 1792.2 1807.5 1874.7 1878.1 112769.0 114825.0 117103.8 118466.0 121851.4 8865.8 11000.5 11415.0 11430.5 11560.5 14275.6 14276.1 14280.6 14297.4 14306.6 7499.7 8150.7 8310.1 8532.9 8634.3 8274.1 8302.4 8442.7 8471.0 8650.6 4353.1 4442.0 4447.1 4525.7 4542.1 1640.9 1699.9 1790.0 1882.1 1931.8 824.3 856.3 868.5 872.6 895.0 19864.8 20604.7 20668.7 20792.7 20824.2 891.3 906.9 936.3 974.9 996.3 10574.7 11080.3 11084.3 11107.8 11162.1 567236.0 570289.0 574602.9 586580.0 592215.0 118161.8 127332.0 131332.0 134124.0 136955.6 968.7 1004.1 1017.4 1017.7 1024.0 18313.4 18711.0 19327.0 19403.9 19624.6 53879.7 55565.2 56722.4 56811.1 58113.4 81370.4 81991.0 82596.3 85193.0 85896.0 7749.9 7809.2 7980.3 8088.2 8106.3 11224.8 12661.6 12878.9 13167.7 13199.5 10930.1 10995.7 11063.4 11148.3 11173.5 10287.7 10900.0 10970.3 11136.8 11445.7 11289.9 11360.0 11375.7 11542.2 11674.7 7762.4 7961.6 7965.8 8062.2 8298.3 8178.1 10554.8 10620.9 11339.0 11484.3 19:35:04 INFO - PROCESS | 1639 | 19:35:04 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:5724.51 Median:4878.09 stddev:3268.02 (67.0%) stddev-sans-first:3260.73 19:35:04 INFO - PROCESS | 1639 | Values: 1360.6 1397.8 1494.6 1693.0 1702.3 8020.4 8505.0 8515.4 8521.9 8854.3 8820.3 9015.4 9030.3 9098.1 9112.5 2405.3 2438.3 2438.8 2439.8 2442.9 2095.3 2375.9 2411.5 2423.6 2433.8 2377.3 2396.6 2413.3 2416.1 2416.7 2347.7 2375.9 2378.6 2384.4 2400.3 2335.1 2365.3 2393.8 2395.6 2401.1 8866.3 8992.3 8995.5 8999.4 9005.1 5182.9 5237.7 5346.6 5347.7 5353.9 3567.8 3645.7 3646.6 3651.2 3653.2 963.7 1071.8 1083.8 1097.2 1104.8 11787.9 11829.8 11831.2 11876.3 11879.4 4445.5 4606.8 4698.5 4892.0 4939.8 9268.8 9345.2 9345.3 9353.3 9357.4 5301.3 5302.9 5305.5 5305.5 5314.5 4163.9 4435.0 4561.6 4864.1 4981.5 3754.4 3765.7 3802.3 3840.4 3876.9 1159.9 1165.0 1166.4 1176.8 1184.9 812.0 870.7 875.5 886.9 892.5 5679.7 5800.5 5867.8 5895.0 6091.1 444.0 448.8 454.2 462.2 463.2 3363.9 3717.2 3959.2 4050.9 4129.9 9342.0 10582.0 11052.9 11079.2 11117.7 9930.0 10751.9 11055.7 11055.8 11075.6 8143.2 8309.6 8318.6 8515.3 8518.0 4720.4 5849.7 5880.7 5926.9 6008.2 10047.7 10132.7 10329.8 10339.5 10477.9 10564.8 10566.8 10589.0 10593.7 10612.4 7027.8 7126.4 7128.3 7222.6 7236.6 8434.7 8523.8 8537.3 8537.5 8545.7 7412.3 7628.6 7640.7 7738.9 7857.1 7361.0 7483.2 7485.0 7502.2 7531.9 6999.4 7149.2 7153.6 7159.4 7159.6 4936.2 5007.3 5051.1 5051.5 5243.4 4798.5 4991.4 5107.4 5145.9 5299.8 19:35:04 INFO - PROCESS | 1639 | 19:35:04 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4351.36 Median:4983.69 stddev:1359.78 (27.3%) stddev-sans-first:1328.71 19:35:04 INFO - PROCESS | 1639 | Values: 273.9 302.6 305.9 312.4 313.1 5134.9 5141.3 5159.4 5174.8 5214.9 5103.2 5127.2 5132.4 5142.6 5158.6 3211.9 3728.3 3858.6 3875.9 3918.5 3542.8 3881.3 3892.8 3904.4 3913.0 2903.4 3738.9 3809.0 3853.9 3880.2 3221.2 3421.9 3423.8 3665.4 3710.6 3343.7 3351.8 3353.1 3360.2 3366.7 4910.7 4921.9 4960.6 4962.1 4964.8 3391.8 4238.6 4390.5 4395.2 4406.6 5191.6 5213.0 5239.3 5246.1 5264.9 3178.8 3346.5 3568.0 3617.9 3622.4 4841.8 5207.8 5409.7 5524.4 5614.0 3099.4 3670.4 3698.1 3702.8 3710.9 3399.6 3470.9 3703.3 3768.5 3770.7 4187.4 4362.4 4407.8 4419.3 4426.5 4920.9 5308.5 5335.7 5342.5 5357.4 1146.4 1164.3 1192.6 1264.7 1291.8 676.2 793.2 800.2 803.6 804.4 4782.9 4820.6 4833.2 4849.8 4875.0 5002.6 5467.4 5474.9 5505.0 5505.9 4740.0 4920.0 4922.6 4938.7 4939.0 2799.7 2851.0 2895.0 2895.6 2896.1 5412.4 5527.1 5672.5 5682.9 5716.5 5289.3 5332.8 5406.0 5455.4 5529.4 5552.6 5593.8 5605.3 5611.9 5622.2 5214.0 5403.7 5442.2 5456.1 5464.3 5069.0 5388.7 5409.9 5455.0 5462.6 4949.7 5624.0 5629.4 5633.8 5755.7 3586.9 3687.4 3688.7 3692.4 3711.0 4476.8 4752.4 5313.3 5522.9 5559.3 4506.0 5400.7 5420.9 5423.0 5498.9 5174.4 5366.6 5402.0 5404.7 5425.5 5233.5 5264.7 5278.4 5289.9 5297.9 5092.5 5212.5 5221.4 5402.0 5433.0 5252.4 5257.6 5260.4 5290.8 5403.7 19:35:04 INFO - PROCESS | 1639 | 19:35:04 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:24189.55 Median:3526.43 stddev:38112.46 (1080.8%) stddev-sans-first:38178.61 19:35:04 INFO - PROCESS | 1639 | Values: 713.0 751.2 761.9 790.4 803.6 12551.5 16246.5 16254.4 16273.8 16288.3 14486.9 14586.9 14587.2 14618.0 15014.4 681.0 706.3 840.2 850.6 870.7 755.3 818.8 857.4 860.1 863.6 904.1 905.1 905.7 905.8 906.8 724.2 901.5 902.4 904.9 905.3 554.3 554.9 557.4 648.9 668.9 14692.9 14760.4 14765.6 14773.9 14776.8 13519.7 13987.4 14058.6 14093.6 14100.5 12832.9 12936.6 12946.4 13115.9 13198.4 4552.8 4598.0 4668.9 4684.6 4695.4 106776.5 108446.0 108721.0 108865.5 109024.0 10820.9 10924.3 11165.6 11264.9 11497.3 21467.0 21576.6 21664.8 21702.3 21709.7 7747.9 7756.1 7849.5 7912.8 8075.6 4316.5 4361.0 4377.4 4392.8 4585.5 3405.8 3513.6 3539.2 3540.1 3559.0 652.9 668.2 671.3 671.9 675.3 516.9 552.6 589.3 593.3 619.0 8609.3 8626.8 8776.9 9147.0 9206.0 561.9 594.4 594.6 604.6 627.6 4290.1 4450.4 4458.9 4965.9 5012.2 140690.9 144779.0 146279.0 148433.5 148733.3 100869.0 126263.0 128257.0 128951.0 130255.3 86864.0 106646.5 108328.5 108915.0 113822.0 9088.0 9633.7 9780.4 9783.8 9955.8 51394.5 51397.7 55729.7 56334.7 60877.4 82849.6 85372.1 87803.0 88299.1 88762.0 12448.6 12775.9 12869.8 13147.1 13345.5 14763.8 15127.3 15151.2 15465.9 15742.0 12249.8 13585.7 13959.2 14259.6 14663.2 13743.4 13758.9 14126.8 14128.7 14395.4 11006.5 13352.1 13465.3 13815.0 13879.2 10860.6 11765.8 11799.3 11804.2 12051.9 11295.5 11565.2 11576.6 11606.2 11681.9 19:35:04 INFO - PROCESS | 1639 | -------- Summary: end -------- 19:35:04 INFO - PROCESS | 1639 | 19:35:05 INFO - TEST-INFO | 1639: exit 0 19:35:05 INFO - TEST-OK | dromaeo_css | took 1118092ms 19:35:05 INFO - TEST-START | kraken 19:35:05 INFO - operating with platform_type : mac_ 19:35:05 INFO - Initialising browser for kraken test... 19:35:05 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/tmpkIBC__/profile http://localhost:49274/getInfo.html) 19:35:07 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 19:35:07 INFO - PROCESS | 1649 | colorDepth:24 19:35:07 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 19:35:07 INFO - PROCESS | 1649 | __metrics 19:35:07 INFO - PROCESS | 1649 | 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" 19:35:07 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:35:08 INFO - TEST-INFO | 1649: exit 0 19:35:08 INFO - Browser initialized. 19:35:08 INFO - Running cycle 1/1 for kraken test... 19:35:08 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/tmpkIBC__/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:35:20 INFO - PROCESS | 1650 | RSS: Main: 234430464 19:35:20 INFO - PROCESS | 1650 | 19:35:22 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 19:35:23 INFO - PROCESS | 1650 | RSS: Main: 292454400 19:35:23 INFO - PROCESS | 1650 | 19:35:26 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 19:35:26 INFO - PROCESS | 1650 | RSS: Main: 288296960 19:35:26 INFO - PROCESS | 1650 | 19:35:30 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 19:35:31 INFO - PROCESS | 1650 | RSS: Main: 377749504 19:35:31 INFO - PROCESS | 1650 | 19:35:33 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 19:35:34 INFO - PROCESS | 1650 | RSS: Main: 277815296 19:35:34 INFO - PROCESS | 1650 | 19:35:36 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:35:36 INFO - PROCESS | 1650 | RSS: Main: 281616384 19:35:36 INFO - PROCESS | 1650 | 19:35:39 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 19:35:40 INFO - PROCESS | 1650 | RSS: Main: 320647168 19:35:40 INFO - PROCESS | 1650 | 19:35:43 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 19:35:44 INFO - PROCESS | 1650 | RSS: Main: 321376256 19:35:44 INFO - PROCESS | 1650 | 19:35:46 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 19:35:47 INFO - PROCESS | 1650 | RSS: Main: 319037440 19:35:47 INFO - PROCESS | 1650 | 19:35:48 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:35:49 INFO - PROCESS | 1650 | RSS: Main: 297762816 19:35:49 INFO - PROCESS | 1650 | 19:35:51 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:35:52 INFO - PROCESS | 1650 | RSS: Main: 303640576 19:35:52 INFO - PROCESS | 1650 | 19:35:54 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:35:55 INFO - PROCESS | 1650 | RSS: Main: 309460992 19:35:55 INFO - PROCESS | 1650 | 19:35:57 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:35:58 INFO - PROCESS | 1650 | RSS: Main: 323059712 19:35:58 INFO - PROCESS | 1650 | 19:36:01 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:36:02 INFO - PROCESS | 1650 | RSS: Main: 319229952 19:36:02 INFO - PROCESS | 1650 | 19:36:04 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 19:36:04 INFO - PROCESS | 1650 | RSS: Main: 362782720 19:36:04 INFO - PROCESS | 1650 | 19:36:04 INFO - PROCESS | 1650 | __start_tp_report 19:36:04 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 19:36:04 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 19:36:04 INFO - PROCESS | 1650 | |i|pagename|runs| 19:36:04 INFO - PROCESS | 1650 | |0;ai-astar;114;114;134;113;114;113;113;114;112;116 19:36:04 INFO - PROCESS | 1650 | |1;audio-beat-detection;147;110;129;163;107;131;131;108;161;108 19:36:04 INFO - PROCESS | 1650 | |2;audio-dft;198;260;191;156;201;262;196;193;263;183 19:36:04 INFO - PROCESS | 1650 | |3;audio-fft;76;75;75;155;75;82;82;98;142;64 19:36:04 INFO - PROCESS | 1650 | |4;audio-oscillator;81;83;134;70;69;78;118;69;69;117 19:36:04 INFO - PROCESS | 1650 | |5;imaging-gaussian-blur;87;129;134;82;120;130;131;87;89;86 19:36:04 INFO - PROCESS | 1650 | |6;imaging-darkroom;96;96;96;96;96;96;95;96;97;97 19:36:04 INFO - PROCESS | 1650 | |7;imaging-desaturate;86;83;87;87;83;85;84;82;83;84 19:36:04 INFO - PROCESS | 1650 | |8;json-parse-financial;52;53;53;55;52;53;54;52;53;51 19:36:04 INFO - PROCESS | 1650 | |9;json-stringify-tinderbox;66;67;80;77;75;72;71;71;70;72 19:36:04 INFO - PROCESS | 1650 | |10;stanford-crypto-aes;86;86;87;88;88;96;103;104;105;91 19:36:04 INFO - PROCESS | 1650 | |11;stanford-crypto-ccm;140;138;140;148;145;153;149;139;179;140 19:36:04 INFO - PROCESS | 1650 | |12;stanford-crypto-pbkdf2;177;179;178;178;244;218;178;175;177;175 19:36:04 INFO - PROCESS | 1650 | |13;stanford-crypto-sha256-iterative;69;67;70;69;68;69;68;68;68;68 19:36:04 INFO - PROCESS | 1650 | __end_tp_report 19:36:04 INFO - PROCESS | 1650 | __start_cc_report 19:36:04 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,2062 19:36:04 INFO - PROCESS | 1650 | __end_cc_report 19:36:04 INFO - PROCESS | 1650 | __startTimestamp1472697364281__endTimestamp 19:36:04 INFO - PROCESS | 1650 | 19:36:04 INFO - PROCESS | 1650 | ------- Summary: start ------- 19:36:04 INFO - PROCESS | 1650 | Number of tests: 14 19:36:04 INFO - PROCESS | 1650 | 19:36:04 INFO - PROCESS | 1650 | [#0] ai-astar Cycles:10 Average:115.70 Median:114.00 stddev:6.52 (5.7%) stddev-sans-first:6.88 19:36:04 INFO - PROCESS | 1650 | Values: 114.0 114.0 134.0 113.0 114.0 113.0 113.0 114.0 112.0 116.0 19:36:04 INFO - PROCESS | 1650 | 19:36:04 INFO - PROCESS | 1650 | [#1] audio-beat-detection Cycles:10 Average:129.50 Median:131.00 stddev:21.70 (16.6%) stddev-sans-first:22.07 19:36:04 INFO - PROCESS | 1650 | Values: 147.0 110.0 129.0 163.0 107.0 131.0 131.0 108.0 161.0 108.0 19:36:04 INFO - PROCESS | 1650 | 19:36:04 INFO - PROCESS | 1650 | [#2] audio-dft Cycles:10 Average:210.30 Median:199.50 stddev:37.61 (18.9%) stddev-sans-first:39.62 19:36:04 INFO - PROCESS | 1650 | Values: 198.0 260.0 191.0 156.0 201.0 262.0 196.0 193.0 263.0 183.0 19:36:04 INFO - PROCESS | 1650 | 19:36:04 INFO - PROCESS | 1650 | [#3] audio-fft Cycles:10 Average:92.40 Median:75.50 stddev:30.93 (41.0%) stddev-sans-first:32.23 19:36:04 INFO - PROCESS | 1650 | Values: 76.0 75.0 75.0 155.0 75.0 82.0 82.0 98.0 142.0 64.0 19:36:04 INFO - PROCESS | 1650 | 19:36:04 INFO - PROCESS | 1650 | [#4] audio-oscillator Cycles:10 Average:88.80 Median:69.50 stddev:24.57 (35.3%) stddev-sans-first:25.89 19:36:04 INFO - PROCESS | 1650 | Values: 81.0 83.0 134.0 70.0 69.0 78.0 118.0 69.0 69.0 117.0 19:36:04 INFO - PROCESS | 1650 | 19:36:04 INFO - PROCESS | 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:107.50 Median:84.00 stddev:22.79 (27.1%) stddev-sans-first:22.93 19:36:04 INFO - PROCESS | 1650 | Values: 87.0 129.0 134.0 82.0 120.0 130.0 131.0 87.0 89.0 86.0 19:36:04 INFO - PROCESS | 1650 | 19:36:04 INFO - PROCESS | 1650 | [#6] imaging-darkroom Cycles:10 Average:96.10 Median:96.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 19:36:04 INFO - PROCESS | 1650 | Values: 96.0 96.0 96.0 96.0 96.0 96.0 95.0 96.0 97.0 97.0 19:36:04 INFO - PROCESS | 1650 | 19:36:04 INFO - PROCESS | 1650 | [#7] imaging-desaturate Cycles:10 Average:84.40 Median:84.50 stddev:1.78 (2.1%) stddev-sans-first:1.79 19:36:04 INFO - PROCESS | 1650 | Values: 86.0 83.0 87.0 87.0 83.0 85.0 84.0 82.0 83.0 84.0 19:36:04 INFO - PROCESS | 1650 | 19:36:04 INFO - PROCESS | 1650 | [#8] json-parse-financial Cycles:10 Average:52.80 Median:53.00 stddev:1.14 (2.1%) stddev-sans-first:1.17 19:36:04 INFO - PROCESS | 1650 | Values: 52.0 53.0 53.0 55.0 52.0 53.0 54.0 52.0 53.0 51.0 19:36:04 INFO - PROCESS | 1650 | 19:36:04 INFO - PROCESS | 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:72.10 Median:72.00 stddev:4.28 (5.9%) stddev-sans-first:3.93 19:36:04 INFO - PROCESS | 1650 | Values: 66.0 67.0 80.0 77.0 75.0 72.0 71.0 71.0 70.0 72.0 19:36:04 INFO - PROCESS | 1650 | 19:36:04 INFO - PROCESS | 1650 | [#10] stanford-crypto-aes Cycles:10 Average:93.40 Median:87.50 stddev:7.89 (9.0%) stddev-sans-first:7.90 19:36:04 INFO - PROCESS | 1650 | Values: 86.0 86.0 87.0 88.0 88.0 96.0 103.0 104.0 105.0 91.0 19:36:04 INFO - PROCESS | 1650 | 19:36:04 INFO - PROCESS | 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:147.10 Median:146.50 stddev:12.30 (8.4%) stddev-sans-first:12.77 19:36:04 INFO - PROCESS | 1650 | Values: 140.0 138.0 140.0 148.0 145.0 153.0 149.0 139.0 179.0 140.0 19:36:04 INFO - PROCESS | 1650 | 19:36:04 INFO - PROCESS | 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.90 Median:178.00 stddev:23.56 (13.2%) stddev-sans-first:24.66 19:36:04 INFO - PROCESS | 1650 | Values: 177.0 179.0 178.0 178.0 244.0 218.0 178.0 175.0 177.0 175.0 19:36:04 INFO - PROCESS | 1650 | 19:36:04 INFO - PROCESS | 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:68.50 stddev:0.84 (1.2%) stddev-sans-first:0.87 19:36:04 INFO - PROCESS | 1650 | Values: 69.0 67.0 70.0 69.0 68.0 69.0 68.0 68.0 68.0 68.0 19:36:04 INFO - PROCESS | 1650 | -------- Summary: end -------- 19:36:04 INFO - PROCESS | 1650 | 19:36:05 INFO - TEST-INFO | 1650: exit 0 19:36:05 INFO - TEST-OK | kraken | took 60006ms 19:36:05 INFO - SUITE-END | took 1178s 19:36:05 INFO - Completed test suite (00:19:38) 19:36:05 INFO - javascript benchmark 19:36:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2820.760235379993, 3342.064222017836, 3376.3753705564473, 3377.0881861897305, 3447.052060683312, 5667.631603945741, 5707.286589176353, 5782.017057100293, 5807.8257652270395, 5822.7088645567765, 5757.481826635602, 5787.594868359217, 5797.043487824504, 5813.854653633662, 5817.360090390053, 5246.475352464751, 5261.473852614727, 5320.308359913206, 5324.227986941891, 5370.060239458098, 5325.041492531343, 5341.65279256849, 5346.705931173784, 5348.759305831228, 5352.090144675395, 5281.2342210379475, 5305.230741542474, 5320.600954928376, 5340.599455040868, 5357.678539287655, 4485.573870482299, 5394.433584473652, 5396.298481197458, 5405.5135037846385, 5413.485718856702, 5123.077845987721, 5142.974285128588, 5170.224466330043, 5296.390915044781, 5318.095923692981, 5696.060150075233, 5711.228984087136, 5733.684647344395, 5738.684372359518, 5812.82561523155, 6236.18929539157, 6242.687865606701, 6249.7187626556915, 6255.968720156416, 6257.092721555349, 5173.456787037345, 6241.469475094623, 6272.3100458949475, 6318.968405157945, 6326.462250708695, 5815.41845815415, 5821.155932389822, 5833.7083145842535, 5863.941360586382, 5884.293884733774, 6248.718807653667, 6266.436020758154, 6280.874382512324, 6281.120643109968, 6283.3402492737605, 6016.127031385684, 6051.8487037823525, 6052.0376020041, 6181.443670069715, 6205.906911396332, 6196.380361963767, 6245.156903817927, 6275.717592708248, 6290.685465726695, 6294.685265736694, 6229.813105606839, 6230.6884655767935, 6232.189815323979, 6252.468540174045, 6255.78104766331, 6114.388610881752, 6134.693265336716, 6241.063840423882, 6257.561970662101, 6264.686765661699, 4973.975130124399, 5018.899622007612, 5022.573081288131, 5026.321446604735, 5074.695518268915, 4448.755318457451, 4473.597376236155, 4474.105178964221, 4477.8432754854075, 4503.121891231179, 5650.23721797552, 5697.743601537941, 5755.079187330007, 5777.0, 5786.768529258867, 5253.44838791929, 6229.283632382243, 6235.750569977241, 6267.780627678009, 6292.905606415908, 5744.971275143597, 5752.683602401824, 5762.0, 5762.164486149485, 5810.767569297265, 5400.513953744104, 5411.053065713484, 5429.891402172014, 5441.5102640762525, 5445.319335083114, 6206.0, 6245.156903818108, 6248.218972628421, 6257.655828929453, 6303.936960630335, 5632.746526406239, 6264.937350626617, 6299.960506516373, 6315.873682526416, 6317.842053948688, 6273.0, 6280.717367718555, 6284.4344009039405, 6287.402696743802, 6297.905531416941, 6142.877142457215, 6208.937910621016, 6211.254649442096, 6223.222097237845, 6265.373462653881, 5319.468053194804, 6094.634321940698, 6133.487685122301, 6222.097795819672, 6279.748810047547, 6193.690315484298, 6195.752169913153, 6202.100695399052, 6205.5345849060595, 6223.439890409885, 6074.696265186812, 6133.172021777003, 6218.5647004709235, 6219.378062193744, 6220.129181914445, 5874.618149820248, 5943.108533719804, 5975.168132436606, 5980.0, 6068.059450785136, 6121.689788751648, 6160.599561028519, 6161.753529858755, 6166.167567378347, 6182.319944805978, 5895.793647222326, 5947.762089516371, 5964.5526585505395, 6018.21763170785, 6043.425874541911], "value": 5718.905148900774, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [471.84193295246104, 476.5782282679829, 518.3028826228722, 533.5518164741617, 545.8580769000059, 29025.41949161018, 40441.59558404415, 41476.377854332175, 41855.790721046425, 43457.7827110864, 42955.140897182035, 43299.13401731964, 43365.78317108418, 43385.34921976173, 43462.0, 8000.919990800105, 9752.512374381271, 9952.402855828637, 9953.35303205293, 9956.950215248913, 8719.389642725011, 11962.1626486146, 12205.57280495183, 12232.0, 12462.937685311561, 10347.586096556166, 12134.332611706353, 12213.206141600813, 12291.569795057174, 12325.938370308137, 12075.2151110178, 12136.696582585506, 12147.817782733222, 12163.93918030413, 12189.207701499374, 11967.86305300995, 12048.518059277618, 12052.397380130958, 12053.397330133546, 12059.035277177805, 40367.59632403668, 40575.59424405748, 40675.796621017, 40763.7961810192, 40775.79612101921, 3497.9475307870403, 3521.7358698097487, 3555.768875023141, 3777.905552361213, 3947.605239476058, 30498.84750576233, 31577.684223157703, 31728.682713172802, 31763.84118079395, 31871.203219919455, 3187.9203019924457, 3248.4640034394297, 3323.966760332414, 3393.2874096439778, 3394.117529442363, 501281.0, 501694.0, 502713.0, 503276.0, 517181.4140929309, 59735.701321493114, 59781.701091494266, 59884.80230395368, 60355.396446035855, 60401.69799150976, 63609.68195158995, 63711.6814415925, 63787.36212637814, 63832.0, 63940.36059639344, 13834.169949803045, 16071.107200703986, 16305.918470407572, 16307.184640768151, 16352.75470867938, 11428.542858285575, 11663.542057242845, 12670.0, 12746.617601471971, 12749.298788566532, 13650.590482285548, 17164.82835171632, 17226.483205503853, 17444.430001299945, 17560.38538651141, 6369.9171140906155, 6382.138411314506, 6730.663466826639, 6786.4570834333135, 6813.250542440328, 850.927671147947, 930.9255259579218, 964.7009427077628, 966.2415004221652, 982.9066238707311, 25814.483710325687, 27820.304492387448, 29546.96585619536, 29887.252818679706, 30769.538456923165, 1036.4765793274348, 1046.5186014433446, 1084.3927400655657, 1120.8679233973746, 1122.7586068995206, 14202.715945681028, 15022.624434389008, 15591.610209744847, 15885.761713574306, 15933.920330398274, 648731.0, 654963.0, 657172.0, 657344.0, 661662.0, 58051.4194858046, 58268.54328641818, 58277.70861145667, 58404.4159558399, 58436.0, 1231.205872212418, 1347.5620423362643, 1358.3004752552247, 1384.349355802771, 1390.2840037380756, 30215.848920755256, 30867.69132308738, 31021.379572407976, 31434.899778507643, 31844.885429009875, 49987.50012499828, 50415.0, 50478.747606261735, 50614.0, 51703.707407315116, 508012.459937698, 508233.0, 509478.0, 509974.0, 511878.0, 25889.482210355312, 26402.0, 26501.33746656349, 27408.86295568589, 40014.59985400225, 46699.76650116728, 46968.53031469778, 47136.0, 47870.76064619655, 48057.75971120122, 38558.879261640875, 38876.80561597174, 39024.41463378108, 40267.39598906077, 40346.0, 36591.71928982473, 38758.80620596879, 38848.41727374035, 39458.40812387759, 40391.788246352655, 37400.812995934844, 38015.42976855294, 38534.22931541409, 38605.0, 38695.03262418349, 32857.17857053593, 33317.50023749694, 33988.49017264693, 34883.9534813956, 34896.65103349036, 22567.548649027256, 25121.748782511942, 25574.23277301684, 25727.742722573283, 26117.772901535955], "value": 20373.44128471988, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.56429463171037, 367.2013370918253, 369.3481006024366, 377.91119087014545, 398.6571548468317, 15416.537503874873, 16383.853130280884, 19474.707879381822, 20629.896850515765, 20721.274755383547, 19096.427107186806, 20507.794922050776, 21156.0, 21500.032498537563, 21506.56986860262, 5182.792688292464, 5899.380565040663, 6010.158577799111, 6089.147519347295, 6100.389961003909, 5987.670678112702, 6055.515558755311, 6058.939410605882, 6062.666553339564, 6062.696865156747, 5095.363079615048, 5684.715764211794, 5967.104934259853, 5991.460768530831, 6022.608530445529, 5981.700914954256, 6034.30605480371, 6037.667928263943, 6352.936470635327, 6578.407943285079, 5609.242752228479, 5839.124131380285, 5979.1031345298115, 5987.072003839412, 5990.101484777275, 13063.804042939364, 14823.851761482356, 19994.30048445884, 26093.08674196413, 26486.47027059448, 2210.8452408331423, 2254.458929856839, 2294.609916314214, 2594.597837335216, 2701.257154282588, 9539.90460095397, 10061.647842325483, 10071.647492337806, 10177.491125443697, 10187.286889917708, 1709.6922553940285, 1792.2203841329062, 1807.5210069331638, 1874.7158196635348, 1878.1454438230571, 112769.0, 114825.0, 117103.82896171014, 118466.0, 121851.3907430466, 8865.778355541033, 11000.504977276041, 11415.0, 11430.485628146753, 11560.479778409992, 14275.643108922151, 14276.072055316372, 14280.571582852532, 14297.428102875769, 14306.57080287593, 7499.737509187153, 8150.736635821457, 8310.085890552034, 8532.914670853335, 8634.309255259564, 8274.089850116483, 8302.381035697981, 8442.704505342406, 8470.957645211858, 8650.567471626393, 4353.10761293934, 4441.977790111094, 4447.08834688888, 4525.660575456788, 4542.091581683677, 1640.8933419327705, 1699.9150042498072, 1789.997601343252, 1882.077781886893, 1931.758530183727, 824.3158178711655, 856.3235044314921, 868.5179725252419, 872.5689868615476, 895.0467751844265, 19864.841899011353, 20604.690929636068, 20668.689969650466, 20792.688109678365, 20824.1670333185, 891.3047822698305, 906.8549032154824, 936.2977766674994, 974.8635191073255, 996.3075662414698, 10574.682759517227, 11080.335179889233, 11084.334939903632, 11107.777844443066, 11162.107031437465, 567236.0, 570289.0, 574602.8888988865, 586580.0, 592215.0, 118161.81838181509, 127332.0, 131332.0, 134124.0, 136955.63044369628, 968.7481254873784, 1004.1013293102632, 1017.4200705597739, 1017.6896046705677, 1023.9641612543675, 18313.359032434335, 18711.0, 19327.03364831781, 19403.902980485007, 19624.607507849476, 53879.730601346746, 55565.166522501386, 56722.43277567172, 56811.14783278337, 58113.4188658108, 81370.372592549, 81991.0, 82596.348073037, 85193.0, 85896.0, 7749.922500775146, 7809.180036096128, 7980.284238888667, 8088.231617996516, 8106.270435660583, 11224.7755044897, 12661.620151395473, 12878.93560532229, 13167.670808229488, 13199.538016169388, 10930.08723473373, 10995.670129896116, 11063.446827658423, 11148.331100134017, 11173.49719262619, 10287.691369258935, 10900.018998290192, 10970.286931349438, 11136.832947505956, 11445.713857153638, 11289.887101128883, 11360.034397076011, 11375.658730238107, 11542.249753765978, 11674.70813229676, 7762.3790096793255, 7961.601919903866, 7965.800854978672, 8062.234087761653, 8298.294644955246, 8178.059523154675, 10554.841677375, 10620.89379106199, 11338.979491845443, 11484.253523520945], "value": 9460.736941153704, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1360.5578187089186, 1397.82527184102, 1494.5740463967768, 1693.0349700670622, 1702.276532473699, 8020.398470114736, 8504.999999999984, 8515.403921725481, 8521.91478085219, 8854.33592480565, 8820.250278726317, 9015.41399809012, 9030.277577793777, 9098.135677110664, 9112.453252804837, 2405.256189262779, 2438.2685194441683, 2438.8048956083558, 2439.817013723969, 2442.9389265268333, 2095.2561840546614, 2375.904963801446, 2411.5418070566625, 2423.587990041697, 2433.841800282976, 2377.2511658827502, 2396.628522579003, 2413.3122060212845, 2416.142269494331, 2416.6979127609047, 2347.718273807154, 2375.881205939696, 2378.5718570657273, 2384.4277373430427, 2400.3159099656687, 2335.1243283768586, 2365.266767302142, 2393.8324317297797, 2395.556821987938, 2401.135591187171, 8866.290696744245, 8992.325575581792, 8995.5052472114, 8999.415038022507, 9005.099490051009, 5182.896342073175, 5237.659552129099, 5346.625736198468, 5347.732613369316, 5353.919691204596, 3567.7680950738118, 3645.65366290202, 3646.6353364663323, 3651.233241019389, 3653.2328211075705, 963.734972882456, 1071.7802850415724, 1083.7615724540588, 1097.226455348977, 1104.8342748587695, 11787.94106029473, 11829.757875423129, 11831.171817972745, 11876.287422754576, 11879.40602969848, 4445.466544014739, 4606.769661516911, 4698.506656801055, 4892.021595680878, 4939.827106051271, 9268.814623707489, 9345.20565751905, 9345.299102567335, 9353.345265831327, 9357.391769535094, 5301.284326615934, 5302.8939421212135, 5305.495977882095, 5305.549028332635, 5314.5216930476445, 4163.937540936889, 4435.0, 4561.566651168134, 4864.148773964542, 4981.452040275567, 3754.4180651998986, 3765.7363984521367, 3802.315583194996, 3840.385538313856, 3876.8836934891997, 1159.8782127876452, 1164.9650510484698, 1166.393475392791, 1176.7822952753597, 1184.8518935133109, 811.9850187265918, 870.6865528409768, 875.4747151709051, 886.9068747781519, 892.5180402582532, 5679.744411501492, 5800.50695690871, 5867.823965281048, 5895.0, 6091.116788065706, 443.98106345936156, 448.78682625752504, 454.23234733301274, 462.20039331955235, 463.1963543252522, 3363.882264120744, 3717.1636381814415, 3959.208158368338, 4050.8784736457956, 4129.91740165201, 9342.0, 10582.047615714622, 11052.944735276273, 11079.169062320358, 11117.666470005914, 9929.950350248202, 10751.946240268904, 11055.72360690989, 11055.778884422267, 11075.556977720962, 8143.18568143181, 8309.626066826886, 8318.625661845232, 8515.276201522816, 8517.95741021291, 4720.433547974265, 5849.678267695147, 5880.676562788916, 5926.881462370815, 6008.218931538872, 10047.748806279902, 10132.746681333025, 10329.845052324072, 10339.534720937727, 10477.895221047693, 10564.841527376942, 10566.788664226826, 10589.046985771318, 10593.682189534326, 10612.416317102325, 7027.824304392431, 7126.35862772353, 7128.251533589106, 7222.6388680566815, 7236.565806051653, 8434.746957591282, 8523.82952340962, 8537.317014638938, 8537.53043582609, 8545.74362769118, 7412.295831895961, 7628.618569071636, 7640.73257435987, 7738.92261077382, 7857.096433910174, 7361.0, 7483.214262502359, 7484.962575187089, 7502.212267711811, 7531.924680753342, 6999.370056694922, 7149.213586505376, 7153.60655163971, 7159.391451726661, 7159.64201789898, 4936.160852654985, 5007.349044624176, 5051.090803655377, 5051.545360917535, 5243.370795504564, 4798.47216806144, 4991.3511243538105, 5107.438181799925, 5145.948540514547, 5299.814506492255], "value": 4496.008493275056, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [273.92877851758544, 302.64741576063886, 305.9449299126157, 312.4111050669487, 313.07642454758457, 5134.922976155361, 5141.280220769096, 5159.380874295085, 5174.818881339153, 5214.895702085965, 5103.234514822779, 5127.179651255799, 5132.358455193101, 5142.5801841775265, 5158.587313014959, 3211.8715251389917, 3728.3289007978487, 3858.59484754101, 3875.883723488286, 3918.549366822809, 3542.8051457169845, 3881.340172170738, 3892.8053597320163, 3904.394818803076, 3913.0413048803084, 2903.3902880395035, 3738.8691395801015, 3809.047738065484, 3853.9401664542197, 3880.185161116169, 3221.21080335317, 3421.887886436893, 3423.832954517087, 3665.4318580619915, 3710.628937106295, 3343.7157841583494, 3351.793354392417, 3353.0779035765117, 3360.1935535471557, 3366.663333666638, 4910.705357678515, 4921.926171107436, 4960.603151747851, 4962.106820772259, 4964.801407943678, 3391.7795343302855, 4238.554951730055, 4390.53899340571, 4395.164918665443, 4406.581374769392, 5191.5846732261325, 5213.0, 5239.266502689627, 5246.108161612497, 5264.868378290574, 3178.8410579470933, 3346.464565669481, 3568.0, 3617.927641447183, 3622.4385220290897, 4841.830535931261, 5207.776172599436, 5409.675419474844, 5524.447555244444, 5613.971930140364, 3099.411111888734, 3670.4310831821113, 3698.1494256320657, 3702.796346200931, 3710.925781484409, 3399.609044959812, 3470.930581388358, 3703.2593481303848, 3768.453574231721, 3770.679492243135, 4187.350960601112, 4362.432883725159, 4407.801648925806, 4419.337099435046, 4426.535213802569, 4920.852374428773, 5308.548773354231, 5335.653182543122, 5342.519173274424, 5357.4106848246665, 1146.4267866066966, 1164.2723297938787, 1192.5527927027365, 1264.6585421936027, 1291.8126871603565, 676.2392308652766, 793.1909452358677, 800.2397722163968, 803.5540275147349, 804.3605333759675, 4782.880427989258, 4820.6143508519235, 4833.226683730586, 4849.781759820817, 4875.0, 5002.57478114357, 5467.371252305956, 5474.863128421821, 5505.0, 5505.9174112387545, 4740.0, 4919.966806970505, 4922.556969872657, 4938.678985865908, 4939.0, 2799.692033876272, 2850.9714902850706, 2895.0, 2895.5821888723153, 2896.0877323642985, 5412.377576578744, 5527.136120214186, 5672.517835983987, 5682.943170568242, 5716.5140963017775, 5289.338832645919, 5332.786688532416, 5405.972970135124, 5455.399906010336, 5529.364123125812, 5552.639078459887, 5593.8321850344555, 5605.299337582802, 5611.905678392674, 5622.184783206494, 5214.0, 5403.702796346239, 5442.163274891113, 5456.117639414396, 5464.26232458613, 5068.974655126701, 5388.73056347189, 5409.917181885081, 5455.0, 5462.558588089478, 4949.703017818798, 5623.971880140573, 5629.437056294338, 5633.774649013993, 5755.7409916553015, 3586.928261434809, 3687.446882967576, 3688.6680198782237, 3692.4276737105793, 3710.981445092865, 4476.820927163008, 4752.42970843501, 5313.256144139901, 5522.861928451821, 5559.305086864142, 4506.0, 5400.650716937987, 5420.915816836569, 5423.0, 5498.9002199559445, 5174.403789366354, 5366.570674346121, 5401.972990135024, 5404.675719456845, 5425.538829199562, 5233.476652334736, 5264.684118952875, 5278.419373868948, 5289.8677533060445, 5297.920531192112, 5092.490750924878, 5212.471951103787, 5221.37343518765, 5402.000629883484, 5433.0, 5252.369715634149, 5257.63196576236, 5260.39505456862, 5290.81482148123, 5403.729813509387], "value": 3909.460046044038, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.9536580122292, 751.2187325181811, 761.8628646843569, 790.3637571754736, 803.5660743198673, 12551.497940082385, 16246.512604621865, 16254.43109491168, 16273.83726162741, 16288.26702798374, 14486.927565362184, 14586.927065364685, 14587.197704126269, 14618.0, 15014.399424023053, 681.0329332348077, 706.3184027413545, 840.2479780596362, 850.5917159763318, 870.7257213977576, 755.3051192902516, 818.7881934736594, 857.4169564696004, 860.096898256831, 863.6027427383411, 904.077840602585, 905.0768216419249, 905.6875377994614, 905.7735566108473, 906.8050369170627, 724.2467833453202, 901.5312037740339, 902.3999040638, 904.9095090490964, 905.3119629081913, 554.3015800091873, 554.8723793527512, 557.4453418848232, 648.8540078482351, 668.886289330815, 14692.926535367362, 14760.409583616642, 14765.557033289018, 14773.92613036928, 14776.778348324784, 13519.66200844977, 13987.37056832445, 14058.578242652737, 14093.577192684235, 14100.506482273071, 12832.935835320857, 12936.611901642966, 12946.417411216518, 13115.86884131156, 13198.406071726795, 4552.817887284505, 4597.954020459786, 4668.929966050512, 4684.648651351128, 4695.389599352096, 106776.4661176697, 108446.0, 108721.0, 108865.45567272192, 109024.0, 10820.891791082146, 10924.2899211551, 11165.553377864957, 11264.887351126548, 11497.310161390344, 21467.0, 21576.56846863054, 21664.783352166593, 21702.348929532138, 21709.67435488469, 7747.883781743279, 7756.1442706671105, 7849.529028258323, 7912.841743165104, 8075.5558444286125, 4316.460442444694, 4361.0, 4377.382528291718, 4392.780301219228, 4585.472670642852, 3405.8126803025575, 3513.631068737797, 3539.2213712983103, 3540.097275194809, 3559.0, 652.8628987912497, 668.204836244867, 671.2750229751856, 671.8689855478135, 675.2909445082644, 516.8656149401107, 552.6324993879106, 589.3460544623267, 593.3147214966721, 618.9876202475925, 8609.311255099577, 8626.827463450696, 8776.912230877737, 9146.954265228764, 9205.99766557927, 561.9241402410704, 594.3729365519418, 594.5897330841706, 604.5556515960766, 627.6108812536256, 4290.141971605692, 4450.35469856869, 4458.888527786767, 4965.925511117336, 5012.181353615829, 140690.88963665554, 144779.0, 146279.0, 148433.51566484413, 148733.25633371764, 100869.0, 126263.0, 128257.0, 128951.0, 130255.34872325578, 86864.0, 106646.46676766567, 108328.45835770929, 108915.0, 113822.0, 9088.0, 9633.710988670351, 9780.413175209225, 9783.755406114904, 9955.751106222402, 51394.48605513897, 51397.74301128471, 55729.721351394604, 56334.7183264081, 60877.39122608717, 82849.58575207085, 85372.14627853641, 87803.0, 88299.11700883166, 88762.0, 12448.62654120378, 12775.87224127784, 12869.806952895528, 13147.14543554666, 13345.466181353026, 14763.778543322074, 15127.319270632628, 15151.242437877841, 15465.922670386575, 15742.0, 12249.755004899675, 13585.660358491117, 13959.232242226775, 14259.64350891236, 14663.193524356262, 13743.381547830171, 13758.93120534391, 14126.78809817874, 14128.717425651224, 14395.424183032981, 11006.504707288032, 13352.132111412728, 13465.259410732111, 13815.0, 13879.236641984788, 10860.565577376816, 11765.76468470643, 11799.292042477478, 11804.2327248732, 12051.939740301243, 11295.491702873225, 11565.248258863146, 11576.594819181626, 11606.187566870576, 11681.88318116842], "value": 7775.631825173166, "unit": "score"}], "name": "dromaeo_css", "value": 7294.662759996502, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 134.0, 113.0, 114.0, 113.0, 113.0, 114.0, 112.0, 116.0], "value": 115.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 110.0, 129.0, 163.0, 107.0, 131.0, 131.0, 108.0, 161.0, 108.0], "value": 129.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 260.0, 191.0, 156.0, 201.0, 262.0, 196.0, 193.0, 263.0, 183.0], "value": 210.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 155.0, 75.0, 82.0, 82.0, 98.0, 142.0, 64.0], "value": 92.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 134.0, 70.0, 69.0, 78.0, 118.0, 69.0, 69.0, 117.0], "value": 88.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 129.0, 134.0, 82.0, 120.0, 130.0, 131.0, 87.0, 89.0, 86.0], "value": 107.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 97.0, 97.0], "value": 96.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 87.0, 87.0, 83.0, 85.0, 84.0, 82.0, 83.0, 84.0], "value": 84.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 53.0, 55.0, 52.0, 53.0, 54.0, 52.0, 53.0, 51.0], "value": 52.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 80.0, 77.0, 75.0, 72.0, 71.0, 71.0, 70.0, 72.0], "value": 72.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 87.0, 88.0, 88.0, 96.0, 103.0, 104.0, 105.0, 91.0], "value": 93.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 138.0, 140.0, 148.0, 145.0, 153.0, 149.0, 139.0, 179.0, 140.0], "value": 147.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 179.0, 178.0, 178.0, 244.0, 218.0, 178.0, 175.0, 177.0, 175.0], "value": 187.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 70.0, 69.0, 68.0, 69.0, 68.0, 68.0, 68.0, 68.0], "value": 68.4, "unit": "score"}], "name": "kraken", "value": 1546.4, "alertThreshold": 2.0}]} 19:36:05 INFO - Return code: 0 19:36:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:36:05 INFO - # TBPL SUCCESS # 19:36:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:36:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:36:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:36:05 INFO - 2016-08-31 19:36:05.596 screenresolution[1654:9687] starting screenresolution argv=screenresolution get 19:36:05 INFO - 2016-08-31 19:36:05.599 screenresolution[1654:9687] Display 0: 1600x1200x32@60 19:36:05 INFO - 2016-08-31 19:36:05.605 screenresolution[1655:9690] starting screenresolution argv=screenresolution list 19:36:05 INFO - Available Modes on Display 0 19:36:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:36:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:36:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:36:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:36:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:36:05 INFO - Intel Iris: 19:36:05 INFO - Chipset Model: Intel Iris 19:36:05 INFO - Type: GPU 19:36:05 INFO - Bus: Built-In 19:36:05 INFO - VRAM (Dynamic, Max): 1536 MB 19:36:05 INFO - Vendor: Intel (0x8086) 19:36:05 INFO - Device ID: 0x0a2e 19:36:05 INFO - Revision ID: 0x0009 19:36:05 INFO - Displays: 19:36:05 INFO - Display: 19:36:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:36:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:36:05 INFO - Display Serial Number: 1600x1200 60 19:36:05 INFO - Main Display: Yes 19:36:05 INFO - Mirror: Off 19:36:05 INFO - Online: Yes 19:36:05 INFO - Rotation: Supported 19:36:05 INFO - Return code: 0 19:36:05 INFO - Running post-action listener: _resource_record_post_action 19:36:05 INFO - [mozharness: 2016-09-01 02:36:05.849960Z] Finished run-tests step (success) 19:36:05 INFO - Running post-run listener: _resource_record_post_run 19:36:05 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 37352448; Write bytes: 704207872; Read time: 247; Write time: 1984 19:36:05 INFO - TinderboxPrint: CPU usage
53.7% 19:36:05 INFO - TinderboxPrint: I/O read bytes / time
37,352,448 / 247 19:36:05 INFO - TinderboxPrint: I/O write bytes / time
704,207,872 / 1,984 19:36:05 INFO - TinderboxPrint: CPU idle
2,246.7 (46.3%) 19:36:05 INFO - TinderboxPrint: CPU system
971.7 (20.0%) 19:36:05 INFO - TinderboxPrint: CPU user
1,635.2 (33.7%) 19:36:05 INFO - TinderboxPrint: Swap in / out
442,712,064 / 0 19:36:05 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 396915200; Write bytes: 390641152; Read time: 29499; Write time: 833 19:36:05 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 35181568; Write bytes: 294880768; Read time: 220; Write time: 1102 19:36:06 INFO - Running post-run listener: _upload_blobber_files 19:36:06 INFO - Blob upload gear active. 19:36:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:36:06 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 19:36:06 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:36:06 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:36:06 INFO - (blobuploader) - INFO - Open directory for files ... 19:36:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:36:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:36:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:36:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:36:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:36:07 INFO - (blobuploader) - INFO - Done attempting. 19:36:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:36:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:36:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:36:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:36:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:36:08 INFO - (blobuploader) - INFO - Done attempting. 19:36:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:36:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:36:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:36:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:36:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:36:11 INFO - (blobuploader) - INFO - Done attempting. 19:36:11 INFO - (blobuploader) - INFO - Iteration through files over. 19:36:11 INFO - Return code: 0 19:36:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:36:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:36:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb92f96e2221ca603b8b533e34c9ee912ed2ca7c09b2d5ec93b06c9a1930a0ad725c92a6d30755d858f85c0f3f84f90e319d2e07e058ec1a120567e3e834b22a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dfb4eb13db8dc1163ff226b0c231eee62ac8702cd10aba60ed79a392f46c4ff2c0a1ef700b144a0eadb0a42e96471ad6e1d23fe67c6d6ee5ed73034fe594e476", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/14a03c326ea861c35bbe81e7751b3a87fc670ef98714fe6cb2a7e0972c10a6dba4e4aec6ee9e14772916b61a3f864c96f92d51d6aab24ef8aa7828f510def39c"} 19:36:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:36:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:36:11 INFO - Contents: 19:36:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb92f96e2221ca603b8b533e34c9ee912ed2ca7c09b2d5ec93b06c9a1930a0ad725c92a6d30755d858f85c0f3f84f90e319d2e07e058ec1a120567e3e834b22a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dfb4eb13db8dc1163ff226b0c231eee62ac8702cd10aba60ed79a392f46c4ff2c0a1ef700b144a0eadb0a42e96471ad6e1d23fe67c6d6ee5ed73034fe594e476", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/14a03c326ea861c35bbe81e7751b3a87fc670ef98714fe6cb2a7e0972c10a6dba4e4aec6ee9e14772916b61a3f864c96f92d51d6aab24ef8aa7828f510def39c"} 19:36:11 INFO - Running post-run listener: copy_logs_to_upload_dir 19:36:11 INFO - Copying logs to upload dir... 19:36:11 INFO - mkdir: /builds/slave/test/build/upload/logs 19:36:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.742466 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-31 19:36:11.188972) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 19:36:11.192837) ========= 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.xxeyNH5Cxn/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.5qqVfGKWQs/Listeners TMPDIR=/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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/Firefox/sha512/cb92f96e2221ca603b8b533e34c9ee912ed2ca7c09b2d5ec93b06c9a1930a0ad725c92a6d30755d858f85c0f3f84f90e319d2e07e058ec1a120567e3e834b22a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dfb4eb13db8dc1163ff226b0c231eee62ac8702cd10aba60ed79a392f46c4ff2c0a1ef700b144a0eadb0a42e96471ad6e1d23fe67c6d6ee5ed73034fe594e476", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/14a03c326ea861c35bbe81e7751b3a87fc670ef98714fe6cb2a7e0972c10a6dba4e4aec6ee9e14772916b61a3f864c96f92d51d6aab24ef8aa7828f510def39c"} build_url:https://queue.taskcluster.net/v1/task/CKoMrTYcRCaTRi80TknJsA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011035 build_url: 'https://queue.taskcluster.net/v1/task/CKoMrTYcRCaTRi80TknJsA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb92f96e2221ca603b8b533e34c9ee912ed2ca7c09b2d5ec93b06c9a1930a0ad725c92a6d30755d858f85c0f3f84f90e319d2e07e058ec1a120567e3e834b22a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dfb4eb13db8dc1163ff226b0c231eee62ac8702cd10aba60ed79a392f46c4ff2c0a1ef700b144a0eadb0a42e96471ad6e1d23fe67c6d6ee5ed73034fe594e476", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/14a03c326ea861c35bbe81e7751b3a87fc670ef98714fe6cb2a7e0972c10a6dba4e4aec6ee9e14772916b61a3f864c96f92d51d6aab24ef8aa7828f510def39c"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 19:36:11.230927) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 19:36:11.231699) ========= 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.xxeyNH5Cxn/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.5qqVfGKWQs/Listeners TMPDIR=/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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.005251 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 19:36:11.281311) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 19:36:11.281660) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 19:36:11.281987) ========= ========= Total master_lag: 0.19 =========