builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0118 starttime: 1470743620.64 results: success (0) buildid: 20160809034720 builduid: e2d8351070ad481e992fd1e945e58a10 revision: 5179814c12b173f8126279665c48dfdef099b13f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 04:53:40.644106) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 04:53:40.644781) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 04:53:40.645069) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 04:53:40.738062) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 04:53:40.738403) ========= 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.HCMLwDohjQ/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.CkI7tXI73V/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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-09 04:53:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 36.8M=0s 2016-08-09 04:53:41 (36.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.565348 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 04:53:41.326358) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 04:53:41.326670) ========= 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.HCMLwDohjQ/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.CkI7tXI73V/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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.027086 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 04:53:41.406342) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-09 04:53:41.406642) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 5179814c12b173f8126279665c48dfdef099b13f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 5179814c12b173f8126279665c48dfdef099b13f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HCMLwDohjQ/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.CkI7tXI73V/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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-09 04:53:41,512 truncating revision to first 12 chars 2016-08-09 04:53:41,512 Setting DEBUG logging. 2016-08-09 04:53:41,512 attempt 1/10 2016-08-09 04:53:41,512 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/5179814c12b1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-09 04:53:42,471 unpacking tar archive at: autoland-5179814c12b1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.210872 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-09 04:53:42.667053) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 04:53:42.667355) ========= script_repo_revision: 5179814c12b173f8126279665c48dfdef099b13f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 04:53:42.667729) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 04:53:42.668015) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 04:53:42.686440) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-09 04:53:42.686840) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HCMLwDohjQ/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.CkI7tXI73V/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 04:53:42 INFO - MultiFileLogger online at 20160809 04:53:42 in /builds/slave/test 04:53:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 04:53:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:53:42 INFO - {'append_to_log': False, 04:53:42 INFO - 'base_work_dir': '/builds/slave/test', 04:53:42 INFO - 'blob_upload_branch': 'Autoland', 04:53:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:53:42 INFO - 'branch': 'Autoland', 04:53:42 INFO - 'buildbot_json_path': 'buildprops.json', 04:53:42 INFO - 'config_files': ('talos/mac_config.py',), 04:53:42 INFO - 'default_actions': ('clobber', 04:53:42 INFO - 'read-buildbot-config', 04:53:42 INFO - 'download-and-extract', 04:53:42 INFO - 'populate-webroot', 04:53:42 INFO - 'create-virtualenv', 04:53:42 INFO - 'install', 04:53:42 INFO - 'run-tests'), 04:53:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:53:42 INFO - 'download_minidump_stackwalk': True, 04:53:42 INFO - 'download_symbols': 'ondemand', 04:53:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:53:42 INFO - 'tooltool.py': '/tools/tooltool.py', 04:53:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:53:42 INFO - '/tools/misc-python/virtualenv.py')}, 04:53:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:53:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:53:42 INFO - 'installer_path': 'installer.exe', 04:53:42 INFO - 'log_level': 'info', 04:53:42 INFO - 'log_name': 'talos', 04:53:42 INFO - 'log_to_console': True, 04:53:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:53:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:53:42 INFO - 'opt_config_files': (), 04:53:42 INFO - 'pip_index': False, 04:53:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:53:42 INFO - 'cmd': ('bash', 04:53:42 INFO - '-c', 04:53:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:53:42 INFO - 'enabled': True, 04:53:42 INFO - 'halt_on_failure': False, 04:53:42 INFO - 'name': 'check_screen_resolution'},), 04:53:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:53:42 INFO - 'cmd': ('bash', 04:53:42 INFO - '-c', 04:53:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:53:42 INFO - 'enabled': True, 04:53:42 INFO - 'halt_on_failure': False, 04:53:42 INFO - 'name': 'check_screen_resolution'},), 04:53:42 INFO - 'run_cmd_checks_enabled': True, 04:53:42 INFO - 'sps_profile': False, 04:53:42 INFO - 'sps_profile_interval': 0, 04:53:42 INFO - 'suite': 'dromaeojs', 04:53:42 INFO - 'system_bits': '32', 04:53:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:53:42 INFO - 'title': 't-yosemite-r7-0118', 04:53:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:53:42 INFO - 'use_talos_json': True, 04:53:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:53:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:53:42 INFO - 'work_dir': 'build'} 04:53:42 INFO - [mozharness: 2016-08-09 11:53:42.897934Z] Running clobber step. 04:53:42 INFO - Running pre-action listener: _resource_record_pre_action 04:53:42 INFO - Running main action method: clobber 04:53:42 INFO - rmtree: /builds/slave/test/build 04:53:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:53:44 INFO - Running post-action listener: _resource_record_post_action 04:53:44 INFO - [mozharness: 2016-08-09 11:53:44.959499Z] Finished clobber step (success) 04:53:44 INFO - [mozharness: 2016-08-09 11:53:44.959654Z] Running read-buildbot-config step. 04:53:44 INFO - Running pre-action listener: _resource_record_pre_action 04:53:44 INFO - Running main action method: read_buildbot_config 04:53:44 INFO - Using buildbot properties: 04:53:44 INFO - { 04:53:44 INFO - "project": "", 04:53:44 INFO - "product": "firefox", 04:53:44 INFO - "script_repo_revision": "production", 04:53:44 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 04:53:44 INFO - "repository": "", 04:53:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 04:53:44 INFO - "buildid": "20160809034720", 04:53:44 INFO - "pgo_build": "False", 04:53:44 INFO - "basedir": "/builds/slave/test", 04:53:44 INFO - "buildnumber": 58, 04:53:44 INFO - "slavename": "t-yosemite-r7-0118", 04:53:44 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 04:53:44 INFO - "platform": "yosemite_r7", 04:53:44 INFO - "scheduler": "tests-autoland-macosx64-talos", 04:53:44 INFO - "branch": "autoland", 04:53:44 INFO - "repo_path": "integration/autoland", 04:53:44 INFO - "revision": "5179814c12b173f8126279665c48dfdef099b13f", 04:53:44 INFO - "stage_platform": "macosx64", 04:53:44 INFO - "builduid": "e2d8351070ad481e992fd1e945e58a10", 04:53:44 INFO - "slavebuilddir": "test" 04:53:44 INFO - } 04:53:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/XwUjCl45RfCsc65tSu91sg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 04:53:44 INFO - Running post-action listener: _resource_record_post_action 04:53:44 INFO - [mozharness: 2016-08-09 11:53:44.963027Z] Finished read-buildbot-config step (success) 04:53:44 INFO - [mozharness: 2016-08-09 11:53:44.963178Z] Running download-and-extract step. 04:53:44 INFO - Running pre-action listener: _resource_record_pre_action 04:53:44 INFO - Running main action method: download_and_extract 04:53:44 INFO - mkdir: /builds/slave/test/build/tests 04: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')]} 04:53:44 INFO - trying https://queue.taskcluster.net/v1/task/XwUjCl45RfCsc65tSu91sg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 04:53:44 INFO - Downloading https://queue.taskcluster.net/v1/task/XwUjCl45RfCsc65tSu91sg/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 04:53:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XwUjCl45RfCsc65tSu91sg/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 04:53:46 INFO - Downloaded 1199 bytes. 04:53:46 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 04:53:46 INFO - Using the following test package requirements: 04:53:46 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 04:53:46 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:53:46 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 04:53:46 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:53:46 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 04:53:46 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:53:46 INFO - u'jsshell-mac.zip'], 04:53:46 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:53:46 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 04:53:46 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 04:53:46 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:53:46 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 04:53:46 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:53:46 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 04:53:46 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:53:46 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 04:53:46 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:53:46 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 04:53:46 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:53:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:46 INFO - trying https://queue.taskcluster.net/v1/task/XwUjCl45RfCsc65tSu91sg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 04:53:46 INFO - Downloading https://queue.taskcluster.net/v1/task/XwUjCl45RfCsc65tSu91sg/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 04:53:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XwUjCl45RfCsc65tSu91sg/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 04:53:48 INFO - Downloaded 16324742 bytes. 04:53:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:53:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:53:51 INFO - Return code: 0 04:53:51 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04: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')]} 04:53:51 INFO - trying https://queue.taskcluster.net/v1/task/XwUjCl45RfCsc65tSu91sg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 04:53:51 INFO - Downloading https://queue.taskcluster.net/v1/task/XwUjCl45RfCsc65tSu91sg/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 04:53:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XwUjCl45RfCsc65tSu91sg/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 04:53:52 INFO - Downloaded 16324742 bytes. 04:53:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:53:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:53:55 INFO - Return code: 0 04:53:55 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')]} 04:53:55 INFO - trying https://queue.taskcluster.net/v1/task/XwUjCl45RfCsc65tSu91sg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 04:53:55 INFO - Downloading https://queue.taskcluster.net/v1/task/XwUjCl45RfCsc65tSu91sg/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 04:53:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XwUjCl45RfCsc65tSu91sg/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 04:53:57 INFO - Downloaded 14413723 bytes. 04:53:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:53:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:53:58 INFO - Return code: 0 04:53:58 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')]} 04:53:58 INFO - trying https://queue.taskcluster.net/v1/task/XwUjCl45RfCsc65tSu91sg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 04:53:58 INFO - Downloading https://queue.taskcluster.net/v1/task/XwUjCl45RfCsc65tSu91sg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 04:53:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XwUjCl45RfCsc65tSu91sg/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 04:54:06 INFO - Downloaded 111948327 bytes. 04:54:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XwUjCl45RfCsc65tSu91sg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 04:54:06 INFO - mkdir: /builds/slave/test/properties 04:54:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:54:06 INFO - Writing to file /builds/slave/test/properties/build_url 04:54:06 INFO - Contents: 04:54:06 INFO - build_url:https://queue.taskcluster.net/v1/task/XwUjCl45RfCsc65tSu91sg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 04:54:07 INFO - Running post-action listener: _resource_record_post_action 04:54:07 INFO - Running post-action listener: set_extra_try_arguments 04:54:07 INFO - [mozharness: 2016-08-09 11:54:07.349504Z] Finished download-and-extract step (success) 04:54:07 INFO - [mozharness: 2016-08-09 11:54:07.349646Z] Running populate-webroot step. 04:54:07 INFO - Running pre-action listener: _resource_record_pre_action 04:54:07 INFO - Running main action method: populate_webroot 04:54:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:54:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:54:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:54:07 INFO - '--tppagecycles', 04:54:07 INFO - '7'], 04:54:07 INFO - 'tests': ['tsvgm']}}, 04:54:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:54:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:54:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:54:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:54:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:54:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:54:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:54:07 INFO - 'talos_options': ['--disable-e10s'], 04:54:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:54:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:54:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:54:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:54:07 INFO - 'talos_options': ['--disable-e10s'], 04:54:07 INFO - 'tests': ['damp', 'tps']}, 04:54:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:54:07 INFO - 'tests': ['damp', 'tps']}, 04:54:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:54:07 INFO - 'tests': ['dromaeo_dom']}, 04:54:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:54:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:54:07 INFO - 'tests': ['basic_compositor_video']}, 04:54:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:54:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:54:07 INFO - 'tests': ['a11yr', 04:54:07 INFO - 'ts_paint', 04:54:07 INFO - 'tpaint', 04:54:07 INFO - 'sessionrestore', 04:54:07 INFO - 'sessionrestore_no_auto_restore', 04:54:07 INFO - 'tabpaint']}, 04:54:07 INFO - 'other-e10s': {'tests': ['a11yr', 04:54:07 INFO - 'ts_paint', 04:54:07 INFO - 'tpaint', 04:54:07 INFO - 'sessionrestore', 04:54:07 INFO - 'sessionrestore_no_auto_restore', 04:54:07 INFO - 'tabpaint']}, 04:54:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:54:07 INFO - 'ts_paint', 04:54:07 INFO - 'tpaint', 04:54:07 INFO - 'sessionrestore', 04:54:07 INFO - 'sessionrestore_no_auto_restore', 04:54:07 INFO - 'tabpaint']}, 04:54:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:54:07 INFO - 'ts_paint', 04:54:07 INFO - 'tpaint', 04:54:07 INFO - 'sessionrestore', 04:54:07 INFO - 'sessionrestore_no_auto_restore', 04:54:07 INFO - 'tabpaint']}, 04:54:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:54:07 INFO - 'tests': ['a11yr', 04:54:07 INFO - 'ts_paint', 04:54:07 INFO - 'tpaint', 04:54:07 INFO - 'sessionrestore', 04:54:07 INFO - 'sessionrestore_no_auto_restore', 04:54:07 INFO - 'tabpaint']}, 04:54:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:54:07 INFO - 'tests': ['a11yr', 04:54:07 INFO - 'ts_paint', 04:54:07 INFO - 'tpaint', 04:54:07 INFO - 'sessionrestore', 04:54:07 INFO - 'sessionrestore_no_auto_restore', 04:54:07 INFO - 'tabpaint']}, 04:54:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:54:07 INFO - 'tests': ['tsvgx', 04:54:07 INFO - 'tsvgr_opacity', 04:54:07 INFO - 'tart', 04:54:07 INFO - 'tscrollx', 04:54:07 INFO - 'cart']}, 04:54:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:54:07 INFO - 'tsvgr_opacity', 04:54:07 INFO - 'tart', 04:54:07 INFO - 'tscrollx', 04:54:07 INFO - 'cart']}, 04:54:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:54:07 INFO - 'talos_options': ['--disable-e10s'], 04:54:07 INFO - 'tests': ['tp5o']}, 04:54:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:54:07 INFO - 'tests': ['tp5o']}, 04:54:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:54:07 INFO - 'talos_options': ['--disable-e10s', 04:54:07 INFO - '--xperf_path', 04:54:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:54:07 INFO - 'tests': ['tp5n']}, 04:54:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:54:07 INFO - 'talos_options': ['--xperf_path', 04:54:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:54:07 INFO - 'tests': ['tp5n']}}, 04:54:07 INFO - 'talos.zip': {'path': '', 04:54:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:54:07 INFO - Running post-action listener: _resource_record_post_action 04:54:07 INFO - [mozharness: 2016-08-09 11:54:07.368571Z] Finished populate-webroot step (success) 04:54:07 INFO - [mozharness: 2016-08-09 11:54:07.368709Z] Running create-virtualenv step. 04:54:07 INFO - Running pre-action listener: _resource_record_pre_action 04:54:07 INFO - Running main action method: create_virtualenv 04:54:07 INFO - Creating virtualenv /builds/slave/test/build/venv 04:54:07 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 04:54:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:54:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:54:07 INFO - Using real prefix '/tools/python27' 04:54:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:54:08 INFO - Installing distribute.............................................................................................................................................................................................done. 04:54:11 INFO - Installing pip.................done. 04:54:11 INFO - Return code: 0 04:54:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:54:11 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')]} 04:54:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:11 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')]} 04:54:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:11 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 0x10bbb3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc48618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7abc9d460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb17d30>, '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 0x10b87cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb0fb20>, '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.HCMLwDohjQ/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.CkI7tXI73V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 04:54:11 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 04:54:11 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 04:54:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HCMLwDohjQ/Render', 04:54:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:11 INFO - 'HOME': '/Users/cltbld', 04:54:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:11 INFO - 'LOGNAME': 'cltbld', 04:54:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:11 INFO - 'MOZ_NO_REMOTE': '1', 04:54:11 INFO - 'NO_EM_RESTART': '1', 04:54:11 INFO - 'PAGER': '/bin/cat', 04:54:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:11 INFO - 'PWD': '/builds/slave/test', 04:54:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:11 INFO - 'SHELL': '/bin/bash', 04:54:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CkI7tXI73V/Listeners', 04:54:11 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 04:54:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:11 INFO - 'USER': 'cltbld', 04:54:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:11 INFO - 'XPC_FLAGS': '0x0', 04:54:11 INFO - 'XPC_SERVICE_NAME': '0', 04:54:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:11 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 04:54:11 INFO - Cleaning up... 04:54:11 INFO - Return code: 0 04:54:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:54:11 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')]} 04:54:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:11 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')]} 04:54:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:11 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 0x10bbb3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc48618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7abc9d460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb17d30>, '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 0x10b87cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb0fb20>, '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.HCMLwDohjQ/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.CkI7tXI73V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 04:54:11 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 04:54:11 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 04:54:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HCMLwDohjQ/Render', 04:54:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:11 INFO - 'HOME': '/Users/cltbld', 04:54:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:11 INFO - 'LOGNAME': 'cltbld', 04:54:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:11 INFO - 'MOZ_NO_REMOTE': '1', 04:54:11 INFO - 'NO_EM_RESTART': '1', 04:54:11 INFO - 'PAGER': '/bin/cat', 04:54:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:11 INFO - 'PWD': '/builds/slave/test', 04:54:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:11 INFO - 'SHELL': '/bin/bash', 04:54:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CkI7tXI73V/Listeners', 04:54:11 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 04:54:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:11 INFO - 'USER': 'cltbld', 04:54:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:11 INFO - 'XPC_FLAGS': '0x0', 04:54:11 INFO - 'XPC_SERVICE_NAME': '0', 04:54:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:11 INFO - Downloading/unpacking psutil>=3.1.1 04:54:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:54:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:54:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:54:15 INFO - Installing collected packages: psutil 04:54:15 INFO - Running setup.py install for psutil 04:54:15 INFO - building 'psutil._psutil_osx' extension 04:54:15 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 04:54:15 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 04:54:15 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 04:54:15 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 04:54:15 INFO - building 'psutil._psutil_posix' extension 04:54:15 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 04:54:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:54:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:54:16 INFO - ^ 04:54:16 INFO - 1 warning generated. 04:54:16 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 04:54:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:54:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:54:16 INFO - Successfully installed psutil 04:54:16 INFO - Cleaning up... 04:54:16 INFO - Return code: 0 04:54:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:54:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:54:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:54:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bbb3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc48618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7abc9d460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb17d30>, '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 0x10b87cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb0fb20>, '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.HCMLwDohjQ/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.CkI7tXI73V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 04:54:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:54:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:54:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HCMLwDohjQ/Render', 04:54:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:16 INFO - 'HOME': '/Users/cltbld', 04:54:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:16 INFO - 'LOGNAME': 'cltbld', 04:54:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:16 INFO - 'MOZ_NO_REMOTE': '1', 04:54:16 INFO - 'NO_EM_RESTART': '1', 04:54:16 INFO - 'PAGER': '/bin/cat', 04:54:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:16 INFO - 'PWD': '/builds/slave/test', 04:54:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:16 INFO - 'SHELL': '/bin/bash', 04:54:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CkI7tXI73V/Listeners', 04:54:16 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 04:54:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:16 INFO - 'USER': 'cltbld', 04:54:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:16 INFO - 'XPC_FLAGS': '0x0', 04:54:16 INFO - 'XPC_SERVICE_NAME': '0', 04:54:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:54:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:54:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:54:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:54:19 INFO - Installing collected packages: mozsystemmonitor 04:54:19 INFO - Running setup.py install for mozsystemmonitor 04:54:19 INFO - Successfully installed mozsystemmonitor 04:54:19 INFO - Cleaning up... 04:54:19 INFO - Return code: 0 04:54:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:54:19 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')]} 04:54:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:19 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')]} 04:54:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:19 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 0x10bbb3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc48618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7abc9d460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb17d30>, '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 0x10b87cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb0fb20>, '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.HCMLwDohjQ/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.CkI7tXI73V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 04:54:19 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 04:54:19 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 04:54:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HCMLwDohjQ/Render', 04:54:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:19 INFO - 'HOME': '/Users/cltbld', 04:54:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:19 INFO - 'LOGNAME': 'cltbld', 04:54:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:19 INFO - 'MOZ_NO_REMOTE': '1', 04:54:19 INFO - 'NO_EM_RESTART': '1', 04:54:19 INFO - 'PAGER': '/bin/cat', 04:54:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:19 INFO - 'PWD': '/builds/slave/test', 04:54:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:19 INFO - 'SHELL': '/bin/bash', 04:54:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CkI7tXI73V/Listeners', 04:54:19 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 04:54:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:19 INFO - 'USER': 'cltbld', 04:54:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:19 INFO - 'XPC_FLAGS': '0x0', 04:54:19 INFO - 'XPC_SERVICE_NAME': '0', 04:54:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:19 INFO - Downloading/unpacking jsonschema==2.5.1 04:54:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:54:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:54:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:22 INFO - Downloading functools32-3.2.3-2.tar.gz 04:54:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:54:22 INFO - warning: no files found matching '*.txt' 04:54:22 INFO - no previously-included directories found matching 'build' 04:54:22 INFO - no previously-included directories found matching 'dist' 04:54:22 INFO - no previously-included directories found matching '.git*' 04:54:22 INFO - Installing collected packages: jsonschema, functools32 04:54:22 INFO - Running setup.py install for functools32 04:54:22 INFO - warning: no files found matching '*.txt' 04:54:22 INFO - no previously-included directories found matching 'build' 04:54:22 INFO - no previously-included directories found matching 'dist' 04:54:22 INFO - no previously-included directories found matching '.git*' 04:54:22 INFO - Successfully installed jsonschema functools32 04:54:22 INFO - Cleaning up... 04:54:22 INFO - Return code: 0 04:54:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:54:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:54:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:54:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:22 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 0x10bbb3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc48618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7abc9d460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb17d30>, '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 0x10b87cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb0fb20>, '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.HCMLwDohjQ/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.CkI7tXI73V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 04:54:22 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 04:54:22 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 04:54:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HCMLwDohjQ/Render', 04:54:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:22 INFO - 'HOME': '/Users/cltbld', 04:54:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:22 INFO - 'LOGNAME': 'cltbld', 04:54:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:22 INFO - 'MOZ_NO_REMOTE': '1', 04:54:22 INFO - 'NO_EM_RESTART': '1', 04:54:22 INFO - 'PAGER': '/bin/cat', 04:54:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:22 INFO - 'PWD': '/builds/slave/test', 04:54:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:22 INFO - 'SHELL': '/bin/bash', 04:54:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CkI7tXI73V/Listeners', 04:54:22 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 04:54:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:22 INFO - 'USER': 'cltbld', 04:54:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:22 INFO - 'XPC_FLAGS': '0x0', 04:54:22 INFO - 'XPC_SERVICE_NAME': '0', 04:54:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:54:22 INFO - Cleaning up... 04:54:22 INFO - Return code: 0 04:54:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:54:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:54:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:54:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:22 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 0x10bbb3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc48618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7abc9d460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb17d30>, '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 0x10b87cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb0fb20>, '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.HCMLwDohjQ/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.CkI7tXI73V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 04:54:22 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 04:54:22 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 04:54:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HCMLwDohjQ/Render', 04:54:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:22 INFO - 'HOME': '/Users/cltbld', 04:54:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:22 INFO - 'LOGNAME': 'cltbld', 04:54:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:22 INFO - 'MOZ_NO_REMOTE': '1', 04:54:22 INFO - 'NO_EM_RESTART': '1', 04:54:22 INFO - 'PAGER': '/bin/cat', 04:54:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:22 INFO - 'PWD': '/builds/slave/test', 04:54:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:22 INFO - 'SHELL': '/bin/bash', 04:54:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CkI7tXI73V/Listeners', 04:54:22 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 04:54:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:22 INFO - 'USER': 'cltbld', 04:54:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:22 INFO - 'XPC_FLAGS': '0x0', 04:54:22 INFO - 'XPC_SERVICE_NAME': '0', 04:54:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:23 INFO - Downloading/unpacking blobuploader==1.2.4 04:54:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:25 INFO - Downloading blobuploader-1.2.4.tar.gz 04:54:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:54:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:54:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:54:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:54:26 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 04:54:26 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 04:54:26 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 04:54:26 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 04:54:26 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 04:54:26 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 04:54:26 INFO - Downloading docopt-0.6.1.tar.gz 04:54:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:54:26 INFO - Installing collected packages: blobuploader, requests, docopt 04:54:26 INFO - Running setup.py install for blobuploader 04:54:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:54:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:54:26 INFO - Running setup.py install for requests 04:54:27 INFO - Running setup.py install for docopt 04:54:27 INFO - Successfully installed blobuploader requests docopt 04:54:27 INFO - Cleaning up... 04:54:27 INFO - Return code: 0 04:54:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:54:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:54:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:54:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:27 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 0x10bbb3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc48618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7abc9d460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb17d30>, '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 0x10b87cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb0fb20>, '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.HCMLwDohjQ/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.CkI7tXI73V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 04:54:27 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 04:54:27 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 04:54:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HCMLwDohjQ/Render', 04:54:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:27 INFO - 'HOME': '/Users/cltbld', 04:54:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:27 INFO - 'LOGNAME': 'cltbld', 04:54:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:27 INFO - 'MOZ_NO_REMOTE': '1', 04:54:27 INFO - 'NO_EM_RESTART': '1', 04:54:27 INFO - 'PAGER': '/bin/cat', 04:54:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:27 INFO - 'PWD': '/builds/slave/test', 04:54:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:27 INFO - 'SHELL': '/bin/bash', 04:54:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CkI7tXI73V/Listeners', 04:54:27 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 04:54:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:27 INFO - 'USER': 'cltbld', 04:54:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:27 INFO - 'XPC_FLAGS': '0x0', 04:54:27 INFO - 'XPC_SERVICE_NAME': '0', 04:54:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:54:28 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-9Hx6aJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:54:28 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-xbaDm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:54:28 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-nOnI5U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:54:28 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-fEcTPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:54:28 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-CTwRtb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:54:28 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-kzt45H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:54:28 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-bryBk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:54:28 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-ynmbIf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:54:28 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-l3FqR1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:54:28 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-lJDZAl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:54:29 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-bTnYyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:54:29 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-nzGw3u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:54:29 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-eCbUhn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:54:29 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-0myWX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:54:29 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-QpdydS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:54:29 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-x6wEse-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:54:29 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-du6peI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:54:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:54:29 INFO - Running setup.py install for manifestparser 04:54:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:54:29 INFO - Running setup.py install for mozcrash 04:54:30 INFO - Running setup.py install for mozdebug 04:54:30 INFO - Running setup.py install for mozdevice 04:54:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:54:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:54:30 INFO - Running setup.py install for mozfile 04:54:30 INFO - Running setup.py install for mozhttpd 04:54:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:54:30 INFO - Running setup.py install for mozinfo 04:54:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:54:30 INFO - Running setup.py install for mozInstall 04:54:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:54:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:54:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:54:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:54:30 INFO - Running setup.py install for mozleak 04:54:31 INFO - Running setup.py install for mozlog 04:54:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:54:31 INFO - Running setup.py install for moznetwork 04:54:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:54:31 INFO - Running setup.py install for mozprocess 04:54:31 INFO - Running setup.py install for mozprofile 04:54:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:54:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:54:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:54:31 INFO - Running setup.py install for mozrunner 04:54:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:54:31 INFO - Running setup.py install for mozscreenshot 04:54:32 INFO - Running setup.py install for moztest 04:54:32 INFO - Running setup.py install for mozversion 04:54:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:54:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:54:32 INFO - Cleaning up... 04:54:32 INFO - Return code: 0 04:54:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:54: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')]} 04:54:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54: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')]} 04:54:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:32 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 0x10bbb3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc48618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7abc9d460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb17d30>, '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 0x10b87cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb0fb20>, '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.HCMLwDohjQ/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.CkI7tXI73V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 04:54:32 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 04:54:32 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 04:54:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HCMLwDohjQ/Render', 04:54:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:32 INFO - 'HOME': '/Users/cltbld', 04:54:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:32 INFO - 'LOGNAME': 'cltbld', 04:54:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:32 INFO - 'MOZ_NO_REMOTE': '1', 04:54:32 INFO - 'NO_EM_RESTART': '1', 04:54:32 INFO - 'PAGER': '/bin/cat', 04:54:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:32 INFO - 'PWD': '/builds/slave/test', 04:54:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:32 INFO - 'SHELL': '/bin/bash', 04:54:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CkI7tXI73V/Listeners', 04:54:32 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 04:54:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:32 INFO - 'USER': 'cltbld', 04:54:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:32 INFO - 'XPC_FLAGS': '0x0', 04:54:32 INFO - 'XPC_SERVICE_NAME': '0', 04:54:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:54:32 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-0H73MT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:54:32 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)) 04:54:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:54:32 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-qZpodl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:54:32 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)) 04:54:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:54:32 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-gZohoX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:54:32 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)) 04:54:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:54:32 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-DXPxT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:54:33 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)) 04:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:54:33 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-EOsFd5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:54:33 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)) 04:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:54:33 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-HSF__o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:54:33 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)) 04:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:54:33 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-GZApZr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:54:33 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)) 04:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:54:33 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-vtrXJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:54:33 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)) 04:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:54:33 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-e1i1Nj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:54:33 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)) 04:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:54:33 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-__upqj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:54:33 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)) 04:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:54:33 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-wYrdXq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:54:33 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)) 04:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:54:33 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-URyJsC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:54:33 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)) 04:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:54:33 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-ptdp0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:54:34 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)) 04:54:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:54:34 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-1joQsm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:54:34 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)) 04:54:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:54:34 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-C3bsqj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:54:34 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)) 04:54:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:54:34 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-xsiL2P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:54:34 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)) 04:54:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:54:34 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-WwoSBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:54:34 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)) 04:54:34 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)) 04:54:34 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)) 04:54:34 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)) 04:54:34 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)) 04:54:34 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)) 04:54:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:54:34 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 04:54:34 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 04:54:34 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 04:54:34 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 04:54:34 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 04:54:34 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 04:54:36 INFO - Downloading blessings-1.6.tar.gz 04:54:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:54:36 INFO - Installing collected packages: blessings 04:54:36 INFO - Running setup.py install for blessings 04:54:37 INFO - Successfully installed blessings 04:54:37 INFO - Cleaning up... 04:54:37 INFO - Return code: 0 04:54:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:54:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:54:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:54:37 INFO - Reading from file tmpfile_stdout 04:54:37 INFO - Current package versions: 04:54:37 INFO - blessings == 1.6 04:54:37 INFO - blobuploader == 1.2.4 04:54:37 INFO - docopt == 0.6.1 04:54:37 INFO - functools32 == 3.2.3-2 04:54:37 INFO - jsonschema == 2.5.1 04:54:37 INFO - manifestparser == 1.1 04:54:37 INFO - mozInstall == 1.12 04:54:37 INFO - mozcrash == 0.17 04:54:37 INFO - mozdebug == 0.1 04:54:37 INFO - mozdevice == 0.48 04:54:37 INFO - mozfile == 1.2 04:54:37 INFO - mozhttpd == 0.7 04:54:37 INFO - mozinfo == 0.9 04:54:37 INFO - mozleak == 0.1 04:54:37 INFO - mozlog == 3.3 04:54:37 INFO - moznetwork == 0.27 04:54:37 INFO - mozprocess == 0.23 04:54:37 INFO - mozprofile == 0.28 04:54:37 INFO - mozrunner == 6.12 04:54:37 INFO - mozscreenshot == 0.1 04:54:37 INFO - mozsystemmonitor == 0.3 04:54:37 INFO - moztest == 0.7 04:54:37 INFO - mozversion == 1.4 04:54:37 INFO - psutil == 3.1.1 04:54:37 INFO - requests == 1.2.3 04:54:37 INFO - wsgiref == 0.1.2 04:54:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:54:37 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')]} 04:54:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:37 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')]} 04:54:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:37 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 0x10bbb3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc48618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7abc9d460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb17d30>, '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 0x10b87cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb0fb20>, '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.HCMLwDohjQ/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.CkI7tXI73V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 04:54:37 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 04:54:37 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 04:54:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HCMLwDohjQ/Render', 04:54:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:37 INFO - 'HOME': '/Users/cltbld', 04:54:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:37 INFO - 'LOGNAME': 'cltbld', 04:54:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:37 INFO - 'MOZ_NO_REMOTE': '1', 04:54:37 INFO - 'NO_EM_RESTART': '1', 04:54:37 INFO - 'PAGER': '/bin/cat', 04:54:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:37 INFO - 'PWD': '/builds/slave/test', 04:54:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:37 INFO - 'SHELL': '/bin/bash', 04:54:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CkI7tXI73V/Listeners', 04:54:37 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 04:54:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:37 INFO - 'USER': 'cltbld', 04:54:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:37 INFO - 'XPC_FLAGS': '0x0', 04:54:37 INFO - 'XPC_SERVICE_NAME': '0', 04:54:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:37 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)) 04:54:37 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)) 04:54:37 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)) 04:54:37 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)) 04:54:37 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)) 04:54:37 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)) 04:54:37 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)) 04:54:37 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)) 04:54:37 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)) 04:54:37 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)) 04:54:37 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)) 04:54:37 INFO - Cleaning up... 04:54:37 INFO - Return code: 0 04:54:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:54:37 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')]} 04:54:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:37 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')]} 04:54:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:37 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 0x10bbb3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc48618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7abc9d460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb17d30>, '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 0x10b87cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb0fb20>, '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.HCMLwDohjQ/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.CkI7tXI73V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 04:54:37 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 04:54:37 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 04:54:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HCMLwDohjQ/Render', 04:54:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:37 INFO - 'HOME': '/Users/cltbld', 04:54:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:37 INFO - 'LOGNAME': 'cltbld', 04:54:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:37 INFO - 'MOZ_NO_REMOTE': '1', 04:54:37 INFO - 'NO_EM_RESTART': '1', 04:54:37 INFO - 'PAGER': '/bin/cat', 04:54:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:37 INFO - 'PWD': '/builds/slave/test', 04:54:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:37 INFO - 'SHELL': '/bin/bash', 04:54:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CkI7tXI73V/Listeners', 04:54:37 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 04:54:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:37 INFO - 'USER': 'cltbld', 04:54:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:37 INFO - 'XPC_FLAGS': '0x0', 04:54:37 INFO - 'XPC_SERVICE_NAME': '0', 04:54:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:54:38 INFO - Cleaning up... 04:54:38 INFO - Return code: 0 04:54:38 INFO - Running post-action listener: _resource_record_post_action 04:54:38 INFO - Running post-action listener: _start_resource_monitoring 04:54:38 INFO - Starting resource monitoring. 04:54:38 INFO - [mozharness: 2016-08-09 11:54:38.133344Z] Finished create-virtualenv step (success) 04:54:38 INFO - [mozharness: 2016-08-09 11:54:38.134318Z] Running install step. 04:54:38 INFO - Running pre-action listener: _resource_record_pre_action 04:54:38 INFO - Running main action method: install 04:54:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:54:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:54:38 INFO - Reading from file tmpfile_stdout 04:54:38 INFO - Detecting whether we're running mozinstall >=1.0... 04:54:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:54:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:54:38 INFO - Reading from file tmpfile_stdout 04:54:38 INFO - Output received: 04:54:38 INFO - Usage: mozinstall [options] installer 04:54:38 INFO - Options: 04:54:38 INFO - -h, --help show this help message and exit 04:54:38 INFO - -d DEST, --destination=DEST 04:54:38 INFO - Directory to install application into. [default: 04:54:38 INFO - "/builds/slave/test"] 04:54:38 INFO - --app=APP Application being installed. [default: firefox] 04:54:38 INFO - mkdir: /builds/slave/test/build/application 04:54:38 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'] 04:54:38 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 04:55:13 INFO - Reading from file tmpfile_stdout 04:55:13 INFO - Output received: 04:55:13 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:55:13 INFO - Running post-action listener: _resource_record_post_action 04:55:13 INFO - [mozharness: 2016-08-09 11:55:13.286028Z] Finished install step (success) 04:55:13 INFO - [mozharness: 2016-08-09 11:55:13.286163Z] Running run-tests step. 04:55:13 INFO - Running pre-action listener: _resource_record_pre_action 04:55:13 INFO - Running main action method: run_tests 04:55:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:55:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:55:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:55:13 INFO - 2016-08-09 04:55:13.300 screenresolution[1643:8057] starting screenresolution argv=screenresolution get 04:55:13 INFO - 2016-08-09 04:55:13.303 screenresolution[1643:8057] Display 0: 1600x1200x32@60 04:55:13 INFO - 2016-08-09 04:55:13.308 screenresolution[1644:8059] starting screenresolution argv=screenresolution list 04:55:13 INFO - Available Modes on Display 0 04:55:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:55:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:55:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:55:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:55:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:55:13 INFO - Intel Iris: 04:55:13 INFO - Chipset Model: Intel Iris 04:55:13 INFO - Type: GPU 04:55:13 INFO - Bus: Built-In 04:55:13 INFO - VRAM (Dynamic, Max): 1536 MB 04:55:13 INFO - Vendor: Intel (0x8086) 04:55:13 INFO - Device ID: 0x0a2e 04:55:13 INFO - Revision ID: 0x0009 04:55:13 INFO - Displays: 04:55:13 INFO - Display: 04:55:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:55:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:55:13 INFO - Display Serial Number: 1600x1200 60 04:55:13 INFO - Main Display: Yes 04:55:13 INFO - Mirror: Off 04:55:13 INFO - Online: Yes 04:55:13 INFO - Rotation: Supported 04:55:13 INFO - Return code: 0 04:55:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:55:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:55:13 INFO - Python 2.7.3 04:55:13 INFO - Return code: 0 04:55:13 INFO - grabbing minidump binary from tooltool 04:55: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')]} 04:55:13 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 0x10bb17d30>, '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 0x10b87cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb0fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:55:13 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 04:55:13 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 04:55:13 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 04:55:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:55:13 INFO - Return code: 0 04:55:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:55:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:55:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:55:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:55:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:55:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XwUjCl45RfCsc65tSu91sg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0118', '--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 04:55:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XwUjCl45RfCsc65tSu91sg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0118 --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 04:55:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HCMLwDohjQ/Render', 04:55:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:13 INFO - 'HOME': '/Users/cltbld', 04:55:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:13 INFO - 'LOGNAME': 'cltbld', 04:55:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:55:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:55:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:13 INFO - 'MOZ_NO_REMOTE': '1', 04:55:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:55:13 INFO - 'NO_EM_RESTART': '1', 04:55:13 INFO - 'PAGER': '/bin/cat', 04:55:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:13 INFO - 'PWD': '/builds/slave/test', 04:55:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:55:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:13 INFO - 'SHELL': '/bin/bash', 04:55:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CkI7tXI73V/Listeners', 04:55:13 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 04:55:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:13 INFO - 'USER': 'cltbld', 04:55:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:13 INFO - 'XPC_FLAGS': '0x0', 04:55:13 INFO - 'XPC_SERVICE_NAME': '0', 04:55:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XwUjCl45RfCsc65tSu91sg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0118', '--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 04:55:13 INFO - mozversion application_buildid: 20160809034720 04:55:13 INFO - mozversion application_changeset: 5179814c12b173f8126279665c48dfdef099b13f 04:55:13 INFO - mozversion application_display_name: Nightly 04:55:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:55:13 INFO - mozversion application_name: Firefox 04:55:13 INFO - mozversion application_remotingname: firefox 04:55:13 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 04:55:13 INFO - mozversion application_vendor: Mozilla 04:55:13 INFO - mozversion application_version: 51.0a1 04:55:13 INFO - mozversion platform_buildid: 20160809034720 04:55:13 INFO - mozversion platform_changeset: 5179814c12b173f8126279665c48dfdef099b13f 04:55:13 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 04:55:13 INFO - mozversion platform_version: 51.0a1 04:55:13 INFO - using testdate: 1470743713 04:55:13 INFO - actual date: 1470743713 04:55:13 INFO - starting webserver on 'localhost:49266' 04:55:13 INFO - SUITE-START | Running 2 tests 04:55:13 INFO - TEST-START | dromaeo_css 04:55:13 INFO - operating with platform_type : mac_ 04:55:13 INFO - Initialising browser for dromaeo_css test... 04:55:13 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/tmpUgCCbo/profile http://localhost:49266/getInfo.html) 04:55:16 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 04:55:16 INFO - PROCESS | 1654 | colorDepth:24 04:55:16 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 04:55:16 INFO - PROCESS | 1654 | __metrics 04:55:16 INFO - PROCESS | 1654 | 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" 04:55:16 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:55:17 INFO - TEST-INFO | 1654: exit 0 04:55:17 INFO - Browser initialized. 04:55:17 INFO - Running cycle 1/1 for dromaeo_css test... 04:55:17 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/tmpUgCCbo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:55:29 INFO - PROCESS | 1658 | RSS: Main: 243625984 04:55:29 INFO - PROCESS | 1658 | 04:56:20 INFO - PROCESS | 1658 | 1470743780743 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] 04:58:32 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 04:58:34 INFO - PROCESS | 1658 | RSS: Main: 380125184 04:58:34 INFO - PROCESS | 1658 | 05:01:37 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 05:01:37 INFO - PROCESS | 1658 | RSS: Main: 386375680 05:01:37 INFO - PROCESS | 1658 | 05:04:40 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 05:04:40 INFO - PROCESS | 1658 | RSS: Main: 354123776 05:04:40 INFO - PROCESS | 1658 | 05:07:42 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 05:07:43 INFO - PROCESS | 1658 | RSS: Main: 358084608 05:07:43 INFO - PROCESS | 1658 | 05:10:46 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 05:10:47 INFO - PROCESS | 1658 | RSS: Main: 350453760 05:10:47 INFO - PROCESS | 1658 | 05:13:50 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 05:13:51 INFO - PROCESS | 1658 | RSS: Main: 472100864 05:13:51 INFO - PROCESS | 1658 | 05:13:51 INFO - PROCESS | 1658 | __start_tp_report 05:13:51 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 05:13:51 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 05:13:51 INFO - PROCESS | 1658 | |i|pagename|runs| 05:13:51 INFO - PROCESS | 1658 | |0;dojo.html;3286.917827054324;3304.6034475862893;3310.553075334829;3326.085326535201;3368.7810292330996;5483.314585676791;5540.252065971093;5586.35756887958;5613.214150019001;5621.859453513659;5547.667139971604;5583.748731307091;5630.071038278679;5663.490285874271;5672.489475947164;5020.698758074509;5044.82343117991;5109.489051094898;5118.692878427306;5250.981387226285;5175.327207463025;5212.478752124776;5222.556082732972;5227.32044834171;5232.372115346164;4956.87607809804;4979.502049795028;4995.425526064513;5053.873653158664;5145.073886700374;5038.345015148044;5057.216131499624;5064.316317297192;5076.593872490192;5085.542301192873;4396.494403143647;5050.04049230645;5093.032323858492;5121.615878809067;5163.7676304566385;5522.530584900288;5525.944740552583;5606.775728970836;5637.267155269829;5684;6077.205261012639;6092.390760923917;6101.725422356005;6104.847378815565;6105.633661980295;5198.584113270929;6144.924638188344;6191.81424557264;6217.68911554425;6232.87534249317;5573.554115670696;5605.1031834907035;5615.803446879339;5655.293088363955;5738.684372359518;6233.252009758768;6247.3440288769025;6247.812565622948;6251.343608921089;6251.937480625135;6005.3694362091355;6016.819495415057;6036.245469316335;6046.818595442144;6069.302030266487;5966.761329546856;6120.357362476965;6131.50947924165;6139.3553676863285;6141.416565426277;5938.465538101592;5943.821685349447;5977.073553599199;5983.850403739942;6047.818565443044;5992.8801423972145;6033.909491357633;6063.333033366326;6068.908966365508;6089.512838972872;4890.804367825318;4897.191963326011;4920.852374428773;4927.457979622239;4930.778114984834;4258.127083947783;4305.74165550068;4306.0957198987935;4372.016296333363;4424.446944131983;5432.239486471897;5559.555235581144;5589.888202235932;5590.972045139748;5642.971785141048;6004.399560044048;6107.816765496953;6107.816765497042;6113.480354169857;6124.816255512342;5513.3108361454815;5520.475554822286;5527.55779537636;5528.253685752372;5530.336359636869;5357.276767636397;5365.973170134124;5381.78472861089;5384.192371144281;5404.91892621611;6252.812415627538;6257.4994000480765;6266.091416744638;6288.025356069817;6289.339619339903;6077.969610152098;6112.2299296386045;6167.876642467036;6177.938220617916;6189.752409903734;6222.937770622236;6225.750969961332;6230.127782110418;6243.125962365181;6244.500439964882;5944.078667806497;5956.463918247378;6002.8499287516415;6069.605475644069;6105.145279660762;6106.45041946227;6111.816645500642;6114.541409394402;6121.3572574879;6122.785702500391;5909.96575599277;5923.378045305181;5926.851828704144;5929.229200203946;5942.64344139353;5780.5953583248765;5829.242198514166;5831.387704291158;5884.235049443716;5982.7008649568215;4506.369108324902;5772.5381969441505;5798.536117110537;5840.445157710011;5856.795012174553;5857.50211232033;5925.9407405925385;5926.377730338252;5932.822015339547;5933.554983376351;5615.803446879339;5675.57433192504;5702.40124786908;5715.2855893013375;5809.36097029318 05:13:51 INFO - PROCESS | 1658 | |1;ext.html;469.11337571988923;509.1802198460478;516.2720564004753;541.1260813785735;554.8307766131328;30531;31316.843415782918;32528.186795330068;32618;40981.180376392454;41496.58503414965;44300.11399772003;44325.99999999992;44338.77830610843;44443.77778111113;9647.372920760146;9792.069753373418;9802.2158227342;9803.6078556858;9805.65680201193;8690.956545217265;8861.202491775739;9739.074787895173;9860.506974651275;9867.977064701754;11794.29234245948;11939;11994.64016079519;12245.14284000116;12317.692057698585;10047.849282260771;11887.583934562292;11982.981446577009;12000.459979300953;12002.399880006053;11870.347130907796;11939.34333611651;11944.730501204831;11962.401879906058;11963.700907477296;41156.588434115576;41391;41518;41545;41559.168816623496;3009.337945651953;3722.8324725387424;3753.60587138352;3999.920001599981;4369.737815731066;31201.37597248065;33130.834345828356;33157.17107072318;33282.33435331304;33333.33333333344;3147.0716138739126;3157.700018498262;3346.6318704942437;3364.8822291219935;3368.6126095499085;356004;356240;357118;357363;360493.3950660486;60110.09834852481;61108.69445652743;61177.77644447086;61258.38741612616;61285.38714612797;59286.11070833941;62872;66267.66866165638;66416.66791666107;66773;16316.918415407847;16539.173041348124;16539.751903721455;16552.917235413744;16588.83411165873;12827.743445131046;12916.22502649844;12919.741605167845;12932.482700691868;12947.093703440858;15751.527454176394;16158.353665853234;16167.625751810992;17479.47561573155;18442.98563579016;5742.59801813869;5945.286565612154;6933.583984960918;6942.778803425585;7051.929480705127;876.8903887014123;937.5218638494417;962.8074385123003;998.1216529454034;1054.931429457083;28744.568831467546;30301;30924.917627882915;31107.68892311093;31317.217069572987;1041.6874937518864;1043.3479075577764;1125.4372813593204;1137.4881303413497;1165.4056431220138;13629.86370136286;14996.625084372978;15842.44551440717;15913.283902224197;16245.756313655305;615998;618354;623591;624150;625128;51728;52378.21432678513;52389.47610523922;58370;58726.412735872094;1381.9239941803096;1388.2572823539358;1427.9143251404741;1433.5914264434587;1433.7419264532275;28419;30975.225619358796;31635.52546711847;31974.200644984063;32590.34819303648;52630.73684631552;53373.93252134858;53402;53480.73259633833;53533.46466535285;475504;478227;619485;635793;637790;26641.733582663925;26919.92320307244;26946.191614251602;26973.595396069468;27003;39134.217315654096;40397.990050247805;45897.77051114723;46502.837429064544;46880.7655961718;32888;33147.66852331446;38799.6120038796;38833;39332.80333598314;38492.80753596214;38892.2221555573;39846.402303966046;39864.60135398609;39933.80033099932;37168.6283137176;37520.81239593784;37717.62282377141;37814.054648634;38663.226735465694;34346;35084.64915350814;36044.4593331095;36200.91397258086;36340.45489317715;25649.615255770805;25998.870005650606;26006.479870402865;26121.477570448864;26630.467390652466 05:13:51 INFO - PROCESS | 1658 | |2;jquery.html;354.8030842882199;362.29171968800995;373.60771190250233;380.2755750295687;400.89977505623597;14925.776113358308;15543.766843497357;20944.371668849923;20958.16167353304;20982.475438114056;20383.18467261308;20574.38276851693;20762.235210007166;20766.688499672517;21789.237376691817;5384.596155288349;5775.018246898037;5915.408459154082;6180.412860778219;6185.474234690034;5986.371430999748;6155.07673848924;6157.353477884825;6162.414570615807;6176.752929882822;4179.770112643803;6422.967885160568;6682.599044057372;6717.932820671804;6808.455323574127;6527.543071984963;6725.293844146343;6785.21969973452;6790.796276111724;6798.320167983212;5887.264091988502;5891.293044834604;5896.557758168155;5899.970500147472;5900.232969713909;23443.4139146521;24438.877805610857;26468.338291542674;26816.865915670296;27022.864885675444;2139.5827813576357;2222.4777177363308;2403.9302510382886;2436.951260974771;2678.3170291575525;9255.583498742573;10161.847572286422;10366.896331036669;10623.787524249548;10637.627683031134;1872.3821139024226;1898.4494496595971;1987.5627361980341;2043.1112466077304;2130.094709748351;99310;108367.45816270947;108920;109479;109761.45119274352;11074.446277686244;11344.376059316652;11499;11553.711157221136;11688.824667629993;14503.347349369304;14527.709445811024;14535.927320363542;14562;14569.9801013928;8348.039975402906;8488.533130677872;8534.359923005799;8573.356998225034;8627.956860215785;8417.284530814826;8448.746537603882;8580.313574913991;8591.656333746705;8634.582316951264;4424.823007079681;4472.016156445577;4507.5943165115295;4564.88160400704;4603.6777425579885;1678.8628978245279;1889.574845659743;1967.872088314255;1970.290695349673;1971.5958228563397;860.5783166248493;873.3144481582054;897.8877640294963;900.7027680865299;976.5507866381402;16274.593135171715;20541.589168216553;20704.48238794042;21226.150953961667;21404;876.7150676030316;934.9766255843658;950.4148715493845;967.7967626798311;970.4856426094182;9508.619655213852;10556;11237.269577477438;11285.040771534348;11309.71725706864;529606;597083;603519;607756;609147.542622867;92307;112405;127606;136410;137457;950.6577632052456;978.5596481583316;1006.3609607899036;1013.878334599838;1015.5125539740818;18694;19005.429837104908;19038.904805475882;19103.713444298624;19138.425847224604;35931.64068359283;43602.127957441306;50959;53898;54107;80672;81397;81982;82315.17684823315;82569.58715206386;6991.615461149686;7237.131544214728;7249.420046396381;7780.766577002699;7977.641006154853;12322.521297444364;12651.620451386472;12667.683307917376;12980.8052879205;13073.477060917457;5404.32445944257;9671.661491847752;9793.559289831832;9964.352317099365;10804.86548912353;10378.221633377376;10620.309679870783;10830.67507974762;10995.395253261147;11381.54473821038;11015.118790496901;11093.778124437305;11389.601363952223;11521.423928803359;11628.953394194563;7148.4281257500315;7362.889556656547;7437.590932498548;7456.254374562501;7527.585982771;10023.54894029785;10813;10884.40135792539;11267.26762760418;11329.320240785579 05:13:51 INFO - PROCESS | 1658 | |3;mootools.html;1534.46293797171;1544.675618120195;1748.5803407182273;1846.2522678315288;1895.6966885298355;8262.876056859144;8827.955860220705;8829.16122968317;8831.381803273773;8845.690400835963;7708.922910770891;7912.208779122085;7914.129445760962;8019.438639295252;8670.132986701326;2136.188248465585;2170.479085019592;2291.450051987519;2364.7280562735245;2366.337425520857;2352.635341522067;2358.575456417844;2359.398353419884;2363.078399424226;2369.1944738788807;2211.159759291475;2251.7072780538506;2264.932052038433;2268.0587556164255;2274.6133157363206;2306.97693023071;2329.743728189906;2330.545543618995;2346.1084787780705;2352.717673879128;1976.6837306030966;2321.419645088728;2334.5447637710654;2338.5790557699606;2339.543788961153;8754.56227188638;8803.515806630632;8853.601587928559;8870.512121833297;8872.112788721077;5163.896722065575;5329;5329.760160792774;5335.839924802262;5337.146056630921;3428.879989200391;3585.6055833858254;3656.8354424050717;3668.248009158116;3679.889603311878;1050.6585359758033;1081.8918108189198;1087.9075278601329;1100.7138144082517;1108.0581505720154;11504.884951150465;11563.595274165447;11566.016888564482;11572.94213528927;11598.072154227642;4435.667324950641;4569.588737013684;4748.216544270157;4754.857354279377;4759.714417134983;9872.012798720214;9872.96333884946;9880.962498937652;9901.554430050664;9948.204143668489;5300.946990530122;5312.7343632818975;5313.787448502094;5318.122509785842;5327.467253274642;4660.277656963176;4800.015996720664;4872.001239745844;4874.634402419832;4878.390201224847;3626.147855254066;3692.538432695913;3804.1250512382308;3892.8637497687446;3893.0851249956236;1217.7442737025324;1288.7486940046601;1291.1349395904767;1300.232862611064;1300.7268473620456;719.5718547464251;745.3031415626407;893.3657103456619;903.5256490342623;949.9335046546676;5467.890642187134;5505.889882202334;5732.856678582985;6024.759009639653;6042.848928776816;949.9857502137473;949.9863078493311;992.3301771304341;1012.1023081721771;1033.302520798473;3281.797402908505;3698.8890333290046;3956.228535435562;3992.0818211811447;4042.191561687674;10198.490075496344;10567.788644227227;10590.364578125336;10593.894061059445;10594.682159535227;10551.997560231766;10558.63044793444;10561.841572376421;10570.682879513626;10571.471426428649;7333.339999400079;7735.381169506426;8122.675092996332;8124.999999999882;8129.430939834155;5526.613137080366;5681.545476361964;5828.854278642898;5881.529477641864;5894.675792831264;9796.020397960147;9862.901370986197;9894.851577226491;9903.653372131941;9924.15644670211;8919.55402229873;9756.707298781048;9900.50497475109;9986.400815951067;10156.18750499973;6988.231294557696;7134.001239826324;7137.286271372821;7142.857142857009;7150.141982962078;6686.197656281277;7517.210692877388;7778.766637000899;8036.4776289541;8220.63007164667;7464.328210460867;7512.511686740344;7539.283768042027;7601.581912994839;7660;7355.77932662021;7359.37445317154;7367.521111127974;7449.553026818408;7466.141393739789;6934.479914006369;6955.200151982384;6967.512274140761;7015.859682806214;7057.2237053925055;4886.218205087312;4971.502849715;4977.626677999092;4995.575376092927;5119.0529751996;4915.6313276505125;5082.415522214993;5098.337216162024;5131.538161565477;5184.559312458483 05:13:51 INFO - PROCESS | 1658 | |4;prototype.html;288.9696581858905;297.74840259980317;308.8193406856989;313.9089663997439;317.62837480148227;4484.887877803057;5222.738863056841;5358.4641535846395;5378.52587809607;5410.513053825155;4481.731096134235;4593.977030114853;5348.171033489815;5387.299651045369;5393.83818485446;3331.7344205939953;3733.9439908401328;3897;4042.5148982122187;4200.726952748077;4061.1471590965784;4094.549599544063;4102.384642303648;4128.566500517448;4133.731307465006;4045;4063.3701776224734;4075.7787006081758;4078.8980275493054;4099.979500102495;3854.8843534694006;3879.7797827802997;4063.674906007512;4067.8983025424304;4074.8370065197355;3473.3747925373423;3518.5074089627474;3520.7887526748223;3521.4893840393;3523.2777280657406;4728.645351598644;4796.928046079277;5222.686638801684;5223.529882310572;5283.77689609338;3522.6085696150135;3738.896715725941;4267.359896015622;4484.013517026249;4657.906841863144;5558.360788509332;5609.635373700697;5614.410486898899;5629.634073785191;5649.576281778842;3118.469860123784;3360.663933606644;3570.6072332043454;3862.246861861938;3909.139989202371;4175.45508161979;5168.01807656544;5459.972700136514;5706.400827913092;5872.297033860175;3287.391832510993;3871.2257548490416;3879.741017545219;3889.747166434173;3894.045958740127;3173.2066983254185;3304.5704058472243;3397.9320413592086;3474.2704032153024;3755.1550901047044;3815.904602384907;4191.559886211904;4243.321068629066;4600.700954437951;4604.309353596987;4760.309755085494;5341.86645333862;5358.544523715528;5651.208830763697;5698.230738850285;1184.9585264515702;1333.48660765604;1353.7495563320817;1353.9932300338435;1362.4413990263945;772.5441989226383;772.6214155064091;818.0183779464667;827.6027506796651;840.4621042532784;4244.766537840386;5084.491550844886;5085.593152547787;5277.920831187535;5288.9735551322765;5811.941880581225;5816.76732930678;5858.589898707135;5862.413758624189;5867.501262392659;4918.409561020404;5236.748198274779;5262.289590905255;5307.203919412119;5377.90770307075;2990.551417287424;3063.3413816029215;3069.5088785794164;3070.2324418895278;3081.0140754958647;5394.78420863169;5569.693666848362;5925.229720136354;6053.667048312385;6086.93478641236;4809.398825146856;5022.598192144693;5474.233607294982;5726.513246374022;5977.790777322773;5999.850003749942;6007.708342706252;6016.639001659914;6039.698015099228;6045.516358691294;5503.504684578407;5807.477327040587;5835.649861008353;5872.735726892385;5877.206577112035;5730.341010783646;5732;5738.540916726735;5754.4175351778595;5759.740811663569;4774.3554620127225;5758.280214973129;6027.30685971119;6032.914075466459;6057.7879774207695;3315.718163956091;3368.5620869286836;3610.4042832932273;3900.921981560296;3954.070793363615;5077.822276220315;5083.881546059861;5135.6119404477095;5329.615694034239;5837.445442682938;5423.322084739408;5692.1746346778655;5709.800156994485;5712.685802280915;5722.828315150552;5076.466970968143;5443.482869127427;5639.154126881001;5662.8301150965535;5694.402087780889;4957.909259962802;5082.567981721595;5590.664560126405;5596.216529685766;5628.943710562842;4972.751362431792;5118.364042518032;5266.683998960075;5409.648372855751;5574.662081100588;5338.706371149624;5410.538205116583;5445.809396671098;5482.21727008363;5486.643368181056 05:13:51 INFO - PROCESS | 1658 | |5;yui.html;720.776559266627;721.7041013184594;770.6146926536732;775.8991331126956;881.0704706534606;12272.877271227286;16560.917195414022;16569.585760355996;16585.170741462938;16637.251323690434;14809.407623695039;14962.952593318472;14964.102153870776;14981.101133931972;15026;667.2560095493515;694.9513534052618;696.686491079014;842.7050532313702;844.4342290665238;861.0872475176302;862.779991102268;874.8337815814975;875.7810547363159;878.3017501086651;932.496451915968;933.2813733425259;933.5658918602849;933.7152168588577;934.7569631895689;924.2098006204666;925.8194652042854;926.2913870888775;926.393212445849;927.0821886332516;526.1765337247195;658.3679667519187;680.7106979533678;681.2778454837838;683.4293365040197;14754.63113422173;14794.852051479455;14803.777943330859;14807.70384592302;14830.258487075604;13761.17432954016;14143.787843182361;14216.431342746278;14277.428902843978;14380.065295755847;12779.169353991962;12821.423035963404;12906.225626462443;13102.148360356547;13780.966427517977;4557.430321209848;4703.835365762182;4753.33453316536;4753.429588449409;4785.688930219525;117016.41491792486;119435.4028229862;121520;121974.99999999911;122490;10932.234743567873;11252.549898004152;11262.436878156059;11443.370614616277;11625.476853541611;21806.236781712563;22217.44456388603;22385.44036399103;22394.55210895773;22412.77587224107;7960.601969901481;8057.83884322322;8100.497293842757;8172.877406838902;8347.081820999685;4343.3484977253665;4357.586029327209;4417.3815665806815;4532.5694059064335;4694.882627934329;2924.4735947529225;3358.3115461330367;3489.9704587146853;3493.8951831445097;3494.947575786365;564.4806777764467;575.221679289033;582.6242073862335;596.3410431473246;640.2573015302227;522.7360183107506;577.6245440463732;599.9850003749941;614.1432701381636;652.1554586809998;8397.412181147261;9337.112974267433;9351.672691455902;9396.342256042011;9413.670521531714;554.7226386806597;593.3413910559276;598.35676647604;653.8038588423454;680.5100327764002;4497.280435130363;4560.703554268962;4606.861794146181;4618.769061546909;4688.953110468851;115757;138575.30712346575;161094;162728;167380;31143.844280778452;33084.834575826964;38318.42522362167;40768.18463630711;96740.51629741947;96083;108424;110878;111828;112061;8584.141585841366;9105;9353.625854965998;9898.802023959624;10041.949790251001;56808.715956419954;60311.396886030576;60587.69706151618;61707.38292617016;63787;91406;96094;96128;98760.50619746855;99034.50482747541;12946.870531294566;12998.870011300145;13061.477540898257;13457.125286856322;13681.589552313446;14638.560843174722;14870.77693834615;15009.324580394126;15017.399304027716;15725.842741572895;13704.10923289983;13875.236861972688;13922.234277114856;13960.650983725489;14582.854171458574;13312.534061307808;13899;13922.582322530341;13945.3724582392;13945.721085578434;13417.396217170055;13533.86466135326;13560.321983900963;13560.728785424433;13561.321933903462;10959.397233152253;10989.7120982535;11432.085433165494;11507.367094809866;11848.711479994325;11350.148738844455;11370.602028928948;11488.817318844362;11525.942370288094;11568.305901645928 05:13:51 INFO - PROCESS | 1658 | __end_tp_report 05:13:51 INFO - PROCESS | 1658 | __start_cc_report 05:13:51 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,1300 05:13:51 INFO - PROCESS | 1658 | __end_cc_report 05:13:51 INFO - PROCESS | 1658 | __startTimestamp1470744831103__endTimestamp 05:13:51 INFO - PROCESS | 1658 | 05:13:51 INFO - PROCESS | 1658 | ------- Summary: start ------- 05:13:51 INFO - PROCESS | 1658 | Number of tests: 6 05:13:51 INFO - PROCESS | 1658 | 05:13:51 INFO - PROCESS | 1658 | [#0] dojo.html Cycles:165 Average:5611.16 Median:5780.60 stddev:630.53 (10.9%) stddev-sans-first:605.53 05:13:51 INFO - PROCESS | 1658 | Values: 3286.9 3304.6 3310.6 3326.1 3368.8 5483.3 5540.3 5586.4 5613.2 5621.9 5547.7 5583.7 5630.1 5663.5 5672.5 5020.7 5044.8 5109.5 5118.7 5251.0 5175.3 5212.5 5222.6 5227.3 5232.4 4956.9 4979.5 4995.4 5053.9 5145.1 5038.3 5057.2 5064.3 5076.6 5085.5 4396.5 5050.0 5093.0 5121.6 5163.8 5522.5 5525.9 5606.8 5637.3 5684.0 6077.2 6092.4 6101.7 6104.8 6105.6 5198.6 6144.9 6191.8 6217.7 6232.9 5573.6 5605.1 5615.8 5655.3 5738.7 6233.3 6247.3 6247.8 6251.3 6251.9 6005.4 6016.8 6036.2 6046.8 6069.3 5966.8 6120.4 6131.5 6139.4 6141.4 5938.5 5943.8 5977.1 5983.9 6047.8 5992.9 6033.9 6063.3 6068.9 6089.5 4890.8 4897.2 4920.9 4927.5 4930.8 4258.1 4305.7 4306.1 4372.0 4424.4 5432.2 5559.6 5589.9 5591.0 5643.0 6004.4 6107.8 6107.8 6113.5 6124.8 5513.3 5520.5 5527.6 5528.3 5530.3 5357.3 5366.0 5381.8 5384.2 5404.9 6252.8 6257.5 6266.1 6288.0 6289.3 6078.0 6112.2 6167.9 6177.9 6189.8 6222.9 6225.8 6230.1 6243.1 6244.5 5944.1 5956.5 6002.8 6069.6 6105.1 6106.5 6111.8 6114.5 6121.4 6122.8 5910.0 5923.4 5926.9 5929.2 5942.6 5780.6 5829.2 5831.4 5884.2 5982.7 4506.4 5772.5 5798.5 5840.4 5856.8 5857.5 5925.9 5926.4 5932.8 5933.6 5615.8 5675.6 5702.4 5715.3 5809.4 05:13:51 INFO - PROCESS | 1658 | 05:13:51 INFO - PROCESS | 1658 | [#1] ext.html Cycles:180 Average:65966.21 Median:198347.15 stddev:141743.24 (71.5%) stddev-sans-first:142055.57 05:13:51 INFO - PROCESS | 1658 | Values: 469.1 509.2 516.3 541.1 554.8 30531.0 31316.8 32528.2 32618.0 40981.2 41496.6 44300.1 44326.0 44338.8 44443.8 9647.4 9792.1 9802.2 9803.6 9805.7 8691.0 8861.2 9739.1 9860.5 9868.0 11794.3 11939.0 11994.6 12245.1 12317.7 10047.8 11887.6 11983.0 12000.5 12002.4 11870.3 11939.3 11944.7 11962.4 11963.7 41156.6 41391.0 41518.0 41545.0 41559.2 3009.3 3722.8 3753.6 3999.9 4369.7 31201.4 33130.8 33157.2 33282.3 33333.3 3147.1 3157.7 3346.6 3364.9 3368.6 356004.0 356240.0 357118.0 357363.0 360493.4 60110.1 61108.7 61177.8 61258.4 61285.4 59286.1 62872.0 66267.7 66416.7 66773.0 16316.9 16539.2 16539.8 16552.9 16588.8 12827.7 12916.2 12919.7 12932.5 12947.1 15751.5 16158.4 16167.6 17479.5 18443.0 5742.6 5945.3 6933.6 6942.8 7051.9 876.9 937.5 962.8 998.1 1054.9 28744.6 30301.0 30924.9 31107.7 31317.2 1041.7 1043.3 1125.4 1137.5 1165.4 13629.9 14996.6 15842.4 15913.3 16245.8 615998.0 618354.0 623591.0 624150.0 625128.0 51728.0 52378.2 52389.5 58370.0 58726.4 1381.9 1388.3 1427.9 1433.6 1433.7 28419.0 30975.2 31635.5 31974.2 32590.3 52630.7 53373.9 53402.0 53480.7 53533.5 475504.0 478227.0 619485.0 635793.0 637790.0 26641.7 26919.9 26946.2 26973.6 27003.0 39134.2 40398.0 45897.8 46502.8 46880.8 32888.0 33147.7 38799.6 38833.0 39332.8 38492.8 38892.2 39846.4 39864.6 39933.8 37168.6 37520.8 37717.6 37814.1 38663.2 34346.0 35084.6 36044.5 36200.9 36340.5 25649.6 25998.9 26006.5 26121.5 26630.5 05:13:51 INFO - PROCESS | 1658 | 05:13:51 INFO - PROCESS | 1658 | [#2] jquery.html Cycles:180 Average:34167.48 Median:18143.22 stddev:98264.74 (541.6%) stddev-sans-first:98507.60 05:13:51 INFO - PROCESS | 1658 | Values: 354.8 362.3 373.6 380.3 400.9 14925.8 15543.8 20944.4 20958.2 20982.5 20383.2 20574.4 20762.2 20766.7 21789.2 5384.6 5775.0 5915.4 6180.4 6185.5 5986.4 6155.1 6157.4 6162.4 6176.8 4179.8 6423.0 6682.6 6717.9 6808.5 6527.5 6725.3 6785.2 6790.8 6798.3 5887.3 5891.3 5896.6 5900.0 5900.2 23443.4 24438.9 26468.3 26816.9 27022.9 2139.6 2222.5 2403.9 2437.0 2678.3 9255.6 10161.8 10366.9 10623.8 10637.6 1872.4 1898.4 1987.6 2043.1 2130.1 99310.0 108367.5 108920.0 109479.0 109761.5 11074.4 11344.4 11499.0 11553.7 11688.8 14503.3 14527.7 14535.9 14562.0 14570.0 8348.0 8488.5 8534.4 8573.4 8628.0 8417.3 8448.7 8580.3 8591.7 8634.6 4424.8 4472.0 4507.6 4564.9 4603.7 1678.9 1889.6 1967.9 1970.3 1971.6 860.6 873.3 897.9 900.7 976.6 16274.6 20541.6 20704.5 21226.2 21404.0 876.7 935.0 950.4 967.8 970.5 9508.6 10556.0 11237.3 11285.0 11309.7 529606.0 597083.0 603519.0 607756.0 609147.5 92307.0 112405.0 127606.0 136410.0 137457.0 950.7 978.6 1006.4 1013.9 1015.5 18694.0 19005.4 19038.9 19103.7 19138.4 35931.6 43602.1 50959.0 53898.0 54107.0 80672.0 81397.0 81982.0 82315.2 82569.6 6991.6 7237.1 7249.4 7780.8 7977.6 12322.5 12651.6 12667.7 12980.8 13073.5 5404.3 9671.7 9793.6 9964.4 10804.9 10378.2 10620.3 10830.7 10995.4 11381.5 11015.1 11093.8 11389.6 11521.4 11629.0 7148.4 7362.9 7437.6 7456.3 7527.6 10023.5 10813.0 10884.4 11267.3 11329.3 05:13:51 INFO - PROCESS | 1658 | 05:13:51 INFO - PROCESS | 1658 | [#3] mootools.html Cycles:180 Average:5627.00 Median:5125.30 stddev:3134.10 (61.1%) stddev-sans-first:3127.81 05:13:51 INFO - PROCESS | 1658 | Values: 1534.5 1544.7 1748.6 1846.3 1895.7 8262.9 8828.0 8829.2 8831.4 8845.7 7708.9 7912.2 7914.1 8019.4 8670.1 2136.2 2170.5 2291.5 2364.7 2366.3 2352.6 2358.6 2359.4 2363.1 2369.2 2211.2 2251.7 2264.9 2268.1 2274.6 2307.0 2329.7 2330.5 2346.1 2352.7 1976.7 2321.4 2334.5 2338.6 2339.5 8754.6 8803.5 8853.6 8870.5 8872.1 5163.9 5329.0 5329.8 5335.8 5337.1 3428.9 3585.6 3656.8 3668.2 3679.9 1050.7 1081.9 1087.9 1100.7 1108.1 11504.9 11563.6 11566.0 11572.9 11598.1 4435.7 4569.6 4748.2 4754.9 4759.7 9872.0 9873.0 9881.0 9901.6 9948.2 5300.9 5312.7 5313.8 5318.1 5327.5 4660.3 4800.0 4872.0 4874.6 4878.4 3626.1 3692.5 3804.1 3892.9 3893.1 1217.7 1288.7 1291.1 1300.2 1300.7 719.6 745.3 893.4 903.5 949.9 5467.9 5505.9 5732.9 6024.8 6042.8 950.0 950.0 992.3 1012.1 1033.3 3281.8 3698.9 3956.2 3992.1 4042.2 10198.5 10567.8 10590.4 10593.9 10594.7 10552.0 10558.6 10561.8 10570.7 10571.5 7333.3 7735.4 8122.7 8125.0 8129.4 5526.6 5681.5 5828.9 5881.5 5894.7 9796.0 9862.9 9894.9 9903.7 9924.2 8919.6 9756.7 9900.5 9986.4 10156.2 6988.2 7134.0 7137.3 7142.9 7150.1 6686.2 7517.2 7778.8 8036.5 8220.6 7464.3 7512.5 7539.3 7601.6 7660.0 7355.8 7359.4 7367.5 7449.6 7466.1 6934.5 6955.2 6967.5 7015.9 7057.2 4886.2 4971.5 4977.6 4995.6 5119.1 4915.6 5082.4 5098.3 5131.5 5184.6 05:13:51 INFO - PROCESS | 1658 | 05:13:51 INFO - PROCESS | 1658 | [#4] prototype.html Cycles:180 Average:4510.68 Median:5126.99 stddev:1421.36 (27.7%) stddev-sans-first:1389.58 05:13:51 INFO - PROCESS | 1658 | Values: 289.0 297.7 308.8 313.9 317.6 4484.9 5222.7 5358.5 5378.5 5410.5 4481.7 4594.0 5348.2 5387.3 5393.8 3331.7 3733.9 3897.0 4042.5 4200.7 4061.1 4094.5 4102.4 4128.6 4133.7 4045.0 4063.4 4075.8 4078.9 4100.0 3854.9 3879.8 4063.7 4067.9 4074.8 3473.4 3518.5 3520.8 3521.5 3523.3 4728.6 4796.9 5222.7 5223.5 5283.8 3522.6 3738.9 4267.4 4484.0 4657.9 5558.4 5609.6 5614.4 5629.6 5649.6 3118.5 3360.7 3570.6 3862.2 3909.1 4175.5 5168.0 5460.0 5706.4 5872.3 3287.4 3871.2 3879.7 3889.7 3894.0 3173.2 3304.6 3397.9 3474.3 3755.2 3815.9 4191.6 4243.3 4600.7 4604.3 4760.3 5341.9 5358.5 5651.2 5698.2 1185.0 1333.5 1353.7 1354.0 1362.4 772.5 772.6 818.0 827.6 840.5 4244.8 5084.5 5085.6 5277.9 5289.0 5811.9 5816.8 5858.6 5862.4 5867.5 4918.4 5236.7 5262.3 5307.2 5377.9 2990.6 3063.3 3069.5 3070.2 3081.0 5394.8 5569.7 5925.2 6053.7 6086.9 4809.4 5022.6 5474.2 5726.5 5977.8 5999.9 6007.7 6016.6 6039.7 6045.5 5503.5 5807.5 5835.6 5872.7 5877.2 5730.3 5732.0 5738.5 5754.4 5759.7 4774.4 5758.3 6027.3 6032.9 6057.8 3315.7 3368.6 3610.4 3900.9 3954.1 5077.8 5083.9 5135.6 5329.6 5837.4 5423.3 5692.2 5709.8 5712.7 5722.8 5076.5 5443.5 5639.2 5662.8 5694.4 4957.9 5082.6 5590.7 5596.2 5628.9 4972.8 5118.4 5266.7 5409.6 5574.7 5338.7 5410.5 5445.8 5482.2 5486.6 05:13:51 INFO - PROCESS | 1658 | 05:13:51 INFO - PROCESS | 1658 | [#5] yui.html Cycles:180 Average:23099.98 Median:3494.42 stddev:36882.57 (1055.5%) stddev-sans-first:36947.76 05:13:51 INFO - PROCESS | 1658 | Values: 720.8 721.7 770.6 775.9 881.1 12272.9 16560.9 16569.6 16585.2 16637.3 14809.4 14963.0 14964.1 14981.1 15026.0 667.3 695.0 696.7 842.7 844.4 861.1 862.8 874.8 875.8 878.3 932.5 933.3 933.6 933.7 934.8 924.2 925.8 926.3 926.4 927.1 526.2 658.4 680.7 681.3 683.4 14754.6 14794.9 14803.8 14807.7 14830.3 13761.2 14143.8 14216.4 14277.4 14380.1 12779.2 12821.4 12906.2 13102.1 13781.0 4557.4 4703.8 4753.3 4753.4 4785.7 117016.4 119435.4 121520.0 121975.0 122490.0 10932.2 11252.5 11262.4 11443.4 11625.5 21806.2 22217.4 22385.4 22394.6 22412.8 7960.6 8057.8 8100.5 8172.9 8347.1 4343.3 4357.6 4417.4 4532.6 4694.9 2924.5 3358.3 3490.0 3493.9 3494.9 564.5 575.2 582.6 596.3 640.3 522.7 577.6 600.0 614.1 652.2 8397.4 9337.1 9351.7 9396.3 9413.7 554.7 593.3 598.4 653.8 680.5 4497.3 4560.7 4606.9 4618.8 4689.0 115757.0 138575.3 161094.0 162728.0 167380.0 31143.8 33084.8 38318.4 40768.2 96740.5 96083.0 108424.0 110878.0 111828.0 112061.0 8584.1 9105.0 9353.6 9898.8 10041.9 56808.7 60311.4 60587.7 61707.4 63787.0 91406.0 96094.0 96128.0 98760.5 99034.5 12946.9 12998.9 13061.5 13457.1 13681.6 14638.6 14870.8 15009.3 15017.4 15725.8 13704.1 13875.2 13922.2 13960.7 14582.9 13312.5 13899.0 13922.6 13945.4 13945.7 13417.4 13533.9 13560.3 13560.7 13561.3 10959.4 10989.7 11432.1 11507.4 11848.7 11350.1 11370.6 11488.8 11525.9 11568.3 05:13:51 INFO - PROCESS | 1658 | -------- Summary: end -------- 05:13:51 INFO - PROCESS | 1658 | 05:13:51 INFO - TEST-INFO | 1658: exit 0 05:13:51 INFO - TEST-OK | dromaeo_css | took 1117867ms 05:13:51 INFO - TEST-START | kraken 05:13:51 INFO - operating with platform_type : mac_ 05:13:51 INFO - Initialising browser for kraken test... 05:13:51 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/tmpzZTEDl/profile http://localhost:49266/getInfo.html) 05:13:54 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 05:13:54 INFO - PROCESS | 1663 | colorDepth:24 05:13:54 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 05:13:54 INFO - PROCESS | 1663 | __metrics 05:13:54 INFO - PROCESS | 1663 | 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:13:54 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:13:54 INFO - TEST-INFO | 1663: exit 0 05:13:54 INFO - Browser initialized. 05:13:54 INFO - Running cycle 1/1 for kraken test... 05:13:54 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/tmpzZTEDl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:14:06 INFO - PROCESS | 1664 | RSS: Main: 243773440 05:14:06 INFO - PROCESS | 1664 | 05:14:09 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 05:14:10 INFO - PROCESS | 1664 | RSS: Main: 294379520 05:14:10 INFO - PROCESS | 1664 | 05:14:12 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 05:14:13 INFO - PROCESS | 1664 | RSS: Main: 300814336 05:14:13 INFO - PROCESS | 1664 | 05:14:17 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 05:14:17 INFO - PROCESS | 1664 | RSS: Main: 395280384 05:14:17 INFO - PROCESS | 1664 | 05:14:19 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 05:14:20 INFO - PROCESS | 1664 | RSS: Main: 298385408 05:14:20 INFO - PROCESS | 1664 | 05:14:22 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:14:22 INFO - PROCESS | 1664 | RSS: Main: 303403008 05:14:22 INFO - PROCESS | 1664 | 05:14:25 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 05:14:26 INFO - PROCESS | 1664 | RSS: Main: 339427328 05:14:26 INFO - PROCESS | 1664 | 05:14:29 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 05:14:29 INFO - PROCESS | 1664 | RSS: Main: 341794816 05:14:29 INFO - PROCESS | 1664 | 05:14:32 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 05:14:33 INFO - PROCESS | 1664 | RSS: Main: 342589440 05:14:33 INFO - PROCESS | 1664 | 05:14:34 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:14:35 INFO - PROCESS | 1664 | RSS: Main: 315138048 05:14:35 INFO - PROCESS | 1664 | 05:14:37 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:14:38 INFO - PROCESS | 1664 | RSS: Main: 320061440 05:14:38 INFO - PROCESS | 1664 | 05:14:40 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:14:41 INFO - PROCESS | 1664 | RSS: Main: 318271488 05:14:41 INFO - PROCESS | 1664 | 05:14:43 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:14:44 INFO - PROCESS | 1664 | RSS: Main: 329474048 05:14:44 INFO - PROCESS | 1664 | 05:14:47 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:14:47 INFO - PROCESS | 1664 | RSS: Main: 330817536 05:14:47 INFO - PROCESS | 1664 | 05:14:49 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 05:14:50 INFO - PROCESS | 1664 | RSS: Main: 374378496 05:14:50 INFO - PROCESS | 1664 | 05:14:50 INFO - PROCESS | 1664 | __start_tp_report 05:14:50 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 05:14:50 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 05:14:50 INFO - PROCESS | 1664 | |i|pagename|runs| 05:14:50 INFO - PROCESS | 1664 | |0;ai-astar;101;111;112;114;113;112;113;112;112;111 05:14:50 INFO - PROCESS | 1664 | |1;audio-beat-detection;142;110;133;181;110;106;130;105;143;107 05:14:50 INFO - PROCESS | 1664 | |2;audio-dft;184;186;187;184;191;186;181;180;184;185 05:14:50 INFO - PROCESS | 1664 | |3;audio-fft;78;58;61;87;60;57;68;62;83;57 05:14:50 INFO - PROCESS | 1664 | |4;audio-oscillator;82;68;101;70;78;69;112;96;67;101 05:14:50 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;89;84;87;85;89;84;90;85;87;84 05:14:50 INFO - PROCESS | 1664 | |6;imaging-darkroom;93;94;95;95;94;106;149;121;94;94 05:14:50 INFO - PROCESS | 1664 | |7;imaging-desaturate;85;86;98;126;85;82;85;85;85;87 05:14:50 INFO - PROCESS | 1664 | |8;json-parse-financial;55;54;55;56;53;55;54;51;52;72 05:14:50 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;67;74;71;76;79;74;71;72;72;72 05:14:50 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;81;73;94;74;80;92;101;100;100;81 05:14:50 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;139;137;140;137;134;133;135;137;155;139 05:14:50 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;165;165;164;164;163;165;164;164;165;166 05:14:50 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;57;62;86;62;61;81;80;84;82;65 05:14:50 INFO - PROCESS | 1664 | __end_tp_report 05:14:50 INFO - PROCESS | 1664 | __start_cc_report 05:14:50 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,2042 05:14:50 INFO - PROCESS | 1664 | __end_cc_report 05:14:50 INFO - PROCESS | 1664 | __startTimestamp1470744890059__endTimestamp 05:14:50 INFO - PROCESS | 1664 | 05:14:50 INFO - PROCESS | 1664 | ------- Summary: start ------- 05:14:50 INFO - PROCESS | 1664 | Number of tests: 14 05:14:50 INFO - PROCESS | 1664 | 05:14:50 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:111.10 Median:112.00 stddev:3.67 (3.3%) stddev-sans-first:0.97 05:14:50 INFO - PROCESS | 1664 | Values: 101.0 111.0 112.0 114.0 113.0 112.0 113.0 112.0 112.0 111.0 05:14:50 INFO - PROCESS | 1664 | 05:14:50 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:126.70 Median:131.50 stddev:24.37 (18.5%) stddev-sans-first:25.21 05:14:50 INFO - PROCESS | 1664 | Values: 142.0 110.0 133.0 181.0 110.0 106.0 130.0 105.0 143.0 107.0 05:14:50 INFO - PROCESS | 1664 | 05:14:50 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:184.80 Median:185.50 stddev:3.08 (1.7%) stddev-sans-first:3.26 05:14:50 INFO - PROCESS | 1664 | Values: 184.0 186.0 187.0 184.0 191.0 186.0 181.0 180.0 184.0 185.0 05:14:50 INFO - PROCESS | 1664 | 05:14:50 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:67.10 Median:65.00 stddev:11.40 (17.5%) stddev-sans-first:11.38 05:14:50 INFO - PROCESS | 1664 | Values: 78.0 58.0 61.0 87.0 60.0 57.0 68.0 62.0 83.0 57.0 05:14:50 INFO - PROCESS | 1664 | 05:14:50 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:84.40 Median:69.50 stddev:16.70 (24.0%) stddev-sans-first:17.69 05:14:50 INFO - PROCESS | 1664 | Values: 82.0 68.0 101.0 70.0 78.0 69.0 112.0 96.0 67.0 101.0 05:14:50 INFO - PROCESS | 1664 | 05:14:50 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:86.40 Median:87.00 stddev:2.32 (2.7%) stddev-sans-first:2.26 05:14:50 INFO - PROCESS | 1664 | Values: 89.0 84.0 87.0 85.0 89.0 84.0 90.0 85.0 87.0 84.0 05:14:50 INFO - PROCESS | 1664 | 05:14:50 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:103.50 Median:94.00 stddev:18.25 (19.4%) stddev-sans-first:18.96 05:14:50 INFO - PROCESS | 1664 | Values: 93.0 94.0 95.0 95.0 94.0 106.0 149.0 121.0 94.0 94.0 05:14:50 INFO - PROCESS | 1664 | 05:14:50 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:90.40 Median:85.00 stddev:13.22 (15.6%) stddev-sans-first:13.87 05:14:50 INFO - PROCESS | 1664 | Values: 85.0 86.0 98.0 126.0 85.0 82.0 85.0 85.0 85.0 87.0 05:14:50 INFO - PROCESS | 1664 | 05:14:50 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:55.70 Median:55.00 stddev:5.93 (10.8%) stddev-sans-first:6.28 05:14:50 INFO - PROCESS | 1664 | Values: 55.0 54.0 55.0 56.0 53.0 55.0 54.0 51.0 52.0 72.0 05:14:50 INFO - PROCESS | 1664 | 05:14:50 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:72.80 Median:73.00 stddev:3.22 (4.4%) stddev-sans-first:2.65 05:14:50 INFO - PROCESS | 1664 | Values: 67.0 74.0 71.0 76.0 79.0 74.0 71.0 72.0 72.0 72.0 05:14:50 INFO - PROCESS | 1664 | 05:14:50 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:87.60 Median:80.50 stddev:11.01 (13.7%) stddev-sans-first:11.41 05:14:50 INFO - PROCESS | 1664 | Values: 81.0 73.0 94.0 74.0 80.0 92.0 101.0 100.0 100.0 81.0 05:14:50 INFO - PROCESS | 1664 | 05:14:50 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:138.60 Median:138.00 stddev:6.19 (4.5%) stddev-sans-first:6.56 05:14:50 INFO - PROCESS | 1664 | Values: 139.0 137.0 140.0 137.0 134.0 133.0 135.0 137.0 155.0 139.0 05:14:50 INFO - PROCESS | 1664 | 05:14:50 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.50 Median:165.00 stddev:0.85 (0.5%) stddev-sans-first:0.88 05:14:50 INFO - PROCESS | 1664 | Values: 165.0 165.0 164.0 164.0 163.0 165.0 164.0 164.0 165.0 166.0 05:14:50 INFO - PROCESS | 1664 | 05:14:50 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.00 Median:80.50 stddev:11.45 (14.2%) stddev-sans-first:10.78 05:14:50 INFO - PROCESS | 1664 | Values: 57.0 62.0 86.0 62.0 61.0 81.0 80.0 84.0 82.0 65.0 05:14:50 INFO - PROCESS | 1664 | -------- Summary: end -------- 05:14:50 INFO - PROCESS | 1664 | 05:14:50 INFO - TEST-INFO | 1664: exit 0 05:14:50 INFO - TEST-OK | kraken | took 59164ms 05:14:50 INFO - SUITE-END | took 1177s 05:14:51 INFO - Completed test suite (00:19:37) 05:14:51 INFO - javascript benchmark 05:14:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3286.917827054324, 3304.6034475862893, 3310.553075334829, 3326.085326535201, 3368.7810292330996, 5483.314585676791, 5540.252065971093, 5586.35756887958, 5613.214150019001, 5621.859453513659, 5547.667139971604, 5583.748731307091, 5630.071038278679, 5663.490285874271, 5672.489475947164, 5020.698758074509, 5044.82343117991, 5109.489051094898, 5118.692878427306, 5250.981387226285, 5175.327207463025, 5212.478752124776, 5222.556082732972, 5227.32044834171, 5232.372115346164, 4956.87607809804, 4979.502049795028, 4995.425526064513, 5053.873653158664, 5145.073886700374, 5038.345015148044, 5057.216131499624, 5064.316317297192, 5076.593872490192, 5085.542301192873, 4396.494403143647, 5050.04049230645, 5093.032323858492, 5121.615878809067, 5163.7676304566385, 5522.530584900288, 5525.944740552583, 5606.775728970836, 5637.267155269829, 5684.0, 6077.205261012639, 6092.390760923917, 6101.725422356005, 6104.847378815565, 6105.633661980295, 5198.584113270929, 6144.924638188344, 6191.81424557264, 6217.68911554425, 6232.87534249317, 5573.554115670696, 5605.1031834907035, 5615.803446879339, 5655.293088363955, 5738.684372359518, 6233.252009758768, 6247.3440288769025, 6247.812565622948, 6251.343608921089, 6251.937480625135, 6005.3694362091355, 6016.819495415057, 6036.245469316335, 6046.818595442144, 6069.302030266487, 5966.761329546856, 6120.357362476965, 6131.50947924165, 6139.3553676863285, 6141.416565426277, 5938.465538101592, 5943.821685349447, 5977.073553599199, 5983.850403739942, 6047.818565443044, 5992.8801423972145, 6033.909491357633, 6063.333033366326, 6068.908966365508, 6089.512838972872, 4890.804367825318, 4897.191963326011, 4920.852374428773, 4927.457979622239, 4930.778114984834, 4258.127083947783, 4305.74165550068, 4306.0957198987935, 4372.016296333363, 4424.446944131983, 5432.239486471897, 5559.555235581144, 5589.888202235932, 5590.972045139748, 5642.971785141048, 6004.399560044048, 6107.816765496953, 6107.816765497042, 6113.480354169857, 6124.816255512342, 5513.3108361454815, 5520.475554822286, 5527.55779537636, 5528.253685752372, 5530.336359636869, 5357.276767636397, 5365.973170134124, 5381.78472861089, 5384.192371144281, 5404.91892621611, 6252.812415627538, 6257.4994000480765, 6266.091416744638, 6288.025356069817, 6289.339619339903, 6077.969610152098, 6112.2299296386045, 6167.876642467036, 6177.938220617916, 6189.752409903734, 6222.937770622236, 6225.750969961332, 6230.127782110418, 6243.125962365181, 6244.500439964882, 5944.078667806497, 5956.463918247378, 6002.8499287516415, 6069.605475644069, 6105.145279660762, 6106.45041946227, 6111.816645500642, 6114.541409394402, 6121.3572574879, 6122.785702500391, 5909.96575599277, 5923.378045305181, 5926.851828704144, 5929.229200203946, 5942.64344139353, 5780.5953583248765, 5829.242198514166, 5831.387704291158, 5884.235049443716, 5982.7008649568215, 4506.369108324902, 5772.5381969441505, 5798.536117110537, 5840.445157710011, 5856.795012174553, 5857.50211232033, 5925.9407405925385, 5926.377730338252, 5932.822015339547, 5933.554983376351, 5615.803446879339, 5675.57433192504, 5702.40124786908, 5715.2855893013375, 5809.36097029318], "value": 5571.349438584879, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [469.11337571988923, 509.1802198460478, 516.2720564004753, 541.1260813785735, 554.8307766131328, 30531.0, 31316.843415782918, 32528.186795330068, 32618.0, 40981.180376392454, 41496.58503414965, 44300.11399772003, 44325.99999999992, 44338.77830610843, 44443.77778111113, 9647.372920760146, 9792.069753373418, 9802.2158227342, 9803.6078556858, 9805.65680201193, 8690.956545217265, 8861.202491775739, 9739.074787895173, 9860.506974651275, 9867.977064701754, 11794.29234245948, 11939.0, 11994.64016079519, 12245.14284000116, 12317.692057698585, 10047.849282260771, 11887.583934562292, 11982.981446577009, 12000.459979300953, 12002.399880006053, 11870.347130907796, 11939.34333611651, 11944.730501204831, 11962.401879906058, 11963.700907477296, 41156.588434115576, 41391.0, 41518.0, 41545.0, 41559.168816623496, 3009.337945651953, 3722.8324725387424, 3753.60587138352, 3999.920001599981, 4369.737815731066, 31201.37597248065, 33130.834345828356, 33157.17107072318, 33282.33435331304, 33333.33333333344, 3147.0716138739126, 3157.700018498262, 3346.6318704942437, 3364.8822291219935, 3368.6126095499085, 356004.0, 356240.0, 357118.0, 357363.0, 360493.3950660486, 60110.09834852481, 61108.69445652743, 61177.77644447086, 61258.38741612616, 61285.38714612797, 59286.11070833941, 62872.0, 66267.66866165638, 66416.66791666107, 66773.0, 16316.918415407847, 16539.173041348124, 16539.751903721455, 16552.917235413744, 16588.83411165873, 12827.743445131046, 12916.22502649844, 12919.741605167845, 12932.482700691868, 12947.093703440858, 15751.527454176394, 16158.353665853234, 16167.625751810992, 17479.47561573155, 18442.98563579016, 5742.59801813869, 5945.286565612154, 6933.583984960918, 6942.778803425585, 7051.929480705127, 876.8903887014123, 937.5218638494417, 962.8074385123003, 998.1216529454034, 1054.931429457083, 28744.568831467546, 30301.0, 30924.917627882915, 31107.68892311093, 31317.217069572987, 1041.6874937518864, 1043.3479075577764, 1125.4372813593204, 1137.4881303413497, 1165.4056431220138, 13629.86370136286, 14996.625084372978, 15842.44551440717, 15913.283902224197, 16245.756313655305, 615998.0, 618354.0, 623591.0, 624150.0, 625128.0, 51728.0, 52378.21432678513, 52389.47610523922, 58370.0, 58726.412735872094, 1381.9239941803096, 1388.2572823539358, 1427.9143251404741, 1433.5914264434587, 1433.7419264532275, 28419.0, 30975.225619358796, 31635.52546711847, 31974.200644984063, 32590.34819303648, 52630.73684631552, 53373.93252134858, 53402.0, 53480.73259633833, 53533.46466535285, 475504.0, 478227.0, 619485.0, 635793.0, 637790.0, 26641.733582663925, 26919.92320307244, 26946.191614251602, 26973.595396069468, 27003.0, 39134.217315654096, 40397.990050247805, 45897.77051114723, 46502.837429064544, 46880.7655961718, 32888.0, 33147.66852331446, 38799.6120038796, 38833.0, 39332.80333598314, 38492.80753596214, 38892.2221555573, 39846.402303966046, 39864.60135398609, 39933.80033099932, 37168.6283137176, 37520.81239593784, 37717.62282377141, 37814.054648634, 38663.226735465694, 34346.0, 35084.64915350814, 36044.4593331095, 36200.91397258086, 36340.45489317715, 25649.615255770805, 25998.870005650606, 26006.479870402865, 26121.477570448864, 26630.467390652466], "value": 20129.930582447396, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.8030842882199, 362.29171968800995, 373.60771190250233, 380.2755750295687, 400.89977505623597, 14925.776113358308, 15543.766843497357, 20944.371668849923, 20958.16167353304, 20982.475438114056, 20383.18467261308, 20574.38276851693, 20762.235210007166, 20766.688499672517, 21789.237376691817, 5384.596155288349, 5775.018246898037, 5915.408459154082, 6180.412860778219, 6185.474234690034, 5986.371430999748, 6155.07673848924, 6157.353477884825, 6162.414570615807, 6176.752929882822, 4179.770112643803, 6422.967885160568, 6682.599044057372, 6717.932820671804, 6808.455323574127, 6527.543071984963, 6725.293844146343, 6785.21969973452, 6790.796276111724, 6798.320167983212, 5887.264091988502, 5891.293044834604, 5896.557758168155, 5899.970500147472, 5900.232969713909, 23443.4139146521, 24438.877805610857, 26468.338291542674, 26816.865915670296, 27022.864885675444, 2139.5827813576357, 2222.4777177363308, 2403.9302510382886, 2436.951260974771, 2678.3170291575525, 9255.583498742573, 10161.847572286422, 10366.896331036669, 10623.787524249548, 10637.627683031134, 1872.3821139024226, 1898.4494496595971, 1987.5627361980341, 2043.1112466077304, 2130.094709748351, 99310.0, 108367.45816270947, 108920.0, 109479.0, 109761.45119274352, 11074.446277686244, 11344.376059316652, 11499.0, 11553.711157221136, 11688.824667629993, 14503.347349369304, 14527.709445811024, 14535.927320363542, 14562.0, 14569.9801013928, 8348.039975402906, 8488.533130677872, 8534.359923005799, 8573.356998225034, 8627.956860215785, 8417.284530814826, 8448.746537603882, 8580.313574913991, 8591.656333746705, 8634.582316951264, 4424.823007079681, 4472.016156445577, 4507.5943165115295, 4564.88160400704, 4603.6777425579885, 1678.8628978245279, 1889.574845659743, 1967.872088314255, 1970.290695349673, 1971.5958228563397, 860.5783166248493, 873.3144481582054, 897.8877640294963, 900.7027680865299, 976.5507866381402, 16274.593135171715, 20541.589168216553, 20704.48238794042, 21226.150953961667, 21404.0, 876.7150676030316, 934.9766255843658, 950.4148715493845, 967.7967626798311, 970.4856426094182, 9508.619655213852, 10556.0, 11237.269577477438, 11285.040771534348, 11309.71725706864, 529606.0, 597083.0, 603519.0, 607756.0, 609147.542622867, 92307.0, 112405.0, 127606.0, 136410.0, 137457.0, 950.6577632052456, 978.5596481583316, 1006.3609607899036, 1013.878334599838, 1015.5125539740818, 18694.0, 19005.429837104908, 19038.904805475882, 19103.713444298624, 19138.425847224604, 35931.64068359283, 43602.127957441306, 50959.0, 53898.0, 54107.0, 80672.0, 81397.0, 81982.0, 82315.17684823315, 82569.58715206386, 6991.615461149686, 7237.131544214728, 7249.420046396381, 7780.766577002699, 7977.641006154853, 12322.521297444364, 12651.620451386472, 12667.683307917376, 12980.8052879205, 13073.477060917457, 5404.32445944257, 9671.661491847752, 9793.559289831832, 9964.352317099365, 10804.86548912353, 10378.221633377376, 10620.309679870783, 10830.67507974762, 10995.395253261147, 11381.54473821038, 11015.118790496901, 11093.778124437305, 11389.601363952223, 11521.423928803359, 11628.953394194563, 7148.4281257500315, 7362.889556656547, 7437.590932498548, 7456.254374562501, 7527.585982771, 10023.54894029785, 10813.0, 10884.40135792539, 11267.26762760418, 11329.320240785579], "value": 9478.421456446826, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1534.46293797171, 1544.675618120195, 1748.5803407182273, 1846.2522678315288, 1895.6966885298355, 8262.876056859144, 8827.955860220705, 8829.16122968317, 8831.381803273773, 8845.690400835963, 7708.922910770891, 7912.208779122085, 7914.129445760962, 8019.438639295252, 8670.132986701326, 2136.188248465585, 2170.479085019592, 2291.450051987519, 2364.7280562735245, 2366.337425520857, 2352.635341522067, 2358.575456417844, 2359.398353419884, 2363.078399424226, 2369.1944738788807, 2211.159759291475, 2251.7072780538506, 2264.932052038433, 2268.0587556164255, 2274.6133157363206, 2306.97693023071, 2329.743728189906, 2330.545543618995, 2346.1084787780705, 2352.717673879128, 1976.6837306030966, 2321.419645088728, 2334.5447637710654, 2338.5790557699606, 2339.543788961153, 8754.56227188638, 8803.515806630632, 8853.601587928559, 8870.512121833297, 8872.112788721077, 5163.896722065575, 5329.0, 5329.760160792774, 5335.839924802262, 5337.146056630921, 3428.879989200391, 3585.6055833858254, 3656.8354424050717, 3668.248009158116, 3679.889603311878, 1050.6585359758033, 1081.8918108189198, 1087.9075278601329, 1100.7138144082517, 1108.0581505720154, 11504.884951150465, 11563.595274165447, 11566.016888564482, 11572.94213528927, 11598.072154227642, 4435.667324950641, 4569.588737013684, 4748.216544270157, 4754.857354279377, 4759.714417134983, 9872.012798720214, 9872.96333884946, 9880.962498937652, 9901.554430050664, 9948.204143668489, 5300.946990530122, 5312.7343632818975, 5313.787448502094, 5318.122509785842, 5327.467253274642, 4660.277656963176, 4800.015996720664, 4872.001239745844, 4874.634402419832, 4878.390201224847, 3626.147855254066, 3692.538432695913, 3804.1250512382308, 3892.8637497687446, 3893.0851249956236, 1217.7442737025324, 1288.7486940046601, 1291.1349395904767, 1300.232862611064, 1300.7268473620456, 719.5718547464251, 745.3031415626407, 893.3657103456619, 903.5256490342623, 949.9335046546676, 5467.890642187134, 5505.889882202334, 5732.856678582985, 6024.759009639653, 6042.848928776816, 949.9857502137473, 949.9863078493311, 992.3301771304341, 1012.1023081721771, 1033.302520798473, 3281.797402908505, 3698.8890333290046, 3956.228535435562, 3992.0818211811447, 4042.191561687674, 10198.490075496344, 10567.788644227227, 10590.364578125336, 10593.894061059445, 10594.682159535227, 10551.997560231766, 10558.63044793444, 10561.841572376421, 10570.682879513626, 10571.471426428649, 7333.339999400079, 7735.381169506426, 8122.675092996332, 8124.999999999882, 8129.430939834155, 5526.613137080366, 5681.545476361964, 5828.854278642898, 5881.529477641864, 5894.675792831264, 9796.020397960147, 9862.901370986197, 9894.851577226491, 9903.653372131941, 9924.15644670211, 8919.55402229873, 9756.707298781048, 9900.50497475109, 9986.400815951067, 10156.18750499973, 6988.231294557696, 7134.001239826324, 7137.286271372821, 7142.857142857009, 7150.141982962078, 6686.197656281277, 7517.210692877388, 7778.766637000899, 8036.4776289541, 8220.63007164667, 7464.328210460867, 7512.511686740344, 7539.283768042027, 7601.581912994839, 7660.0, 7355.77932662021, 7359.37445317154, 7367.521111127974, 7449.553026818408, 7466.141393739789, 6934.479914006369, 6955.200151982384, 6967.512274140761, 7015.859682806214, 7057.2237053925055, 4886.218205087312, 4971.502849715, 4977.626677999092, 4995.575376092927, 5119.0529751996, 4915.6313276505125, 5082.415522214993, 5098.337216162024, 5131.538161565477, 5184.559312458483], "value": 4537.036363231123, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [288.9696581858905, 297.74840259980317, 308.8193406856989, 313.9089663997439, 317.62837480148227, 4484.887877803057, 5222.738863056841, 5358.4641535846395, 5378.52587809607, 5410.513053825155, 4481.731096134235, 4593.977030114853, 5348.171033489815, 5387.299651045369, 5393.83818485446, 3331.7344205939953, 3733.9439908401328, 3897.0, 4042.5148982122187, 4200.726952748077, 4061.1471590965784, 4094.549599544063, 4102.384642303648, 4128.566500517448, 4133.731307465006, 4045.0, 4063.3701776224734, 4075.7787006081758, 4078.8980275493054, 4099.979500102495, 3854.8843534694006, 3879.7797827802997, 4063.674906007512, 4067.8983025424304, 4074.8370065197355, 3473.3747925373423, 3518.5074089627474, 3520.7887526748223, 3521.4893840393, 3523.2777280657406, 4728.645351598644, 4796.928046079277, 5222.686638801684, 5223.529882310572, 5283.77689609338, 3522.6085696150135, 3738.896715725941, 4267.359896015622, 4484.013517026249, 4657.906841863144, 5558.360788509332, 5609.635373700697, 5614.410486898899, 5629.634073785191, 5649.576281778842, 3118.469860123784, 3360.663933606644, 3570.6072332043454, 3862.246861861938, 3909.139989202371, 4175.45508161979, 5168.01807656544, 5459.972700136514, 5706.400827913092, 5872.297033860175, 3287.391832510993, 3871.2257548490416, 3879.741017545219, 3889.747166434173, 3894.045958740127, 3173.2066983254185, 3304.5704058472243, 3397.9320413592086, 3474.2704032153024, 3755.1550901047044, 3815.904602384907, 4191.559886211904, 4243.321068629066, 4600.700954437951, 4604.309353596987, 4760.309755085494, 5341.86645333862, 5358.544523715528, 5651.208830763697, 5698.230738850285, 1184.9585264515702, 1333.48660765604, 1353.7495563320817, 1353.9932300338435, 1362.4413990263945, 772.5441989226383, 772.6214155064091, 818.0183779464667, 827.6027506796651, 840.4621042532784, 4244.766537840386, 5084.491550844886, 5085.593152547787, 5277.920831187535, 5288.9735551322765, 5811.941880581225, 5816.76732930678, 5858.589898707135, 5862.413758624189, 5867.501262392659, 4918.409561020404, 5236.748198274779, 5262.289590905255, 5307.203919412119, 5377.90770307075, 2990.551417287424, 3063.3413816029215, 3069.5088785794164, 3070.2324418895278, 3081.0140754958647, 5394.78420863169, 5569.693666848362, 5925.229720136354, 6053.667048312385, 6086.93478641236, 4809.398825146856, 5022.598192144693, 5474.233607294982, 5726.513246374022, 5977.790777322773, 5999.850003749942, 6007.708342706252, 6016.639001659914, 6039.698015099228, 6045.516358691294, 5503.504684578407, 5807.477327040587, 5835.649861008353, 5872.735726892385, 5877.206577112035, 5730.341010783646, 5732.0, 5738.540916726735, 5754.4175351778595, 5759.740811663569, 4774.3554620127225, 5758.280214973129, 6027.30685971119, 6032.914075466459, 6057.7879774207695, 3315.718163956091, 3368.5620869286836, 3610.4042832932273, 3900.921981560296, 3954.070793363615, 5077.822276220315, 5083.881546059861, 5135.6119404477095, 5329.615694034239, 5837.445442682938, 5423.322084739408, 5692.1746346778655, 5709.800156994485, 5712.685802280915, 5722.828315150552, 5076.466970968143, 5443.482869127427, 5639.154126881001, 5662.8301150965535, 5694.402087780889, 4957.909259962802, 5082.567981721595, 5590.664560126405, 5596.216529685766, 5628.943710562842, 4972.751362431792, 5118.364042518032, 5266.683998960075, 5409.648372855751, 5574.662081100588, 5338.706371149624, 5410.538205116583, 5445.809396671098, 5482.21727008363, 5486.643368181056], "value": 4054.094332605425, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [720.776559266627, 721.7041013184594, 770.6146926536732, 775.8991331126956, 881.0704706534606, 12272.877271227286, 16560.917195414022, 16569.585760355996, 16585.170741462938, 16637.251323690434, 14809.407623695039, 14962.952593318472, 14964.102153870776, 14981.101133931972, 15026.0, 667.2560095493515, 694.9513534052618, 696.686491079014, 842.7050532313702, 844.4342290665238, 861.0872475176302, 862.779991102268, 874.8337815814975, 875.7810547363159, 878.3017501086651, 932.496451915968, 933.2813733425259, 933.5658918602849, 933.7152168588577, 934.7569631895689, 924.2098006204666, 925.8194652042854, 926.2913870888775, 926.393212445849, 927.0821886332516, 526.1765337247195, 658.3679667519187, 680.7106979533678, 681.2778454837838, 683.4293365040197, 14754.63113422173, 14794.852051479455, 14803.777943330859, 14807.70384592302, 14830.258487075604, 13761.17432954016, 14143.787843182361, 14216.431342746278, 14277.428902843978, 14380.065295755847, 12779.169353991962, 12821.423035963404, 12906.225626462443, 13102.148360356547, 13780.966427517977, 4557.430321209848, 4703.835365762182, 4753.33453316536, 4753.429588449409, 4785.688930219525, 117016.41491792486, 119435.4028229862, 121520.0, 121974.99999999911, 122490.0, 10932.234743567873, 11252.549898004152, 11262.436878156059, 11443.370614616277, 11625.476853541611, 21806.236781712563, 22217.44456388603, 22385.44036399103, 22394.55210895773, 22412.77587224107, 7960.601969901481, 8057.83884322322, 8100.497293842757, 8172.877406838902, 8347.081820999685, 4343.3484977253665, 4357.586029327209, 4417.3815665806815, 4532.5694059064335, 4694.882627934329, 2924.4735947529225, 3358.3115461330367, 3489.9704587146853, 3493.8951831445097, 3494.947575786365, 564.4806777764467, 575.221679289033, 582.6242073862335, 596.3410431473246, 640.2573015302227, 522.7360183107506, 577.6245440463732, 599.9850003749941, 614.1432701381636, 652.1554586809998, 8397.412181147261, 9337.112974267433, 9351.672691455902, 9396.342256042011, 9413.670521531714, 554.7226386806597, 593.3413910559276, 598.35676647604, 653.8038588423454, 680.5100327764002, 4497.280435130363, 4560.703554268962, 4606.861794146181, 4618.769061546909, 4688.953110468851, 115757.0, 138575.30712346575, 161094.0, 162728.0, 167380.0, 31143.844280778452, 33084.834575826964, 38318.42522362167, 40768.18463630711, 96740.51629741947, 96083.0, 108424.0, 110878.0, 111828.0, 112061.0, 8584.141585841366, 9105.0, 9353.625854965998, 9898.802023959624, 10041.949790251001, 56808.715956419954, 60311.396886030576, 60587.69706151618, 61707.38292617016, 63787.0, 91406.0, 96094.0, 96128.0, 98760.50619746855, 99034.50482747541, 12946.870531294566, 12998.870011300145, 13061.477540898257, 13457.125286856322, 13681.589552313446, 14638.560843174722, 14870.77693834615, 15009.324580394126, 15017.399304027716, 15725.842741572895, 13704.10923289983, 13875.236861972688, 13922.234277114856, 13960.650983725489, 14582.854171458574, 13312.534061307808, 13899.0, 13922.582322530341, 13945.3724582392, 13945.721085578434, 13417.396217170055, 13533.86466135326, 13560.321983900963, 13560.728785424433, 13561.321933903462, 10959.397233152253, 10989.7120982535, 11432.085433165494, 11507.367094809866, 11848.711479994325, 11350.148738844455, 11370.602028928948, 11488.817318844362, 11525.942370288094, 11568.305901645928], "value": 7703.827758773729, "unit": "score"}], "name": "dromaeo_css", "value": 7294.4594696705435, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 111.0, 112.0, 114.0, 113.0, 112.0, 113.0, 112.0, 112.0, 111.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 110.0, 133.0, 181.0, 110.0, 106.0, 130.0, 105.0, 143.0, 107.0], "value": 126.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 187.0, 184.0, 191.0, 186.0, 181.0, 180.0, 184.0, 185.0], "value": 184.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 58.0, 61.0, 87.0, 60.0, 57.0, 68.0, 62.0, 83.0, 57.0], "value": 67.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 68.0, 101.0, 70.0, 78.0, 69.0, 112.0, 96.0, 67.0, 101.0], "value": 84.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 84.0, 87.0, 85.0, 89.0, 84.0, 90.0, 85.0, 87.0, 84.0], "value": 86.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 95.0, 95.0, 94.0, 106.0, 149.0, 121.0, 94.0, 94.0], "value": 103.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 98.0, 126.0, 85.0, 82.0, 85.0, 85.0, 85.0, 87.0], "value": 90.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 56.0, 53.0, 55.0, 54.0, 51.0, 52.0, 72.0], "value": 55.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 74.0, 71.0, 76.0, 79.0, 74.0, 71.0, 72.0, 72.0, 72.0], "value": 72.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 73.0, 94.0, 74.0, 80.0, 92.0, 101.0, 100.0, 100.0, 81.0], "value": 87.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 137.0, 140.0, 137.0, 134.0, 133.0, 135.0, 137.0, 155.0, 139.0], "value": 138.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 165.0, 164.0, 164.0, 163.0, 165.0, 164.0, 164.0, 165.0, 166.0], "value": 164.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 62.0, 86.0, 62.0, 61.0, 81.0, 80.0, 84.0, 82.0, 65.0], "value": 72.0, "unit": "score"}], "name": "kraken", "value": 1445.6, "alertThreshold": 2.0}]} 05:14:51 INFO - Return code: 0 05:14:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:14:51 INFO - # TBPL SUCCESS # 05:14:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:14:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:14:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:14:51 INFO - 2016-08-09 05:14:51.357 screenresolution[1668:10254] starting screenresolution argv=screenresolution get 05:14:51 INFO - 2016-08-09 05:14:51.360 screenresolution[1668:10254] Display 0: 1600x1200x32@60 05:14:51 INFO - 2016-08-09 05:14:51.365 screenresolution[1669:10257] starting screenresolution argv=screenresolution list 05:14:51 INFO - Available Modes on Display 0 05:14:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:14:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:14:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:14:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:14:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:14:51 INFO - Intel Iris: 05:14:51 INFO - Chipset Model: Intel Iris 05:14:51 INFO - Type: GPU 05:14:51 INFO - Bus: Built-In 05:14:51 INFO - VRAM (Dynamic, Max): 1536 MB 05:14:51 INFO - Vendor: Intel (0x8086) 05:14:51 INFO - Device ID: 0x0a2e 05:14:51 INFO - Revision ID: 0x0009 05:14:51 INFO - Displays: 05:14:51 INFO - Display: 05:14:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:14:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:14:51 INFO - Display Serial Number: 1600x1200 60 05:14:51 INFO - Main Display: Yes 05:14:51 INFO - Mirror: Off 05:14:51 INFO - Online: Yes 05:14:51 INFO - Rotation: Supported 05:14:51 INFO - Return code: 0 05:14:51 INFO - Running post-action listener: _resource_record_post_action 05:14:51 INFO - [mozharness: 2016-08-09 12:14:51.599716Z] Finished run-tests step (success) 05:14:51 INFO - Running post-run listener: _resource_record_post_run 05:14:51 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 38956544; Write bytes: 707253248; Read time: 374; Write time: 2006 05:14:51 INFO - TinderboxPrint: CPU usage
53.5% 05:14:51 INFO - TinderboxPrint: I/O read bytes / time
38,956,544 / 374 05:14:51 INFO - TinderboxPrint: I/O write bytes / time
707,253,248 / 2,006 05:14:51 INFO - TinderboxPrint: CPU idle
2,257.5 (46.5%) 05:14:51 INFO - TinderboxPrint: CPU system
972.5 (20.0%) 05:14:51 INFO - TinderboxPrint: CPU user
1,623.6 (33.5%) 05:14:51 INFO - TinderboxPrint: Swap in / out
449,417,216 / 0 05:14:51 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 408005120; Write bytes: 408741376; Read time: 30757; Write time: 703 05:14:51 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 35442176; Write bytes: 295138816; Read time: 344; Write time: 1248 05:14:52 INFO - Running post-run listener: _upload_blobber_files 05:14:52 INFO - Blob upload gear active. 05:14:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:14:52 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 05:14:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:14:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:14:52 INFO - (blobuploader) - INFO - Open directory for files ... 05:14:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:14:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:14:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:14:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:14:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:14:53 INFO - (blobuploader) - INFO - Done attempting. 05:14:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:14:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:14:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:14:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:14:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:14:54 INFO - (blobuploader) - INFO - Done attempting. 05:14:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:14:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:14:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:14:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:14:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:14:56 INFO - (blobuploader) - INFO - Done attempting. 05:14:56 INFO - (blobuploader) - INFO - Iteration through files over. 05:14:56 INFO - Return code: 0 05:14:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:14:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:14:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3724f98939ca2921b6c6f5159dc8abcc93e5aa67488a1b091083929f1ab69c3ae1c4c5a0191d78fe3c020d36aae3db702a1fa5d1e94007f125e03e6680027db6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/626f00e4c3453907f3a6b9ca1763fbd0a0fb3fc4edddd6b5ae38625a4b8673de5886d8a0a348c404ee5299cbee90c4e404756abcc98f9184a2871fc2e26f3ac5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2cd5105fe14a80ca6992020f13264f5c0d46e24eff82b86cf3a909b9e91723ea575216f3516a2ea7dd61ff646538c9767b4fe4e000adddbc586634fb969032c6"} 05:14:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:14:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:14:56 INFO - Contents: 05:14:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3724f98939ca2921b6c6f5159dc8abcc93e5aa67488a1b091083929f1ab69c3ae1c4c5a0191d78fe3c020d36aae3db702a1fa5d1e94007f125e03e6680027db6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/626f00e4c3453907f3a6b9ca1763fbd0a0fb3fc4edddd6b5ae38625a4b8673de5886d8a0a348c404ee5299cbee90c4e404756abcc98f9184a2871fc2e26f3ac5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2cd5105fe14a80ca6992020f13264f5c0d46e24eff82b86cf3a909b9e91723ea575216f3516a2ea7dd61ff646538c9767b4fe4e000adddbc586634fb969032c6"} 05:14:56 INFO - Running post-run listener: copy_logs_to_upload_dir 05:14:56 INFO - Copying logs to upload dir... 05:14:56 INFO - mkdir: /builds/slave/test/build/upload/logs 05:14:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.089202 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-09 05:14:56.812814) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 05:14:56.816463) ========= 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.HCMLwDohjQ/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.CkI7tXI73V/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3724f98939ca2921b6c6f5159dc8abcc93e5aa67488a1b091083929f1ab69c3ae1c4c5a0191d78fe3c020d36aae3db702a1fa5d1e94007f125e03e6680027db6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/626f00e4c3453907f3a6b9ca1763fbd0a0fb3fc4edddd6b5ae38625a4b8673de5886d8a0a348c404ee5299cbee90c4e404756abcc98f9184a2871fc2e26f3ac5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2cd5105fe14a80ca6992020f13264f5c0d46e24eff82b86cf3a909b9e91723ea575216f3516a2ea7dd61ff646538c9767b4fe4e000adddbc586634fb969032c6"} build_url:https://queue.taskcluster.net/v1/task/XwUjCl45RfCsc65tSu91sg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011129 build_url: 'https://queue.taskcluster.net/v1/task/XwUjCl45RfCsc65tSu91sg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3724f98939ca2921b6c6f5159dc8abcc93e5aa67488a1b091083929f1ab69c3ae1c4c5a0191d78fe3c020d36aae3db702a1fa5d1e94007f125e03e6680027db6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/626f00e4c3453907f3a6b9ca1763fbd0a0fb3fc4edddd6b5ae38625a4b8673de5886d8a0a348c404ee5299cbee90c4e404756abcc98f9184a2871fc2e26f3ac5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2cd5105fe14a80ca6992020f13264f5c0d46e24eff82b86cf3a909b9e91723ea575216f3516a2ea7dd61ff646538c9767b4fe4e000adddbc586634fb969032c6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 05:14:56.849137) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 05:14:56.849459) ========= 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.HCMLwDohjQ/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.CkI7tXI73V/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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.005303 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 05:14:56.898991) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 05:14:56.899351) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 05:14:56.899860) ========= ========= Total master_lag: 0.23 =========