builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0114 starttime: 1471351988.47 results: success (0) buildid: 20160816030459 builduid: 6ae259358ece4561b497272569cd90f9 revision: 054d4856cea6150a6638e5daf7913713281af97d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 05:53:08.474117) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 05:53:08.474566) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 05:53:08.474855) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 05:53:08.501124) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 05:53:08.501818) ========= 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.O0JCfSfI1F/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.UWkEZEQfwc/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-16 05:53:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 6.81M=0.002s 2016-08-16 05:53:08 (6.81 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.091242 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 05:53:08.621319) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 05:53:08.622126) ========= 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.O0JCfSfI1F/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.UWkEZEQfwc/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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.028212 ========= master_lag: 0.18 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 05:53:08.826110) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-08-16 05:53:08.826518) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 054d4856cea6150a6638e5daf7913713281af97d --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 054d4856cea6150a6638e5daf7913713281af97d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.O0JCfSfI1F/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.UWkEZEQfwc/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-16 05:53:09,035 truncating revision to first 12 chars 2016-08-16 05:53:09,035 Setting DEBUG logging. 2016-08-16 05:53:09,035 attempt 1/10 2016-08-16 05:53:09,035 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/054d4856cea6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-16 05:53:11,119 unpacking tar archive at: mozilla-central-054d4856cea6/testing/mozharness/ program finished with exit code 0 elapsedTime=2.349573 ========= master_lag: 0.15 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-08-16 05:53:11.326770) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 05:53:11.327104) ========= script_repo_revision: 054d4856cea6150a6638e5daf7913713281af97d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 05:53:11.327573) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 05:53:11.327873) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 05:53:11.345646) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-16 05:53:11.345949) ========= /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.O0JCfSfI1F/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.UWkEZEQfwc/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 05:53:11 INFO - MultiFileLogger online at 20160816 05:53:11 in /builds/slave/test 05:53:11 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 05:53:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:53:11 INFO - {'append_to_log': False, 05:53:11 INFO - 'base_work_dir': '/builds/slave/test', 05:53:11 INFO - 'blob_upload_branch': 'Firefox', 05:53:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:53:11 INFO - 'branch': 'Firefox', 05:53:11 INFO - 'buildbot_json_path': 'buildprops.json', 05:53:11 INFO - 'config_files': ('talos/mac_config.py',), 05:53:11 INFO - 'default_actions': ('clobber', 05:53:11 INFO - 'read-buildbot-config', 05:53:11 INFO - 'download-and-extract', 05:53:11 INFO - 'populate-webroot', 05:53:11 INFO - 'create-virtualenv', 05:53:11 INFO - 'install', 05:53:11 INFO - 'run-tests'), 05:53:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:53:11 INFO - 'download_minidump_stackwalk': True, 05:53:11 INFO - 'download_symbols': 'ondemand', 05:53:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:53:11 INFO - 'tooltool.py': '/tools/tooltool.py', 05:53:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:53:11 INFO - '/tools/misc-python/virtualenv.py')}, 05:53:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:53:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:53:11 INFO - 'installer_path': 'installer.exe', 05:53:11 INFO - 'log_level': 'info', 05:53:11 INFO - 'log_name': 'talos', 05:53:11 INFO - 'log_to_console': True, 05:53:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:53:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:53:11 INFO - 'opt_config_files': (), 05:53:11 INFO - 'pip_index': False, 05:53:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:53:11 INFO - 'cmd': ('bash', 05:53:11 INFO - '-c', 05:53:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:53:11 INFO - 'enabled': True, 05:53:11 INFO - 'halt_on_failure': False, 05:53:11 INFO - 'name': 'check_screen_resolution'},), 05:53:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:53:11 INFO - 'cmd': ('bash', 05:53:11 INFO - '-c', 05:53:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:53:11 INFO - 'enabled': True, 05:53:11 INFO - 'halt_on_failure': False, 05:53:11 INFO - 'name': 'check_screen_resolution'},), 05:53:11 INFO - 'run_cmd_checks_enabled': True, 05:53:11 INFO - 'sps_profile': False, 05:53:11 INFO - 'sps_profile_interval': 0, 05:53:11 INFO - 'suite': 'dromaeojs', 05:53:11 INFO - 'system_bits': '32', 05:53:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:53:11 INFO - 'title': 't-yosemite-r7-0114', 05:53:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:53:11 INFO - 'use_talos_json': True, 05:53:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:53:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:53:11 INFO - 'work_dir': 'build'} 05:53:11 INFO - [mozharness: 2016-08-16 12:53:11.553647Z] Running clobber step. 05:53:11 INFO - Running pre-action listener: _resource_record_pre_action 05:53:11 INFO - Running main action method: clobber 05:53:11 INFO - rmtree: /builds/slave/test/build 05:53:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:53:13 INFO - Running post-action listener: _resource_record_post_action 05:53:13 INFO - [mozharness: 2016-08-16 12:53:13.668427Z] Finished clobber step (success) 05:53:13 INFO - [mozharness: 2016-08-16 12:53:13.668604Z] Running read-buildbot-config step. 05:53:13 INFO - Running pre-action listener: _resource_record_pre_action 05:53:13 INFO - Running main action method: read_buildbot_config 05:53:13 INFO - Using buildbot properties: 05:53:13 INFO - { 05:53:13 INFO - "project": "", 05:53:13 INFO - "product": "firefox", 05:53:13 INFO - "script_repo_revision": "production", 05:53:13 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:53:13 INFO - "repository": "", 05:53:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:53:13 INFO - "buildid": "20160816030459", 05:53:13 INFO - "stage_platform": "macosx64", 05:53:13 INFO - "basedir": "/builds/slave/test", 05:53:13 INFO - "nightly_build": "True", 05:53:13 INFO - "buildnumber": 44, 05:53:13 INFO - "slavename": "t-yosemite-r7-0114", 05:53:13 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:53:13 INFO - "platform": "yosemite_r7", 05:53:13 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:53:13 INFO - "branch": "mozilla-central", 05:53:13 INFO - "repo_path": "mozilla-central", 05:53:13 INFO - "revision": "054d4856cea6150a6638e5daf7913713281af97d", 05:53:13 INFO - "pgo_build": "False", 05:53:13 INFO - "builduid": "6ae259358ece4561b497272569cd90f9", 05:53:13 INFO - "slavebuilddir": "test" 05:53:13 INFO - } 05:53:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/HN4qu-HUSPu_PrevkeOdOA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 05:53:13 INFO - Running post-action listener: _resource_record_post_action 05:53:13 INFO - [mozharness: 2016-08-16 12:53:13.672021Z] Finished read-buildbot-config step (success) 05:53:13 INFO - [mozharness: 2016-08-16 12:53:13.672154Z] Running download-and-extract step. 05:53:13 INFO - Running pre-action listener: _resource_record_pre_action 05:53:13 INFO - Running main action method: download_and_extract 05:53:13 INFO - mkdir: /builds/slave/test/build/tests 05:53:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:53:13 INFO - trying https://queue.taskcluster.net/v1/task/HN4qu-HUSPu_PrevkeOdOA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 05:53:13 INFO - Downloading https://queue.taskcluster.net/v1/task/HN4qu-HUSPu_PrevkeOdOA/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 05:53:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HN4qu-HUSPu_PrevkeOdOA/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 05:53:14 INFO - Downloaded 1199 bytes. 05:53:14 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 05:53:14 INFO - Using the following test package requirements: 05:53:14 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:53:14 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:53:14 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 05:53:14 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:53:14 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 05:53:14 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:53:14 INFO - u'jsshell-mac.zip'], 05:53:14 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:53:14 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 05:53:14 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:53:14 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:53:14 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 05:53:14 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:53:14 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 05:53:14 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:53:14 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 05:53:14 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:53:14 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 05:53:14 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:53: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')]} 05:53:14 INFO - trying https://queue.taskcluster.net/v1/task/HN4qu-HUSPu_PrevkeOdOA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:53:14 INFO - Downloading https://queue.taskcluster.net/v1/task/HN4qu-HUSPu_PrevkeOdOA/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 05:53:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HN4qu-HUSPu_PrevkeOdOA/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 05:53:15 INFO - Downloaded 16332274 bytes. 05:53:15 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 05:53:23 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 05:53:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:53:23 INFO - trying https://queue.taskcluster.net/v1/task/HN4qu-HUSPu_PrevkeOdOA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:53:23 INFO - Downloading https://queue.taskcluster.net/v1/task/HN4qu-HUSPu_PrevkeOdOA/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 05:53:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HN4qu-HUSPu_PrevkeOdOA/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 05:53:24 INFO - Downloaded 16332274 bytes. 05:53:24 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 05:53:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:53:30 INFO - trying https://queue.taskcluster.net/v1/task/HN4qu-HUSPu_PrevkeOdOA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 05:53:30 INFO - Downloading https://queue.taskcluster.net/v1/task/HN4qu-HUSPu_PrevkeOdOA/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 05:53:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HN4qu-HUSPu_PrevkeOdOA/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 05:53:31 INFO - Downloaded 14413719 bytes. 05:53:31 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 05:53: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')]} 05:53:32 INFO - trying https://queue.taskcluster.net/v1/task/HN4qu-HUSPu_PrevkeOdOA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:53:32 INFO - Downloading https://queue.taskcluster.net/v1/task/HN4qu-HUSPu_PrevkeOdOA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 05:53:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HN4qu-HUSPu_PrevkeOdOA/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 05:53:34 INFO - Downloaded 111207323 bytes. 05:53:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HN4qu-HUSPu_PrevkeOdOA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:53:34 INFO - mkdir: /builds/slave/test/properties 05:53:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:53:34 INFO - Writing to file /builds/slave/test/properties/build_url 05:53:34 INFO - Contents: 05:53:34 INFO - build_url:https://queue.taskcluster.net/v1/task/HN4qu-HUSPu_PrevkeOdOA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:53:35 INFO - Running post-action listener: _resource_record_post_action 05:53:35 INFO - Running post-action listener: set_extra_try_arguments 05:53:35 INFO - [mozharness: 2016-08-16 12:53:35.687401Z] Finished download-and-extract step (success) 05:53:35 INFO - [mozharness: 2016-08-16 12:53:35.687556Z] Running populate-webroot step. 05:53:35 INFO - Running pre-action listener: _resource_record_pre_action 05:53:35 INFO - Running main action method: populate_webroot 05:53:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:53:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:53:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:53:35 INFO - '--tppagecycles', 05:53:35 INFO - '7'], 05:53:35 INFO - 'tests': ['tsvgm']}}, 05:53:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:53:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:53:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:53:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:53:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:53:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:53:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:53:35 INFO - 'talos_options': ['--disable-e10s'], 05:53:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:53:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:53:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:53:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:53:35 INFO - 'talos_options': ['--disable-e10s'], 05:53:35 INFO - 'tests': ['damp', 'tps']}, 05:53:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:53:35 INFO - 'tests': ['damp', 'tps']}, 05:53:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:53:35 INFO - 'tests': ['dromaeo_dom']}, 05:53:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:53:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:53:35 INFO - 'tests': ['basic_compositor_video']}, 05:53:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:53:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:53:35 INFO - 'tests': ['a11yr', 05:53:35 INFO - 'ts_paint', 05:53:35 INFO - 'tpaint', 05:53:35 INFO - 'sessionrestore', 05:53:35 INFO - 'sessionrestore_no_auto_restore', 05:53:35 INFO - 'tabpaint']}, 05:53:35 INFO - 'other-e10s': {'tests': ['a11yr', 05:53:35 INFO - 'ts_paint', 05:53:35 INFO - 'tpaint', 05:53:35 INFO - 'sessionrestore', 05:53:35 INFO - 'sessionrestore_no_auto_restore', 05:53:35 INFO - 'tabpaint']}, 05:53:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:53:35 INFO - 'ts_paint', 05:53:35 INFO - 'tpaint', 05:53:35 INFO - 'sessionrestore', 05:53:35 INFO - 'sessionrestore_no_auto_restore', 05:53:35 INFO - 'tabpaint']}, 05:53:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:53:35 INFO - 'ts_paint', 05:53:35 INFO - 'tpaint', 05:53:35 INFO - 'sessionrestore', 05:53:35 INFO - 'sessionrestore_no_auto_restore', 05:53:35 INFO - 'tabpaint']}, 05:53:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:53:35 INFO - 'tests': ['a11yr', 05:53:35 INFO - 'ts_paint', 05:53:35 INFO - 'tpaint', 05:53:35 INFO - 'sessionrestore', 05:53:35 INFO - 'sessionrestore_no_auto_restore', 05:53:35 INFO - 'tabpaint']}, 05:53:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:53:35 INFO - 'tests': ['a11yr', 05:53:35 INFO - 'ts_paint', 05:53:35 INFO - 'tpaint', 05:53:35 INFO - 'sessionrestore', 05:53:35 INFO - 'sessionrestore_no_auto_restore', 05:53:35 INFO - 'tabpaint']}, 05:53:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:53:35 INFO - 'tests': ['tsvgx', 05:53:35 INFO - 'tsvgr_opacity', 05:53:35 INFO - 'tart', 05:53:35 INFO - 'tscrollx', 05:53:35 INFO - 'cart']}, 05:53:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:53:35 INFO - 'tsvgr_opacity', 05:53:35 INFO - 'tart', 05:53:35 INFO - 'tscrollx', 05:53:35 INFO - 'cart']}, 05:53:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:53:35 INFO - 'talos_options': ['--disable-e10s'], 05:53:35 INFO - 'tests': ['tp5o']}, 05:53:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:53:35 INFO - 'tests': ['tp5o']}, 05:53:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:53:35 INFO - 'talos_options': ['--disable-e10s', 05:53:35 INFO - '--xperf_path', 05:53:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:53:35 INFO - 'tests': ['tp5n']}, 05:53:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:53:35 INFO - 'talos_options': ['--xperf_path', 05:53:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:53:35 INFO - 'tests': ['tp5n']}}, 05:53:35 INFO - 'talos.zip': {'path': '', 05:53:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:53:35 INFO - Running post-action listener: _resource_record_post_action 05:53:35 INFO - [mozharness: 2016-08-16 12:53:35.707126Z] Finished populate-webroot step (success) 05:53:35 INFO - [mozharness: 2016-08-16 12:53:35.707224Z] Running create-virtualenv step. 05:53:35 INFO - Running pre-action listener: _resource_record_pre_action 05:53:35 INFO - Running main action method: create_virtualenv 05:53:35 INFO - Creating virtualenv /builds/slave/test/build/venv 05:53:35 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 05:53:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:53:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:53:35 INFO - Using real prefix '/tools/python27' 05:53:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:53:36 INFO - Installing distribute.............................................................................................................................................................................................done. 05:53:39 INFO - Installing pip.................done. 05:53:39 INFO - Return code: 0 05:53:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:53: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')]} 05:53:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:53:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53: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')]} 05:53:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:53:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:39 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 0x104fcfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105064618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7b9c9d940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f33d30>, '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 0x104c98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f2bb20>, '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.O0JCfSfI1F/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.UWkEZEQfwc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 05:53:39 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 05:53:39 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 05:53:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O0JCfSfI1F/Render', 05:53:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:53:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:53:39 INFO - 'HOME': '/Users/cltbld', 05:53:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:53:39 INFO - 'LOGNAME': 'cltbld', 05:53:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:53:39 INFO - 'MOZ_NO_REMOTE': '1', 05:53:39 INFO - 'NO_EM_RESTART': '1', 05:53:39 INFO - 'PAGER': '/bin/cat', 05:53:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:53:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:53:39 INFO - 'PWD': '/builds/slave/test', 05:53:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:53:39 INFO - 'SHELL': '/bin/bash', 05:53:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UWkEZEQfwc/Listeners', 05:53:39 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 05:53:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:53:39 INFO - 'USER': 'cltbld', 05:53:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:53:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:53:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:53:39 INFO - 'XPC_FLAGS': '0x0', 05:53:39 INFO - 'XPC_SERVICE_NAME': '0', 05:53:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:53:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:53:39 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 05:53:39 INFO - Cleaning up... 05:53:39 INFO - Return code: 0 05:53:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:53: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')]} 05:53:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:53:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53: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')]} 05:53:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:53:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:39 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 0x104fcfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105064618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7b9c9d940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f33d30>, '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 0x104c98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f2bb20>, '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.O0JCfSfI1F/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.UWkEZEQfwc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 05:53:39 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 05:53:39 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 05:53:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O0JCfSfI1F/Render', 05:53:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:53:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:53:39 INFO - 'HOME': '/Users/cltbld', 05:53:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:53:39 INFO - 'LOGNAME': 'cltbld', 05:53:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:53:39 INFO - 'MOZ_NO_REMOTE': '1', 05:53:39 INFO - 'NO_EM_RESTART': '1', 05:53:39 INFO - 'PAGER': '/bin/cat', 05:53:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:53:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:53:39 INFO - 'PWD': '/builds/slave/test', 05:53:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:53:39 INFO - 'SHELL': '/bin/bash', 05:53:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UWkEZEQfwc/Listeners', 05:53:39 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 05:53:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:53:39 INFO - 'USER': 'cltbld', 05:53:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:53:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:53:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:53:39 INFO - 'XPC_FLAGS': '0x0', 05:53:39 INFO - 'XPC_SERVICE_NAME': '0', 05:53:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:53:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:53:39 INFO - Downloading/unpacking psutil>=3.1.1 05:53:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:53:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:53:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:53:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:53:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:53:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:53:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:53:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:53:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:53:43 INFO - Installing collected packages: psutil 05:53:43 INFO - Running setup.py install for psutil 05:53:43 INFO - building 'psutil._psutil_osx' extension 05:53:43 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 05:53:43 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 05:53:43 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 05:53:44 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 05:53:44 INFO - building 'psutil._psutil_posix' extension 05:53:44 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 05:53:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:53:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:53:44 INFO - ^ 05:53:44 INFO - 1 warning generated. 05:53:44 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 05:53:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:53:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:53:44 INFO - Successfully installed psutil 05:53:44 INFO - Cleaning up... 05:53:44 INFO - Return code: 0 05:53:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:53:44 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')]} 05:53:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:53:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:44 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')]} 05:53:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:53:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:44 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 0x104fcfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105064618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7b9c9d940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f33d30>, '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 0x104c98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f2bb20>, '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.O0JCfSfI1F/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.UWkEZEQfwc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 05:53:44 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 05:53:44 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 05:53:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O0JCfSfI1F/Render', 05:53:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:53:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:53:44 INFO - 'HOME': '/Users/cltbld', 05:53:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:53:44 INFO - 'LOGNAME': 'cltbld', 05:53:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:53:44 INFO - 'MOZ_NO_REMOTE': '1', 05:53:44 INFO - 'NO_EM_RESTART': '1', 05:53:44 INFO - 'PAGER': '/bin/cat', 05:53:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:53:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:53:44 INFO - 'PWD': '/builds/slave/test', 05:53:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:53:44 INFO - 'SHELL': '/bin/bash', 05:53:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UWkEZEQfwc/Listeners', 05:53:44 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 05:53:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:53:44 INFO - 'USER': 'cltbld', 05:53:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:53:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:53:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:53:44 INFO - 'XPC_FLAGS': '0x0', 05:53:44 INFO - 'XPC_SERVICE_NAME': '0', 05:53:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:53:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:53:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:53:44 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 05:53:44 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 05:53:44 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 05:53:44 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 05:53:44 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 05:53:44 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 05:53:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:53:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:53:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:53:47 INFO - Installing collected packages: mozsystemmonitor 05:53:47 INFO - Running setup.py install for mozsystemmonitor 05:53:47 INFO - Successfully installed mozsystemmonitor 05:53:47 INFO - Cleaning up... 05:53:47 INFO - Return code: 0 05:53:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:53:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:53:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:53:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:53:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:53:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:47 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 0x104fcfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105064618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7b9c9d940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f33d30>, '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 0x104c98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f2bb20>, '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.O0JCfSfI1F/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.UWkEZEQfwc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 05:53:47 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 05:53:47 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 05:53:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O0JCfSfI1F/Render', 05:53:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:53:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:53:47 INFO - 'HOME': '/Users/cltbld', 05:53:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:53:47 INFO - 'LOGNAME': 'cltbld', 05:53:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:53:47 INFO - 'MOZ_NO_REMOTE': '1', 05:53:47 INFO - 'NO_EM_RESTART': '1', 05:53:47 INFO - 'PAGER': '/bin/cat', 05:53:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:53:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:53:47 INFO - 'PWD': '/builds/slave/test', 05:53:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:53:47 INFO - 'SHELL': '/bin/bash', 05:53:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UWkEZEQfwc/Listeners', 05:53:47 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 05:53:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:53:47 INFO - 'USER': 'cltbld', 05:53:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:53:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:53:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:53:47 INFO - 'XPC_FLAGS': '0x0', 05:53:47 INFO - 'XPC_SERVICE_NAME': '0', 05:53:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:53:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:53:47 INFO - Downloading/unpacking jsonschema==2.5.1 05:53:47 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 05:53:47 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 05:53:47 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 05:53:47 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 05:53:47 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 05:53:47 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 05:53:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:53:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:53:50 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 05:53:50 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 05:53:50 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 05:53:50 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 05:53:50 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 05:53:50 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 05:53:50 INFO - Downloading functools32-3.2.3-2.tar.gz 05:53:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:53:50 INFO - warning: no files found matching '*.txt' 05:53:50 INFO - no previously-included directories found matching 'build' 05:53:50 INFO - no previously-included directories found matching 'dist' 05:53:50 INFO - no previously-included directories found matching '.git*' 05:53:50 INFO - Installing collected packages: jsonschema, functools32 05:53:50 INFO - Running setup.py install for functools32 05:53:50 INFO - warning: no files found matching '*.txt' 05:53:50 INFO - no previously-included directories found matching 'build' 05:53:50 INFO - no previously-included directories found matching 'dist' 05:53:50 INFO - no previously-included directories found matching '.git*' 05:53:50 INFO - Successfully installed jsonschema functools32 05:53:50 INFO - Cleaning up... 05:53:51 INFO - Return code: 0 05:53:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:53:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:53:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:53:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:53:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:53:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104fcfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105064618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7b9c9d940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f33d30>, '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 0x104c98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f2bb20>, '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.O0JCfSfI1F/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.UWkEZEQfwc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 05:53:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:53:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:53:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O0JCfSfI1F/Render', 05:53:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:53:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:53:51 INFO - 'HOME': '/Users/cltbld', 05:53:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:53:51 INFO - 'LOGNAME': 'cltbld', 05:53:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:53:51 INFO - 'MOZ_NO_REMOTE': '1', 05:53:51 INFO - 'NO_EM_RESTART': '1', 05:53:51 INFO - 'PAGER': '/bin/cat', 05:53:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:53:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:53:51 INFO - 'PWD': '/builds/slave/test', 05:53:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:53:51 INFO - 'SHELL': '/bin/bash', 05:53:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UWkEZEQfwc/Listeners', 05:53:51 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 05:53:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:53:51 INFO - 'USER': 'cltbld', 05:53:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:53:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:53:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:53:51 INFO - 'XPC_FLAGS': '0x0', 05:53:51 INFO - 'XPC_SERVICE_NAME': '0', 05:53:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:53:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:53:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:53:51 INFO - Cleaning up... 05:53:51 INFO - Return code: 0 05:53:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:53:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:53:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:53:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:53:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:53:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104fcfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105064618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7b9c9d940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f33d30>, '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 0x104c98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f2bb20>, '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.O0JCfSfI1F/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.UWkEZEQfwc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 05:53:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:53:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:53:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O0JCfSfI1F/Render', 05:53:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:53:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:53:51 INFO - 'HOME': '/Users/cltbld', 05:53:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:53:51 INFO - 'LOGNAME': 'cltbld', 05:53:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:53:51 INFO - 'MOZ_NO_REMOTE': '1', 05:53:51 INFO - 'NO_EM_RESTART': '1', 05:53:51 INFO - 'PAGER': '/bin/cat', 05:53:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:53:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:53:51 INFO - 'PWD': '/builds/slave/test', 05:53:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:53:51 INFO - 'SHELL': '/bin/bash', 05:53:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UWkEZEQfwc/Listeners', 05:53:51 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 05:53:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:53:51 INFO - 'USER': 'cltbld', 05:53:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:53:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:53:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:53:51 INFO - 'XPC_FLAGS': '0x0', 05:53:51 INFO - 'XPC_SERVICE_NAME': '0', 05:53:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:53:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:53:51 INFO - Downloading/unpacking blobuploader==1.2.4 05:53:51 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 05:53:51 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 05:53:51 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 05:53:51 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 05:53:51 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 05:53:51 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 05:53:54 INFO - Downloading blobuploader-1.2.4.tar.gz 05:53:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:53:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:53:54 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 05:53:54 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 05:53:54 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 05:53:54 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 05:53:54 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 05:53:54 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 05:53:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:53:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:53:54 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 05:53:54 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 05:53:54 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 05:53:54 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 05:53:54 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 05:53:54 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 05:53:55 INFO - Downloading docopt-0.6.1.tar.gz 05:53:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:53:55 INFO - Installing collected packages: blobuploader, requests, docopt 05:53:55 INFO - Running setup.py install for blobuploader 05:53:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:53:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:53:55 INFO - Running setup.py install for requests 05:53:55 INFO - Running setup.py install for docopt 05:53:56 INFO - Successfully installed blobuploader requests docopt 05:53:56 INFO - Cleaning up... 05:53:56 INFO - Return code: 0 05:53:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:53: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')]} 05:53:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:53:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53: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')]} 05:53:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:53:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:56 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 0x104fcfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105064618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7b9c9d940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f33d30>, '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 0x104c98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f2bb20>, '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.O0JCfSfI1F/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.UWkEZEQfwc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 05:53:56 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 05:53:56 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 05:53:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O0JCfSfI1F/Render', 05:53:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:53:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:53:56 INFO - 'HOME': '/Users/cltbld', 05:53:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:53:56 INFO - 'LOGNAME': 'cltbld', 05:53:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:53:56 INFO - 'MOZ_NO_REMOTE': '1', 05:53:56 INFO - 'NO_EM_RESTART': '1', 05:53:56 INFO - 'PAGER': '/bin/cat', 05:53:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:53:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:53:56 INFO - 'PWD': '/builds/slave/test', 05:53:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:53:56 INFO - 'SHELL': '/bin/bash', 05:53:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UWkEZEQfwc/Listeners', 05:53:56 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 05:53:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:53:56 INFO - 'USER': 'cltbld', 05:53:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:53:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:53:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:53:56 INFO - 'XPC_FLAGS': '0x0', 05:53:56 INFO - 'XPC_SERVICE_NAME': '0', 05:53:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:53:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:53:56 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-lCLHtV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:53:56 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-Jwvt2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:53:56 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-nz7R3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:53:56 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-Wut5Lt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:53:56 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-8tQMCJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:53:57 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-EBZp03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:53:57 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-9P41YP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:53:57 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-C_nfN0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:53:57 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-Zyv3Kn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:53:57 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-bZs4p7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:53:57 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-FWQ9Yq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:53:57 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-E6voYm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:53:57 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-w3Crun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:53:57 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-3IOx5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:53:57 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-X1exwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:53:58 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-prWwcp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:53:58 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-HOLPtX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:53:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:53:58 INFO - Running setup.py install for manifestparser 05:53:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:53:58 INFO - Running setup.py install for mozcrash 05:53:58 INFO - Running setup.py install for mozdebug 05:53:58 INFO - Running setup.py install for mozdevice 05:53:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:53:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:53:58 INFO - Running setup.py install for mozfile 05:53:58 INFO - Running setup.py install for mozhttpd 05:53:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:53:59 INFO - Running setup.py install for mozinfo 05:53:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:53:59 INFO - Running setup.py install for mozInstall 05:53:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:53:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:53:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:53:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:53:59 INFO - Running setup.py install for mozleak 05:53:59 INFO - Running setup.py install for mozlog 05:53:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:53:59 INFO - Running setup.py install for moznetwork 05:53:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:53:59 INFO - Running setup.py install for mozprocess 05:54:00 INFO - Running setup.py install for mozprofile 05:54:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:54:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:54:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:54:00 INFO - Running setup.py install for mozrunner 05:54:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:54:00 INFO - Running setup.py install for mozscreenshot 05:54:00 INFO - Running setup.py install for moztest 05:54:00 INFO - Running setup.py install for mozversion 05:54:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:54:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:54:00 INFO - Cleaning up... 05:54:00 INFO - Return code: 0 05:54:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:54:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:54:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:54:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:00 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 0x104fcfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105064618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7b9c9d940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f33d30>, '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 0x104c98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f2bb20>, '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.O0JCfSfI1F/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.UWkEZEQfwc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 05:54:00 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 05:54:00 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 05:54:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O0JCfSfI1F/Render', 05:54:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:54:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:54:00 INFO - 'HOME': '/Users/cltbld', 05:54:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:54:00 INFO - 'LOGNAME': 'cltbld', 05:54:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:00 INFO - 'MOZ_NO_REMOTE': '1', 05:54:00 INFO - 'NO_EM_RESTART': '1', 05:54:00 INFO - 'PAGER': '/bin/cat', 05:54:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:54:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:00 INFO - 'PWD': '/builds/slave/test', 05:54:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:54:00 INFO - 'SHELL': '/bin/bash', 05:54:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UWkEZEQfwc/Listeners', 05:54:00 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 05:54:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:54:00 INFO - 'USER': 'cltbld', 05:54:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:54:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:54:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:00 INFO - 'XPC_FLAGS': '0x0', 05:54:00 INFO - 'XPC_SERVICE_NAME': '0', 05:54:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:54:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:54:01 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-ec_oII-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:54:01 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)) 05:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:54:01 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-ncZ0Xo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:54:01 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)) 05:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:54:01 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-JNILm3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:54:01 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)) 05:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:54:01 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-C5_9kP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:54:01 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)) 05:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:54:01 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-LEg_p3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:54:01 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)) 05:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:54:01 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-GXKlGR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:54:01 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)) 05:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:54:01 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-YwOrKJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:54:01 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)) 05:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:54:01 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-u2W93C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:54:01 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)) 05:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:54:01 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-691beu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:54:02 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)) 05:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:54:02 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-CaTUDO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:54:02 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)) 05:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:54:02 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-a8OBa0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:54:02 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)) 05:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:54:02 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-ITK9NC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:54:02 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)) 05:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:54:02 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-7rKoWO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:54:02 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)) 05:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:54:02 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-7EcO93-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:54:02 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)) 05:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:54:02 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-f8zKhS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:54:02 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)) 05:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:54:02 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-gO_X3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:54:02 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)) 05:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:54:02 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-advbr_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:54:02 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)) 05:54:02 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)) 05:54:02 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)) 05:54:02 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)) 05:54:02 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)) 05:54:02 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)) 05:54:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:54:02 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 05:54:02 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 05:54:02 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 05:54:02 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 05:54:02 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 05:54:02 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 05:54:05 INFO - Downloading blessings-1.6.tar.gz 05:54:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:54:05 INFO - Installing collected packages: blessings 05:54:05 INFO - Running setup.py install for blessings 05:54:05 INFO - Successfully installed blessings 05:54:05 INFO - Cleaning up... 05:54:05 INFO - Return code: 0 05:54:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:54:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:54:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:54:05 INFO - Reading from file tmpfile_stdout 05:54:05 INFO - Current package versions: 05:54:05 INFO - blessings == 1.6 05:54:05 INFO - blobuploader == 1.2.4 05:54:05 INFO - docopt == 0.6.1 05:54:05 INFO - functools32 == 3.2.3-2 05:54:05 INFO - jsonschema == 2.5.1 05:54:05 INFO - manifestparser == 1.1 05:54:05 INFO - mozInstall == 1.12 05:54:05 INFO - mozcrash == 0.17 05:54:05 INFO - mozdebug == 0.1 05:54:05 INFO - mozdevice == 0.48 05:54:05 INFO - mozfile == 1.2 05:54:05 INFO - mozhttpd == 0.7 05:54:05 INFO - mozinfo == 0.9 05:54:05 INFO - mozleak == 0.1 05:54:05 INFO - mozlog == 3.3 05:54:05 INFO - moznetwork == 0.27 05:54:05 INFO - mozprocess == 0.23 05:54:05 INFO - mozprofile == 0.28 05:54:05 INFO - mozrunner == 6.12 05:54:05 INFO - mozscreenshot == 0.1 05:54:05 INFO - mozsystemmonitor == 0.3 05:54:05 INFO - moztest == 0.7 05:54:05 INFO - mozversion == 1.4 05:54:05 INFO - psutil == 3.1.1 05:54:05 INFO - requests == 1.2.3 05:54:05 INFO - wsgiref == 0.1.2 05:54:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:54: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')]} 05:54:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:06 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')]} 05:54:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:06 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 0x104fcfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105064618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7b9c9d940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f33d30>, '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 0x104c98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f2bb20>, '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.O0JCfSfI1F/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.UWkEZEQfwc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 05:54:06 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 05:54:06 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 05:54:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O0JCfSfI1F/Render', 05:54:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:54:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:54:06 INFO - 'HOME': '/Users/cltbld', 05:54:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:54:06 INFO - 'LOGNAME': 'cltbld', 05:54:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:06 INFO - 'MOZ_NO_REMOTE': '1', 05:54:06 INFO - 'NO_EM_RESTART': '1', 05:54:06 INFO - 'PAGER': '/bin/cat', 05:54:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:54:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:06 INFO - 'PWD': '/builds/slave/test', 05:54:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:54:06 INFO - 'SHELL': '/bin/bash', 05:54:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UWkEZEQfwc/Listeners', 05:54:06 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 05:54:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:54:06 INFO - 'USER': 'cltbld', 05:54:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:54:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:54:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:06 INFO - 'XPC_FLAGS': '0x0', 05:54:06 INFO - 'XPC_SERVICE_NAME': '0', 05:54:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:54:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:06 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)) 05:54:06 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)) 05:54:06 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)) 05:54:06 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)) 05:54:06 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)) 05:54:06 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)) 05:54:06 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)) 05:54:06 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)) 05:54:06 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)) 05:54:06 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)) 05:54:06 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)) 05:54:06 INFO - Cleaning up... 05:54:06 INFO - Return code: 0 05:54:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:54:06 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')]} 05:54:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:06 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')]} 05:54:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:06 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 0x104fcfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105064618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7b9c9d940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f33d30>, '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 0x104c98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f2bb20>, '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.O0JCfSfI1F/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.UWkEZEQfwc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 05:54:06 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 05:54:06 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 05:54:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O0JCfSfI1F/Render', 05:54:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:54:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:54:06 INFO - 'HOME': '/Users/cltbld', 05:54:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:54:06 INFO - 'LOGNAME': 'cltbld', 05:54:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:06 INFO - 'MOZ_NO_REMOTE': '1', 05:54:06 INFO - 'NO_EM_RESTART': '1', 05:54:06 INFO - 'PAGER': '/bin/cat', 05:54:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:54:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:06 INFO - 'PWD': '/builds/slave/test', 05:54:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:54:06 INFO - 'SHELL': '/bin/bash', 05:54:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UWkEZEQfwc/Listeners', 05:54:06 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 05:54:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:54:06 INFO - 'USER': 'cltbld', 05:54:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:54:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:54:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:06 INFO - 'XPC_FLAGS': '0x0', 05:54:06 INFO - 'XPC_SERVICE_NAME': '0', 05:54:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:54:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:54:06 INFO - Cleaning up... 05:54:06 INFO - Return code: 0 05:54:06 INFO - Running post-action listener: _resource_record_post_action 05:54:06 INFO - Running post-action listener: _start_resource_monitoring 05:54:06 INFO - Starting resource monitoring. 05:54:06 INFO - [mozharness: 2016-08-16 12:54:06.812764Z] Finished create-virtualenv step (success) 05:54:06 INFO - [mozharness: 2016-08-16 12:54:06.813706Z] Running install step. 05:54:06 INFO - Running pre-action listener: _resource_record_pre_action 05:54:06 INFO - Running main action method: install 05:54:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:54:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:54:07 INFO - Reading from file tmpfile_stdout 05:54:07 INFO - Detecting whether we're running mozinstall >=1.0... 05:54:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:54:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:54:07 INFO - Reading from file tmpfile_stdout 05:54:07 INFO - Output received: 05:54:07 INFO - Usage: mozinstall [options] installer 05:54:07 INFO - Options: 05:54:07 INFO - -h, --help show this help message and exit 05:54:07 INFO - -d DEST, --destination=DEST 05:54:07 INFO - Directory to install application into. [default: 05:54:07 INFO - "/builds/slave/test"] 05:54:07 INFO - --app=APP Application being installed. [default: firefox] 05:54:07 INFO - mkdir: /builds/slave/test/build/application 05:54:07 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'] 05:54:07 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 05:54:41 INFO - Reading from file tmpfile_stdout 05:54:41 INFO - Output received: 05:54:41 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:54:41 INFO - Running post-action listener: _resource_record_post_action 05:54:41 INFO - [mozharness: 2016-08-16 12:54:41.135010Z] Finished install step (success) 05:54:41 INFO - [mozharness: 2016-08-16 12:54:41.135180Z] Running run-tests step. 05:54:41 INFO - Running pre-action listener: _resource_record_pre_action 05:54:41 INFO - Running main action method: run_tests 05:54:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:54:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:54:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:54:41 INFO - 2016-08-16 05:54:41.149 screenresolution[1636:7395] starting screenresolution argv=screenresolution get 05:54:41 INFO - 2016-08-16 05:54:41.152 screenresolution[1636:7395] Display 0: 1600x1200x32@60 05:54:41 INFO - 2016-08-16 05:54:41.158 screenresolution[1637:7397] starting screenresolution argv=screenresolution list 05:54:41 INFO - Available Modes on Display 0 05:54:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:54:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:54:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:54:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:54:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:54:41 INFO - Intel Iris: 05:54:41 INFO - Chipset Model: Intel Iris 05:54:41 INFO - Type: GPU 05:54:41 INFO - Bus: Built-In 05:54:41 INFO - VRAM (Dynamic, Max): 1536 MB 05:54:41 INFO - Vendor: Intel (0x8086) 05:54:41 INFO - Device ID: 0x0a2e 05:54:41 INFO - Revision ID: 0x0009 05:54:41 INFO - Displays: 05:54:41 INFO - Display: 05:54:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:54:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:54:41 INFO - Display Serial Number: 1600x1200 60 05:54:41 INFO - Main Display: Yes 05:54:41 INFO - Mirror: Off 05:54:41 INFO - Online: Yes 05:54:41 INFO - Rotation: Supported 05:54:41 INFO - Return code: 0 05:54:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:54:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:54:41 INFO - Python 2.7.3 05:54:41 INFO - Return code: 0 05:54:41 INFO - grabbing minidump binary from tooltool 05:54: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')]} 05:54:41 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 0x104f33d30>, '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 0x104c98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f2bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:54:41 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 05:54:41 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 05:54:41 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 05:54:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:54:41 INFO - Return code: 0 05:54:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:54:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:54:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:54:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:54:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:54:41 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HN4qu-HUSPu_PrevkeOdOA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0114', '--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 05:54:41 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HN4qu-HUSPu_PrevkeOdOA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0114 --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 05:54:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O0JCfSfI1F/Render', 05:54:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:54:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:54:41 INFO - 'HOME': '/Users/cltbld', 05:54:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:54:41 INFO - 'LOGNAME': 'cltbld', 05:54:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:54:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:54:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:41 INFO - 'MOZ_NO_REMOTE': '1', 05:54:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:54:41 INFO - 'NO_EM_RESTART': '1', 05:54:41 INFO - 'PAGER': '/bin/cat', 05:54:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:54:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:41 INFO - 'PWD': '/builds/slave/test', 05:54:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:54:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:54:41 INFO - 'SHELL': '/bin/bash', 05:54:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UWkEZEQfwc/Listeners', 05:54:41 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 05:54:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:54:41 INFO - 'USER': 'cltbld', 05:54:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:54:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:54:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:41 INFO - 'XPC_FLAGS': '0x0', 05:54:41 INFO - 'XPC_SERVICE_NAME': '0', 05:54:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:54:41 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HN4qu-HUSPu_PrevkeOdOA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0114', '--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 05:54:41 INFO - mozversion application_buildid: 20160816030459 05:54:41 INFO - mozversion application_changeset: 054d4856cea6150a6638e5daf7913713281af97d 05:54:41 INFO - mozversion application_display_name: Nightly 05:54:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:54:41 INFO - mozversion application_name: Firefox 05:54:41 INFO - mozversion application_remotingname: firefox 05:54:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:54:41 INFO - mozversion application_vendor: Mozilla 05:54:41 INFO - mozversion application_version: 51.0a1 05:54:41 INFO - mozversion platform_buildid: 20160816030459 05:54:41 INFO - mozversion platform_changeset: 054d4856cea6150a6638e5daf7913713281af97d 05:54:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:54:41 INFO - mozversion platform_version: 51.0a1 05:54:41 INFO - using testdate: 1471352081 05:54:41 INFO - actual date: 1471352081 05:54:41 INFO - starting webserver on 'localhost:49267' 05:54:41 INFO - SUITE-START | Running 2 tests 05:54:41 INFO - TEST-START | dromaeo_css 05:54:41 INFO - operating with platform_type : mac_ 05:54:41 INFO - Initialising browser for dromaeo_css test... 05:54:41 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/tmp9Dvh8T/profile http://localhost:49267/getInfo.html) 05:54:44 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 05:54:44 INFO - PROCESS | 1647 | colorDepth:24 05:54:44 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 05:54:44 INFO - PROCESS | 1647 | __metrics 05:54:44 INFO - PROCESS | 1647 | 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" 05:54:44 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:54:45 INFO - TEST-INFO | 1647: exit 0 05:54:45 INFO - Browser initialized. 05:54:45 INFO - Running cycle 1/1 for dromaeo_css test... 05:54:45 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/tmp9Dvh8T/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:54:57 INFO - PROCESS | 1653 | RSS: Main: 228356096 05:54:57 INFO - PROCESS | 1653 | 05:55:48 INFO - PROCESS | 1653 | 1471352148174 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] 05:57:59 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 05:58:01 INFO - PROCESS | 1653 | RSS: Main: 367362048 05:58:01 INFO - PROCESS | 1653 | 06:01:04 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 06:01:04 INFO - PROCESS | 1653 | RSS: Main: 372244480 06:01:04 INFO - PROCESS | 1653 | 06:04:07 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 06:04:07 INFO - PROCESS | 1653 | RSS: Main: 362864640 06:04:07 INFO - PROCESS | 1653 | 06:07:10 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 06:07:10 INFO - PROCESS | 1653 | RSS: Main: 354324480 06:07:10 INFO - PROCESS | 1653 | 06:10:14 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 06:10:14 INFO - PROCESS | 1653 | RSS: Main: 337186816 06:10:14 INFO - PROCESS | 1653 | 06:13:17 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 06:13:18 INFO - PROCESS | 1653 | RSS: Main: 479760384 06:13:18 INFO - PROCESS | 1653 | 06:13:18 INFO - PROCESS | 1653 | __start_tp_report 06:13:18 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 06:13:18 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 06:13:18 INFO - PROCESS | 1653 | |i|pagename|runs| 06:13:18 INFO - PROCESS | 1653 | |0;dojo.html;3290.967090329096;3303.801771893685;3313.7514686398526;3354.949675754863;3356.7146792522635;5071.391433028042;5135.870108576112;5556.611037227396;5601.691906945116;5661.660300381975;5538.252335934648;5598.804041858536;5660.5471562275025;5664.915026274599;5666.178404131409;4146.523149837777;5052.671576347544;5147.433782283946;5177.430482646924;5306.097963346221;5212.739363031852;5216.374035115772;5262.105442074857;5265.552428043621;5275.287836142129;5078.492150784948;5103.260027296058;5109.080365534202;5145.6398052136365;5175.922361164586;5057.848264552069;5085.186370180791;5113.718745468997;5125.692458452505;5131.897362052738;5050.49495050492;5076;5131.538161565477;5143.3313669223135;5168.689878607295;5444.3194600674915;5468.207109969072;5543.916841247385;5606.915896261559;5691.971540142273;6144;6187.133801267826;6214.689265536705;6222.719977600974;6235.781747638811;6203.255609326865;6261.40516650922;6277.7175027123885;6278.277998030239;6278.843028924268;5560.360558535739;5678.176664383724;5755.3381361144;5800.7679692812235;5817.301923769175;6308.779192728232;6313.968430157912;6315.936840631535;6319.178506794179;6325.683715814283;6115.663638499926;6122.081687746784;6130.540209484307;6133.84665383369;6134;5927.347991720907;6091.634501929899;6110.419510146529;6213.316535181127;6228.03465462854;6118.296395914438;6122.020476723703;6129.389354848322;6142.959235178526;6168.568200225941;6189.164462797465;6193.876122477525;6204.5036397089025;6209.379062093754;6210.4100110489435;4831.975840120777;4884.535969082932;4964.652474326763;4965.205567109245;4998.775055122528;4394.582514661102;4402.185595664812;4461.665375096881;4464.129494748493;4469.664775141812;5522.171674248895;5532.197831314437;5534.446555344514;5544.611877168641;5558.388577256498;6111.480524155497;6138.907916381258;6167.660778657128;6196.721147548371;6197.96901015492;5604.887902241932;5608.551315894719;5608.775648974077;5610.354809196913;5631.7747290108755;5353.491418315331;5375.274337964403;5384.273123128328;5387.649802762808;5425.348958124971;6183.96908015466;6257.061440783918;6261.655608941552;6296.307406185224;6308.905366419507;6172.814815555541;6185.165002724574;6189.473894718819;6193.81418557444;6200;5056.469070747666;6289.371062893857;6317.905231421624;6323.873522529616;6330.461910737639;4390.3414487827085;5290.153575427913;6073.392660733891;6119.204503414528;6134.9079763804475;5658.868226354663;6196.690165491617;6216.2540495140875;6219.222597175353;6226.346233645402;4763.428388593391;5986.580939334205;6017.969910150421;6023.186869772525;6039.909401359071;4822.662413631012;5775.768969241351;5950.404959504015;5960.0761881908375;5970.3432622412365;5884.588078834612;5896.823095307148;5899.646021238739;5912.970435147797;5932;5977.790777322947;6044.032954727222;6044.727987240497;6047.606905551125;6074.848128796815;5691.231683722644;5775.768969241183;5812;5833.416658334133;5877.23595932526 06:13:18 INFO - PROCESS | 1653 | |1;ext.html;475.72408003358044;478.8563430970708;511.66997286750046;540.2652392745865;556.2407314016369;25442;40377.79811100944;41937.580624193826;42087.36868946968;43997.78001109998;43004.354934675925;43231.56768432315;43298;43351.78324108375;43458.565414345845;8806.73579792604;9719.757006074851;9944.154746846527;9949.403035817837;9952.452615106165;8845.425047371933;11942.582009629665;12186.903178713914;12195.08536859739;12472.750544989094;12115.515379384859;12186.634400967941;12193.634190974284;12200.145989780718;12242.51029958805;12092.093093018058;12177.51289948401;12187;12202.816957745641;12216.389180540937;12022.579209727617;12078.69803254917;12094.576689815902;12115.273083615011;12133.968612667933;40654.39018414726;40970.79514602438;41013.79493102516;41027;41102.38346424806;3292.967070329314;3313.4698448248164;3400;3479.8260086995547;3802.8478860845808;31717.524237136462;32351.83824080888;32715.836420817745;32804.85183018607;32806.50790238148;2939.1770304314828;2991.790574659775;3022.2595464111214;3243.7729358944885;3368.7473439492137;369511;369838;369969;370818;371799.28200717916;61231;61581;61632.69183654053;61695.90497092802;61752.69123654353;60486;60500.092498612554;60554.697226513585;60625.09062364068;60648.78702425927;16375.754363684555;16577.00537967726;16626.83373166253;16651.333946642;16676.332946681996;11615.709607259718;11870.228435151688;12607.117501774788;12870.871291287154;12929.482820687255;17625.294988200327;18022.36921707734;18025.188866501037;18045.7293140603;18059.638807224044;5344.706041167773;6651.567648102858;6691.899621505681;6706.250437320675;6721.193456785119;898.7528429681759;931.4830269200523;989.1295659819313;1021.4790456866903;1025.3642741500319;23573;26443.603345949825;29376;30450.84774576113;31059.84470077635;992.3748038735695;998.9920260276896;1057.9047885690325;1141.1840534018218;1197.862245841722;14015.229162395963;15127.54617361459;15468.149251791034;15760.68478630421;15865.762013569805;668918;672759;673337;674494;704979;57238.71380643154;61227.38772612306;61416.54333826686;61437.38562614406;61476.38523614796;1330.7604631166482;1334.7730885749445;1374.5326588959801;1378.7518246715488;1380.4547203854622;27733.44533109367;30430.23924401819;30995.380092398478;31817.5227371585;31820;52306.47693523016;52709.20936186037;52773.944521110134;52905.7354713224;52926;585618;589582;589892;589892;592483;26459.73540264573;26802.329941751614;26806.865965670047;26815.73184268132;26820;48476.788080298276;48662;48802.75598621984;48981.75509122574;49665.00669986652;39654.405183921685;39832.80083599564;39869.20261594694;41088;41420.378694319006;39223.41164882472;39736.801315994395;40154.799226003684;40966.38550421806;41396;38809.6119038806;39511.80244098876;39525.40711889266;39583.40624890687;39807.00482487961;34018.36641854815;34695.82652086824;35537;35612.64387356093;35750.39974496076;25901.35246618774;26021.219363419128;26045.0884219059;26265.474690506464;26391.4721705561 06:13:18 INFO - PROCESS | 1653 | |2;jquery.html;348.1835096698245;367.17569057465977;373.43611147167775;379.84046700385846;394.9368101103824;15971.281292341844;16654.250558724856;20427.48931276719;21063.2627858025;21201.151953921843;14582.489612863552;20244.89877550614;20318.187272509083;20730.27444039459;20782.856942868173;4792.329073929653;5396.946030539693;5566.997940370732;5572.721363931808;5577.051901176789;5339.8398048058425;5417.187421886729;5420.512153906147;5427.240186373911;5430.212619170218;5378.677279363231;5418.239072301498;5427.104527752916;5436.83689489314;5866.677332746697;6113.052476866093;6238.87522249557;6245.687715614201;6248.343923887972;6248.812535623893;5123.821521050142;5840.883182336372;6028.276606807167;6066.545009124334;6106.45041946227;13565.660858478519;13767.967402444856;13789.310534473236;20501.8974905126;25950.87024564865;2064.690296455529;2079.4593405714472;2122.7877212278804;2432.793212576933;2452.3623857796924;9934.552945117415;10043.447610381427;10108.494575271281;10414.843777343345;10422.89577104227;1908.9522761930923;1912.531429799708;1923.8749481283671;1962.1562728026943;1962.2935743132462;120006.3999679996;124033.13950290754;125484.1177382331;126926.36536817349;129017.35491322578;11114.55541778336;11611.941940290244;11616.070714342832;11634;11784.823227651592;14634.926825365805;14638.780418293734;14683.632909177357;14692.926535367254;14713.190774507504;7142.678579463833;8383.455075420201;8646.092160323202;8677.915260592426;8793.38446308765;8540.615672294762;8563.100874408223;8617.61220745068;8751.474911505331;8753.781155471037;4460.77696115193;4552.97723511387;4553.271476563734;4629.86110416688;4635;1607.649574357522;1625.9430919917745;1680.5126513311247;1912.6748452763063;1976.4762337980446;835.5154010673795;874.4840544079024;904.2991681446931;916.6929078758557;944.6788092048737;20609.381718548466;20867;21151.682724759143;21301.46746331354;21323;889.4974339498162;916.3539704508306;961.1541843177959;986.9062439068277;996.7308826616772;8913.73258802224;10719.303245289031;10938.663707569267;11302.265352752045;11334.206605537533;512442;515292;518364;613499;617782;138356.30821845826;140437.63654270073;143832;145703;147790;985.2955137076881;990.7262831801033;1041.1722680469045;1043.0560342889696;1043.3322673488974;18199.272029118685;18782.6243475127;19405.126769295133;19495.8050419494;19555.21779128819;54597.181042283606;56136;56866.43133568612;57208.42791572031;58460.3692833815;56083.87832243251;82953;84073;84228;85738.57130714307;8036.919630803617;8083.797905052421;8094.676212951652;8211.302039326723;8356.415050946376;12381.071419643382;12997.15518491295;13041.152325098838;13042.804357934448;13404.530841420503;8076.71731489395;10878.782424351626;11216.158788091088;11371.31772093677;11374.146938979775;7689.3464055555905;7813.335866451415;8155.510669359857;8308.169183081643;8324.375671824759;8023.277904988579;8158.510489370657;8308.459950103466;8587.355948303777;8691.043985161501;11919.735250302414;12531.436085376363;12844.908182804193;12872.292023938773;12962.93518532439;11350.772984540097;11387.658370248575;11551.364674942959;11730.12024098179;12157.270563766202 06:13:18 INFO - PROCESS | 1653 | |3;mootools.html;1387.9867696581503;1555.0669598241054;1579.0525684589245;1764.3031002753912;1887.245101959216;7812.882806757896;8585.399022068457;8888.111188881125;8920.8661870072;9010.23413009895;8782.429142105759;8871.1572400622;8871.733847984555;8874.955625221864;8879;2365.95268094638;2400.3999000249937;2401.2796161151673;2401.651760494727;2403.387136280246;2342.4143964009;2378.2508509819436;2384.916527921523;2390.259019703897;2391.0077317913046;2351.988240058797;2364.7635236476385;2380.73811880693;2387.95224095518;2393.0308225168833;2315.2822624986306;2327.4065113396146;2335.2877372401413;2343.8828058597;2366.6213405854983;2328.627419612854;2348.7651234876375;2356.740758516562;2358.2925122463193;2363.2393866569346;8762.780930476725;8868.778280542974;8882.600282987281;8922.286217102615;9014.684486042957;5125.718085505296;5309.840704778863;5310.309659744247;5318.97340513295;5320.5211530962015;3637.999550123711;3640.8179591020344;3705.6109108543756;3708.295423869456;3714.405695088773;1094.6770702642739;1100.273819279272;1105.745678493951;1118.1271370454456;1129.7345123895877;11765.294082355085;11833.88166118345;11928.70178245542;11932.343721095336;11938.28370297785;4375.409319741794;4499.10017996402;4630.53694630541;4669.039003618488;4672.252439609714;9311.627534898664;9330.440173589606;9341.719748407559;9343.159115679622;9348.392354496937;5249.921251181236;5261.842144735664;5262.55268302191;5265.657732247469;5266.947330526722;4250.3199488081755;4403.647708183337;4547.47704014036;4905.852824415273;4932.062908047459;3711.16498787776;3763.7741735495956;3859.3053250415196;3866.845326186921;3875.302445559826;1067.7720621285584;1166.1312322319757;1183.5679976808403;1196.2642974570751;1199.3643369014437;854.6453221912836;871.808202195516;874.2612492443875;928.8513837785922;957.1337939165066;5846.824595262149;5988.790392336247;6109.969450152809;6149.569530132848;6196.0705894116245;945.3571571331561;959.2565761534727;1011.9089281964658;1015.081351377005;1018;3900.6684431823614;3953.150072734376;4025.5571887092397;4100.712950093524;4125.66994640428;10764.923507649173;10999.835002474969;11001.724956876142;11037.061849742862;11093.667189984313;11041.447927603587;11042.834357484644;11064.668059958214;11079.72300692489;11081.337799330166;7900.565468899265;8255;8259.380546458919;8359.749207523784;8369.665213391518;5970.134330522137;6007.189029481139;6023.728932197974;6152.354002829818;6344.333844946214;10058.485378655336;10144.54349554257;10393.532291047073;10402.947985260025;10413.687589372332;10151.695449136538;10285.588576456857;10335.17318614494;10474.266801324138;10541.736456588647;7056.788296351117;7149.964250178716;7617;7746.883796743166;7769.184235655175;8322.54226017575;8556.786080348042;8577;8580.699675511327;8582.785430364042;7554.735584254525;7613.961930190535;7636.427267954815;7755.069391673035;7813.531188128731;7360.227176146642;7442.106947166375;7501.699932002877;7527.661255243636;7659.923400765921;6915.308469153045;7065.364117229474;7073.292670732885;7085.433165346657;7086.822829429306;4944.258361245842;5086.1353569893945;5118.3857937046305;5165.535101840853;5186.611004174627;5050.49495050492;5075.720835354091;5211.1922651989;5236.371635403623;5313.760880760297 06:13:18 INFO - PROCESS | 1653 | |4;prototype.html;295.2309234444273;302.1268533936922;309.5449688957233;313.4248653720423;314.0656546773351;5183.559397451221;5323.7338133093335;5356.812511562096;5424.864378390537;5425.538829199523;5139.229115632647;5296.576273898088;5315.7873685052555;5318.6332082223325;5341.706206158659;4074.633283004529;4078.5513593504693;4080.592195692489;4097.995990982214;4101.815418306183;2934.501134807088;3365.6634336566394;3966.3653815389544;3970.225805967823;4094.324436467994;3356.9328613427715;3814.5994670559608;4016.8192431340512;4033.9596604033877;4063.0248740302263;3968.103284602242;4009.4587230723914;4013.5585085640555;4034.6570541503716;4043.2722110020186;3469.09803451102;3483.5471388719557;3490.301939612062;3493.493443450711;3509.1752288809676;3719.8840347895793;5198.2722418861385;5206.386020333671;5216.695826043489;5255.237990491396;4613.331066995301;4617.376654151713;4619.099275641252;4630.9073818523775;4632.420947381577;4690.671652984292;5602.38373778888;5635.746391412396;5636.379998200195;5706.885862282773;2982.821030738163;3815.523059617548;3833.8083095845377;3860.1314704191614;3872.244912242114;5221.431247658261;5822.650640961556;5829.5919285650025;5843.3864444233595;5850.3564607893095;3568.357695614762;3629.985358126265;3897.2595206910596;3901.648851603369;3943.8028098594955;3564.697000754913;3693.778373297611;3967.9801600992387;3989.3816458448987;3993.780342081155;3877.96122038776;4623.838165664186;4625.213713668684;4626.7223966562115;4645.419322584677;5667.696429821164;5708.916245570337;5713.371848949447;5739.598228124075;5763;1221.1390969366576;1266.385358667706;1301.4273719563362;1313.5288476905735;1316.2822516615718;644.8097811145723;795.9681612735426;808.9887640449438;820.3478234803346;826.3265438667466;4108.890599538108;4399.406080179135;4401.22978478765;4406.361077643788;4447.822087116544;4238.788060596958;4323.870283891489;4402.581754733295;4512.842050528216;4848.781804818792;3776.5656949450618;3821;4162.2299874522705;4368.541303163186;4372.825086996548;2025.2911480981538;2206.856554323964;2359.9380278874573;2376.7860892519757;2495.126705653007;4693.330136514191;4891.679246603398;4970.502949705001;4979.402471703419;5039.97480012605;4460.821567137343;4566.360709500673;4709.3877795887;4929.359183306219;4976.751162441863;3995.780232087205;4924.261360795909;4969.719984810008;4988.401391832931;5021.790412218612;4092.5293591237387;4281.636060934856;4781.952180478151;4799.488161229202;4800.5199480051715;4542.139306347332;4671.719696818202;4792.693041801146;4801.575204521694;4845.563899249085;4318.503372112247;4856.973032911062;4923.375286155631;4957.546014722735;4983.20940273448;3343.699067083974;3356.3622911646708;3367.696907278357;3371.353487512291;3386.1873150444208;4003.4195041718203;4680.251159814414;4751.548602882721;4767.594754445911;4835.492273311393;3976.661983731415;4161.97919010403;4197.496300443966;4479.619232365286;4642.53574642533;4172.624463798273;4498.640108791224;4615.630749540096;4678.719276843401;4702.76486175683;4316.856033151154;4990.6007519397635;5160.432352441153;5262.342207224097;5338.733063346739;5046.905534203264;5158.4583618721;5590;5684.63049901755;5691.914621280601;4616.537596802398;4618.607418369476;4867.637061622751;4950.979902259691;5504.394516603091 06:13:18 INFO - PROCESS | 1653 | |5;yui.html;562.7439515020666;735.7498450526823;742.6658003898244;777.8833175023746;813.922677345652;15128.394864205433;16453.25960331785;16505.752413713773;16517;16526.669466610674;14814.407423703038;15007.624809379771;15019.249037548134;15034.548963531111;15063.17152556609;682.5631595778706;828.2752591482453;828.983420331593;851.1190917400492;860.423516244118;859.5444414460346;860.2000139869918;860.2945584620613;860.5568132411813;861.0356400831078;892.0187793427243;892.848215803315;892.9598168082451;893.2139717048988;894.6019021535419;715.5170260074455;790.8769547242929;853.6756032707532;902.6434558349415;907.2651152566376;680.9659517024179;681.2982627893277;682.4813142012057;682.9658517074126;684.9554778939353;15108.320125594375;15126.546203613909;15151.242437878172;15155.242237888062;15157.848421515753;12114.030877529776;14258.714825703428;14311.835682961357;14398.928005359907;14525.854741452556;12348.320842353667;12500;12587.37063146839;12624.116311858174;13072.477100915952;4505.211587972124;4576.336431217488;4635.119327327797;4767.094252092092;4804.615630749567;95132;110934;116103.83896161015;117244;119600.20599691098;11348.489317980711;11368;11372.48823802931;11736.823947640793;11788.705282367839;21422.250221242182;23045.193418230618;23079.769202308096;23094.191703290304;23096.538069238857;7961.761147165595;7982.880256796153;8004.359651228005;8187;8205.753827385188;4246.8725938221905;4291.9785401072795;4310.4611923509565;4405.7797110144365;4440.3561483585345;3278.852451639634;3383.763136580416;3384.4415671414436;3385.6614338565946;3386.3396637655915;632.4308122689524;636.6625688385163;636.7198432689694;656.7832615236961;660.4320284555279;577.8150991682705;604.6764980735373;611.1871211288975;621.4810633121394;637.4963778614936;8850.64597416109;9093.54532273397;9192.034836342222;9201;9320.627174913063;586.355009489558;601.575888998264;611.0864257934328;627.0468887291291;633.9873202535923;4278.315469524923;4330.167801495699;4481.5294394088805;4483.955160448419;4541.931871021938;155399;156833.2158339201;161104.19447902686;161553;164387;134114;136139.99999999802;137514;139523.90714139296;142463.5753642471;116758.41620792012;118424;121428;122601;122683.38658306652;8684.652613895627;8688.478691278544;8736.912630873865;8809.295256379346;8905.421147625384;49704.75147624239;65728.01407978979;66174.45857939622;68088.31911681018;68438.65780671065;95233.95320935702;100430;101882;104641.95358046322;105447.47276263569;11288.717782055513;11721.062315014608;11784.70538236551;11903.46434410471;12078.456469458719;12455.688607784878;12532.62931610926;12879.871201288242;12949.935250323688;13076.673083172996;11647.417629118341;11715.707107322385;12145.878541214475;12190.963768079813;12395.318257495925;11798.882011179778;12078.637640870787;12082.577109801116;12368.567100151451;12413.75172496563;11440.885591143982;11631.88368116308;11717.648470545897;11723.296602203895;12142.392880356123;9667.564959576695;10049.849252261076;10396.752389713283;10407.479626018518;10526.263161578616;8902.59938302791;9593.42439453635;9889.85165222508;9911.752206194902;9912.851307230541 06:13:18 INFO - PROCESS | 1653 | __end_tp_report 06:13:18 INFO - PROCESS | 1653 | __start_cc_report 06:13:18 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,1273 06:13:18 INFO - PROCESS | 1653 | __end_cc_report 06:13:18 INFO - PROCESS | 1653 | __startTimestamp1471353198209__endTimestamp 06:13:18 INFO - PROCESS | 1653 | 06:13:18 INFO - PROCESS | 1653 | ------- Summary: start ------- 06:13:18 INFO - PROCESS | 1653 | Number of tests: 6 06:13:18 INFO - PROCESS | 1653 | 06:13:18 INFO - PROCESS | 1653 | [#0] dojo.html Cycles:165 Average:5637.12 Median:5800.77 stddev:658.38 (11.3%) stddev-sans-first:634.15 06:13:18 INFO - PROCESS | 1653 | Values: 3291.0 3303.8 3313.8 3354.9 3356.7 5071.4 5135.9 5556.6 5601.7 5661.7 5538.3 5598.8 5660.5 5664.9 5666.2 4146.5 5052.7 5147.4 5177.4 5306.1 5212.7 5216.4 5262.1 5265.6 5275.3 5078.5 5103.3 5109.1 5145.6 5175.9 5057.8 5085.2 5113.7 5125.7 5131.9 5050.5 5076.0 5131.5 5143.3 5168.7 5444.3 5468.2 5543.9 5606.9 5692.0 6144.0 6187.1 6214.7 6222.7 6235.8 6203.3 6261.4 6277.7 6278.3 6278.8 5560.4 5678.2 5755.3 5800.8 5817.3 6308.8 6314.0 6315.9 6319.2 6325.7 6115.7 6122.1 6130.5 6133.8 6134.0 5927.3 6091.6 6110.4 6213.3 6228.0 6118.3 6122.0 6129.4 6143.0 6168.6 6189.2 6193.9 6204.5 6209.4 6210.4 4832.0 4884.5 4964.7 4965.2 4998.8 4394.6 4402.2 4461.7 4464.1 4469.7 5522.2 5532.2 5534.4 5544.6 5558.4 6111.5 6138.9 6167.7 6196.7 6198.0 5604.9 5608.6 5608.8 5610.4 5631.8 5353.5 5375.3 5384.3 5387.6 5425.3 6184.0 6257.1 6261.7 6296.3 6308.9 6172.8 6185.2 6189.5 6193.8 6200.0 5056.5 6289.4 6317.9 6323.9 6330.5 4390.3 5290.2 6073.4 6119.2 6134.9 5658.9 6196.7 6216.3 6219.2 6226.3 4763.4 5986.6 6018.0 6023.2 6039.9 4822.7 5775.8 5950.4 5960.1 5970.3 5884.6 5896.8 5899.6 5913.0 5932.0 5977.8 6044.0 6044.7 6047.6 6074.8 5691.2 5775.8 5812.0 5833.4 5877.2 06:13:18 INFO - PROCESS | 1653 | 06:13:18 INFO - PROCESS | 1653 | [#1] ext.html Cycles:180 Average:68949.20 Median:369903.50 stddev:150231.30 (40.6%) stddev-sans-first:150564.77 06:13:18 INFO - PROCESS | 1653 | Values: 475.7 478.9 511.7 540.3 556.2 25442.0 40377.8 41937.6 42087.4 43997.8 43004.4 43231.6 43298.0 43351.8 43458.6 8806.7 9719.8 9944.2 9949.4 9952.5 8845.4 11942.6 12186.9 12195.1 12472.8 12115.5 12186.6 12193.6 12200.1 12242.5 12092.1 12177.5 12187.0 12202.8 12216.4 12022.6 12078.7 12094.6 12115.3 12134.0 40654.4 40970.8 41013.8 41027.0 41102.4 3293.0 3313.5 3400.0 3479.8 3802.8 31717.5 32351.8 32715.8 32804.9 32806.5 2939.2 2991.8 3022.3 3243.8 3368.7 369511.0 369838.0 369969.0 370818.0 371799.3 61231.0 61581.0 61632.7 61695.9 61752.7 60486.0 60500.1 60554.7 60625.1 60648.8 16375.8 16577.0 16626.8 16651.3 16676.3 11615.7 11870.2 12607.1 12870.9 12929.5 17625.3 18022.4 18025.2 18045.7 18059.6 5344.7 6651.6 6691.9 6706.3 6721.2 898.8 931.5 989.1 1021.5 1025.4 23573.0 26443.6 29376.0 30450.8 31059.8 992.4 999.0 1057.9 1141.2 1197.9 14015.2 15127.5 15468.1 15760.7 15865.8 668918.0 672759.0 673337.0 674494.0 704979.0 57238.7 61227.4 61416.5 61437.4 61476.4 1330.8 1334.8 1374.5 1378.8 1380.5 27733.4 30430.2 30995.4 31817.5 31820.0 52306.5 52709.2 52773.9 52905.7 52926.0 585618.0 589582.0 589892.0 589892.0 592483.0 26459.7 26802.3 26806.9 26815.7 26820.0 48476.8 48662.0 48802.8 48981.8 49665.0 39654.4 39832.8 39869.2 41088.0 41420.4 39223.4 39736.8 40154.8 40966.4 41396.0 38809.6 39511.8 39525.4 39583.4 39807.0 34018.4 34695.8 35537.0 35612.6 35750.4 25901.4 26021.2 26045.1 26265.5 26391.5 06:13:18 INFO - PROCESS | 1653 | 06:13:18 INFO - PROCESS | 1653 | [#2] jquery.html Cycles:180 Average:34266.64 Median:357.68 stddev:94244.46 (26348.8%) stddev-sans-first:94474.43 06:13:18 INFO - PROCESS | 1653 | Values: 348.2 367.2 373.4 379.8 394.9 15971.3 16654.3 20427.5 21063.3 21201.2 14582.5 20244.9 20318.2 20730.3 20782.9 4792.3 5396.9 5567.0 5572.7 5577.1 5339.8 5417.2 5420.5 5427.2 5430.2 5378.7 5418.2 5427.1 5436.8 5866.7 6113.1 6238.9 6245.7 6248.3 6248.8 5123.8 5840.9 6028.3 6066.5 6106.5 13565.7 13768.0 13789.3 20501.9 25950.9 2064.7 2079.5 2122.8 2432.8 2452.4 9934.6 10043.4 10108.5 10414.8 10422.9 1909.0 1912.5 1923.9 1962.2 1962.3 120006.4 124033.1 125484.1 126926.4 129017.4 11114.6 11611.9 11616.1 11634.0 11784.8 14634.9 14638.8 14683.6 14692.9 14713.2 7142.7 8383.5 8646.1 8677.9 8793.4 8540.6 8563.1 8617.6 8751.5 8753.8 4460.8 4553.0 4553.3 4629.9 4635.0 1607.6 1625.9 1680.5 1912.7 1976.5 835.5 874.5 904.3 916.7 944.7 20609.4 20867.0 21151.7 21301.5 21323.0 889.5 916.4 961.2 986.9 996.7 8913.7 10719.3 10938.7 11302.3 11334.2 512442.0 515292.0 518364.0 613499.0 617782.0 138356.3 140437.6 143832.0 145703.0 147790.0 985.3 990.7 1041.2 1043.1 1043.3 18199.3 18782.6 19405.1 19495.8 19555.2 54597.2 56136.0 56866.4 57208.4 58460.4 56083.9 82953.0 84073.0 84228.0 85738.6 8036.9 8083.8 8094.7 8211.3 8356.4 12381.1 12997.2 13041.2 13042.8 13404.5 8076.7 10878.8 11216.2 11371.3 11374.1 7689.3 7813.3 8155.5 8308.2 8324.4 8023.3 8158.5 8308.5 8587.4 8691.0 11919.7 12531.4 12844.9 12872.3 12962.9 11350.8 11387.7 11551.4 11730.1 12157.3 06:13:18 INFO - PROCESS | 1653 | 06:13:18 INFO - PROCESS | 1653 | [#3] mootools.html Cycles:180 Average:5778.47 Median:4789.05 stddev:3255.98 (68.0%) stddev-sans-first:3248.39 06:13:18 INFO - PROCESS | 1653 | Values: 1388.0 1555.1 1579.1 1764.3 1887.2 7812.9 8585.4 8888.1 8920.9 9010.2 8782.4 8871.2 8871.7 8875.0 8879.0 2366.0 2400.4 2401.3 2401.7 2403.4 2342.4 2378.3 2384.9 2390.3 2391.0 2352.0 2364.8 2380.7 2388.0 2393.0 2315.3 2327.4 2335.3 2343.9 2366.6 2328.6 2348.8 2356.7 2358.3 2363.2 8762.8 8868.8 8882.6 8922.3 9014.7 5125.7 5309.8 5310.3 5319.0 5320.5 3638.0 3640.8 3705.6 3708.3 3714.4 1094.7 1100.3 1105.7 1118.1 1129.7 11765.3 11833.9 11928.7 11932.3 11938.3 4375.4 4499.1 4630.5 4669.0 4672.3 9311.6 9330.4 9341.7 9343.2 9348.4 5249.9 5261.8 5262.6 5265.7 5266.9 4250.3 4403.6 4547.5 4905.9 4932.1 3711.2 3763.8 3859.3 3866.8 3875.3 1067.8 1166.1 1183.6 1196.3 1199.4 854.6 871.8 874.3 928.9 957.1 5846.8 5988.8 6110.0 6149.6 6196.1 945.4 959.3 1011.9 1015.1 1018.0 3900.7 3953.2 4025.6 4100.7 4125.7 10764.9 10999.8 11001.7 11037.1 11093.7 11041.4 11042.8 11064.7 11079.7 11081.3 7900.6 8255.0 8259.4 8359.7 8369.7 5970.1 6007.2 6023.7 6152.4 6344.3 10058.5 10144.5 10393.5 10402.9 10413.7 10151.7 10285.6 10335.2 10474.3 10541.7 7056.8 7150.0 7617.0 7746.9 7769.2 8322.5 8556.8 8577.0 8580.7 8582.8 7554.7 7614.0 7636.4 7755.1 7813.5 7360.2 7442.1 7501.7 7527.7 7659.9 6915.3 7065.4 7073.3 7085.4 7086.8 4944.3 5086.1 5118.4 5165.5 5186.6 5050.5 5075.7 5211.2 5236.4 5313.8 06:13:18 INFO - PROCESS | 1653 | 06:13:18 INFO - PROCESS | 1653 | [#4] prototype.html Cycles:180 Average:4172.27 Median:4589.85 stddev:1270.23 (27.7%) stddev-sans-first:1240.01 06:13:18 INFO - PROCESS | 1653 | Values: 295.2 302.1 309.5 313.4 314.1 5183.6 5323.7 5356.8 5424.9 5425.5 5139.2 5296.6 5315.8 5318.6 5341.7 4074.6 4078.6 4080.6 4098.0 4101.8 2934.5 3365.7 3966.4 3970.2 4094.3 3356.9 3814.6 4016.8 4034.0 4063.0 3968.1 4009.5 4013.6 4034.7 4043.3 3469.1 3483.5 3490.3 3493.5 3509.2 3719.9 5198.3 5206.4 5216.7 5255.2 4613.3 4617.4 4619.1 4630.9 4632.4 4690.7 5602.4 5635.7 5636.4 5706.9 2982.8 3815.5 3833.8 3860.1 3872.2 5221.4 5822.7 5829.6 5843.4 5850.4 3568.4 3630.0 3897.3 3901.6 3943.8 3564.7 3693.8 3968.0 3989.4 3993.8 3878.0 4623.8 4625.2 4626.7 4645.4 5667.7 5708.9 5713.4 5739.6 5763.0 1221.1 1266.4 1301.4 1313.5 1316.3 644.8 796.0 809.0 820.3 826.3 4108.9 4399.4 4401.2 4406.4 4447.8 4238.8 4323.9 4402.6 4512.8 4848.8 3776.6 3821.0 4162.2 4368.5 4372.8 2025.3 2206.9 2359.9 2376.8 2495.1 4693.3 4891.7 4970.5 4979.4 5040.0 4460.8 4566.4 4709.4 4929.4 4976.8 3995.8 4924.3 4969.7 4988.4 5021.8 4092.5 4281.6 4782.0 4799.5 4800.5 4542.1 4671.7 4792.7 4801.6 4845.6 4318.5 4857.0 4923.4 4957.5 4983.2 3343.7 3356.4 3367.7 3371.4 3386.2 4003.4 4680.3 4751.5 4767.6 4835.5 3976.7 4162.0 4197.5 4479.6 4642.5 4172.6 4498.6 4615.6 4678.7 4702.8 4316.9 4990.6 5160.4 5262.3 5338.7 5046.9 5158.5 5590.0 5684.6 5691.9 4616.5 4618.6 4867.6 4951.0 5504.4 06:13:18 INFO - PROCESS | 1653 | 06:13:18 INFO - PROCESS | 1653 | [#5] yui.html Cycles:180 Average:25889.50 Median:3386.00 stddev:42584.03 (1257.6%) stddev-sans-first:42661.03 06:13:18 INFO - PROCESS | 1653 | Values: 562.7 735.7 742.7 777.9 813.9 15128.4 16453.3 16505.8 16517.0 16526.7 14814.4 15007.6 15019.2 15034.5 15063.2 682.6 828.3 829.0 851.1 860.4 859.5 860.2 860.3 860.6 861.0 892.0 892.8 893.0 893.2 894.6 715.5 790.9 853.7 902.6 907.3 681.0 681.3 682.5 683.0 685.0 15108.3 15126.5 15151.2 15155.2 15157.8 12114.0 14258.7 14311.8 14398.9 14525.9 12348.3 12500.0 12587.4 12624.1 13072.5 4505.2 4576.3 4635.1 4767.1 4804.6 95132.0 110934.0 116103.8 117244.0 119600.2 11348.5 11368.0 11372.5 11736.8 11788.7 21422.3 23045.2 23079.8 23094.2 23096.5 7961.8 7982.9 8004.4 8187.0 8205.8 4246.9 4292.0 4310.5 4405.8 4440.4 3278.9 3383.8 3384.4 3385.7 3386.3 632.4 636.7 636.7 656.8 660.4 577.8 604.7 611.2 621.5 637.5 8850.6 9093.5 9192.0 9201.0 9320.6 586.4 601.6 611.1 627.0 634.0 4278.3 4330.2 4481.5 4484.0 4541.9 155399.0 156833.2 161104.2 161553.0 164387.0 134114.0 136140.0 137514.0 139523.9 142463.6 116758.4 118424.0 121428.0 122601.0 122683.4 8684.7 8688.5 8736.9 8809.3 8905.4 49704.8 65728.0 66174.5 68088.3 68438.7 95234.0 100430.0 101882.0 104642.0 105447.5 11288.7 11721.1 11784.7 11903.5 12078.5 12455.7 12532.6 12879.9 12949.9 13076.7 11647.4 11715.7 12145.9 12191.0 12395.3 11798.9 12078.6 12082.6 12368.6 12413.8 11440.9 11631.9 11717.6 11723.3 12142.4 9667.6 10049.8 10396.8 10407.5 10526.3 8902.6 9593.4 9889.9 9911.8 9912.9 06:13:18 INFO - PROCESS | 1653 | -------- Summary: end -------- 06:13:18 INFO - PROCESS | 1653 | 06:13:18 INFO - TEST-INFO | 1653: exit 0 06:13:18 INFO - TEST-OK | dromaeo_css | took 1117110ms 06:13:18 INFO - TEST-START | kraken 06:13:18 INFO - operating with platform_type : mac_ 06:13:18 INFO - Initialising browser for kraken test... 06:13:18 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/tmpjEjG7f/profile http://localhost:49267/getInfo.html) 06:13:21 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 06:13:21 INFO - PROCESS | 1659 | colorDepth:24 06:13:21 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 06:13:21 INFO - PROCESS | 1659 | __metrics 06:13:21 INFO - PROCESS | 1659 | 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" 06:13:21 INFO - PROCESS | 1659 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:13:21 INFO - TEST-INFO | 1659: exit 0 06:13:21 INFO - Browser initialized. 06:13:21 INFO - Running cycle 1/1 for kraken test... 06:13:22 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/tmpjEjG7f/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:13:33 INFO - PROCESS | 1661 | RSS: Main: 228339712 06:13:33 INFO - PROCESS | 1661 | 06:13:36 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) 06:13:37 INFO - PROCESS | 1661 | RSS: Main: 285069312 06:13:37 INFO - PROCESS | 1661 | 06:13:39 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) 06:13:40 INFO - PROCESS | 1661 | RSS: Main: 293142528 06:13:40 INFO - PROCESS | 1661 | 06:13:44 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) 06:13:44 INFO - PROCESS | 1661 | RSS: Main: 386940928 06:13:44 INFO - PROCESS | 1661 | 06:13:46 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) 06:13:47 INFO - PROCESS | 1661 | RSS: Main: 288452608 06:13:47 INFO - PROCESS | 1661 | 06:13:49 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) 06:13:50 INFO - PROCESS | 1661 | RSS: Main: 292552704 06:13:50 INFO - PROCESS | 1661 | 06:13:52 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) 06:13:53 INFO - PROCESS | 1661 | RSS: Main: 330080256 06:13:53 INFO - PROCESS | 1661 | 06:13:56 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) 06:13:56 INFO - PROCESS | 1661 | RSS: Main: 333729792 06:13:56 INFO - PROCESS | 1661 | 06:13:59 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) 06:14:00 INFO - PROCESS | 1661 | RSS: Main: 332070912 06:14:00 INFO - PROCESS | 1661 | 06:14:02 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) 06:14:02 INFO - PROCESS | 1661 | RSS: Main: 301789184 06:14:02 INFO - PROCESS | 1661 | 06:14:04 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) 06:14:05 INFO - PROCESS | 1661 | RSS: Main: 308817920 06:14:05 INFO - PROCESS | 1661 | 06:14:07 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) 06:14:08 INFO - PROCESS | 1661 | RSS: Main: 308576256 06:14:08 INFO - PROCESS | 1661 | 06:14:11 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) 06:14:11 INFO - PROCESS | 1661 | RSS: Main: 313565184 06:14:11 INFO - PROCESS | 1661 | 06:14:14 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) 06:14:15 INFO - PROCESS | 1661 | RSS: Main: 316162048 06:14:15 INFO - PROCESS | 1661 | 06:14:17 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) 06:14:17 INFO - PROCESS | 1661 | RSS: Main: 360140800 06:14:17 INFO - PROCESS | 1661 | 06:14:17 INFO - PROCESS | 1661 | __start_tp_report 06:14:17 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 06:14:17 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 06:14:17 INFO - PROCESS | 1661 | |i|pagename|runs| 06:14:17 INFO - PROCESS | 1661 | |0;ai-astar;103;115;114;112;107;114;112;113;114;113 06:14:17 INFO - PROCESS | 1661 | |1;audio-beat-detection;141;114;106;162;108;106;125;110;131;119 06:14:17 INFO - PROCESS | 1661 | |2;audio-dft;184;189;184;182;182;184;180;181;184;183 06:14:17 INFO - PROCESS | 1661 | |3;audio-fft;75;57;62;86;58;56;63;68;83;57 06:14:17 INFO - PROCESS | 1661 | |4;audio-oscillator;86;69;104;70;79;69;94;71;67;98 06:14:17 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;89;85;88;86;88;85;88;86;88;85 06:14:17 INFO - PROCESS | 1661 | |6;imaging-darkroom;93;94;94;110;149;114;94;94;103;93 06:14:17 INFO - PROCESS | 1661 | |7;imaging-desaturate;85;86;84;83;84;86;103;124;133;142 06:14:17 INFO - PROCESS | 1661 | |8;json-parse-financial;52;51;55;53;52;54;54;53;53;53 06:14:17 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;68;65;73;68;76;71;69;69;69;71 06:14:17 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;85;87;91;85;86;86;89;85;85;89 06:14:17 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;144;151;148;148;119;146;145;153;215;135 06:14:17 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;174;173;175;175;191;245;193;196;233;202 06:14:17 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;70;69;69;68;68;67;69;69;68;67 06:14:17 INFO - PROCESS | 1661 | __end_tp_report 06:14:17 INFO - PROCESS | 1661 | __start_cc_report 06:14:17 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,2057 06:14:17 INFO - PROCESS | 1661 | __end_cc_report 06:14:17 INFO - PROCESS | 1661 | __startTimestamp1471353257618__endTimestamp 06:14:17 INFO - PROCESS | 1661 | 06:14:17 INFO - PROCESS | 1661 | ------- Summary: start ------- 06:14:17 INFO - PROCESS | 1661 | Number of tests: 14 06:14:17 INFO - PROCESS | 1661 | 06:14:17 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:111.70 Median:113.50 stddev:3.77 (3.3%) stddev-sans-first:2.35 06:14:17 INFO - PROCESS | 1661 | Values: 103.0 115.0 114.0 112.0 107.0 114.0 112.0 113.0 114.0 113.0 06:14:17 INFO - PROCESS | 1661 | 06:14:17 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:122.20 Median:122.00 stddev:18.18 (14.9%) stddev-sans-first:17.97 06:14:17 INFO - PROCESS | 1661 | Values: 141.0 114.0 106.0 162.0 108.0 106.0 125.0 110.0 131.0 119.0 06:14:17 INFO - PROCESS | 1661 | 06:14:17 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:183.30 Median:184.00 stddev:2.45 (1.3%) stddev-sans-first:2.59 06:14:17 INFO - PROCESS | 1661 | Values: 184.0 189.0 184.0 182.0 182.0 184.0 180.0 181.0 184.0 183.0 06:14:17 INFO - PROCESS | 1661 | 06:14:17 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:66.50 Median:65.50 stddev:11.17 (17.1%) stddev-sans-first:11.41 06:14:17 INFO - PROCESS | 1661 | Values: 75.0 57.0 62.0 86.0 58.0 56.0 63.0 68.0 83.0 57.0 06:14:17 INFO - PROCESS | 1661 | 06:14:17 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:80.70 Median:75.00 stddev:13.82 (18.4%) stddev-sans-first:14.53 06:14:17 INFO - PROCESS | 1661 | Values: 86.0 69.0 104.0 70.0 79.0 69.0 94.0 71.0 67.0 98.0 06:14:17 INFO - PROCESS | 1661 | 06:14:17 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:86.80 Median:88.00 stddev:1.55 (1.8%) stddev-sans-first:1.42 06:14:17 INFO - PROCESS | 1661 | Values: 89.0 85.0 88.0 86.0 88.0 85.0 88.0 86.0 88.0 85.0 06:14:17 INFO - PROCESS | 1661 | 06:14:17 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:103.80 Median:93.50 stddev:17.65 (18.9%) stddev-sans-first:18.28 06:14:17 INFO - PROCESS | 1661 | Values: 93.0 94.0 94.0 110.0 149.0 114.0 94.0 94.0 103.0 93.0 06:14:17 INFO - PROCESS | 1661 | 06:14:17 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:101.00 Median:84.00 stddev:23.20 (27.6%) stddev-sans-first:23.88 06:14:17 INFO - PROCESS | 1661 | Values: 85.0 86.0 84.0 83.0 84.0 86.0 103.0 124.0 133.0 142.0 06:14:17 INFO - PROCESS | 1661 | 06:14:17 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:53.00 Median:53.00 stddev:1.15 (2.2%) stddev-sans-first:1.17 06:14:17 INFO - PROCESS | 1661 | Values: 52.0 51.0 55.0 53.0 52.0 54.0 54.0 53.0 53.0 53.0 06:14:17 INFO - PROCESS | 1661 | 06:14:17 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:69.90 Median:70.00 stddev:3.03 (4.3%) stddev-sans-first:3.14 06:14:17 INFO - PROCESS | 1661 | Values: 68.0 65.0 73.0 68.0 76.0 71.0 69.0 69.0 69.0 71.0 06:14:17 INFO - PROCESS | 1661 | 06:14:17 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:86.80 Median:86.50 stddev:2.15 (2.5%) stddev-sans-first:2.18 06:14:17 INFO - PROCESS | 1661 | Values: 85.0 87.0 91.0 85.0 86.0 86.0 89.0 85.0 85.0 89.0 06:14:17 INFO - PROCESS | 1661 | 06:14:17 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:150.40 Median:148.00 stddev:24.73 (16.7%) stddev-sans-first:26.12 06:14:17 INFO - PROCESS | 1661 | Values: 144.0 151.0 148.0 148.0 119.0 146.0 145.0 153.0 215.0 135.0 06:14:17 INFO - PROCESS | 1661 | 06:14:17 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.70 Median:194.50 stddev:25.24 (13.0%) stddev-sans-first:25.52 06:14:17 INFO - PROCESS | 1661 | Values: 174.0 173.0 175.0 175.0 191.0 245.0 193.0 196.0 233.0 202.0 06:14:17 INFO - PROCESS | 1661 | 06:14:17 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:69.00 stddev:0.97 (1.4%) stddev-sans-first:0.83 06:14:17 INFO - PROCESS | 1661 | Values: 70.0 69.0 69.0 68.0 68.0 67.0 69.0 69.0 68.0 67.0 06:14:17 INFO - PROCESS | 1661 | -------- Summary: end -------- 06:14:17 INFO - PROCESS | 1661 | 06:14:18 INFO - TEST-INFO | 1661: exit 0 06:14:18 INFO - TEST-OK | kraken | took 59621ms 06:14:18 INFO - SUITE-END | took 1176s 06:14:18 INFO - Completed test suite (00:19:37) 06:14:18 INFO - javascript benchmark 06:14:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3290.967090329096, 3303.801771893685, 3313.7514686398526, 3354.949675754863, 3356.7146792522635, 5071.391433028042, 5135.870108576112, 5556.611037227396, 5601.691906945116, 5661.660300381975, 5538.252335934648, 5598.804041858536, 5660.5471562275025, 5664.915026274599, 5666.178404131409, 4146.523149837777, 5052.671576347544, 5147.433782283946, 5177.430482646924, 5306.097963346221, 5212.739363031852, 5216.374035115772, 5262.105442074857, 5265.552428043621, 5275.287836142129, 5078.492150784948, 5103.260027296058, 5109.080365534202, 5145.6398052136365, 5175.922361164586, 5057.848264552069, 5085.186370180791, 5113.718745468997, 5125.692458452505, 5131.897362052738, 5050.49495050492, 5076.0, 5131.538161565477, 5143.3313669223135, 5168.689878607295, 5444.3194600674915, 5468.207109969072, 5543.916841247385, 5606.915896261559, 5691.971540142273, 6144.0, 6187.133801267826, 6214.689265536705, 6222.719977600974, 6235.781747638811, 6203.255609326865, 6261.40516650922, 6277.7175027123885, 6278.277998030239, 6278.843028924268, 5560.360558535739, 5678.176664383724, 5755.3381361144, 5800.7679692812235, 5817.301923769175, 6308.779192728232, 6313.968430157912, 6315.936840631535, 6319.178506794179, 6325.683715814283, 6115.663638499926, 6122.081687746784, 6130.540209484307, 6133.84665383369, 6134.0, 5927.347991720907, 6091.634501929899, 6110.419510146529, 6213.316535181127, 6228.03465462854, 6118.296395914438, 6122.020476723703, 6129.389354848322, 6142.959235178526, 6168.568200225941, 6189.164462797465, 6193.876122477525, 6204.5036397089025, 6209.379062093754, 6210.4100110489435, 4831.975840120777, 4884.535969082932, 4964.652474326763, 4965.205567109245, 4998.775055122528, 4394.582514661102, 4402.185595664812, 4461.665375096881, 4464.129494748493, 4469.664775141812, 5522.171674248895, 5532.197831314437, 5534.446555344514, 5544.611877168641, 5558.388577256498, 6111.480524155497, 6138.907916381258, 6167.660778657128, 6196.721147548371, 6197.96901015492, 5604.887902241932, 5608.551315894719, 5608.775648974077, 5610.354809196913, 5631.7747290108755, 5353.491418315331, 5375.274337964403, 5384.273123128328, 5387.649802762808, 5425.348958124971, 6183.96908015466, 6257.061440783918, 6261.655608941552, 6296.307406185224, 6308.905366419507, 6172.814815555541, 6185.165002724574, 6189.473894718819, 6193.81418557444, 6200.0, 5056.469070747666, 6289.371062893857, 6317.905231421624, 6323.873522529616, 6330.461910737639, 4390.3414487827085, 5290.153575427913, 6073.392660733891, 6119.204503414528, 6134.9079763804475, 5658.868226354663, 6196.690165491617, 6216.2540495140875, 6219.222597175353, 6226.346233645402, 4763.428388593391, 5986.580939334205, 6017.969910150421, 6023.186869772525, 6039.909401359071, 4822.662413631012, 5775.768969241351, 5950.404959504015, 5960.0761881908375, 5970.3432622412365, 5884.588078834612, 5896.823095307148, 5899.646021238739, 5912.970435147797, 5932.0, 5977.790777322947, 6044.032954727222, 6044.727987240497, 6047.606905551125, 6074.848128796815, 5691.231683722644, 5775.768969241183, 5812.0, 5833.416658334133, 5877.23595932526], "value": 5596.91973735551, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [475.72408003358044, 478.8563430970708, 511.66997286750046, 540.2652392745865, 556.2407314016369, 25442.0, 40377.79811100944, 41937.580624193826, 42087.36868946968, 43997.78001109998, 43004.354934675925, 43231.56768432315, 43298.0, 43351.78324108375, 43458.565414345845, 8806.73579792604, 9719.757006074851, 9944.154746846527, 9949.403035817837, 9952.452615106165, 8845.425047371933, 11942.582009629665, 12186.903178713914, 12195.08536859739, 12472.750544989094, 12115.515379384859, 12186.634400967941, 12193.634190974284, 12200.145989780718, 12242.51029958805, 12092.093093018058, 12177.51289948401, 12187.0, 12202.816957745641, 12216.389180540937, 12022.579209727617, 12078.69803254917, 12094.576689815902, 12115.273083615011, 12133.968612667933, 40654.39018414726, 40970.79514602438, 41013.79493102516, 41027.0, 41102.38346424806, 3292.967070329314, 3313.4698448248164, 3400.0, 3479.8260086995547, 3802.8478860845808, 31717.524237136462, 32351.83824080888, 32715.836420817745, 32804.85183018607, 32806.50790238148, 2939.1770304314828, 2991.790574659775, 3022.2595464111214, 3243.7729358944885, 3368.7473439492137, 369511.0, 369838.0, 369969.0, 370818.0, 371799.28200717916, 61231.0, 61581.0, 61632.69183654053, 61695.90497092802, 61752.69123654353, 60486.0, 60500.092498612554, 60554.697226513585, 60625.09062364068, 60648.78702425927, 16375.754363684555, 16577.00537967726, 16626.83373166253, 16651.333946642, 16676.332946681996, 11615.709607259718, 11870.228435151688, 12607.117501774788, 12870.871291287154, 12929.482820687255, 17625.294988200327, 18022.36921707734, 18025.188866501037, 18045.7293140603, 18059.638807224044, 5344.706041167773, 6651.567648102858, 6691.899621505681, 6706.250437320675, 6721.193456785119, 898.7528429681759, 931.4830269200523, 989.1295659819313, 1021.4790456866903, 1025.3642741500319, 23573.0, 26443.603345949825, 29376.0, 30450.84774576113, 31059.84470077635, 992.3748038735695, 998.9920260276896, 1057.9047885690325, 1141.1840534018218, 1197.862245841722, 14015.229162395963, 15127.54617361459, 15468.149251791034, 15760.68478630421, 15865.762013569805, 668918.0, 672759.0, 673337.0, 674494.0, 704979.0, 57238.71380643154, 61227.38772612306, 61416.54333826686, 61437.38562614406, 61476.38523614796, 1330.7604631166482, 1334.7730885749445, 1374.5326588959801, 1378.7518246715488, 1380.4547203854622, 27733.44533109367, 30430.23924401819, 30995.380092398478, 31817.5227371585, 31820.0, 52306.47693523016, 52709.20936186037, 52773.944521110134, 52905.7354713224, 52926.0, 585618.0, 589582.0, 589892.0, 589892.0, 592483.0, 26459.73540264573, 26802.329941751614, 26806.865965670047, 26815.73184268132, 26820.0, 48476.788080298276, 48662.0, 48802.75598621984, 48981.75509122574, 49665.00669986652, 39654.405183921685, 39832.80083599564, 39869.20261594694, 41088.0, 41420.378694319006, 39223.41164882472, 39736.801315994395, 40154.799226003684, 40966.38550421806, 41396.0, 38809.6119038806, 39511.80244098876, 39525.40711889266, 39583.40624890687, 39807.00482487961, 34018.36641854815, 34695.82652086824, 35537.0, 35612.64387356093, 35750.39974496076, 25901.35246618774, 26021.219363419128, 26045.0884219059, 26265.474690506464, 26391.4721705561], "value": 20435.301011805856, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.1835096698245, 367.17569057465977, 373.43611147167775, 379.84046700385846, 394.9368101103824, 15971.281292341844, 16654.250558724856, 20427.48931276719, 21063.2627858025, 21201.151953921843, 14582.489612863552, 20244.89877550614, 20318.187272509083, 20730.27444039459, 20782.856942868173, 4792.329073929653, 5396.946030539693, 5566.997940370732, 5572.721363931808, 5577.051901176789, 5339.8398048058425, 5417.187421886729, 5420.512153906147, 5427.240186373911, 5430.212619170218, 5378.677279363231, 5418.239072301498, 5427.104527752916, 5436.83689489314, 5866.677332746697, 6113.052476866093, 6238.87522249557, 6245.687715614201, 6248.343923887972, 6248.812535623893, 5123.821521050142, 5840.883182336372, 6028.276606807167, 6066.545009124334, 6106.45041946227, 13565.660858478519, 13767.967402444856, 13789.310534473236, 20501.8974905126, 25950.87024564865, 2064.690296455529, 2079.4593405714472, 2122.7877212278804, 2432.793212576933, 2452.3623857796924, 9934.552945117415, 10043.447610381427, 10108.494575271281, 10414.843777343345, 10422.89577104227, 1908.9522761930923, 1912.531429799708, 1923.8749481283671, 1962.1562728026943, 1962.2935743132462, 120006.3999679996, 124033.13950290754, 125484.1177382331, 126926.36536817349, 129017.35491322578, 11114.55541778336, 11611.941940290244, 11616.070714342832, 11634.0, 11784.823227651592, 14634.926825365805, 14638.780418293734, 14683.632909177357, 14692.926535367254, 14713.190774507504, 7142.678579463833, 8383.455075420201, 8646.092160323202, 8677.915260592426, 8793.38446308765, 8540.615672294762, 8563.100874408223, 8617.61220745068, 8751.474911505331, 8753.781155471037, 4460.77696115193, 4552.97723511387, 4553.271476563734, 4629.86110416688, 4635.0, 1607.649574357522, 1625.9430919917745, 1680.5126513311247, 1912.6748452763063, 1976.4762337980446, 835.5154010673795, 874.4840544079024, 904.2991681446931, 916.6929078758557, 944.6788092048737, 20609.381718548466, 20867.0, 21151.682724759143, 21301.46746331354, 21323.0, 889.4974339498162, 916.3539704508306, 961.1541843177959, 986.9062439068277, 996.7308826616772, 8913.73258802224, 10719.303245289031, 10938.663707569267, 11302.265352752045, 11334.206605537533, 512442.0, 515292.0, 518364.0, 613499.0, 617782.0, 138356.30821845826, 140437.63654270073, 143832.0, 145703.0, 147790.0, 985.2955137076881, 990.7262831801033, 1041.1722680469045, 1043.0560342889696, 1043.3322673488974, 18199.272029118685, 18782.6243475127, 19405.126769295133, 19495.8050419494, 19555.21779128819, 54597.181042283606, 56136.0, 56866.43133568612, 57208.42791572031, 58460.3692833815, 56083.87832243251, 82953.0, 84073.0, 84228.0, 85738.57130714307, 8036.919630803617, 8083.797905052421, 8094.676212951652, 8211.302039326723, 8356.415050946376, 12381.071419643382, 12997.15518491295, 13041.152325098838, 13042.804357934448, 13404.530841420503, 8076.71731489395, 10878.782424351626, 11216.158788091088, 11371.31772093677, 11374.146938979775, 7689.3464055555905, 7813.335866451415, 8155.510669359857, 8308.169183081643, 8324.375671824759, 8023.277904988579, 8158.510489370657, 8308.459950103466, 8587.355948303777, 8691.043985161501, 11919.735250302414, 12531.436085376363, 12844.908182804193, 12872.292023938773, 12962.93518532439, 11350.772984540097, 11387.658370248575, 11551.364674942959, 11730.12024098179, 12157.270563766202], "value": 9406.49749182534, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1387.9867696581503, 1555.0669598241054, 1579.0525684589245, 1764.3031002753912, 1887.245101959216, 7812.882806757896, 8585.399022068457, 8888.111188881125, 8920.8661870072, 9010.23413009895, 8782.429142105759, 8871.1572400622, 8871.733847984555, 8874.955625221864, 8879.0, 2365.95268094638, 2400.3999000249937, 2401.2796161151673, 2401.651760494727, 2403.387136280246, 2342.4143964009, 2378.2508509819436, 2384.916527921523, 2390.259019703897, 2391.0077317913046, 2351.988240058797, 2364.7635236476385, 2380.73811880693, 2387.95224095518, 2393.0308225168833, 2315.2822624986306, 2327.4065113396146, 2335.2877372401413, 2343.8828058597, 2366.6213405854983, 2328.627419612854, 2348.7651234876375, 2356.740758516562, 2358.2925122463193, 2363.2393866569346, 8762.780930476725, 8868.778280542974, 8882.600282987281, 8922.286217102615, 9014.684486042957, 5125.718085505296, 5309.840704778863, 5310.309659744247, 5318.97340513295, 5320.5211530962015, 3637.999550123711, 3640.8179591020344, 3705.6109108543756, 3708.295423869456, 3714.405695088773, 1094.6770702642739, 1100.273819279272, 1105.745678493951, 1118.1271370454456, 1129.7345123895877, 11765.294082355085, 11833.88166118345, 11928.70178245542, 11932.343721095336, 11938.28370297785, 4375.409319741794, 4499.10017996402, 4630.53694630541, 4669.039003618488, 4672.252439609714, 9311.627534898664, 9330.440173589606, 9341.719748407559, 9343.159115679622, 9348.392354496937, 5249.921251181236, 5261.842144735664, 5262.55268302191, 5265.657732247469, 5266.947330526722, 4250.3199488081755, 4403.647708183337, 4547.47704014036, 4905.852824415273, 4932.062908047459, 3711.16498787776, 3763.7741735495956, 3859.3053250415196, 3866.845326186921, 3875.302445559826, 1067.7720621285584, 1166.1312322319757, 1183.5679976808403, 1196.2642974570751, 1199.3643369014437, 854.6453221912836, 871.808202195516, 874.2612492443875, 928.8513837785922, 957.1337939165066, 5846.824595262149, 5988.790392336247, 6109.969450152809, 6149.569530132848, 6196.0705894116245, 945.3571571331561, 959.2565761534727, 1011.9089281964658, 1015.081351377005, 1018.0, 3900.6684431823614, 3953.150072734376, 4025.5571887092397, 4100.712950093524, 4125.66994640428, 10764.923507649173, 10999.835002474969, 11001.724956876142, 11037.061849742862, 11093.667189984313, 11041.447927603587, 11042.834357484644, 11064.668059958214, 11079.72300692489, 11081.337799330166, 7900.565468899265, 8255.0, 8259.380546458919, 8359.749207523784, 8369.665213391518, 5970.134330522137, 6007.189029481139, 6023.728932197974, 6152.354002829818, 6344.333844946214, 10058.485378655336, 10144.54349554257, 10393.532291047073, 10402.947985260025, 10413.687589372332, 10151.695449136538, 10285.588576456857, 10335.17318614494, 10474.266801324138, 10541.736456588647, 7056.788296351117, 7149.964250178716, 7617.0, 7746.883796743166, 7769.184235655175, 8322.54226017575, 8556.786080348042, 8577.0, 8580.699675511327, 8582.785430364042, 7554.735584254525, 7613.961930190535, 7636.427267954815, 7755.069391673035, 7813.531188128731, 7360.227176146642, 7442.106947166375, 7501.699932002877, 7527.661255243636, 7659.923400765921, 6915.308469153045, 7065.364117229474, 7073.292670732885, 7085.433165346657, 7086.822829429306, 4944.258361245842, 5086.1353569893945, 5118.3857937046305, 5165.535101840853, 5186.611004174627, 5050.49495050492, 5075.720835354091, 5211.1922651989, 5236.371635403623, 5313.760880760297], "value": 4632.628203312699, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.2309234444273, 302.1268533936922, 309.5449688957233, 313.4248653720423, 314.0656546773351, 5183.559397451221, 5323.7338133093335, 5356.812511562096, 5424.864378390537, 5425.538829199523, 5139.229115632647, 5296.576273898088, 5315.7873685052555, 5318.6332082223325, 5341.706206158659, 4074.633283004529, 4078.5513593504693, 4080.592195692489, 4097.995990982214, 4101.815418306183, 2934.501134807088, 3365.6634336566394, 3966.3653815389544, 3970.225805967823, 4094.324436467994, 3356.9328613427715, 3814.5994670559608, 4016.8192431340512, 4033.9596604033877, 4063.0248740302263, 3968.103284602242, 4009.4587230723914, 4013.5585085640555, 4034.6570541503716, 4043.2722110020186, 3469.09803451102, 3483.5471388719557, 3490.301939612062, 3493.493443450711, 3509.1752288809676, 3719.8840347895793, 5198.2722418861385, 5206.386020333671, 5216.695826043489, 5255.237990491396, 4613.331066995301, 4617.376654151713, 4619.099275641252, 4630.9073818523775, 4632.420947381577, 4690.671652984292, 5602.38373778888, 5635.746391412396, 5636.379998200195, 5706.885862282773, 2982.821030738163, 3815.523059617548, 3833.8083095845377, 3860.1314704191614, 3872.244912242114, 5221.431247658261, 5822.650640961556, 5829.5919285650025, 5843.3864444233595, 5850.3564607893095, 3568.357695614762, 3629.985358126265, 3897.2595206910596, 3901.648851603369, 3943.8028098594955, 3564.697000754913, 3693.778373297611, 3967.9801600992387, 3989.3816458448987, 3993.780342081155, 3877.96122038776, 4623.838165664186, 4625.213713668684, 4626.7223966562115, 4645.419322584677, 5667.696429821164, 5708.916245570337, 5713.371848949447, 5739.598228124075, 5763.0, 1221.1390969366576, 1266.385358667706, 1301.4273719563362, 1313.5288476905735, 1316.2822516615718, 644.8097811145723, 795.9681612735426, 808.9887640449438, 820.3478234803346, 826.3265438667466, 4108.890599538108, 4399.406080179135, 4401.22978478765, 4406.361077643788, 4447.822087116544, 4238.788060596958, 4323.870283891489, 4402.581754733295, 4512.842050528216, 4848.781804818792, 3776.5656949450618, 3821.0, 4162.2299874522705, 4368.541303163186, 4372.825086996548, 2025.2911480981538, 2206.856554323964, 2359.9380278874573, 2376.7860892519757, 2495.126705653007, 4693.330136514191, 4891.679246603398, 4970.502949705001, 4979.402471703419, 5039.97480012605, 4460.821567137343, 4566.360709500673, 4709.3877795887, 4929.359183306219, 4976.751162441863, 3995.780232087205, 4924.261360795909, 4969.719984810008, 4988.401391832931, 5021.790412218612, 4092.5293591237387, 4281.636060934856, 4781.952180478151, 4799.488161229202, 4800.5199480051715, 4542.139306347332, 4671.719696818202, 4792.693041801146, 4801.575204521694, 4845.563899249085, 4318.503372112247, 4856.973032911062, 4923.375286155631, 4957.546014722735, 4983.20940273448, 3343.699067083974, 3356.3622911646708, 3367.696907278357, 3371.353487512291, 3386.1873150444208, 4003.4195041718203, 4680.251159814414, 4751.548602882721, 4767.594754445911, 4835.492273311393, 3976.661983731415, 4161.97919010403, 4197.496300443966, 4479.619232365286, 4642.53574642533, 4172.624463798273, 4498.640108791224, 4615.630749540096, 4678.719276843401, 4702.76486175683, 4316.856033151154, 4990.6007519397635, 5160.432352441153, 5262.342207224097, 5338.733063346739, 5046.905534203264, 5158.4583618721, 5590.0, 5684.63049901755, 5691.914621280601, 4616.537596802398, 4618.607418369476, 4867.637061622751, 4950.979902259691, 5504.394516603091], "value": 3773.5255619325712, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.7439515020666, 735.7498450526823, 742.6658003898244, 777.8833175023746, 813.922677345652, 15128.394864205433, 16453.25960331785, 16505.752413713773, 16517.0, 16526.669466610674, 14814.407423703038, 15007.624809379771, 15019.249037548134, 15034.548963531111, 15063.17152556609, 682.5631595778706, 828.2752591482453, 828.983420331593, 851.1190917400492, 860.423516244118, 859.5444414460346, 860.2000139869918, 860.2945584620613, 860.5568132411813, 861.0356400831078, 892.0187793427243, 892.848215803315, 892.9598168082451, 893.2139717048988, 894.6019021535419, 715.5170260074455, 790.8769547242929, 853.6756032707532, 902.6434558349415, 907.2651152566376, 680.9659517024179, 681.2982627893277, 682.4813142012057, 682.9658517074126, 684.9554778939353, 15108.320125594375, 15126.546203613909, 15151.242437878172, 15155.242237888062, 15157.848421515753, 12114.030877529776, 14258.714825703428, 14311.835682961357, 14398.928005359907, 14525.854741452556, 12348.320842353667, 12500.0, 12587.37063146839, 12624.116311858174, 13072.477100915952, 4505.211587972124, 4576.336431217488, 4635.119327327797, 4767.094252092092, 4804.615630749567, 95132.0, 110934.0, 116103.83896161015, 117244.0, 119600.20599691098, 11348.489317980711, 11368.0, 11372.48823802931, 11736.823947640793, 11788.705282367839, 21422.250221242182, 23045.193418230618, 23079.769202308096, 23094.191703290304, 23096.538069238857, 7961.761147165595, 7982.880256796153, 8004.359651228005, 8187.0, 8205.753827385188, 4246.8725938221905, 4291.9785401072795, 4310.4611923509565, 4405.7797110144365, 4440.3561483585345, 3278.852451639634, 3383.763136580416, 3384.4415671414436, 3385.6614338565946, 3386.3396637655915, 632.4308122689524, 636.6625688385163, 636.7198432689694, 656.7832615236961, 660.4320284555279, 577.8150991682705, 604.6764980735373, 611.1871211288975, 621.4810633121394, 637.4963778614936, 8850.64597416109, 9093.54532273397, 9192.034836342222, 9201.0, 9320.627174913063, 586.355009489558, 601.575888998264, 611.0864257934328, 627.0468887291291, 633.9873202535923, 4278.315469524923, 4330.167801495699, 4481.5294394088805, 4483.955160448419, 4541.931871021938, 155399.0, 156833.2158339201, 161104.19447902686, 161553.0, 164387.0, 134114.0, 136139.99999999802, 137514.0, 139523.90714139296, 142463.5753642471, 116758.41620792012, 118424.0, 121428.0, 122601.0, 122683.38658306652, 8684.652613895627, 8688.478691278544, 8736.912630873865, 8809.295256379346, 8905.421147625384, 49704.75147624239, 65728.01407978979, 66174.45857939622, 68088.31911681018, 68438.65780671065, 95233.95320935702, 100430.0, 101882.0, 104641.95358046322, 105447.47276263569, 11288.717782055513, 11721.062315014608, 11784.70538236551, 11903.46434410471, 12078.456469458719, 12455.688607784878, 12532.62931610926, 12879.871201288242, 12949.935250323688, 13076.673083172996, 11647.417629118341, 11715.707107322385, 12145.878541214475, 12190.963768079813, 12395.318257495925, 11798.882011179778, 12078.637640870787, 12082.577109801116, 12368.567100151451, 12413.75172496563, 11440.885591143982, 11631.88368116308, 11717.648470545897, 11723.296602203895, 12142.392880356123, 9667.564959576695, 10049.849252261076, 10396.752389713283, 10407.479626018518, 10526.263161578616, 8902.59938302791, 9593.42439453635, 9889.85165222508, 9911.752206194902, 9912.851307230541], "value": 7743.161809108868, "unit": "score"}], "name": "dromaeo_css", "value": 7253.54491268779, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 115.0, 114.0, 112.0, 107.0, 114.0, 112.0, 113.0, 114.0, 113.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 114.0, 106.0, 162.0, 108.0, 106.0, 125.0, 110.0, 131.0, 119.0], "value": 122.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 189.0, 184.0, 182.0, 182.0, 184.0, 180.0, 181.0, 184.0, 183.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 57.0, 62.0, 86.0, 58.0, 56.0, 63.0, 68.0, 83.0, 57.0], "value": 66.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 69.0, 104.0, 70.0, 79.0, 69.0, 94.0, 71.0, 67.0, 98.0], "value": 80.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 88.0, 86.0, 88.0, 85.0, 88.0, 86.0, 88.0, 85.0], "value": 86.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 110.0, 149.0, 114.0, 94.0, 94.0, 103.0, 93.0], "value": 103.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 84.0, 83.0, 84.0, 86.0, 103.0, 124.0, 133.0, 142.0], "value": 101.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 55.0, 53.0, 52.0, 54.0, 54.0, 53.0, 53.0, 53.0], "value": 53.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 73.0, 68.0, 76.0, 71.0, 69.0, 69.0, 69.0, 71.0], "value": 69.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 91.0, 85.0, 86.0, 86.0, 89.0, 85.0, 85.0, 89.0], "value": 86.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 151.0, 148.0, 148.0, 119.0, 146.0, 145.0, 153.0, 215.0, 135.0], "value": 150.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 173.0, 175.0, 175.0, 191.0, 245.0, 193.0, 196.0, 233.0, 202.0], "value": 195.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 69.0, 68.0, 68.0, 67.0, 69.0, 69.0, 68.0, 67.0], "value": 68.4, "unit": "score"}], "name": "kraken", "value": 1480.2000000000003, "alertThreshold": 2.0}]} 06:14:18 INFO - Return code: 0 06:14:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:14:18 INFO - # TBPL SUCCESS # 06:14:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:14:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:14:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:14:18 INFO - 2016-08-16 06:14:18.963 screenresolution[1665:9832] starting screenresolution argv=screenresolution get 06:14:18 INFO - 2016-08-16 06:14:18.965 screenresolution[1665:9832] Display 0: 1600x1200x32@60 06:14:18 INFO - 2016-08-16 06:14:18.971 screenresolution[1666:9835] starting screenresolution argv=screenresolution list 06:14:18 INFO - Available Modes on Display 0 06:14:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:14:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:14:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:14:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:14:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:14:19 INFO - Intel Iris: 06:14:19 INFO - Chipset Model: Intel Iris 06:14:19 INFO - Type: GPU 06:14:19 INFO - Bus: Built-In 06:14:19 INFO - VRAM (Dynamic, Max): 1536 MB 06:14:19 INFO - Vendor: Intel (0x8086) 06:14:19 INFO - Device ID: 0x0a2e 06:14:19 INFO - Revision ID: 0x0009 06:14:19 INFO - Displays: 06:14:19 INFO - Display: 06:14:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:14:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:14:19 INFO - Display Serial Number: 1600x1200 60 06:14:19 INFO - Main Display: Yes 06:14:19 INFO - Mirror: Off 06:14:19 INFO - Online: Yes 06:14:19 INFO - Rotation: Supported 06:14:19 INFO - Return code: 0 06:14:19 INFO - Running post-action listener: _resource_record_post_action 06:14:19 INFO - [mozharness: 2016-08-16 13:14:19.204847Z] Finished run-tests step (success) 06:14:19 INFO - Running post-run listener: _resource_record_post_run 06:14:19 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 49412096; Write bytes: 713534464; Read time: 542; Write time: 2123 06:14:19 INFO - TinderboxPrint: CPU usage
53.6% 06:14:19 INFO - TinderboxPrint: I/O read bytes / time
49,412,096 / 542 06:14:19 INFO - TinderboxPrint: I/O write bytes / time
713,534,464 / 2,123 06:14:19 INFO - TinderboxPrint: CPU idle
2,248.2 (46.4%) 06:14:19 INFO - TinderboxPrint: CPU system
966.7 (19.9%) 06:14:19 INFO - TinderboxPrint: CPU user
1,630.7 (33.7%) 06:14:19 INFO - TinderboxPrint: Swap in / out
447,270,912 / 0 06:14:19 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 400577024; Write bytes: 394577920; Read time: 29559; Write time: 710 06:14:19 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 47298560; Write bytes: 304919552; Read time: 518; Write time: 1344 06:14:19 INFO - Running post-run listener: _upload_blobber_files 06:14:19 INFO - Blob upload gear active. 06:14:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:14:19 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 06:14:19 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'] 06:14:19 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 06:14:20 INFO - (blobuploader) - INFO - Open directory for files ... 06:14:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:14:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:14:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:14:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:14:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:14:21 INFO - (blobuploader) - INFO - Done attempting. 06:14:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:14:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:14:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:14:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:14:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:14:22 INFO - (blobuploader) - INFO - Done attempting. 06:14:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:14:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:14:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:14:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:14:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:14:24 INFO - (blobuploader) - INFO - Done attempting. 06:14:24 INFO - (blobuploader) - INFO - Iteration through files over. 06:14:24 INFO - Return code: 0 06:14:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:14:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:14:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dfe9bed59f969171fe3cb90bdd296073e326d2437883461ace2b4a1957b01eb92315de35bab843bade038ac0903cc222908a7324c6d0dba57dd9d32af604078f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98b527d051a3db2f1cf8e544f9599998fb9fed743ed6e6cbaa5e7b1b96461a53bf9fde5648c68084a3b7532fe4334f176b638443675cee9545bf2c96039d5cd8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/91c6297d39b8a6768dafb4f845e8b8ee7d2119da52daad1e0c90821e5828251f0f3ededede28be4f7ee0f8d37d4ce99052e9347894ca9c91c849bbe8cfcf0c01"} 06:14:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:14:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:14:24 INFO - Contents: 06:14:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dfe9bed59f969171fe3cb90bdd296073e326d2437883461ace2b4a1957b01eb92315de35bab843bade038ac0903cc222908a7324c6d0dba57dd9d32af604078f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98b527d051a3db2f1cf8e544f9599998fb9fed743ed6e6cbaa5e7b1b96461a53bf9fde5648c68084a3b7532fe4334f176b638443675cee9545bf2c96039d5cd8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/91c6297d39b8a6768dafb4f845e8b8ee7d2119da52daad1e0c90821e5828251f0f3ededede28be4f7ee0f8d37d4ce99052e9347894ca9c91c849bbe8cfcf0c01"} 06:14:24 INFO - Running post-run listener: copy_logs_to_upload_dir 06:14:24 INFO - Copying logs to upload dir... 06:14:24 INFO - mkdir: /builds/slave/test/build/upload/logs 06:14:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.201434 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-16 06:14:24.590925) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 06:14:24.593983) ========= 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.O0JCfSfI1F/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.UWkEZEQfwc/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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/dfe9bed59f969171fe3cb90bdd296073e326d2437883461ace2b4a1957b01eb92315de35bab843bade038ac0903cc222908a7324c6d0dba57dd9d32af604078f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98b527d051a3db2f1cf8e544f9599998fb9fed743ed6e6cbaa5e7b1b96461a53bf9fde5648c68084a3b7532fe4334f176b638443675cee9545bf2c96039d5cd8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/91c6297d39b8a6768dafb4f845e8b8ee7d2119da52daad1e0c90821e5828251f0f3ededede28be4f7ee0f8d37d4ce99052e9347894ca9c91c849bbe8cfcf0c01"} build_url:https://queue.taskcluster.net/v1/task/HN4qu-HUSPu_PrevkeOdOA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011362 build_url: 'https://queue.taskcluster.net/v1/task/HN4qu-HUSPu_PrevkeOdOA/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/dfe9bed59f969171fe3cb90bdd296073e326d2437883461ace2b4a1957b01eb92315de35bab843bade038ac0903cc222908a7324c6d0dba57dd9d32af604078f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98b527d051a3db2f1cf8e544f9599998fb9fed743ed6e6cbaa5e7b1b96461a53bf9fde5648c68084a3b7532fe4334f176b638443675cee9545bf2c96039d5cd8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/91c6297d39b8a6768dafb4f845e8b8ee7d2119da52daad1e0c90821e5828251f0f3ededede28be4f7ee0f8d37d4ce99052e9347894ca9c91c849bbe8cfcf0c01"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 06:14:24.626409) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 06:14:24.626714) ========= 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.O0JCfSfI1F/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.UWkEZEQfwc/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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.005227 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 06:14:24.674245) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 06:14:24.674538) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 06:14:24.674847) ========= ========= Total master_lag: 0.46 =========