builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0006 starttime: 1471964138.93 results: success (0) buildid: 20160823065947 builduid: 01ad60674bde4718a17ce71a9ea97517 revision: 352d2e43336522a95c803d25a48c0df63dff931d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-23 07:55:38.925333) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-23 07:55:38.925702) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-23 07:55:38.925963) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-23 07:55:38.949194) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 07:55:38.949460) ========= 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.7PlHkr6UfN/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.o9EyqqVe0D/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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-23 07:55:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.84M=0.001s 2016-08-23 07:55:39 (7.84 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.142812 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 07:55:39.114396) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 07:55:39.114703) ========= 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.7PlHkr6UfN/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.o9EyqqVe0D/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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.020468 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 07:55:39.162912) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-23 07:55:39.163218) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 352d2e43336522a95c803d25a48c0df63dff931d --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 352d2e43336522a95c803d25a48c0df63dff931d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7PlHkr6UfN/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.o9EyqqVe0D/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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-23 07:55:39,238 truncating revision to first 12 chars 2016-08-23 07:55:39,238 Setting DEBUG logging. 2016-08-23 07:55:39,238 attempt 1/10 2016-08-23 07:55:39,238 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/352d2e433365?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-23 07:55:40,376 unpacking tar archive at: mozilla-central-352d2e433365/testing/mozharness/ program finished with exit code 0 elapsedTime=1.389928 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-23 07:55:40.575231) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-23 07:55:40.575544) ========= script_repo_revision: 352d2e43336522a95c803d25a48c0df63dff931d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-23 07:55:40.575921) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-23 07:55:40.576236) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-23 07:55:40.594897) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-23 07:55:40.595159) ========= /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.7PlHkr6UfN/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.o9EyqqVe0D/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 07:55:40 INFO - MultiFileLogger online at 20160823 07:55:40 in /builds/slave/test 07:55:40 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 07:55:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:55:40 INFO - {'append_to_log': False, 07:55:40 INFO - 'base_work_dir': '/builds/slave/test', 07:55:40 INFO - 'blob_upload_branch': 'Firefox', 07:55:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:55:40 INFO - 'branch': 'Firefox', 07:55:40 INFO - 'buildbot_json_path': 'buildprops.json', 07:55:40 INFO - 'config_files': ('talos/mac_config.py',), 07:55:40 INFO - 'default_actions': ('clobber', 07:55:40 INFO - 'read-buildbot-config', 07:55:40 INFO - 'download-and-extract', 07:55:40 INFO - 'populate-webroot', 07:55:40 INFO - 'create-virtualenv', 07:55:40 INFO - 'install', 07:55:40 INFO - 'run-tests'), 07:55:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:55:40 INFO - 'download_minidump_stackwalk': True, 07:55:40 INFO - 'download_symbols': 'ondemand', 07:55:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:55:40 INFO - 'tooltool.py': '/tools/tooltool.py', 07:55:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:55:40 INFO - '/tools/misc-python/virtualenv.py')}, 07:55:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:55:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:55:40 INFO - 'installer_path': 'installer.exe', 07:55:40 INFO - 'log_level': 'info', 07:55:40 INFO - 'log_name': 'talos', 07:55:40 INFO - 'log_to_console': True, 07:55:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:55:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:55:40 INFO - 'opt_config_files': (), 07:55:40 INFO - 'pip_index': False, 07:55:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:55:40 INFO - 'cmd': ('bash', 07:55:40 INFO - '-c', 07:55:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:55:40 INFO - 'enabled': True, 07:55:40 INFO - 'halt_on_failure': False, 07:55:40 INFO - 'name': 'check_screen_resolution'},), 07:55:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:55:40 INFO - 'cmd': ('bash', 07:55:40 INFO - '-c', 07:55:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:55:40 INFO - 'enabled': True, 07:55:40 INFO - 'halt_on_failure': False, 07:55:40 INFO - 'name': 'check_screen_resolution'},), 07:55:40 INFO - 'run_cmd_checks_enabled': True, 07:55:40 INFO - 'sps_profile': False, 07:55:40 INFO - 'sps_profile_interval': 0, 07:55:40 INFO - 'suite': 'dromaeojs', 07:55:40 INFO - 'system_bits': '32', 07:55:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:55:40 INFO - 'title': 't-yosemite-r7-0006', 07:55:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:55:40 INFO - 'use_talos_json': True, 07:55:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:55:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:55:40 INFO - 'work_dir': 'build'} 07:55:40 INFO - [mozharness: 2016-08-23 14:55:40.791052Z] Running clobber step. 07:55:40 INFO - Running pre-action listener: _resource_record_pre_action 07:55:40 INFO - Running main action method: clobber 07:55:40 INFO - rmtree: /builds/slave/test/build 07:55:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:55:42 INFO - Running post-action listener: _resource_record_post_action 07:55:42 INFO - [mozharness: 2016-08-23 14:55:42.412702Z] Finished clobber step (success) 07:55:42 INFO - [mozharness: 2016-08-23 14:55:42.412858Z] Running read-buildbot-config step. 07:55:42 INFO - Running pre-action listener: _resource_record_pre_action 07:55:42 INFO - Running main action method: read_buildbot_config 07:55:42 INFO - Using buildbot properties: 07:55:42 INFO - { 07:55:42 INFO - "project": "", 07:55:42 INFO - "product": "firefox", 07:55:42 INFO - "script_repo_revision": "production", 07:55:42 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 07:55:42 INFO - "repository": "", 07:55:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 07:55:42 INFO - "buildid": "20160823065947", 07:55:42 INFO - "pgo_build": "False", 07:55:42 INFO - "basedir": "/builds/slave/test", 07:55:42 INFO - "buildnumber": 48, 07:55:42 INFO - "slavename": "t-yosemite-r7-0006", 07:55:42 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 07:55:42 INFO - "platform": "yosemite_r7", 07:55:42 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:55:42 INFO - "branch": "mozilla-central", 07:55:42 INFO - "repo_path": "mozilla-central", 07:55:42 INFO - "revision": "352d2e43336522a95c803d25a48c0df63dff931d", 07:55:42 INFO - "stage_platform": "macosx64", 07:55:42 INFO - "builduid": "01ad60674bde4718a17ce71a9ea97517", 07:55:42 INFO - "slavebuilddir": "test" 07:55:42 INFO - } 07:55:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/URUUS0aSTY21SpJMpbTVtA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 07:55:42 INFO - Running post-action listener: _resource_record_post_action 07:55:42 INFO - [mozharness: 2016-08-23 14:55:42.416221Z] Finished read-buildbot-config step (success) 07:55:42 INFO - [mozharness: 2016-08-23 14:55:42.416361Z] Running download-and-extract step. 07:55:42 INFO - Running pre-action listener: _resource_record_pre_action 07:55:42 INFO - Running main action method: download_and_extract 07:55:42 INFO - mkdir: /builds/slave/test/build/tests 07:55:42 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')]} 07:55:42 INFO - trying https://queue.taskcluster.net/v1/task/URUUS0aSTY21SpJMpbTVtA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 07:55:42 INFO - Downloading https://queue.taskcluster.net/v1/task/URUUS0aSTY21SpJMpbTVtA/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 07:55:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/URUUS0aSTY21SpJMpbTVtA/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 07:55:43 INFO - Downloaded 1199 bytes. 07:55:43 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 07:55:43 INFO - Using the following test package requirements: 07:55:43 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 07:55:43 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:55:43 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 07:55:43 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:55:43 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 07:55:43 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:55:43 INFO - u'jsshell-mac.zip'], 07:55:43 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:55:43 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 07:55:43 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 07:55:43 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:55:43 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 07:55:43 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:55:43 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 07:55:43 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:55:43 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 07:55:43 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:55:43 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 07:55:43 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:55:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:55:43 INFO - trying https://queue.taskcluster.net/v1/task/URUUS0aSTY21SpJMpbTVtA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 07:55:43 INFO - Downloading https://queue.taskcluster.net/v1/task/URUUS0aSTY21SpJMpbTVtA/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 07:55:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/URUUS0aSTY21SpJMpbTVtA/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 07:55:44 INFO - Downloaded 16352347 bytes. 07:55:44 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 07:55:51 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 07:55: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')]} 07:55:51 INFO - trying https://queue.taskcluster.net/v1/task/URUUS0aSTY21SpJMpbTVtA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 07:55:51 INFO - Downloading https://queue.taskcluster.net/v1/task/URUUS0aSTY21SpJMpbTVtA/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 07:55:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/URUUS0aSTY21SpJMpbTVtA/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 07:55:52 INFO - Downloaded 16352347 bytes. 07:55:52 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 07:55:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:55:59 INFO - trying https://queue.taskcluster.net/v1/task/URUUS0aSTY21SpJMpbTVtA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 07:55:59 INFO - Downloading https://queue.taskcluster.net/v1/task/URUUS0aSTY21SpJMpbTVtA/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 07:55:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/URUUS0aSTY21SpJMpbTVtA/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 07:56:00 INFO - Downloaded 14413981 bytes. 07:56:00 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 07:56: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')]} 07:56:01 INFO - trying https://queue.taskcluster.net/v1/task/URUUS0aSTY21SpJMpbTVtA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 07:56:01 INFO - Downloading https://queue.taskcluster.net/v1/task/URUUS0aSTY21SpJMpbTVtA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 07:56:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/URUUS0aSTY21SpJMpbTVtA/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 07:56:04 INFO - Downloaded 111476996 bytes. 07:56:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/URUUS0aSTY21SpJMpbTVtA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 07:56:04 INFO - mkdir: /builds/slave/test/properties 07:56:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:56:04 INFO - Writing to file /builds/slave/test/properties/build_url 07:56:04 INFO - Contents: 07:56:04 INFO - build_url:https://queue.taskcluster.net/v1/task/URUUS0aSTY21SpJMpbTVtA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 07:56:05 INFO - Running post-action listener: _resource_record_post_action 07:56:05 INFO - Running post-action listener: set_extra_try_arguments 07:56:05 INFO - [mozharness: 2016-08-23 14:56:05.255348Z] Finished download-and-extract step (success) 07:56:05 INFO - [mozharness: 2016-08-23 14:56:05.255487Z] Running populate-webroot step. 07:56:05 INFO - Running pre-action listener: _resource_record_pre_action 07:56:05 INFO - Running main action method: populate_webroot 07:56:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:56:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:56:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:56:05 INFO - '--tppagecycles', 07:56:05 INFO - '7'], 07:56:05 INFO - 'tests': ['tsvgm']}}, 07:56:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:56:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:56:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:56:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:56:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:56:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:56:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:56:05 INFO - 'talos_options': ['--disable-e10s'], 07:56:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:56:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:56:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:56:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:56:05 INFO - 'talos_options': ['--disable-e10s'], 07:56:05 INFO - 'tests': ['damp', 'tps']}, 07:56:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:56:05 INFO - 'tests': ['damp', 'tps']}, 07:56:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:56:05 INFO - 'tests': ['dromaeo_dom']}, 07:56:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:56:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:56:05 INFO - 'tests': ['basic_compositor_video']}, 07:56:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:56:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:56:05 INFO - 'tests': ['a11yr', 07:56:05 INFO - 'ts_paint', 07:56:05 INFO - 'tpaint', 07:56:05 INFO - 'sessionrestore', 07:56:05 INFO - 'sessionrestore_no_auto_restore', 07:56:05 INFO - 'tabpaint']}, 07:56:05 INFO - 'other-e10s': {'tests': ['a11yr', 07:56:05 INFO - 'ts_paint', 07:56:05 INFO - 'tpaint', 07:56:05 INFO - 'sessionrestore', 07:56:05 INFO - 'sessionrestore_no_auto_restore', 07:56:05 INFO - 'tabpaint']}, 07:56:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:56:05 INFO - 'ts_paint', 07:56:05 INFO - 'tpaint', 07:56:05 INFO - 'sessionrestore', 07:56:05 INFO - 'sessionrestore_no_auto_restore', 07:56:05 INFO - 'tabpaint']}, 07:56:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:56:05 INFO - 'ts_paint', 07:56:05 INFO - 'tpaint', 07:56:05 INFO - 'sessionrestore', 07:56:05 INFO - 'sessionrestore_no_auto_restore', 07:56:05 INFO - 'tabpaint']}, 07:56:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:56:05 INFO - 'tests': ['a11yr', 07:56:05 INFO - 'ts_paint', 07:56:05 INFO - 'tpaint', 07:56:05 INFO - 'sessionrestore', 07:56:05 INFO - 'sessionrestore_no_auto_restore', 07:56:05 INFO - 'tabpaint']}, 07:56:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:56:05 INFO - 'tests': ['a11yr', 07:56:05 INFO - 'ts_paint', 07:56:05 INFO - 'tpaint', 07:56:05 INFO - 'sessionrestore', 07:56:05 INFO - 'sessionrestore_no_auto_restore', 07:56:05 INFO - 'tabpaint']}, 07:56:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:56:05 INFO - 'tests': ['tsvgx', 07:56:05 INFO - 'tsvgr_opacity', 07:56:05 INFO - 'tart', 07:56:05 INFO - 'tscrollx', 07:56:05 INFO - 'cart']}, 07:56:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:56:05 INFO - 'tsvgr_opacity', 07:56:05 INFO - 'tart', 07:56:05 INFO - 'tscrollx', 07:56:05 INFO - 'cart']}, 07:56:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:56:05 INFO - 'talos_options': ['--disable-e10s'], 07:56:05 INFO - 'tests': ['tp5o']}, 07:56:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:56:05 INFO - 'tests': ['tp5o']}, 07:56:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:56:05 INFO - 'talos_options': ['--disable-e10s', 07:56:05 INFO - '--xperf_path', 07:56:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:56:05 INFO - 'tests': ['tp5n']}, 07:56:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:56:05 INFO - 'talos_options': ['--xperf_path', 07:56:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:56:05 INFO - 'tests': ['tp5n']}}, 07:56:05 INFO - 'talos.zip': {'path': '', 07:56:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:56:05 INFO - Running post-action listener: _resource_record_post_action 07:56:05 INFO - [mozharness: 2016-08-23 14:56:05.274996Z] Finished populate-webroot step (success) 07:56:05 INFO - [mozharness: 2016-08-23 14:56:05.275132Z] Running create-virtualenv step. 07:56:05 INFO - Running pre-action listener: _resource_record_pre_action 07:56:05 INFO - Running main action method: create_virtualenv 07:56:05 INFO - Creating virtualenv /builds/slave/test/build/venv 07:56:05 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 07:56:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:56:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:56:05 INFO - Using real prefix '/tools/python27' 07:56:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:56:06 INFO - Installing distribute.............................................................................................................................................................................................done. 07:56:08 INFO - Installing pip.................done. 07:56:08 INFO - Return code: 0 07:56:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:56:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:56:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:56:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56: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')]} 07:56:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:56:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:09 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 0x10e188a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e21d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf504714e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0ecd30>, '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 0x10de51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0e4b20>, '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.7PlHkr6UfN/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.o9EyqqVe0D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 07:56:09 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 07:56:09 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 07:56:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7PlHkr6UfN/Render', 07:56:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:56:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:56:09 INFO - 'HOME': '/Users/cltbld', 07:56:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:56:09 INFO - 'LOGNAME': 'cltbld', 07:56:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:56:09 INFO - 'MOZ_NO_REMOTE': '1', 07:56:09 INFO - 'NO_EM_RESTART': '1', 07:56:09 INFO - 'PAGER': '/bin/cat', 07:56:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:56:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:56:09 INFO - 'PWD': '/builds/slave/test', 07:56:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:56:09 INFO - 'SHELL': '/bin/bash', 07:56:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o9EyqqVe0D/Listeners', 07:56:09 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 07:56:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:56:09 INFO - 'USER': 'cltbld', 07:56:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:56:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:56:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:56:09 INFO - 'XPC_FLAGS': '0x0', 07:56:09 INFO - 'XPC_SERVICE_NAME': '0', 07:56:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:56:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:56:09 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 07:56:09 INFO - Cleaning up... 07:56:09 INFO - Return code: 0 07:56:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:56: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')]} 07:56:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:56:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56: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')]} 07:56:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:56:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:09 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 0x10e188a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e21d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf504714e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0ecd30>, '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 0x10de51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0e4b20>, '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.7PlHkr6UfN/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.o9EyqqVe0D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 07:56:09 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 07:56:09 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 07:56:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7PlHkr6UfN/Render', 07:56:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:56:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:56:09 INFO - 'HOME': '/Users/cltbld', 07:56:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:56:09 INFO - 'LOGNAME': 'cltbld', 07:56:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:56:09 INFO - 'MOZ_NO_REMOTE': '1', 07:56:09 INFO - 'NO_EM_RESTART': '1', 07:56:09 INFO - 'PAGER': '/bin/cat', 07:56:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:56:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:56:09 INFO - 'PWD': '/builds/slave/test', 07:56:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:56:09 INFO - 'SHELL': '/bin/bash', 07:56:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o9EyqqVe0D/Listeners', 07:56:09 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 07:56:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:56:09 INFO - 'USER': 'cltbld', 07:56:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:56:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:56:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:56:09 INFO - 'XPC_FLAGS': '0x0', 07:56:09 INFO - 'XPC_SERVICE_NAME': '0', 07:56:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:56:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:56:09 INFO - Downloading/unpacking psutil>=3.1.1 07:56:09 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 07:56:09 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 07:56:09 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 07:56:09 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 07:56:09 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 07:56:09 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 07:56:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:56:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:56:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:56:12 INFO - Installing collected packages: psutil 07:56:12 INFO - Running setup.py install for psutil 07:56:12 INFO - building 'psutil._psutil_osx' extension 07:56:12 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 07:56:13 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 07:56:13 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 07:56:13 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 07:56:13 INFO - building 'psutil._psutil_posix' extension 07:56:13 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 07:56:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:56:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:56:13 INFO - ^ 07:56:13 INFO - 1 warning generated. 07:56:13 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 07:56:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:56:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:56:13 INFO - Successfully installed psutil 07:56:13 INFO - Cleaning up... 07:56:13 INFO - Return code: 0 07:56:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:56:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:56:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:56:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:56:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:56:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:13 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 0x10e188a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e21d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf504714e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0ecd30>, '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 0x10de51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0e4b20>, '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.7PlHkr6UfN/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.o9EyqqVe0D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 07:56:13 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 07:56:13 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 07:56:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7PlHkr6UfN/Render', 07:56:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:56:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:56:13 INFO - 'HOME': '/Users/cltbld', 07:56:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:56:13 INFO - 'LOGNAME': 'cltbld', 07:56:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:56:13 INFO - 'MOZ_NO_REMOTE': '1', 07:56:13 INFO - 'NO_EM_RESTART': '1', 07:56:13 INFO - 'PAGER': '/bin/cat', 07:56:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:56:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:56:13 INFO - 'PWD': '/builds/slave/test', 07:56:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:56:13 INFO - 'SHELL': '/bin/bash', 07:56:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o9EyqqVe0D/Listeners', 07:56:13 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 07:56:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:56:13 INFO - 'USER': 'cltbld', 07:56:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:56:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:56:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:56:13 INFO - 'XPC_FLAGS': '0x0', 07:56:13 INFO - 'XPC_SERVICE_NAME': '0', 07:56:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:56:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:56:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:56:13 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 07:56:13 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 07:56:13 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 07:56:13 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 07:56:13 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 07:56:13 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 07:56:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:56:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:56:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:56:16 INFO - Installing collected packages: mozsystemmonitor 07:56:16 INFO - Running setup.py install for mozsystemmonitor 07:56:16 INFO - Successfully installed mozsystemmonitor 07:56:16 INFO - Cleaning up... 07:56:16 INFO - Return code: 0 07:56:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:56: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')]} 07:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:56:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56: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')]} 07:56:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:56:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:16 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 0x10e188a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e21d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf504714e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0ecd30>, '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 0x10de51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0e4b20>, '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.7PlHkr6UfN/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.o9EyqqVe0D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 07:56:16 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 07:56:16 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 07:56:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7PlHkr6UfN/Render', 07:56:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:56:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:56:16 INFO - 'HOME': '/Users/cltbld', 07:56:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:56:16 INFO - 'LOGNAME': 'cltbld', 07:56:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:56:16 INFO - 'MOZ_NO_REMOTE': '1', 07:56:16 INFO - 'NO_EM_RESTART': '1', 07:56:16 INFO - 'PAGER': '/bin/cat', 07:56:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:56:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:56:16 INFO - 'PWD': '/builds/slave/test', 07:56:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:56:16 INFO - 'SHELL': '/bin/bash', 07:56:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o9EyqqVe0D/Listeners', 07:56:16 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 07:56:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:56:16 INFO - 'USER': 'cltbld', 07:56:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:56:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:56:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:56:16 INFO - 'XPC_FLAGS': '0x0', 07:56:16 INFO - 'XPC_SERVICE_NAME': '0', 07:56:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:56:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:56:16 INFO - Downloading/unpacking jsonschema==2.5.1 07:56:16 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 07:56:16 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 07:56:16 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 07:56:16 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 07:56:16 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 07:56:16 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 07:56:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:56:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:56:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:56:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:56:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:56:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:56:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:56:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:56:19 INFO - Downloading functools32-3.2.3-2.tar.gz 07:56:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:56:19 INFO - warning: no files found matching '*.txt' 07:56:19 INFO - no previously-included directories found matching 'build' 07:56:19 INFO - no previously-included directories found matching 'dist' 07:56:19 INFO - no previously-included directories found matching '.git*' 07:56:19 INFO - Installing collected packages: jsonschema, functools32 07:56:19 INFO - Running setup.py install for functools32 07:56:20 INFO - warning: no files found matching '*.txt' 07:56:20 INFO - no previously-included directories found matching 'build' 07:56:20 INFO - no previously-included directories found matching 'dist' 07:56:20 INFO - no previously-included directories found matching '.git*' 07:56:20 INFO - Successfully installed jsonschema functools32 07:56:20 INFO - Cleaning up... 07:56:20 INFO - Return code: 0 07:56:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:56:20 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')]} 07:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:56:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:20 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')]} 07:56:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:56:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:20 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 0x10e188a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e21d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf504714e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0ecd30>, '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 0x10de51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0e4b20>, '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.7PlHkr6UfN/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.o9EyqqVe0D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 07:56:20 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 07:56:20 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 07:56:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7PlHkr6UfN/Render', 07:56:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:56:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:56:20 INFO - 'HOME': '/Users/cltbld', 07:56:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:56:20 INFO - 'LOGNAME': 'cltbld', 07:56:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:56:20 INFO - 'MOZ_NO_REMOTE': '1', 07:56:20 INFO - 'NO_EM_RESTART': '1', 07:56:20 INFO - 'PAGER': '/bin/cat', 07:56:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:56:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:56:20 INFO - 'PWD': '/builds/slave/test', 07:56:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:56:20 INFO - 'SHELL': '/bin/bash', 07:56:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o9EyqqVe0D/Listeners', 07:56:20 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 07:56:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:56:20 INFO - 'USER': 'cltbld', 07:56:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:56:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:56:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:56:20 INFO - 'XPC_FLAGS': '0x0', 07:56:20 INFO - 'XPC_SERVICE_NAME': '0', 07:56:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:56:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:56:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:56:20 INFO - Cleaning up... 07:56:20 INFO - Return code: 0 07:56:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:56:20 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')]} 07:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:56:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:20 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')]} 07:56:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:56:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:20 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 0x10e188a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e21d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf504714e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0ecd30>, '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 0x10de51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0e4b20>, '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.7PlHkr6UfN/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.o9EyqqVe0D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 07:56:20 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 07:56:20 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 07:56:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7PlHkr6UfN/Render', 07:56:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:56:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:56:20 INFO - 'HOME': '/Users/cltbld', 07:56:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:56:20 INFO - 'LOGNAME': 'cltbld', 07:56:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:56:20 INFO - 'MOZ_NO_REMOTE': '1', 07:56:20 INFO - 'NO_EM_RESTART': '1', 07:56:20 INFO - 'PAGER': '/bin/cat', 07:56:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:56:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:56:20 INFO - 'PWD': '/builds/slave/test', 07:56:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:56:20 INFO - 'SHELL': '/bin/bash', 07:56:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o9EyqqVe0D/Listeners', 07:56:20 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 07:56:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:56:20 INFO - 'USER': 'cltbld', 07:56:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:56:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:56:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:56:20 INFO - 'XPC_FLAGS': '0x0', 07:56:20 INFO - 'XPC_SERVICE_NAME': '0', 07:56:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:56:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:56:20 INFO - Downloading/unpacking blobuploader==1.2.4 07:56:20 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 07:56:20 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 07:56:20 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 07:56:20 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 07:56:20 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 07:56:20 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 07:56:23 INFO - Downloading blobuploader-1.2.4.tar.gz 07:56:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:56:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:56:23 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 07:56:23 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 07:56:23 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 07:56:23 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 07:56:23 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 07:56:23 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 07:56:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:56:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:56:23 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 07:56:23 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 07:56:23 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 07:56:23 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 07:56:23 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 07:56:23 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 07:56:24 INFO - Downloading docopt-0.6.1.tar.gz 07:56:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:56:24 INFO - Installing collected packages: blobuploader, requests, docopt 07:56:24 INFO - Running setup.py install for blobuploader 07:56:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:56:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:56:24 INFO - Running setup.py install for requests 07:56:25 INFO - Running setup.py install for docopt 07:56:25 INFO - Successfully installed blobuploader requests docopt 07:56:25 INFO - Cleaning up... 07:56:25 INFO - Return code: 0 07:56:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:56:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:56:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:56:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:56:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:56:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:25 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 0x10e188a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e21d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf504714e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0ecd30>, '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 0x10de51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0e4b20>, '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.7PlHkr6UfN/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.o9EyqqVe0D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 07:56:25 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 07:56:25 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 07:56:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7PlHkr6UfN/Render', 07:56:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:56:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:56:25 INFO - 'HOME': '/Users/cltbld', 07:56:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:56:25 INFO - 'LOGNAME': 'cltbld', 07:56:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:56:25 INFO - 'MOZ_NO_REMOTE': '1', 07:56:25 INFO - 'NO_EM_RESTART': '1', 07:56:25 INFO - 'PAGER': '/bin/cat', 07:56:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:56:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:56:25 INFO - 'PWD': '/builds/slave/test', 07:56:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:56:25 INFO - 'SHELL': '/bin/bash', 07:56:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o9EyqqVe0D/Listeners', 07:56:25 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 07:56:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:56:25 INFO - 'USER': 'cltbld', 07:56:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:56:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:56:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:56:25 INFO - 'XPC_FLAGS': '0x0', 07:56:25 INFO - 'XPC_SERVICE_NAME': '0', 07:56:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:56:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:56:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:56:25 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-xczRdf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:56:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:56:25 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-9NOmse-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:56:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:56:25 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-h1SGrU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:56:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:56:25 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-g2A0jU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:56:26 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-8d2m36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:56:26 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-7TFC6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:56:26 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-s_I53S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:56:26 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-ebEmpf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:56:26 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-iQKzo2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:56:26 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-KLZ7rH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:56:26 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-JayNsC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:56:26 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-7eJ9lJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:56:26 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-0OSfIY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:56:26 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-m30TPp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:56:27 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-Pmgmgg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:56:27 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-AgdpUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:56:27 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-B9QWVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:56:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:56:27 INFO - Running setup.py install for manifestparser 07:56:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:56:27 INFO - Running setup.py install for mozcrash 07:56:27 INFO - Running setup.py install for mozdebug 07:56:27 INFO - Running setup.py install for mozdevice 07:56:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:56:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:56:28 INFO - Running setup.py install for mozfile 07:56:28 INFO - Running setup.py install for mozhttpd 07:56:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:56:28 INFO - Running setup.py install for mozinfo 07:56:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:56:28 INFO - Running setup.py install for mozInstall 07:56:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:56:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:56:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:56:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:56:28 INFO - Running setup.py install for mozleak 07:56:28 INFO - Running setup.py install for mozlog 07:56:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:56:28 INFO - Running setup.py install for moznetwork 07:56:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:56:29 INFO - Running setup.py install for mozprocess 07:56:29 INFO - Running setup.py install for mozprofile 07:56:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:56:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:56:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:56:29 INFO - Running setup.py install for mozrunner 07:56:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:56:29 INFO - Running setup.py install for mozscreenshot 07:56:29 INFO - Running setup.py install for moztest 07:56:29 INFO - Running setup.py install for mozversion 07:56:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:56:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:56:29 INFO - Cleaning up... 07:56:29 INFO - Return code: 0 07:56:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:56:29 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')]} 07:56:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:56:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:29 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')]} 07:56:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:56:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:29 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 0x10e188a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e21d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf504714e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0ecd30>, '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 0x10de51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0e4b20>, '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.7PlHkr6UfN/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.o9EyqqVe0D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 07:56:29 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 07:56:29 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 07:56:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7PlHkr6UfN/Render', 07:56:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:56:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:56:29 INFO - 'HOME': '/Users/cltbld', 07:56:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:56:29 INFO - 'LOGNAME': 'cltbld', 07:56:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:56:29 INFO - 'MOZ_NO_REMOTE': '1', 07:56:29 INFO - 'NO_EM_RESTART': '1', 07:56:29 INFO - 'PAGER': '/bin/cat', 07:56:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:56:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:56:29 INFO - 'PWD': '/builds/slave/test', 07:56:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:56:29 INFO - 'SHELL': '/bin/bash', 07:56:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o9EyqqVe0D/Listeners', 07:56:29 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 07:56:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:56:29 INFO - 'USER': 'cltbld', 07:56:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:56:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:56:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:56:29 INFO - 'XPC_FLAGS': '0x0', 07:56:29 INFO - 'XPC_SERVICE_NAME': '0', 07:56:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:56:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:56:30 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-KMdq6Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:56:30 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)) 07:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:56:30 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-sf7ffn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:56:30 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)) 07:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:56:30 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-Cng7dF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:56:30 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)) 07:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:56:30 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-n0sALK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:56:30 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)) 07:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:56:30 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-q4lDsk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:56:30 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)) 07:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:56:30 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-PCEsNt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:56:30 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)) 07:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:56:30 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-BkiInQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:56:30 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)) 07:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:56:31 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-ULVDXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:56:31 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)) 07:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:56:31 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-gWOelB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:56:31 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)) 07:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:56:31 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-jrEy4G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:56:31 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)) 07:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:56:31 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-_8NvEQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:56:31 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)) 07:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:56:31 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-lvSMvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:56:31 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)) 07:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:56:31 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-XP4T4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:56:31 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)) 07:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:56:31 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-vSZNGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:56:31 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)) 07:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:56:31 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-AhSyiB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:56:31 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)) 07:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:56:31 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-R1UTn9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:56:31 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)) 07:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:56:31 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-hVqnwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:56:32 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)) 07:56:32 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)) 07:56:32 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)) 07:56:32 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)) 07:56:32 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)) 07:56:32 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)) 07:56:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:56:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:56:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:56:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:56:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:56:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:56:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:56:34 INFO - Downloading blessings-1.6.tar.gz 07:56:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:56:34 INFO - Installing collected packages: blessings 07:56:34 INFO - Running setup.py install for blessings 07:56:34 INFO - Successfully installed blessings 07:56:34 INFO - Cleaning up... 07:56:34 INFO - Return code: 0 07:56:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:56:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:56:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:56:35 INFO - Reading from file tmpfile_stdout 07:56:35 INFO - Current package versions: 07:56:35 INFO - blessings == 1.6 07:56:35 INFO - blobuploader == 1.2.4 07:56:35 INFO - docopt == 0.6.1 07:56:35 INFO - functools32 == 3.2.3-2 07:56:35 INFO - jsonschema == 2.5.1 07:56:35 INFO - manifestparser == 1.1 07:56:35 INFO - mozInstall == 1.12 07:56:35 INFO - mozcrash == 0.17 07:56:35 INFO - mozdebug == 0.1 07:56:35 INFO - mozdevice == 0.48 07:56:35 INFO - mozfile == 1.2 07:56:35 INFO - mozhttpd == 0.7 07:56:35 INFO - mozinfo == 0.9 07:56:35 INFO - mozleak == 0.1 07:56:35 INFO - mozlog == 3.3 07:56:35 INFO - moznetwork == 0.27 07:56:35 INFO - mozprocess == 0.23 07:56:35 INFO - mozprofile == 0.28 07:56:35 INFO - mozrunner == 6.12 07:56:35 INFO - mozscreenshot == 0.1 07:56:35 INFO - mozsystemmonitor == 0.3 07:56:35 INFO - moztest == 0.7 07:56:35 INFO - mozversion == 1.4 07:56:35 INFO - psutil == 3.1.1 07:56:35 INFO - requests == 1.2.3 07:56:35 INFO - wsgiref == 0.1.2 07:56:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:56:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:56:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:56:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:56:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:56:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:35 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 0x10e188a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e21d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf504714e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0ecd30>, '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 0x10de51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0e4b20>, '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.7PlHkr6UfN/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.o9EyqqVe0D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 07:56:35 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 07:56:35 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 07:56:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7PlHkr6UfN/Render', 07:56:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:56:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:56:35 INFO - 'HOME': '/Users/cltbld', 07:56:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:56:35 INFO - 'LOGNAME': 'cltbld', 07:56:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:56:35 INFO - 'MOZ_NO_REMOTE': '1', 07:56:35 INFO - 'NO_EM_RESTART': '1', 07:56:35 INFO - 'PAGER': '/bin/cat', 07:56:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:56:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:56:35 INFO - 'PWD': '/builds/slave/test', 07:56:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:56:35 INFO - 'SHELL': '/bin/bash', 07:56:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o9EyqqVe0D/Listeners', 07:56:35 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 07:56:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:56:35 INFO - 'USER': 'cltbld', 07:56:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:56:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:56:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:56:35 INFO - 'XPC_FLAGS': '0x0', 07:56:35 INFO - 'XPC_SERVICE_NAME': '0', 07:56:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:56:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:56:35 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)) 07:56:35 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)) 07:56:35 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)) 07:56:35 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)) 07:56:35 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)) 07:56:35 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)) 07:56:35 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)) 07:56:35 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)) 07:56:35 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)) 07:56:35 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)) 07:56:35 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)) 07:56:35 INFO - Cleaning up... 07:56:35 INFO - Return code: 0 07:56:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:56:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:56:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:56:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:56:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:56:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e188a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e21d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf504714e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0ecd30>, '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 0x10de51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0e4b20>, '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.7PlHkr6UfN/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.o9EyqqVe0D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 07:56:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:56:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:56:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7PlHkr6UfN/Render', 07:56:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:56:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:56:35 INFO - 'HOME': '/Users/cltbld', 07:56:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:56:35 INFO - 'LOGNAME': 'cltbld', 07:56:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:56:35 INFO - 'MOZ_NO_REMOTE': '1', 07:56:35 INFO - 'NO_EM_RESTART': '1', 07:56:35 INFO - 'PAGER': '/bin/cat', 07:56:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:56:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:56:35 INFO - 'PWD': '/builds/slave/test', 07:56:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:56:35 INFO - 'SHELL': '/bin/bash', 07:56:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o9EyqqVe0D/Listeners', 07:56:35 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 07:56:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:56:35 INFO - 'USER': 'cltbld', 07:56:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:56:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:56:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:56:35 INFO - 'XPC_FLAGS': '0x0', 07:56:35 INFO - 'XPC_SERVICE_NAME': '0', 07:56:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:56:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:56:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:56:35 INFO - Cleaning up... 07:56:35 INFO - Return code: 0 07:56:35 INFO - Running post-action listener: _resource_record_post_action 07:56:35 INFO - Running post-action listener: _start_resource_monitoring 07:56:35 INFO - Starting resource monitoring. 07:56:35 INFO - [mozharness: 2016-08-23 14:56:35.653084Z] Finished create-virtualenv step (success) 07:56:35 INFO - [mozharness: 2016-08-23 14:56:35.654034Z] Running install step. 07:56:35 INFO - Running pre-action listener: _resource_record_pre_action 07:56:35 INFO - Running main action method: install 07:56:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:56:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:56:35 INFO - Reading from file tmpfile_stdout 07:56:35 INFO - Detecting whether we're running mozinstall >=1.0... 07:56:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:56:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:56:36 INFO - Reading from file tmpfile_stdout 07:56:36 INFO - Output received: 07:56:36 INFO - Usage: mozinstall [options] installer 07:56:36 INFO - Options: 07:56:36 INFO - -h, --help show this help message and exit 07:56:36 INFO - -d DEST, --destination=DEST 07:56:36 INFO - Directory to install application into. [default: 07:56:36 INFO - "/builds/slave/test"] 07:56:36 INFO - --app=APP Application being installed. [default: firefox] 07:56:36 INFO - mkdir: /builds/slave/test/build/application 07:56:36 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'] 07:56:36 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 07:57:10 INFO - Reading from file tmpfile_stdout 07:57:10 INFO - Output received: 07:57:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:57:10 INFO - Running post-action listener: _resource_record_post_action 07:57:10 INFO - [mozharness: 2016-08-23 14:57:10.276402Z] Finished install step (success) 07:57:10 INFO - [mozharness: 2016-08-23 14:57:10.276574Z] Running run-tests step. 07:57:10 INFO - Running pre-action listener: _resource_record_pre_action 07:57:10 INFO - Running main action method: run_tests 07:57:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:57:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:57:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:57:10 INFO - 2016-08-23 07:57:10.289 screenresolution[1910:9480] starting screenresolution argv=screenresolution get 07:57:10 INFO - 2016-08-23 07:57:10.291 screenresolution[1910:9480] Display 0: 1600x1200x32@60 07:57:10 INFO - 2016-08-23 07:57:10.296 screenresolution[1911:9482] starting screenresolution argv=screenresolution list 07:57:10 INFO - Available Modes on Display 0 07:57:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:57:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:57:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:57:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:57:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:57:10 INFO - Intel Iris: 07:57:10 INFO - Chipset Model: Intel Iris 07:57:10 INFO - Type: GPU 07:57:10 INFO - Bus: Built-In 07:57:10 INFO - VRAM (Dynamic, Max): 1536 MB 07:57:10 INFO - Vendor: Intel (0x8086) 07:57:10 INFO - Device ID: 0x0a2e 07:57:10 INFO - Revision ID: 0x0009 07:57:10 INFO - Displays: 07:57:10 INFO - Display: 07:57:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:57:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:57:10 INFO - Display Serial Number: 1600x1200 60 07:57:10 INFO - Main Display: Yes 07:57:10 INFO - Mirror: Off 07:57:10 INFO - Online: Yes 07:57:10 INFO - Rotation: Supported 07:57:10 INFO - Return code: 0 07:57:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:57:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:57:10 INFO - Python 2.7.3 07:57:10 INFO - Return code: 0 07:57:10 INFO - grabbing minidump binary from tooltool 07:57:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:10 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 0x10e0ecd30>, '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 0x10de51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0e4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:57:10 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 07:57:10 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 07:57:10 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 07:57:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:57:10 INFO - Return code: 0 07:57:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:57:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:57:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:57:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:57:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:57:10 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/URUUS0aSTY21SpJMpbTVtA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0006', '--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 07:57:10 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/URUUS0aSTY21SpJMpbTVtA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0006 --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 07:57:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7PlHkr6UfN/Render', 07:57:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:10 INFO - 'HOME': '/Users/cltbld', 07:57:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:10 INFO - 'LOGNAME': 'cltbld', 07:57:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:57:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:57:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:10 INFO - 'MOZ_NO_REMOTE': '1', 07:57:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:57:10 INFO - 'NO_EM_RESTART': '1', 07:57:10 INFO - 'PAGER': '/bin/cat', 07:57:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:10 INFO - 'PWD': '/builds/slave/test', 07:57:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:57:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:10 INFO - 'SHELL': '/bin/bash', 07:57:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o9EyqqVe0D/Listeners', 07:57:10 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 07:57:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:10 INFO - 'USER': 'cltbld', 07:57:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:10 INFO - 'XPC_FLAGS': '0x0', 07:57:10 INFO - 'XPC_SERVICE_NAME': '0', 07:57:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:10 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/URUUS0aSTY21SpJMpbTVtA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0006', '--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 07:57:10 INFO - mozversion application_buildid: 20160823065947 07:57:10 INFO - mozversion application_changeset: 352d2e43336522a95c803d25a48c0df63dff931d 07:57:10 INFO - mozversion application_display_name: Nightly 07:57:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:57:10 INFO - mozversion application_name: Firefox 07:57:10 INFO - mozversion application_remotingname: firefox 07:57:10 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:57:10 INFO - mozversion application_vendor: Mozilla 07:57:10 INFO - mozversion application_version: 51.0a1 07:57:10 INFO - mozversion platform_buildid: 20160823065947 07:57:10 INFO - mozversion platform_changeset: 352d2e43336522a95c803d25a48c0df63dff931d 07:57:10 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:57:10 INFO - mozversion platform_version: 51.0a1 07:57:10 INFO - using testdate: 1471964230 07:57:10 INFO - actual date: 1471964230 07:57:10 INFO - starting webserver on 'localhost:49874' 07:57:10 INFO - SUITE-START | Running 2 tests 07:57:10 INFO - TEST-START | dromaeo_css 07:57:10 INFO - operating with platform_type : mac_ 07:57:10 INFO - Initialising browser for dromaeo_css test... 07:57:10 INFO - TEST-INFO | started process 1921 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpq8kxkl/profile http://localhost:49874/getInfo.html) 07:57:13 INFO - PROCESS | 1921 | __metrics Screen width/height:1600/1200 07:57:13 INFO - PROCESS | 1921 | colorDepth:24 07:57:13 INFO - PROCESS | 1921 | Browser inner width/height: 1024/689 07:57:13 INFO - PROCESS | 1921 | __metrics 07:57:13 INFO - PROCESS | 1921 | 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" 07:57:13 INFO - PROCESS | 1921 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:57:13 INFO - TEST-INFO | 1921: exit 0 07:57:13 INFO - Browser initialized. 07:57:13 INFO - Running cycle 1/1 for dromaeo_css test... 07:57:13 INFO - TEST-INFO | started process 1923 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpq8kxkl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:57:25 INFO - PROCESS | 1923 | RSS: Main: 228446208 07:57:25 INFO - PROCESS | 1923 | 07:58:17 INFO - PROCESS | 1923 | 1471964297058 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] 08:00:29 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:49874/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49874/tests/dromaeo/cssquery-ext.html) 08:00:31 INFO - PROCESS | 1923 | RSS: Main: 325427200 08:00:31 INFO - PROCESS | 1923 | 08:03:34 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:49874/tests/dromaeo/cssquery-ext.html (next: http://localhost:49874/tests/dromaeo/cssquery-jquery.html) 08:03:34 INFO - PROCESS | 1923 | RSS: Main: 367624192 08:03:34 INFO - PROCESS | 1923 | 08:06:37 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:49874/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49874/tests/dromaeo/cssquery-mootools.html) 08:06:37 INFO - PROCESS | 1923 | RSS: Main: 339894272 08:06:37 INFO - PROCESS | 1923 | 08:09:40 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:49874/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49874/tests/dromaeo/cssquery-prototype.html) 08:09:40 INFO - PROCESS | 1923 | RSS: Main: 344694784 08:09:40 INFO - PROCESS | 1923 | 08:12:44 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:49874/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49874/tests/dromaeo/cssquery-yui.html) 08:12:44 INFO - PROCESS | 1923 | RSS: Main: 329555968 08:12:44 INFO - PROCESS | 1923 | 08:15:48 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:49874/tests/dromaeo/cssquery-yui.html (next: http://localhost:49874/tests/dromaeo/cssquery-dojo.html) 08:15:48 INFO - PROCESS | 1923 | RSS: Main: 503005184 08:15:48 INFO - PROCESS | 1923 | 08:15:48 INFO - PROCESS | 1923 | __start_tp_report 08:15:48 INFO - PROCESS | 1923 | _x_x_mozilla_page_load 08:15:48 INFO - PROCESS | 1923 | _x_x_mozilla_page_load_details 08:15:48 INFO - PROCESS | 1923 | |i|pagename|runs| 08:15:48 INFO - PROCESS | 1923 | |0;dojo.html;3321.7840840345375;3343.9164020899475;3347.514610381493;3363.814990175541;3373.5108409280656;5632.014397480435;5702.287214098238;5735.713214339282;5786.508146807521;5841.474267315941;5701.629394089381;5732.942670573293;5750.769969201227;5750.971245143768;5755.079187330018;5159.1229490986625;5230.843074707765;5321.388040375367;5324.440933701963;5347.6256662033675;4166.520850102232;5088.949110508885;5308.362996440433;5316.9202461963105;5331.280277162572;5359.624826262163;5377.4084850666395;5381.892362152755;5386.622936394454;5397.811076612319;5133.820316288912;5304.363476382859;5322.281491998569;5340.359156901158;5373.946260537384;5107.668001579923;5170.948290517084;5205.427402985669;5239.16173412256;5251;5567.4432556744005;5631.436856314377;5636.323641163046;5730.7421166047625;5753.741081651336;5462.6995515246645;6099.115628233925;6203.100550420164;6246.843828904268;6272;6169.074638804171;6194.783182588588;6299.401556852092;6307.117003619497;6312.526560507934;5873.7944171954205;5936.851578710567;5937;5946.554008449383;5950.107483877367;6215.968920155461;6310.211223597051;6317.4946004319545;6335.049742538656;6344.936550634435;5119.6416250862085;6111.694415279218;6181.443670069715;6235.908715974686;6249.093881387264;6293.3391993840905;6294.968525157345;6310.305866354697;6314.810555683337;6322.083297921776;6090.3605121461605;6218.720157592919;6251.468625166821;6257.374262573706;6259.217597800275;6190.690465476709;6197.876042479125;6253.968095264321;6274.247090349096;6325.746970121144;5000.949990500121;5037.471065538066;5039.596832253411;5043.974780126149;5047;4398.69209155356;4520.909581808345;4544.386507821468;4550.931736023962;4561.315802629566;5656.236408084856;5718;5740.052891272893;5787.479126878601;5789.623674461147;6287.559870809091;6296.811095667137;6298;6300.432961033529;6306.369363063657;5608.803691870765;5760.193572899798;5777.306723193244;5779.508741756998;5804.158397032491;5371.516563509303;5380.973095134499;5405.21624364465;5450.127979523337;5484.2596249506605;6297.4647154992335;6302.1177035215105;6304.495640348853;6307.400796924285;6312.968435157978;6306.616901253585;6312.3056463788025;6320.936790632035;6325.177726895474;6327.936720632735;6195.504359651127;6219.937800621936;6293.433590976834;6302.527310451643;6350.936490635035;6251.468625166912;6270.968645156928;6272.121902933684;6296.5592408530965;6301.023341382093;6249.375062493714;6253.624782513064;6300.464460520907;6329.367063293634;6336.96831515858;6018.548608854441;6090.269167699905;6093.177421048102;6124.846878828065;6165.630062196283;5980.073088671263;6057.606255593372;6100.23747031621;6126.1423400724825;6166.660833654192;5009.398872135367;5811.767529298781;6009.278886533469;6021.2473440819895;6025.276966764016;6047.183630210041;6047.9395206047375;6058.757649693963;6110.7861224856915;6120.418560236771;5888.970555147197;5960.5231581474245;5966.19456373384;6042;6050.90923636137 08:15:48 INFO - PROCESS | 1923 | |1;ext.html;519.2107995846313;523.5915985531287;529.4652401074915;544.8394918822906;551.8703104770376;36732;39596;39628.2074358513;39686;39745.801270993674;40195.79902100485;40775.796121019426;40828.59171408285;40860;40901;8781.1218878112;8991.460512369245;9603.231741460666;9891.901080989206;11807.94096029523;11775.823362649568;12093.939530302336;12100.15298929071;12102.394880255952;12124.636260912186;12012.819807702892;12043.578474753385;12056.095792815573;12083.91244787969;12112.333821639806;11966.461509232062;12021.759564808699;12034;12045.63863084109;12064.698382540506;11965.700857478545;11975.28148311104;11978.460969256404;11982.64052078439;12029.218100823506;39617;39943.00142496432;39944.60055399438;39953.400698989244;40002.999925001815;2905.3608206194604;3528.947065794015;3649.9780061582533;3690.2435000824767;3887.4751908492276;31004.844975774977;31326.530102048488;32218.033458996266;32310;32312.35375292481;3301.405746965569;3364;3379.915502112467;3380.7126394256516;3457.7233821294235;374697;377853;377896;377936;378509;56718.43281567214;57023.71488142533;57029.71485142631;57068.714656426455;57293.40598910197;55869;55942;55955.44044559502;56008;56070.439295607335;15599.376024958874;15915.920420397824;15928.283227254802;15944.2825072872;15945;10436.321639093434;11238.325700458;11419.429028548539;12186.08604354677;12431.37843107841;14750.55748327552;14753.557393278219;14779.926100369645;14834.777478337834;15066.020708653903;5123.38519377677;5421.620486565981;5942.732577034044;6127.846803829851;6129.965666994866;865.8614621660504;931.6971984105193;940.8024314893948;993.5280741647659;1008.5310330696261;24192.395190120034;29930.70069299323;30228.84885575558;30873.536896946564;30974.845125774227;955.6082006377352;1062.2458054781036;1085.6905781852133;1086.4676308608882;1242.211195890616;14196;15053.322600483005;15608.765868511982;15708.842911570966;15747.68504629901;678895;691856;695005;696609;697572;57958.840823184146;58209.70895145582;58318.25045248649;58390;58390.7080464595;1248.3820508848178;1300.746354460871;1372.8283964504437;1403.7052219033912;1425.8289005319427;30481.695183048774;30910.0726978191;31570.526442102928;32175;32208.83895580507;53401;53954.46045539495;53981;54014.459855402514;54053.45946540641;622970;626904;626948;628624;629972;33031;33764.66235337615;34668.30663386769;39078.13827925834;39422.21155576815;38996.415053774785;39789.40315895206;47288.52711472979;47935.520644793105;48114.518854811;38461.615383845805;38704.80647596744;38726.22547549089;40387;40409.59590404058;38399.80800095982;38918.832435027;39387.80306098451;40401.98995025148;40450;35989;36327.636723633485;36423.45364819583;36424.81787591045;38648;28818.567721483774;32292.677073228966;33433;33731.76479947444;34079.65920340864;24879.378015549755;25067.122650707137;25235.495290094463;25277.98888044531;25757.484850302513 08:15:48 INFO - PROCESS | 1923 | |2;jquery.html;380.68783597450084;380.72968192583267;386.7930657098453;399.7841165770484;410.0855093142293;19143.138558764855;20969.895150524226;20970.16119355226;21179.68230476544;21273.68089478659;19883.40349789505;21123.683144752842;21143.471413214676;21439.785602144013;21601.67597486035;5796.9130463042875;5807.709614519278;6040.848978775543;6106.877862442749;6110;3982.183652351261;4185.518665353493;5915.9704201478935;6012.879742405149;6442.484601231914;6707.228668703088;6709.09427227323;6757.054012438262;6764.560303580276;6791.626460544668;6408.134901788246;6597.967010164943;6655.600663960178;6657.667116644148;6666.966665166643;5277;5555.666660000413;5732.484076433141;5756.942430575683;5758.74085666146;13394.06241563091;13607.77530022293;13648.863511364858;13662.590122296348;23664.290071297888;2151.4836439254623;2165.6859755335545;2175.7280339957347;2240.529488807352;2949.1447480230922;8868.600912958933;9521.91710050467;10032.849507257324;10297.433641149733;10332.018458246455;1807.2319264312614;1825.5983683589589;1862.2638702959907;1873.531617095726;1913.339897735293;120131;125011;125725;128209;128251;11075.849729875647;11253.268537545033;11680.299182049104;11701.88298117008;12013.699657508632;14476.27618619065;14483.275836208148;14488.565343039725;14496.202708851115;14500.202488863213;8149.470284431493;8312.251897329148;8359.247667709813;8434.493930364199;8444.61999210037;8309.293710034593;8350.958245208734;8351.791205219797;8517.48895066298;8522.659093636184;4447.755373454491;4524.253498172765;4529.84145554904;4606.746628935373;4616.538346165356;1601.3994751968012;1754.2895127473394;1825.2698920431933;1854.7774267088037;1922.8365588925099;798.596708662122;895.5477483870603;902.9774255643662;927.0821886332617;974.7465658928729;16572.58568535772;21597;21644.242451514438;21691.240806571695;21841;800.3437181511105;940.1031336771177;941.7928055827707;944;978.8678528398717;7872.724454644174;10957.671269861758;11320.320780753182;11369.772604548029;11383.658490245305;589744;598165;601878.9906050442;620643;621219;90536;111694.44152779346;137627.31186344003;138692;140251.29874350768;853.620139038122;956.8325543029941;982.4792859784326;999.7300728803183;1002.4686915934566;15156.696866062837;18617.813821861608;18775.624487510446;18854.6229075415;19660.21359145618;51941.7402912983;52552;52914;53023.20465192948;53559.12543060974;79899;80404;80613;81239.78140328018;81603.18396816193;7060.964695176696;7493.400527957641;7655.732049378245;7812.679611165346;8091.1099779025435;12025.819612705991;12287.201331913755;12569.622911312676;12973.481060757464;12990.155639883378;10918.727031824268;10938.179636527451;11160.274582152135;11215.943920280346;11276.830847537458;5068.974655126701;7624.504407213513;10757.246992710749;10906.291091079054;11149.665510034712;9609.039096090335;11108.666739997812;11182;11482.425878705864;11559.30644161353;7895.368370530458;7915.44591878586;7936.841263174589;7979.80050498719;8083.312918401766;10820.404877731486;10940.890591093987;11650.88349116498;11745.88254117482;12010 08:15:48 INFO - PROCESS | 1923 | |3;mootools.html;1500.8499150084992;1544.7914531538242;1704.2160606121183;1825.3611236067386;1874.6438176746417;8231.547264900428;8784.077671844445;8793.2965362771;8813.294936405087;8824;7686.423518236126;7902.762917112482;8338.708145214903;8541.530215838126;8618.827623447527;2359.6342566902113;2383.356493746687;2387.8567285962813;2389.247387073075;2391.5934291170456;2347.753485883983;2370.5614461324626;2397.968873384444;2398.556267090585;2400.3759022654062;2091.602595506849;2176.4341271269427;2311.1448763957274;2381.3451300892307;2389.390705370137;2370.881455927214;2380.1907351500486;2408.843425177358;2411.8191135664806;2414.2515820095828;2371.786539211479;2377.4413012941945;2378.7621237876247;2380.7143142822797;2381.0594815048134;8762.386632935697;8801.163889430494;8806.515641639708;8818.867716984187;8823.779405514913;5142.408623008327;5233.869153271199;5299.33758280215;5302;5306.522412982811;3376.4428869236544;3410.2497450560836;3548.4677298405186;3558.5551806024246;3571.160777217352;1068.9518971646294;1107.3134656512932;1113.8440618313443;1129.2151954391702;1131.9264247823864;11398.088152947827;11472.311661300266;11488.540458381654;11489.42552872353;11505.424728763528;4486.573775491323;4584.684734209203;4768.737719425465;4791.9936813268905;4800.3279540864305;8890.710846927159;9403.529823508798;9791.265655075898;9796.804063918682;9803.803923921481;5298.364196296469;5306.8407947761625;5310.6813591184655;5310.893782124336;5311.070562651521;4686.742229177428;4834.081524510402;4866.099771542276;4872.829450969199;4882.145624515695;3727.1613886875234;3749.362608356586;3800.244822503718;3841.9231615367535;3846.9807650961566;941.9340646154841;1180.681216071673;1187.8574571051356;1195.1574140231116;1199.33436942496;719.8092505486051;758.7989182866545;785.6464590934103;864.5590748718073;921.7050044687226;5535.280413546294;5723.255976723082;5847.532197424196;5901.970490147522;5942.762289508458;941.9387739796891;946.3091942881734;946.6450081219542;957.7788319892054;988.8763904511936;3884.6503814656817;3893.7274390792372;3916.5104361954754;4035.6771458283265;4038.293298672779;10326.638567650096;10784.56861725538;10789.730256743644;10815;10817.513211905483;10717.089047431056;10723.410212438235;10753.623623173151;10763.892361076289;10778.245522813484;7656.310932016146;7970.123286438487;7990.280874721303;8179;8195.303399211134;5654.293213348332;5671.574631902707;5750.079987202028;5806.332271788629;5896.086106653475;8515.574221289035;9670.903290967;10014.799704005733;10043.849342260019;10057.346272492265;9694.982026887357;9999.35004224723;10047.447390393307;10267.229957753048;10274.075333220046;6920.757773477904;7064.788056358318;7078.504504684623;7090.964545177241;7120.8219794503475;8206.466579672302;8272.793180170303;8277.668893244203;8283.50298982063;8290.295324897452;7210.2789721027475;7251.8187045324285;7495.287947644965;7532.133804612539;7585.58279294622;7286.125664920243;7325;7357.5217610855125;7421.740239091606;7422.480426370318;5888.617239879394;6636.13730215069;6842.042114104129;6957.582545047313;7031.488230030568;4681.227597446383;4795.856124316137;4859.8299059534165;4942.308076869169;5120.359955005624;3919.8236079375924;4150.833966641421;4740.739259340769;4808.110499557593;4854.320395144612 08:15:48 INFO - PROCESS | 1923 | |4;prototype.html;300.6662604508995;306.5738623313594;315.52198419394614;319.44257271062;320.1819351556773;4534.841280555185;5223.973880130604;5369.382521010085;5438.755256013479;5503.422140675231;4305.643722227496;5095.184770436731;5314.362276526813;5324;5353.491418315254;3535.8331750522393;3784.526934133233;4083.9795801020955;4100.548939616655;4110.938335924963;3221.822799746013;3423.4522476403654;3636.345462814413;3741.420079887608;4130.607592278729;3350.8942049123852;4073.63337299643;4075.714699971003;4100.323446631302;4113.441872780054;4058.228936502055;4067.6373414905893;4079.796010199478;4083.3262511685534;4141.213169497786;3536.664016918389;3544.503769472276;3545.18460754028;3556.5198698175673;3557.3063252665743;4839.039984016376;4987.476314986947;4994;5302.73486325686;5353.705546194958;4617.838375656869;4652.302154676791;4667.836625718083;4674.462436819776;4676.976615116937;5761.596688231783;5794.406538201986;5798.478136967651;5831.854203644943;5841.56188285876;3516.4901089342156;3630.382834918044;3757.28611563802;3876.7480113792512;3899.7075219358376;4906.546726636681;5996.880062398727;5997.820065398001;6001.729922153513;6023.216981792383;3391.576052993376;3525.7884526927955;3595.40675788492;3875.817875547985;3927.489426374553;3380.814055226986;3775.7734535927384;3925.4111883217747;3946.644801967837;3994.960050399517;3715.4426835974277;4615.246206441598;4619.769011549476;4629.953700462952;4665.0903073900945;5005.599552035828;5780.595358324961;5781.942180578225;5799.463142267303;5812.82561523155;1074.7581794096423;1170.795110855597;1264.582687713071;1298.7077907470932;1348.267476292959;770.876659734451;887.8668199770086;897.6409436225562;914.291424146292;922.1700469577434;4596.311157972556;5250.632455728062;5263.7629007671585;5313.521783039545;5361.705106219118;4686.5782079613;4805.077968812433;4947.282644016597;5474.7262636868;5770.740316685759;4161.812718427678;4362.781860906942;4398.912021759611;4635.605973492291;5221.869453263699;2391.210900402863;2391.7249516305847;2588.301158687144;2605.1533251692895;2624.386002608382;5038.974805125951;5051.494850514992;5058.519440653132;5084.542391184811;5088.913545446349;4435.911281774346;4583.931241031387;4764.927891224516;4823.107725070873;4962.60299176065;4188.889944164738;4652.627789776742;4740.771837236493;4834.250691142878;5032.84901452957;4241.809118589733;4593.116822102922;4876.756162191948;4906.63200259989;4968.602511798975;4129;4134.780239829198;4543.3639290498695;4869.2957464887;4871.634627402888;4548.11311794197;4767.976160119178;5093.668911520887;5095.388553373619;5111.744412779421;2825.2513084033158;3384.8984530464127;3412.7952322860706;3426.2633533790354;3470.496777967231;3956.090099277125;4157.542670306324;4430.401895744033;5064.9746751267485;5610.4670056344585;4927.630427717864;5167.22491626259;5683.60214784961;5763.394843541536;5787.160861675117;4359.795550270612;4631.9536804631525;4900.264960255848;5226.4912841836895;5677.489025987681;4834.395700537433;4997.900041999213;5619.353774315842;5628.802991895264;5644.407337229696;4659.906801863876;5475.616706830643;5779.335376431596;5803.448672376117;5852.717440198411;4729.456112546964;4874.635801636133;5615.213870058114;5680.517156041617;5707.6575405475805 08:15:48 INFO - PROCESS | 1923 | |5;yui.html;713.7255680520948;723.6996646391746;772.3280745751193;823.9670413183472;832.3466079127886;15325.386984520619;16249.431269905555;16273.674526509463;16276.918615406936;16307.429239976573;14757.557273281818;14897.627559311022;14921.477748278812;14937.551873443814;14968.401263949429;688.8381230410849;706.9858602827941;709.1844378964181;843.9113893041234;861.3755027604999;659.5844617890722;856.3791251342789;861.483110133921;862.5342315149811;864.2308345572415;916.9816603667923;917.4220241248005;918.2470374293049;919.8804155459815;922.8015976565038;910.0444533240137;911.9908800911973;911.9908800912006;912.0150237743221;912.8767616371805;535.2720300391464;653.6600967496875;676.5264314979534;679.3647939176836;686.0498209979165;14743.557693269218;14783;14807.851921480755;14808.111513309128;14809.88925830567;13606.863931360658;14000.789988150185;14077.296135193303;14158.716825663429;14376.568702938928;10173.949130254376;11583;12213.816792748115;12657.746845063048;12982;4671.11248862715;4671.43942726875;4721.5750582447745;4838.709677419366;4891.315215869781;110240;113963.43018284938;114329.42835285854;115062;116844.83155168424;10669.733256668645;10930.234883558074;10968.780624387628;11014.06380457654;11126;20789.85655788946;20809.791902080786;20819.271325503534;20851.165953362;20888.37334879956;7704.229577042363;7912.642832646606;7992.600369981477;7994.280514753584;7996.200379961957;4423.800928958204;4428.822847086145;4561.2245918193985;4681.859544213679;4684.461286951976;3389.932201355959;3459.39460594395;3513.6662017108333;3515.5078289039557;3517.6984515729346;606.848287928018;633.4488994574749;644.1658052821607;645.3288579876969;649.3052433895687;571.5970240980099;585.7627660797384;589.9144624029577;596.9522438204933;655.7508146904241;7812.6093695315;8868.8669669955;8937.285017198608;9033.954830225806;9129.817403652023;536.570743405282;537.3363895588944;596.436367632583;597.7788218359235;611.6238513314369;4293.509049166587;4548.522405147478;4634;4868.488808675109;5023.14606516893;150107;153289;155550;162184.5378554581;166713.16643416949;142979.2851035759;143450.2827485856;145175;148045.25977370044;148234.25882870518;107129;115724;117070;117279.16731241344;117543.824561755;9760.256994982889;9770.117795394368;10024.14794742455;10126.392416455037;10138.746531336776;57949;59908.400915992024;61207.08189377074;63530.68234658797;64893.026604600025;77279.61360193163;78261.43477130539;82209.58895205485;89270;93091.53454232686;13389.263590502616;13496.932515337361;13662;14102.224377659328;14181.290935453393;14823.555293341216;15283;15316.38734450654;15386.9230653846;15651.921740391224;14167.716645667235;14178.787318190442;14469.27653617336;14662.780058299348;14675.633109172357;11349.4892729829;13694.38375273135;14002.299885005912;14246.28768561547;14465.421383144978;13530.120542164728;13791.724165516433;14111.22388268614;14255.50105746294;14390.784138238145;11306.60426885088;11372.943135284602;11519.25124866881;11635.650930472098;11899.345535995604;8723.502970859996;10090;11069.83395249088;11111.888881111085;11404.429778510876 08:15:48 INFO - PROCESS | 1923 | __end_tp_report 08:15:48 INFO - PROCESS | 1923 | __start_cc_report 08:15:48 INFO - PROCESS | 1923 | _x_x_mozilla_cycle_collect,2049 08:15:48 INFO - PROCESS | 1923 | __end_cc_report 08:15:48 INFO - PROCESS | 1923 | __startTimestamp1471965348563__endTimestamp 08:15:48 INFO - PROCESS | 1923 | 08:15:48 INFO - PROCESS | 1923 | ------- Summary: start ------- 08:15:48 INFO - PROCESS | 1923 | Number of tests: 6 08:15:48 INFO - PROCESS | 1923 | 08:15:48 INFO - PROCESS | 1923 | [#0] dojo.html Cycles:165 Average:5764.11 Median:5980.07 stddev:645.77 (10.8%) stddev-sans-first:618.67 08:15:48 INFO - PROCESS | 1923 | Values: 3321.8 3343.9 3347.5 3363.8 3373.5 5632.0 5702.3 5735.7 5786.5 5841.5 5701.6 5732.9 5750.8 5751.0 5755.1 5159.1 5230.8 5321.4 5324.4 5347.6 4166.5 5088.9 5308.4 5316.9 5331.3 5359.6 5377.4 5381.9 5386.6 5397.8 5133.8 5304.4 5322.3 5340.4 5373.9 5107.7 5170.9 5205.4 5239.2 5251.0 5567.4 5631.4 5636.3 5730.7 5753.7 5462.7 6099.1 6203.1 6246.8 6272.0 6169.1 6194.8 6299.4 6307.1 6312.5 5873.8 5936.9 5937.0 5946.6 5950.1 6216.0 6310.2 6317.5 6335.0 6344.9 5119.6 6111.7 6181.4 6235.9 6249.1 6293.3 6295.0 6310.3 6314.8 6322.1 6090.4 6218.7 6251.5 6257.4 6259.2 6190.7 6197.9 6254.0 6274.2 6325.7 5000.9 5037.5 5039.6 5044.0 5047.0 4398.7 4520.9 4544.4 4550.9 4561.3 5656.2 5718.0 5740.1 5787.5 5789.6 6287.6 6296.8 6298.0 6300.4 6306.4 5608.8 5760.2 5777.3 5779.5 5804.2 5371.5 5381.0 5405.2 5450.1 5484.3 6297.5 6302.1 6304.5 6307.4 6313.0 6306.6 6312.3 6320.9 6325.2 6327.9 6195.5 6219.9 6293.4 6302.5 6350.9 6251.5 6271.0 6272.1 6296.6 6301.0 6249.4 6253.6 6300.5 6329.4 6337.0 6018.5 6090.3 6093.2 6124.8 6165.6 5980.1 6057.6 6100.2 6126.1 6166.7 5009.4 5811.8 6009.3 6021.2 6025.3 6047.2 6047.9 6058.8 6110.8 6120.4 5889.0 5960.5 5966.2 6042.0 6050.9 08:15:48 INFO - PROCESS | 1923 | 08:15:48 INFO - PROCESS | 1923 | [#1] ext.html Cycles:180 Average:69987.89 Median:20190.99 stddev:155850.73 (771.9%) stddev-sans-first:156200.66 08:15:48 INFO - PROCESS | 1923 | Values: 519.2 523.6 529.5 544.8 551.9 36732.0 39596.0 39628.2 39686.0 39745.8 40195.8 40775.8 40828.6 40860.0 40901.0 8781.1 8991.5 9603.2 9891.9 11807.9 11775.8 12093.9 12100.2 12102.4 12124.6 12012.8 12043.6 12056.1 12083.9 12112.3 11966.5 12021.8 12034.0 12045.6 12064.7 11965.7 11975.3 11978.5 11982.6 12029.2 39617.0 39943.0 39944.6 39953.4 40003.0 2905.4 3528.9 3650.0 3690.2 3887.5 31004.8 31326.5 32218.0 32310.0 32312.4 3301.4 3364.0 3379.9 3380.7 3457.7 374697.0 377853.0 377896.0 377936.0 378509.0 56718.4 57023.7 57029.7 57068.7 57293.4 55869.0 55942.0 55955.4 56008.0 56070.4 15599.4 15915.9 15928.3 15944.3 15945.0 10436.3 11238.3 11419.4 12186.1 12431.4 14750.6 14753.6 14779.9 14834.8 15066.0 5123.4 5421.6 5942.7 6127.8 6130.0 865.9 931.7 940.8 993.5 1008.5 24192.4 29930.7 30228.8 30873.5 30974.8 955.6 1062.2 1085.7 1086.5 1242.2 14196.0 15053.3 15608.8 15708.8 15747.7 678895.0 691856.0 695005.0 696609.0 697572.0 57958.8 58209.7 58318.3 58390.0 58390.7 1248.4 1300.7 1372.8 1403.7 1425.8 30481.7 30910.1 31570.5 32175.0 32208.8 53401.0 53954.5 53981.0 54014.5 54053.5 622970.0 626904.0 626948.0 628624.0 629972.0 33031.0 33764.7 34668.3 39078.1 39422.2 38996.4 39789.4 47288.5 47935.5 48114.5 38461.6 38704.8 38726.2 40387.0 40409.6 38399.8 38918.8 39387.8 40402.0 40450.0 35989.0 36327.6 36423.5 36424.8 38648.0 28818.6 32292.7 33433.0 33731.8 34079.7 24879.4 25067.1 25235.5 25278.0 25757.5 08:15:48 INFO - PROCESS | 1923 | 08:15:48 INFO - PROCESS | 1923 | [#2] jquery.html Cycles:180 Average:35089.58 Median:383.76 stddev:101357.76 (26411.7%) stddev-sans-first:101608.58 08:15:48 INFO - PROCESS | 1923 | Values: 380.7 380.7 386.8 399.8 410.1 19143.1 20969.9 20970.2 21179.7 21273.7 19883.4 21123.7 21143.5 21439.8 21601.7 5796.9 5807.7 6040.8 6106.9 6110.0 3982.2 4185.5 5916.0 6012.9 6442.5 6707.2 6709.1 6757.1 6764.6 6791.6 6408.1 6598.0 6655.6 6657.7 6667.0 5277.0 5555.7 5732.5 5756.9 5758.7 13394.1 13607.8 13648.9 13662.6 23664.3 2151.5 2165.7 2175.7 2240.5 2949.1 8868.6 9521.9 10032.8 10297.4 10332.0 1807.2 1825.6 1862.3 1873.5 1913.3 120131.0 125011.0 125725.0 128209.0 128251.0 11075.8 11253.3 11680.3 11701.9 12013.7 14476.3 14483.3 14488.6 14496.2 14500.2 8149.5 8312.3 8359.2 8434.5 8444.6 8309.3 8351.0 8351.8 8517.5 8522.7 4447.8 4524.3 4529.8 4606.7 4616.5 1601.4 1754.3 1825.3 1854.8 1922.8 798.6 895.5 903.0 927.1 974.7 16572.6 21597.0 21644.2 21691.2 21841.0 800.3 940.1 941.8 944.0 978.9 7872.7 10957.7 11320.3 11369.8 11383.7 589744.0 598165.0 601879.0 620643.0 621219.0 90536.0 111694.4 137627.3 138692.0 140251.3 853.6 956.8 982.5 999.7 1002.5 15156.7 18617.8 18775.6 18854.6 19660.2 51941.7 52552.0 52914.0 53023.2 53559.1 79899.0 80404.0 80613.0 81239.8 81603.2 7061.0 7493.4 7655.7 7812.7 8091.1 12025.8 12287.2 12569.6 12973.5 12990.2 10918.7 10938.2 11160.3 11215.9 11276.8 5069.0 7624.5 10757.2 10906.3 11149.7 9609.0 11108.7 11182.0 11482.4 11559.3 7895.4 7915.4 7936.8 7979.8 8083.3 10820.4 10940.9 11650.9 11745.9 12010.0 08:15:48 INFO - PROCESS | 1923 | 08:15:48 INFO - PROCESS | 1923 | [#3] mootools.html Cycles:180 Average:5622.63 Median:4869.46 stddev:3150.95 (64.7%) stddev-sans-first:3144.56 08:15:48 INFO - PROCESS | 1923 | Values: 1500.8 1544.8 1704.2 1825.4 1874.6 8231.5 8784.1 8793.3 8813.3 8824.0 7686.4 7902.8 8338.7 8541.5 8618.8 2359.6 2383.4 2387.9 2389.2 2391.6 2347.8 2370.6 2398.0 2398.6 2400.4 2091.6 2176.4 2311.1 2381.3 2389.4 2370.9 2380.2 2408.8 2411.8 2414.3 2371.8 2377.4 2378.8 2380.7 2381.1 8762.4 8801.2 8806.5 8818.9 8823.8 5142.4 5233.9 5299.3 5302.0 5306.5 3376.4 3410.2 3548.5 3558.6 3571.2 1069.0 1107.3 1113.8 1129.2 1131.9 11398.1 11472.3 11488.5 11489.4 11505.4 4486.6 4584.7 4768.7 4792.0 4800.3 8890.7 9403.5 9791.3 9796.8 9803.8 5298.4 5306.8 5310.7 5310.9 5311.1 4686.7 4834.1 4866.1 4872.8 4882.1 3727.2 3749.4 3800.2 3841.9 3847.0 941.9 1180.7 1187.9 1195.2 1199.3 719.8 758.8 785.6 864.6 921.7 5535.3 5723.3 5847.5 5902.0 5942.8 941.9 946.3 946.6 957.8 988.9 3884.7 3893.7 3916.5 4035.7 4038.3 10326.6 10784.6 10789.7 10815.0 10817.5 10717.1 10723.4 10753.6 10763.9 10778.2 7656.3 7970.1 7990.3 8179.0 8195.3 5654.3 5671.6 5750.1 5806.3 5896.1 8515.6 9670.9 10014.8 10043.8 10057.3 9695.0 9999.4 10047.4 10267.2 10274.1 6920.8 7064.8 7078.5 7091.0 7120.8 8206.5 8272.8 8277.7 8283.5 8290.3 7210.3 7251.8 7495.3 7532.1 7585.6 7286.1 7325.0 7357.5 7421.7 7422.5 5888.6 6636.1 6842.0 6957.6 7031.5 4681.2 4795.9 4859.8 4942.3 5120.4 3919.8 4150.8 4740.7 4808.1 4854.3 08:15:48 INFO - PROCESS | 1923 | 08:15:48 INFO - PROCESS | 1923 | [#4] prototype.html Cycles:180 Average:4306.17 Median:4671.15 stddev:1333.19 (28.5%) stddev-sans-first:1302.59 08:15:48 INFO - PROCESS | 1923 | Values: 300.7 306.6 315.5 319.4 320.2 4534.8 5224.0 5369.4 5438.8 5503.4 4305.6 5095.2 5314.4 5324.0 5353.5 3535.8 3784.5 4084.0 4100.5 4110.9 3221.8 3423.5 3636.3 3741.4 4130.6 3350.9 4073.6 4075.7 4100.3 4113.4 4058.2 4067.6 4079.8 4083.3 4141.2 3536.7 3544.5 3545.2 3556.5 3557.3 4839.0 4987.5 4994.0 5302.7 5353.7 4617.8 4652.3 4667.8 4674.5 4677.0 5761.6 5794.4 5798.5 5831.9 5841.6 3516.5 3630.4 3757.3 3876.7 3899.7 4906.5 5996.9 5997.8 6001.7 6023.2 3391.6 3525.8 3595.4 3875.8 3927.5 3380.8 3775.8 3925.4 3946.6 3995.0 3715.4 4615.2 4619.8 4630.0 4665.1 5005.6 5780.6 5781.9 5799.5 5812.8 1074.8 1170.8 1264.6 1298.7 1348.3 770.9 887.9 897.6 914.3 922.2 4596.3 5250.6 5263.8 5313.5 5361.7 4686.6 4805.1 4947.3 5474.7 5770.7 4161.8 4362.8 4398.9 4635.6 5221.9 2391.2 2391.7 2588.3 2605.2 2624.4 5039.0 5051.5 5058.5 5084.5 5088.9 4435.9 4583.9 4764.9 4823.1 4962.6 4188.9 4652.6 4740.8 4834.3 5032.8 4241.8 4593.1 4876.8 4906.6 4968.6 4129.0 4134.8 4543.4 4869.3 4871.6 4548.1 4768.0 5093.7 5095.4 5111.7 2825.3 3384.9 3412.8 3426.3 3470.5 3956.1 4157.5 4430.4 5065.0 5610.5 4927.6 5167.2 5683.6 5763.4 5787.2 4359.8 4632.0 4900.3 5226.5 5677.5 4834.4 4997.9 5619.4 5628.8 5644.4 4659.9 5475.6 5779.3 5803.4 5852.7 4729.5 4874.6 5615.2 5680.5 5707.7 08:15:48 INFO - PROCESS | 1923 | 08:15:48 INFO - PROCESS | 1923 | [#5] yui.html Cycles:180 Average:25695.91 Median:20869.77 stddev:41816.51 (200.4%) stddev-sans-first:41891.75 08:15:48 INFO - PROCESS | 1923 | Values: 713.7 723.7 772.3 824.0 832.3 15325.4 16249.4 16273.7 16276.9 16307.4 14757.6 14897.6 14921.5 14937.6 14968.4 688.8 707.0 709.2 843.9 861.4 659.6 856.4 861.5 862.5 864.2 917.0 917.4 918.2 919.9 922.8 910.0 912.0 912.0 912.0 912.9 535.3 653.7 676.5 679.4 686.0 14743.6 14783.0 14807.9 14808.1 14809.9 13606.9 14000.8 14077.3 14158.7 14376.6 10173.9 11583.0 12213.8 12657.7 12982.0 4671.1 4671.4 4721.6 4838.7 4891.3 110240.0 113963.4 114329.4 115062.0 116844.8 10669.7 10930.2 10968.8 11014.1 11126.0 20789.9 20809.8 20819.3 20851.2 20888.4 7704.2 7912.6 7992.6 7994.3 7996.2 4423.8 4428.8 4561.2 4681.9 4684.5 3389.9 3459.4 3513.7 3515.5 3517.7 606.8 633.4 644.2 645.3 649.3 571.6 585.8 589.9 597.0 655.8 7812.6 8868.9 8937.3 9034.0 9129.8 536.6 537.3 596.4 597.8 611.6 4293.5 4548.5 4634.0 4868.5 5023.1 150107.0 153289.0 155550.0 162184.5 166713.2 142979.3 143450.3 145175.0 148045.3 148234.3 107129.0 115724.0 117070.0 117279.2 117543.8 9760.3 9770.1 10024.1 10126.4 10138.7 57949.0 59908.4 61207.1 63530.7 64893.0 77279.6 78261.4 82209.6 89270.0 93091.5 13389.3 13496.9 13662.0 14102.2 14181.3 14823.6 15283.0 15316.4 15386.9 15651.9 14167.7 14178.8 14469.3 14662.8 14675.6 11349.5 13694.4 14002.3 14246.3 14465.4 13530.1 13791.7 14111.2 14255.5 14390.8 11306.6 11372.9 11519.3 11635.7 11899.3 8723.5 10090.0 11069.8 11111.9 11404.4 08:15:48 INFO - PROCESS | 1923 | -------- Summary: end -------- 08:15:48 INFO - PROCESS | 1923 | 08:15:49 INFO - TEST-INFO | 1923: exit 0 08:15:49 INFO - TEST-OK | dromaeo_css | took 1118302ms 08:15:49 INFO - TEST-START | kraken 08:15:49 INFO - operating with platform_type : mac_ 08:15:49 INFO - Initialising browser for kraken test... 08:15:49 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpb7LwJ3/profile http://localhost:49874/getInfo.html) 08:15:51 INFO - PROCESS | 1928 | __metrics Screen width/height:1600/1200 08:15:51 INFO - PROCESS | 1928 | colorDepth:24 08:15:51 INFO - PROCESS | 1928 | Browser inner width/height: 1024/689 08:15:51 INFO - PROCESS | 1928 | __metrics 08:15:51 INFO - PROCESS | 1928 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:15:51 INFO - PROCESS | 1928 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:15:52 INFO - TEST-INFO | 1928: exit 0 08:15:52 INFO - Browser initialized. 08:15:52 INFO - Running cycle 1/1 for kraken test... 08:15:52 INFO - TEST-INFO | started process 1929 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpb7LwJ3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:16:04 INFO - PROCESS | 1929 | RSS: Main: 228462592 08:16:04 INFO - PROCESS | 1929 | 08:16:07 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49874/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49874/tests/kraken/driver.html?testName=audio-beat-detection) 08:16:08 INFO - PROCESS | 1929 | RSS: Main: 293888000 08:16:08 INFO - PROCESS | 1929 | 08:16:10 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49874/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49874/tests/kraken/driver.html?testName=audio-dft) 08:16:11 INFO - PROCESS | 1929 | RSS: Main: 294793216 08:16:11 INFO - PROCESS | 1929 | 08:16:14 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49874/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49874/tests/kraken/driver.html?testName=audio-fft) 08:16:15 INFO - PROCESS | 1929 | RSS: Main: 382099456 08:16:15 INFO - PROCESS | 1929 | 08:16:17 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49874/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49874/tests/kraken/driver.html?testName=audio-oscillator) 08:16:17 INFO - PROCESS | 1929 | RSS: Main: 282562560 08:16:17 INFO - PROCESS | 1929 | 08:16:20 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49874/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49874/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:16:20 INFO - PROCESS | 1929 | RSS: Main: 286478336 08:16:20 INFO - PROCESS | 1929 | 08:16:23 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49874/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49874/tests/kraken/driver.html?testName=imaging-darkroom) 08:16:24 INFO - PROCESS | 1929 | RSS: Main: 323297280 08:16:24 INFO - PROCESS | 1929 | 08:16:26 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49874/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49874/tests/kraken/driver.html?testName=imaging-desaturate) 08:16:27 INFO - PROCESS | 1929 | RSS: Main: 324784128 08:16:27 INFO - PROCESS | 1929 | 08:16:30 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49874/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49874/tests/kraken/driver.html?testName=json-parse-financial) 08:16:30 INFO - PROCESS | 1929 | RSS: Main: 324001792 08:16:30 INFO - PROCESS | 1929 | 08:16:32 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49874/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49874/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:16:33 INFO - PROCESS | 1929 | RSS: Main: 302067712 08:16:33 INFO - PROCESS | 1929 | 08:16:35 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49874/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49874/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:16:35 INFO - PROCESS | 1929 | RSS: Main: 304193536 08:16:35 INFO - PROCESS | 1929 | 08:16:37 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49874/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49874/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:16:38 INFO - PROCESS | 1929 | RSS: Main: 301195264 08:16:38 INFO - PROCESS | 1929 | 08:16:41 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49874/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49874/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:16:42 INFO - PROCESS | 1929 | RSS: Main: 313991168 08:16:42 INFO - PROCESS | 1929 | 08:16:45 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49874/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49874/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:16:45 INFO - PROCESS | 1929 | RSS: Main: 316755968 08:16:45 INFO - PROCESS | 1929 | 08:16:47 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49874/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49874/tests/kraken/driver.html?testName=ai-astar) 08:16:47 INFO - PROCESS | 1929 | RSS: Main: 359866368 08:16:47 INFO - PROCESS | 1929 | 08:16:47 INFO - PROCESS | 1929 | __start_tp_report 08:16:47 INFO - PROCESS | 1929 | _x_x_mozilla_page_load 08:16:47 INFO - PROCESS | 1929 | _x_x_mozilla_page_load_details 08:16:47 INFO - PROCESS | 1929 | |i|pagename|runs| 08:16:47 INFO - PROCESS | 1929 | |0;ai-astar;100;165;152;113;113;113;115;113;113;115 08:16:47 INFO - PROCESS | 1929 | |1;audio-beat-detection;148;109;133;163;108;127;126;106;157;105 08:16:47 INFO - PROCESS | 1929 | |2;audio-dft;184;185;182;184;183;183;183;183;185;182 08:16:47 INFO - PROCESS | 1929 | |3;audio-fft;77;75;103;93;57;86;63;84;88;56 08:16:47 INFO - PROCESS | 1929 | |4;audio-oscillator;83;84;140;70;75;72;123;70;69;122 08:16:47 INFO - PROCESS | 1929 | |5;imaging-gaussian-blur;87;85;87;85;87;87;89;85;91;86 08:16:47 INFO - PROCESS | 1929 | |6;imaging-darkroom;105;150;94;94;93;94;93;95;93;96 08:16:47 INFO - PROCESS | 1929 | |7;imaging-desaturate;87;86;85;86;85;82;83;85;86;83 08:16:47 INFO - PROCESS | 1929 | |8;json-parse-financial;57;57;59;59;57;56;57;57;56;57 08:16:47 INFO - PROCESS | 1929 | |9;json-stringify-tinderbox;67;67;72;81;75;72;70;71;71;71 08:16:47 INFO - PROCESS | 1929 | |10;stanford-crypto-aes;87;86;85;85;81;86;82;85;84;83 08:16:47 INFO - PROCESS | 1929 | |11;stanford-crypto-ccm;141;140;138;137;140;138;139;138;222;150 08:16:47 INFO - PROCESS | 1929 | |12;stanford-crypto-pbkdf2;166;166;187;232;167;168;168;168;167;163 08:16:47 INFO - PROCESS | 1929 | |13;stanford-crypto-sha256-iterative;64;81;85;86;86;77;66;67;68;67 08:16:47 INFO - PROCESS | 1929 | __end_tp_report 08:16:47 INFO - PROCESS | 1929 | __start_cc_report 08:16:47 INFO - PROCESS | 1929 | _x_x_mozilla_cycle_collect,2123 08:16:47 INFO - PROCESS | 1929 | __end_cc_report 08:16:47 INFO - PROCESS | 1929 | __startTimestamp1471965407814__endTimestamp 08:16:47 INFO - PROCESS | 1929 | 08:16:47 INFO - PROCESS | 1929 | ------- Summary: start ------- 08:16:47 INFO - PROCESS | 1929 | Number of tests: 14 08:16:47 INFO - PROCESS | 1929 | 08:16:47 INFO - PROCESS | 1929 | [#0] ai-astar Cycles:10 Average:121.20 Median:114.00 stddev:20.36 (17.9%) stddev-sans-first:20.09 08:16:47 INFO - PROCESS | 1929 | Values: 100.0 165.0 152.0 113.0 113.0 113.0 115.0 113.0 113.0 115.0 08:16:47 INFO - PROCESS | 1929 | 08:16:47 INFO - PROCESS | 1929 | [#1] audio-beat-detection Cycles:10 Average:128.20 Median:130.00 stddev:21.78 (16.8%) stddev-sans-first:21.89 08:16:47 INFO - PROCESS | 1929 | Values: 148.0 109.0 133.0 163.0 108.0 127.0 126.0 106.0 157.0 105.0 08:16:47 INFO - PROCESS | 1929 | 08:16:47 INFO - PROCESS | 1929 | [#2] audio-dft Cycles:10 Average:183.40 Median:183.50 stddev:1.07 (0.6%) stddev-sans-first:1.12 08:16:47 INFO - PROCESS | 1929 | Values: 184.0 185.0 182.0 184.0 183.0 183.0 183.0 183.0 185.0 182.0 08:16:47 INFO - PROCESS | 1929 | 08:16:47 INFO - PROCESS | 1929 | [#3] audio-fft Cycles:10 Average:78.20 Median:80.50 stddev:15.67 (19.5%) stddev-sans-first:16.61 08:16:47 INFO - PROCESS | 1929 | Values: 77.0 75.0 103.0 93.0 57.0 86.0 63.0 84.0 88.0 56.0 08:16:47 INFO - PROCESS | 1929 | 08:16:47 INFO - PROCESS | 1929 | [#4] audio-oscillator Cycles:10 Average:90.80 Median:71.00 stddev:26.84 (37.8%) stddev-sans-first:28.32 08:16:47 INFO - PROCESS | 1929 | Values: 83.0 84.0 140.0 70.0 75.0 72.0 123.0 70.0 69.0 122.0 08:16:47 INFO - PROCESS | 1929 | 08:16:47 INFO - PROCESS | 1929 | [#5] imaging-gaussian-blur Cycles:10 Average:86.90 Median:87.00 stddev:1.91 (2.2%) stddev-sans-first:2.03 08:16:47 INFO - PROCESS | 1929 | Values: 87.0 85.0 87.0 85.0 87.0 87.0 89.0 85.0 91.0 86.0 08:16:47 INFO - PROCESS | 1929 | 08:16:47 INFO - PROCESS | 1929 | [#6] imaging-darkroom Cycles:10 Average:100.70 Median:94.00 stddev:17.69 (18.8%) stddev-sans-first:18.69 08:16:47 INFO - PROCESS | 1929 | Values: 105.0 150.0 94.0 94.0 93.0 94.0 93.0 95.0 93.0 96.0 08:16:47 INFO - PROCESS | 1929 | 08:16:47 INFO - PROCESS | 1929 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.50 stddev:1.62 (1.9%) stddev-sans-first:1.51 08:16:47 INFO - PROCESS | 1929 | Values: 87.0 86.0 85.0 86.0 85.0 82.0 83.0 85.0 86.0 83.0 08:16:47 INFO - PROCESS | 1929 | 08:16:47 INFO - PROCESS | 1929 | [#8] json-parse-financial Cycles:10 Average:57.20 Median:57.00 stddev:1.03 (1.8%) stddev-sans-first:1.09 08:16:47 INFO - PROCESS | 1929 | Values: 57.0 57.0 59.0 59.0 57.0 56.0 57.0 57.0 56.0 57.0 08:16:47 INFO - PROCESS | 1929 | 08:16:47 INFO - PROCESS | 1929 | [#9] json-stringify-tinderbox Cycles:10 Average:71.70 Median:71.50 stddev:4.03 (5.6%) stddev-sans-first:3.90 08:16:47 INFO - PROCESS | 1929 | Values: 67.0 67.0 72.0 81.0 75.0 72.0 70.0 71.0 71.0 71.0 08:16:47 INFO - PROCESS | 1929 | 08:16:47 INFO - PROCESS | 1929 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:85.00 stddev:1.90 (2.2%) stddev-sans-first:1.76 08:16:47 INFO - PROCESS | 1929 | Values: 87.0 86.0 85.0 85.0 81.0 86.0 82.0 85.0 84.0 83.0 08:16:47 INFO - PROCESS | 1929 | 08:16:47 INFO - PROCESS | 1929 | [#11] stanford-crypto-ccm Cycles:10 Average:148.30 Median:140.00 stddev:26.16 (18.7%) stddev-sans-first:27.61 08:16:47 INFO - PROCESS | 1929 | Values: 141.0 140.0 138.0 137.0 140.0 138.0 139.0 138.0 222.0 150.0 08:16:47 INFO - PROCESS | 1929 | 08:16:47 INFO - PROCESS | 1929 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.20 Median:168.00 stddev:21.01 (12.5%) stddev-sans-first:22.02 08:16:47 INFO - PROCESS | 1929 | Values: 166.0 166.0 187.0 232.0 167.0 168.0 168.0 168.0 167.0 163.0 08:16:47 INFO - PROCESS | 1929 | 08:16:47 INFO - PROCESS | 1929 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.70 Median:79.00 stddev:9.19 (11.6%) stddev-sans-first:8.89 08:16:47 INFO - PROCESS | 1929 | Values: 64.0 81.0 85.0 86.0 86.0 77.0 66.0 67.0 68.0 67.0 08:16:47 INFO - PROCESS | 1929 | -------- Summary: end -------- 08:16:47 INFO - PROCESS | 1929 | 08:16:48 INFO - TEST-INFO | 1929: exit 0 08:16:48 INFO - TEST-OK | kraken | took 59484ms 08:16:48 INFO - SUITE-END | took 1177s 08:16:48 INFO - Completed test suite (00:19:38) 08:16:48 INFO - javascript benchmark 08:16:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3321.7840840345375, 3343.9164020899475, 3347.514610381493, 3363.814990175541, 3373.5108409280656, 5632.014397480435, 5702.287214098238, 5735.713214339282, 5786.508146807521, 5841.474267315941, 5701.629394089381, 5732.942670573293, 5750.769969201227, 5750.971245143768, 5755.079187330018, 5159.1229490986625, 5230.843074707765, 5321.388040375367, 5324.440933701963, 5347.6256662033675, 4166.520850102232, 5088.949110508885, 5308.362996440433, 5316.9202461963105, 5331.280277162572, 5359.624826262163, 5377.4084850666395, 5381.892362152755, 5386.622936394454, 5397.811076612319, 5133.820316288912, 5304.363476382859, 5322.281491998569, 5340.359156901158, 5373.946260537384, 5107.668001579923, 5170.948290517084, 5205.427402985669, 5239.16173412256, 5251.0, 5567.4432556744005, 5631.436856314377, 5636.323641163046, 5730.7421166047625, 5753.741081651336, 5462.6995515246645, 6099.115628233925, 6203.100550420164, 6246.843828904268, 6272.0, 6169.074638804171, 6194.783182588588, 6299.401556852092, 6307.117003619497, 6312.526560507934, 5873.7944171954205, 5936.851578710567, 5937.0, 5946.554008449383, 5950.107483877367, 6215.968920155461, 6310.211223597051, 6317.4946004319545, 6335.049742538656, 6344.936550634435, 5119.6416250862085, 6111.694415279218, 6181.443670069715, 6235.908715974686, 6249.093881387264, 6293.3391993840905, 6294.968525157345, 6310.305866354697, 6314.810555683337, 6322.083297921776, 6090.3605121461605, 6218.720157592919, 6251.468625166821, 6257.374262573706, 6259.217597800275, 6190.690465476709, 6197.876042479125, 6253.968095264321, 6274.247090349096, 6325.746970121144, 5000.949990500121, 5037.471065538066, 5039.596832253411, 5043.974780126149, 5047.0, 4398.69209155356, 4520.909581808345, 4544.386507821468, 4550.931736023962, 4561.315802629566, 5656.236408084856, 5718.0, 5740.052891272893, 5787.479126878601, 5789.623674461147, 6287.559870809091, 6296.811095667137, 6298.0, 6300.432961033529, 6306.369363063657, 5608.803691870765, 5760.193572899798, 5777.306723193244, 5779.508741756998, 5804.158397032491, 5371.516563509303, 5380.973095134499, 5405.21624364465, 5450.127979523337, 5484.2596249506605, 6297.4647154992335, 6302.1177035215105, 6304.495640348853, 6307.400796924285, 6312.968435157978, 6306.616901253585, 6312.3056463788025, 6320.936790632035, 6325.177726895474, 6327.936720632735, 6195.504359651127, 6219.937800621936, 6293.433590976834, 6302.527310451643, 6350.936490635035, 6251.468625166912, 6270.968645156928, 6272.121902933684, 6296.5592408530965, 6301.023341382093, 6249.375062493714, 6253.624782513064, 6300.464460520907, 6329.367063293634, 6336.96831515858, 6018.548608854441, 6090.269167699905, 6093.177421048102, 6124.846878828065, 6165.630062196283, 5980.073088671263, 6057.606255593372, 6100.23747031621, 6126.1423400724825, 6166.660833654192, 5009.398872135367, 5811.767529298781, 6009.278886533469, 6021.2473440819895, 6025.276966764016, 6047.183630210041, 6047.9395206047375, 6058.757649693963, 6110.7861224856915, 6120.418560236771, 5888.970555147197, 5960.5231581474245, 5966.19456373384, 6042.0, 6050.90923636137], "value": 5723.188482588726, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [519.2107995846313, 523.5915985531287, 529.4652401074915, 544.8394918822906, 551.8703104770376, 36732.0, 39596.0, 39628.2074358513, 39686.0, 39745.801270993674, 40195.79902100485, 40775.796121019426, 40828.59171408285, 40860.0, 40901.0, 8781.1218878112, 8991.460512369245, 9603.231741460666, 9891.901080989206, 11807.94096029523, 11775.823362649568, 12093.939530302336, 12100.15298929071, 12102.394880255952, 12124.636260912186, 12012.819807702892, 12043.578474753385, 12056.095792815573, 12083.91244787969, 12112.333821639806, 11966.461509232062, 12021.759564808699, 12034.0, 12045.63863084109, 12064.698382540506, 11965.700857478545, 11975.28148311104, 11978.460969256404, 11982.64052078439, 12029.218100823506, 39617.0, 39943.00142496432, 39944.60055399438, 39953.400698989244, 40002.999925001815, 2905.3608206194604, 3528.947065794015, 3649.9780061582533, 3690.2435000824767, 3887.4751908492276, 31004.844975774977, 31326.530102048488, 32218.033458996266, 32310.0, 32312.35375292481, 3301.405746965569, 3364.0, 3379.915502112467, 3380.7126394256516, 3457.7233821294235, 374697.0, 377853.0, 377896.0, 377936.0, 378509.0, 56718.43281567214, 57023.71488142533, 57029.71485142631, 57068.714656426455, 57293.40598910197, 55869.0, 55942.0, 55955.44044559502, 56008.0, 56070.439295607335, 15599.376024958874, 15915.920420397824, 15928.283227254802, 15944.2825072872, 15945.0, 10436.321639093434, 11238.325700458, 11419.429028548539, 12186.08604354677, 12431.37843107841, 14750.55748327552, 14753.557393278219, 14779.926100369645, 14834.777478337834, 15066.020708653903, 5123.38519377677, 5421.620486565981, 5942.732577034044, 6127.846803829851, 6129.965666994866, 865.8614621660504, 931.6971984105193, 940.8024314893948, 993.5280741647659, 1008.5310330696261, 24192.395190120034, 29930.70069299323, 30228.84885575558, 30873.536896946564, 30974.845125774227, 955.6082006377352, 1062.2458054781036, 1085.6905781852133, 1086.4676308608882, 1242.211195890616, 14196.0, 15053.322600483005, 15608.765868511982, 15708.842911570966, 15747.68504629901, 678895.0, 691856.0, 695005.0, 696609.0, 697572.0, 57958.840823184146, 58209.70895145582, 58318.25045248649, 58390.0, 58390.7080464595, 1248.3820508848178, 1300.746354460871, 1372.8283964504437, 1403.7052219033912, 1425.8289005319427, 30481.695183048774, 30910.0726978191, 31570.526442102928, 32175.0, 32208.83895580507, 53401.0, 53954.46045539495, 53981.0, 54014.459855402514, 54053.45946540641, 622970.0, 626904.0, 626948.0, 628624.0, 629972.0, 33031.0, 33764.66235337615, 34668.30663386769, 39078.13827925834, 39422.21155576815, 38996.415053774785, 39789.40315895206, 47288.52711472979, 47935.520644793105, 48114.518854811, 38461.615383845805, 38704.80647596744, 38726.22547549089, 40387.0, 40409.59590404058, 38399.80800095982, 38918.832435027, 39387.80306098451, 40401.98995025148, 40450.0, 35989.0, 36327.636723633485, 36423.45364819583, 36424.81787591045, 38648.0, 28818.567721483774, 32292.677073228966, 33433.0, 33731.76479947444, 34079.65920340864, 24879.378015549755, 25067.122650707137, 25235.495290094463, 25277.98888044531, 25757.484850302513], "value": 20207.0295625053, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.68783597450084, 380.72968192583267, 386.7930657098453, 399.7841165770484, 410.0855093142293, 19143.138558764855, 20969.895150524226, 20970.16119355226, 21179.68230476544, 21273.68089478659, 19883.40349789505, 21123.683144752842, 21143.471413214676, 21439.785602144013, 21601.67597486035, 5796.9130463042875, 5807.709614519278, 6040.848978775543, 6106.877862442749, 6110.0, 3982.183652351261, 4185.518665353493, 5915.9704201478935, 6012.879742405149, 6442.484601231914, 6707.228668703088, 6709.09427227323, 6757.054012438262, 6764.560303580276, 6791.626460544668, 6408.134901788246, 6597.967010164943, 6655.600663960178, 6657.667116644148, 6666.966665166643, 5277.0, 5555.666660000413, 5732.484076433141, 5756.942430575683, 5758.74085666146, 13394.06241563091, 13607.77530022293, 13648.863511364858, 13662.590122296348, 23664.290071297888, 2151.4836439254623, 2165.6859755335545, 2175.7280339957347, 2240.529488807352, 2949.1447480230922, 8868.600912958933, 9521.91710050467, 10032.849507257324, 10297.433641149733, 10332.018458246455, 1807.2319264312614, 1825.5983683589589, 1862.2638702959907, 1873.531617095726, 1913.339897735293, 120131.0, 125011.0, 125725.0, 128209.0, 128251.0, 11075.849729875647, 11253.268537545033, 11680.299182049104, 11701.88298117008, 12013.699657508632, 14476.27618619065, 14483.275836208148, 14488.565343039725, 14496.202708851115, 14500.202488863213, 8149.470284431493, 8312.251897329148, 8359.247667709813, 8434.493930364199, 8444.61999210037, 8309.293710034593, 8350.958245208734, 8351.791205219797, 8517.48895066298, 8522.659093636184, 4447.755373454491, 4524.253498172765, 4529.84145554904, 4606.746628935373, 4616.538346165356, 1601.3994751968012, 1754.2895127473394, 1825.2698920431933, 1854.7774267088037, 1922.8365588925099, 798.596708662122, 895.5477483870603, 902.9774255643662, 927.0821886332617, 974.7465658928729, 16572.58568535772, 21597.0, 21644.242451514438, 21691.240806571695, 21841.0, 800.3437181511105, 940.1031336771177, 941.7928055827707, 944.0, 978.8678528398717, 7872.724454644174, 10957.671269861758, 11320.320780753182, 11369.772604548029, 11383.658490245305, 589744.0, 598165.0, 601878.9906050442, 620643.0, 621219.0, 90536.0, 111694.44152779346, 137627.31186344003, 138692.0, 140251.29874350768, 853.620139038122, 956.8325543029941, 982.4792859784326, 999.7300728803183, 1002.4686915934566, 15156.696866062837, 18617.813821861608, 18775.624487510446, 18854.6229075415, 19660.21359145618, 51941.7402912983, 52552.0, 52914.0, 53023.20465192948, 53559.12543060974, 79899.0, 80404.0, 80613.0, 81239.78140328018, 81603.18396816193, 7060.964695176696, 7493.400527957641, 7655.732049378245, 7812.679611165346, 8091.1099779025435, 12025.819612705991, 12287.201331913755, 12569.622911312676, 12973.481060757464, 12990.155639883378, 10918.727031824268, 10938.179636527451, 11160.274582152135, 11215.943920280346, 11276.830847537458, 5068.974655126701, 7624.504407213513, 10757.246992710749, 10906.291091079054, 11149.665510034712, 9609.039096090335, 11108.666739997812, 11182.0, 11482.425878705864, 11559.30644161353, 7895.368370530458, 7915.44591878586, 7936.841263174589, 7979.80050498719, 8083.312918401766, 10820.404877731486, 10940.890591093987, 11650.88349116498, 11745.88254117482, 12010.0], "value": 9395.946214282063, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1500.8499150084992, 1544.7914531538242, 1704.2160606121183, 1825.3611236067386, 1874.6438176746417, 8231.547264900428, 8784.077671844445, 8793.2965362771, 8813.294936405087, 8824.0, 7686.423518236126, 7902.762917112482, 8338.708145214903, 8541.530215838126, 8618.827623447527, 2359.6342566902113, 2383.356493746687, 2387.8567285962813, 2389.247387073075, 2391.5934291170456, 2347.753485883983, 2370.5614461324626, 2397.968873384444, 2398.556267090585, 2400.3759022654062, 2091.602595506849, 2176.4341271269427, 2311.1448763957274, 2381.3451300892307, 2389.390705370137, 2370.881455927214, 2380.1907351500486, 2408.843425177358, 2411.8191135664806, 2414.2515820095828, 2371.786539211479, 2377.4413012941945, 2378.7621237876247, 2380.7143142822797, 2381.0594815048134, 8762.386632935697, 8801.163889430494, 8806.515641639708, 8818.867716984187, 8823.779405514913, 5142.408623008327, 5233.869153271199, 5299.33758280215, 5302.0, 5306.522412982811, 3376.4428869236544, 3410.2497450560836, 3548.4677298405186, 3558.5551806024246, 3571.160777217352, 1068.9518971646294, 1107.3134656512932, 1113.8440618313443, 1129.2151954391702, 1131.9264247823864, 11398.088152947827, 11472.311661300266, 11488.540458381654, 11489.42552872353, 11505.424728763528, 4486.573775491323, 4584.684734209203, 4768.737719425465, 4791.9936813268905, 4800.3279540864305, 8890.710846927159, 9403.529823508798, 9791.265655075898, 9796.804063918682, 9803.803923921481, 5298.364196296469, 5306.8407947761625, 5310.6813591184655, 5310.893782124336, 5311.070562651521, 4686.742229177428, 4834.081524510402, 4866.099771542276, 4872.829450969199, 4882.145624515695, 3727.1613886875234, 3749.362608356586, 3800.244822503718, 3841.9231615367535, 3846.9807650961566, 941.9340646154841, 1180.681216071673, 1187.8574571051356, 1195.1574140231116, 1199.33436942496, 719.8092505486051, 758.7989182866545, 785.6464590934103, 864.5590748718073, 921.7050044687226, 5535.280413546294, 5723.255976723082, 5847.532197424196, 5901.970490147522, 5942.762289508458, 941.9387739796891, 946.3091942881734, 946.6450081219542, 957.7788319892054, 988.8763904511936, 3884.6503814656817, 3893.7274390792372, 3916.5104361954754, 4035.6771458283265, 4038.293298672779, 10326.638567650096, 10784.56861725538, 10789.730256743644, 10815.0, 10817.513211905483, 10717.089047431056, 10723.410212438235, 10753.623623173151, 10763.892361076289, 10778.245522813484, 7656.310932016146, 7970.123286438487, 7990.280874721303, 8179.0, 8195.303399211134, 5654.293213348332, 5671.574631902707, 5750.079987202028, 5806.332271788629, 5896.086106653475, 8515.574221289035, 9670.903290967, 10014.799704005733, 10043.849342260019, 10057.346272492265, 9694.982026887357, 9999.35004224723, 10047.447390393307, 10267.229957753048, 10274.075333220046, 6920.757773477904, 7064.788056358318, 7078.504504684623, 7090.964545177241, 7120.8219794503475, 8206.466579672302, 8272.793180170303, 8277.668893244203, 8283.50298982063, 8290.295324897452, 7210.2789721027475, 7251.8187045324285, 7495.287947644965, 7532.133804612539, 7585.58279294622, 7286.125664920243, 7325.0, 7357.5217610855125, 7421.740239091606, 7422.480426370318, 5888.617239879394, 6636.13730215069, 6842.042114104129, 6957.582545047313, 7031.488230030568, 4681.227597446383, 4795.856124316137, 4859.8299059534165, 4942.308076869169, 5120.359955005624, 3919.8236079375924, 4150.833966641421, 4740.739259340769, 4808.110499557593, 4854.320395144612], "value": 4520.390561315771, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.6662604508995, 306.5738623313594, 315.52198419394614, 319.44257271062, 320.1819351556773, 4534.841280555185, 5223.973880130604, 5369.382521010085, 5438.755256013479, 5503.422140675231, 4305.643722227496, 5095.184770436731, 5314.362276526813, 5324.0, 5353.491418315254, 3535.8331750522393, 3784.526934133233, 4083.9795801020955, 4100.548939616655, 4110.938335924963, 3221.822799746013, 3423.4522476403654, 3636.345462814413, 3741.420079887608, 4130.607592278729, 3350.8942049123852, 4073.63337299643, 4075.714699971003, 4100.323446631302, 4113.441872780054, 4058.228936502055, 4067.6373414905893, 4079.796010199478, 4083.3262511685534, 4141.213169497786, 3536.664016918389, 3544.503769472276, 3545.18460754028, 3556.5198698175673, 3557.3063252665743, 4839.039984016376, 4987.476314986947, 4994.0, 5302.73486325686, 5353.705546194958, 4617.838375656869, 4652.302154676791, 4667.836625718083, 4674.462436819776, 4676.976615116937, 5761.596688231783, 5794.406538201986, 5798.478136967651, 5831.854203644943, 5841.56188285876, 3516.4901089342156, 3630.382834918044, 3757.28611563802, 3876.7480113792512, 3899.7075219358376, 4906.546726636681, 5996.880062398727, 5997.820065398001, 6001.729922153513, 6023.216981792383, 3391.576052993376, 3525.7884526927955, 3595.40675788492, 3875.817875547985, 3927.489426374553, 3380.814055226986, 3775.7734535927384, 3925.4111883217747, 3946.644801967837, 3994.960050399517, 3715.4426835974277, 4615.246206441598, 4619.769011549476, 4629.953700462952, 4665.0903073900945, 5005.599552035828, 5780.595358324961, 5781.942180578225, 5799.463142267303, 5812.82561523155, 1074.7581794096423, 1170.795110855597, 1264.582687713071, 1298.7077907470932, 1348.267476292959, 770.876659734451, 887.8668199770086, 897.6409436225562, 914.291424146292, 922.1700469577434, 4596.311157972556, 5250.632455728062, 5263.7629007671585, 5313.521783039545, 5361.705106219118, 4686.5782079613, 4805.077968812433, 4947.282644016597, 5474.7262636868, 5770.740316685759, 4161.812718427678, 4362.781860906942, 4398.912021759611, 4635.605973492291, 5221.869453263699, 2391.210900402863, 2391.7249516305847, 2588.301158687144, 2605.1533251692895, 2624.386002608382, 5038.974805125951, 5051.494850514992, 5058.519440653132, 5084.542391184811, 5088.913545446349, 4435.911281774346, 4583.931241031387, 4764.927891224516, 4823.107725070873, 4962.60299176065, 4188.889944164738, 4652.627789776742, 4740.771837236493, 4834.250691142878, 5032.84901452957, 4241.809118589733, 4593.116822102922, 4876.756162191948, 4906.63200259989, 4968.602511798975, 4129.0, 4134.780239829198, 4543.3639290498695, 4869.2957464887, 4871.634627402888, 4548.11311794197, 4767.976160119178, 5093.668911520887, 5095.388553373619, 5111.744412779421, 2825.2513084033158, 3384.8984530464127, 3412.7952322860706, 3426.2633533790354, 3470.496777967231, 3956.090099277125, 4157.542670306324, 4430.401895744033, 5064.9746751267485, 5610.4670056344585, 4927.630427717864, 5167.22491626259, 5683.60214784961, 5763.394843541536, 5787.160861675117, 4359.795550270612, 4631.9536804631525, 4900.264960255848, 5226.4912841836895, 5677.489025987681, 4834.395700537433, 4997.900041999213, 5619.353774315842, 5628.802991895264, 5644.407337229696, 4659.906801863876, 5475.616706830643, 5779.335376431596, 5803.448672376117, 5852.717440198411, 4729.456112546964, 4874.635801636133, 5615.213870058114, 5680.517156041617, 5707.6575405475805], "value": 3892.5026805861503, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [713.7255680520948, 723.6996646391746, 772.3280745751193, 823.9670413183472, 832.3466079127886, 15325.386984520619, 16249.431269905555, 16273.674526509463, 16276.918615406936, 16307.429239976573, 14757.557273281818, 14897.627559311022, 14921.477748278812, 14937.551873443814, 14968.401263949429, 688.8381230410849, 706.9858602827941, 709.1844378964181, 843.9113893041234, 861.3755027604999, 659.5844617890722, 856.3791251342789, 861.483110133921, 862.5342315149811, 864.2308345572415, 916.9816603667923, 917.4220241248005, 918.2470374293049, 919.8804155459815, 922.8015976565038, 910.0444533240137, 911.9908800911973, 911.9908800912006, 912.0150237743221, 912.8767616371805, 535.2720300391464, 653.6600967496875, 676.5264314979534, 679.3647939176836, 686.0498209979165, 14743.557693269218, 14783.0, 14807.851921480755, 14808.111513309128, 14809.88925830567, 13606.863931360658, 14000.789988150185, 14077.296135193303, 14158.716825663429, 14376.568702938928, 10173.949130254376, 11583.0, 12213.816792748115, 12657.746845063048, 12982.0, 4671.11248862715, 4671.43942726875, 4721.5750582447745, 4838.709677419366, 4891.315215869781, 110240.0, 113963.43018284938, 114329.42835285854, 115062.0, 116844.83155168424, 10669.733256668645, 10930.234883558074, 10968.780624387628, 11014.06380457654, 11126.0, 20789.85655788946, 20809.791902080786, 20819.271325503534, 20851.165953362, 20888.37334879956, 7704.229577042363, 7912.642832646606, 7992.600369981477, 7994.280514753584, 7996.200379961957, 4423.800928958204, 4428.822847086145, 4561.2245918193985, 4681.859544213679, 4684.461286951976, 3389.932201355959, 3459.39460594395, 3513.6662017108333, 3515.5078289039557, 3517.6984515729346, 606.848287928018, 633.4488994574749, 644.1658052821607, 645.3288579876969, 649.3052433895687, 571.5970240980099, 585.7627660797384, 589.9144624029577, 596.9522438204933, 655.7508146904241, 7812.6093695315, 8868.8669669955, 8937.285017198608, 9033.954830225806, 9129.817403652023, 536.570743405282, 537.3363895588944, 596.436367632583, 597.7788218359235, 611.6238513314369, 4293.509049166587, 4548.522405147478, 4634.0, 4868.488808675109, 5023.14606516893, 150107.0, 153289.0, 155550.0, 162184.5378554581, 166713.16643416949, 142979.2851035759, 143450.2827485856, 145175.0, 148045.25977370044, 148234.25882870518, 107129.0, 115724.0, 117070.0, 117279.16731241344, 117543.824561755, 9760.256994982889, 9770.117795394368, 10024.14794742455, 10126.392416455037, 10138.746531336776, 57949.0, 59908.400915992024, 61207.08189377074, 63530.68234658797, 64893.026604600025, 77279.61360193163, 78261.43477130539, 82209.58895205485, 89270.0, 93091.53454232686, 13389.263590502616, 13496.932515337361, 13662.0, 14102.224377659328, 14181.290935453393, 14823.555293341216, 15283.0, 15316.38734450654, 15386.9230653846, 15651.921740391224, 14167.716645667235, 14178.787318190442, 14469.27653617336, 14662.780058299348, 14675.633109172357, 11349.4892729829, 13694.38375273135, 14002.299885005912, 14246.28768561547, 14465.421383144978, 13530.120542164728, 13791.724165516433, 14111.22388268614, 14255.50105746294, 14390.784138238145, 11306.60426885088, 11372.943135284602, 11519.25124866881, 11635.650930472098, 11899.345535995604, 8723.502970859996, 10090.0, 11069.83395249088, 11111.888881111085, 11404.429778510876], "value": 7901.706067404331, "unit": "score"}], "name": "dromaeo_css", "value": 7298.091830938204, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 165.0, 152.0, 113.0, 113.0, 113.0, 115.0, 113.0, 113.0, 115.0], "value": 121.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 109.0, 133.0, 163.0, 108.0, 127.0, 126.0, 106.0, 157.0, 105.0], "value": 128.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 182.0, 184.0, 183.0, 183.0, 183.0, 183.0, 185.0, 182.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 103.0, 93.0, 57.0, 86.0, 63.0, 84.0, 88.0, 56.0], "value": 78.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 140.0, 70.0, 75.0, 72.0, 123.0, 70.0, 69.0, 122.0], "value": 90.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 85.0, 87.0, 87.0, 89.0, 85.0, 91.0, 86.0], "value": 86.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 150.0, 94.0, 94.0, 93.0, 94.0, 93.0, 95.0, 93.0, 96.0], "value": 100.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 85.0, 86.0, 85.0, 82.0, 83.0, 85.0, 86.0, 83.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 59.0, 59.0, 57.0, 56.0, 57.0, 57.0, 56.0, 57.0], "value": 57.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 72.0, 81.0, 75.0, 72.0, 70.0, 71.0, 71.0, 71.0], "value": 71.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 85.0, 85.0, 81.0, 86.0, 82.0, 85.0, 84.0, 83.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 140.0, 138.0, 137.0, 140.0, 138.0, 139.0, 138.0, 222.0, 150.0], "value": 148.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 166.0, 187.0, 232.0, 167.0, 168.0, 168.0, 168.0, 167.0, 163.0], "value": 175.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 81.0, 85.0, 86.0, 86.0, 77.0, 66.0, 67.0, 68.0, 67.0], "value": 74.7, "unit": "score"}], "name": "kraken", "value": 1485.7, "alertThreshold": 2.0}]} 08:16:49 INFO - Return code: 0 08:16:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:16:49 INFO - # TBPL SUCCESS # 08:16:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:16:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:16:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:16:49 INFO - 2016-08-23 08:16:49.163 screenresolution[1933:11426] starting screenresolution argv=screenresolution get 08:16:49 INFO - 2016-08-23 08:16:49.166 screenresolution[1933:11426] Display 0: 1600x1200x32@60 08:16:49 INFO - 2016-08-23 08:16:49.172 screenresolution[1934:11429] starting screenresolution argv=screenresolution list 08:16:49 INFO - Available Modes on Display 0 08:16:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:16:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:16:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:16:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:16:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:16:49 INFO - Intel Iris: 08:16:49 INFO - Chipset Model: Intel Iris 08:16:49 INFO - Type: GPU 08:16:49 INFO - Bus: Built-In 08:16:49 INFO - VRAM (Dynamic, Max): 1536 MB 08:16:49 INFO - Vendor: Intel (0x8086) 08:16:49 INFO - Device ID: 0x0a2e 08:16:49 INFO - Revision ID: 0x0009 08:16:49 INFO - Displays: 08:16:49 INFO - Display: 08:16:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:16:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:16:49 INFO - Display Serial Number: 1600x1200 60 08:16:49 INFO - Main Display: Yes 08:16:49 INFO - Mirror: Off 08:16:49 INFO - Online: Yes 08:16:49 INFO - Rotation: Supported 08:16:49 INFO - Return code: 0 08:16:49 INFO - Running post-action listener: _resource_record_post_action 08:16:49 INFO - [mozharness: 2016-08-23 15:16:49.407941Z] Finished run-tests step (success) 08:16:49 INFO - Running post-run listener: _resource_record_post_run 08:16:49 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 1236992; Write bytes: 704974848; Read time: 14; Write time: 2149 08:16:49 INFO - TinderboxPrint: CPU usage
53.6% 08:16:49 INFO - TinderboxPrint: I/O read bytes / time
1,236,992 / 14 08:16:49 INFO - TinderboxPrint: I/O write bytes / time
704,974,848 / 2,149 08:16:49 INFO - TinderboxPrint: CPU idle
2,251.9 (46.4%) 08:16:49 INFO - TinderboxPrint: CPU system
972.4 (20.0%) 08:16:49 INFO - TinderboxPrint: CPU user
1,629.4 (33.6%) 08:16:49 INFO - TinderboxPrint: Swap in / out
407,187,456 / 0 08:16:49 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 395391488; Write bytes: 388724736; Read time: 29627; Write time: 667 08:16:49 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 1228800; Write bytes: 296261632; Read time: 14; Write time: 1435 08:16:49 INFO - Running post-run listener: _upload_blobber_files 08:16:49 INFO - Blob upload gear active. 08:16:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:16:49 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 08:16:49 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'] 08:16:49 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 08:16:50 INFO - (blobuploader) - INFO - Open directory for files ... 08:16:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:16:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:16:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:16:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:16:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:16:50 INFO - (blobuploader) - INFO - Done attempting. 08:16:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:16:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:16:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:16:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:16:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:16:51 INFO - (blobuploader) - INFO - Done attempting. 08:16:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:16:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:16:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:16:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:16:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:16:54 INFO - (blobuploader) - INFO - Done attempting. 08:16:54 INFO - (blobuploader) - INFO - Iteration through files over. 08:16:54 INFO - Return code: 0 08:16:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:16:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:16:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/045be562a73df384234a40cf3d0085297b61635bb47ac146b61c48f2126838362f58cda171a59c1397e71c04525d80770c8414ee72ae06e5615d053740009290", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/484b79d9f43a54d9abffefe28597630fabd4ee6d518fa4f6d536857a9070bc6702cbdea580ade85fbd69297682bec7ce94dfbd38e3ebb6de8ce1435617e77669", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/143b8dfeeb4eb69991d4be8816bcad86eb770987d7a249420ca4abafe2ba78314d0891d3aa535bbff1011d81a43982926dbe2789726315f3913e923e75af7989"} 08:16:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:16:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:16:54 INFO - Contents: 08:16:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/045be562a73df384234a40cf3d0085297b61635bb47ac146b61c48f2126838362f58cda171a59c1397e71c04525d80770c8414ee72ae06e5615d053740009290", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/484b79d9f43a54d9abffefe28597630fabd4ee6d518fa4f6d536857a9070bc6702cbdea580ade85fbd69297682bec7ce94dfbd38e3ebb6de8ce1435617e77669", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/143b8dfeeb4eb69991d4be8816bcad86eb770987d7a249420ca4abafe2ba78314d0891d3aa535bbff1011d81a43982926dbe2789726315f3913e923e75af7989"} 08:16:54 INFO - Running post-run listener: copy_logs_to_upload_dir 08:16:54 INFO - Copying logs to upload dir... 08:16:54 INFO - mkdir: /builds/slave/test/build/upload/logs 08:16:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.035287 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-23 08:16:54.667806) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-23 08:16:54.670912) ========= 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.7PlHkr6UfN/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.o9EyqqVe0D/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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/045be562a73df384234a40cf3d0085297b61635bb47ac146b61c48f2126838362f58cda171a59c1397e71c04525d80770c8414ee72ae06e5615d053740009290", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/484b79d9f43a54d9abffefe28597630fabd4ee6d518fa4f6d536857a9070bc6702cbdea580ade85fbd69297682bec7ce94dfbd38e3ebb6de8ce1435617e77669", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/143b8dfeeb4eb69991d4be8816bcad86eb770987d7a249420ca4abafe2ba78314d0891d3aa535bbff1011d81a43982926dbe2789726315f3913e923e75af7989"} build_url:https://queue.taskcluster.net/v1/task/URUUS0aSTY21SpJMpbTVtA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010848 build_url: 'https://queue.taskcluster.net/v1/task/URUUS0aSTY21SpJMpbTVtA/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/045be562a73df384234a40cf3d0085297b61635bb47ac146b61c48f2126838362f58cda171a59c1397e71c04525d80770c8414ee72ae06e5615d053740009290", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/484b79d9f43a54d9abffefe28597630fabd4ee6d518fa4f6d536857a9070bc6702cbdea580ade85fbd69297682bec7ce94dfbd38e3ebb6de8ce1435617e77669", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/143b8dfeeb4eb69991d4be8816bcad86eb770987d7a249420ca4abafe2ba78314d0891d3aa535bbff1011d81a43982926dbe2789726315f3913e923e75af7989"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-23 08:16:54.703392) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 08:16:54.703760) ========= 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.7PlHkr6UfN/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.o9EyqqVe0D/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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.005186 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 08:16:54.752801) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-23 08:16:54.753106) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-23 08:16:54.753414) ========= ========= Total master_lag: 0.17 =========