builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0063 starttime: 1476719017.35 results: success (0) buildid: 20161017074849 builduid: d6d32be62bc44a0389202bd99a3b4f37 revision: dabadf63cb447fe9253c605b41afb116334e24ed ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-17 08:43:37.345986) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-17 08:43:37.346413) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-17 08:43:37.346702) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-17 08:43:37.370991) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 08:43:37.371356) ========= 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.Q8T7yprdC8/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.Vmrm2gfqV2/Listeners TMPDIR=/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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-10-17 08:43:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2016-10-17 08:43:37 (161 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.096722 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 08:43:37.490708) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 08:43:37.491019) ========= 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.Q8T7yprdC8/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.Vmrm2gfqV2/Listeners TMPDIR=/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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.024675 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 08:43:37.539439) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-17 08:43:37.539801) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev dabadf63cb447fe9253c605b41afb116334e24ed --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 dabadf63cb447fe9253c605b41afb116334e24ed --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Q8T7yprdC8/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.Vmrm2gfqV2/Listeners TMPDIR=/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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-10-17 08:43:37,613 truncating revision to first 12 chars 2016-10-17 08:43:37,613 Setting DEBUG logging. 2016-10-17 08:43:37,613 attempt 1/10 2016-10-17 08:43:37,613 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/dabadf63cb44?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-17 08:43:39,250 unpacking tar archive at: autoland-dabadf63cb44/testing/mozharness/ program finished with exit code 0 elapsedTime=1.892550 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-17 08:43:39.456887) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-17 08:43:39.457211) ========= script_repo_revision: dabadf63cb447fe9253c605b41afb116334e24ed ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-17 08:43:39.457611) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-17 08:43:39.457878) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-17 08:43:39.475844) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 49 secs) (at 2016-10-17 08:43:39.476176) ========= /tools/buildbot/bin/python -u 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', '-u', '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.Q8T7yprdC8/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.Vmrm2gfqV2/Listeners TMPDIR=/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 08:43:39 INFO - MultiFileLogger online at 20161017 08:43:39 in /builds/slave/test 08:43:39 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 08:43:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:43:39 INFO - {'append_to_log': False, 08:43:39 INFO - 'base_work_dir': '/builds/slave/test', 08:43:39 INFO - 'blob_upload_branch': 'Autoland', 08:43:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:43:39 INFO - 'branch': 'Autoland', 08:43:39 INFO - 'buildbot_json_path': 'buildprops.json', 08:43:39 INFO - 'config_files': ('talos/mac_config.py',), 08:43:39 INFO - 'default_actions': ('clobber', 08:43:39 INFO - 'read-buildbot-config', 08:43:39 INFO - 'download-and-extract', 08:43:39 INFO - 'populate-webroot', 08:43:39 INFO - 'create-virtualenv', 08:43:39 INFO - 'install', 08:43:39 INFO - 'run-tests'), 08:43:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:43:39 INFO - 'download_minidump_stackwalk': True, 08:43:39 INFO - 'download_symbols': 'ondemand', 08:43:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:43:39 INFO - 'tooltool.py': '/tools/tooltool.py', 08:43:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:43:39 INFO - '/tools/misc-python/virtualenv.py')}, 08:43:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:43:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:43:39 INFO - 'installer_path': 'installer.exe', 08:43:39 INFO - 'log_level': 'info', 08:43:39 INFO - 'log_name': 'talos', 08:43:39 INFO - 'log_to_console': True, 08:43:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:43:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:43:39 INFO - 'opt_config_files': (), 08:43:39 INFO - 'pip_index': False, 08:43:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:43:39 INFO - 'cmd': ('bash', 08:43:39 INFO - '-c', 08:43:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:43:39 INFO - 'enabled': True, 08:43:39 INFO - 'halt_on_failure': False, 08:43:39 INFO - 'name': 'check_screen_resolution'},), 08:43:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:43:39 INFO - 'cmd': ('bash', 08:43:39 INFO - '-c', 08:43:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:43:39 INFO - 'enabled': True, 08:43:39 INFO - 'halt_on_failure': False, 08:43:39 INFO - 'name': 'check_screen_resolution'},), 08:43:39 INFO - 'run_cmd_checks_enabled': True, 08:43:39 INFO - 'sps_profile': False, 08:43:39 INFO - 'sps_profile_interval': 0, 08:43:39 INFO - 'suite': 'dromaeojs', 08:43:39 INFO - 'system_bits': '32', 08:43:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:43:39 INFO - 'title': 't-yosemite-r7-0063', 08:43:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:43:39 INFO - 'use_talos_json': True, 08:43:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:43:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:43:39 INFO - 'work_dir': 'build'} 08:43:39 INFO - [mozharness: 2016-10-17 15:43:39.682295Z] Running clobber step. 08:43:39 INFO - Running pre-action listener: _resource_record_pre_action 08:43:39 INFO - Running main action method: clobber 08:43:39 INFO - rmtree: /builds/slave/test/build 08:43:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:43:41 INFO - Running post-action listener: _resource_record_post_action 08:43:41 INFO - [mozharness: 2016-10-17 15:43:41.821931Z] Finished clobber step (success) 08:43:41 INFO - [mozharness: 2016-10-17 15:43:41.822069Z] Running read-buildbot-config step. 08:43:41 INFO - Running pre-action listener: _resource_record_pre_action 08:43:41 INFO - Running main action method: read_buildbot_config 08:43:41 INFO - Using buildbot properties: 08:43:41 INFO - { 08:43:41 INFO - "project": "", 08:43:41 INFO - "product": "firefox", 08:43:41 INFO - "script_repo_revision": "production", 08:43:41 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 08:43:41 INFO - "repository": "", 08:43:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 08:43:41 INFO - "buildid": "20161017074849", 08:43:41 INFO - "pgo_build": "False", 08:43:41 INFO - "basedir": "/builds/slave/test", 08:43:41 INFO - "buildnumber": 254, 08:43:41 INFO - "slavename": "t-yosemite-r7-0063", 08:43:41 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 08:43:41 INFO - "platform": "yosemite_r7", 08:43:41 INFO - "scheduler": "tests-autoland-macosx64-talos", 08:43:41 INFO - "branch": "autoland", 08:43:41 INFO - "repo_path": "integration/autoland", 08:43:41 INFO - "revision": "dabadf63cb447fe9253c605b41afb116334e24ed", 08:43:41 INFO - "stage_platform": "macosx64", 08:43:41 INFO - "builduid": "d6d32be62bc44a0389202bd99a3b4f37", 08:43:41 INFO - "slavebuilddir": "test" 08:43:41 INFO - } 08:43:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/CPhvVpesSfu8e9An5szyVw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 08:43:41 INFO - Running post-action listener: _resource_record_post_action 08:43:41 INFO - [mozharness: 2016-10-17 15:43:41.825541Z] Finished read-buildbot-config step (success) 08:43:41 INFO - [mozharness: 2016-10-17 15:43:41.825652Z] Running download-and-extract step. 08:43:41 INFO - Running pre-action listener: _resource_record_pre_action 08:43:41 INFO - Running main action method: download_and_extract 08:43:41 INFO - mkdir: /builds/slave/test/build/tests 08:43:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:41 INFO - trying https://queue.taskcluster.net/v1/task/CPhvVpesSfu8e9An5szyVw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 08:43:41 INFO - Downloading https://queue.taskcluster.net/v1/task/CPhvVpesSfu8e9An5szyVw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 08:43:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CPhvVpesSfu8e9An5szyVw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 08:43:42 INFO - Downloaded 1199 bytes. 08:43:42 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 08:43:42 INFO - Using the following test package requirements: 08:43:42 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:43:42 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:43:42 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 08:43:42 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:43:42 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 08:43:42 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:43:42 INFO - u'jsshell-mac.zip'], 08:43:42 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:43:42 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 08:43:42 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:43:42 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:43:42 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 08:43:42 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:43:42 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 08:43:42 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:43:42 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 08:43:42 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:43:42 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 08:43:42 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:43:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CPhvVpesSfu8e9An5szyVw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:43:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CPhvVpesSfu8e9An5szyVw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:43:42 INFO - Fetch https://queue.taskcluster.net/v1/task/CPhvVpesSfu8e9An5szyVw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:43:43 INFO - Http code: 200 08:43:43 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 08:43:43 INFO - x-amz-cf-id: 9CtQua9UX_AWarYTSnq2ZPoj8PgQFzlBM6nMZYsw7SkOXzQOTkDJCw== 08:43:43 INFO - x-amz-version-id: MHAZAmdsnhI4YmgFQZCnC3k9baWu0.Ec 08:43:43 INFO - Expected file size: 16483084 08:43:43 INFO - Obtained file size: 16483084 08:43:50 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:43:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CPhvVpesSfu8e9An5szyVw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:43:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CPhvVpesSfu8e9An5szyVw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:43:50 INFO - Fetch https://queue.taskcluster.net/v1/task/CPhvVpesSfu8e9An5szyVw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:43:50 INFO - Http code: 200 08:43:50 INFO - via: 1.1 d2bd759914e30b1d5aee2929535c55f9.cloudfront.net (CloudFront) 08:43:50 INFO - x-amz-cf-id: CtaApYGKb71xJWxq4ZGEw0-yCsSLCeXRykEx2b1blhTlKKSiIYeV4g== 08:43:50 INFO - x-amz-version-id: MHAZAmdsnhI4YmgFQZCnC3k9baWu0.Ec 08:43:51 INFO - Expected file size: 16483084 08:43:51 INFO - Obtained file size: 16483084 08:43:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CPhvVpesSfu8e9An5szyVw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 08:43:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CPhvVpesSfu8e9An5szyVw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:43:57 INFO - Fetch https://queue.taskcluster.net/v1/task/CPhvVpesSfu8e9An5szyVw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 08:43:58 INFO - Http code: 200 08:43:58 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 08:43:58 INFO - x-amz-cf-id: PkUDJ62QgIDQlc74Pode_GvmK-bp3HOTJ3ZGBPKaJWxGrD6NnNVvcA== 08:43:58 INFO - x-amz-version-id: SeBurOvMN4L4553P5Pg9Kt8rar6f.0Kj 08:43:58 INFO - Expected file size: 14409028 08:43:58 INFO - Obtained file size: 14409028 08:43: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')]} 08:43:58 INFO - trying https://queue.taskcluster.net/v1/task/CPhvVpesSfu8e9An5szyVw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:43:58 INFO - Downloading https://queue.taskcluster.net/v1/task/CPhvVpesSfu8e9An5szyVw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 08:43:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CPhvVpesSfu8e9An5szyVw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 08:44:34 INFO - Downloaded 111701679 bytes. 08:44:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CPhvVpesSfu8e9An5szyVw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:44:34 INFO - mkdir: /builds/slave/test/properties 08:44:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:44:34 INFO - Writing to file /builds/slave/test/properties/build_url 08:44:34 INFO - Contents: 08:44:34 INFO - build_url:https://queue.taskcluster.net/v1/task/CPhvVpesSfu8e9An5szyVw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:44:36 INFO - Running post-action listener: _resource_record_post_action 08:44:36 INFO - Running post-action listener: set_extra_try_arguments 08:44:36 INFO - [mozharness: 2016-10-17 15:44:36.005524Z] Finished download-and-extract step (success) 08:44:36 INFO - [mozharness: 2016-10-17 15:44:36.005679Z] Running populate-webroot step. 08:44:36 INFO - Running pre-action listener: _resource_record_pre_action 08:44:36 INFO - Running main action method: populate_webroot 08:44:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:44:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:44:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:44:36 INFO - '--tppagecycles', 08:44:36 INFO - '7'], 08:44:36 INFO - 'tests': ['tsvgm']}}, 08:44:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:44:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:44:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:44:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:44:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:44:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:44:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:36 INFO - 'talos_options': ['--disable-e10s'], 08:44:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:44:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:44:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:36 INFO - 'talos_options': ['--disable-e10s'], 08:44:36 INFO - 'tests': ['damp', 'tps']}, 08:44:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:36 INFO - 'tests': ['damp', 'tps']}, 08:44:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:44:36 INFO - 'tests': ['dromaeo_dom']}, 08:44:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:44:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:44:36 INFO - 'tests': ['basic_compositor_video']}, 08:44:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:44:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:44:36 INFO - 'tests': ['a11yr', 08:44:36 INFO - 'ts_paint', 08:44:36 INFO - 'tpaint', 08:44:36 INFO - 'sessionrestore', 08:44:36 INFO - 'sessionrestore_no_auto_restore', 08:44:36 INFO - 'tabpaint']}, 08:44:36 INFO - 'other-e10s': {'tests': ['a11yr', 08:44:36 INFO - 'ts_paint', 08:44:36 INFO - 'tpaint', 08:44:36 INFO - 'sessionrestore', 08:44:36 INFO - 'sessionrestore_no_auto_restore', 08:44:36 INFO - 'tabpaint']}, 08:44:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:44:36 INFO - 'ts_paint', 08:44:36 INFO - 'tpaint', 08:44:36 INFO - 'sessionrestore', 08:44:36 INFO - 'sessionrestore_no_auto_restore', 08:44:36 INFO - 'tabpaint']}, 08:44:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:44:36 INFO - 'ts_paint', 08:44:36 INFO - 'tpaint', 08:44:36 INFO - 'sessionrestore', 08:44:36 INFO - 'sessionrestore_no_auto_restore', 08:44:36 INFO - 'tabpaint']}, 08:44:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:44:36 INFO - 'tests': ['a11yr', 08:44:36 INFO - 'ts_paint', 08:44:36 INFO - 'tpaint', 08:44:36 INFO - 'sessionrestore', 08:44:36 INFO - 'sessionrestore_no_auto_restore', 08:44:36 INFO - 'tabpaint']}, 08:44:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:44:36 INFO - 'tests': ['a11yr', 08:44:36 INFO - 'ts_paint', 08:44:36 INFO - 'tpaint', 08:44:36 INFO - 'sessionrestore', 08:44:36 INFO - 'sessionrestore_no_auto_restore', 08:44:36 INFO - 'tabpaint']}, 08:44:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:44:36 INFO - 'tests': ['tsvgx', 08:44:36 INFO - 'tsvgr_opacity', 08:44:36 INFO - 'tart', 08:44:36 INFO - 'tscrollx', 08:44:36 INFO - 'cart']}, 08:44:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:44:36 INFO - 'tsvgr_opacity', 08:44:36 INFO - 'tart', 08:44:36 INFO - 'tscrollx', 08:44:36 INFO - 'cart']}, 08:44:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:36 INFO - 'talos_options': ['--disable-e10s'], 08:44:36 INFO - 'tests': ['tp5o']}, 08:44:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:36 INFO - 'tests': ['tp5o']}, 08:44:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:36 INFO - 'talos_options': ['--disable-e10s', 08:44:36 INFO - '--xperf_path', 08:44:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:44:36 INFO - 'tests': ['tp5n']}, 08:44:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:36 INFO - 'talos_options': ['--xperf_path', 08:44:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:44:36 INFO - 'tests': ['tp5n']}}, 08:44:36 INFO - 'talos.zip': {'path': '', 08:44:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:44:36 INFO - Running post-action listener: _resource_record_post_action 08:44:36 INFO - [mozharness: 2016-10-17 15:44:36.024330Z] Finished populate-webroot step (success) 08:44:36 INFO - [mozharness: 2016-10-17 15:44:36.024465Z] Running create-virtualenv step. 08:44:36 INFO - Running pre-action listener: _resource_record_pre_action 08:44:36 INFO - Running main action method: create_virtualenv 08:44:36 INFO - Creating virtualenv /builds/slave/test/build/venv 08:44:36 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 08:44:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:44:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:44:36 INFO - Using real prefix '/tools/python27' 08:44:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:44:36 INFO - Installing distribute.............................................................................................................................................................................................done. 08:44:39 INFO - Installing pip.................done. 08:44:39 INFO - Return code: 0 08:44:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:44:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:44:40 INFO - Reading from file tmpfile_stdout 08:44:40 INFO - Output received: 08:44:40 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:44:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:44:40 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')]} 08:44:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:40 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')]} 08:44:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:40 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 0x10f016490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef8c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8750c69440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee2ad30>, '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 0x10ec50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee22b20>, '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.Q8T7yprdC8/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.Vmrm2gfqV2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 08:44:40 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 08:44:40 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 08:44:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q8T7yprdC8/Render', 08:44:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:40 INFO - 'HOME': '/Users/cltbld', 08:44:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:40 INFO - 'LOGNAME': 'cltbld', 08:44:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:40 INFO - 'MOZ_NO_REMOTE': '1', 08:44:40 INFO - 'NO_EM_RESTART': '1', 08:44:40 INFO - 'PAGER': '/bin/cat', 08:44:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:40 INFO - 'PWD': '/builds/slave/test', 08:44:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:40 INFO - 'SHELL': '/bin/bash', 08:44:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vmrm2gfqV2/Listeners', 08:44:40 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 08:44:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:40 INFO - 'USER': 'cltbld', 08:44:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:40 INFO - 'XPC_FLAGS': '0x0', 08:44:40 INFO - 'XPC_SERVICE_NAME': '0', 08:44:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:41 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 08:44:41 INFO - Cleaning up... 08:44:41 INFO - Return code: 0 08:44:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:44:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:41 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 0x10f016490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef8c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8750c69440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee2ad30>, '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 0x10ec50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee22b20>, '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.Q8T7yprdC8/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.Vmrm2gfqV2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 08:44:41 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 08:44:41 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 08:44:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q8T7yprdC8/Render', 08:44:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:41 INFO - 'HOME': '/Users/cltbld', 08:44:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:41 INFO - 'LOGNAME': 'cltbld', 08:44:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:41 INFO - 'MOZ_NO_REMOTE': '1', 08:44:41 INFO - 'NO_EM_RESTART': '1', 08:44:41 INFO - 'PAGER': '/bin/cat', 08:44:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:41 INFO - 'PWD': '/builds/slave/test', 08:44:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:41 INFO - 'SHELL': '/bin/bash', 08:44:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vmrm2gfqV2/Listeners', 08:44:41 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 08:44:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:41 INFO - 'USER': 'cltbld', 08:44:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:41 INFO - 'XPC_FLAGS': '0x0', 08:44:41 INFO - 'XPC_SERVICE_NAME': '0', 08:44:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:41 INFO - Downloading/unpacking psutil>=3.1.1 08:44:41 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 08:44:41 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 08:44:41 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 08:44:41 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 08:44:41 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 08:44:41 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 08:44:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:44:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:44:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:44:45 INFO - Installing collected packages: psutil 08:44:45 INFO - Running setup.py install for psutil 08:44:45 INFO - building 'psutil._psutil_osx' extension 08:44:45 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 08:44:45 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 08:44:46 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 08:44:46 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 08:44:46 INFO - building 'psutil._psutil_posix' extension 08:44:46 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 08:44:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:44:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:44:46 INFO - ^ 08:44:46 INFO - 1 warning generated. 08:44:46 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 08:44:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:44:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:44:46 INFO - Successfully installed psutil 08:44:46 INFO - Cleaning up... 08:44:46 INFO - Return code: 0 08:44:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:44: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')]} 08:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44: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')]} 08:44:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:46 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 0x10f016490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef8c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8750c69440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee2ad30>, '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 0x10ec50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee22b20>, '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.Q8T7yprdC8/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.Vmrm2gfqV2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 08:44:46 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 08:44:46 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 08:44:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q8T7yprdC8/Render', 08:44:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:46 INFO - 'HOME': '/Users/cltbld', 08:44:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:46 INFO - 'LOGNAME': 'cltbld', 08:44:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:46 INFO - 'MOZ_NO_REMOTE': '1', 08:44:46 INFO - 'NO_EM_RESTART': '1', 08:44:46 INFO - 'PAGER': '/bin/cat', 08:44:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:46 INFO - 'PWD': '/builds/slave/test', 08:44:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:46 INFO - 'SHELL': '/bin/bash', 08:44:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vmrm2gfqV2/Listeners', 08:44:46 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 08:44:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:46 INFO - 'USER': 'cltbld', 08:44:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:46 INFO - 'XPC_FLAGS': '0x0', 08:44:46 INFO - 'XPC_SERVICE_NAME': '0', 08:44:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:44:46 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 08:44:46 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 08:44:46 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 08:44:46 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 08:44:46 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 08:44:46 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 08:44:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:44:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:44:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:44:49 INFO - Installing collected packages: mozsystemmonitor 08:44:49 INFO - Running setup.py install for mozsystemmonitor 08:44:49 INFO - Successfully installed mozsystemmonitor 08:44:49 INFO - Cleaning up... 08:44:49 INFO - Return code: 0 08:44:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:44:49 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')]} 08:44:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:49 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')]} 08:44:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:49 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 0x10f016490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef8c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8750c69440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee2ad30>, '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 0x10ec50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee22b20>, '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.Q8T7yprdC8/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.Vmrm2gfqV2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 08:44:49 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 08:44:49 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 08:44:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q8T7yprdC8/Render', 08:44:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:49 INFO - 'HOME': '/Users/cltbld', 08:44:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:49 INFO - 'LOGNAME': 'cltbld', 08:44:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:49 INFO - 'MOZ_NO_REMOTE': '1', 08:44:49 INFO - 'NO_EM_RESTART': '1', 08:44:49 INFO - 'PAGER': '/bin/cat', 08:44:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:49 INFO - 'PWD': '/builds/slave/test', 08:44:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:49 INFO - 'SHELL': '/bin/bash', 08:44:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vmrm2gfqV2/Listeners', 08:44:49 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 08:44:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:49 INFO - 'USER': 'cltbld', 08:44:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:49 INFO - 'XPC_FLAGS': '0x0', 08:44:49 INFO - 'XPC_SERVICE_NAME': '0', 08:44:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:49 INFO - Downloading/unpacking jsonschema==2.5.1 08:44:49 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 08:44:49 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 08:44:49 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 08:44:49 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 08:44:49 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 08:44:49 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 08:44:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:44:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:44:52 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 08:44:52 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 08:44:52 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 08:44:52 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 08:44:52 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 08:44:52 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 08:44:52 INFO - Downloading functools32-3.2.3-2.tar.gz 08:44:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:44:52 INFO - warning: no files found matching '*.txt' 08:44:52 INFO - no previously-included directories found matching 'build' 08:44:52 INFO - no previously-included directories found matching 'dist' 08:44:52 INFO - no previously-included directories found matching '.git*' 08:44:52 INFO - Installing collected packages: jsonschema, functools32 08:44:53 INFO - Running setup.py install for functools32 08:44:53 INFO - warning: no files found matching '*.txt' 08:44:53 INFO - no previously-included directories found matching 'build' 08:44:53 INFO - no previously-included directories found matching 'dist' 08:44:53 INFO - no previously-included directories found matching '.git*' 08:44:53 INFO - Successfully installed jsonschema functools32 08:44:53 INFO - Cleaning up... 08:44:53 INFO - Return code: 0 08:44:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:44:53 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')]} 08:44:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:53 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')]} 08:44:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:53 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 0x10f016490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef8c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8750c69440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee2ad30>, '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 0x10ec50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee22b20>, '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.Q8T7yprdC8/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.Vmrm2gfqV2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 08:44:53 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 08:44:53 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 08:44:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q8T7yprdC8/Render', 08:44:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:53 INFO - 'HOME': '/Users/cltbld', 08:44:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:53 INFO - 'LOGNAME': 'cltbld', 08:44:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:53 INFO - 'MOZ_NO_REMOTE': '1', 08:44:53 INFO - 'NO_EM_RESTART': '1', 08:44:53 INFO - 'PAGER': '/bin/cat', 08:44:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:53 INFO - 'PWD': '/builds/slave/test', 08:44:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:53 INFO - 'SHELL': '/bin/bash', 08:44:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vmrm2gfqV2/Listeners', 08:44:53 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 08:44:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:53 INFO - 'USER': 'cltbld', 08:44:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:53 INFO - 'XPC_FLAGS': '0x0', 08:44:53 INFO - 'XPC_SERVICE_NAME': '0', 08:44:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:44:53 INFO - Cleaning up... 08:44:53 INFO - Return code: 0 08:44:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:44:53 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')]} 08:44:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:53 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')]} 08:44:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:53 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 0x10f016490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef8c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8750c69440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee2ad30>, '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 0x10ec50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee22b20>, '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.Q8T7yprdC8/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.Vmrm2gfqV2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 08:44:53 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 08:44:53 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 08:44:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q8T7yprdC8/Render', 08:44:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:53 INFO - 'HOME': '/Users/cltbld', 08:44:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:53 INFO - 'LOGNAME': 'cltbld', 08:44:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:53 INFO - 'MOZ_NO_REMOTE': '1', 08:44:53 INFO - 'NO_EM_RESTART': '1', 08:44:53 INFO - 'PAGER': '/bin/cat', 08:44:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:53 INFO - 'PWD': '/builds/slave/test', 08:44:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:53 INFO - 'SHELL': '/bin/bash', 08:44:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vmrm2gfqV2/Listeners', 08:44:53 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 08:44:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:53 INFO - 'USER': 'cltbld', 08:44:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:53 INFO - 'XPC_FLAGS': '0x0', 08:44:53 INFO - 'XPC_SERVICE_NAME': '0', 08:44:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:53 INFO - Downloading/unpacking blobuploader==1.2.4 08:44:53 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 08:44:53 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 08:44:53 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 08:44:53 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 08:44:53 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 08:44:53 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 08:44:56 INFO - Downloading blobuploader-1.2.4.tar.gz 08:44:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:44:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:44:56 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 08:44:56 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 08:44:56 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 08:44:56 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 08:44:56 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 08:44:56 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 08:44:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:44:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:44:56 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 08:44:56 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 08:44:56 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 08:44:56 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 08:44:56 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 08:44:56 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 08:44:57 INFO - Downloading docopt-0.6.1.tar.gz 08:44:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:44:57 INFO - Installing collected packages: blobuploader, requests, docopt 08:44:57 INFO - Running setup.py install for blobuploader 08:44:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:44:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:44:57 INFO - Running setup.py install for requests 08:44:58 INFO - Running setup.py install for docopt 08:44:58 INFO - Successfully installed blobuploader requests docopt 08:44:58 INFO - Cleaning up... 08:44:58 INFO - Return code: 0 08:44:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:44: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')]} 08:44:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44: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')]} 08:44:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:58 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 0x10f016490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef8c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8750c69440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee2ad30>, '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 0x10ec50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee22b20>, '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.Q8T7yprdC8/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.Vmrm2gfqV2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 08:44:58 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 08:44:58 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 08:44:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q8T7yprdC8/Render', 08:44:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:58 INFO - 'HOME': '/Users/cltbld', 08:44:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:58 INFO - 'LOGNAME': 'cltbld', 08:44:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:58 INFO - 'MOZ_NO_REMOTE': '1', 08:44:58 INFO - 'NO_EM_RESTART': '1', 08:44:58 INFO - 'PAGER': '/bin/cat', 08:44:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:58 INFO - 'PWD': '/builds/slave/test', 08:44:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:58 INFO - 'SHELL': '/bin/bash', 08:44:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vmrm2gfqV2/Listeners', 08:44:58 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 08:44:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:58 INFO - 'USER': 'cltbld', 08:44:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:58 INFO - 'XPC_FLAGS': '0x0', 08:44:58 INFO - 'XPC_SERVICE_NAME': '0', 08:44:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:44:58 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-JHW8HD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:44:58 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-h53UZl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:44:58 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-h6QGCg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:44:59 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-_1nbBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:44:59 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-CjlG6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:44:59 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-qhUpqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:44:59 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-j_lb8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:44:59 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-rO4SZ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:44:59 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-raQ7JY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:44:59 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-7aL8pT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:44:59 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-MFu6dS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:44:59 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-VOEwHW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:44:59 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-uSTJzw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:45:00 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-PKq_BC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:45:00 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-nlF9m9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:45:00 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-Wlc0oa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:45:00 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-t4W9sx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:45:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:45:00 INFO - Running setup.py install for manifestparser 08:45:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:45:00 INFO - Running setup.py install for mozcrash 08:45:00 INFO - Running setup.py install for mozdebug 08:45:00 INFO - Running setup.py install for mozdevice 08:45:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:45:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:45:01 INFO - Running setup.py install for mozfile 08:45:01 INFO - Running setup.py install for mozhttpd 08:45:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:45:01 INFO - Running setup.py install for mozinfo 08:45:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:45:01 INFO - Running setup.py install for mozInstall 08:45:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:45:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:45:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:45:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:45:01 INFO - Running setup.py install for mozleak 08:45:01 INFO - Running setup.py install for mozlog 08:45:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:45:02 INFO - Running setup.py install for moznetwork 08:45:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:45:02 INFO - Running setup.py install for mozprocess 08:45:02 INFO - Running setup.py install for mozprofile 08:45:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:45:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:45:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:45:02 INFO - Running setup.py install for mozrunner 08:45:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:45:02 INFO - Running setup.py install for mozscreenshot 08:45:02 INFO - Running setup.py install for moztest 08:45:02 INFO - Running setup.py install for mozversion 08:45:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:45:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:45:03 INFO - Cleaning up... 08:45:03 INFO - Return code: 0 08:45:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:45:03 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')]} 08:45:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:03 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')]} 08:45:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:03 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 0x10f016490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef8c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8750c69440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee2ad30>, '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 0x10ec50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee22b20>, '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.Q8T7yprdC8/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.Vmrm2gfqV2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 08:45:03 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 08:45:03 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 08:45:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q8T7yprdC8/Render', 08:45:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:45:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:45:03 INFO - 'HOME': '/Users/cltbld', 08:45:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:45:03 INFO - 'LOGNAME': 'cltbld', 08:45:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:03 INFO - 'MOZ_NO_REMOTE': '1', 08:45:03 INFO - 'NO_EM_RESTART': '1', 08:45:03 INFO - 'PAGER': '/bin/cat', 08:45:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:45:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:03 INFO - 'PWD': '/builds/slave/test', 08:45:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:45:03 INFO - 'SHELL': '/bin/bash', 08:45:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vmrm2gfqV2/Listeners', 08:45:03 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 08:45:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:45:03 INFO - 'USER': 'cltbld', 08:45:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:45:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:45:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:03 INFO - 'XPC_FLAGS': '0x0', 08:45:03 INFO - 'XPC_SERVICE_NAME': '0', 08:45:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:45:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:45:03 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-nvvRqN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:45:03 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)) 08:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:45:03 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-d1Cz3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:45:03 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)) 08:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:45:03 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-3i45wc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:45:03 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)) 08:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:45:03 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-ucrDf1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:45:03 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)) 08:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:45:03 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-I4TK8J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:45:03 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)) 08:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:45:03 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-g57I6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:45:04 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)) 08:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:45:04 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-5NsHCI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:45:04 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)) 08:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:45:04 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-QVJr0i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:45:04 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)) 08:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:45:04 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-IcTKTn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:45:04 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)) 08:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:45:04 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-nCkQ7j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:45:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 08:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:45:04 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-NWGjXA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:45:04 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)) 08:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:45:04 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-qFnojv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:45:04 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)) 08:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:45:04 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-gDpOqx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:45:04 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)) 08:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:45:04 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-6NfN7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:45:04 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)) 08:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:45:04 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-J4S1nm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:45:04 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)) 08:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:45:05 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-ng6XVd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:45:05 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)) 08:45:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:45:05 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-1FeELh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:45:05 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)) 08:45:05 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)) 08:45:05 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)) 08:45:05 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)) 08:45:05 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)) 08:45:05 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)) 08:45:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:45:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:45:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:45:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:07 INFO - Downloading blessings-1.6.tar.gz 08:45:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:45:07 INFO - Installing collected packages: blessings 08:45:07 INFO - Running setup.py install for blessings 08:45:07 INFO - Successfully installed blessings 08:45:07 INFO - Cleaning up... 08:45:08 INFO - Return code: 0 08:45:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:45:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:45:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:45:08 INFO - Reading from file tmpfile_stdout 08:45:08 INFO - Current package versions: 08:45:08 INFO - blessings == 1.6 08:45:08 INFO - blobuploader == 1.2.4 08:45:08 INFO - docopt == 0.6.1 08:45:08 INFO - functools32 == 3.2.3-2 08:45:08 INFO - jsonschema == 2.5.1 08:45:08 INFO - manifestparser == 1.1 08:45:08 INFO - mozInstall == 1.12 08:45:08 INFO - mozcrash == 0.17 08:45:08 INFO - mozdebug == 0.1 08:45:08 INFO - mozdevice == 0.48 08:45:08 INFO - mozfile == 1.2 08:45:08 INFO - mozhttpd == 0.7 08:45:08 INFO - mozinfo == 0.9 08:45:08 INFO - mozleak == 0.1 08:45:08 INFO - mozlog == 3.4 08:45:08 INFO - moznetwork == 0.27 08:45:08 INFO - mozprocess == 0.23 08:45:08 INFO - mozprofile == 0.28 08:45:08 INFO - mozrunner == 6.12 08:45:08 INFO - mozscreenshot == 0.1 08:45:08 INFO - mozsystemmonitor == 0.3 08:45:08 INFO - moztest == 0.7 08:45:08 INFO - mozversion == 1.4 08:45:08 INFO - psutil == 3.1.1 08:45:08 INFO - requests == 1.2.3 08:45:08 INFO - wsgiref == 0.1.2 08:45:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:45:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:45:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:08 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 0x10f016490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef8c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8750c69440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee2ad30>, '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 0x10ec50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee22b20>, '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.Q8T7yprdC8/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.Vmrm2gfqV2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 08:45:08 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 08:45:08 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 08:45:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q8T7yprdC8/Render', 08:45:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:45:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:45:08 INFO - 'HOME': '/Users/cltbld', 08:45:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:45:08 INFO - 'LOGNAME': 'cltbld', 08:45:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:08 INFO - 'MOZ_NO_REMOTE': '1', 08:45:08 INFO - 'NO_EM_RESTART': '1', 08:45:08 INFO - 'PAGER': '/bin/cat', 08:45:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:45:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:08 INFO - 'PWD': '/builds/slave/test', 08:45:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:45:08 INFO - 'SHELL': '/bin/bash', 08:45:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vmrm2gfqV2/Listeners', 08:45:08 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 08:45:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:45:08 INFO - 'USER': 'cltbld', 08:45:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:45:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:45:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:08 INFO - 'XPC_FLAGS': '0x0', 08:45:08 INFO - 'XPC_SERVICE_NAME': '0', 08:45:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:45:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:08 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)) 08:45:08 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)) 08:45:08 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)) 08:45:08 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)) 08:45:08 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)) 08:45:08 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)) 08:45:08 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)) 08:45:08 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)) 08:45:08 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)) 08:45:08 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)) 08:45:08 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)) 08:45:08 INFO - Cleaning up... 08:45:08 INFO - Return code: 0 08:45:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:45:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:45:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:08 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 0x10f016490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef8c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8750c69440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee2ad30>, '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 0x10ec50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee22b20>, '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.Q8T7yprdC8/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.Vmrm2gfqV2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 08:45:08 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 08:45:08 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 08:45:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q8T7yprdC8/Render', 08:45:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:45:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:45:08 INFO - 'HOME': '/Users/cltbld', 08:45:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:45:08 INFO - 'LOGNAME': 'cltbld', 08:45:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:08 INFO - 'MOZ_NO_REMOTE': '1', 08:45:08 INFO - 'NO_EM_RESTART': '1', 08:45:08 INFO - 'PAGER': '/bin/cat', 08:45:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:45:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:08 INFO - 'PWD': '/builds/slave/test', 08:45:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:45:08 INFO - 'SHELL': '/bin/bash', 08:45:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vmrm2gfqV2/Listeners', 08:45:08 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 08:45:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:45:08 INFO - 'USER': 'cltbld', 08:45:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:45:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:45:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:08 INFO - 'XPC_FLAGS': '0x0', 08:45:08 INFO - 'XPC_SERVICE_NAME': '0', 08:45:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:45:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:45:08 INFO - Cleaning up... 08:45:08 INFO - Return code: 0 08:45:08 INFO - Running post-action listener: _resource_record_post_action 08:45:08 INFO - Running post-action listener: _start_resource_monitoring 08:45:08 INFO - Starting resource monitoring. 08:45:08 INFO - [mozharness: 2016-10-17 15:45:08.888628Z] Finished create-virtualenv step (success) 08:45:08 INFO - [mozharness: 2016-10-17 15:45:08.889686Z] Running install step. 08:45:08 INFO - Running pre-action listener: _resource_record_pre_action 08:45:08 INFO - Running main action method: install 08:45:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:45:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:45:09 INFO - Reading from file tmpfile_stdout 08:45:09 INFO - Detecting whether we're running mozinstall >=1.0... 08:45:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:45:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:45:09 INFO - Reading from file tmpfile_stdout 08:45:09 INFO - Output received: 08:45:09 INFO - Usage: mozinstall [options] installer 08:45:09 INFO - Options: 08:45:09 INFO - -h, --help show this help message and exit 08:45:09 INFO - -d DEST, --destination=DEST 08:45:09 INFO - Directory to install application into. [default: 08:45:09 INFO - "/builds/slave/test"] 08:45:09 INFO - --app=APP Application being installed. [default: firefox] 08:45:09 INFO - mkdir: /builds/slave/test/build/application 08:45:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:45:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:45:43 INFO - Reading from file tmpfile_stdout 08:45:43 INFO - Output received: 08:45:43 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:45:43 INFO - Running post-action listener: _resource_record_post_action 08:45:43 INFO - [mozharness: 2016-10-17 15:45:43.261834Z] Finished install step (success) 08:45:43 INFO - [mozharness: 2016-10-17 15:45:43.261970Z] Running run-tests step. 08:45:43 INFO - Running pre-action listener: _resource_record_pre_action 08:45:43 INFO - Running main action method: run_tests 08:45:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:45:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:45:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:45:43 INFO - 2016-10-17 08:45:43.276 screenresolution[1670:7510] starting screenresolution argv=screenresolution get 08:45:43 INFO - 2016-10-17 08:45:43.278 screenresolution[1670:7510] Display 0: 1600x1200x32@60 08:45:43 INFO - 2016-10-17 08:45:43.284 screenresolution[1671:7512] starting screenresolution argv=screenresolution list 08:45:43 INFO - Available Modes on Display 0 08:45:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:45:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:45:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:45:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:45:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:45:43 INFO - Intel Iris: 08:45:43 INFO - Chipset Model: Intel Iris 08:45:43 INFO - Type: GPU 08:45:43 INFO - Bus: Built-In 08:45:43 INFO - VRAM (Dynamic, Max): 1536 MB 08:45:43 INFO - Vendor: Intel (0x8086) 08:45:43 INFO - Device ID: 0x0a2e 08:45:43 INFO - Revision ID: 0x0009 08:45:43 INFO - Displays: 08:45:43 INFO - Display: 08:45:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:45:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:45:43 INFO - Display Serial Number: 1600x1200 60 08:45:43 INFO - Main Display: Yes 08:45:43 INFO - Mirror: Off 08:45:43 INFO - Online: Yes 08:45:43 INFO - Rotation: Supported 08:45:43 INFO - Return code: 0 08:45:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:45:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:45:43 INFO - Python 2.7.3 08:45:43 INFO - Return code: 0 08:45:43 INFO - grabbing minidump binary from tooltool 08:45:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:45:43 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 0x10ee2ad30>, '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 0x10ec50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee22b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:45:43 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 08:45:43 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 08:45:43 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 08:45:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:45:43 INFO - Return code: 0 08:45:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:45:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:45:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:45:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:45:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:45:43 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/CPhvVpesSfu8e9An5szyVw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0063', '--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 08:45:43 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/CPhvVpesSfu8e9An5szyVw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0063 --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 08:45:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q8T7yprdC8/Render', 08:45:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:45:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:45:43 INFO - 'HOME': '/Users/cltbld', 08:45:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:45:43 INFO - 'LOGNAME': 'cltbld', 08:45:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:45:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:45:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:43 INFO - 'MOZ_NO_REMOTE': '1', 08:45:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:45:43 INFO - 'NO_EM_RESTART': '1', 08:45:43 INFO - 'PAGER': '/bin/cat', 08:45:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:45:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:43 INFO - 'PWD': '/builds/slave/test', 08:45:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:45:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:45:43 INFO - 'SHELL': '/bin/bash', 08:45:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vmrm2gfqV2/Listeners', 08:45:43 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 08:45:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:45:43 INFO - 'USER': 'cltbld', 08:45:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:45:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:45:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:43 INFO - 'XPC_FLAGS': '0x0', 08:45:43 INFO - 'XPC_SERVICE_NAME': '0', 08:45:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:45:43 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/CPhvVpesSfu8e9An5szyVw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0063', '--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 08:45:43 INFO - mozversion application_buildid: 20161017074849 08:45:43 INFO - mozversion application_changeset: dabadf63cb447fe9253c605b41afb116334e24ed 08:45:43 INFO - mozversion application_display_name: Nightly 08:45:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:45:43 INFO - mozversion application_name: Firefox 08:45:43 INFO - mozversion application_remotingname: firefox 08:45:43 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 08:45:43 INFO - mozversion application_vendor: Mozilla 08:45:43 INFO - mozversion application_version: 52.0a1 08:45:43 INFO - mozversion platform_buildid: 20161017074849 08:45:43 INFO - mozversion platform_changeset: dabadf63cb447fe9253c605b41afb116334e24ed 08:45:43 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 08:45:43 INFO - mozversion platform_version: 52.0a1 08:45:43 INFO - using testdate: 1476719143 08:45:43 INFO - actual date: 1476719143 08:45:43 INFO - starting webserver on 'localhost:49279' 08:45:43 INFO - SUITE-START | Running 2 tests 08:45:43 INFO - TEST-START | dromaeo_css 08:45:43 INFO - operating with platform_type : mac_ 08:45:43 INFO - Initialising browser for dromaeo_css test... 08:45:43 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/tmp8ZE1_3/profile http://localhost:49279/getInfo.html) 08:45:46 INFO - PROCESS | 1681 | __metrics Screen width/height:1600/1200 08:45:46 INFO - PROCESS | 1681 | colorDepth:24 08:45:46 INFO - PROCESS | 1681 | Browser inner width/height: 1024/689 08:45:46 INFO - PROCESS | 1681 | __metrics 08:45:46 INFO - PROCESS | 1681 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:45:46 INFO - PROCESS | 1681 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:45:47 INFO - TEST-INFO | 1681: exit 0 08:45:47 INFO - Browser initialized. 08:45:47 INFO - Running cycle 1/1 for dromaeo_css test... 08:45:47 INFO - TEST-INFO | started process 1684 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/tmp8ZE1_3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:45:59 INFO - PROCESS | 1684 | RSS: Main: 230596608 08:45:59 INFO - PROCESS | 1684 | 08:46:50 INFO - PROCESS | 1684 | 1476719210099 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:49:04 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 08:49:06 INFO - PROCESS | 1684 | RSS: Main: 368115712 08:49:06 INFO - PROCESS | 1684 | 08:52:08 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 08:52:09 INFO - PROCESS | 1684 | RSS: Main: 370540544 08:52:09 INFO - PROCESS | 1684 | 08:55:11 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 08:55:12 INFO - PROCESS | 1684 | RSS: Main: 333238272 08:55:12 INFO - PROCESS | 1684 | 08:58:14 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 08:58:15 INFO - PROCESS | 1684 | RSS: Main: 336736256 08:58:15 INFO - PROCESS | 1684 | 09:01:18 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 09:01:19 INFO - PROCESS | 1684 | RSS: Main: 332890112 09:01:19 INFO - PROCESS | 1684 | 09:04:22 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 09:04:23 INFO - PROCESS | 1684 | RSS: Main: 449126400 09:04:23 INFO - PROCESS | 1684 | 09:04:23 INFO - PROCESS | 1684 | __start_tp_report 09:04:23 INFO - PROCESS | 1684 | _x_x_mozilla_page_load 09:04:23 INFO - PROCESS | 1684 | _x_x_mozilla_page_load_details 09:04:23 INFO - PROCESS | 1684 | |i|pagename|runs| 09:04:23 INFO - PROCESS | 1684 | |0;dojo.html;3340.0814775936615;3363.1760218746413;3365.4110530657144;3373.578302712161;3430.433978393565;5617.859553511164;5683.147527870821;5756.884862302757;5772.163036359725;5817.301923769149;5709.743061562229;5759.712014399273;5864.091065884784;5864.442877926601;5869.266341707286;5132.024915266087;5179.964007198557;5191.091558977183;5246.134387826004;5255.316808814868;5298.788048478056;5310.468953104697;5318.149096144618;5319.255304257407;5351.942414418376;5172.353455818023;5299.152135658314;5327.280817089682;5356.330458692663;5397.514223719864;5223.399309079467;5265.210218467222;5270.262163297141;5278.1291086970605;5285.180796976437;5173.715445650488;5224.425313215581;5235.005348983681;5246.921296180522;5249.527542521153;4774.761261936889;5736.655800651933;5737.512311453532;5746.396628354005;5749.137629355589;5398;6170.352113028112;6212.316645169028;6293.024581189923;6300.275468321156;6219.129321894939;6227.564070515065;6299.905501417483;6328.303886572473;6332.113504109382;5840.3575606683235;5847.736851841635;5849.853753656193;5867.266591676041;5910.290765108214;6206.658633775095;6259.6870156492;6267.905981410283;6271;6302.968485157545;6119.418655227746;6128.4143737903505;6145.84635384119;6146.801373732079;6174.228221472316;5259.316288882421;6265.467435268053;6299.149614802035;6305.621662700252;6321.273053598895;6216.515474257597;6230.875382492415;6231.4391704746795;6243.969744992026;6250.468710159596;6100.48145907594;6115.270764408792;6132.4521822465285;6148.723307451175;6181.1655426516845;4933.950660493421;4958.380202474691;4968.205087186033;5001.074801161797;5014.774335154927;4396.60430561251;4409.470863496401;4437.800298986554;4446.933296000562;4460.754658493749;5582.469665381782;5655.208270842086;5788.392218817048;5812.7674893003805;5857.7949771757785;6203.286622038433;6233.1273621693;6245;6252.968735156295;6254.2807577129215;5777.30672319316;5796.3913789052385;5796.62321949072;5799.739011744397;5852.648841069465;5385.5960802939935;5404.918926216032;5411.485908838654;5413.864653383697;5417.83746487606;6183.103449999815;6196.783112591038;6250.749970001331;6309.621422714652;6329.303776584664;6208.875822483616;6295.866743985944;6314.778982735582;6319.715612797343;6335.714892829926;6315.27374351937;6331.746730130927;6334.778282760081;6335.303116657256;6361.268454127835;6106.786262480792;6119.868162944292;6120.355709576858;6189.0097584386285;6217.968910155601;6032;6287.559870808999;6289.937100628935;6306.432421082125;6322.178116844781;6115.938840611715;6122.081687746873;6125.601835880653;6149.2620885494025;6155.969220153871;6019.367966363644;6057.303410107894;6068.302145253352;6085.817425477243;6114.938850611437;4758.595519380891;5933.376995415592;5971.462568368867;6043.395660433922;6078.3617720138745;6134.5705800595315;6149.385061493815;6154.323024467394;6162.661053642093;6166.537509686702;5784.190213370048;5836.620619659709;5860.326062502867;5874.148248504029;5924.674142922181 09:04:23 INFO - PROCESS | 1684 | |1;ext.html;470.57177968049064;515.7859488312349;526.9724038125651;526.9762860671269;535.218580871927;30311.545326820087;39604.00989975252;39744.801275993654;40918.181636367255;41023.79488102559;36194.63805361952;41759.58240417595;41844.58155418445;41850.162996740044;41862.37206441898;9375.906240937571;9428.905710942872;9568.952155239249;9573.186279166244;9584.041595840396;8125.3093487053675;9338.906610933871;9465.763355916088;9469.479178645171;9480.525973701288;9481.47851868147;9512.429254244733;9531.332806703533;9555.092266234731;9559.091886270793;9437.669681561147;9494.620215191384;9503.477308748015;9509.952450237704;9553.90446095544;9402.15380615748;9473.194778443773;9479.289053321028;9484.241260699127;9501.4299142051;39107.80446097751;39468.01329966745;39495.40756888649;39495.60504394948;39518.80240598807;3399.354122716676;3404.591449026108;3529.5588051493564;3582.6059133495296;3820.3505404081093;24928.5014299713;29915.952941646938;30059.549106763418;30555;30555.847220763753;2643.729743206183;3168.445522033635;3182.236263296792;3192.8722851085927;3242.2010184407027;467743.3225667734;470056;471754;472062;472745;50737.746311268944;55870.882582348124;57358.85282294414;57620;57901.8419631605;28561.71438285632;34102.31795364079;34690.47964280538;60681.39318606757;61032;16117.67764644724;16262.430814921421;16275.023498590122;16280.43018494347;16297.91851040761;12489.31308778026;12572.622821315375;12580.685482862818;12580.874191258154;12581.874181258254;18696.439106826816;18730.1571429286;18734.71897921505;18760.43718688414;18766.249350025846;6725.8654826903185;7399;7642.421032646088;8056.7180148694515;8102.7569172924905;756.8751156059299;757.4357103957477;892.6922059183328;899.7165892743895;963.4180396537347;27392.45215095687;29459;29719;29808.40383192324;29861.954831581224;1050.968470945873;1079.865016872891;1131.9886801132047;1200.6338066889507;1203.7231436769591;14833.70332593342;15277.694446111016;15373.692526149414;15495.992760470534;15591.298391572407;560694.1965290147;637800;639373;641014;643038;55940.44059559352;56626;56681.149782753295;56693.43306566964;56718.71640641853;1354.4040622126233;1395.7417877692455;1408.936597853099;1419.7941298511657;1422.5732280316072;25263.747362526876;28986.855065724536;29064.982725604503;29665.11004669863;29920.551191731705;49233.75383123061;49616.75191624019;49699.50300496949;49745.75127124341;49818.75090624524;540609;542253;543105;544214;544634;25492.745072549038;25577;25726.356841078374;25905.74094259033;25977.35056623524;45684;46092.30861537147;46746.53253467422;46803.53196467992;46805;29984.400311994075;36798.63201367952;37438.6256137446;38548;38912;37793.81103094467;37937.81031094827;38157;38981.22037559289;39499.617513388046;36988.6301136996;37029.074273143386;37997.86006419812;38264.61735382611;38333.80833095816;30290.84854575713;33817.49273760945;33913.66086339105;33924.812631557776;34007;24316;24845.87577062103;24917.87541062283;25193.709550354306;25222.49555008853 09:04:23 INFO - PROCESS | 1684 | |2;jquery.html;341.31225580455373;349.952756377889;360.5727213252296;375.3225428102275;395.25494442974986;15014.324355404005;15193;16058.59853503663;20240.493987650316;20743.585128297425;20124.396268111945;20747.792522074775;21271.04280307386;21425.99999999996;21571.784282157212;5285.418603953562;5826.592138550303;6155.415235552616;6160.19917410736;6162.22972128484;5968.67172305523;6083.604565703237;6113.358097399753;6114.449699527042;6127.816165515042;5739.598228124033;5994.730237139339;6048.1834952281315;6096.908546371808;6100.969495152518;5955.434233747808;6013.128096426038;6017.217761690996;6019.307779605358;6020.969895150496;4975.203967365241;5173.3016042834115;5216.895662086775;5247.790088396459;5262.079136151158;26124.477510449684;26163.869180653975;26304.473910521872;26493.3376665583;26762.866185669143;2038.3375402993938;2411.4141588981443;2424.1878970544713;2629.2638061342855;2931.7801164912717;9926.503674816302;10269.486525673687;10278.783778405681;10299.330543514647;13461.394237259346;1647.9258433370528;1844.2346426233232;1879.2389082421505;1882.4070417818343;1890.3194849854178;108797;111618;120228;120506.39746801298;121524.99999999911;11396.259243149168;11530.308181509135;11552.249103808226;11561.942190288995;11745.4714537846;14367;14372.425102995763;14384.496542620958;14422.350994205286;14423;8133.308668763103;8386.329093672492;8434.325253979667;8587.785305367415;8596.8710469343;6910.550814197061;8075.717349892726;8368.163183681583;8423.70517031901;8590.957045214735;3795.544534655859;4359.542248063971;4366.585174408426;4408.647308215335;4612;1734.4363081998401;1833.4591295567839;1891.489297889562;1900.211412263895;1904.2668572599648;711.6441779110445;759.7350411564682;783.6904422752981;823.9423240373236;935.9064093590587;18530.907345463187;21150.682739758915;21476.677849832264;21582.028808703333;21637.35087947364;908.6819613135482;944.2115833279152;980.5097451274363;1006.2402885821158;1067.4022547373495;10699.946500267604;10917.39954302505;11232.93287137737;11355.716107097389;11379.203455758183;557652;567363;584579;589536;590488;120992;123409.99999999821;126012;129380;130559;854.1970547685156;951.058452132384;971.5093877591771;972.0619602083882;988.8071825993862;18303.26786928508;18920.905395473485;19052.618947621246;19181.712274316174;19187.232510699974;36561;41072;41845.163096738506;49150.5084949146;54895.72552137214;77924.61037694775;77930.22069779229;79187.87309142205;81167;81206.59396702978;6839.555428897106;7460.4031677466755;7465.440091993014;8188.385871059575;8205;10558.472076395996;12749.826337203755;13171.748683875017;13245.40395682216;13308.933455333048;9486.430814150897;11180.440977950908;11501.769964600828;11597.18819682614;11747.240882917384;10915.83626245591;11105.278156919774;11109.11127109813;11483.483243253906;11572.18994670365;11061.230203167455;11738.530458781554;11808.70478238051;11914.225575337576;11951.940240298743;5738.426157384395;7424.851502970018;7487.588182650006;7507.962460187664;8320.417570769989;10917.508712107816;10993.395363255098;11332.320060796379;11541.538338466367;11683.357415342238 09:04:23 INFO - PROCESS | 1684 | |3;mootools.html;1538.8230353509346;1568.9607759806004;1811.293595497756;1915.434946690726;1991.462305177602;8628.56857157142;9109.954450227757;9135.862962055575;9137.406068605544;9143.177114059734;7050.5417147885355;8276.91723082769;8309.252167304941;8706.782330441742;8955.731328060152;2364.0780095762666;2396.7962723168553;2407.2417188585628;2410.385351735305;2410.7468715784853;2370.229675355508;2372.6796882420913;2402.795762360193;2403.4111642647576;2404.8196385271085;2375.6436534519876;2384.96422553661;2398.496315773681;2400.075970751265;2413.2157048959157;2348.567373901927;2350.142198097682;2354.058376649337;2361;2378.797802186816;1976.8715033522774;2336.4742932840145;2372.2290255666803;2376.2039716694926;2381.4522659788345;8930.062343453908;8973.057828927998;9052.909470905273;9063;9118.270538356981;5304.36347638282;5483.698396588186;5484.61607687462;5494.230807686927;5508.779648814043;3584.605693373727;3600.225951420431;3654.3239500692457;3661.1945372018113;3670.247599242149;1073.4257172412722;1077;1079.7894410589938;1085.2891356161726;1096.6216655254004;11747.941260293645;11846.289222646668;11882.28706277626;11885.22746021506;11895.345755983417;4558.566936141061;4661.300804879227;4843.128236917319;4881.243407271879;4892.779824907888;8604.526751028628;9934.652287169914;9952.601895924226;10007.549660265306;10017.749556261006;5431.945680543223;5437.32033495813;5437.32033495813;5442.047641662693;5451;4442.777861106932;4590.517995610479;4614.2617181250835;4931.654784165074;4944.752762361867;3892.9416058759143;3932.643238082857;4027.7381970172432;4044.3529035354204;4058.24922389353;1270.2188154285234;1275.6428200103985;1277.744451109763;1288.941997610091;1299.3957809618385;754.5435011817881;843.4433274039227;872.7425409504211;929.2148134826061;964.6382606522554;5748.741306641127;5836.182934389189;5948.196993405922;5970.373110823388;6143.232095988002;892.205936716323;941.609232168656;950.292032435826;962.9277804164576;1000.7798284377425;3796.962030379716;3879.379299312153;3927.1752931884052;4026.5168179818024;4047.1500984793524;10783.622573210057;10907.345559266469;11124.833127503094;11184;11188.440577971069;8775.341849361323;10974.957379048978;11048.889511104786;11100.500477478532;11124.833127503094;8143.714969976022;8381.916180838236;8388.832223355621;8393.664253429795;8406.24343809048;5763.7118144093465;5820.505257053198;5863.941360586339;5931.792387266425;6169.938300617116;10232.64185753495;10247.590096396061;10251.7437064074;10264.127549158107;10286.331388459726;9744.415335079917;10014.599416023277;10173.542190601293;10231.334963227366;10321.277510574488;7049.08361912948;7289.380402665833;7300.488965772345;7576.659050342638;7783.571903545359;8117.878231826646;8422.157784221528;8435.620397082268;8533.530655813742;8556.529390883561;7357.411407087528;7462.4854657690275;7610.9238907612435;7673;7805.843883122192;6451.838704032249;6465.903011454926;7224.638768061681;7252.746153884589;7333.973243745773;7111.111111111111;7122.465815063994;7219.783406497813;7230.385417239593;7230.385417239593;4886.096072226649;4914.852554423373;4960.627952903475;4970.254461830755;5068.695878247317;4635.675502714777;4661;4762.499937506651;4795.448523419712;4854.344663470386 09:04:23 INFO - PROCESS | 1684 | |4;prototype.html;294.49200129776136;295.96152500174986;307.4496650994719;311.6011505273251;313.5641458372862;4291.884110131365;4916.704997700132;5213.765380557875;5241.3227767114495;5248.973755131228;4178.561251068632;4808.595702148926;5039.672421292614;5071;5083.771230294637;3811.2758575870635;3961.4850069490926;3969.9801500992453;3984.6015398460213;3992.041909941622;3704.8332825022803;3988.327820360351;4029.871635941941;4043.261397599031;4051.1695102504063;3866.845326186963;3912.1979994101284;3912.7065470089715;3943.7239393242485;3944.073142811437;3588.1115701859994;3904.7852368119743;3913.999999999986;3940.7044471664453;3949.94075088871;3359.059463350266;3400.9829950850335;3403.9659603403898;3417.572803399575;3424.938269136576;4630.83792067276;4772.570468657837;4942.357493525855;4993.350864387643;5089.491050894918;3638.508801311842;3730.9067273318115;3744.4944932434046;4458.977705111486;4461.375407442961;4361.956380436187;5097.451274362818;5202.271681964528;5413.374513361056;5446.972765136188;3456;3727.9123690733004;3738.728832197039;3742.9625703742886;3744.6893587244517;5615.971920140414;5693.434305566003;5706.349325299909;5711.6001879868845;5754.367019627837;3766.213002378275;3780.6219378062524;3780.9432858506593;3784.508013958223;3796.126890815073;3592.796413201607;3780.6597406233573;3842.4620553122586;3870.6709929656295;3888.8055597220027;3581.426971684518;3749.7562658427114;4374.606285434326;4430.756308403069;4444.599986001275;5246;5393.2179833923865;5402.324709411324;5412.296401467784;5418.458154184629;1078.665613659768;1137.3687603380204;1211.0916812390708;1226.2519862883635;1267.5803100527469;818.2022528035237;840.4201101240125;847.4618617178136;948.170350942925;954.2985905359556;4198.8110535026;4529.977350113294;5085.3134826797905;5102.821401250939;5122.846314610568;3592;3705.0911288590796;4790.952090479121;5390.703511306838;5634.928963714785;4306.569343065731;4745.098431298043;5081.7713202905215;5095.420419669914;5108.66793658411;2402.4594466244953;2791.2184588315304;2923.9415211695646;2929.516629756109;2948.174511136885;4998.875028124326;5344.866378340573;5349.5720342372515;5392.085809537558;5743.846057728352;5583.05088135018;5615.354234263112;5624.634398764149;5657.405972372924;5668.323633002789;5656.66256908426;5663.716814159193;5670.546356291405;5696.943030569641;5699.886002279931;5054.848354549369;5097.949020509747;5467.808626698046;5524.8342549723575;5548.611597188319;5411.944670789158;5426.620136590401;5431.646942948696;5460.453954604508;5465.344158700981;5021.974890125526;5313.432537401399;5684.8010319638615;5734.541236701138;5737.942620573741;3069.4474994501206;3435.1927297085663;3628.729944519397;3841.5774264831407;3910.843566257318;4608.3778689876435;4650.767461626838;4717.976410117927;5330.547947943989;5519.254900588529;4346.847860324873;4969.906620543475;5008.923081537487;5147.536721695065;5427.267318911896;4661.643616596046;5101.668391554537;5108.463611320758;5278.4721527846905;5340.092184328596;4160.543809666592;5185.792568297225;5213.270142180174;5225.570109898916;5262.131943160123;4663.942370138002;5092.592592592658;5296.973515132399;5391.7059905623155;5391.975524650304;5240.68555886648;5254.5533629641905;5260.500252476008;5274.393444753902;5299.2316114164005 09:04:23 INFO - PROCESS | 1684 | |5;yui.html;704.3731079339389;735.771910707681;746.2537462537464;812.1472453923379;844.3540691371102;15191.924040379796;15358.69282614347;15605.765913511277;15968.520944371672;16530.917345413272;14480.927595362009;14619.488317908876;14717.264136793172;14725.558233252992;14757.18835464049;693.8855088910324;720.2365492577858;865.8181781825824;885.9158379953894;892.3619611977435;711.5197241861723;852.2372476633409;888.3692578269404;888.9244414224798;897.9640814367417;939.6945992552413;944.3578366710634;945.0596656326965;945.678469320431;946.6828612414815;899.2670973156889;914.9496777677225;928.1553786054658;940.261894412886;941.7410212191634;528.8915772266677;548.6762809942113;666.8066260784367;686.5228666077079;689.1936434371797;15127.773083403757;15159;15176.696466070727;15177;15223.162726050063;12385.690357741129;12964.351782410937;13288.800667989988;13871.791923121162;14078.436862525487;12356.62930112098;12908.806367904488;12990.545330913372;13248.801267980987;13274;4475.30632751924;4509.413776209105;4518.57062186073;4618.861434156981;4746.667733258673;104455;105511;110531.89468105296;110942;113229;9724.222062235005;10800.729981750519;10810.945945270223;10972.780544389068;11371.260868043551;21194.78805211959;21465.46336341573;21480.57038859214;21489;21510.56978860414;7155.212926578072;7532.962335188398;7789.532628042222;7947.4436789425345;7968.721094761656;4211.389348544444;4292.484901811803;4385.912281754347;4488.079943611552;4500.549945005473;3406.148462884279;3447.0692912913373;3508.2808024354945;3512.754107212522;3525.11872031992;600.0848705723806;632.1939527102888;643.7521554201666;650.8307839961644;664.2328111031766;511.8515630467197;530.9044372013075;559.7042845811932;561.1074908844911;624.7750809708501;7652.73215437457;8288.96387951506;8578.927634045744;8626.0507327233;8722.30221582272;543.5026950340394;551.8344496650989;596.6670319052485;620.1627802466724;621.5804332075921;3479.1666666666665;4322.157179349996;4323.221820072355;4389.846355377546;4476.843310484118;124872;153597;154812.22593887182;157889.2105539465;158574.5813966241;79819;80413.58759237232;100994;112571;140717;95884.52057739667;112690;120324;122285;124144.9999999982;9179.632814687338;9633.518324083627;9735.26985476078;9765.169960553432;9818.361806482555;59835.102473463805;62394.50421983252;63157.36842631515;64594.677026616446;64928.675356622916;87647;92455.07544924734;92941;94699;94977;10574.2598018138;12746.808797867854;12930.288834114213;13139.60581182566;13487.190768553919;14412.279386030867;14557.126572405266;14570.708585828435;15153.924230378778;15159.469418570297;13628.318584070956;13732.382042807898;13740.793888091486;13745.312734363442;13775.448982040607;13078.673033174247;13379;13975.440982360591;13983.091099078527;14257.765948994698;13537;13680.93159534196;13722.65693357674;13838;14339.067960582528;10858.867593736253;10989.175811813986;11145;11165.776684466427;11869;10470.162387008868;10524.210684198562;10620.84068738954;10882.33837007679;11338.546458141582 09:04:23 INFO - PROCESS | 1684 | __end_tp_report 09:04:23 INFO - PROCESS | 1684 | __start_cc_report 09:04:23 INFO - PROCESS | 1684 | _x_x_mozilla_cycle_collect,1446 09:04:23 INFO - PROCESS | 1684 | __end_cc_report 09:04:23 INFO - PROCESS | 1684 | __startTimestamp1476720263054__endTimestamp 09:04:23 INFO - PROCESS | 1684 | 09:04:23 INFO - PROCESS | 1684 | ------- Summary: start ------- 09:04:23 INFO - PROCESS | 1684 | Number of tests: 6 09:04:23 INFO - PROCESS | 1684 | 09:04:23 INFO - PROCESS | 1684 | [#0] dojo.html Cycles:165 Average:5749.05 Median:5924.67 stddev:638.52 (10.8%) stddev-sans-first:611.87 09:04:23 INFO - PROCESS | 1684 | Values: 3340.1 3363.2 3365.4 3373.6 3430.4 5617.9 5683.1 5756.9 5772.2 5817.3 5709.7 5759.7 5864.1 5864.4 5869.3 5132.0 5180.0 5191.1 5246.1 5255.3 5298.8 5310.5 5318.1 5319.3 5351.9 5172.4 5299.2 5327.3 5356.3 5397.5 5223.4 5265.2 5270.3 5278.1 5285.2 5173.7 5224.4 5235.0 5246.9 5249.5 4774.8 5736.7 5737.5 5746.4 5749.1 5398.0 6170.4 6212.3 6293.0 6300.3 6219.1 6227.6 6299.9 6328.3 6332.1 5840.4 5847.7 5849.9 5867.3 5910.3 6206.7 6259.7 6267.9 6271.0 6303.0 6119.4 6128.4 6145.8 6146.8 6174.2 5259.3 6265.5 6299.1 6305.6 6321.3 6216.5 6230.9 6231.4 6244.0 6250.5 6100.5 6115.3 6132.5 6148.7 6181.2 4934.0 4958.4 4968.2 5001.1 5014.8 4396.6 4409.5 4437.8 4446.9 4460.8 5582.5 5655.2 5788.4 5812.8 5857.8 6203.3 6233.1 6245.0 6253.0 6254.3 5777.3 5796.4 5796.6 5799.7 5852.6 5385.6 5404.9 5411.5 5413.9 5417.8 6183.1 6196.8 6250.7 6309.6 6329.3 6208.9 6295.9 6314.8 6319.7 6335.7 6315.3 6331.7 6334.8 6335.3 6361.3 6106.8 6119.9 6120.4 6189.0 6218.0 6032.0 6287.6 6289.9 6306.4 6322.2 6115.9 6122.1 6125.6 6149.3 6156.0 6019.4 6057.3 6068.3 6085.8 6114.9 4758.6 5933.4 5971.5 6043.4 6078.4 6134.6 6149.4 6154.3 6162.7 6166.5 5784.2 5836.6 5860.3 5874.1 5924.7 09:04:23 INFO - PROCESS | 1684 | 09:04:23 INFO - PROCESS | 1684 | [#1] ext.html Cycles:180 Average:67236.13 Median:39481.71 stddev:146953.95 (372.2%) stddev-sans-first:147280.70 09:04:23 INFO - PROCESS | 1684 | Values: 470.6 515.8 527.0 527.0 535.2 30311.5 39604.0 39744.8 40918.2 41023.8 36194.6 41759.6 41844.6 41850.2 41862.4 9375.9 9428.9 9569.0 9573.2 9584.0 8125.3 9338.9 9465.8 9469.5 9480.5 9481.5 9512.4 9531.3 9555.1 9559.1 9437.7 9494.6 9503.5 9510.0 9553.9 9402.2 9473.2 9479.3 9484.2 9501.4 39107.8 39468.0 39495.4 39495.6 39518.8 3399.4 3404.6 3529.6 3582.6 3820.4 24928.5 29916.0 30059.5 30555.0 30555.8 2643.7 3168.4 3182.2 3192.9 3242.2 467743.3 470056.0 471754.0 472062.0 472745.0 50737.7 55870.9 57358.9 57620.0 57901.8 28561.7 34102.3 34690.5 60681.4 61032.0 16117.7 16262.4 16275.0 16280.4 16297.9 12489.3 12572.6 12580.7 12580.9 12581.9 18696.4 18730.2 18734.7 18760.4 18766.2 6725.9 7399.0 7642.4 8056.7 8102.8 756.9 757.4 892.7 899.7 963.4 27392.5 29459.0 29719.0 29808.4 29862.0 1051.0 1079.9 1132.0 1200.6 1203.7 14833.7 15277.7 15373.7 15496.0 15591.3 560694.2 637800.0 639373.0 641014.0 643038.0 55940.4 56626.0 56681.1 56693.4 56718.7 1354.4 1395.7 1408.9 1419.8 1422.6 25263.7 28986.9 29065.0 29665.1 29920.6 49233.8 49616.8 49699.5 49745.8 49818.8 540609.0 542253.0 543105.0 544214.0 544634.0 25492.7 25577.0 25726.4 25905.7 25977.4 45684.0 46092.3 46746.5 46803.5 46805.0 29984.4 36798.6 37438.6 38548.0 38912.0 37793.8 37937.8 38157.0 38981.2 39499.6 36988.6 37029.1 37997.9 38264.6 38333.8 30290.8 33817.5 33913.7 33924.8 34007.0 24316.0 24845.9 24917.9 25193.7 25222.5 09:04:23 INFO - PROCESS | 1684 | 09:04:23 INFO - PROCESS | 1684 | [#2] jquery.html Cycles:180 Average:34149.97 Median:14466.87 stddev:96592.23 (667.7%) stddev-sans-first:96829.84 09:04:23 INFO - PROCESS | 1684 | Values: 341.3 350.0 360.6 375.3 395.3 15014.3 15193.0 16058.6 20240.5 20743.6 20124.4 20747.8 21271.0 21426.0 21571.8 5285.4 5826.6 6155.4 6160.2 6162.2 5968.7 6083.6 6113.4 6114.4 6127.8 5739.6 5994.7 6048.2 6096.9 6101.0 5955.4 6013.1 6017.2 6019.3 6021.0 4975.2 5173.3 5216.9 5247.8 5262.1 26124.5 26163.9 26304.5 26493.3 26762.9 2038.3 2411.4 2424.2 2629.3 2931.8 9926.5 10269.5 10278.8 10299.3 13461.4 1647.9 1844.2 1879.2 1882.4 1890.3 108797.0 111618.0 120228.0 120506.4 121525.0 11396.3 11530.3 11552.2 11561.9 11745.5 14367.0 14372.4 14384.5 14422.4 14423.0 8133.3 8386.3 8434.3 8587.8 8596.9 6910.6 8075.7 8368.2 8423.7 8591.0 3795.5 4359.5 4366.6 4408.6 4612.0 1734.4 1833.5 1891.5 1900.2 1904.3 711.6 759.7 783.7 823.9 935.9 18530.9 21150.7 21476.7 21582.0 21637.4 908.7 944.2 980.5 1006.2 1067.4 10699.9 10917.4 11232.9 11355.7 11379.2 557652.0 567363.0 584579.0 589536.0 590488.0 120992.0 123410.0 126012.0 129380.0 130559.0 854.2 951.1 971.5 972.1 988.8 18303.3 18920.9 19052.6 19181.7 19187.2 36561.0 41072.0 41845.2 49150.5 54895.7 77924.6 77930.2 79187.9 81167.0 81206.6 6839.6 7460.4 7465.4 8188.4 8205.0 10558.5 12749.8 13171.7 13245.4 13308.9 9486.4 11180.4 11501.8 11597.2 11747.2 10915.8 11105.3 11109.1 11483.5 11572.2 11061.2 11738.5 11808.7 11914.2 11951.9 5738.4 7424.9 7487.6 7508.0 8320.4 10917.5 10993.4 11332.3 11541.5 11683.4 09:04:23 INFO - PROCESS | 1684 | 09:04:23 INFO - PROCESS | 1684 | [#3] mootools.html Cycles:180 Average:5749.66 Median:4848.74 stddev:3229.84 (66.6%) stddev-sans-first:3223.40 09:04:23 INFO - PROCESS | 1684 | Values: 1538.8 1569.0 1811.3 1915.4 1991.5 8628.6 9110.0 9135.9 9137.4 9143.2 7050.5 8276.9 8309.3 8706.8 8955.7 2364.1 2396.8 2407.2 2410.4 2410.7 2370.2 2372.7 2402.8 2403.4 2404.8 2375.6 2385.0 2398.5 2400.1 2413.2 2348.6 2350.1 2354.1 2361.0 2378.8 1976.9 2336.5 2372.2 2376.2 2381.5 8930.1 8973.1 9052.9 9063.0 9118.3 5304.4 5483.7 5484.6 5494.2 5508.8 3584.6 3600.2 3654.3 3661.2 3670.2 1073.4 1077.0 1079.8 1085.3 1096.6 11747.9 11846.3 11882.3 11885.2 11895.3 4558.6 4661.3 4843.1 4881.2 4892.8 8604.5 9934.7 9952.6 10007.5 10017.7 5431.9 5437.3 5437.3 5442.0 5451.0 4442.8 4590.5 4614.3 4931.7 4944.8 3892.9 3932.6 4027.7 4044.4 4058.2 1270.2 1275.6 1277.7 1288.9 1299.4 754.5 843.4 872.7 929.2 964.6 5748.7 5836.2 5948.2 5970.4 6143.2 892.2 941.6 950.3 962.9 1000.8 3797.0 3879.4 3927.2 4026.5 4047.2 10783.6 10907.3 11124.8 11184.0 11188.4 8775.3 10975.0 11048.9 11100.5 11124.8 8143.7 8381.9 8388.8 8393.7 8406.2 5763.7 5820.5 5863.9 5931.8 6169.9 10232.6 10247.6 10251.7 10264.1 10286.3 9744.4 10014.6 10173.5 10231.3 10321.3 7049.1 7289.4 7300.5 7576.7 7783.6 8117.9 8422.2 8435.6 8533.5 8556.5 7357.4 7462.5 7610.9 7673.0 7805.8 6451.8 6465.9 7224.6 7252.7 7334.0 7111.1 7122.5 7219.8 7230.4 7230.4 4886.1 4914.9 4960.6 4970.3 5068.7 4635.7 4661.0 4762.5 4795.4 4854.3 09:04:23 INFO - PROCESS | 1684 | 09:04:23 INFO - PROCESS | 1684 | [#4] prototype.html Cycles:180 Average:4328.52 Median:4929.53 stddev:1326.99 (26.9%) stddev-sans-first:1295.71 09:04:23 INFO - PROCESS | 1684 | Values: 294.5 296.0 307.4 311.6 313.6 4291.9 4916.7 5213.8 5241.3 5249.0 4178.6 4808.6 5039.7 5071.0 5083.8 3811.3 3961.5 3970.0 3984.6 3992.0 3704.8 3988.3 4029.9 4043.3 4051.2 3866.8 3912.2 3912.7 3943.7 3944.1 3588.1 3904.8 3914.0 3940.7 3949.9 3359.1 3401.0 3404.0 3417.6 3424.9 4630.8 4772.6 4942.4 4993.4 5089.5 3638.5 3730.9 3744.5 4459.0 4461.4 4362.0 5097.5 5202.3 5413.4 5447.0 3456.0 3727.9 3738.7 3743.0 3744.7 5616.0 5693.4 5706.3 5711.6 5754.4 3766.2 3780.6 3780.9 3784.5 3796.1 3592.8 3780.7 3842.5 3870.7 3888.8 3581.4 3749.8 4374.6 4430.8 4444.6 5246.0 5393.2 5402.3 5412.3 5418.5 1078.7 1137.4 1211.1 1226.3 1267.6 818.2 840.4 847.5 948.2 954.3 4198.8 4530.0 5085.3 5102.8 5122.8 3592.0 3705.1 4791.0 5390.7 5634.9 4306.6 4745.1 5081.8 5095.4 5108.7 2402.5 2791.2 2923.9 2929.5 2948.2 4998.9 5344.9 5349.6 5392.1 5743.8 5583.1 5615.4 5624.6 5657.4 5668.3 5656.7 5663.7 5670.5 5696.9 5699.9 5054.8 5097.9 5467.8 5524.8 5548.6 5411.9 5426.6 5431.6 5460.5 5465.3 5022.0 5313.4 5684.8 5734.5 5737.9 3069.4 3435.2 3628.7 3841.6 3910.8 4608.4 4650.8 4718.0 5330.5 5519.3 4346.8 4969.9 5008.9 5147.5 5427.3 4661.6 5101.7 5108.5 5278.5 5340.1 4160.5 5185.8 5213.3 5225.6 5262.1 4663.9 5092.6 5297.0 5391.7 5392.0 5240.7 5254.6 5260.5 5274.4 5299.2 09:04:23 INFO - PROCESS | 1684 | 09:04:23 INFO - PROCESS | 1684 | [#5] yui.html Cycles:180 Average:24333.09 Median:3518.94 stddev:38584.07 (1096.5%) stddev-sans-first:38651.52 09:04:23 INFO - PROCESS | 1684 | Values: 704.4 735.8 746.3 812.1 844.4 15191.9 15358.7 15605.8 15968.5 16530.9 14480.9 14619.5 14717.3 14725.6 14757.2 693.9 720.2 865.8 885.9 892.4 711.5 852.2 888.4 888.9 898.0 939.7 944.4 945.1 945.7 946.7 899.3 914.9 928.2 940.3 941.7 528.9 548.7 666.8 686.5 689.2 15127.8 15159.0 15176.7 15177.0 15223.2 12385.7 12964.4 13288.8 13871.8 14078.4 12356.6 12908.8 12990.5 13248.8 13274.0 4475.3 4509.4 4518.6 4618.9 4746.7 104455.0 105511.0 110531.9 110942.0 113229.0 9724.2 10800.7 10810.9 10972.8 11371.3 21194.8 21465.5 21480.6 21489.0 21510.6 7155.2 7533.0 7789.5 7947.4 7968.7 4211.4 4292.5 4385.9 4488.1 4500.5 3406.1 3447.1 3508.3 3512.8 3525.1 600.1 632.2 643.8 650.8 664.2 511.9 530.9 559.7 561.1 624.8 7652.7 8289.0 8578.9 8626.1 8722.3 543.5 551.8 596.7 620.2 621.6 3479.2 4322.2 4323.2 4389.8 4476.8 124872.0 153597.0 154812.2 157889.2 158574.6 79819.0 80413.6 100994.0 112571.0 140717.0 95884.5 112690.0 120324.0 122285.0 124145.0 9179.6 9633.5 9735.3 9765.2 9818.4 59835.1 62394.5 63157.4 64594.7 64928.7 87647.0 92455.1 92941.0 94699.0 94977.0 10574.3 12746.8 12930.3 13139.6 13487.2 14412.3 14557.1 14570.7 15153.9 15159.5 13628.3 13732.4 13740.8 13745.3 13775.4 13078.7 13379.0 13975.4 13983.1 14257.8 13537.0 13680.9 13722.7 13838.0 14339.1 10858.9 10989.2 11145.0 11165.8 11869.0 10470.2 10524.2 10620.8 10882.3 11338.5 09:04:23 INFO - PROCESS | 1684 | -------- Summary: end -------- 09:04:23 INFO - PROCESS | 1684 | 09:04:23 INFO - TEST-INFO | 1684: exit 0 09:04:23 INFO - TEST-OK | dromaeo_css | took 1119852ms 09:04:23 INFO - TEST-START | kraken 09:04:23 INFO - operating with platform_type : mac_ 09:04:23 INFO - Initialising browser for kraken test... 09:04:23 INFO - TEST-INFO | started process 1802 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/tmpAP_x2d/profile http://localhost:49279/getInfo.html) 09:04:25 INFO - PROCESS | 1802 | __metrics Screen width/height:1600/1200 09:04:25 INFO - PROCESS | 1802 | colorDepth:24 09:04:25 INFO - PROCESS | 1802 | Browser inner width/height: 1024/689 09:04:25 INFO - PROCESS | 1802 | __metrics 09:04:26 INFO - PROCESS | 1802 | 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" 09:04:26 INFO - PROCESS | 1802 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:04:26 INFO - TEST-INFO | 1802: exit 0 09:04:26 INFO - Browser initialized. 09:04:26 INFO - Running cycle 1/1 for kraken test... 09:04:26 INFO - TEST-INFO | started process 1804 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/tmpAP_x2d/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:04:38 INFO - PROCESS | 1804 | RSS: Main: 239087616 09:04:38 INFO - PROCESS | 1804 | 09:04:41 INFO - PROCESS | 1804 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 09:04:42 INFO - PROCESS | 1804 | RSS: Main: 290955264 09:04:42 INFO - PROCESS | 1804 | 09:04:45 INFO - PROCESS | 1804 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 09:04:45 INFO - PROCESS | 1804 | RSS: Main: 289681408 09:04:45 INFO - PROCESS | 1804 | 09:04:49 INFO - PROCESS | 1804 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 09:04:49 INFO - PROCESS | 1804 | RSS: Main: 385052672 09:04:49 INFO - PROCESS | 1804 | 09:04:51 INFO - PROCESS | 1804 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 09:04:52 INFO - PROCESS | 1804 | RSS: Main: 285626368 09:04:52 INFO - PROCESS | 1804 | 09:04:54 INFO - PROCESS | 1804 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:04:55 INFO - PROCESS | 1804 | RSS: Main: 289435648 09:04:55 INFO - PROCESS | 1804 | 09:04:57 INFO - PROCESS | 1804 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 09:04:58 INFO - PROCESS | 1804 | RSS: Main: 326340608 09:04:58 INFO - PROCESS | 1804 | 09:05:01 INFO - PROCESS | 1804 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 09:05:01 INFO - PROCESS | 1804 | RSS: Main: 327409664 09:05:01 INFO - PROCESS | 1804 | 09:05:04 INFO - PROCESS | 1804 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 09:05:05 INFO - PROCESS | 1804 | RSS: Main: 326074368 09:05:05 INFO - PROCESS | 1804 | 09:05:06 INFO - PROCESS | 1804 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:05:07 INFO - PROCESS | 1804 | RSS: Main: 303099904 09:05:07 INFO - PROCESS | 1804 | 09:05:09 INFO - PROCESS | 1804 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:05:09 INFO - PROCESS | 1804 | RSS: Main: 304840704 09:05:09 INFO - PROCESS | 1804 | 09:05:11 INFO - PROCESS | 1804 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:05:12 INFO - PROCESS | 1804 | RSS: Main: 310284288 09:05:12 INFO - PROCESS | 1804 | 09:05:15 INFO - PROCESS | 1804 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:05:16 INFO - PROCESS | 1804 | RSS: Main: 320126976 09:05:16 INFO - PROCESS | 1804 | 09:05:19 INFO - PROCESS | 1804 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:05:19 INFO - PROCESS | 1804 | RSS: Main: 319676416 09:05:19 INFO - PROCESS | 1804 | 09:05:21 INFO - PROCESS | 1804 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 09:05:22 INFO - PROCESS | 1804 | RSS: Main: 362975232 09:05:22 INFO - PROCESS | 1804 | 09:05:22 INFO - PROCESS | 1804 | __start_tp_report 09:05:22 INFO - PROCESS | 1804 | _x_x_mozilla_page_load 09:05:22 INFO - PROCESS | 1804 | _x_x_mozilla_page_load_details 09:05:22 INFO - PROCESS | 1804 | |i|pagename|runs| 09:05:22 INFO - PROCESS | 1804 | |0;ai-astar;113;112;114;113;113;112;111;112;112;114 09:05:22 INFO - PROCESS | 1804 | |1;audio-beat-detection;172;141;116;153;143;114;117;115;143;112 09:05:22 INFO - PROCESS | 1804 | |2;audio-dft;182;182;183;185;186;184;182;181;183;183 09:05:22 INFO - PROCESS | 1804 | |3;audio-fft;78;59;59;87;80;58;59;58;82;57 09:05:22 INFO - PROCESS | 1804 | |4;audio-oscillator;85;71;94;70;68;69;94;68;69;90 09:05:22 INFO - PROCESS | 1804 | |5;imaging-gaussian-blur;89;85;89;87;88;86;89;87;88;84 09:05:22 INFO - PROCESS | 1804 | |6;imaging-darkroom;97;97;98;96;97;97;98;96;96;96 09:05:22 INFO - PROCESS | 1804 | |7;imaging-desaturate;84;81;79;79;83;77;85;82;83;80 09:05:22 INFO - PROCESS | 1804 | |8;json-parse-financial;59;56;55;56;56;54;55;54;57;54 09:05:22 INFO - PROCESS | 1804 | |9;json-stringify-tinderbox;66;73;66;76;74;71;69;72;67;69 09:05:22 INFO - PROCESS | 1804 | |10;stanford-crypto-aes;91;87;86;88;87;89;83;89;87;88 09:05:22 INFO - PROCESS | 1804 | |11;stanford-crypto-ccm;148;151;152;156;142;146;148;157;182;148 09:05:22 INFO - PROCESS | 1804 | |12;stanford-crypto-pbkdf2;176;178;177;174;175;174;169;170;175;170 09:05:22 INFO - PROCESS | 1804 | |13;stanford-crypto-sha256-iterative;70;70;84;88;89;88;68;68;69;69 09:05:22 INFO - PROCESS | 1804 | __end_tp_report 09:05:22 INFO - PROCESS | 1804 | __start_cc_report 09:05:22 INFO - PROCESS | 1804 | _x_x_mozilla_cycle_collect,2012 09:05:22 INFO - PROCESS | 1804 | __end_cc_report 09:05:22 INFO - PROCESS | 1804 | __startTimestamp1476720322082__endTimestamp 09:05:22 INFO - PROCESS | 1804 | 09:05:22 INFO - PROCESS | 1804 | ------- Summary: start ------- 09:05:22 INFO - PROCESS | 1804 | Number of tests: 14 09:05:22 INFO - PROCESS | 1804 | 09:05:22 INFO - PROCESS | 1804 | [#0] ai-astar Cycles:10 Average:112.60 Median:113.00 stddev:0.97 (0.9%) stddev-sans-first:1.01 09:05:22 INFO - PROCESS | 1804 | Values: 113.0 112.0 114.0 113.0 113.0 112.0 111.0 112.0 112.0 114.0 09:05:22 INFO - PROCESS | 1804 | 09:05:22 INFO - PROCESS | 1804 | [#1] audio-beat-detection Cycles:10 Average:132.60 Median:142.00 stddev:20.69 (14.6%) stddev-sans-first:16.32 09:05:22 INFO - PROCESS | 1804 | Values: 172.0 141.0 116.0 153.0 143.0 114.0 117.0 115.0 143.0 112.0 09:05:22 INFO - PROCESS | 1804 | 09:05:22 INFO - PROCESS | 1804 | [#2] audio-dft Cycles:10 Average:183.10 Median:183.00 stddev:1.52 (0.8%) stddev-sans-first:1.56 09:05:22 INFO - PROCESS | 1804 | Values: 182.0 182.0 183.0 185.0 186.0 184.0 182.0 181.0 183.0 183.0 09:05:22 INFO - PROCESS | 1804 | 09:05:22 INFO - PROCESS | 1804 | [#3] audio-fft Cycles:10 Average:67.70 Median:68.50 stddev:12.31 (18.0%) stddev-sans-first:12.48 09:05:22 INFO - PROCESS | 1804 | Values: 78.0 59.0 59.0 87.0 80.0 58.0 59.0 58.0 82.0 57.0 09:05:22 INFO - PROCESS | 1804 | 09:05:22 INFO - PROCESS | 1804 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:78.00 stddev:11.45 (14.7%) stddev-sans-first:11.84 09:05:22 INFO - PROCESS | 1804 | Values: 85.0 71.0 94.0 70.0 68.0 69.0 94.0 68.0 69.0 90.0 09:05:22 INFO - PROCESS | 1804 | 09:05:22 INFO - PROCESS | 1804 | [#5] imaging-gaussian-blur Cycles:10 Average:87.20 Median:88.00 stddev:1.75 (2.0%) stddev-sans-first:1.73 09:05:22 INFO - PROCESS | 1804 | Values: 89.0 85.0 89.0 87.0 88.0 86.0 89.0 87.0 88.0 84.0 09:05:22 INFO - PROCESS | 1804 | 09:05:22 INFO - PROCESS | 1804 | [#6] imaging-darkroom Cycles:10 Average:96.80 Median:97.00 stddev:0.79 (0.8%) stddev-sans-first:0.83 09:05:22 INFO - PROCESS | 1804 | Values: 97.0 97.0 98.0 96.0 97.0 97.0 98.0 96.0 96.0 96.0 09:05:22 INFO - PROCESS | 1804 | 09:05:22 INFO - PROCESS | 1804 | [#7] imaging-desaturate Cycles:10 Average:81.30 Median:82.50 stddev:2.54 (3.1%) stddev-sans-first:2.50 09:05:22 INFO - PROCESS | 1804 | Values: 84.0 81.0 79.0 79.0 83.0 77.0 85.0 82.0 83.0 80.0 09:05:22 INFO - PROCESS | 1804 | 09:05:22 INFO - PROCESS | 1804 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:56.00 stddev:1.58 (2.8%) stddev-sans-first:1.09 09:05:22 INFO - PROCESS | 1804 | Values: 59.0 56.0 55.0 56.0 56.0 54.0 55.0 54.0 57.0 54.0 09:05:22 INFO - PROCESS | 1804 | 09:05:22 INFO - PROCESS | 1804 | [#9] json-stringify-tinderbox Cycles:10 Average:70.30 Median:71.50 stddev:3.47 (4.8%) stddev-sans-first:3.31 09:05:22 INFO - PROCESS | 1804 | Values: 66.0 73.0 66.0 76.0 74.0 71.0 69.0 72.0 67.0 69.0 09:05:22 INFO - PROCESS | 1804 | 09:05:22 INFO - PROCESS | 1804 | [#10] stanford-crypto-aes Cycles:10 Average:87.50 Median:88.00 stddev:2.12 (2.4%) stddev-sans-first:1.83 09:05:22 INFO - PROCESS | 1804 | Values: 91.0 87.0 86.0 88.0 87.0 89.0 83.0 89.0 87.0 88.0 09:05:22 INFO - PROCESS | 1804 | 09:05:22 INFO - PROCESS | 1804 | [#11] stanford-crypto-ccm Cycles:10 Average:153.00 Median:151.50 stddev:11.14 (7.4%) stddev-sans-first:11.66 09:05:22 INFO - PROCESS | 1804 | Values: 148.0 151.0 152.0 156.0 142.0 146.0 148.0 157.0 182.0 148.0 09:05:22 INFO - PROCESS | 1804 | 09:05:22 INFO - PROCESS | 1804 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.80 Median:175.00 stddev:3.12 (1.8%) stddev-sans-first:3.21 09:05:22 INFO - PROCESS | 1804 | Values: 176.0 178.0 177.0 174.0 175.0 174.0 169.0 170.0 175.0 170.0 09:05:22 INFO - PROCESS | 1804 | 09:05:22 INFO - PROCESS | 1804 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.30 Median:77.00 stddev:9.53 (12.4%) stddev-sans-first:9.84 09:05:22 INFO - PROCESS | 1804 | Values: 70.0 70.0 84.0 88.0 89.0 88.0 68.0 68.0 69.0 69.0 09:05:22 INFO - PROCESS | 1804 | -------- Summary: end -------- 09:05:22 INFO - PROCESS | 1804 | 09:05:22 INFO - TEST-INFO | 1804: exit 0 09:05:23 INFO - TEST-OK | kraken | took 59280ms 09:05:23 INFO - SUITE-END | took 1179s 09:05:23 INFO - Completed test suite (00:19:39) 09:05:23 INFO - javascript benchmark 09:05:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3340.0814775936615, 3363.1760218746413, 3365.4110530657144, 3373.578302712161, 3430.433978393565, 5617.859553511164, 5683.147527870821, 5756.884862302757, 5772.163036359725, 5817.301923769149, 5709.743061562229, 5759.712014399273, 5864.091065884784, 5864.442877926601, 5869.266341707286, 5132.024915266087, 5179.964007198557, 5191.091558977183, 5246.134387826004, 5255.316808814868, 5298.788048478056, 5310.468953104697, 5318.149096144618, 5319.255304257407, 5351.942414418376, 5172.353455818023, 5299.152135658314, 5327.280817089682, 5356.330458692663, 5397.514223719864, 5223.399309079467, 5265.210218467222, 5270.262163297141, 5278.1291086970605, 5285.180796976437, 5173.715445650488, 5224.425313215581, 5235.005348983681, 5246.921296180522, 5249.527542521153, 4774.761261936889, 5736.655800651933, 5737.512311453532, 5746.396628354005, 5749.137629355589, 5398.0, 6170.352113028112, 6212.316645169028, 6293.024581189923, 6300.275468321156, 6219.129321894939, 6227.564070515065, 6299.905501417483, 6328.303886572473, 6332.113504109382, 5840.3575606683235, 5847.736851841635, 5849.853753656193, 5867.266591676041, 5910.290765108214, 6206.658633775095, 6259.6870156492, 6267.905981410283, 6271.0, 6302.968485157545, 6119.418655227746, 6128.4143737903505, 6145.84635384119, 6146.801373732079, 6174.228221472316, 5259.316288882421, 6265.467435268053, 6299.149614802035, 6305.621662700252, 6321.273053598895, 6216.515474257597, 6230.875382492415, 6231.4391704746795, 6243.969744992026, 6250.468710159596, 6100.48145907594, 6115.270764408792, 6132.4521822465285, 6148.723307451175, 6181.1655426516845, 4933.950660493421, 4958.380202474691, 4968.205087186033, 5001.074801161797, 5014.774335154927, 4396.60430561251, 4409.470863496401, 4437.800298986554, 4446.933296000562, 4460.754658493749, 5582.469665381782, 5655.208270842086, 5788.392218817048, 5812.7674893003805, 5857.7949771757785, 6203.286622038433, 6233.1273621693, 6245.0, 6252.968735156295, 6254.2807577129215, 5777.30672319316, 5796.3913789052385, 5796.62321949072, 5799.739011744397, 5852.648841069465, 5385.5960802939935, 5404.918926216032, 5411.485908838654, 5413.864653383697, 5417.83746487606, 6183.103449999815, 6196.783112591038, 6250.749970001331, 6309.621422714652, 6329.303776584664, 6208.875822483616, 6295.866743985944, 6314.778982735582, 6319.715612797343, 6335.714892829926, 6315.27374351937, 6331.746730130927, 6334.778282760081, 6335.303116657256, 6361.268454127835, 6106.786262480792, 6119.868162944292, 6120.355709576858, 6189.0097584386285, 6217.968910155601, 6032.0, 6287.559870808999, 6289.937100628935, 6306.432421082125, 6322.178116844781, 6115.938840611715, 6122.081687746873, 6125.601835880653, 6149.2620885494025, 6155.969220153871, 6019.367966363644, 6057.303410107894, 6068.302145253352, 6085.817425477243, 6114.938850611437, 4758.595519380891, 5933.376995415592, 5971.462568368867, 6043.395660433922, 6078.3617720138745, 6134.5705800595315, 6149.385061493815, 6154.323024467394, 6162.661053642093, 6166.537509686702, 5784.190213370048, 5836.620619659709, 5860.326062502867, 5874.148248504029, 5924.674142922181], "value": 5709.165149184366, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [470.57177968049064, 515.7859488312349, 526.9724038125651, 526.9762860671269, 535.218580871927, 30311.545326820087, 39604.00989975252, 39744.801275993654, 40918.181636367255, 41023.79488102559, 36194.63805361952, 41759.58240417595, 41844.58155418445, 41850.162996740044, 41862.37206441898, 9375.906240937571, 9428.905710942872, 9568.952155239249, 9573.186279166244, 9584.041595840396, 8125.3093487053675, 9338.906610933871, 9465.763355916088, 9469.479178645171, 9480.525973701288, 9481.47851868147, 9512.429254244733, 9531.332806703533, 9555.092266234731, 9559.091886270793, 9437.669681561147, 9494.620215191384, 9503.477308748015, 9509.952450237704, 9553.90446095544, 9402.15380615748, 9473.194778443773, 9479.289053321028, 9484.241260699127, 9501.4299142051, 39107.80446097751, 39468.01329966745, 39495.40756888649, 39495.60504394948, 39518.80240598807, 3399.354122716676, 3404.591449026108, 3529.5588051493564, 3582.6059133495296, 3820.3505404081093, 24928.5014299713, 29915.952941646938, 30059.549106763418, 30555.0, 30555.847220763753, 2643.729743206183, 3168.445522033635, 3182.236263296792, 3192.8722851085927, 3242.2010184407027, 467743.3225667734, 470056.0, 471754.0, 472062.0, 472745.0, 50737.746311268944, 55870.882582348124, 57358.85282294414, 57620.0, 57901.8419631605, 28561.71438285632, 34102.31795364079, 34690.47964280538, 60681.39318606757, 61032.0, 16117.67764644724, 16262.430814921421, 16275.023498590122, 16280.43018494347, 16297.91851040761, 12489.31308778026, 12572.622821315375, 12580.685482862818, 12580.874191258154, 12581.874181258254, 18696.439106826816, 18730.1571429286, 18734.71897921505, 18760.43718688414, 18766.249350025846, 6725.8654826903185, 7399.0, 7642.421032646088, 8056.7180148694515, 8102.7569172924905, 756.8751156059299, 757.4357103957477, 892.6922059183328, 899.7165892743895, 963.4180396537347, 27392.45215095687, 29459.0, 29719.0, 29808.40383192324, 29861.954831581224, 1050.968470945873, 1079.865016872891, 1131.9886801132047, 1200.6338066889507, 1203.7231436769591, 14833.70332593342, 15277.694446111016, 15373.692526149414, 15495.992760470534, 15591.298391572407, 560694.1965290147, 637800.0, 639373.0, 641014.0, 643038.0, 55940.44059559352, 56626.0, 56681.149782753295, 56693.43306566964, 56718.71640641853, 1354.4040622126233, 1395.7417877692455, 1408.936597853099, 1419.7941298511657, 1422.5732280316072, 25263.747362526876, 28986.855065724536, 29064.982725604503, 29665.11004669863, 29920.551191731705, 49233.75383123061, 49616.75191624019, 49699.50300496949, 49745.75127124341, 49818.75090624524, 540609.0, 542253.0, 543105.0, 544214.0, 544634.0, 25492.745072549038, 25577.0, 25726.356841078374, 25905.74094259033, 25977.35056623524, 45684.0, 46092.30861537147, 46746.53253467422, 46803.53196467992, 46805.0, 29984.400311994075, 36798.63201367952, 37438.6256137446, 38548.0, 38912.0, 37793.81103094467, 37937.81031094827, 38157.0, 38981.22037559289, 39499.617513388046, 36988.6301136996, 37029.074273143386, 37997.86006419812, 38264.61735382611, 38333.80833095816, 30290.84854575713, 33817.49273760945, 33913.66086339105, 33924.812631557776, 34007.0, 24316.0, 24845.87577062103, 24917.87541062283, 25193.709550354306, 25222.49555008853], "value": 19373.67835441537, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.31225580455373, 349.952756377889, 360.5727213252296, 375.3225428102275, 395.25494442974986, 15014.324355404005, 15193.0, 16058.59853503663, 20240.493987650316, 20743.585128297425, 20124.396268111945, 20747.792522074775, 21271.04280307386, 21425.99999999996, 21571.784282157212, 5285.418603953562, 5826.592138550303, 6155.415235552616, 6160.19917410736, 6162.22972128484, 5968.67172305523, 6083.604565703237, 6113.358097399753, 6114.449699527042, 6127.816165515042, 5739.598228124033, 5994.730237139339, 6048.1834952281315, 6096.908546371808, 6100.969495152518, 5955.434233747808, 6013.128096426038, 6017.217761690996, 6019.307779605358, 6020.969895150496, 4975.203967365241, 5173.3016042834115, 5216.895662086775, 5247.790088396459, 5262.079136151158, 26124.477510449684, 26163.869180653975, 26304.473910521872, 26493.3376665583, 26762.866185669143, 2038.3375402993938, 2411.4141588981443, 2424.1878970544713, 2629.2638061342855, 2931.7801164912717, 9926.503674816302, 10269.486525673687, 10278.783778405681, 10299.330543514647, 13461.394237259346, 1647.9258433370528, 1844.2346426233232, 1879.2389082421505, 1882.4070417818343, 1890.3194849854178, 108797.0, 111618.0, 120228.0, 120506.39746801298, 121524.99999999911, 11396.259243149168, 11530.308181509135, 11552.249103808226, 11561.942190288995, 11745.4714537846, 14367.0, 14372.425102995763, 14384.496542620958, 14422.350994205286, 14423.0, 8133.308668763103, 8386.329093672492, 8434.325253979667, 8587.785305367415, 8596.8710469343, 6910.550814197061, 8075.717349892726, 8368.163183681583, 8423.70517031901, 8590.957045214735, 3795.544534655859, 4359.542248063971, 4366.585174408426, 4408.647308215335, 4612.0, 1734.4363081998401, 1833.4591295567839, 1891.489297889562, 1900.211412263895, 1904.2668572599648, 711.6441779110445, 759.7350411564682, 783.6904422752981, 823.9423240373236, 935.9064093590587, 18530.907345463187, 21150.682739758915, 21476.677849832264, 21582.028808703333, 21637.35087947364, 908.6819613135482, 944.2115833279152, 980.5097451274363, 1006.2402885821158, 1067.4022547373495, 10699.946500267604, 10917.39954302505, 11232.93287137737, 11355.716107097389, 11379.203455758183, 557652.0, 567363.0, 584579.0, 589536.0, 590488.0, 120992.0, 123409.99999999821, 126012.0, 129380.0, 130559.0, 854.1970547685156, 951.058452132384, 971.5093877591771, 972.0619602083882, 988.8071825993862, 18303.26786928508, 18920.905395473485, 19052.618947621246, 19181.712274316174, 19187.232510699974, 36561.0, 41072.0, 41845.163096738506, 49150.5084949146, 54895.72552137214, 77924.61037694775, 77930.22069779229, 79187.87309142205, 81167.0, 81206.59396702978, 6839.555428897106, 7460.4031677466755, 7465.440091993014, 8188.385871059575, 8205.0, 10558.472076395996, 12749.826337203755, 13171.748683875017, 13245.40395682216, 13308.933455333048, 9486.430814150897, 11180.440977950908, 11501.769964600828, 11597.18819682614, 11747.240882917384, 10915.83626245591, 11105.278156919774, 11109.11127109813, 11483.483243253906, 11572.18994670365, 11061.230203167455, 11738.530458781554, 11808.70478238051, 11914.225575337576, 11951.940240298743, 5738.426157384395, 7424.851502970018, 7487.588182650006, 7507.962460187664, 8320.417570769989, 10917.508712107816, 10993.395363255098, 11332.320060796379, 11541.538338466367, 11683.357415342238], "value": 9431.245030593915, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1538.8230353509346, 1568.9607759806004, 1811.293595497756, 1915.434946690726, 1991.462305177602, 8628.56857157142, 9109.954450227757, 9135.862962055575, 9137.406068605544, 9143.177114059734, 7050.5417147885355, 8276.91723082769, 8309.252167304941, 8706.782330441742, 8955.731328060152, 2364.0780095762666, 2396.7962723168553, 2407.2417188585628, 2410.385351735305, 2410.7468715784853, 2370.229675355508, 2372.6796882420913, 2402.795762360193, 2403.4111642647576, 2404.8196385271085, 2375.6436534519876, 2384.96422553661, 2398.496315773681, 2400.075970751265, 2413.2157048959157, 2348.567373901927, 2350.142198097682, 2354.058376649337, 2361.0, 2378.797802186816, 1976.8715033522774, 2336.4742932840145, 2372.2290255666803, 2376.2039716694926, 2381.4522659788345, 8930.062343453908, 8973.057828927998, 9052.909470905273, 9063.0, 9118.270538356981, 5304.36347638282, 5483.698396588186, 5484.61607687462, 5494.230807686927, 5508.779648814043, 3584.605693373727, 3600.225951420431, 3654.3239500692457, 3661.1945372018113, 3670.247599242149, 1073.4257172412722, 1077.0, 1079.7894410589938, 1085.2891356161726, 1096.6216655254004, 11747.941260293645, 11846.289222646668, 11882.28706277626, 11885.22746021506, 11895.345755983417, 4558.566936141061, 4661.300804879227, 4843.128236917319, 4881.243407271879, 4892.779824907888, 8604.526751028628, 9934.652287169914, 9952.601895924226, 10007.549660265306, 10017.749556261006, 5431.945680543223, 5437.32033495813, 5437.32033495813, 5442.047641662693, 5451.0, 4442.777861106932, 4590.517995610479, 4614.2617181250835, 4931.654784165074, 4944.752762361867, 3892.9416058759143, 3932.643238082857, 4027.7381970172432, 4044.3529035354204, 4058.24922389353, 1270.2188154285234, 1275.6428200103985, 1277.744451109763, 1288.941997610091, 1299.3957809618385, 754.5435011817881, 843.4433274039227, 872.7425409504211, 929.2148134826061, 964.6382606522554, 5748.741306641127, 5836.182934389189, 5948.196993405922, 5970.373110823388, 6143.232095988002, 892.205936716323, 941.609232168656, 950.292032435826, 962.9277804164576, 1000.7798284377425, 3796.962030379716, 3879.379299312153, 3927.1752931884052, 4026.5168179818024, 4047.1500984793524, 10783.622573210057, 10907.345559266469, 11124.833127503094, 11184.0, 11188.440577971069, 8775.341849361323, 10974.957379048978, 11048.889511104786, 11100.500477478532, 11124.833127503094, 8143.714969976022, 8381.916180838236, 8388.832223355621, 8393.664253429795, 8406.24343809048, 5763.7118144093465, 5820.505257053198, 5863.941360586339, 5931.792387266425, 6169.938300617116, 10232.64185753495, 10247.590096396061, 10251.7437064074, 10264.127549158107, 10286.331388459726, 9744.415335079917, 10014.599416023277, 10173.542190601293, 10231.334963227366, 10321.277510574488, 7049.08361912948, 7289.380402665833, 7300.488965772345, 7576.659050342638, 7783.571903545359, 8117.878231826646, 8422.157784221528, 8435.620397082268, 8533.530655813742, 8556.529390883561, 7357.411407087528, 7462.4854657690275, 7610.9238907612435, 7673.0, 7805.843883122192, 6451.838704032249, 6465.903011454926, 7224.638768061681, 7252.746153884589, 7333.973243745773, 7111.111111111111, 7122.465815063994, 7219.783406497813, 7230.385417239593, 7230.385417239593, 4886.096072226649, 4914.852554423373, 4960.627952903475, 4970.254461830755, 5068.695878247317, 4635.675502714777, 4661.0, 4762.499937506651, 4795.448523419712, 4854.344663470386], "value": 4623.899019961868, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.49200129776136, 295.96152500174986, 307.4496650994719, 311.6011505273251, 313.5641458372862, 4291.884110131365, 4916.704997700132, 5213.765380557875, 5241.3227767114495, 5248.973755131228, 4178.561251068632, 4808.595702148926, 5039.672421292614, 5071.0, 5083.771230294637, 3811.2758575870635, 3961.4850069490926, 3969.9801500992453, 3984.6015398460213, 3992.041909941622, 3704.8332825022803, 3988.327820360351, 4029.871635941941, 4043.261397599031, 4051.1695102504063, 3866.845326186963, 3912.1979994101284, 3912.7065470089715, 3943.7239393242485, 3944.073142811437, 3588.1115701859994, 3904.7852368119743, 3913.999999999986, 3940.7044471664453, 3949.94075088871, 3359.059463350266, 3400.9829950850335, 3403.9659603403898, 3417.572803399575, 3424.938269136576, 4630.83792067276, 4772.570468657837, 4942.357493525855, 4993.350864387643, 5089.491050894918, 3638.508801311842, 3730.9067273318115, 3744.4944932434046, 4458.977705111486, 4461.375407442961, 4361.956380436187, 5097.451274362818, 5202.271681964528, 5413.374513361056, 5446.972765136188, 3456.0, 3727.9123690733004, 3738.728832197039, 3742.9625703742886, 3744.6893587244517, 5615.971920140414, 5693.434305566003, 5706.349325299909, 5711.6001879868845, 5754.367019627837, 3766.213002378275, 3780.6219378062524, 3780.9432858506593, 3784.508013958223, 3796.126890815073, 3592.796413201607, 3780.6597406233573, 3842.4620553122586, 3870.6709929656295, 3888.8055597220027, 3581.426971684518, 3749.7562658427114, 4374.606285434326, 4430.756308403069, 4444.599986001275, 5246.0, 5393.2179833923865, 5402.324709411324, 5412.296401467784, 5418.458154184629, 1078.665613659768, 1137.3687603380204, 1211.0916812390708, 1226.2519862883635, 1267.5803100527469, 818.2022528035237, 840.4201101240125, 847.4618617178136, 948.170350942925, 954.2985905359556, 4198.8110535026, 4529.977350113294, 5085.3134826797905, 5102.821401250939, 5122.846314610568, 3592.0, 3705.0911288590796, 4790.952090479121, 5390.703511306838, 5634.928963714785, 4306.569343065731, 4745.098431298043, 5081.7713202905215, 5095.420419669914, 5108.66793658411, 2402.4594466244953, 2791.2184588315304, 2923.9415211695646, 2929.516629756109, 2948.174511136885, 4998.875028124326, 5344.866378340573, 5349.5720342372515, 5392.085809537558, 5743.846057728352, 5583.05088135018, 5615.354234263112, 5624.634398764149, 5657.405972372924, 5668.323633002789, 5656.66256908426, 5663.716814159193, 5670.546356291405, 5696.943030569641, 5699.886002279931, 5054.848354549369, 5097.949020509747, 5467.808626698046, 5524.8342549723575, 5548.611597188319, 5411.944670789158, 5426.620136590401, 5431.646942948696, 5460.453954604508, 5465.344158700981, 5021.974890125526, 5313.432537401399, 5684.8010319638615, 5734.541236701138, 5737.942620573741, 3069.4474994501206, 3435.1927297085663, 3628.729944519397, 3841.5774264831407, 3910.843566257318, 4608.3778689876435, 4650.767461626838, 4717.976410117927, 5330.547947943989, 5519.254900588529, 4346.847860324873, 4969.906620543475, 5008.923081537487, 5147.536721695065, 5427.267318911896, 4661.643616596046, 5101.668391554537, 5108.463611320758, 5278.4721527846905, 5340.092184328596, 4160.543809666592, 5185.792568297225, 5213.270142180174, 5225.570109898916, 5262.131943160123, 4663.942370138002, 5092.592592592658, 5296.973515132399, 5391.7059905623155, 5391.975524650304, 5240.68555886648, 5254.5533629641905, 5260.500252476008, 5274.393444753902, 5299.2316114164005], "value": 3910.761024785095, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [704.3731079339389, 735.771910707681, 746.2537462537464, 812.1472453923379, 844.3540691371102, 15191.924040379796, 15358.69282614347, 15605.765913511277, 15968.520944371672, 16530.917345413272, 14480.927595362009, 14619.488317908876, 14717.264136793172, 14725.558233252992, 14757.18835464049, 693.8855088910324, 720.2365492577858, 865.8181781825824, 885.9158379953894, 892.3619611977435, 711.5197241861723, 852.2372476633409, 888.3692578269404, 888.9244414224798, 897.9640814367417, 939.6945992552413, 944.3578366710634, 945.0596656326965, 945.678469320431, 946.6828612414815, 899.2670973156889, 914.9496777677225, 928.1553786054658, 940.261894412886, 941.7410212191634, 528.8915772266677, 548.6762809942113, 666.8066260784367, 686.5228666077079, 689.1936434371797, 15127.773083403757, 15159.0, 15176.696466070727, 15177.0, 15223.162726050063, 12385.690357741129, 12964.351782410937, 13288.800667989988, 13871.791923121162, 14078.436862525487, 12356.62930112098, 12908.806367904488, 12990.545330913372, 13248.801267980987, 13274.0, 4475.30632751924, 4509.413776209105, 4518.57062186073, 4618.861434156981, 4746.667733258673, 104455.0, 105511.0, 110531.89468105296, 110942.0, 113229.0, 9724.222062235005, 10800.729981750519, 10810.945945270223, 10972.780544389068, 11371.260868043551, 21194.78805211959, 21465.46336341573, 21480.57038859214, 21489.0, 21510.56978860414, 7155.212926578072, 7532.962335188398, 7789.532628042222, 7947.4436789425345, 7968.721094761656, 4211.389348544444, 4292.484901811803, 4385.912281754347, 4488.079943611552, 4500.549945005473, 3406.148462884279, 3447.0692912913373, 3508.2808024354945, 3512.754107212522, 3525.11872031992, 600.0848705723806, 632.1939527102888, 643.7521554201666, 650.8307839961644, 664.2328111031766, 511.8515630467197, 530.9044372013075, 559.7042845811932, 561.1074908844911, 624.7750809708501, 7652.73215437457, 8288.96387951506, 8578.927634045744, 8626.0507327233, 8722.30221582272, 543.5026950340394, 551.8344496650989, 596.6670319052485, 620.1627802466724, 621.5804332075921, 3479.1666666666665, 4322.157179349996, 4323.221820072355, 4389.846355377546, 4476.843310484118, 124872.0, 153597.0, 154812.22593887182, 157889.2105539465, 158574.5813966241, 79819.0, 80413.58759237232, 100994.0, 112571.0, 140717.0, 95884.52057739667, 112690.0, 120324.0, 122285.0, 124144.9999999982, 9179.632814687338, 9633.518324083627, 9735.26985476078, 9765.169960553432, 9818.361806482555, 59835.102473463805, 62394.50421983252, 63157.36842631515, 64594.677026616446, 64928.675356622916, 87647.0, 92455.07544924734, 92941.0, 94699.0, 94977.0, 10574.2598018138, 12746.808797867854, 12930.288834114213, 13139.60581182566, 13487.190768553919, 14412.279386030867, 14557.126572405266, 14570.708585828435, 15153.924230378778, 15159.469418570297, 13628.318584070956, 13732.382042807898, 13740.793888091486, 13745.312734363442, 13775.448982040607, 13078.673033174247, 13379.0, 13975.440982360591, 13983.091099078527, 14257.765948994698, 13537.0, 13680.93159534196, 13722.65693357674, 13838.0, 14339.067960582528, 10858.867593736253, 10989.175811813986, 11145.0, 11165.776684466427, 11869.0, 10470.162387008868, 10524.210684198562, 10620.84068738954, 10882.33837007679, 11338.546458141582], "value": 7744.00495096962, "unit": "score"}], "name": "dromaeo_css", "value": 7257.258294775758, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 114.0, 113.0, 113.0, 112.0, 111.0, 112.0, 112.0, 114.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 141.0, 116.0, 153.0, 143.0, 114.0, 117.0, 115.0, 143.0, 112.0], "value": 132.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 182.0, 183.0, 185.0, 186.0, 184.0, 182.0, 181.0, 183.0, 183.0], "value": 183.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 59.0, 59.0, 87.0, 80.0, 58.0, 59.0, 58.0, 82.0, 57.0], "value": 67.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 71.0, 94.0, 70.0, 68.0, 69.0, 94.0, 68.0, 69.0, 90.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 89.0, 87.0, 88.0, 86.0, 89.0, 87.0, 88.0, 84.0], "value": 87.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 98.0, 96.0, 97.0, 97.0, 98.0, 96.0, 96.0, 96.0], "value": 96.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 81.0, 79.0, 79.0, 83.0, 77.0, 85.0, 82.0, 83.0, 80.0], "value": 81.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 56.0, 55.0, 56.0, 56.0, 54.0, 55.0, 54.0, 57.0, 54.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 73.0, 66.0, 76.0, 74.0, 71.0, 69.0, 72.0, 67.0, 69.0], "value": 70.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 86.0, 88.0, 87.0, 89.0, 83.0, 89.0, 87.0, 88.0], "value": 87.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 151.0, 152.0, 156.0, 142.0, 146.0, 148.0, 157.0, 182.0, 148.0], "value": 153.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 178.0, 177.0, 174.0, 175.0, 174.0, 169.0, 170.0, 175.0, 170.0], "value": 173.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 84.0, 88.0, 89.0, 88.0, 68.0, 68.0, 69.0, 69.0], "value": 76.3, "unit": "score"}], "name": "kraken", "value": 1455.6, "alertThreshold": 2.0}]} 09:05:23 INFO - Return code: 0 09:05:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:05:23 INFO - # TBPL SUCCESS # 09:05:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:05:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:05:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:05:23 INFO - 2016-10-17 09:05:23.418 screenresolution[1813:9978] starting screenresolution argv=screenresolution get 09:05:23 INFO - 2016-10-17 09:05:23.421 screenresolution[1813:9978] Display 0: 1600x1200x32@60 09:05:23 INFO - 2016-10-17 09:05:23.427 screenresolution[1814:9981] starting screenresolution argv=screenresolution list 09:05:23 INFO - Available Modes on Display 0 09:05:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:05:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:05:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:05:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:05:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:05:23 INFO - Intel Iris: 09:05:23 INFO - Chipset Model: Intel Iris 09:05:23 INFO - Type: GPU 09:05:23 INFO - Bus: Built-In 09:05:23 INFO - VRAM (Dynamic, Max): 1536 MB 09:05:23 INFO - Vendor: Intel (0x8086) 09:05:23 INFO - Device ID: 0x0a2e 09:05:23 INFO - Revision ID: 0x0009 09:05:23 INFO - Displays: 09:05:23 INFO - Display: 09:05:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:05:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:05:23 INFO - Display Serial Number: 1600x1200 60 09:05:23 INFO - Main Display: Yes 09:05:23 INFO - Mirror: Off 09:05:23 INFO - Online: Yes 09:05:23 INFO - Rotation: Supported 09:05:23 INFO - Return code: 0 09:05:23 INFO - Running post-action listener: _resource_record_post_action 09:05:23 INFO - [mozharness: 2016-10-17 16:05:23.660722Z] Finished run-tests step (success) 09:05:23 INFO - Running post-run listener: _resource_record_post_run 09:05:23 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 38638592; Write bytes: 711548416; Read time: 372; Write time: 1939 09:05:23 INFO - TinderboxPrint: CPU usage
53.7% 09:05:23 INFO - TinderboxPrint: I/O read bytes / time
38,638,592 / 372 09:05:23 INFO - TinderboxPrint: I/O write bytes / time
711,548,416 / 1,939 09:05:23 INFO - TinderboxPrint: CPU idle
2,249.5 (46.3%) 09:05:23 INFO - TinderboxPrint: CPU system
976.1 (20.1%) 09:05:23 INFO - TinderboxPrint: CPU user
1,632.0 (33.6%) 09:05:23 INFO - TinderboxPrint: Swap in / out
445,415,424 / 0 09:05:23 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 407316992; Write bytes: 393332224; Read time: 29787; Write time: 686 09:05:23 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 31230976; Write bytes: 301840384; Read time: 286; Write time: 1211 09:05:24 INFO - Running post-run listener: _upload_blobber_files 09:05:24 INFO - Blob upload gear active. 09:05:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:05:24 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 09:05:24 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'] 09:05:24 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 09:05:24 INFO - (blobuploader) - INFO - Open directory for files ... 09:05:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:05:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:05:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:05:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:05:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:05:25 INFO - (blobuploader) - INFO - Done attempting. 09:05:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:05:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:05:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:05:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:05:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:05:26 INFO - (blobuploader) - INFO - Done attempting. 09:05:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:05:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:05:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:05:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:05:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:05:28 INFO - (blobuploader) - INFO - Done attempting. 09:05:28 INFO - (blobuploader) - INFO - Iteration through files over. 09:05:28 INFO - Return code: 0 09:05:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:05:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:05:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/15bd9ae235f420c3d645b3f1d1c884d5ec51fc5092a448b6d0dfa8a4b922bb3753d13fce4129e136ee1b4cc1270ec972cf91d6f676b01c25b9671d52e0d74b37", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/021bf59b94fe25a9ba2d68e33908528f3f6cb01fa0c40dd16ca3caca785f403d747174d88bde39110196eb9e365fb2975f445432638a006cda6fa666d04c47ec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ff9f9c84e58a9a0efb750b23bd05db88324944b20d878c5a5e4b2dadc2d5e4135271ad64f02c80212c6e66cb39539f7fa0cb0a23552962dcc627bc71c212385d"} 09:05:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:05:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:05:28 INFO - Contents: 09:05:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/15bd9ae235f420c3d645b3f1d1c884d5ec51fc5092a448b6d0dfa8a4b922bb3753d13fce4129e136ee1b4cc1270ec972cf91d6f676b01c25b9671d52e0d74b37", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/021bf59b94fe25a9ba2d68e33908528f3f6cb01fa0c40dd16ca3caca785f403d747174d88bde39110196eb9e365fb2975f445432638a006cda6fa666d04c47ec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ff9f9c84e58a9a0efb750b23bd05db88324944b20d878c5a5e4b2dadc2d5e4135271ad64f02c80212c6e66cb39539f7fa0cb0a23552962dcc627bc71c212385d"} 09:05:28 INFO - Running post-run listener: copy_logs_to_upload_dir 09:05:28 INFO - Copying logs to upload dir... 09:05:28 INFO - mkdir: /builds/slave/test/build/upload/logs 09:05:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1309.255882 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 49 secs) (at 2016-10-17 09:05:28.769839) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-17 09:05:28.772960) ========= 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.Q8T7yprdC8/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.Vmrm2gfqV2/Listeners TMPDIR=/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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/15bd9ae235f420c3d645b3f1d1c884d5ec51fc5092a448b6d0dfa8a4b922bb3753d13fce4129e136ee1b4cc1270ec972cf91d6f676b01c25b9671d52e0d74b37", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/021bf59b94fe25a9ba2d68e33908528f3f6cb01fa0c40dd16ca3caca785f403d747174d88bde39110196eb9e365fb2975f445432638a006cda6fa666d04c47ec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ff9f9c84e58a9a0efb750b23bd05db88324944b20d878c5a5e4b2dadc2d5e4135271ad64f02c80212c6e66cb39539f7fa0cb0a23552962dcc627bc71c212385d"} build_url:https://queue.taskcluster.net/v1/task/CPhvVpesSfu8e9An5szyVw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011038 build_url: 'https://queue.taskcluster.net/v1/task/CPhvVpesSfu8e9An5szyVw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/15bd9ae235f420c3d645b3f1d1c884d5ec51fc5092a448b6d0dfa8a4b922bb3753d13fce4129e136ee1b4cc1270ec972cf91d6f676b01c25b9671d52e0d74b37", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/021bf59b94fe25a9ba2d68e33908528f3f6cb01fa0c40dd16ca3caca785f403d747174d88bde39110196eb9e365fb2975f445432638a006cda6fa666d04c47ec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ff9f9c84e58a9a0efb750b23bd05db88324944b20d878c5a5e4b2dadc2d5e4135271ad64f02c80212c6e66cb39539f7fa0cb0a23552962dcc627bc71c212385d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-17 09:05:28.808861) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 09:05:28.809628) ========= 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.Q8T7yprdC8/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.Vmrm2gfqV2/Listeners TMPDIR=/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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.005019 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 09:05:28.860784) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-17 09:05:28.861385) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-17 09:05:28.861934) ========= ========= Total master_lag: 0.18 =========