builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0128 starttime: 1472261375.88 results: success (0) buildid: 20160826162112 builduid: 1a18a0bb13a94919b6cc13f9b2e02ad6 revision: f38d60b049f0180156ff254c5bf3fae79020e4a5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 18:29:35.877410) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 18:29:35.877779) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 18:29:35.878043) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 18:29:35.912579) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 18:29:35.912834) ========= 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.y9fGrmMPDB/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.GILpbBmRci/Listeners TMPDIR=/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/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-26 18:29:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 63.6M=0s 2016-08-26 18:29:36 (63.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.129285 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 18:29:36.064244) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 18:29:36.064537) ========= 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.y9fGrmMPDB/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.GILpbBmRci/Listeners TMPDIR=/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/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.027927 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 18:29:36.114253) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-26 18:29:36.114630) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f38d60b049f0180156ff254c5bf3fae79020e4a5 --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 f38d60b049f0180156ff254c5bf3fae79020e4a5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.y9fGrmMPDB/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.GILpbBmRci/Listeners TMPDIR=/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/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-26 18:29:36,193 truncating revision to first 12 chars 2016-08-26 18:29:36,193 Setting DEBUG logging. 2016-08-26 18:29:36,193 attempt 1/10 2016-08-26 18:29:36,193 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f38d60b049f0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-26 18:29:37,252 unpacking tar archive at: mozilla-central-f38d60b049f0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.311352 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-26 18:29:37.448761) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 18:29:37.449105) ========= script_repo_revision: f38d60b049f0180156ff254c5bf3fae79020e4a5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 18:29:37.451444) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 18:29:37.451770) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 18:29:37.486034) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-26 18:29:37.486398) ========= /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.y9fGrmMPDB/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.GILpbBmRci/Listeners TMPDIR=/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/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 18:29:37 INFO - MultiFileLogger online at 20160826 18:29:37 in /builds/slave/test 18:29:37 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 18:29:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:29:37 INFO - {'append_to_log': False, 18:29:37 INFO - 'base_work_dir': '/builds/slave/test', 18:29:37 INFO - 'blob_upload_branch': 'Firefox', 18:29:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:29:37 INFO - 'branch': 'Firefox', 18:29:37 INFO - 'buildbot_json_path': 'buildprops.json', 18:29:37 INFO - 'config_files': ('talos/mac_config.py',), 18:29:37 INFO - 'default_actions': ('clobber', 18:29:37 INFO - 'read-buildbot-config', 18:29:37 INFO - 'download-and-extract', 18:29:37 INFO - 'populate-webroot', 18:29:37 INFO - 'create-virtualenv', 18:29:37 INFO - 'install', 18:29:37 INFO - 'run-tests'), 18:29:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:29:37 INFO - 'download_minidump_stackwalk': True, 18:29:37 INFO - 'download_symbols': 'ondemand', 18:29:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:29:37 INFO - 'tooltool.py': '/tools/tooltool.py', 18:29:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:29:37 INFO - '/tools/misc-python/virtualenv.py')}, 18:29:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:29:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:29:37 INFO - 'installer_path': 'installer.exe', 18:29:37 INFO - 'log_level': 'info', 18:29:37 INFO - 'log_name': 'talos', 18:29:37 INFO - 'log_to_console': True, 18:29:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:29:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:29:37 INFO - 'opt_config_files': (), 18:29:37 INFO - 'pip_index': False, 18:29:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:29:37 INFO - 'cmd': ('bash', 18:29:37 INFO - '-c', 18:29:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:29:37 INFO - 'enabled': True, 18:29:37 INFO - 'halt_on_failure': False, 18:29:37 INFO - 'name': 'check_screen_resolution'},), 18:29:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:29:37 INFO - 'cmd': ('bash', 18:29:37 INFO - '-c', 18:29:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:29:37 INFO - 'enabled': True, 18:29:37 INFO - 'halt_on_failure': False, 18:29:37 INFO - 'name': 'check_screen_resolution'},), 18:29:37 INFO - 'run_cmd_checks_enabled': True, 18:29:37 INFO - 'sps_profile': False, 18:29:37 INFO - 'sps_profile_interval': 0, 18:29:37 INFO - 'suite': 'dromaeojs', 18:29:37 INFO - 'system_bits': '32', 18:29:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:29:37 INFO - 'title': 't-yosemite-r7-0128', 18:29:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:29:37 INFO - 'use_talos_json': True, 18:29:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:29:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:29:37 INFO - 'work_dir': 'build'} 18:29:37 INFO - [mozharness: 2016-08-27 01:29:37.689295Z] Running clobber step. 18:29:37 INFO - Running pre-action listener: _resource_record_pre_action 18:29:37 INFO - Running main action method: clobber 18:29:37 INFO - rmtree: /builds/slave/test/build 18:29:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:29:39 INFO - Running post-action listener: _resource_record_post_action 18:29:39 INFO - [mozharness: 2016-08-27 01:29:39.799640Z] Finished clobber step (success) 18:29:39 INFO - [mozharness: 2016-08-27 01:29:39.799764Z] Running read-buildbot-config step. 18:29:39 INFO - Running pre-action listener: _resource_record_pre_action 18:29:39 INFO - Running main action method: read_buildbot_config 18:29:39 INFO - Using buildbot properties: 18:29:39 INFO - { 18:29:39 INFO - "project": "", 18:29:39 INFO - "product": "firefox", 18:29:39 INFO - "script_repo_revision": "production", 18:29:39 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 18:29:39 INFO - "repository": "", 18:29:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 18:29:39 INFO - "buildid": "20160826162112", 18:29:39 INFO - "pgo_build": "False", 18:29:39 INFO - "basedir": "/builds/slave/test", 18:29:39 INFO - "buildnumber": 5, 18:29:39 INFO - "slavename": "t-yosemite-r7-0128", 18:29:39 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 18:29:39 INFO - "platform": "yosemite_r7", 18:29:39 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:29:39 INFO - "branch": "mozilla-central", 18:29:39 INFO - "repo_path": "mozilla-central", 18:29:39 INFO - "revision": "f38d60b049f0180156ff254c5bf3fae79020e4a5", 18:29:39 INFO - "stage_platform": "macosx64", 18:29:39 INFO - "builduid": "1a18a0bb13a94919b6cc13f9b2e02ad6", 18:29:39 INFO - "slavebuilddir": "test" 18:29:39 INFO - } 18:29:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/E0Y9kB1fT064M9C1snd2wQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 18:29:39 INFO - Running post-action listener: _resource_record_post_action 18:29:39 INFO - [mozharness: 2016-08-27 01:29:39.803202Z] Finished read-buildbot-config step (success) 18:29:39 INFO - [mozharness: 2016-08-27 01:29:39.803304Z] Running download-and-extract step. 18:29:39 INFO - Running pre-action listener: _resource_record_pre_action 18:29:39 INFO - Running main action method: download_and_extract 18:29:39 INFO - mkdir: /builds/slave/test/build/tests 18:29:39 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')]} 18:29:39 INFO - trying https://queue.taskcluster.net/v1/task/E0Y9kB1fT064M9C1snd2wQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 18:29:39 INFO - Downloading https://queue.taskcluster.net/v1/task/E0Y9kB1fT064M9C1snd2wQ/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 18:29:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E0Y9kB1fT064M9C1snd2wQ/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 18:29:41 INFO - Downloaded 1199 bytes. 18:29:41 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 18:29:41 INFO - Using the following test package requirements: 18:29:41 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:29:41 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:29:41 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 18:29:41 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:29:41 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 18:29:41 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:29:41 INFO - u'jsshell-mac.zip'], 18:29:41 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:29:41 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 18:29:41 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:29:41 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:29:41 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 18:29:41 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:29:41 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 18:29:41 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:29:41 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 18:29:41 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:29:41 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 18:29:41 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:29:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:41 INFO - trying https://queue.taskcluster.net/v1/task/E0Y9kB1fT064M9C1snd2wQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:29:41 INFO - Downloading https://queue.taskcluster.net/v1/task/E0Y9kB1fT064M9C1snd2wQ/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 18:29:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E0Y9kB1fT064M9C1snd2wQ/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 18:29:42 INFO - Downloaded 16369394 bytes. 18:29:42 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 18:29:48 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 18:29:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:48 INFO - trying https://queue.taskcluster.net/v1/task/E0Y9kB1fT064M9C1snd2wQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:29:48 INFO - Downloading https://queue.taskcluster.net/v1/task/E0Y9kB1fT064M9C1snd2wQ/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 18:29:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E0Y9kB1fT064M9C1snd2wQ/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 18:29:49 INFO - Downloaded 16369394 bytes. 18:29:49 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 18:29:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:56 INFO - trying https://queue.taskcluster.net/v1/task/E0Y9kB1fT064M9C1snd2wQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 18:29:56 INFO - Downloading https://queue.taskcluster.net/v1/task/E0Y9kB1fT064M9C1snd2wQ/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 18:29:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E0Y9kB1fT064M9C1snd2wQ/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 18:29:57 INFO - Downloaded 14413998 bytes. 18:29:57 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 18:29:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:57 INFO - trying https://queue.taskcluster.net/v1/task/E0Y9kB1fT064M9C1snd2wQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:29:57 INFO - Downloading https://queue.taskcluster.net/v1/task/E0Y9kB1fT064M9C1snd2wQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 18:29:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E0Y9kB1fT064M9C1snd2wQ/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 18:30:00 INFO - Downloaded 111553827 bytes. 18:30:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E0Y9kB1fT064M9C1snd2wQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:30:00 INFO - mkdir: /builds/slave/test/properties 18:30:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:30:00 INFO - Writing to file /builds/slave/test/properties/build_url 18:30:00 INFO - Contents: 18:30:00 INFO - build_url:https://queue.taskcluster.net/v1/task/E0Y9kB1fT064M9C1snd2wQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:30:01 INFO - Running post-action listener: _resource_record_post_action 18:30:01 INFO - Running post-action listener: set_extra_try_arguments 18:30:01 INFO - [mozharness: 2016-08-27 01:30:01.447129Z] Finished download-and-extract step (success) 18:30:01 INFO - [mozharness: 2016-08-27 01:30:01.447274Z] Running populate-webroot step. 18:30:01 INFO - Running pre-action listener: _resource_record_pre_action 18:30:01 INFO - Running main action method: populate_webroot 18:30:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:30:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:30:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:30:01 INFO - '--tppagecycles', 18:30:01 INFO - '7'], 18:30:01 INFO - 'tests': ['tsvgm']}}, 18:30:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:30:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:30:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:30:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:30:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:30:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:30:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:01 INFO - 'talos_options': ['--disable-e10s'], 18:30:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:30:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:30:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:01 INFO - 'talos_options': ['--disable-e10s'], 18:30:01 INFO - 'tests': ['damp', 'tps']}, 18:30:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:01 INFO - 'tests': ['damp', 'tps']}, 18:30:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:30:01 INFO - 'tests': ['dromaeo_dom']}, 18:30:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:30:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:30:01 INFO - 'tests': ['basic_compositor_video']}, 18:30:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:30:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:30:01 INFO - 'tests': ['a11yr', 18:30:01 INFO - 'ts_paint', 18:30:01 INFO - 'tpaint', 18:30:01 INFO - 'sessionrestore', 18:30:01 INFO - 'sessionrestore_no_auto_restore', 18:30:01 INFO - 'tabpaint']}, 18:30:01 INFO - 'other-e10s': {'tests': ['a11yr', 18:30:01 INFO - 'ts_paint', 18:30:01 INFO - 'tpaint', 18:30:01 INFO - 'sessionrestore', 18:30:01 INFO - 'sessionrestore_no_auto_restore', 18:30:01 INFO - 'tabpaint']}, 18:30:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:30:01 INFO - 'ts_paint', 18:30:01 INFO - 'tpaint', 18:30:01 INFO - 'sessionrestore', 18:30:01 INFO - 'sessionrestore_no_auto_restore', 18:30:01 INFO - 'tabpaint']}, 18:30:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:30:01 INFO - 'ts_paint', 18:30:01 INFO - 'tpaint', 18:30:01 INFO - 'sessionrestore', 18:30:01 INFO - 'sessionrestore_no_auto_restore', 18:30:01 INFO - 'tabpaint']}, 18:30:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:30:01 INFO - 'tests': ['a11yr', 18:30:01 INFO - 'ts_paint', 18:30:01 INFO - 'tpaint', 18:30:01 INFO - 'sessionrestore', 18:30:01 INFO - 'sessionrestore_no_auto_restore', 18:30:01 INFO - 'tabpaint']}, 18:30:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:30:01 INFO - 'tests': ['a11yr', 18:30:01 INFO - 'ts_paint', 18:30:01 INFO - 'tpaint', 18:30:01 INFO - 'sessionrestore', 18:30:01 INFO - 'sessionrestore_no_auto_restore', 18:30:01 INFO - 'tabpaint']}, 18:30:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:30:01 INFO - 'tests': ['tsvgx', 18:30:01 INFO - 'tsvgr_opacity', 18:30:01 INFO - 'tart', 18:30:01 INFO - 'tscrollx', 18:30:01 INFO - 'cart']}, 18:30:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:30:01 INFO - 'tsvgr_opacity', 18:30:01 INFO - 'tart', 18:30:01 INFO - 'tscrollx', 18:30:01 INFO - 'cart']}, 18:30:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:01 INFO - 'talos_options': ['--disable-e10s'], 18:30:01 INFO - 'tests': ['tp5o']}, 18:30:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:01 INFO - 'tests': ['tp5o']}, 18:30:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:01 INFO - 'talos_options': ['--disable-e10s', 18:30:01 INFO - '--xperf_path', 18:30:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:30:01 INFO - 'tests': ['tp5n']}, 18:30:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:01 INFO - 'talos_options': ['--xperf_path', 18:30:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:30:01 INFO - 'tests': ['tp5n']}}, 18:30:01 INFO - 'talos.zip': {'path': '', 18:30:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:30:01 INFO - Running post-action listener: _resource_record_post_action 18:30:01 INFO - [mozharness: 2016-08-27 01:30:01.465872Z] Finished populate-webroot step (success) 18:30:01 INFO - [mozharness: 2016-08-27 01:30:01.466001Z] Running create-virtualenv step. 18:30:01 INFO - Running pre-action listener: _resource_record_pre_action 18:30:01 INFO - Running main action method: create_virtualenv 18:30:01 INFO - Creating virtualenv /builds/slave/test/build/venv 18:30:01 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 18:30:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:30:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:30:01 INFO - Using real prefix '/tools/python27' 18:30:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:30:02 INFO - Installing distribute.............................................................................................................................................................................................done. 18:30:05 INFO - Installing pip.................done. 18:30:05 INFO - Return code: 0 18:30:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:30:05 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')]} 18:30:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:05 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')]} 18:30:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:05 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 0x1104c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110557618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3b046df20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110426d30>, '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 0x11018bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11041eb20>, '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.y9fGrmMPDB/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.GILpbBmRci/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/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 18:30:05 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 18:30:05 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 18:30:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y9fGrmMPDB/Render', 18:30:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:05 INFO - 'HOME': '/Users/cltbld', 18:30:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:05 INFO - 'LOGNAME': 'cltbld', 18:30:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:05 INFO - 'MOZ_NO_REMOTE': '1', 18:30:05 INFO - 'NO_EM_RESTART': '1', 18:30:05 INFO - 'PAGER': '/bin/cat', 18:30:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:05 INFO - 'PWD': '/builds/slave/test', 18:30:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:05 INFO - 'SHELL': '/bin/bash', 18:30:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GILpbBmRci/Listeners', 18:30:05 INFO - 'TMPDIR': '/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/', 18:30:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:05 INFO - 'USER': 'cltbld', 18:30:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:05 INFO - 'XPC_FLAGS': '0x0', 18:30:05 INFO - 'XPC_SERVICE_NAME': '0', 18:30:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:05 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 18:30:05 INFO - Cleaning up... 18:30:05 INFO - Return code: 0 18:30:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:30:05 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')]} 18:30:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:05 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')]} 18:30:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:05 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 0x1104c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110557618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3b046df20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110426d30>, '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 0x11018bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11041eb20>, '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.y9fGrmMPDB/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.GILpbBmRci/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/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 18:30:05 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 18:30:05 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 18:30:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y9fGrmMPDB/Render', 18:30:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:05 INFO - 'HOME': '/Users/cltbld', 18:30:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:05 INFO - 'LOGNAME': 'cltbld', 18:30:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:05 INFO - 'MOZ_NO_REMOTE': '1', 18:30:05 INFO - 'NO_EM_RESTART': '1', 18:30:05 INFO - 'PAGER': '/bin/cat', 18:30:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:05 INFO - 'PWD': '/builds/slave/test', 18:30:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:05 INFO - 'SHELL': '/bin/bash', 18:30:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GILpbBmRci/Listeners', 18:30:05 INFO - 'TMPDIR': '/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/', 18:30:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:05 INFO - 'USER': 'cltbld', 18:30:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:05 INFO - 'XPC_FLAGS': '0x0', 18:30:05 INFO - 'XPC_SERVICE_NAME': '0', 18:30:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:05 INFO - Downloading/unpacking psutil>=3.1.1 18:30:05 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 18:30:05 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 18:30:05 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 18:30:05 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 18:30:05 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 18:30:05 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 18:30:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:30:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:30:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:30:08 INFO - Installing collected packages: psutil 18:30:08 INFO - Running setup.py install for psutil 18:30:09 INFO - building 'psutil._psutil_osx' extension 18:30:09 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 18:30:09 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 18:30:09 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 18:30:09 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 18:30:09 INFO - building 'psutil._psutil_posix' extension 18:30:09 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 18:30:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:30:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:30:10 INFO - ^ 18:30:10 INFO - 1 warning generated. 18:30:10 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 18:30:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:30:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:30:10 INFO - Successfully installed psutil 18:30:10 INFO - Cleaning up... 18:30:10 INFO - Return code: 0 18:30:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:30: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')]} 18:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30: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')]} 18:30:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:10 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 0x1104c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110557618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3b046df20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110426d30>, '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 0x11018bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11041eb20>, '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.y9fGrmMPDB/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.GILpbBmRci/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/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 18:30:10 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 18:30:10 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 18:30:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y9fGrmMPDB/Render', 18:30:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:10 INFO - 'HOME': '/Users/cltbld', 18:30:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:10 INFO - 'LOGNAME': 'cltbld', 18:30:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:10 INFO - 'MOZ_NO_REMOTE': '1', 18:30:10 INFO - 'NO_EM_RESTART': '1', 18:30:10 INFO - 'PAGER': '/bin/cat', 18:30:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:10 INFO - 'PWD': '/builds/slave/test', 18:30:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:10 INFO - 'SHELL': '/bin/bash', 18:30:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GILpbBmRci/Listeners', 18:30:10 INFO - 'TMPDIR': '/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/', 18:30:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:10 INFO - 'USER': 'cltbld', 18:30:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:10 INFO - 'XPC_FLAGS': '0x0', 18:30:10 INFO - 'XPC_SERVICE_NAME': '0', 18:30:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:30:10 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 18:30:10 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 18:30:10 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 18:30:10 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 18:30:10 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 18:30:10 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 18:30:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:30:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:30:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:30:13 INFO - Installing collected packages: mozsystemmonitor 18:30:13 INFO - Running setup.py install for mozsystemmonitor 18:30:14 INFO - Successfully installed mozsystemmonitor 18:30:14 INFO - Cleaning up... 18:30:14 INFO - Return code: 0 18:30:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:30:14 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')]} 18:30:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:14 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')]} 18:30:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:14 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 0x1104c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110557618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3b046df20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110426d30>, '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 0x11018bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11041eb20>, '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.y9fGrmMPDB/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.GILpbBmRci/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/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 18:30:14 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 18:30:14 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 18:30:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y9fGrmMPDB/Render', 18:30:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:14 INFO - 'HOME': '/Users/cltbld', 18:30:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:14 INFO - 'LOGNAME': 'cltbld', 18:30:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:14 INFO - 'MOZ_NO_REMOTE': '1', 18:30:14 INFO - 'NO_EM_RESTART': '1', 18:30:14 INFO - 'PAGER': '/bin/cat', 18:30:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:14 INFO - 'PWD': '/builds/slave/test', 18:30:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:14 INFO - 'SHELL': '/bin/bash', 18:30:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GILpbBmRci/Listeners', 18:30:14 INFO - 'TMPDIR': '/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/', 18:30:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:14 INFO - 'USER': 'cltbld', 18:30:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:14 INFO - 'XPC_FLAGS': '0x0', 18:30:14 INFO - 'XPC_SERVICE_NAME': '0', 18:30:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:14 INFO - Downloading/unpacking jsonschema==2.5.1 18:30:14 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 18:30:14 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 18:30:14 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 18:30:14 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 18:30:14 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 18:30:14 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 18:30:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:30:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:30: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 18:30: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 18:30: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 18:30: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 18:30: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 18:30: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 18:30:17 INFO - Downloading functools32-3.2.3-2.tar.gz 18:30:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:30:17 INFO - warning: no files found matching '*.txt' 18:30:17 INFO - no previously-included directories found matching 'build' 18:30:17 INFO - no previously-included directories found matching 'dist' 18:30:17 INFO - no previously-included directories found matching '.git*' 18:30:17 INFO - Installing collected packages: jsonschema, functools32 18:30:17 INFO - Running setup.py install for functools32 18:30:17 INFO - warning: no files found matching '*.txt' 18:30:17 INFO - no previously-included directories found matching 'build' 18:30:17 INFO - no previously-included directories found matching 'dist' 18:30:17 INFO - no previously-included directories found matching '.git*' 18:30:17 INFO - Successfully installed jsonschema functools32 18:30:17 INFO - Cleaning up... 18:30:17 INFO - Return code: 0 18:30:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:30:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:17 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 0x1104c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110557618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3b046df20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110426d30>, '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 0x11018bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11041eb20>, '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.y9fGrmMPDB/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.GILpbBmRci/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/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 18:30:17 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 18:30:17 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 18:30:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y9fGrmMPDB/Render', 18:30:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:17 INFO - 'HOME': '/Users/cltbld', 18:30:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:17 INFO - 'LOGNAME': 'cltbld', 18:30:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:17 INFO - 'MOZ_NO_REMOTE': '1', 18:30:17 INFO - 'NO_EM_RESTART': '1', 18:30:17 INFO - 'PAGER': '/bin/cat', 18:30:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:17 INFO - 'PWD': '/builds/slave/test', 18:30:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:17 INFO - 'SHELL': '/bin/bash', 18:30:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GILpbBmRci/Listeners', 18:30:17 INFO - 'TMPDIR': '/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/', 18:30:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:17 INFO - 'USER': 'cltbld', 18:30:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:17 INFO - 'XPC_FLAGS': '0x0', 18:30:17 INFO - 'XPC_SERVICE_NAME': '0', 18:30:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:30:17 INFO - Cleaning up... 18:30:17 INFO - Return code: 0 18:30:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:30:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:17 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 0x1104c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110557618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3b046df20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110426d30>, '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 0x11018bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11041eb20>, '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.y9fGrmMPDB/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.GILpbBmRci/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/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 18:30:17 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 18:30:17 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 18:30:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y9fGrmMPDB/Render', 18:30:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:17 INFO - 'HOME': '/Users/cltbld', 18:30:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:17 INFO - 'LOGNAME': 'cltbld', 18:30:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:17 INFO - 'MOZ_NO_REMOTE': '1', 18:30:17 INFO - 'NO_EM_RESTART': '1', 18:30:17 INFO - 'PAGER': '/bin/cat', 18:30:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:17 INFO - 'PWD': '/builds/slave/test', 18:30:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:17 INFO - 'SHELL': '/bin/bash', 18:30:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GILpbBmRci/Listeners', 18:30:17 INFO - 'TMPDIR': '/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/', 18:30:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:17 INFO - 'USER': 'cltbld', 18:30:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:17 INFO - 'XPC_FLAGS': '0x0', 18:30:17 INFO - 'XPC_SERVICE_NAME': '0', 18:30:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:18 INFO - Downloading/unpacking blobuploader==1.2.4 18:30:18 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 18:30:18 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 18:30:18 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 18:30:18 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 18:30:18 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 18:30:18 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 18:30:20 INFO - Downloading blobuploader-1.2.4.tar.gz 18:30:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:30:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:30: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 18:30: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 18:30: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 18:30: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 18:30: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 18:30: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 18:30:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:30:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:30:21 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 18:30:21 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 18:30:21 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 18:30:21 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 18:30:21 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 18:30:21 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 18:30:21 INFO - Downloading docopt-0.6.1.tar.gz 18:30:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:30:21 INFO - Installing collected packages: blobuploader, requests, docopt 18:30:21 INFO - Running setup.py install for blobuploader 18:30:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:30:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:30:22 INFO - Running setup.py install for requests 18:30:22 INFO - Running setup.py install for docopt 18:30:22 INFO - Successfully installed blobuploader requests docopt 18:30:22 INFO - Cleaning up... 18:30:22 INFO - Return code: 0 18:30:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:30:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:22 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 0x1104c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110557618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3b046df20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110426d30>, '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 0x11018bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11041eb20>, '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.y9fGrmMPDB/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.GILpbBmRci/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/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 18:30:22 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 18:30:22 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 18:30:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y9fGrmMPDB/Render', 18:30:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:22 INFO - 'HOME': '/Users/cltbld', 18:30:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:22 INFO - 'LOGNAME': 'cltbld', 18:30:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:22 INFO - 'MOZ_NO_REMOTE': '1', 18:30:22 INFO - 'NO_EM_RESTART': '1', 18:30:22 INFO - 'PAGER': '/bin/cat', 18:30:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:22 INFO - 'PWD': '/builds/slave/test', 18:30:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:22 INFO - 'SHELL': '/bin/bash', 18:30:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GILpbBmRci/Listeners', 18:30:22 INFO - 'TMPDIR': '/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/', 18:30:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:22 INFO - 'USER': 'cltbld', 18:30:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:22 INFO - 'XPC_FLAGS': '0x0', 18:30:22 INFO - 'XPC_SERVICE_NAME': '0', 18:30:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:30:22 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-P13vH4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:30:23 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-MqQq5e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:30:23 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-d65wst-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:30:23 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-7GN6m4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:30:23 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-ExOyfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:30:23 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-rIqXWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:30:23 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-jQLTHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:30:23 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-mXZrnT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:30:23 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-FtqEJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:30:23 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-k8mKs9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:30:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:30:24 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-ddsFwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:30:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:30:24 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-yWjE_c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:30:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:30:24 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-oaKHQE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:30:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:30:24 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-tcF_jU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:30:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:24 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-V2JR7A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:30:24 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-oc_gyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:30:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:30:24 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-O14dn3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:30:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:30:24 INFO - Running setup.py install for manifestparser 18:30:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:30:24 INFO - Running setup.py install for mozcrash 18:30:25 INFO - Running setup.py install for mozdebug 18:30:25 INFO - Running setup.py install for mozdevice 18:30:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:30:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:30:25 INFO - Running setup.py install for mozfile 18:30:25 INFO - Running setup.py install for mozhttpd 18:30:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:30:25 INFO - Running setup.py install for mozinfo 18:30:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:30:25 INFO - Running setup.py install for mozInstall 18:30:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:30:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:30:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:30:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:30:25 INFO - Running setup.py install for mozleak 18:30:26 INFO - Running setup.py install for mozlog 18:30:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:30:26 INFO - Running setup.py install for moznetwork 18:30:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:30:26 INFO - Running setup.py install for mozprocess 18:30:26 INFO - Running setup.py install for mozprofile 18:30:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:30:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:30:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:30:26 INFO - Running setup.py install for mozrunner 18:30:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:30:26 INFO - Running setup.py install for mozscreenshot 18:30:27 INFO - Running setup.py install for moztest 18:30:27 INFO - Running setup.py install for mozversion 18:30:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:30:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:30:27 INFO - Cleaning up... 18:30:27 INFO - Return code: 0 18:30:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:30:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:27 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 0x1104c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110557618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3b046df20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110426d30>, '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 0x11018bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11041eb20>, '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.y9fGrmMPDB/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.GILpbBmRci/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/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 18:30:27 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 18:30:27 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 18:30:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y9fGrmMPDB/Render', 18:30:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:27 INFO - 'HOME': '/Users/cltbld', 18:30:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:27 INFO - 'LOGNAME': 'cltbld', 18:30:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:27 INFO - 'MOZ_NO_REMOTE': '1', 18:30:27 INFO - 'NO_EM_RESTART': '1', 18:30:27 INFO - 'PAGER': '/bin/cat', 18:30:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:27 INFO - 'PWD': '/builds/slave/test', 18:30:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:27 INFO - 'SHELL': '/bin/bash', 18:30:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GILpbBmRci/Listeners', 18:30:27 INFO - 'TMPDIR': '/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/', 18:30:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:27 INFO - 'USER': 'cltbld', 18:30:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:27 INFO - 'XPC_FLAGS': '0x0', 18:30:27 INFO - 'XPC_SERVICE_NAME': '0', 18:30:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:30:27 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-bYI9yS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:30:27 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)) 18:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:30:27 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-8JEcXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:30:27 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)) 18:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:30:27 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-OCF3tL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:30:27 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)) 18:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:30:27 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-4qnTbx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:30:28 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)) 18:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:30:28 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-0MR9O1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:30:28 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)) 18:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:30:28 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-Ml7Aag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:30:28 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)) 18:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:30:28 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-No6RZc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:30:28 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)) 18:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:30:28 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-ICwdM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:30:28 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)) 18:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:30:28 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-N44Phj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:30:28 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)) 18:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:30:28 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-lSFILF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:30:28 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)) 18:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:30:28 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-MUDBKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:30:28 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)) 18:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:30:28 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-AS7whE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:30:28 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)) 18:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:30:28 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-hG0kxI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:30:28 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)) 18:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:30:28 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-FZAqeM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:30:29 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)) 18:30:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:29 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-CZGR8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:29 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)) 18:30:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:30:29 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-PXzjHM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:30:29 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)) 18:30:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:30:29 INFO - Running setup.py (path:/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/pip-v2vpqg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:30:29 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)) 18:30:29 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)) 18:30:29 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)) 18:30:29 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)) 18:30:29 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)) 18:30:29 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)) 18:30:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:30:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:31 INFO - Downloading blessings-1.6.tar.gz 18:30:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:30:31 INFO - Installing collected packages: blessings 18:30:31 INFO - Running setup.py install for blessings 18:30:32 INFO - Successfully installed blessings 18:30:32 INFO - Cleaning up... 18:30:32 INFO - Return code: 0 18:30:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:30:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:30:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:30:32 INFO - Reading from file tmpfile_stdout 18:30:32 INFO - Current package versions: 18:30:32 INFO - blessings == 1.6 18:30:32 INFO - blobuploader == 1.2.4 18:30:32 INFO - docopt == 0.6.1 18:30:32 INFO - functools32 == 3.2.3-2 18:30:32 INFO - jsonschema == 2.5.1 18:30:32 INFO - manifestparser == 1.1 18:30:32 INFO - mozInstall == 1.12 18:30:32 INFO - mozcrash == 0.17 18:30:32 INFO - mozdebug == 0.1 18:30:32 INFO - mozdevice == 0.48 18:30:32 INFO - mozfile == 1.2 18:30:32 INFO - mozhttpd == 0.7 18:30:32 INFO - mozinfo == 0.9 18:30:32 INFO - mozleak == 0.1 18:30:32 INFO - mozlog == 3.3 18:30:32 INFO - moznetwork == 0.27 18:30:32 INFO - mozprocess == 0.23 18:30:32 INFO - mozprofile == 0.28 18:30:32 INFO - mozrunner == 6.12 18:30:32 INFO - mozscreenshot == 0.1 18:30:32 INFO - mozsystemmonitor == 0.3 18:30:32 INFO - moztest == 0.7 18:30:32 INFO - mozversion == 1.4 18:30:32 INFO - psutil == 3.1.1 18:30:32 INFO - requests == 1.2.3 18:30:32 INFO - wsgiref == 0.1.2 18:30:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:30:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:32 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 0x1104c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110557618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3b046df20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110426d30>, '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 0x11018bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11041eb20>, '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.y9fGrmMPDB/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.GILpbBmRci/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/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 18:30:32 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 18:30:32 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 18:30:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y9fGrmMPDB/Render', 18:30:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:32 INFO - 'HOME': '/Users/cltbld', 18:30:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:32 INFO - 'LOGNAME': 'cltbld', 18:30:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:32 INFO - 'MOZ_NO_REMOTE': '1', 18:30:32 INFO - 'NO_EM_RESTART': '1', 18:30:32 INFO - 'PAGER': '/bin/cat', 18:30:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:32 INFO - 'PWD': '/builds/slave/test', 18:30:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:32 INFO - 'SHELL': '/bin/bash', 18:30:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GILpbBmRci/Listeners', 18:30:32 INFO - 'TMPDIR': '/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/', 18:30:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:32 INFO - 'USER': 'cltbld', 18:30:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:32 INFO - 'XPC_FLAGS': '0x0', 18:30:32 INFO - 'XPC_SERVICE_NAME': '0', 18:30:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:32 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)) 18:30:32 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)) 18:30:32 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)) 18:30:32 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)) 18:30:32 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)) 18:30:32 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)) 18:30:32 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)) 18:30:32 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)) 18:30:32 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)) 18:30:32 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)) 18:30:32 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)) 18:30:32 INFO - Cleaning up... 18:30:32 INFO - Return code: 0 18:30:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:30:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1104c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110557618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3b046df20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110426d30>, '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 0x11018bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11041eb20>, '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.y9fGrmMPDB/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.GILpbBmRci/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/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 18:30:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:30:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:30:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y9fGrmMPDB/Render', 18:30:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:32 INFO - 'HOME': '/Users/cltbld', 18:30:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:32 INFO - 'LOGNAME': 'cltbld', 18:30:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:32 INFO - 'MOZ_NO_REMOTE': '1', 18:30:32 INFO - 'NO_EM_RESTART': '1', 18:30:32 INFO - 'PAGER': '/bin/cat', 18:30:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:32 INFO - 'PWD': '/builds/slave/test', 18:30:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:32 INFO - 'SHELL': '/bin/bash', 18:30:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GILpbBmRci/Listeners', 18:30:32 INFO - 'TMPDIR': '/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/', 18:30:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:32 INFO - 'USER': 'cltbld', 18:30:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:32 INFO - 'XPC_FLAGS': '0x0', 18:30:32 INFO - 'XPC_SERVICE_NAME': '0', 18:30:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:30:32 INFO - Cleaning up... 18:30:32 INFO - Return code: 0 18:30:32 INFO - Running post-action listener: _resource_record_post_action 18:30:32 INFO - Running post-action listener: _start_resource_monitoring 18:30:32 INFO - Starting resource monitoring. 18:30:32 INFO - [mozharness: 2016-08-27 01:30:32.993834Z] Finished create-virtualenv step (success) 18:30:32 INFO - [mozharness: 2016-08-27 01:30:32.994841Z] Running install step. 18:30:32 INFO - Running pre-action listener: _resource_record_pre_action 18:30:32 INFO - Running main action method: install 18:30:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:30:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:30:33 INFO - Reading from file tmpfile_stdout 18:30:33 INFO - Detecting whether we're running mozinstall >=1.0... 18:30:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:30:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:30:33 INFO - Reading from file tmpfile_stdout 18:30:33 INFO - Output received: 18:30:33 INFO - Usage: mozinstall [options] installer 18:30:33 INFO - Options: 18:30:33 INFO - -h, --help show this help message and exit 18:30:33 INFO - -d DEST, --destination=DEST 18:30:33 INFO - Directory to install application into. [default: 18:30:33 INFO - "/builds/slave/test"] 18:30:33 INFO - --app=APP Application being installed. [default: firefox] 18:30:33 INFO - mkdir: /builds/slave/test/build/application 18:30:33 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'] 18:30:33 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 18:31:07 INFO - Reading from file tmpfile_stdout 18:31:07 INFO - Output received: 18:31:07 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:31:07 INFO - Running post-action listener: _resource_record_post_action 18:31:07 INFO - [mozharness: 2016-08-27 01:31:07.308764Z] Finished install step (success) 18:31:07 INFO - [mozharness: 2016-08-27 01:31:07.308910Z] Running run-tests step. 18:31:07 INFO - Running pre-action listener: _resource_record_pre_action 18:31:07 INFO - Running main action method: run_tests 18:31:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:31:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:31:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:31:07 INFO - 2016-08-26 18:31:07.323 screenresolution[1640:7588] starting screenresolution argv=screenresolution get 18:31:07 INFO - 2016-08-26 18:31:07.325 screenresolution[1640:7588] Display 0: 1600x1200x32@60 18:31:07 INFO - 2016-08-26 18:31:07.331 screenresolution[1641:7590] starting screenresolution argv=screenresolution list 18:31:07 INFO - Available Modes on Display 0 18:31:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:31:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:31:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:31:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:31:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:31:07 INFO - Intel Iris: 18:31:07 INFO - Chipset Model: Intel Iris 18:31:07 INFO - Type: GPU 18:31:07 INFO - Bus: Built-In 18:31:07 INFO - VRAM (Dynamic, Max): 1536 MB 18:31:07 INFO - Vendor: Intel (0x8086) 18:31:07 INFO - Device ID: 0x0a2e 18:31:07 INFO - Revision ID: 0x0009 18:31:07 INFO - Displays: 18:31:07 INFO - Display: 18:31:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:31:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:31:07 INFO - Display Serial Number: 1600x1200 60 18:31:07 INFO - Main Display: Yes 18:31:07 INFO - Mirror: Off 18:31:07 INFO - Online: Yes 18:31:07 INFO - Rotation: Supported 18:31:07 INFO - Return code: 0 18:31:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:31:07 INFO - Python 2.7.3 18:31:07 INFO - Return code: 0 18:31:07 INFO - grabbing minidump binary from tooltool 18:31:07 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')]} 18:31:07 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 0x110426d30>, '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 0x11018bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11041eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:31:07 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 18:31:07 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 18:31:07 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 18:31:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:31:07 INFO - Return code: 0 18:31:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:31:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:31:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:31:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:31:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:31:07 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/E0Y9kB1fT064M9C1snd2wQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0128', '--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 18:31:07 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/E0Y9kB1fT064M9C1snd2wQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0128 --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 18:31:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y9fGrmMPDB/Render', 18:31:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:07 INFO - 'HOME': '/Users/cltbld', 18:31:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:07 INFO - 'LOGNAME': 'cltbld', 18:31:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:31:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:31:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:07 INFO - 'MOZ_NO_REMOTE': '1', 18:31:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:31:07 INFO - 'NO_EM_RESTART': '1', 18:31:07 INFO - 'PAGER': '/bin/cat', 18:31:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:07 INFO - 'PWD': '/builds/slave/test', 18:31:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:31:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:07 INFO - 'SHELL': '/bin/bash', 18:31:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GILpbBmRci/Listeners', 18:31:07 INFO - 'TMPDIR': '/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/', 18:31:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:07 INFO - 'USER': 'cltbld', 18:31:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:07 INFO - 'XPC_FLAGS': '0x0', 18:31:07 INFO - 'XPC_SERVICE_NAME': '0', 18:31:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:07 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/E0Y9kB1fT064M9C1snd2wQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0128', '--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 18:31:07 INFO - mozversion application_buildid: 20160826162112 18:31:07 INFO - mozversion application_changeset: f38d60b049f0180156ff254c5bf3fae79020e4a5 18:31:07 INFO - mozversion application_display_name: Nightly 18:31:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:31:07 INFO - mozversion application_name: Firefox 18:31:07 INFO - mozversion application_remotingname: firefox 18:31:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:31:07 INFO - mozversion application_vendor: Mozilla 18:31:07 INFO - mozversion application_version: 51.0a1 18:31:07 INFO - mozversion platform_buildid: 20160826162112 18:31:07 INFO - mozversion platform_changeset: f38d60b049f0180156ff254c5bf3fae79020e4a5 18:31:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:31:07 INFO - mozversion platform_version: 51.0a1 18:31:07 INFO - using testdate: 1472261467 18:31:07 INFO - actual date: 1472261467 18:31:07 INFO - starting webserver on 'localhost:49267' 18:31:07 INFO - SUITE-START | Running 2 tests 18:31:07 INFO - TEST-START | dromaeo_css 18:31:07 INFO - operating with platform_type : mac_ 18:31:07 INFO - Initialising browser for dromaeo_css test... 18:31:07 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/tmpwl7lnB/profile http://localhost:49267/getInfo.html) 18:31:10 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 18:31:10 INFO - PROCESS | 1651 | colorDepth:24 18:31:10 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 18:31:10 INFO - PROCESS | 1651 | __metrics 18:31:10 INFO - PROCESS | 1651 | 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" 18:31:10 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:31:11 INFO - TEST-INFO | 1651: exit 0 18:31:11 INFO - Browser initialized. 18:31:11 INFO - Running cycle 1/1 for dromaeo_css test... 18:31:11 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/tmpwl7lnB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:31:22 INFO - PROCESS | 1653 | RSS: Main: 233271296 18:31:22 INFO - PROCESS | 1653 | 18:32:14 INFO - PROCESS | 1653 | 1472261534287 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] 18:34:26 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 18:34:27 INFO - PROCESS | 1653 | RSS: Main: 355545088 18:34:27 INFO - PROCESS | 1653 | 18:37:30 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 18:37:30 INFO - PROCESS | 1653 | RSS: Main: 371920896 18:37:30 INFO - PROCESS | 1653 | 18:40:33 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 18:40:34 INFO - PROCESS | 1653 | RSS: Main: 331390976 18:40:34 INFO - PROCESS | 1653 | 18:43:36 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 18:43:37 INFO - PROCESS | 1653 | RSS: Main: 336052224 18:43:37 INFO - PROCESS | 1653 | 18:46:40 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 18:46:41 INFO - PROCESS | 1653 | RSS: Main: 325488640 18:46:41 INFO - PROCESS | 1653 | 18:49:44 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 18:49:44 INFO - PROCESS | 1653 | RSS: Main: 489107456 18:49:44 INFO - PROCESS | 1653 | 18:49:44 INFO - PROCESS | 1653 | __start_tp_report 18:49:44 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 18:49:44 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 18:49:44 INFO - PROCESS | 1653 | |i|pagename|runs| 18:49:44 INFO - PROCESS | 1653 | |0;dojo.html;3327.783694059885;3335.382954153482;3368.865245390186;3395.9320813583745;3441.948370774439;5552.444755524456;5644.153376993453;5653.321601407837;5715.457031581998;5718.685472299027;5658.179563963233;5682.289713785787;5741.454561816631;5790.68151251681;5805.10020946754;5150;5184.740762961856;5217.817376391827;5222.529972302492;5337.279467271907;5266.315379000744;5333.519983201511;5337.359516857982;5347.59893008024;5382.273393091921;5163.457836927106;5189.558887494568;5195.896082078375;5224.1902505111575;5268.973655131738;5265.578753699695;5265.76304066318;5271.525562699337;5272.551833094191;5273.6572122811895;5164.2253661950635;5201.921971170435;5230.267762513251;5231.607629427808;5236.476352364771;5707.74315155819;5709.571782116358;5720.742566584472;5731.914021289684;5737.82786516401;6123.801220575414;6158.7228574714245;6185.505159587267;6187.628742275433;6210.4100110489435;6201.5348848836065;6241.500679945639;6263.217097862767;6303.149074874879;6303.243610766692;4798.256270278112;5695.231143795535;5857.092150716646;5867.647941123589;5867.70661466925;6195.597286176384;6223.782167624111;6224.190855188796;6287.2140982377205;6299.086632438362;6173.506119510428;6173.722182501709;6194.318624951252;6201.255849298113;6206.813795586139;6119.908201376983;6287.654179020198;6288.874222515524;6291.528135389773;6303.46420554258;5992.910438511636;6138.081874382086;6150.415710507495;6191.133241346125;6247.875042499216;5558.277423934863;6250.687465626791;6256.718447669866;6273.215848018997;6274.309825919146;4880.780364883589;5014.699118052856;5014.824481143142;5022.748862556858;5027.899442011212;3595.676389124991;4433.556644335541;4453;4501.122281155209;4562.771861406916;5708.543316534668;5725;5740.512056475246;5750.223719797774;5765.4522820332;6259;6259.061140828821;6294.118823364733;6295.779647712308;6318.557700960981;5608.803691870765;5663.518600918968;5705.315362156568;5737.827865164052;5758.568107391879;5467.289252397163;5472.50747432733;5474.233607294982;5477.315335583052;5516.365617953907;6247.718852651551;6257.124002639725;6269.028300613412;6285.622862628165;6297.1813664223355;6111.166351794699;6185.659788711664;6229.159063526366;6261.405166509175;6290.779822706183;6283;6291.937080629318;6301.40136687014;6319.083732858801;6320.241571011324;6164.876702466016;6181.5363847710705;6192.566520343533;6197.1324014638885;6197.349278325711;6056.121862329849;6108.463491842514;6199.070139479114;6247.68761561911;6302.621842689453;6097.833703576693;6121.239585875234;6142.7136481981925;6145.0782382642965;6156.230471191101;6102.761931713857;6113.841195312776;6138.541671868701;6147.954847675996;6151.876962460815;5795.95672778887;5882.294124705063;5932.317783454958;5954.46409823118;5995.850103747442;6104.125697713769;6120.26428866606;6127.281156068254;6139.124180917401;6141.301904043499;5926.4666180044005;5935.961206788881;5945.583809133319;5954.07711804671;5988.311344195473 18:49:44 INFO - PROCESS | 1653 | |1;ext.html;467.8994016286498;475.5957436179248;505.62836315308243;507.98730031749227;536.3429798496844;26439.67801609919;26672.73327266727;31698.366032679332;39931;41361.37957930633;42960.78519607405;43198.56801431993;43235.56764432355;43250;43339.566604333944;9770.755731106727;9936.205103591696;9936.552855121538;9941.204703623729;9952.800943981116;8819.426737262089;9052.547372631376;9059.909400906006;9740.172085372751;9876.358036727626;9000.954995225014;9053.366264361497;12013.339266340348;12257.877421225807;12285.017198624133;10600.886906874783;11977.161598688095;12311.322877241748;12366;12369.381530923507;12016;12097.879021209852;12101.93949030258;12102.636920892388;12122.636320910387;32917.6708232917;40365;40716.59283407158;40728.981775455555;40877.1824563507;3316.1543806329473;3569.8572057117685;3769.5099637047283;4058.411530328086;4090.141070375225;30593.694063059305;31608.209794755086;31662.525062123856;31686.683133168604;31850.681493185002;2871.3682989742224;3174.873005079794;3304.4052070627276;3349.5764300172327;3369.932601347984;382706;382919;384806;385093;388949.05525472475;61305;61623.383766161765;61691;61785.69107154435;61804.69097654483;64583.354166458674;64599.70800584056;64641.67679161574;64677;64704.35295646983;16659.500214993568;16791.832081679273;16843.157842107845;16844.410445634345;16867.662646746998;13007.739845203232;13060.08579399452;13066.673333166556;13070.869291307155;13076.869231307755;12184.268943863397;16702.331906723593;17340.132993350533;17356.392526261774;17433.302667893142;5436.864078397993;5474.534664553478;6366.745330186741;6386.584871983307;6418.775342862978;767.6008475592772;926.6756635177634;933.705882646964;984.7882705218412;1025.1696126137851;23119.653205201936;29469.410611788073;29628.111156665334;30361.392772144434;30539.847300763355;914.849049906758;992.2062350119875;1017.730301470111;1029.5572903651353;1053.847192157133;14639.707205855822;15367.692646147238;15605.3757849687;15632.84367156314;15875.52373428799;661480.3851961419;914520;923705;923872;925454;50188.74905625521;50655.49344506581;58178.41821581815;58493.122603160984;58873.70563147157;1208.915375923677;1233.8457692788402;1234.0436161974362;1234.4383305595932;1320.914140580859;28734.71265287404;30941.226469338446;31083.378332433676;31592.052238432883;31683.04950851478;49543.50456495389;50137.74931125321;50226.24660630021;50255;50296.497035029184;547525;553091;553659;553699;553929;21220.787792121882;26210.86894565515;26219.606705899045;26219.868900655376;26264.21207363782;46044;47160;47190;47347;47838.52161478341;36562;37530.437043444916;38995;39506.60493395029;39860.800695997495;36563.817180913924;37521.81239093787;38119.80940095282;39246.4113038299;39738.40392394174;35974.460383094796;36382;38629.806850965564;38902;38928.22143557169;29064.837406503502;31160;32869.34261314808;33405.32325474387;34132.658673412945;21673.133074676836;24141.517169656156;24689.50620987606;25056.62415063812;25709.485810284063 18:49:44 INFO - PROCESS | 1653 | |2;jquery.html;328.6746121339874;353.9911502212446;381.7575839342017;382.83155411618884;408.8609872643303;15402.84597154028;16525.50423487296;16819.495415137535;20428.69356959643;20648.27731029416;17835.37576184834;20467.692984605204;20756.27353042644;21039.47401314968;21239.575208495822;5721.885562288741;5786.942130578704;5968.283805943272;5969.522438204933;5978.163057172;6048.788292409767;6140.171076904628;6157.353477884825;6159.0453479710495;6165.722542485577;3929.1552316252;7687.577183254919;8045.235702608243;8097.109317975018;8126.984126984127;7649.847003059963;7970.481918675297;8065.274125328749;8104;8124.471909325934;7271.527350722186;7639.579823109727;7818.491798033109;7825.413094017971;7833.255840695125;23294;28861.989830355837;28984.42031159386;29571.81712731488;29908.102756917327;2367.5264947010496;3387.3733359328357;3442.1566716154452;3496.3881320768764;3528.065062758371;16063.277152528164;16862;17000.914995424944;17729;17865.797110120602;1984.474114359696;1993.591313780686;1994.3076647493172;2051.5384038591346;2060.6599911014664;120051;150455;152037;152587;156195;19860.90069549643;20914.268000619904;21241.78758212398;21311.25410610621;21993.450163745714;35306.64693353033;35500.64499355025;35525;35552.64447355493;35702.64297357045;10587.258891877495;10816.729581760519;10893.074088702391;10951.233413661126;11094.057005154491;8924.821503570021;8925.152110549487;8937.419067760575;9136.95431522838;9143.451392916446;8120.957212482419;9526.570724465217;9530.999245079305;9760.365576237522;9761.438169892925;3148.763842711806;3593.5328407307406;3727.9254414912093;3743.326201283795;3748.212875296164;696.4045740891465;700.0199720391493;725.9673314700851;736.6059158350257;967.9564419601135;18282.817171828112;20930.267440639505;21034.368968930958;21096.578068438546;21151.836648984146;898.442965361467;906.1980147569317;908.8454962656365;931.3549519365035;974.9610015599438;8662.436941598902;10965.780684386427;10975.50610222542;11096.1123110151;11145.944270278758;568349;576083;580166;588940;593591;138180;140017;144586;145589;148837;964.1900803325241;984.2568860510412;1007.8034783217203;1010.4842735896156;1012.9797404052025;18309.725354119433;18515.907420462812;19346;19435.3197638082;19503.902480488076;42747.14505709806;55579;55829.44170558242;56223.87552249014;57001;77299;82613.3477330462;83525.58237208775;84845;85128;7731.38148948094;7934.20657934202;8144.104148543774;8228.136045715113;8233.670653174046;10060.597576097167;12841.422136004088;12876.806847897102;13233.53682621142;13450.192988420726;11193.776124477628;11222.158338124511;11617.593384231512;11668.591599293984;11683.824742628698;11126.332420054823;11154.944225278821;11262.436878156223;11301.660950171508;11612.419379031184;9290.210332121575;9726.513674316115;11470.713232169262;11789.941050294694;11889.167758257185;6267.091271765659;7101.786946391617;7312.195658477457;7577.772666820004;8240.175982401712;9643.373180743229;10399.480025998519;11336.716582085513;11367.374794386387;11779.175457717878 18:49:44 INFO - PROCESS | 1653 | |3;mootools.html;1344.1263178933696;1500.3173556032007;1669.6660667866422;1869.6821540338142;1913.9234430622766;7921.405894557903;8207.958960205206;8390.79023024424;8829.514376709287;8868.689595864145;8372.372072094588;8663.566821658924;8674.956625216864;8767.47395156291;8785.956070219656;2298.2186056740707;2327.7439481657;2329.893300682171;2334.2180369576213;2335.5212181502834;2047.406252186864;2131.8081372676456;2257.24382331919;2273.397549649336;2302.976970230293;2099.5066159452595;2191.364504293753;2326.581215381231;2334.404726794665;2334.673145759595;1965.3023176772326;2287.816974642033;2308.145985985188;2322.930312090623;2328.4295347639936;2301.2290882554366;2301.9424514387106;2302.1597117052324;2302.896369663369;2311.3759284993125;8834.690785822466;8922.821543569156;8938.64245430182;8999.100089991014;8999.640014399482;5177.223416487519;5315.255864178979;5346.091164502044;5397.433269506684;5404.162354834967;2945.6907024762518;3497.1956450016382;3549.2901419715904;3568.678818906311;3570.4644303354444;998.7952469743685;1026.702256345659;1028.480617288272;1048.3185929146039;1056.3609016545033;11716;11739.882601173964;11752.88247117535;11850.585229516926;11853.111016673784;4429.313456414261;4643.326717625925;4752.336682161216;4766.451858036301;4767;9973.80052398948;9976.950115249378;9981.454729044048;9997.550110245056;9998.250131240186;5274.393444753826;5274.7098909560345;5279.260903473517;5279.577633789288;5288;4635.7450340230935;4798.016406636701;4822.324874517571;4832.613390928717;4836.588889944394;3864.5555761087835;3881.1073453105378;3909.745866518724;3972.3048466518244;4093.140440507527;1128.3624752015085;1209.1354681402838;1219.6950762309423;1221.584661215191;1260.3005332040623;737.756540341686;879.907609700985;885.1900511032334;899.9415038022507;920.4615300049412;5816.1566572846705;5879.706014699248;5894.233749612523;5999.610025348338;6070.757169713251;826.2068414322198;860.840744462264;903.5527413930147;908.7410088124985;929.9860502092474;3883.59222281662;3920.9803950980063;4040.8181631826637;4062.918741625151;4065.0447144920918;10424.166066714644;10638.787224255471;10720.35677859331;10727.249092563447;10739.463026848627;10231.641892533726;10574.312669676447;10679.679609611723;10688.808794197172;10734.892651073545;7957.482763620462;7979.4015448841565;8011.6394762237005;8078.980254936266;8086.353091752646;5592.105263157875;5644.153376993484;5718.628289161357;5767.740451679769;5802.912956305574;9774.80450390974;9829.82042154946;9831.557579909064;9841.753956151153;9850.408975461496;9561.235101192027;10174.49127543605;10280.126189273995;10281.74295642615;10286.177105831366;7437.8140546486775;7510.737124200627;7510.924890751241;7515.473916825769;7544.09470863478;7800.531968081933;8174.918250817415;8176.959115204386;8258.628361723617;8279.586020699062;6215.751369945152;7399.8150046249275;7418.295261950106;7424.406047516078;7433.389142542027;7327.2672732726305;7341.33927946466;7357.264273572814;7424.628768561658;7430.405567554474;6764.695588698422;6781.559198652072;6821.420179284816;6880.0711903894335;6946.097007389007;4702.600278976325;4862.197737373293;4940.135476291706;4960.379952505937;5036.370453693288;4977.328060711758;5042.722650254124;5059;5117.769700363418;5165.018646457161 18:49:44 INFO - PROCESS | 1653 | |4;prototype.html;302.7003266765902;307.9476488996872;311.6602902835908;318.24417009602195;319.06832050412794;4429.557044295573;4712.599429048535;4731.858044258669;5102.800841802183;5424.566034717223;4006.018525461266;4688.71867687939;5379.919301210475;5426.2674538937235;5459.235707001013;3845.788481633509;3851.8074096295145;3891.3190191716335;4098.0329441867925;4105.938410923838;3844.673202777767;4037.7577345359227;4049.3521036634143;4072.124493233954;4095.692823038269;3522.7357948153863;3536.8328451611037;3978.3435733104;4011.438398624195;4012.6589239914642;3345.832708364572;4025.08808316049;4043;4061.715679902408;4138.895828128903;2650.5891586804078;3311.8012919224925;3341.9280046371164;3547.7161827053774;3565.6434356564396;5147.845564633067;5167.948320516784;5168.457311982225;5217.912834866046;5237.188235823415;3793.943090853639;4662.743549104798;4681.391419115527;4723.409573803275;4750.928736068961;4775.734430375918;5090.694558326475;5583.302087239095;5658.575606829504;5727.684977326246;3284.244623736554;3479.2345683949743;3707.850566324421;3723.6090210527777;3939.8424063037446;5153.407358153785;5348.171033489776;5469.714617064987;5864.325602555676;5896.498797602209;3316.6849149330774;3319.336132773455;3557.964420355815;4008.2584721825974;4039.3537034074407;3392.8982130536124;3412.982935085309;3585.539953944404;4068.2270368630457;4131.400946862688;4537.02453972391;4609.24848557546;4712.081144176853;4761;4775.4479794067065;5313.309269794902;5694.202811606378;5744.281964754406;5771.884562308815;5787.2187254720075;1389.867962543577;1406.5850574080466;1408.8661577150153;1409.689868228988;1410.7742761158165;832.4505826154709;863.9481631102154;876.8421684096796;877.6840337478502;889.6930558957149;4645.419322584677;4754.885549964779;4899.608031357483;5030.597552195889;5347.5134062661045;5771.394003629643;5802.912956305659;5814.244148260699;5824.068753591309;5846.824595262149;4252.87241382759;4497.415336006364;5231.607629427808;5293.602979776532;5326.334208223972;2667.826591271561;2973.884793202549;2982.418428406483;3009.217603423082;3026.303950091491;5235.73821308933;5269.814291784318;5543.473370029841;5903.745666356946;5958.404159584007;4616.76916154191;5132.384113906355;5257.502686224089;5648.079652918028;5919.4080591940465;5868.862451971435;5871.735771890189;5974.61165024272;5981.611195272293;6033.849153771191;5369.6509726867625;5799.043157879071;5808.477237048686;5851.61964472308;5859.765609375577;4822.636907915166;5761.683107429131;5795.333536643171;5802.82591522255;5834.024647429756;5472.890542189054;5719.685417302088;6096.207493026056;6099.603525770811;6099.634021958696;3179.42770301348;3904.0239940014994;3905.6484916357663;3919.511634645225;3920;5522.751476183501;5772.884542309214;5868.38381969887;5872.354041055395;5879.5002424794375;5672.432756724294;5760.452756988079;5760.8271751847515;5778.179873340053;5811.302643682785;5342.038433081927;5376.220448034935;5506.889862202814;5627.8593035174445;5674.319081710221;5486.177073439016;5560.304961879765;5565.777368905198;5569.438058493139;5596.468335508121;5255.452569515485;5390.655065046717;5494.780208791603;5569.610127291051;5789.56578256624;4913.729744864067;5106.7446627669215;5430.782768689366;5432.864178395572;5453.2365468833605 18:49:44 INFO - PROCESS | 1653 | |5;yui.html;700.7337211859492;704.73220176333;718.540134314039;724.6811402982687;762.3101093510373;13609.979251556124;14885.553433397003;15118.395264189447;15156.696866062686;15158.242087895602;13295.800562991539;14299.428022879072;14300.427982880698;14352.28238588069;14793.112413255212;653.9869202615945;667.3360006793248;670.5406796344495;832.5462622870535;855.4909828651973;824.583585289431;836.9079401265856;837.0457678246803;837.5728378526965;838.739990602912;904.398574947659;905.5110240470138;907.6233363154283;909.05912380686;911.0388540090203;688.0023965250407;877.9341549383757;899.9605455698678;904.1275169461468;906.374601524949;673.491513907002;673.9427148692367;676.6650507998523;677.7695583501584;681.0601370109217;14545.636359091002;14574.635634109127;14580.270986450634;14584.562463126123;14592.90553208503;13574;13762.311884405739;13846.238456884867;13969.511067112704;14097.577072687733;12859.485620575166;12867.485300587965;12910.741785164339;12926.806097908539;13086.869131308755;4499.10017996402;4518.412606361152;4566.474855391639;4609.7925593348045;4615.169269531483;91134;98473;124267;125272.37363813214;125312;9511.334206605472;10907.236493445384;11041.668749937515;11202;11539.538418463168;21855.453613659785;21873.67189492159;21886;21892;21896.233631822808;7827.112688324098;8089.150639182801;8139.715109971123;8228.094909559944;8228.382871284557;4230.111676547959;4446.9555304447185;4450.443694538183;4511.842085526991;4593.173228818845;3417.79493230407;3528.2237907660274;3606.8016259106003;3609.1879327151705;3610.9638903611153;634.8888944434706;638.5211091681239;639.9648019358887;640.0591131037368;640.8622146238488;489.62299029746896;543.166812009468;617.6016469377226;627.1658693937144;643.29526754102;7798.727044553413;8062.677492900219;8581.927259092614;8840.734777956672;9286.489243091559;521.7000224870715;603.5865432178972;613.2916481463916;627.996860015697;635.7520566978882;3737.570179429346;4019.9397009044887;4370.519242883281;4521.340247307591;4620.722756634613;119214.8078519221;129902;132169;134759.32620337032;158259;118723;126658;128033;129240;130849;99800.50099749454;108487.45756271169;111395;112683;117981.4100929507;9496.477693726634;9763.121319081316;9796.706098817047;9803.656872009446;9806.362586431858;59756.70121649364;62605.747885042954;63280.68359658172;63357.73284534376;63700;71423.64288178526;85203.57398212969;96473.9055008655;96610;99670;12421.100762402726;12979;13018.2839943804;13425.66435839112;13695.178289302674;14468.710625787215;14721.940546777587;14852.257387130816;15095.24523773785;15206.695866082837;13609.727805444034;13669.589912302647;13905.513307034205;14043.36804843805;14348.210848403021;13618.319084045956;13690.52083177084;13888.583342499742;14267.07264027835;14418.206998615176;11345.773084538097;13047.412866420844;13171.341432928506;13495.59513214605;13529.729405412034;10963.780724385308;11127.332360058423;11177.217594768614;11374;11525.711857203636;7566.546007239584;9786.266030047633;10511.789764204826;11357;11469.426528673366 18:49:44 INFO - PROCESS | 1653 | __end_tp_report 18:49:44 INFO - PROCESS | 1653 | __start_cc_report 18:49:44 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,1211 18:49:44 INFO - PROCESS | 1653 | __end_cc_report 18:49:44 INFO - PROCESS | 1653 | __startTimestamp1472262584329__endTimestamp 18:49:44 INFO - PROCESS | 1653 | 18:49:44 INFO - PROCESS | 1653 | ------- Summary: start ------- 18:49:44 INFO - PROCESS | 1653 | Number of tests: 6 18:49:44 INFO - PROCESS | 1653 | 18:49:44 INFO - PROCESS | 1653 | [#0] dojo.html Cycles:165 Average:5747.32 Median:5954.08 stddev:641.41 (10.8%) stddev-sans-first:614.65 18:49:44 INFO - PROCESS | 1653 | Values: 3327.8 3335.4 3368.9 3395.9 3441.9 5552.4 5644.2 5653.3 5715.5 5718.7 5658.2 5682.3 5741.5 5790.7 5805.1 5150.0 5184.7 5217.8 5222.5 5337.3 5266.3 5333.5 5337.4 5347.6 5382.3 5163.5 5189.6 5195.9 5224.2 5269.0 5265.6 5265.8 5271.5 5272.6 5273.7 5164.2 5201.9 5230.3 5231.6 5236.5 5707.7 5709.6 5720.7 5731.9 5737.8 6123.8 6158.7 6185.5 6187.6 6210.4 6201.5 6241.5 6263.2 6303.1 6303.2 4798.3 5695.2 5857.1 5867.6 5867.7 6195.6 6223.8 6224.2 6287.2 6299.1 6173.5 6173.7 6194.3 6201.3 6206.8 6119.9 6287.7 6288.9 6291.5 6303.5 5992.9 6138.1 6150.4 6191.1 6247.9 5558.3 6250.7 6256.7 6273.2 6274.3 4880.8 5014.7 5014.8 5022.7 5027.9 3595.7 4433.6 4453.0 4501.1 4562.8 5708.5 5725.0 5740.5 5750.2 5765.5 6259.0 6259.1 6294.1 6295.8 6318.6 5608.8 5663.5 5705.3 5737.8 5758.6 5467.3 5472.5 5474.2 5477.3 5516.4 6247.7 6257.1 6269.0 6285.6 6297.2 6111.2 6185.7 6229.2 6261.4 6290.8 6283.0 6291.9 6301.4 6319.1 6320.2 6164.9 6181.5 6192.6 6197.1 6197.3 6056.1 6108.5 6199.1 6247.7 6302.6 6097.8 6121.2 6142.7 6145.1 6156.2 6102.8 6113.8 6138.5 6148.0 6151.9 5796.0 5882.3 5932.3 5954.5 5995.9 6104.1 6120.3 6127.3 6139.1 6141.3 5926.5 5936.0 5945.6 5954.1 5988.3 18:49:44 INFO - PROCESS | 1653 | 18:49:44 INFO - PROCESS | 1653 | [#1] ext.html Cycles:180 Average:72797.31 Median:210412.90 stddev:172176.56 (81.8%) stddev-sans-first:172573.92 18:49:44 INFO - PROCESS | 1653 | Values: 467.9 475.6 505.6 508.0 536.3 26439.7 26672.7 31698.4 39931.0 41361.4 42960.8 43198.6 43235.6 43250.0 43339.6 9770.8 9936.2 9936.6 9941.2 9952.8 8819.4 9052.5 9059.9 9740.2 9876.4 9001.0 9053.4 12013.3 12257.9 12285.0 10600.9 11977.2 12311.3 12366.0 12369.4 12016.0 12097.9 12101.9 12102.6 12122.6 32917.7 40365.0 40716.6 40729.0 40877.2 3316.2 3569.9 3769.5 4058.4 4090.1 30593.7 31608.2 31662.5 31686.7 31850.7 2871.4 3174.9 3304.4 3349.6 3369.9 382706.0 382919.0 384806.0 385093.0 388949.1 61305.0 61623.4 61691.0 61785.7 61804.7 64583.4 64599.7 64641.7 64677.0 64704.4 16659.5 16791.8 16843.2 16844.4 16867.7 13007.7 13060.1 13066.7 13070.9 13076.9 12184.3 16702.3 17340.1 17356.4 17433.3 5436.9 5474.5 6366.7 6386.6 6418.8 767.6 926.7 933.7 984.8 1025.2 23119.7 29469.4 29628.1 30361.4 30539.8 914.8 992.2 1017.7 1029.6 1053.8 14639.7 15367.7 15605.4 15632.8 15875.5 661480.4 914520.0 923705.0 923872.0 925454.0 50188.7 50655.5 58178.4 58493.1 58873.7 1208.9 1233.8 1234.0 1234.4 1320.9 28734.7 30941.2 31083.4 31592.1 31683.0 49543.5 50137.7 50226.2 50255.0 50296.5 547525.0 553091.0 553659.0 553699.0 553929.0 21220.8 26210.9 26219.6 26219.9 26264.2 46044.0 47160.0 47190.0 47347.0 47838.5 36562.0 37530.4 38995.0 39506.6 39860.8 36563.8 37521.8 38119.8 39246.4 39738.4 35974.5 36382.0 38629.8 38902.0 38928.2 29064.8 31160.0 32869.3 33405.3 34132.7 21673.1 24141.5 24689.5 25056.6 25709.5 18:49:44 INFO - PROCESS | 1653 | 18:49:44 INFO - PROCESS | 1653 | [#2] jquery.html Cycles:180 Average:37382.42 Median:1941.03 stddev:98237.69 (5061.1%) stddev-sans-first:98473.87 18:49:44 INFO - PROCESS | 1653 | Values: 328.7 354.0 381.8 382.8 408.9 15402.8 16525.5 16819.5 20428.7 20648.3 17835.4 20467.7 20756.3 21039.5 21239.6 5721.9 5786.9 5968.3 5969.5 5978.2 6048.8 6140.2 6157.4 6159.0 6165.7 3929.2 7687.6 8045.2 8097.1 8127.0 7649.8 7970.5 8065.3 8104.0 8124.5 7271.5 7639.6 7818.5 7825.4 7833.3 23294.0 28862.0 28984.4 29571.8 29908.1 2367.5 3387.4 3442.2 3496.4 3528.1 16063.3 16862.0 17000.9 17729.0 17865.8 1984.5 1993.6 1994.3 2051.5 2060.7 120051.0 150455.0 152037.0 152587.0 156195.0 19860.9 20914.3 21241.8 21311.3 21993.5 35306.6 35500.6 35525.0 35552.6 35702.6 10587.3 10816.7 10893.1 10951.2 11094.1 8924.8 8925.2 8937.4 9137.0 9143.5 8121.0 9526.6 9531.0 9760.4 9761.4 3148.8 3593.5 3727.9 3743.3 3748.2 696.4 700.0 726.0 736.6 968.0 18282.8 20930.3 21034.4 21096.6 21151.8 898.4 906.2 908.8 931.4 975.0 8662.4 10965.8 10975.5 11096.1 11145.9 568349.0 576083.0 580166.0 588940.0 593591.0 138180.0 140017.0 144586.0 145589.0 148837.0 964.2 984.3 1007.8 1010.5 1013.0 18309.7 18515.9 19346.0 19435.3 19503.9 42747.1 55579.0 55829.4 56223.9 57001.0 77299.0 82613.3 83525.6 84845.0 85128.0 7731.4 7934.2 8144.1 8228.1 8233.7 10060.6 12841.4 12876.8 13233.5 13450.2 11193.8 11222.2 11617.6 11668.6 11683.8 11126.3 11154.9 11262.4 11301.7 11612.4 9290.2 9726.5 11470.7 11789.9 11889.2 6267.1 7101.8 7312.2 7577.8 8240.2 9643.4 10399.5 11336.7 11367.4 11779.2 18:49:44 INFO - PROCESS | 1653 | 18:49:44 INFO - PROCESS | 1653 | [#3] mootools.html Cycles:180 Average:5668.22 Median:5088.38 stddev:3201.23 (62.9%) stddev-sans-first:3193.71 18:49:44 INFO - PROCESS | 1653 | Values: 1344.1 1500.3 1669.7 1869.7 1913.9 7921.4 8208.0 8390.8 8829.5 8868.7 8372.4 8663.6 8675.0 8767.5 8786.0 2298.2 2327.7 2329.9 2334.2 2335.5 2047.4 2131.8 2257.2 2273.4 2303.0 2099.5 2191.4 2326.6 2334.4 2334.7 1965.3 2287.8 2308.1 2322.9 2328.4 2301.2 2301.9 2302.2 2302.9 2311.4 8834.7 8922.8 8938.6 8999.1 8999.6 5177.2 5315.3 5346.1 5397.4 5404.2 2945.7 3497.2 3549.3 3568.7 3570.5 998.8 1026.7 1028.5 1048.3 1056.4 11716.0 11739.9 11752.9 11850.6 11853.1 4429.3 4643.3 4752.3 4766.5 4767.0 9973.8 9977.0 9981.5 9997.6 9998.3 5274.4 5274.7 5279.3 5279.6 5288.0 4635.7 4798.0 4822.3 4832.6 4836.6 3864.6 3881.1 3909.7 3972.3 4093.1 1128.4 1209.1 1219.7 1221.6 1260.3 737.8 879.9 885.2 899.9 920.5 5816.2 5879.7 5894.2 5999.6 6070.8 826.2 860.8 903.6 908.7 930.0 3883.6 3921.0 4040.8 4062.9 4065.0 10424.2 10638.8 10720.4 10727.2 10739.5 10231.6 10574.3 10679.7 10688.8 10734.9 7957.5 7979.4 8011.6 8079.0 8086.4 5592.1 5644.2 5718.6 5767.7 5802.9 9774.8 9829.8 9831.6 9841.8 9850.4 9561.2 10174.5 10280.1 10281.7 10286.2 7437.8 7510.7 7510.9 7515.5 7544.1 7800.5 8174.9 8177.0 8258.6 8279.6 6215.8 7399.8 7418.3 7424.4 7433.4 7327.3 7341.3 7357.3 7424.6 7430.4 6764.7 6781.6 6821.4 6880.1 6946.1 4702.6 4862.2 4940.1 4960.4 5036.4 4977.3 5042.7 5059.0 5117.8 5165.0 18:49:44 INFO - PROCESS | 1653 | 18:49:44 INFO - PROCESS | 1653 | [#4] prototype.html Cycles:180 Average:4534.14 Median:5160.68 stddev:1421.36 (27.5%) stddev-sans-first:1389.41 18:49:44 INFO - PROCESS | 1653 | Values: 302.7 307.9 311.7 318.2 319.1 4429.6 4712.6 4731.9 5102.8 5424.6 4006.0 4688.7 5379.9 5426.3 5459.2 3845.8 3851.8 3891.3 4098.0 4105.9 3844.7 4037.8 4049.4 4072.1 4095.7 3522.7 3536.8 3978.3 4011.4 4012.7 3345.8 4025.1 4043.0 4061.7 4138.9 2650.6 3311.8 3341.9 3547.7 3565.6 5147.8 5167.9 5168.5 5217.9 5237.2 3793.9 4662.7 4681.4 4723.4 4750.9 4775.7 5090.7 5583.3 5658.6 5727.7 3284.2 3479.2 3707.9 3723.6 3939.8 5153.4 5348.2 5469.7 5864.3 5896.5 3316.7 3319.3 3558.0 4008.3 4039.4 3392.9 3413.0 3585.5 4068.2 4131.4 4537.0 4609.2 4712.1 4761.0 4775.4 5313.3 5694.2 5744.3 5771.9 5787.2 1389.9 1406.6 1408.9 1409.7 1410.8 832.5 863.9 876.8 877.7 889.7 4645.4 4754.9 4899.6 5030.6 5347.5 5771.4 5802.9 5814.2 5824.1 5846.8 4252.9 4497.4 5231.6 5293.6 5326.3 2667.8 2973.9 2982.4 3009.2 3026.3 5235.7 5269.8 5543.5 5903.7 5958.4 4616.8 5132.4 5257.5 5648.1 5919.4 5868.9 5871.7 5974.6 5981.6 6033.8 5369.7 5799.0 5808.5 5851.6 5859.8 4822.6 5761.7 5795.3 5802.8 5834.0 5472.9 5719.7 6096.2 6099.6 6099.6 3179.4 3904.0 3905.6 3919.5 3920.0 5522.8 5772.9 5868.4 5872.4 5879.5 5672.4 5760.5 5760.8 5778.2 5811.3 5342.0 5376.2 5506.9 5627.9 5674.3 5486.2 5560.3 5565.8 5569.4 5596.5 5255.5 5390.7 5494.8 5569.6 5789.6 4913.7 5106.7 5430.8 5432.9 5453.2 18:49:44 INFO - PROCESS | 1653 | 18:49:44 INFO - PROCESS | 1653 | [#5] yui.html Cycles:180 Average:24441.42 Median:4125.03 stddev:38555.23 (934.7%) stddev-sans-first:38622.18 18:49:44 INFO - PROCESS | 1653 | Values: 700.7 704.7 718.5 724.7 762.3 13610.0 14885.6 15118.4 15156.7 15158.2 13295.8 14299.4 14300.4 14352.3 14793.1 654.0 667.3 670.5 832.5 855.5 824.6 836.9 837.0 837.6 838.7 904.4 905.5 907.6 909.1 911.0 688.0 877.9 900.0 904.1 906.4 673.5 673.9 676.7 677.8 681.1 14545.6 14574.6 14580.3 14584.6 14592.9 13574.0 13762.3 13846.2 13969.5 14097.6 12859.5 12867.5 12910.7 12926.8 13086.9 4499.1 4518.4 4566.5 4609.8 4615.2 91134.0 98473.0 124267.0 125272.4 125312.0 9511.3 10907.2 11041.7 11202.0 11539.5 21855.5 21873.7 21886.0 21892.0 21896.2 7827.1 8089.2 8139.7 8228.1 8228.4 4230.1 4447.0 4450.4 4511.8 4593.2 3417.8 3528.2 3606.8 3609.2 3611.0 634.9 638.5 640.0 640.1 640.9 489.6 543.2 617.6 627.2 643.3 7798.7 8062.7 8581.9 8840.7 9286.5 521.7 603.6 613.3 628.0 635.8 3737.6 4019.9 4370.5 4521.3 4620.7 119214.8 129902.0 132169.0 134759.3 158259.0 118723.0 126658.0 128033.0 129240.0 130849.0 99800.5 108487.5 111395.0 112683.0 117981.4 9496.5 9763.1 9796.7 9803.7 9806.4 59756.7 62605.7 63280.7 63357.7 63700.0 71423.6 85203.6 96473.9 96610.0 99670.0 12421.1 12979.0 13018.3 13425.7 13695.2 14468.7 14721.9 14852.3 15095.2 15206.7 13609.7 13669.6 13905.5 14043.4 14348.2 13618.3 13690.5 13888.6 14267.1 14418.2 11345.8 13047.4 13171.3 13495.6 13529.7 10963.8 11127.3 11177.2 11374.0 11525.7 7566.5 9786.3 10511.8 11357.0 11469.4 18:49:44 INFO - PROCESS | 1653 | -------- Summary: end -------- 18:49:44 INFO - PROCESS | 1653 | 18:49:44 INFO - TEST-INFO | 1653: exit 0 18:49:44 INFO - TEST-OK | dromaeo_css | took 1117083ms 18:49:44 INFO - TEST-START | kraken 18:49:44 INFO - operating with platform_type : mac_ 18:49:44 INFO - Initialising browser for kraken test... 18:49:45 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/tmp1szQuW/profile http://localhost:49267/getInfo.html) 18:49:47 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 18:49:47 INFO - PROCESS | 1660 | colorDepth:24 18:49:47 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 18:49:47 INFO - PROCESS | 1660 | __metrics 18:49:47 INFO - PROCESS | 1660 | 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" 18:49:47 INFO - PROCESS | 1660 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:49:48 INFO - TEST-INFO | 1660: exit 0 18:49:48 INFO - Browser initialized. 18:49:48 INFO - Running cycle 1/1 for kraken test... 18:49:48 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rg/gwlnpvp52557f_83wf586mv000000w/T/tmp1szQuW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:50:00 INFO - PROCESS | 1661 | RSS: Main: 233193472 18:50:00 INFO - PROCESS | 1661 | 18:50:02 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 18:50:03 INFO - PROCESS | 1661 | RSS: Main: 290594816 18:50:03 INFO - PROCESS | 1661 | 18:50:06 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 18:50:06 INFO - PROCESS | 1661 | RSS: Main: 286642176 18:50:06 INFO - PROCESS | 1661 | 18:50:10 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 18:50:10 INFO - PROCESS | 1661 | RSS: Main: 376340480 18:50:10 INFO - PROCESS | 1661 | 18:50:12 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 18:50:13 INFO - PROCESS | 1661 | RSS: Main: 278355968 18:50:13 INFO - PROCESS | 1661 | 18:50:15 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:50:16 INFO - PROCESS | 1661 | RSS: Main: 282931200 18:50:16 INFO - PROCESS | 1661 | 18:50:19 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 18:50:19 INFO - PROCESS | 1661 | RSS: Main: 321622016 18:50:19 INFO - PROCESS | 1661 | 18:50:22 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 18:50:23 INFO - PROCESS | 1661 | RSS: Main: 322289664 18:50:23 INFO - PROCESS | 1661 | 18:50:26 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 18:50:26 INFO - PROCESS | 1661 | RSS: Main: 322809856 18:50:26 INFO - PROCESS | 1661 | 18:50:28 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:50:29 INFO - PROCESS | 1661 | RSS: Main: 296267776 18:50:29 INFO - PROCESS | 1661 | 18:50:31 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:50:31 INFO - PROCESS | 1661 | RSS: Main: 301723648 18:50:31 INFO - PROCESS | 1661 | 18:50:33 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:50:34 INFO - PROCESS | 1661 | RSS: Main: 304836608 18:50:34 INFO - PROCESS | 1661 | 18:50:37 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:50:38 INFO - PROCESS | 1661 | RSS: Main: 320352256 18:50:38 INFO - PROCESS | 1661 | 18:50:41 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:50:41 INFO - PROCESS | 1661 | RSS: Main: 316571648 18:50:41 INFO - PROCESS | 1661 | 18:50:43 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 18:50:43 INFO - PROCESS | 1661 | RSS: Main: 359890944 18:50:43 INFO - PROCESS | 1661 | 18:50:43 INFO - PROCESS | 1661 | __start_tp_report 18:50:43 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 18:50:43 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 18:50:43 INFO - PROCESS | 1661 | |i|pagename|runs| 18:50:43 INFO - PROCESS | 1661 | |0;ai-astar;112;113;130;112;113;112;110;111;114;108 18:50:43 INFO - PROCESS | 1661 | |1;audio-beat-detection;145;112;129;161;108;130;128;105;157;106 18:50:43 INFO - PROCESS | 1661 | |2;audio-dft;186;185;183;184;183;184;184;180;183;182 18:50:43 INFO - PROCESS | 1661 | |3;audio-fft;74;79;76;149;79;71;87;75;138;57 18:50:43 INFO - PROCESS | 1661 | |4;audio-oscillator;83;102;150;59;93;118;154;99;80;122 18:50:43 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;87;85;87;89;134;84;87;83;87;83 18:50:43 INFO - PROCESS | 1661 | |6;imaging-darkroom;92;93;116;95;92;94;92;96;93;92 18:50:43 INFO - PROCESS | 1661 | |7;imaging-desaturate;124;141;98;90;87;70;110;132;78;124 18:50:43 INFO - PROCESS | 1661 | |8;json-parse-financial;55;56;56;55;54;53;51;55;54;55 18:50:43 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;67;65;65;77;73;77;73;68;69;70 18:50:43 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;85;87;84;84;82;86;85;89;85;86 18:50:43 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;140;150;149;135;128;137;144;150;175;143 18:50:43 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;165;164;150;241;232;169;167;195;184;219 18:50:43 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;65;67;73;83;77;67;66;69;66;66 18:50:43 INFO - PROCESS | 1661 | __end_tp_report 18:50:43 INFO - PROCESS | 1661 | __start_cc_report 18:50:43 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,2112 18:50:43 INFO - PROCESS | 1661 | __end_cc_report 18:50:43 INFO - PROCESS | 1661 | __startTimestamp1472262643900__endTimestamp 18:50:43 INFO - PROCESS | 1661 | 18:50:43 INFO - PROCESS | 1661 | ------- Summary: start ------- 18:50:43 INFO - PROCESS | 1661 | Number of tests: 14 18:50:43 INFO - PROCESS | 1661 | 18:50:43 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:113.50 Median:112.50 stddev:6.04 (5.4%) stddev-sans-first:6.38 18:50:43 INFO - PROCESS | 1661 | Values: 112.0 113.0 130.0 112.0 113.0 112.0 110.0 111.0 114.0 108.0 18:50:43 INFO - PROCESS | 1661 | 18:50:43 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:128.10 Median:129.50 stddev:20.80 (16.1%) stddev-sans-first:21.14 18:50:43 INFO - PROCESS | 1661 | Values: 145.0 112.0 129.0 161.0 108.0 130.0 128.0 105.0 157.0 106.0 18:50:43 INFO - PROCESS | 1661 | 18:50:43 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:183.40 Median:184.00 stddev:1.65 (0.9%) stddev-sans-first:1.45 18:50:43 INFO - PROCESS | 1661 | Values: 186.0 185.0 183.0 184.0 183.0 184.0 184.0 180.0 183.0 182.0 18:50:43 INFO - PROCESS | 1661 | 18:50:43 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:88.50 Median:75.50 stddev:30.07 (39.8%) stddev-sans-first:31.44 18:50:43 INFO - PROCESS | 1661 | Values: 74.0 79.0 76.0 149.0 79.0 71.0 87.0 75.0 138.0 57.0 18:50:43 INFO - PROCESS | 1661 | 18:50:43 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:106.00 Median:69.50 stddev:30.35 (43.7%) stddev-sans-first:31.02 18:50:43 INFO - PROCESS | 1661 | Values: 83.0 102.0 150.0 59.0 93.0 118.0 154.0 99.0 80.0 122.0 18:50:43 INFO - PROCESS | 1661 | 18:50:43 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:90.60 Median:87.00 stddev:15.38 (17.7%) stddev-sans-first:16.26 18:50:43 INFO - PROCESS | 1661 | Values: 87.0 85.0 87.0 89.0 134.0 84.0 87.0 83.0 87.0 83.0 18:50:43 INFO - PROCESS | 1661 | 18:50:43 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:95.50 Median:93.00 stddev:7.34 (7.9%) stddev-sans-first:7.67 18:50:43 INFO - PROCESS | 1661 | Values: 92.0 93.0 116.0 95.0 92.0 94.0 92.0 96.0 93.0 92.0 18:50:43 INFO - PROCESS | 1661 | 18:50:43 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:105.40 Median:74.00 stddev:24.32 (32.9%) stddev-sans-first:24.84 18:50:43 INFO - PROCESS | 1661 | Values: 124.0 141.0 98.0 90.0 87.0 70.0 110.0 132.0 78.0 124.0 18:50:43 INFO - PROCESS | 1661 | 18:50:43 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:55.00 stddev:1.51 (2.7%) stddev-sans-first:1.58 18:50:43 INFO - PROCESS | 1661 | Values: 55.0 56.0 56.0 55.0 54.0 53.0 51.0 55.0 54.0 55.0 18:50:43 INFO - PROCESS | 1661 | 18:50:43 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:71.50 stddev:4.45 (6.2%) stddev-sans-first:4.55 18:50:43 INFO - PROCESS | 1661 | Values: 67.0 65.0 65.0 77.0 73.0 77.0 73.0 68.0 69.0 70.0 18:50:43 INFO - PROCESS | 1661 | 18:50:43 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:85.30 Median:85.50 stddev:1.89 (2.2%) stddev-sans-first:2.00 18:50:43 INFO - PROCESS | 1661 | Values: 85.0 87.0 84.0 84.0 82.0 86.0 85.0 89.0 85.0 86.0 18:50:43 INFO - PROCESS | 1661 | 18:50:43 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:145.10 Median:146.50 stddev:12.69 (8.7%) stddev-sans-first:13.32 18:50:43 INFO - PROCESS | 1661 | Values: 140.0 150.0 149.0 135.0 128.0 137.0 144.0 150.0 175.0 143.0 18:50:43 INFO - PROCESS | 1661 | 18:50:43 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.60 Median:189.50 stddev:31.83 (16.8%) stddev-sans-first:32.60 18:50:43 INFO - PROCESS | 1661 | Values: 165.0 164.0 150.0 241.0 232.0 169.0 167.0 195.0 184.0 219.0 18:50:43 INFO - PROCESS | 1661 | 18:50:43 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.90 Median:68.00 stddev:5.95 (8.8%) stddev-sans-first:6.04 18:50:43 INFO - PROCESS | 1661 | Values: 65.0 67.0 73.0 83.0 77.0 67.0 66.0 69.0 66.0 66.0 18:50:43 INFO - PROCESS | 1661 | -------- Summary: end -------- 18:50:43 INFO - PROCESS | 1661 | 18:50:44 INFO - TEST-INFO | 1661: exit 0 18:50:44 INFO - TEST-OK | kraken | took 59728ms 18:50:44 INFO - SUITE-END | took 1176s 18:50:45 INFO - Completed test suite (00:19:37) 18:50:45 INFO - javascript benchmark 18:50:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3327.783694059885, 3335.382954153482, 3368.865245390186, 3395.9320813583745, 3441.948370774439, 5552.444755524456, 5644.153376993453, 5653.321601407837, 5715.457031581998, 5718.685472299027, 5658.179563963233, 5682.289713785787, 5741.454561816631, 5790.68151251681, 5805.10020946754, 5150.0, 5184.740762961856, 5217.817376391827, 5222.529972302492, 5337.279467271907, 5266.315379000744, 5333.519983201511, 5337.359516857982, 5347.59893008024, 5382.273393091921, 5163.457836927106, 5189.558887494568, 5195.896082078375, 5224.1902505111575, 5268.973655131738, 5265.578753699695, 5265.76304066318, 5271.525562699337, 5272.551833094191, 5273.6572122811895, 5164.2253661950635, 5201.921971170435, 5230.267762513251, 5231.607629427808, 5236.476352364771, 5707.74315155819, 5709.571782116358, 5720.742566584472, 5731.914021289684, 5737.82786516401, 6123.801220575414, 6158.7228574714245, 6185.505159587267, 6187.628742275433, 6210.4100110489435, 6201.5348848836065, 6241.500679945639, 6263.217097862767, 6303.149074874879, 6303.243610766692, 4798.256270278112, 5695.231143795535, 5857.092150716646, 5867.647941123589, 5867.70661466925, 6195.597286176384, 6223.782167624111, 6224.190855188796, 6287.2140982377205, 6299.086632438362, 6173.506119510428, 6173.722182501709, 6194.318624951252, 6201.255849298113, 6206.813795586139, 6119.908201376983, 6287.654179020198, 6288.874222515524, 6291.528135389773, 6303.46420554258, 5992.910438511636, 6138.081874382086, 6150.415710507495, 6191.133241346125, 6247.875042499216, 5558.277423934863, 6250.687465626791, 6256.718447669866, 6273.215848018997, 6274.309825919146, 4880.780364883589, 5014.699118052856, 5014.824481143142, 5022.748862556858, 5027.899442011212, 3595.676389124991, 4433.556644335541, 4453.0, 4501.122281155209, 4562.771861406916, 5708.543316534668, 5725.0, 5740.512056475246, 5750.223719797774, 5765.4522820332, 6259.0, 6259.061140828821, 6294.118823364733, 6295.779647712308, 6318.557700960981, 5608.803691870765, 5663.518600918968, 5705.315362156568, 5737.827865164052, 5758.568107391879, 5467.289252397163, 5472.50747432733, 5474.233607294982, 5477.315335583052, 5516.365617953907, 6247.718852651551, 6257.124002639725, 6269.028300613412, 6285.622862628165, 6297.1813664223355, 6111.166351794699, 6185.659788711664, 6229.159063526366, 6261.405166509175, 6290.779822706183, 6283.0, 6291.937080629318, 6301.40136687014, 6319.083732858801, 6320.241571011324, 6164.876702466016, 6181.5363847710705, 6192.566520343533, 6197.1324014638885, 6197.349278325711, 6056.121862329849, 6108.463491842514, 6199.070139479114, 6247.68761561911, 6302.621842689453, 6097.833703576693, 6121.239585875234, 6142.7136481981925, 6145.0782382642965, 6156.230471191101, 6102.761931713857, 6113.841195312776, 6138.541671868701, 6147.954847675996, 6151.876962460815, 5795.95672778887, 5882.294124705063, 5932.317783454958, 5954.46409823118, 5995.850103747442, 6104.125697713769, 6120.26428866606, 6127.281156068254, 6139.124180917401, 6141.301904043499, 5926.4666180044005, 5935.961206788881, 5945.583809133319, 5954.07711804671, 5988.311344195473], "value": 5706.043773033141, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [467.8994016286498, 475.5957436179248, 505.62836315308243, 507.98730031749227, 536.3429798496844, 26439.67801609919, 26672.73327266727, 31698.366032679332, 39931.0, 41361.37957930633, 42960.78519607405, 43198.56801431993, 43235.56764432355, 43250.0, 43339.566604333944, 9770.755731106727, 9936.205103591696, 9936.552855121538, 9941.204703623729, 9952.800943981116, 8819.426737262089, 9052.547372631376, 9059.909400906006, 9740.172085372751, 9876.358036727626, 9000.954995225014, 9053.366264361497, 12013.339266340348, 12257.877421225807, 12285.017198624133, 10600.886906874783, 11977.161598688095, 12311.322877241748, 12366.0, 12369.381530923507, 12016.0, 12097.879021209852, 12101.93949030258, 12102.636920892388, 12122.636320910387, 32917.6708232917, 40365.0, 40716.59283407158, 40728.981775455555, 40877.1824563507, 3316.1543806329473, 3569.8572057117685, 3769.5099637047283, 4058.411530328086, 4090.141070375225, 30593.694063059305, 31608.209794755086, 31662.525062123856, 31686.683133168604, 31850.681493185002, 2871.3682989742224, 3174.873005079794, 3304.4052070627276, 3349.5764300172327, 3369.932601347984, 382706.0, 382919.0, 384806.0, 385093.0, 388949.05525472475, 61305.0, 61623.383766161765, 61691.0, 61785.69107154435, 61804.69097654483, 64583.354166458674, 64599.70800584056, 64641.67679161574, 64677.0, 64704.35295646983, 16659.500214993568, 16791.832081679273, 16843.157842107845, 16844.410445634345, 16867.662646746998, 13007.739845203232, 13060.08579399452, 13066.673333166556, 13070.869291307155, 13076.869231307755, 12184.268943863397, 16702.331906723593, 17340.132993350533, 17356.392526261774, 17433.302667893142, 5436.864078397993, 5474.534664553478, 6366.745330186741, 6386.584871983307, 6418.775342862978, 767.6008475592772, 926.6756635177634, 933.705882646964, 984.7882705218412, 1025.1696126137851, 23119.653205201936, 29469.410611788073, 29628.111156665334, 30361.392772144434, 30539.847300763355, 914.849049906758, 992.2062350119875, 1017.730301470111, 1029.5572903651353, 1053.847192157133, 14639.707205855822, 15367.692646147238, 15605.3757849687, 15632.84367156314, 15875.52373428799, 661480.3851961419, 914520.0, 923705.0, 923872.0, 925454.0, 50188.74905625521, 50655.49344506581, 58178.41821581815, 58493.122603160984, 58873.70563147157, 1208.915375923677, 1233.8457692788402, 1234.0436161974362, 1234.4383305595932, 1320.914140580859, 28734.71265287404, 30941.226469338446, 31083.378332433676, 31592.052238432883, 31683.04950851478, 49543.50456495389, 50137.74931125321, 50226.24660630021, 50255.0, 50296.497035029184, 547525.0, 553091.0, 553659.0, 553699.0, 553929.0, 21220.787792121882, 26210.86894565515, 26219.606705899045, 26219.868900655376, 26264.21207363782, 46044.0, 47160.0, 47190.0, 47347.0, 47838.52161478341, 36562.0, 37530.437043444916, 38995.0, 39506.60493395029, 39860.800695997495, 36563.817180913924, 37521.81239093787, 38119.80940095282, 39246.4113038299, 39738.40392394174, 35974.460383094796, 36382.0, 38629.806850965564, 38902.0, 38928.22143557169, 29064.837406503502, 31160.0, 32869.34261314808, 33405.32325474387, 34132.658673412945, 21673.133074676836, 24141.517169656156, 24689.50620987606, 25056.62415063812, 25709.485810284063], "value": 19876.74137874588, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.6746121339874, 353.9911502212446, 381.7575839342017, 382.83155411618884, 408.8609872643303, 15402.84597154028, 16525.50423487296, 16819.495415137535, 20428.69356959643, 20648.27731029416, 17835.37576184834, 20467.692984605204, 20756.27353042644, 21039.47401314968, 21239.575208495822, 5721.885562288741, 5786.942130578704, 5968.283805943272, 5969.522438204933, 5978.163057172, 6048.788292409767, 6140.171076904628, 6157.353477884825, 6159.0453479710495, 6165.722542485577, 3929.1552316252, 7687.577183254919, 8045.235702608243, 8097.109317975018, 8126.984126984127, 7649.847003059963, 7970.481918675297, 8065.274125328749, 8104.0, 8124.471909325934, 7271.527350722186, 7639.579823109727, 7818.491798033109, 7825.413094017971, 7833.255840695125, 23294.0, 28861.989830355837, 28984.42031159386, 29571.81712731488, 29908.102756917327, 2367.5264947010496, 3387.3733359328357, 3442.1566716154452, 3496.3881320768764, 3528.065062758371, 16063.277152528164, 16862.0, 17000.914995424944, 17729.0, 17865.797110120602, 1984.474114359696, 1993.591313780686, 1994.3076647493172, 2051.5384038591346, 2060.6599911014664, 120051.0, 150455.0, 152037.0, 152587.0, 156195.0, 19860.90069549643, 20914.268000619904, 21241.78758212398, 21311.25410610621, 21993.450163745714, 35306.64693353033, 35500.64499355025, 35525.0, 35552.64447355493, 35702.64297357045, 10587.258891877495, 10816.729581760519, 10893.074088702391, 10951.233413661126, 11094.057005154491, 8924.821503570021, 8925.152110549487, 8937.419067760575, 9136.95431522838, 9143.451392916446, 8120.957212482419, 9526.570724465217, 9530.999245079305, 9760.365576237522, 9761.438169892925, 3148.763842711806, 3593.5328407307406, 3727.9254414912093, 3743.326201283795, 3748.212875296164, 696.4045740891465, 700.0199720391493, 725.9673314700851, 736.6059158350257, 967.9564419601135, 18282.817171828112, 20930.267440639505, 21034.368968930958, 21096.578068438546, 21151.836648984146, 898.442965361467, 906.1980147569317, 908.8454962656365, 931.3549519365035, 974.9610015599438, 8662.436941598902, 10965.780684386427, 10975.50610222542, 11096.1123110151, 11145.944270278758, 568349.0, 576083.0, 580166.0, 588940.0, 593591.0, 138180.0, 140017.0, 144586.0, 145589.0, 148837.0, 964.1900803325241, 984.2568860510412, 1007.8034783217203, 1010.4842735896156, 1012.9797404052025, 18309.725354119433, 18515.907420462812, 19346.0, 19435.3197638082, 19503.902480488076, 42747.14505709806, 55579.0, 55829.44170558242, 56223.87552249014, 57001.0, 77299.0, 82613.3477330462, 83525.58237208775, 84845.0, 85128.0, 7731.38148948094, 7934.20657934202, 8144.104148543774, 8228.136045715113, 8233.670653174046, 10060.597576097167, 12841.422136004088, 12876.806847897102, 13233.53682621142, 13450.192988420726, 11193.776124477628, 11222.158338124511, 11617.593384231512, 11668.591599293984, 11683.824742628698, 11126.332420054823, 11154.944225278821, 11262.436878156223, 11301.660950171508, 11612.419379031184, 9290.210332121575, 9726.513674316115, 11470.713232169262, 11789.941050294694, 11889.167758257185, 6267.091271765659, 7101.786946391617, 7312.195658477457, 7577.772666820004, 8240.175982401712, 9643.373180743229, 10399.480025998519, 11336.716582085513, 11367.374794386387, 11779.175457717878], "value": 10985.00756251737, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1344.1263178933696, 1500.3173556032007, 1669.6660667866422, 1869.6821540338142, 1913.9234430622766, 7921.405894557903, 8207.958960205206, 8390.79023024424, 8829.514376709287, 8868.689595864145, 8372.372072094588, 8663.566821658924, 8674.956625216864, 8767.47395156291, 8785.956070219656, 2298.2186056740707, 2327.7439481657, 2329.893300682171, 2334.2180369576213, 2335.5212181502834, 2047.406252186864, 2131.8081372676456, 2257.24382331919, 2273.397549649336, 2302.976970230293, 2099.5066159452595, 2191.364504293753, 2326.581215381231, 2334.404726794665, 2334.673145759595, 1965.3023176772326, 2287.816974642033, 2308.145985985188, 2322.930312090623, 2328.4295347639936, 2301.2290882554366, 2301.9424514387106, 2302.1597117052324, 2302.896369663369, 2311.3759284993125, 8834.690785822466, 8922.821543569156, 8938.64245430182, 8999.100089991014, 8999.640014399482, 5177.223416487519, 5315.255864178979, 5346.091164502044, 5397.433269506684, 5404.162354834967, 2945.6907024762518, 3497.1956450016382, 3549.2901419715904, 3568.678818906311, 3570.4644303354444, 998.7952469743685, 1026.702256345659, 1028.480617288272, 1048.3185929146039, 1056.3609016545033, 11716.0, 11739.882601173964, 11752.88247117535, 11850.585229516926, 11853.111016673784, 4429.313456414261, 4643.326717625925, 4752.336682161216, 4766.451858036301, 4767.0, 9973.80052398948, 9976.950115249378, 9981.454729044048, 9997.550110245056, 9998.250131240186, 5274.393444753826, 5274.7098909560345, 5279.260903473517, 5279.577633789288, 5288.0, 4635.7450340230935, 4798.016406636701, 4822.324874517571, 4832.613390928717, 4836.588889944394, 3864.5555761087835, 3881.1073453105378, 3909.745866518724, 3972.3048466518244, 4093.140440507527, 1128.3624752015085, 1209.1354681402838, 1219.6950762309423, 1221.584661215191, 1260.3005332040623, 737.756540341686, 879.907609700985, 885.1900511032334, 899.9415038022507, 920.4615300049412, 5816.1566572846705, 5879.706014699248, 5894.233749612523, 5999.610025348338, 6070.757169713251, 826.2068414322198, 860.840744462264, 903.5527413930147, 908.7410088124985, 929.9860502092474, 3883.59222281662, 3920.9803950980063, 4040.8181631826637, 4062.918741625151, 4065.0447144920918, 10424.166066714644, 10638.787224255471, 10720.35677859331, 10727.249092563447, 10739.463026848627, 10231.641892533726, 10574.312669676447, 10679.679609611723, 10688.808794197172, 10734.892651073545, 7957.482763620462, 7979.4015448841565, 8011.6394762237005, 8078.980254936266, 8086.353091752646, 5592.105263157875, 5644.153376993484, 5718.628289161357, 5767.740451679769, 5802.912956305574, 9774.80450390974, 9829.82042154946, 9831.557579909064, 9841.753956151153, 9850.408975461496, 9561.235101192027, 10174.49127543605, 10280.126189273995, 10281.74295642615, 10286.177105831366, 7437.8140546486775, 7510.737124200627, 7510.924890751241, 7515.473916825769, 7544.09470863478, 7800.531968081933, 8174.918250817415, 8176.959115204386, 8258.628361723617, 8279.586020699062, 6215.751369945152, 7399.8150046249275, 7418.295261950106, 7424.406047516078, 7433.389142542027, 7327.2672732726305, 7341.33927946466, 7357.264273572814, 7424.628768561658, 7430.405567554474, 6764.695588698422, 6781.559198652072, 6821.420179284816, 6880.0711903894335, 6946.097007389007, 4702.600278976325, 4862.197737373293, 4940.135476291706, 4960.379952505937, 5036.370453693288, 4977.328060711758, 5042.722650254124, 5059.0, 5117.769700363418, 5165.018646457161], "value": 4529.198673727711, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.7003266765902, 307.9476488996872, 311.6602902835908, 318.24417009602195, 319.06832050412794, 4429.557044295573, 4712.599429048535, 4731.858044258669, 5102.800841802183, 5424.566034717223, 4006.018525461266, 4688.71867687939, 5379.919301210475, 5426.2674538937235, 5459.235707001013, 3845.788481633509, 3851.8074096295145, 3891.3190191716335, 4098.0329441867925, 4105.938410923838, 3844.673202777767, 4037.7577345359227, 4049.3521036634143, 4072.124493233954, 4095.692823038269, 3522.7357948153863, 3536.8328451611037, 3978.3435733104, 4011.438398624195, 4012.6589239914642, 3345.832708364572, 4025.08808316049, 4043.0, 4061.715679902408, 4138.895828128903, 2650.5891586804078, 3311.8012919224925, 3341.9280046371164, 3547.7161827053774, 3565.6434356564396, 5147.845564633067, 5167.948320516784, 5168.457311982225, 5217.912834866046, 5237.188235823415, 3793.943090853639, 4662.743549104798, 4681.391419115527, 4723.409573803275, 4750.928736068961, 4775.734430375918, 5090.694558326475, 5583.302087239095, 5658.575606829504, 5727.684977326246, 3284.244623736554, 3479.2345683949743, 3707.850566324421, 3723.6090210527777, 3939.8424063037446, 5153.407358153785, 5348.171033489776, 5469.714617064987, 5864.325602555676, 5896.498797602209, 3316.6849149330774, 3319.336132773455, 3557.964420355815, 4008.2584721825974, 4039.3537034074407, 3392.8982130536124, 3412.982935085309, 3585.539953944404, 4068.2270368630457, 4131.400946862688, 4537.02453972391, 4609.24848557546, 4712.081144176853, 4761.0, 4775.4479794067065, 5313.309269794902, 5694.202811606378, 5744.281964754406, 5771.884562308815, 5787.2187254720075, 1389.867962543577, 1406.5850574080466, 1408.8661577150153, 1409.689868228988, 1410.7742761158165, 832.4505826154709, 863.9481631102154, 876.8421684096796, 877.6840337478502, 889.6930558957149, 4645.419322584677, 4754.885549964779, 4899.608031357483, 5030.597552195889, 5347.5134062661045, 5771.394003629643, 5802.912956305659, 5814.244148260699, 5824.068753591309, 5846.824595262149, 4252.87241382759, 4497.415336006364, 5231.607629427808, 5293.602979776532, 5326.334208223972, 2667.826591271561, 2973.884793202549, 2982.418428406483, 3009.217603423082, 3026.303950091491, 5235.73821308933, 5269.814291784318, 5543.473370029841, 5903.745666356946, 5958.404159584007, 4616.76916154191, 5132.384113906355, 5257.502686224089, 5648.079652918028, 5919.4080591940465, 5868.862451971435, 5871.735771890189, 5974.61165024272, 5981.611195272293, 6033.849153771191, 5369.6509726867625, 5799.043157879071, 5808.477237048686, 5851.61964472308, 5859.765609375577, 4822.636907915166, 5761.683107429131, 5795.333536643171, 5802.82591522255, 5834.024647429756, 5472.890542189054, 5719.685417302088, 6096.207493026056, 6099.603525770811, 6099.634021958696, 3179.42770301348, 3904.0239940014994, 3905.6484916357663, 3919.511634645225, 3920.0, 5522.751476183501, 5772.884542309214, 5868.38381969887, 5872.354041055395, 5879.5002424794375, 5672.432756724294, 5760.452756988079, 5760.8271751847515, 5778.179873340053, 5811.302643682785, 5342.038433081927, 5376.220448034935, 5506.889862202814, 5627.8593035174445, 5674.319081710221, 5486.177073439016, 5560.304961879765, 5565.777368905198, 5569.438058493139, 5596.468335508121, 5255.452569515485, 5390.655065046717, 5494.780208791603, 5569.610127291051, 5789.56578256624, 4913.729744864067, 5106.7446627669215, 5430.782768689366, 5432.864178395572, 5453.2365468833605], "value": 4086.202935557707, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [700.7337211859492, 704.73220176333, 718.540134314039, 724.6811402982687, 762.3101093510373, 13609.979251556124, 14885.553433397003, 15118.395264189447, 15156.696866062686, 15158.242087895602, 13295.800562991539, 14299.428022879072, 14300.427982880698, 14352.28238588069, 14793.112413255212, 653.9869202615945, 667.3360006793248, 670.5406796344495, 832.5462622870535, 855.4909828651973, 824.583585289431, 836.9079401265856, 837.0457678246803, 837.5728378526965, 838.739990602912, 904.398574947659, 905.5110240470138, 907.6233363154283, 909.05912380686, 911.0388540090203, 688.0023965250407, 877.9341549383757, 899.9605455698678, 904.1275169461468, 906.374601524949, 673.491513907002, 673.9427148692367, 676.6650507998523, 677.7695583501584, 681.0601370109217, 14545.636359091002, 14574.635634109127, 14580.270986450634, 14584.562463126123, 14592.90553208503, 13574.0, 13762.311884405739, 13846.238456884867, 13969.511067112704, 14097.577072687733, 12859.485620575166, 12867.485300587965, 12910.741785164339, 12926.806097908539, 13086.869131308755, 4499.10017996402, 4518.412606361152, 4566.474855391639, 4609.7925593348045, 4615.169269531483, 91134.0, 98473.0, 124267.0, 125272.37363813214, 125312.0, 9511.334206605472, 10907.236493445384, 11041.668749937515, 11202.0, 11539.538418463168, 21855.453613659785, 21873.67189492159, 21886.0, 21892.0, 21896.233631822808, 7827.112688324098, 8089.150639182801, 8139.715109971123, 8228.094909559944, 8228.382871284557, 4230.111676547959, 4446.9555304447185, 4450.443694538183, 4511.842085526991, 4593.173228818845, 3417.79493230407, 3528.2237907660274, 3606.8016259106003, 3609.1879327151705, 3610.9638903611153, 634.8888944434706, 638.5211091681239, 639.9648019358887, 640.0591131037368, 640.8622146238488, 489.62299029746896, 543.166812009468, 617.6016469377226, 627.1658693937144, 643.29526754102, 7798.727044553413, 8062.677492900219, 8581.927259092614, 8840.734777956672, 9286.489243091559, 521.7000224870715, 603.5865432178972, 613.2916481463916, 627.996860015697, 635.7520566978882, 3737.570179429346, 4019.9397009044887, 4370.519242883281, 4521.340247307591, 4620.722756634613, 119214.8078519221, 129902.0, 132169.0, 134759.32620337032, 158259.0, 118723.0, 126658.0, 128033.0, 129240.0, 130849.0, 99800.50099749454, 108487.45756271169, 111395.0, 112683.0, 117981.4100929507, 9496.477693726634, 9763.121319081316, 9796.706098817047, 9803.656872009446, 9806.362586431858, 59756.70121649364, 62605.747885042954, 63280.68359658172, 63357.73284534376, 63700.0, 71423.64288178526, 85203.57398212969, 96473.9055008655, 96610.0, 99670.0, 12421.100762402726, 12979.0, 13018.2839943804, 13425.66435839112, 13695.178289302674, 14468.710625787215, 14721.940546777587, 14852.257387130816, 15095.24523773785, 15206.695866082837, 13609.727805444034, 13669.589912302647, 13905.513307034205, 14043.36804843805, 14348.210848403021, 13618.319084045956, 13690.52083177084, 13888.583342499742, 14267.07264027835, 14418.206998615176, 11345.773084538097, 13047.412866420844, 13171.341432928506, 13495.59513214605, 13529.729405412034, 10963.780724385308, 11127.332360058423, 11177.217594768614, 11374.0, 11525.711857203636, 7566.546007239584, 9786.266030047633, 10511.789764204826, 11357.0, 11469.426528673366], "value": 7729.538847239594, "unit": "score"}], "name": "dromaeo_css", "value": 7501.889638446725, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 130.0, 112.0, 113.0, 112.0, 110.0, 111.0, 114.0, 108.0], "value": 113.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 112.0, 129.0, 161.0, 108.0, 130.0, 128.0, 105.0, 157.0, 106.0], "value": 128.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 183.0, 184.0, 183.0, 184.0, 184.0, 180.0, 183.0, 182.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 79.0, 76.0, 149.0, 79.0, 71.0, 87.0, 75.0, 138.0, 57.0], "value": 88.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 102.0, 150.0, 59.0, 93.0, 118.0, 154.0, 99.0, 80.0, 122.0], "value": 106.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 89.0, 134.0, 84.0, 87.0, 83.0, 87.0, 83.0], "value": 90.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 116.0, 95.0, 92.0, 94.0, 92.0, 96.0, 93.0, 92.0], "value": 95.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 141.0, 98.0, 90.0, 87.0, 70.0, 110.0, 132.0, 78.0, 124.0], "value": 105.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 56.0, 55.0, 54.0, 53.0, 51.0, 55.0, 54.0, 55.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 65.0, 77.0, 73.0, 77.0, 73.0, 68.0, 69.0, 70.0], "value": 70.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 84.0, 84.0, 82.0, 86.0, 85.0, 89.0, 85.0, 86.0], "value": 85.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 150.0, 149.0, 135.0, 128.0, 137.0, 144.0, 150.0, 175.0, 143.0], "value": 145.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 150.0, 241.0, 232.0, 169.0, 167.0, 195.0, 184.0, 219.0], "value": 188.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 73.0, 83.0, 77.0, 67.0, 66.0, 69.0, 66.0, 66.0], "value": 69.9, "unit": "score"}], "name": "kraken", "value": 1524.6999999999998, "alertThreshold": 2.0}]} 18:50:45 INFO - Return code: 0 18:50:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:50:45 INFO - # TBPL SUCCESS # 18:50:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:50:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:50:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:50:45 INFO - 2016-08-26 18:50:45.213 screenresolution[1665:9871] starting screenresolution argv=screenresolution get 18:50:45 INFO - 2016-08-26 18:50:45.216 screenresolution[1665:9871] Display 0: 1600x1200x32@60 18:50:45 INFO - 2016-08-26 18:50:45.221 screenresolution[1666:9874] starting screenresolution argv=screenresolution list 18:50:45 INFO - Available Modes on Display 0 18:50:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:50:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:50:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:50:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:50:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:50:45 INFO - Intel Iris: 18:50:45 INFO - Chipset Model: Intel Iris 18:50:45 INFO - Type: GPU 18:50:45 INFO - Bus: Built-In 18:50:45 INFO - VRAM (Dynamic, Max): 1536 MB 18:50:45 INFO - Vendor: Intel (0x8086) 18:50:45 INFO - Device ID: 0x0a2e 18:50:45 INFO - Revision ID: 0x0009 18:50:45 INFO - Displays: 18:50:45 INFO - Display: 18:50:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:50:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:50:45 INFO - Display Serial Number: 1600x1200 60 18:50:45 INFO - Main Display: Yes 18:50:45 INFO - Mirror: Off 18:50:45 INFO - Online: Yes 18:50:45 INFO - Rotation: Supported 18:50:45 INFO - Return code: 0 18:50:45 INFO - Running post-action listener: _resource_record_post_action 18:50:45 INFO - [mozharness: 2016-08-27 01:50:45.455707Z] Finished run-tests step (success) 18:50:45 INFO - Running post-run listener: _resource_record_post_run 18:50:45 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 38151168; Write bytes: 704549376; Read time: 377; Write time: 2339 18:50:45 INFO - TinderboxPrint: CPU usage
53.5% 18:50:45 INFO - TinderboxPrint: I/O read bytes / time
38,151,168 / 377 18:50:45 INFO - TinderboxPrint: I/O write bytes / time
704,549,376 / 2,339 18:50:45 INFO - TinderboxPrint: CPU idle
2,252.5 (46.5%) 18:50:45 INFO - TinderboxPrint: CPU system
968.8 (20.0%) 18:50:45 INFO - TinderboxPrint: CPU user
1,624.4 (33.5%) 18:50:45 INFO - TinderboxPrint: Swap in / out
444,407,808 / 0 18:50:45 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 408250880; Write bytes: 397801472; Read time: 29664; Write time: 1019 18:50:45 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 30731264; Write bytes: 294087168; Read time: 287; Write time: 1288 18:50:45 INFO - Running post-run listener: _upload_blobber_files 18:50:45 INFO - Blob upload gear active. 18:50:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:50:45 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 18:50:45 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'] 18:50:45 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 18:50:46 INFO - (blobuploader) - INFO - Open directory for files ... 18:50:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:50:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:50:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:50:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:50:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:50:47 INFO - (blobuploader) - INFO - Done attempting. 18:50:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:50:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:50:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:50:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:50:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:50:48 INFO - (blobuploader) - INFO - Done attempting. 18:50:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:50:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:50:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:50:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:50:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:50:52 INFO - (blobuploader) - INFO - Done attempting. 18:50:52 INFO - (blobuploader) - INFO - Iteration through files over. 18:50:52 INFO - Return code: 0 18:50:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:50:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:50:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/422824b43fc14c1892fbdd47ca9f6ca2a70e78f5e90defb55cd12bcf8b7936a816e7be90f1ca2bec56f0955064c9140f708de79983b9c7bdca50b10267964960", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55fabe654a6020f8bdee0265979fdee35de0708991c1d2e96cbb74d984ab1ee583e25b3e49b17c6863070ae3e5fe2cb96a2dffb88d6154f0b6d6761f9cc5e28c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c093240f27b715338401990b40971cc1fdddf7840f7fc482b5a8768b37791fae6f09c920858a5f694ef6e43cb6c73b7566071a3cecd361a0523607efbdd5407"} 18:50:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:50:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:50:52 INFO - Contents: 18:50:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/422824b43fc14c1892fbdd47ca9f6ca2a70e78f5e90defb55cd12bcf8b7936a816e7be90f1ca2bec56f0955064c9140f708de79983b9c7bdca50b10267964960", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55fabe654a6020f8bdee0265979fdee35de0708991c1d2e96cbb74d984ab1ee583e25b3e49b17c6863070ae3e5fe2cb96a2dffb88d6154f0b6d6761f9cc5e28c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c093240f27b715338401990b40971cc1fdddf7840f7fc482b5a8768b37791fae6f09c920858a5f694ef6e43cb6c73b7566071a3cecd361a0523607efbdd5407"} 18:50:52 INFO - Running post-run listener: copy_logs_to_upload_dir 18:50:52 INFO - Copying logs to upload dir... 18:50:52 INFO - mkdir: /builds/slave/test/build/upload/logs 18:50:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.849520 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-26 18:50:52.377048) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 18:50:52.380838) ========= 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.y9fGrmMPDB/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.GILpbBmRci/Listeners TMPDIR=/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/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/422824b43fc14c1892fbdd47ca9f6ca2a70e78f5e90defb55cd12bcf8b7936a816e7be90f1ca2bec56f0955064c9140f708de79983b9c7bdca50b10267964960", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55fabe654a6020f8bdee0265979fdee35de0708991c1d2e96cbb74d984ab1ee583e25b3e49b17c6863070ae3e5fe2cb96a2dffb88d6154f0b6d6761f9cc5e28c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c093240f27b715338401990b40971cc1fdddf7840f7fc482b5a8768b37791fae6f09c920858a5f694ef6e43cb6c73b7566071a3cecd361a0523607efbdd5407"} build_url:https://queue.taskcluster.net/v1/task/E0Y9kB1fT064M9C1snd2wQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010982 build_url: 'https://queue.taskcluster.net/v1/task/E0Y9kB1fT064M9C1snd2wQ/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/422824b43fc14c1892fbdd47ca9f6ca2a70e78f5e90defb55cd12bcf8b7936a816e7be90f1ca2bec56f0955064c9140f708de79983b9c7bdca50b10267964960", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55fabe654a6020f8bdee0265979fdee35de0708991c1d2e96cbb74d984ab1ee583e25b3e49b17c6863070ae3e5fe2cb96a2dffb88d6154f0b6d6761f9cc5e28c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c093240f27b715338401990b40971cc1fdddf7840f7fc482b5a8768b37791fae6f09c920858a5f694ef6e43cb6c73b7566071a3cecd361a0523607efbdd5407"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 18:50:52.417024) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 18:50:52.417590) ========= 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.y9fGrmMPDB/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.GILpbBmRci/Listeners TMPDIR=/var/folders/rg/gwlnpvp52557f_83wf586mv000000w/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.004631 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 18:50:52.468783) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 18:50:52.469425) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 18:50:52.470143) ========= ========= Total master_lag: 0.18 =========