builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0276 starttime: 1473016238.57 results: success (0) buildid: 20160904103549 builduid: 7a7f5c814d29484fb23860dc35e0cfe6 revision: ce2a0cbdb4e80f88c9a12f63dcb49176525d3dd2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-04 12:10:38.572160) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-04 12:10:38.572636) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-04 12:10:38.572925) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-04 12:10:38.650286) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 12:10:38.651229) ========= 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.1smeMuAJBk/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.BQczjBcycD/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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-09-04 12:10:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 91.2M=0s 2016-09-04 12:10:39 (91.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.529306 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 12:10:39.203691) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 12:10:39.204060) ========= 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.1smeMuAJBk/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.BQczjBcycD/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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.041306 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 12:10:39.266819) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 12:10:39.267222) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ce2a0cbdb4e80f88c9a12f63dcb49176525d3dd2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev ce2a0cbdb4e80f88c9a12f63dcb49176525d3dd2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1smeMuAJBk/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.BQczjBcycD/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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-09-04 12:10:39,355 truncating revision to first 12 chars 2016-09-04 12:10:39,355 Setting DEBUG logging. 2016-09-04 12:10:39,355 attempt 1/10 2016-09-04 12:10:39,355 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ce2a0cbdb4e8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-04 12:10:39,644 unpacking tar archive at: mozilla-central-ce2a0cbdb4e8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.553474 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 12:10:39.842783) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-04 12:10:39.846217) ========= script_repo_revision: ce2a0cbdb4e80f88c9a12f63dcb49176525d3dd2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-04 12:10:39.846729) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-04 12:10:39.847030) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-04 12:10:39.865727) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-04 12:10:39.872081) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1smeMuAJBk/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.BQczjBcycD/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 12:10:40 INFO - MultiFileLogger online at 20160904 12:10:40 in /builds/slave/test 12:10:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 12:10:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:10:40 INFO - {'append_to_log': False, 12:10:40 INFO - 'base_work_dir': '/builds/slave/test', 12:10:40 INFO - 'blob_upload_branch': 'Firefox', 12:10:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:10:40 INFO - 'branch': 'Firefox', 12:10:40 INFO - 'buildbot_json_path': 'buildprops.json', 12:10:40 INFO - 'config_files': ('talos/mac_config.py',), 12:10:40 INFO - 'default_actions': ('clobber', 12:10:40 INFO - 'read-buildbot-config', 12:10:40 INFO - 'download-and-extract', 12:10:40 INFO - 'populate-webroot', 12:10:40 INFO - 'create-virtualenv', 12:10:40 INFO - 'install', 12:10:40 INFO - 'run-tests'), 12:10:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:10:40 INFO - 'download_minidump_stackwalk': True, 12:10:40 INFO - 'download_symbols': 'ondemand', 12:10:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:10:40 INFO - 'tooltool.py': '/tools/tooltool.py', 12:10:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:10:40 INFO - '/tools/misc-python/virtualenv.py')}, 12:10:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:10:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:10:40 INFO - 'installer_path': 'installer.exe', 12:10:40 INFO - 'log_level': 'info', 12:10:40 INFO - 'log_name': 'talos', 12:10:40 INFO - 'log_to_console': True, 12:10:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:10:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:10:40 INFO - 'opt_config_files': (), 12:10:40 INFO - 'pip_index': False, 12:10:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:10:40 INFO - 'cmd': ('bash', 12:10:40 INFO - '-c', 12:10:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:10:40 INFO - 'enabled': True, 12:10:40 INFO - 'halt_on_failure': False, 12:10:40 INFO - 'name': 'check_screen_resolution'},), 12:10:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:10:40 INFO - 'cmd': ('bash', 12:10:40 INFO - '-c', 12:10:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:10:40 INFO - 'enabled': True, 12:10:40 INFO - 'halt_on_failure': False, 12:10:40 INFO - 'name': 'check_screen_resolution'},), 12:10:40 INFO - 'run_cmd_checks_enabled': True, 12:10:40 INFO - 'sps_profile': False, 12:10:40 INFO - 'sps_profile_interval': 0, 12:10:40 INFO - 'suite': 'dromaeojs-e10s', 12:10:40 INFO - 'system_bits': '32', 12:10:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:10:40 INFO - 'title': 't-yosemite-r7-0276', 12:10:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:10:40 INFO - 'use_talos_json': True, 12:10:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:10:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:10:40 INFO - 'work_dir': 'build'} 12:10:40 INFO - [mozharness: 2016-09-04 19:10:40.076396Z] Running clobber step. 12:10:40 INFO - Running pre-action listener: _resource_record_pre_action 12:10:40 INFO - Running main action method: clobber 12:10:40 INFO - rmtree: /builds/slave/test/build 12:10:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:10:42 INFO - Running post-action listener: _resource_record_post_action 12:10:42 INFO - [mozharness: 2016-09-04 19:10:42.253401Z] Finished clobber step (success) 12:10:42 INFO - [mozharness: 2016-09-04 19:10:42.253653Z] Running read-buildbot-config step. 12:10:42 INFO - Running pre-action listener: _resource_record_pre_action 12:10:42 INFO - Running main action method: read_buildbot_config 12:10:42 INFO - Using buildbot properties: 12:10:42 INFO - { 12:10:42 INFO - "project": "", 12:10:42 INFO - "product": "firefox", 12:10:42 INFO - "script_repo_revision": "production", 12:10:42 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 12:10:42 INFO - "repository": "", 12:10:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 12:10:42 INFO - "buildid": "20160904103549", 12:10:42 INFO - "pgo_build": "False", 12:10:42 INFO - "basedir": "/builds/slave/test", 12:10:42 INFO - "buildnumber": 75, 12:10:42 INFO - "slavename": "t-yosemite-r7-0276", 12:10:42 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 12:10:42 INFO - "platform": "yosemite_r7", 12:10:42 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 12:10:42 INFO - "branch": "mozilla-central", 12:10:42 INFO - "repo_path": "mozilla-central", 12:10:42 INFO - "revision": "ce2a0cbdb4e80f88c9a12f63dcb49176525d3dd2", 12:10:42 INFO - "stage_platform": "macosx64", 12:10:42 INFO - "builduid": "7a7f5c814d29484fb23860dc35e0cfe6", 12:10:42 INFO - "slavebuilddir": "test" 12:10:42 INFO - } 12:10:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/QUpyhRJlT9SpFzddD_72WA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 12:10:42 INFO - Running post-action listener: _resource_record_post_action 12:10:42 INFO - [mozharness: 2016-09-04 19:10:42.259750Z] Finished read-buildbot-config step (success) 12:10:42 INFO - [mozharness: 2016-09-04 19:10:42.259961Z] Running download-and-extract step. 12:10:42 INFO - Running pre-action listener: _resource_record_pre_action 12:10:42 INFO - Running main action method: download_and_extract 12:10:42 INFO - mkdir: /builds/slave/test/build/tests 12:10:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:42 INFO - trying https://queue.taskcluster.net/v1/task/QUpyhRJlT9SpFzddD_72WA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 12:10:42 INFO - Downloading https://queue.taskcluster.net/v1/task/QUpyhRJlT9SpFzddD_72WA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 12:10:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QUpyhRJlT9SpFzddD_72WA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 12:10:43 INFO - Downloaded 1199 bytes. 12:10:43 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 12:10:43 INFO - Using the following test package requirements: 12:10:43 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 12:10:43 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:10:43 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 12:10:43 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:10:43 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 12:10:43 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:10:43 INFO - u'jsshell-mac.zip'], 12:10:43 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:10:43 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 12:10:43 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 12:10:43 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:10:43 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 12:10:43 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:10:43 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 12:10:43 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:10:43 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 12:10:43 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:10:43 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 12:10:43 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:10:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QUpyhRJlT9SpFzddD_72WA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 12:10:44 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 12:10:50 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:10:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QUpyhRJlT9SpFzddD_72WA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 12:10:51 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 12:10:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QUpyhRJlT9SpFzddD_72WA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 12:10:58 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 12:10:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:59 INFO - trying https://queue.taskcluster.net/v1/task/QUpyhRJlT9SpFzddD_72WA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:10:59 INFO - Downloading https://queue.taskcluster.net/v1/task/QUpyhRJlT9SpFzddD_72WA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 12:10:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QUpyhRJlT9SpFzddD_72WA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 12:11:06 INFO - Downloaded 111490047 bytes. 12:11:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QUpyhRJlT9SpFzddD_72WA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:11:06 INFO - mkdir: /builds/slave/test/properties 12:11:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:11:06 INFO - Writing to file /builds/slave/test/properties/build_url 12:11:06 INFO - Contents: 12:11:06 INFO - build_url:https://queue.taskcluster.net/v1/task/QUpyhRJlT9SpFzddD_72WA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:11:07 INFO - Running post-action listener: _resource_record_post_action 12:11:07 INFO - Running post-action listener: set_extra_try_arguments 12:11:07 INFO - [mozharness: 2016-09-04 19:11:07.367048Z] Finished download-and-extract step (success) 12:11:07 INFO - [mozharness: 2016-09-04 19:11:07.367199Z] Running populate-webroot step. 12:11:07 INFO - Running pre-action listener: _resource_record_pre_action 12:11:07 INFO - Running main action method: populate_webroot 12:11:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:11:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:11:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:11:07 INFO - '--tppagecycles', 12:11:07 INFO - '7'], 12:11:07 INFO - 'tests': ['tsvgm']}}, 12:11:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:11:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:11:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:11:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:11:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:11:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:11:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:07 INFO - 'talos_options': ['--disable-e10s'], 12:11:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:11:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:11:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:07 INFO - 'talos_options': ['--disable-e10s'], 12:11:07 INFO - 'tests': ['damp', 'tps']}, 12:11:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:07 INFO - 'tests': ['damp', 'tps']}, 12:11:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:11:07 INFO - 'tests': ['dromaeo_dom']}, 12:11:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:11:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:11:07 INFO - 'tests': ['basic_compositor_video']}, 12:11:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:11:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:11:07 INFO - 'tests': ['a11yr', 12:11:07 INFO - 'ts_paint', 12:11:07 INFO - 'tpaint', 12:11:07 INFO - 'sessionrestore', 12:11:07 INFO - 'sessionrestore_no_auto_restore', 12:11:07 INFO - 'tabpaint']}, 12:11:07 INFO - 'other-e10s': {'tests': ['a11yr', 12:11:07 INFO - 'ts_paint', 12:11:07 INFO - 'tpaint', 12:11:07 INFO - 'sessionrestore', 12:11:07 INFO - 'sessionrestore_no_auto_restore', 12:11:07 INFO - 'tabpaint']}, 12:11:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:11:07 INFO - 'ts_paint', 12:11:07 INFO - 'tpaint', 12:11:07 INFO - 'sessionrestore', 12:11:07 INFO - 'sessionrestore_no_auto_restore', 12:11:07 INFO - 'tabpaint']}, 12:11:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:11:07 INFO - 'ts_paint', 12:11:07 INFO - 'tpaint', 12:11:07 INFO - 'sessionrestore', 12:11:07 INFO - 'sessionrestore_no_auto_restore', 12:11:07 INFO - 'tabpaint']}, 12:11:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:11:07 INFO - 'tests': ['a11yr', 12:11:07 INFO - 'ts_paint', 12:11:07 INFO - 'tpaint', 12:11:07 INFO - 'sessionrestore', 12:11:07 INFO - 'sessionrestore_no_auto_restore', 12:11:07 INFO - 'tabpaint']}, 12:11:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:11:07 INFO - 'tests': ['a11yr', 12:11:07 INFO - 'ts_paint', 12:11:07 INFO - 'tpaint', 12:11:07 INFO - 'sessionrestore', 12:11:07 INFO - 'sessionrestore_no_auto_restore', 12:11:07 INFO - 'tabpaint']}, 12:11:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:11:07 INFO - 'tests': ['tsvgx', 12:11:07 INFO - 'tsvgr_opacity', 12:11:07 INFO - 'tart', 12:11:07 INFO - 'tscrollx', 12:11:07 INFO - 'cart']}, 12:11:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:11:07 INFO - 'tsvgr_opacity', 12:11:07 INFO - 'tart', 12:11:07 INFO - 'tscrollx', 12:11:07 INFO - 'cart']}, 12:11:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:07 INFO - 'talos_options': ['--disable-e10s'], 12:11:07 INFO - 'tests': ['tp5o']}, 12:11:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:07 INFO - 'tests': ['tp5o']}, 12:11:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:07 INFO - 'talos_options': ['--disable-e10s', 12:11:07 INFO - '--xperf_path', 12:11:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:11:07 INFO - 'tests': ['tp5n']}, 12:11:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:07 INFO - 'talos_options': ['--xperf_path', 12:11:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:11:07 INFO - 'tests': ['tp5n']}}, 12:11:07 INFO - 'talos.zip': {'path': '', 12:11:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:11:07 INFO - Running post-action listener: _resource_record_post_action 12:11:07 INFO - [mozharness: 2016-09-04 19:11:07.385811Z] Finished populate-webroot step (success) 12:11:07 INFO - [mozharness: 2016-09-04 19:11:07.385918Z] Running create-virtualenv step. 12:11:07 INFO - Running pre-action listener: _resource_record_pre_action 12:11:07 INFO - Running main action method: create_virtualenv 12:11:07 INFO - Creating virtualenv /builds/slave/test/build/venv 12:11:07 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:11:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:11:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:11:07 INFO - Using real prefix '/tools/python27' 12:11:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:11:08 INFO - Installing distribute.............................................................................................................................................................................................done. 12:11:11 INFO - Installing pip.................done. 12:11:11 INFO - Return code: 0 12:11:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:11:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dd41a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ddd6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3daceedd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dca5d30>, '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 0x10da0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dc9db20>, '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.1smeMuAJBk/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.BQczjBcycD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 12:11:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:11:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:11:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1smeMuAJBk/Render', 12:11:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:11 INFO - 'HOME': '/Users/cltbld', 12:11:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:11 INFO - 'LOGNAME': 'cltbld', 12:11:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:11 INFO - 'MOZ_NO_REMOTE': '1', 12:11:11 INFO - 'NO_EM_RESTART': '1', 12:11:11 INFO - 'PAGER': '/bin/cat', 12:11:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:11 INFO - 'PWD': '/builds/slave/test', 12:11:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:11 INFO - 'SHELL': '/bin/bash', 12:11:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BQczjBcycD/Listeners', 12:11:11 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 12:11:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:11 INFO - 'USER': 'cltbld', 12:11:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:11 INFO - 'XPC_FLAGS': '0x0', 12:11:11 INFO - 'XPC_SERVICE_NAME': '0', 12:11:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:11 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:11:11 INFO - Cleaning up... 12:11:11 INFO - Return code: 0 12:11:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:11:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dd41a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ddd6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3daceedd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dca5d30>, '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 0x10da0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dc9db20>, '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.1smeMuAJBk/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.BQczjBcycD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 12:11:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:11:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:11:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1smeMuAJBk/Render', 12:11:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:11 INFO - 'HOME': '/Users/cltbld', 12:11:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:11 INFO - 'LOGNAME': 'cltbld', 12:11:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:11 INFO - 'MOZ_NO_REMOTE': '1', 12:11:11 INFO - 'NO_EM_RESTART': '1', 12:11:11 INFO - 'PAGER': '/bin/cat', 12:11:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:11 INFO - 'PWD': '/builds/slave/test', 12:11:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:11 INFO - 'SHELL': '/bin/bash', 12:11:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BQczjBcycD/Listeners', 12:11:11 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 12:11:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:11 INFO - 'USER': 'cltbld', 12:11:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:11 INFO - 'XPC_FLAGS': '0x0', 12:11:11 INFO - 'XPC_SERVICE_NAME': '0', 12:11:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:11 INFO - Downloading/unpacking psutil>=3.1.1 12:11:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:11:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:11:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:11:14 INFO - Installing collected packages: psutil 12:11:14 INFO - Running setup.py install for psutil 12:11:15 INFO - building 'psutil._psutil_osx' extension 12:11:15 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 12:11:15 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 12:11:15 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 12:11:15 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 12:11:15 INFO - building 'psutil._psutil_posix' extension 12:11:15 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 12:11:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:11:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:11:15 INFO - ^ 12:11:15 INFO - 1 warning generated. 12:11:15 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 12:11:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:11:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:11:16 INFO - Successfully installed psutil 12:11:16 INFO - Cleaning up... 12:11:16 INFO - Return code: 0 12:11:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:11:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dd41a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ddd6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3daceedd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dca5d30>, '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 0x10da0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dc9db20>, '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.1smeMuAJBk/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.BQczjBcycD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 12:11:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:11:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:11:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1smeMuAJBk/Render', 12:11:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:16 INFO - 'HOME': '/Users/cltbld', 12:11:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:16 INFO - 'LOGNAME': 'cltbld', 12:11:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:16 INFO - 'MOZ_NO_REMOTE': '1', 12:11:16 INFO - 'NO_EM_RESTART': '1', 12:11:16 INFO - 'PAGER': '/bin/cat', 12:11:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:16 INFO - 'PWD': '/builds/slave/test', 12:11:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:16 INFO - 'SHELL': '/bin/bash', 12:11:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BQczjBcycD/Listeners', 12:11:16 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 12:11:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:16 INFO - 'USER': 'cltbld', 12:11:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:16 INFO - 'XPC_FLAGS': '0x0', 12:11:16 INFO - 'XPC_SERVICE_NAME': '0', 12:11:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:11:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:11:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:11:19 INFO - Installing collected packages: mozsystemmonitor 12:11:19 INFO - Running setup.py install for mozsystemmonitor 12:11:19 INFO - Successfully installed mozsystemmonitor 12:11:19 INFO - Cleaning up... 12:11:19 INFO - Return code: 0 12:11:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:11:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dd41a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ddd6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3daceedd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dca5d30>, '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 0x10da0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dc9db20>, '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.1smeMuAJBk/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.BQczjBcycD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 12:11:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:11:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:11:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1smeMuAJBk/Render', 12:11:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:19 INFO - 'HOME': '/Users/cltbld', 12:11:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:19 INFO - 'LOGNAME': 'cltbld', 12:11:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:19 INFO - 'MOZ_NO_REMOTE': '1', 12:11:19 INFO - 'NO_EM_RESTART': '1', 12:11:19 INFO - 'PAGER': '/bin/cat', 12:11:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:19 INFO - 'PWD': '/builds/slave/test', 12:11:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:19 INFO - 'SHELL': '/bin/bash', 12:11:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BQczjBcycD/Listeners', 12:11:19 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 12:11:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:19 INFO - 'USER': 'cltbld', 12:11:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:19 INFO - 'XPC_FLAGS': '0x0', 12:11:19 INFO - 'XPC_SERVICE_NAME': '0', 12:11:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:19 INFO - Downloading/unpacking jsonschema==2.5.1 12:11:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:11:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:11:22 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 12:11:22 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 12:11:22 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 12:11:22 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 12:11:22 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 12:11:22 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 12:11:22 INFO - Downloading functools32-3.2.3-2.tar.gz 12:11:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:11:22 INFO - warning: no files found matching '*.txt' 12:11:22 INFO - no previously-included directories found matching 'build' 12:11:22 INFO - no previously-included directories found matching 'dist' 12:11:22 INFO - no previously-included directories found matching '.git*' 12:11:22 INFO - Installing collected packages: jsonschema, functools32 12:11:22 INFO - Running setup.py install for functools32 12:11:22 INFO - warning: no files found matching '*.txt' 12:11:22 INFO - no previously-included directories found matching 'build' 12:11:22 INFO - no previously-included directories found matching 'dist' 12:11:22 INFO - no previously-included directories found matching '.git*' 12:11:22 INFO - Successfully installed jsonschema functools32 12:11:22 INFO - Cleaning up... 12:11:22 INFO - Return code: 0 12:11:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:11:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dd41a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ddd6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3daceedd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dca5d30>, '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 0x10da0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dc9db20>, '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.1smeMuAJBk/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.BQczjBcycD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 12:11:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:11:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:11:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1smeMuAJBk/Render', 12:11:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:22 INFO - 'HOME': '/Users/cltbld', 12:11:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:22 INFO - 'LOGNAME': 'cltbld', 12:11:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:22 INFO - 'MOZ_NO_REMOTE': '1', 12:11:22 INFO - 'NO_EM_RESTART': '1', 12:11:22 INFO - 'PAGER': '/bin/cat', 12:11:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:22 INFO - 'PWD': '/builds/slave/test', 12:11:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:22 INFO - 'SHELL': '/bin/bash', 12:11:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BQczjBcycD/Listeners', 12:11:22 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 12:11:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:22 INFO - 'USER': 'cltbld', 12:11:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:22 INFO - 'XPC_FLAGS': '0x0', 12:11:22 INFO - 'XPC_SERVICE_NAME': '0', 12:11:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:11:23 INFO - Cleaning up... 12:11:23 INFO - Return code: 0 12:11:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:23 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 0x10dd41a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ddd6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3daceedd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dca5d30>, '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 0x10da0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dc9db20>, '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.1smeMuAJBk/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.BQczjBcycD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 12:11:23 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 12:11:23 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 12:11:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1smeMuAJBk/Render', 12:11:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:23 INFO - 'HOME': '/Users/cltbld', 12:11:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:23 INFO - 'LOGNAME': 'cltbld', 12:11:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:23 INFO - 'MOZ_NO_REMOTE': '1', 12:11:23 INFO - 'NO_EM_RESTART': '1', 12:11:23 INFO - 'PAGER': '/bin/cat', 12:11:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:23 INFO - 'PWD': '/builds/slave/test', 12:11:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:23 INFO - 'SHELL': '/bin/bash', 12:11:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BQczjBcycD/Listeners', 12:11:23 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 12:11:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:23 INFO - 'USER': 'cltbld', 12:11:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:23 INFO - 'XPC_FLAGS': '0x0', 12:11:23 INFO - 'XPC_SERVICE_NAME': '0', 12:11:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:23 INFO - Downloading/unpacking blobuploader==1.2.4 12:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:25 INFO - Downloading blobuploader-1.2.4.tar.gz 12:11:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:11:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:11:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:27 INFO - Downloading docopt-0.6.1.tar.gz 12:11:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:11:27 INFO - Installing collected packages: blobuploader, requests, docopt 12:11:27 INFO - Running setup.py install for blobuploader 12:11:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:11:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:11:27 INFO - Running setup.py install for requests 12:11:27 INFO - Running setup.py install for docopt 12:11:27 INFO - Successfully installed blobuploader requests docopt 12:11:27 INFO - Cleaning up... 12:11:28 INFO - Return code: 0 12:11:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:11:28 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')]} 12:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:28 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')]} 12:11:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:28 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 0x10dd41a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ddd6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3daceedd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dca5d30>, '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 0x10da0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dc9db20>, '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.1smeMuAJBk/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.BQczjBcycD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 12:11:28 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 12:11:28 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 12:11:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1smeMuAJBk/Render', 12:11:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:28 INFO - 'HOME': '/Users/cltbld', 12:11:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:28 INFO - 'LOGNAME': 'cltbld', 12:11:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:28 INFO - 'MOZ_NO_REMOTE': '1', 12:11:28 INFO - 'NO_EM_RESTART': '1', 12:11:28 INFO - 'PAGER': '/bin/cat', 12:11:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:28 INFO - 'PWD': '/builds/slave/test', 12:11:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:28 INFO - 'SHELL': '/bin/bash', 12:11:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BQczjBcycD/Listeners', 12:11:28 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 12:11:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:28 INFO - 'USER': 'cltbld', 12:11:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:28 INFO - 'XPC_FLAGS': '0x0', 12:11:28 INFO - 'XPC_SERVICE_NAME': '0', 12:11:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:11:28 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-DKt2nY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:11:28 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-Pp9oFC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:11:28 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-77rhtR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:11:28 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-dq6Nlk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:11:28 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-d_YyGA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:11:28 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-Xf7Ijs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:11:28 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-wy4tQs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:11:28 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-zWU3Hu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:11:29 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-wWnTj4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:11:29 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-obRhbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:11:29 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-7Uuud7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:11:29 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-RxXOD5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:11:29 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-cR6DnZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:11:29 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-06kJX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:29 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-Ha3BCr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:11:29 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-EvGZsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:11:29 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-fSI6JE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:11:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:11:30 INFO - Running setup.py install for manifestparser 12:11:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:11:30 INFO - Running setup.py install for mozcrash 12:11:30 INFO - Running setup.py install for mozdebug 12:11:30 INFO - Running setup.py install for mozdevice 12:11:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:11:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:11:30 INFO - Running setup.py install for mozfile 12:11:30 INFO - Running setup.py install for mozhttpd 12:11:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:11:30 INFO - Running setup.py install for mozinfo 12:11:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:11:31 INFO - Running setup.py install for mozInstall 12:11:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:11:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:11:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:11:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:11:31 INFO - Running setup.py install for mozleak 12:11:31 INFO - Running setup.py install for mozlog 12:11:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:11:31 INFO - Running setup.py install for moznetwork 12:11:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:11:31 INFO - Running setup.py install for mozprocess 12:11:31 INFO - Running setup.py install for mozprofile 12:11:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:11:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:11:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:11:31 INFO - Running setup.py install for mozrunner 12:11:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:11:32 INFO - Running setup.py install for mozscreenshot 12:11:32 INFO - Running setup.py install for moztest 12:11:32 INFO - Running setup.py install for mozversion 12:11:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:11:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:11:32 INFO - Cleaning up... 12:11:32 INFO - Return code: 0 12:11:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:11:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dd41a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ddd6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3daceedd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dca5d30>, '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 0x10da0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dc9db20>, '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.1smeMuAJBk/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.BQczjBcycD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 12:11:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:11:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:11:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1smeMuAJBk/Render', 12:11:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:32 INFO - 'HOME': '/Users/cltbld', 12:11:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:32 INFO - 'LOGNAME': 'cltbld', 12:11:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:32 INFO - 'MOZ_NO_REMOTE': '1', 12:11:32 INFO - 'NO_EM_RESTART': '1', 12:11:32 INFO - 'PAGER': '/bin/cat', 12:11:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:32 INFO - 'PWD': '/builds/slave/test', 12:11:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:32 INFO - 'SHELL': '/bin/bash', 12:11:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BQczjBcycD/Listeners', 12:11:32 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 12:11:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:32 INFO - 'USER': 'cltbld', 12:11:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:32 INFO - 'XPC_FLAGS': '0x0', 12:11:32 INFO - 'XPC_SERVICE_NAME': '0', 12:11:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:11:32 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-AsdS3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:11:32 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:11:32 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-_WTQvm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:11:33 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)) 12:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:11:33 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-mc_pDM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:11:33 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)) 12:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:11:33 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-8D2c3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:11:33 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-0lptZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:11:33 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-YNU0qG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:11:33 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-YeVrtv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:11:33 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-FDvI6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:11:33 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-9eLo25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:11:33 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-LFFJHn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:11:33 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-quD5H1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:11:34 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)) 12:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:11:34 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-VKAItQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:11:34 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)) 12:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:11:34 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-Xhnwbh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:11:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:11:34 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-pJGMxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:11:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:34 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-PVHXrU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:11:34 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-1_QQ18-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:11:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:11:34 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-KGoQaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:11:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:11:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:11:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:11:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:11:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:11:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:11:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:37 INFO - Downloading blessings-1.6.tar.gz 12:11:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:11:37 INFO - Installing collected packages: blessings 12:11:37 INFO - Running setup.py install for blessings 12:11:37 INFO - Successfully installed blessings 12:11:37 INFO - Cleaning up... 12:11:37 INFO - Return code: 0 12:11:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:11:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:11:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:11:37 INFO - Reading from file tmpfile_stdout 12:11:37 INFO - Current package versions: 12:11:37 INFO - blessings == 1.6 12:11:37 INFO - blobuploader == 1.2.4 12:11:37 INFO - docopt == 0.6.1 12:11:37 INFO - functools32 == 3.2.3-2 12:11:37 INFO - jsonschema == 2.5.1 12:11:37 INFO - manifestparser == 1.1 12:11:37 INFO - mozInstall == 1.12 12:11:37 INFO - mozcrash == 0.17 12:11:37 INFO - mozdebug == 0.1 12:11:37 INFO - mozdevice == 0.48 12:11:37 INFO - mozfile == 1.2 12:11:37 INFO - mozhttpd == 0.7 12:11:37 INFO - mozinfo == 0.9 12:11:37 INFO - mozleak == 0.1 12:11:37 INFO - mozlog == 3.3 12:11:37 INFO - moznetwork == 0.27 12:11:37 INFO - mozprocess == 0.23 12:11:37 INFO - mozprofile == 0.28 12:11:37 INFO - mozrunner == 6.12 12:11:37 INFO - mozscreenshot == 0.1 12:11:37 INFO - mozsystemmonitor == 0.3 12:11:37 INFO - moztest == 0.7 12:11:37 INFO - mozversion == 1.4 12:11:37 INFO - psutil == 3.1.1 12:11:37 INFO - requests == 1.2.3 12:11:37 INFO - wsgiref == 0.1.2 12:11:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:11:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dd41a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ddd6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3daceedd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dca5d30>, '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 0x10da0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dc9db20>, '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.1smeMuAJBk/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.BQczjBcycD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 12:11:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:11:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:11:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1smeMuAJBk/Render', 12:11:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:37 INFO - 'HOME': '/Users/cltbld', 12:11:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:37 INFO - 'LOGNAME': 'cltbld', 12:11:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:37 INFO - 'MOZ_NO_REMOTE': '1', 12:11:37 INFO - 'NO_EM_RESTART': '1', 12:11:37 INFO - 'PAGER': '/bin/cat', 12:11:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:37 INFO - 'PWD': '/builds/slave/test', 12:11:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:37 INFO - 'SHELL': '/bin/bash', 12:11:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BQczjBcycD/Listeners', 12:11:37 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 12:11:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:37 INFO - 'USER': 'cltbld', 12:11:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:37 INFO - 'XPC_FLAGS': '0x0', 12:11:37 INFO - 'XPC_SERVICE_NAME': '0', 12:11:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:38 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)) 12:11:38 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)) 12:11:38 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)) 12:11:38 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)) 12:11:38 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)) 12:11:38 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)) 12:11:38 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)) 12:11:38 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)) 12:11:38 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)) 12:11:38 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)) 12:11:38 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)) 12:11:38 INFO - Cleaning up... 12:11:38 INFO - Return code: 0 12:11:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:11:38 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')]} 12:11:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:38 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')]} 12:11:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:38 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 0x10dd41a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ddd6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3daceedd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dca5d30>, '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 0x10da0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dc9db20>, '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.1smeMuAJBk/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.BQczjBcycD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 12:11:38 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 12:11:38 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 12:11:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1smeMuAJBk/Render', 12:11:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:38 INFO - 'HOME': '/Users/cltbld', 12:11:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:38 INFO - 'LOGNAME': 'cltbld', 12:11:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:38 INFO - 'MOZ_NO_REMOTE': '1', 12:11:38 INFO - 'NO_EM_RESTART': '1', 12:11:38 INFO - 'PAGER': '/bin/cat', 12:11:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:38 INFO - 'PWD': '/builds/slave/test', 12:11:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:38 INFO - 'SHELL': '/bin/bash', 12:11:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BQczjBcycD/Listeners', 12:11:38 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 12:11:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:38 INFO - 'USER': 'cltbld', 12:11:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:38 INFO - 'XPC_FLAGS': '0x0', 12:11:38 INFO - 'XPC_SERVICE_NAME': '0', 12:11:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:11:38 INFO - Cleaning up... 12:11:38 INFO - Return code: 0 12:11:38 INFO - Running post-action listener: _resource_record_post_action 12:11:38 INFO - Running post-action listener: _start_resource_monitoring 12:11:38 INFO - Starting resource monitoring. 12:11:38 INFO - [mozharness: 2016-09-04 19:11:38.414581Z] Finished create-virtualenv step (success) 12:11:38 INFO - [mozharness: 2016-09-04 19:11:38.415520Z] Running install step. 12:11:38 INFO - Running pre-action listener: _resource_record_pre_action 12:11:38 INFO - Running main action method: install 12:11:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:11:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:11:38 INFO - Reading from file tmpfile_stdout 12:11:38 INFO - Detecting whether we're running mozinstall >=1.0... 12:11:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:11:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:11:38 INFO - Reading from file tmpfile_stdout 12:11:38 INFO - Output received: 12:11:38 INFO - Usage: mozinstall [options] installer 12:11:38 INFO - Options: 12:11:38 INFO - -h, --help show this help message and exit 12:11:38 INFO - -d DEST, --destination=DEST 12:11:38 INFO - Directory to install application into. [default: 12:11:38 INFO - "/builds/slave/test"] 12:11:38 INFO - --app=APP Application being installed. [default: firefox] 12:11:38 INFO - mkdir: /builds/slave/test/build/application 12:11:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:11:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:12:13 INFO - Reading from file tmpfile_stdout 12:12:13 INFO - Output received: 12:12:13 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:12:13 INFO - Running post-action listener: _resource_record_post_action 12:12:13 INFO - [mozharness: 2016-09-04 19:12:13.213397Z] Finished install step (success) 12:12:13 INFO - [mozharness: 2016-09-04 19:12:13.213496Z] Running run-tests step. 12:12:13 INFO - Running pre-action listener: _resource_record_pre_action 12:12:13 INFO - Running main action method: run_tests 12:12:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:12:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:12:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:12:13 INFO - 2016-09-04 12:12:13.227 screenresolution[1639:8015] starting screenresolution argv=screenresolution get 12:12:13 INFO - 2016-09-04 12:12:13.230 screenresolution[1639:8015] Display 0: 1600x1200x32@60 12:12:13 INFO - 2016-09-04 12:12:13.236 screenresolution[1640:8017] starting screenresolution argv=screenresolution list 12:12:13 INFO - Available Modes on Display 0 12:12:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:12:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:12:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:12:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:12:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:12:13 INFO - Intel Iris: 12:12:13 INFO - Chipset Model: Intel Iris 12:12:13 INFO - Type: GPU 12:12:13 INFO - Bus: Built-In 12:12:13 INFO - VRAM (Dynamic, Max): 1536 MB 12:12:13 INFO - Vendor: Intel (0x8086) 12:12:13 INFO - Device ID: 0x0a2e 12:12:13 INFO - Revision ID: 0x0009 12:12:13 INFO - Displays: 12:12:13 INFO - Display: 12:12:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:12:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:12:13 INFO - Display Serial Number: 1600x1200 60 12:12:13 INFO - Main Display: Yes 12:12:13 INFO - Mirror: Off 12:12:13 INFO - Online: Yes 12:12:13 INFO - Rotation: Supported 12:12:13 INFO - Return code: 0 12:12:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:12:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:12:13 INFO - Python 2.7.3 12:12:13 INFO - Return code: 0 12:12:13 INFO - grabbing minidump binary from tooltool 12:12:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:12:13 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dca5d30>, '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 0x10da0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dc9db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:12:13 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:12:13 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 12:12:13 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:12:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:12:13 INFO - Return code: 0 12:12:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:12:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:12:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:12:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:12:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:12:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QUpyhRJlT9SpFzddD_72WA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0276', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 12:12:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QUpyhRJlT9SpFzddD_72WA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0276 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 12:12:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1smeMuAJBk/Render', 12:12:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:12:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:12:13 INFO - 'HOME': '/Users/cltbld', 12:12:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:12:13 INFO - 'LOGNAME': 'cltbld', 12:12:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:12:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:12:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:13 INFO - 'MOZ_NO_REMOTE': '1', 12:12:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:12:13 INFO - 'NO_EM_RESTART': '1', 12:12:13 INFO - 'PAGER': '/bin/cat', 12:12:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:12:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:13 INFO - 'PWD': '/builds/slave/test', 12:12:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:12:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:12:13 INFO - 'SHELL': '/bin/bash', 12:12:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BQczjBcycD/Listeners', 12:12:13 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 12:12:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:12:13 INFO - 'USER': 'cltbld', 12:12:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:12:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:12:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:13 INFO - 'XPC_FLAGS': '0x0', 12:12:13 INFO - 'XPC_SERVICE_NAME': '0', 12:12:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:12:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QUpyhRJlT9SpFzddD_72WA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0276', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 12:12:13 INFO - mozversion application_buildid: 20160904103549 12:12:13 INFO - mozversion application_changeset: ce2a0cbdb4e80f88c9a12f63dcb49176525d3dd2 12:12:13 INFO - mozversion application_display_name: Nightly 12:12:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:12:13 INFO - mozversion application_name: Firefox 12:12:13 INFO - mozversion application_remotingname: firefox 12:12:13 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:12:13 INFO - mozversion application_vendor: Mozilla 12:12:13 INFO - mozversion application_version: 51.0a1 12:12:13 INFO - mozversion platform_buildid: 20160904103549 12:12:13 INFO - mozversion platform_changeset: ce2a0cbdb4e80f88c9a12f63dcb49176525d3dd2 12:12:13 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:12:13 INFO - mozversion platform_version: 51.0a1 12:12:13 INFO - using testdate: 1473016333 12:12:13 INFO - actual date: 1473016333 12:12:13 INFO - starting webserver on 'localhost:49271' 12:12:13 INFO - SUITE-START | Running 2 tests 12:12:13 INFO - TEST-START | dromaeo_css 12:12:13 INFO - operating with platform_type : mac_ 12:12:13 INFO - Initialising browser for dromaeo_css test... 12:12:13 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpKUd_4o/profile http://localhost:49271/getInfo.html) 12:12:16 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 12:12:16 INFO - PROCESS | 1650 | colorDepth:24 12:12:16 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 12:12:16 INFO - PROCESS | 1650 | __metrics 12:12:16 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 12:12:17 INFO - TEST-INFO | 1650: exit 0 12:12:17 INFO - Browser initialized. 12:12:17 INFO - Running cycle 1/1 for dromaeo_css test... 12:12:17 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpKUd_4o/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:12:29 INFO - PROCESS | 1654 | RSS: Main: 245354496 12:12:29 INFO - PROCESS | 1654 | 12:13:19 INFO - PROCESS | 1654 | 1473016399141 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] 12:15:30 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 12:15:32 INFO - PROCESS | 1654 | RSS: Main: 266027008 12:15:32 INFO - PROCESS | 1654 | 12:18:34 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 12:18:34 INFO - PROCESS | 1654 | RSS: Main: 270852096 12:18:34 INFO - PROCESS | 1654 | 12:21:36 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 12:21:37 INFO - PROCESS | 1654 | RSS: Main: 274599936 12:21:37 INFO - PROCESS | 1654 | 12:24:39 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 12:24:39 INFO - PROCESS | 1654 | RSS: Main: 269819904 12:24:39 INFO - PROCESS | 1654 | 12:27:42 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 12:27:43 INFO - PROCESS | 1654 | RSS: Main: 276504576 12:27:43 INFO - PROCESS | 1654 | 12:30:46 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 12:30:46 INFO - PROCESS | 1654 | RSS: Main: 279224320 12:30:46 INFO - PROCESS | 1654 | 12:30:46 INFO - PROCESS | 1654 | __start_tp_report 12:30:46 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 12:30:46 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 12:30:46 INFO - PROCESS | 1654 | |i|pagename|runs| 12:30:46 INFO - PROCESS | 1654 | |0;dojo.html;3330.3838789823885;3357.3788849062917;3387.4749413840855;3389.593248810143;3394.3381040697072;4502.302143167814;5252.107141785896;5647.491725744682;5660.094384898417;5743.253377060976;5536.557075433966;5682.886342273152;5702.629329093606;5719.828405147852;5724.313082430114;5043.798248070082;5123.871903202413;5209.557187639036;5243.161094224924;5253.080710875601;5109.105906466372;5250.579953603703;5264;5330.253764472977;5344.465553444644;5076.517730815566;5137.332146820908;5172.32759741235;5204.193350030751;5253.684778913239;5190.4550022247495;5194.428612852583;5197.272381866542;5204.115300398941;5208.322918020671;5144.6141539384325;5170.715610641413;5189.818356357547;5191.091558977201;5221.608379371524;5511.53151982082;5555.083411237181;5622.409646987089;5745.339285982123;5811.854703632401;4635.976820115911;4915.606751459874;5590.468905454016;5747.798827041075;6113.388661133896;5251.52736253739;6234.844128896769;6266.937330626726;6268.247810262752;6280.309165991737;5598.356189038272;5644.5484361251;5697.658140511582;5753.424657534297;5781.277340332458;6157.353477884758;6159.784407545714;6187.876242475125;6201.937980620226;6282.811515654537;4847.684900481458;6064.848378790477;6068.75724971005;6073.301570319381;6075.5747097703625;6026.186464827279;6135.937063216329;6180.969095154495;6194.4115309045565;6203.2556093268195;5950.672713000827;6122.142899994004;6139.921477737008;6154.292256390483;6218.626882387071;6191.380861913773;6205.47253483459;6211.689415529206;6237.532185086136;6237.688115594293;4795.017021510582;4864.027194561102;4869.659123861366;4906.484819093944;4921.163402221631;4368.825246990084;4374.628156606724;4430.335449682509;4467.441569803775;4478.596926276651;4554.407926969473;5656.632318899258;5721.942780572224;5791.710414479259;5792.855178620568;6127.295361033449;6247.250329960434;6248.319942653996;6275.748970041239;6287.779927702509;5078.796848126033;5483.533899618497;5691.459311365414;5706.029974904193;5772.855678608068;5350.5987050971335;5366.382865970385;5368.624196306299;5386;5395.568354531628;6187.752489900354;6291.968540157453;6295;6302.684865756693;6314.810555683429;6191.1641928339095;6192.380761923771;6203.565750397429;6259.499240060875;6270.561060725706;6209.565330426827;6250.937490625036;6279.780207692709;6289.9371006291185;6292.559520833498;6061.696915154313;6161.84595385119;6189.907151392823;6222.346653601307;6242.906356404748;5869.442402971711;6147.954847675816;6185.474234690102;6187.257529096357;6298.653574053471;4934.777934993006;6062.939370606237;6085.2393450818645;6097.4817140543555;6156.476699480594;5916.940830591639;6064.4541991221;6082.391760823883;6092.329843717276;6101.389861013863;5701.657900525982;5722.17028530869;5730.684812335361;5769.480746732815;5807.651540907559;5902.2622172228475;5946.643201407929;5951.732172052355;5968.552358573038;5993.460588547052;4244.7877606118955;4387.890302742457;5743.597948143757;5781.971090144522;5785.971070144622 12:30:46 INFO - PROCESS | 1654 | |1;ext.html;446.23693484142115;466.86460926331364;467.9812807487701;530.8009496438834;537.3337062043067;36290.8185459073;39781.60218397815;39828;39865.80402587923;40105.19789604206;34496.31007379857;39688.20623587527;40953.38569921454;41385.58614413855;41544.58455415445;9496.47769372686;9690.515474226295;9706.708798736014;9717.805643887117;9729.708108756713;9509;9571.37786043905;9678.854817177747;9697.563609637547;9709.17472014876;9694.757631059245;9698.36960597559;9713.854292185622;9719.708408747749;9743.853842192373;9654.806903861918;9697.563609637584;9699.757506062335;9708.95145524275;9721.611135554569;9654.22766178711;9715.514224288758;9734.269929755226;9738.074882886185;9748.805023899553;39133;39307.80346098251;39369.80315098435;39420.60579394227;39464.60535394638;3322.5348451216605;3713.702903767719;4041.2725709372303;4054.8175332110127;4057.797110144481;29625.555616665766;29722;30091.097267082023;30115.09654710362;30387.848060759778;3254.8372581371073;3259.08745551246;3262.3312220994876;3295.571575695168;3350.547676063725;505573;512216.8778312206;514010;514133;516708.41645791905;55565.88868222613;55857.720711396185;55867;55873.44126558764;56049.71975140098;34038.48942265868;34868;34960;35743.4638480423;58455.41544584585;15966.600834978986;16151.577263410583;16152.35390584387;16156.353745850034;16235.918820405823;12417.62747117588;12484.875151248552;12515.624531263895;12522.87477125217;12524.874751252553;14926.4775732851;18223;18333.908330458264;18366.98981556027;18590.628187436174;7801.609919504002;7857.803554911173;7881.832496063514;7961.601919903982;7967.880481792778;855.4268640011207;882.9977974998295;1032.2825636182931;1055.3615062886997;1128.041165009735;28553.857230713715;28672.85663571669;29979.850100749358;30018.849905750332;30080.849595751883;837.367787320569;1029.4543891737392;1037.2004572067458;1046.5081411736471;1058.4020028683924;15324;15371.538853834403;15590.610234744223;16050.598735031484;16077.839221607634;642621;646636;649625;656807;657591;56283.87432251332;56422.614643121546;56912.146317805265;56928;56964;1328.8272524571744;1333.3799783100906;1373.6703191234233;1376.4012654495527;1380.2201756008023;26013.479730404906;28634.85682571574;29604.963826265976;29702.554461683525;30379.848100759355;47575;48356.27465587949;48364.79088022828;48467;48735.512644873095;508337;509026;509532;511254;619386;24933.625995610444;25160.119395821057;25220;25254.242372728848;25254.49491010206;42963.785181075145;43368.56631433645;43453.56546434622;43478.91302717331;43834.780826095666;36415;36550.26899461943;36647.63352366442;37478;37731.24537509289;34930;35784;36041.279174416886;36891.44662830006;37586;34666.65333346634;34826.651733482344;34835.65164348324;35146;35702;26062;30640.387192255585;32139.67860321367;32180;32527.674723252465;22515.66226506571;22985.425364366023;24103.63844542298;24647.876760616084;24851.502969940862 12:30:46 INFO - PROCESS | 1654 | |2;jquery.html;315.8215608181376;360.9350316942951;368.16794045457266;371.0945293483899;387.01890707057595;15618;20160.495987600316;20555;21237.938103094843;21480.785192148112;18134.727979080326;20471.795282047213;21210.46973825655;21216.257430989917;21301.786982130176;5812.360640329561;5940.495057920082;6187.133801267826;6201.069839524085;6206.906896396557;5163.7676304566385;6023.668698221596;6148.692565371736;6150.538709596775;6152.077188421727;5927.911081333784;6129.908051379233;6138.14066030756;6149.354317796612;6200.937990620104;6021.759129634809;6128.662923539204;6151.046587778901;6174.289956654975;6191.164192833955;5741.655500669973;5960.850978725523;6006.489448396892;6022.6687532185715;6026.276846778371;13190.670233244246;13456.461741530327;13886.652833679138;25053.997840086373;25354.746452535608;2101.4011006863125;2191.550732099916;2232.787885150908;2285.325828880484;2600.9089681861046;9943.425167831996;9962.551685174185;9984.201263898944;10294.279400441972;10463;1888.1692055495676;1893.5739458621838;1922.1542521290587;1931.169597073258;1986.7913869043832;119926.0092488439;122721.38639306746;122742.38628806888;124147.37926310311;124415;8780.385373023955;10960;11497.367644779617;11545.480453379618;11582.710432239093;14453;14455.710885782226;14463.566093017227;14471.638209044859;14492.565223043326;8202.958985205036;8505.617247223767;8542;8629.654813807503;8662.133786621287;6884.827879303058;8265.380096492787;8336.374771892133;8374.916250837536;8569.828603427897;4497.775111244425;4502.234620114588;4502.819887204475;4506.031203291308;4530.093981203772;1620.4247492140357;1632.526567295471;1878.680624293846;1918.8081191880979;1926.5087402712218;761.2919984414548;922.3128769066954;939;952.1811242331589;994.9502524873727;19268;21423;21490;21579.892100539397;21839.454013649785;833.3083540661237;869.5565261716444;901.2654686430539;941.5433514745343;997.7705127820642;9258.166764991183;10953.3427994319;11340;11373.147013973985;11460.885391145981;553892;575179;583165;599154;600065;125349;126144;130976;132730;138666;975.1321324021626;1021.7139201023725;1038.133158812385;1039.734867608755;1053.2469284461495;18740.718889216947;18751.437456876316;18881.244750210386;19069.90465047666;19178.520536986685;54081.72959135337;54329.72835135799;55156.17265740937;55275.44724552864;56686.7165664169;78059.60970195112;78739.81890271536;81253;82827;83568.91077722862;6908.861822763617;7011.719531218898;7947.165547617417;8036.437449378487;8120.918790812017;9592.184664303611;11277.32336059839;12295.69260768488;12475.376231188586;13200.471981121033;11042.944785276022;11077.113830893348;11357.716057098638;11389.601363952223;11394.08847292198;10663.200259980687;10694.946525267324;10909.618163364245;11081.445927703422;11099;11047.116230701364;11285.43572821372;11410.030147437561;11484.12896775806;11506.30962142274;6118.724657390517;7062.117235345582;7175.138983322035;7422.406207503279;7424.443166762407;9626;10754.73113172177;11086.22396432242;11117.555297787998;11186.664400068012 12:30:46 INFO - PROCESS | 1654 | |3;mootools.html;1463.8902082343825;1501.9774703379449;1735.123762499938;1858.3681548273585;1899.3637131560936;7196.208417074117;8404.45371050882;8562.272206862424;8957.955210223949;8970.596323165457;8666.869996950036;8790.912090879105;8792.516411597375;8798.472091674488;8806.691765788199;2386.081358676909;2401.423658321999;2406.254061241012;2413.3122060212754;2427.235420842429;2189.288481243594;2370.928872133839;2396.7962723168553;2431.8905649245826;2435.0381599268367;2212.2810086721797;2378.512404956988;2391.8325717199805;2405.494846082316;2408.7229968553665;2370.158593699229;2381.6546600742968;2383.7020372453444;2392.605220138675;2396.113438027923;2339.368370539945;2353.329301149164;2366.3137690069857;2375.5248950209852;2382.5354055959097;8722.476651400937;8892.288616910631;8899.243564296978;8909.777255568597;8910.064443233432;5212.139996900507;5349.251104845286;5388.973055134738;5402.945970540283;5412.242286079952;3580.445031020196;3629.5644522657185;3646.270745850814;3692.095436618019;3704.518412606371;1096.6545538155535;1103.2773533335599;1103.7185517693017;1105.7677887643524;1119.3731510354228;11539.76920461586;11568.190226684135;11647.767044659144;11695.883041169565;11822.881771182265;3476.287361091021;3663.908402289964;3794.203217324393;3866.8979340888272;4646.674732768742;8916.286697064219;9410.541366088268;9843.803123937481;9910.405375677337;9965.252606054575;5338.8665283367445;5379.1662292344745;5386.165144402624;5389.946100539023;5411.080116380225;4107.445494858216;4444.422225110779;4747.098051370229;4916.975415122973;4942.5304596063315;3891.2217556488818;3894.812082314921;3931.9213615727526;4025.6175663311938;4037.1118353962083;1124.342259778039;1305.7584346895856;1311.2853494845333;1316.1510825517678;1323.153181963544;775.4029397363998;885.8626912828522;907.8819753432011;916.9226159262839;924.9953750231206;4542;5037.4458809530925;5920.703964801829;5982.282126144891;6174.197354343996;890.2699786175274;923.3905622289257;956.1983388671122;971.174501673586;979.9020097990286;3268.5587445695;3679.025058359537;3797.411401232758;3806.238752249561;3967.543732470745;8869.68956086534;10577.62978295756;10691.144708423462;10706.250562460553;10815.729606759895;8848;10669.946650266698;10740.462976851126;10781.137508999262;10783.892161078445;8144.267015968591;8167.387445941578;8190.836183276301;8213;8259.669613215405;5632.239647647515;5707.1153971134545;5728.226689396878;5776.971115144397;5817.069268916953;7654.923450765421;9779.266555008546;9863;9970.700878973643;9993.950030249802;10064.043915827984;10177.236707247133;10248.692539223835;10307.072363487323;10360.326578772356;6573.441257493167;6876.415504681958;6985.406240469617;7062.3996960258955;7112.110986126734;8423.70517031901;8440.620172092147;8487.36344774132;8500.064992850657;8520.36097292718;7340.559566426032;7480.588567628833;7509.324160825552;7526.736564220226;7575.810604734926;7145.321194486515;7316.085489313836;7316.341529262392;7463.738769143054;7484.476086673881;6963.1644202696;6970.337817907493;7020.7893763187185;7034;7049.048378468841;4768.833090841943;4866.367372241586;4893.6085113191575;4957.429895562056;4966.726830024239;4879.5120487952345;4914.557689807934;4982.128127577732;4989.850304490871;5019.472955339636 12:30:46 INFO - PROCESS | 1654 | |4;prototype.html;302.63079043566853;309.6485488970019;316.65959093973976;317.85219872759177;319.85926192475324;4402.7578483183415;5521.530669893059;5589.4070189995855;5685.152325494216;5703;5567.638103523268;5606.131049687305;5606.21512988182;5648;5674.773009079642;3508.2632647144133;3550.8579656813827;4297.333913243452;4301.720388174766;4306.720063195898;3187.792793468429;3857.4599556062176;4248.830046798124;4309.978450107745;4336.457942757155;3579.9284014319696;4140.792960351985;4240.809163587646;4253.255680255958;4253.7671369759055;4033.5159780826343;4222.408862759201;4259.3610958356185;4280.4221430106845;4287.399764033038;3042.7870049096573;3160.925285402974;3589.730770192246;3664.78011319319;3715.3683873741256;4717.905641887178;5350.973245133789;5412.648177868466;5426;5530.695811730353;4057.97971010146;4129.318662420697;4938.1111399947995;4957.406649156613;4989.176785830334;5815;6006.786369292451;6014.458698717092;6029.336772954972;6051.69741512927;4047.1298670785627;4051.736637118578;4055.3511438169753;4056.4320995060716;4077.7145599808027;5228.581713462914;6332.873342533124;6360.809175724782;6365.809025729235;6388.488920886364;3226.419244535959;3463.8268086595567;3650.4889315495852;3945.678197803768;3986.720929534905;4003.3594624859884;4099.3031184698675;4148.398482220061;4182.602652747984;4182.832686692559;4392.187445322562;4453.599176074104;4523.525029871882;4907.987725032719;4983.25251212321;5036.294918711384;5476;6055.333913269536;6070.605410648382;6083.387902205919;1267.3599832084922;1420.6022313752167;1429.7354989327007;1446.833614134388;1448.4640682947377;792.5918152151646;850.8723691446208;870.9548541749925;886.8448021596195;887.6538150121457;4334.371516130206;4457.710248833815;4759.16714574948;4765.332853400527;4799.448063472675;4316.892077698083;4483.5204382553675;4605.032943081923;5110.751269441434;5196.142636465017;3959.742616729903;4098.815553300049;4636.884077898012;4764.737939413296;4819.084373969004;2375.2423206826825;2435.7564243575503;2494.2641920633096;2617.371830760642;2691.3540750219804;4712.59942904857;4979.975100124549;5366.880082367443;5373.892522149613;5379.740508986157;4612.76936153191;4672.649551283667;5206.583473322125;5302.867428314323;5310.283111779938;4698.929516057194;4986.675866068694;5031.767217031851;5254.658447200996;5344.625876188629;4656.487786343568;4846.854594362175;5128.974355128201;5160.638755287244;5296;4180.665546756191;4656.324832899279;5121;5176.9128483018985;5507.421720719267;5252.579793616425;5742.310922689304;5838.649681019152;6113.57204995646;6390.616563006234;3074.7700919632325;3717.665410113103;4040.6565441937764;4045.9190816182922;4062.857799976987;5694.800681976111;6017.428344307284;6043.456088952015;6088.756449742138;6209.720562574786;4970.527799859007;5650.463205995424;5978.581499295007;6021.2473440819895;6050.788222412194;4603.6777425579885;4896.779644916059;5265.920486300376;5539.338198540502;5548.973439913629;5583.860403489945;5616.02350754101;5803.245578074992;5839.854003649943;5909.940900591111;6006.069059295816;6011.789587364421;6033.42682445167;6046.848828779316;6107.511399087974;5686.34607020198;5800.970995144998;5834.241548598825;5904.645721256738;5940.970295148497 12:30:46 INFO - PROCESS | 1654 | |5;yui.html;634.9904751428728;640.727690731439;666.257123307512;756.00585230422;805.7421625079974;15147.621309467255;15925.203739812992;16024.75962860558;16071.276792544335;16080.919595402022;15220.923895380536;16074.678506429864;16155.94986325891;16185.35258589655;16263.756043659325;821.9465734727254;845.9873101903477;861.5089399042548;862.5299211929482;869.443556124081;687.2303020616916;860.7718954477068;867.7006432780712;868.0754995929346;870.0386073388909;905.1853332001218;922.6632279218104;924.7688077980505;927.777333439973;929.1776777551847;902.5306840442998;913.8263729891332;915.0209276074603;919.0166521821653;919.4988731141544;679.0459404536634;679.2290749998738;686.4645576450376;687.041577000087;690;15438;15650.060996340257;15862.762058569131;15895.44365947198;16045.117518536475;14232.715345693132;14262.072965257328;14311;14325.856741432557;14487;11813.350265735382;11956.70108247292;11976.880231197663;11984;11986.40193916709;3746.269477451898;3766.3408903441787;3788.5074940257864;3810.6760925321382;3812.1041555234497;107013.46493267562;108923;109146;110316.79366412626;112075.43962280218;10849.132069434427;10935.56257749697;10946.343219406703;10961.83557246642;11105.389203593719;18103.366382176562;21799.891000544896;22267.55464890693;22311.888440557694;22331.218407355664;7811.179826118289;7972.3622110231045;8007.639656215484;8035.55804430762;8157.632906519221;3799.8290076946605;4245.172191422705;4367.301231802896;4413.337999300131;4448.065906159678;3603.279344131184;3660.194757153475;3718.6839118674675;3728.0307120148955;3736.065983504124;654.8409315511518;667.7562689618352;673.5689158938284;684.8014075918028;694.0214297840022;521.3431076843204;526.6050462153385;577.7573419163962;652.9738810447624;665.9633720145439;8077.798055048671;8535.231829135408;9099.226565741987;9250.953745231365;9455.763605909908;493.20593843911274;537.6962016460766;551.481607289147;640.193356370976;672.9360710732473;4286.914261714748;4388.846390376321;4435.955640443619;4476.865694029184;4599.816007359735;76142.61928690321;140705.29647351903;141377.29311353582;143072;143954;103911;107480;108579.82840343307;109307;111191;82900;95776.0422395781;101987;103101;110351;8332.916670833456;8862.42394244372;8914.55427228649;9350.719478415658;9493.57278922464;56248;59181;59540;60132.39867601268;64005;80670;93683.59474608023;94545;94550.52724736332;98413.52379714166;13304.13523120994;13454.125481843648;13474.797878032034;13542.661433464242;13597.456101755819;14522.273886305853;14567.92716036413;14577.489787857374;14579.854201457849;14689.92655036718;13532.797008045085;13554.390052447861;13678.863211368158;13737.908970481561;13787.586372408843;11116.49975751077;13543.187408755506;13631.86368136306;13883;14196.787048194492;13055.804162937373;13399.0756851347;13498.59504214875;13755.724885502033;13851.099678520864;11221.88778112241;11395.487203076047;11415;11484.885151148716;11589.710257243636;7712.691492340244;9457.574409151466;10579.985347109081;11739.119566032752;11744.58893938708 12:30:46 INFO - PROCESS | 1654 | __end_tp_report 12:30:46 INFO - PROCESS | 1654 | __start_cc_report 12:30:46 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,375 12:30:46 INFO - PROCESS | 1654 | __end_cc_report 12:30:46 INFO - PROCESS | 1654 | __startTimestamp1473017446324__endTimestamp 12:30:46 INFO - PROCESS | 1654 | 12:30:46 INFO - PROCESS | 1654 | ------- Summary: start ------- 12:30:46 INFO - PROCESS | 1654 | Number of tests: 6 12:30:46 INFO - PROCESS | 1654 | 12:30:46 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5622.60 Median:5753.42 stddev:661.77 (11.5%) stddev-sans-first:638.90 12:30:46 INFO - PROCESS | 1654 | Values: 3330.4 3357.4 3387.5 3389.6 3394.3 4502.3 5252.1 5647.5 5660.1 5743.3 5536.6 5682.9 5702.6 5719.8 5724.3 5043.8 5123.9 5209.6 5243.2 5253.1 5109.1 5250.6 5264.0 5330.3 5344.5 5076.5 5137.3 5172.3 5204.2 5253.7 5190.5 5194.4 5197.3 5204.1 5208.3 5144.6 5170.7 5189.8 5191.1 5221.6 5511.5 5555.1 5622.4 5745.3 5811.9 4636.0 4915.6 5590.5 5747.8 6113.4 5251.5 6234.8 6266.9 6268.2 6280.3 5598.4 5644.5 5697.7 5753.4 5781.3 6157.4 6159.8 6187.9 6201.9 6282.8 4847.7 6064.8 6068.8 6073.3 6075.6 6026.2 6135.9 6181.0 6194.4 6203.3 5950.7 6122.1 6139.9 6154.3 6218.6 6191.4 6205.5 6211.7 6237.5 6237.7 4795.0 4864.0 4869.7 4906.5 4921.2 4368.8 4374.6 4430.3 4467.4 4478.6 4554.4 5656.6 5721.9 5791.7 5792.9 6127.3 6247.3 6248.3 6275.7 6287.8 5078.8 5483.5 5691.5 5706.0 5772.9 5350.6 5366.4 5368.6 5386.0 5395.6 6187.8 6292.0 6295.0 6302.7 6314.8 6191.2 6192.4 6203.6 6259.5 6270.6 6209.6 6250.9 6279.8 6289.9 6292.6 6061.7 6161.8 6189.9 6222.3 6242.9 5869.4 6148.0 6185.5 6187.3 6298.7 4934.8 6062.9 6085.2 6097.5 6156.5 5916.9 6064.5 6082.4 6092.3 6101.4 5701.7 5722.2 5730.7 5769.5 5807.7 5902.3 5946.6 5951.7 5968.6 5993.5 4244.8 4387.9 5743.6 5782.0 5786.0 12:30:46 INFO - PROCESS | 1654 | 12:30:46 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:68395.53 Median:39220.40 stddev:152189.17 (388.0%) stddev-sans-first:152530.59 12:30:46 INFO - PROCESS | 1654 | Values: 446.2 466.9 468.0 530.8 537.3 36290.8 39781.6 39828.0 39865.8 40105.2 34496.3 39688.2 40953.4 41385.6 41544.6 9496.5 9690.5 9706.7 9717.8 9729.7 9509.0 9571.4 9678.9 9697.6 9709.2 9694.8 9698.4 9713.9 9719.7 9743.9 9654.8 9697.6 9699.8 9709.0 9721.6 9654.2 9715.5 9734.3 9738.1 9748.8 39133.0 39307.8 39369.8 39420.6 39464.6 3322.5 3713.7 4041.3 4054.8 4057.8 29625.6 29722.0 30091.1 30115.1 30387.8 3254.8 3259.1 3262.3 3295.6 3350.5 505573.0 512216.9 514010.0 514133.0 516708.4 55565.9 55857.7 55867.0 55873.4 56049.7 34038.5 34868.0 34960.0 35743.5 58455.4 15966.6 16151.6 16152.4 16156.4 16235.9 12417.6 12484.9 12515.6 12522.9 12524.9 14926.5 18223.0 18333.9 18367.0 18590.6 7801.6 7857.8 7881.8 7961.6 7967.9 855.4 883.0 1032.3 1055.4 1128.0 28553.9 28672.9 29979.9 30018.8 30080.8 837.4 1029.5 1037.2 1046.5 1058.4 15324.0 15371.5 15590.6 16050.6 16077.8 642621.0 646636.0 649625.0 656807.0 657591.0 56283.9 56422.6 56912.1 56928.0 56964.0 1328.8 1333.4 1373.7 1376.4 1380.2 26013.5 28634.9 29605.0 29702.6 30379.8 47575.0 48356.3 48364.8 48467.0 48735.5 508337.0 509026.0 509532.0 511254.0 619386.0 24933.6 25160.1 25220.0 25254.2 25254.5 42963.8 43368.6 43453.6 43478.9 43834.8 36415.0 36550.3 36647.6 37478.0 37731.2 34930.0 35784.0 36041.3 36891.4 37586.0 34666.7 34826.7 34835.7 35146.0 35702.0 26062.0 30640.4 32139.7 32180.0 32527.7 22515.7 22985.4 24103.6 24647.9 24851.5 12:30:46 INFO - PROCESS | 1654 | 12:30:46 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:34663.03 Median:13977.83 stddev:97671.28 (698.8%) stddev-sans-first:97911.22 12:30:46 INFO - PROCESS | 1654 | Values: 315.8 360.9 368.2 371.1 387.0 15618.0 20160.5 20555.0 21237.9 21480.8 18134.7 20471.8 21210.5 21216.3 21301.8 5812.4 5940.5 6187.1 6201.1 6206.9 5163.8 6023.7 6148.7 6150.5 6152.1 5927.9 6129.9 6138.1 6149.4 6200.9 6021.8 6128.7 6151.0 6174.3 6191.2 5741.7 5960.9 6006.5 6022.7 6026.3 13190.7 13456.5 13886.7 25054.0 25354.7 2101.4 2191.6 2232.8 2285.3 2600.9 9943.4 9962.6 9984.2 10294.3 10463.0 1888.2 1893.6 1922.2 1931.2 1986.8 119926.0 122721.4 122742.4 124147.4 124415.0 8780.4 10960.0 11497.4 11545.5 11582.7 14453.0 14455.7 14463.6 14471.6 14492.6 8203.0 8505.6 8542.0 8629.7 8662.1 6884.8 8265.4 8336.4 8374.9 8569.8 4497.8 4502.2 4502.8 4506.0 4530.1 1620.4 1632.5 1878.7 1918.8 1926.5 761.3 922.3 939.0 952.2 995.0 19268.0 21423.0 21490.0 21579.9 21839.5 833.3 869.6 901.3 941.5 997.8 9258.2 10953.3 11340.0 11373.1 11460.9 553892.0 575179.0 583165.0 599154.0 600065.0 125349.0 126144.0 130976.0 132730.0 138666.0 975.1 1021.7 1038.1 1039.7 1053.2 18740.7 18751.4 18881.2 19069.9 19178.5 54081.7 54329.7 55156.2 55275.4 56686.7 78059.6 78739.8 81253.0 82827.0 83568.9 6908.9 7011.7 7947.2 8036.4 8120.9 9592.2 11277.3 12295.7 12475.4 13200.5 11042.9 11077.1 11357.7 11389.6 11394.1 10663.2 10694.9 10909.6 11081.4 11099.0 11047.1 11285.4 11410.0 11484.1 11506.3 6118.7 7062.1 7175.1 7422.4 7424.4 9626.0 10754.7 11086.2 11117.6 11186.7 12:30:46 INFO - PROCESS | 1654 | 12:30:46 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5627.45 Median:4974.43 stddev:3156.01 (63.4%) stddev-sans-first:3149.35 12:30:46 INFO - PROCESS | 1654 | Values: 1463.9 1502.0 1735.1 1858.4 1899.4 7196.2 8404.5 8562.3 8958.0 8970.6 8666.9 8790.9 8792.5 8798.5 8806.7 2386.1 2401.4 2406.3 2413.3 2427.2 2189.3 2370.9 2396.8 2431.9 2435.0 2212.3 2378.5 2391.8 2405.5 2408.7 2370.2 2381.7 2383.7 2392.6 2396.1 2339.4 2353.3 2366.3 2375.5 2382.5 8722.5 8892.3 8899.2 8909.8 8910.1 5212.1 5349.3 5389.0 5402.9 5412.2 3580.4 3629.6 3646.3 3692.1 3704.5 1096.7 1103.3 1103.7 1105.8 1119.4 11539.8 11568.2 11647.8 11695.9 11822.9 3476.3 3663.9 3794.2 3866.9 4646.7 8916.3 9410.5 9843.8 9910.4 9965.3 5338.9 5379.2 5386.2 5389.9 5411.1 4107.4 4444.4 4747.1 4917.0 4942.5 3891.2 3894.8 3931.9 4025.6 4037.1 1124.3 1305.8 1311.3 1316.2 1323.2 775.4 885.9 907.9 916.9 925.0 4542.0 5037.4 5920.7 5982.3 6174.2 890.3 923.4 956.2 971.2 979.9 3268.6 3679.0 3797.4 3806.2 3967.5 8869.7 10577.6 10691.1 10706.3 10815.7 8848.0 10669.9 10740.5 10781.1 10783.9 8144.3 8167.4 8190.8 8213.0 8259.7 5632.2 5707.1 5728.2 5777.0 5817.1 7654.9 9779.3 9863.0 9970.7 9994.0 10064.0 10177.2 10248.7 10307.1 10360.3 6573.4 6876.4 6985.4 7062.4 7112.1 8423.7 8440.6 8487.4 8500.1 8520.4 7340.6 7480.6 7509.3 7526.7 7575.8 7145.3 7316.1 7316.3 7463.7 7484.5 6963.2 6970.3 7020.8 7034.0 7049.0 4768.8 4866.4 4893.6 4957.4 4966.7 4879.5 4914.6 4982.1 4989.9 5019.5 12:30:46 INFO - PROCESS | 1654 | 12:30:46 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4535.79 Median:4923.05 stddev:1429.89 (29.0%) stddev-sans-first:1398.16 12:30:46 INFO - PROCESS | 1654 | Values: 302.6 309.6 316.7 317.9 319.9 4402.8 5521.5 5589.4 5685.2 5703.0 5567.6 5606.1 5606.2 5648.0 5674.8 3508.3 3550.9 4297.3 4301.7 4306.7 3187.8 3857.5 4248.8 4310.0 4336.5 3579.9 4140.8 4240.8 4253.3 4253.8 4033.5 4222.4 4259.4 4280.4 4287.4 3042.8 3160.9 3589.7 3664.8 3715.4 4717.9 5351.0 5412.6 5426.0 5530.7 4058.0 4129.3 4938.1 4957.4 4989.2 5815.0 6006.8 6014.5 6029.3 6051.7 4047.1 4051.7 4055.4 4056.4 4077.7 5228.6 6332.9 6360.8 6365.8 6388.5 3226.4 3463.8 3650.5 3945.7 3986.7 4003.4 4099.3 4148.4 4182.6 4182.8 4392.2 4453.6 4523.5 4908.0 4983.3 5036.3 5476.0 6055.3 6070.6 6083.4 1267.4 1420.6 1429.7 1446.8 1448.5 792.6 850.9 871.0 886.8 887.7 4334.4 4457.7 4759.2 4765.3 4799.4 4316.9 4483.5 4605.0 5110.8 5196.1 3959.7 4098.8 4636.9 4764.7 4819.1 2375.2 2435.8 2494.3 2617.4 2691.4 4712.6 4980.0 5366.9 5373.9 5379.7 4612.8 4672.6 5206.6 5302.9 5310.3 4698.9 4986.7 5031.8 5254.7 5344.6 4656.5 4846.9 5129.0 5160.6 5296.0 4180.7 4656.3 5121.0 5176.9 5507.4 5252.6 5742.3 5838.6 6113.6 6390.6 3074.8 3717.7 4040.7 4045.9 4062.9 5694.8 6017.4 6043.5 6088.8 6209.7 4970.5 5650.5 5978.6 6021.2 6050.8 4603.7 4896.8 5265.9 5539.3 5549.0 5583.9 5616.0 5803.2 5839.9 5909.9 6006.1 6011.8 6033.4 6046.8 6107.5 5686.3 5801.0 5834.2 5904.6 5941.0 12:30:46 INFO - PROCESS | 1654 | 12:30:46 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:23411.49 Median:3756.31 stddev:36027.46 (959.1%) stddev-sans-first:36087.93 12:30:46 INFO - PROCESS | 1654 | Values: 635.0 640.7 666.3 756.0 805.7 15147.6 15925.2 16024.8 16071.3 16080.9 15220.9 16074.7 16155.9 16185.4 16263.8 821.9 846.0 861.5 862.5 869.4 687.2 860.8 867.7 868.1 870.0 905.2 922.7 924.8 927.8 929.2 902.5 913.8 915.0 919.0 919.5 679.0 679.2 686.5 687.0 690.0 15438.0 15650.1 15862.8 15895.4 16045.1 14232.7 14262.1 14311.0 14325.9 14487.0 11813.4 11956.7 11976.9 11984.0 11986.4 3746.3 3766.3 3788.5 3810.7 3812.1 107013.5 108923.0 109146.0 110316.8 112075.4 10849.1 10935.6 10946.3 10961.8 11105.4 18103.4 21799.9 22267.6 22311.9 22331.2 7811.2 7972.4 8007.6 8035.6 8157.6 3799.8 4245.2 4367.3 4413.3 4448.1 3603.3 3660.2 3718.7 3728.0 3736.1 654.8 667.8 673.6 684.8 694.0 521.3 526.6 577.8 653.0 666.0 8077.8 8535.2 9099.2 9251.0 9455.8 493.2 537.7 551.5 640.2 672.9 4286.9 4388.8 4436.0 4476.9 4599.8 76142.6 140705.3 141377.3 143072.0 143954.0 103911.0 107480.0 108579.8 109307.0 111191.0 82900.0 95776.0 101987.0 103101.0 110351.0 8332.9 8862.4 8914.6 9350.7 9493.6 56248.0 59181.0 59540.0 60132.4 64005.0 80670.0 93683.6 94545.0 94550.5 98413.5 13304.1 13454.1 13474.8 13542.7 13597.5 14522.3 14567.9 14577.5 14579.9 14689.9 13532.8 13554.4 13678.9 13737.9 13787.6 11116.5 13543.2 13631.9 13883.0 14196.8 13055.8 13399.1 13498.6 13755.7 13851.1 11221.9 11395.5 11415.0 11484.9 11589.7 7712.7 9457.6 10580.0 11739.1 11744.6 12:30:46 INFO - PROCESS | 1654 | -------- Summary: end -------- 12:30:46 INFO - PROCESS | 1654 | 12:30:46 INFO - TEST-INFO | 1654: exit 0 12:30:46 INFO - TEST-OK | dromaeo_css | took 1113093ms 12:30:46 INFO - TEST-START | kraken 12:30:46 INFO - operating with platform_type : mac_ 12:30:46 INFO - Initialising browser for kraken test... 12:30:46 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpq8yGmL/profile http://localhost:49271/getInfo.html) 12:30:49 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 12:30:49 INFO - PROCESS | 1662 | colorDepth:24 12:30:49 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 12:30:49 INFO - PROCESS | 1662 | __metrics 12:30:49 ERROR - PROCESS | 1662 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 12:30:50 INFO - TEST-INFO | 1662: exit 0 12:30:50 INFO - Browser initialized. 12:30:50 INFO - Running cycle 1/1 for kraken test... 12:30:50 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpq8yGmL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:31:02 INFO - PROCESS | 1664 | RSS: Main: 245039104 12:31:02 INFO - PROCESS | 1664 | 12:31:05 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 12:31:06 INFO - PROCESS | 1664 | RSS: Main: 258117632 12:31:06 INFO - PROCESS | 1664 | 12:31:08 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 12:31:09 INFO - PROCESS | 1664 | RSS: Main: 266813440 12:31:09 INFO - PROCESS | 1664 | 12:31:13 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 12:31:13 INFO - PROCESS | 1664 | RSS: Main: 263467008 12:31:13 INFO - PROCESS | 1664 | 12:31:15 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 12:31:16 INFO - PROCESS | 1664 | RSS: Main: 265809920 12:31:16 INFO - PROCESS | 1664 | 12:31:18 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:31:19 INFO - PROCESS | 1664 | RSS: Main: 266477568 12:31:19 INFO - PROCESS | 1664 | 12:31:22 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 12:31:22 INFO - PROCESS | 1664 | RSS: Main: 266760192 12:31:22 INFO - PROCESS | 1664 | 12:31:25 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 12:31:26 INFO - PROCESS | 1664 | RSS: Main: 267071488 12:31:26 INFO - PROCESS | 1664 | 12:31:29 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 12:31:29 INFO - PROCESS | 1664 | RSS: Main: 267190272 12:31:29 INFO - PROCESS | 1664 | 12:31:31 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:31:32 INFO - PROCESS | 1664 | RSS: Main: 266792960 12:31:32 INFO - PROCESS | 1664 | 12:31:34 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:31:34 INFO - PROCESS | 1664 | RSS: Main: 267169792 12:31:34 INFO - PROCESS | 1664 | 12:31:36 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:31:37 INFO - PROCESS | 1664 | RSS: Main: 267206656 12:31:37 INFO - PROCESS | 1664 | 12:31:40 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:31:41 INFO - PROCESS | 1664 | RSS: Main: 267313152 12:31:41 INFO - PROCESS | 1664 | 12:31:44 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:31:44 INFO - PROCESS | 1664 | RSS: Main: 268374016 12:31:44 INFO - PROCESS | 1664 | 12:31:46 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 12:31:47 INFO - PROCESS | 1664 | RSS: Main: 270446592 12:31:47 INFO - PROCESS | 1664 | 12:31:47 INFO - PROCESS | 1664 | __start_tp_report 12:31:47 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 12:31:47 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 12:31:47 INFO - PROCESS | 1664 | |i|pagename|runs| 12:31:47 INFO - PROCESS | 1664 | |0;ai-astar;170;111;139;172;117;100;117;150;98;172 12:31:47 INFO - PROCESS | 1664 | |1;audio-beat-detection;143;117;131;158;113;133;133;112;161;112 12:31:47 INFO - PROCESS | 1664 | |2;audio-dft;201;208;213;210;231;293;196;196;199;194 12:31:47 INFO - PROCESS | 1664 | |3;audio-fft;70;98;103;178;87;60;57;60;141;52 12:31:47 INFO - PROCESS | 1664 | |4;audio-oscillator;70;73;124;74;69;84;110;70;72;111 12:31:47 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;89;78;137;125;89;73;133;132;90;106 12:31:47 INFO - PROCESS | 1664 | |6;imaging-darkroom;148;109;175;156;98;99;103;98;99;99 12:31:47 INFO - PROCESS | 1664 | |7;imaging-desaturate;102;92;91;92;96;91;91;105;93;112 12:31:47 INFO - PROCESS | 1664 | |8;json-parse-financial;54;91;57;57;58;57;56;57;57;57 12:31:47 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;67;69;74;70;89;67;76;87;93;103 12:31:47 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;91;86;89;88;84;90;89;92;90;88 12:31:47 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;141;141;139;139;141;141;142;144;186;139 12:31:47 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;181;182;203;184;197;264;199;205;259;193 12:31:47 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;66;85;63;89;94;88;91;79;69;69 12:31:47 INFO - PROCESS | 1664 | __end_tp_report 12:31:47 INFO - PROCESS | 1664 | __start_cc_report 12:31:47 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,980 12:31:47 INFO - PROCESS | 1664 | __end_cc_report 12:31:47 INFO - PROCESS | 1664 | __startTimestamp1473017507075__endTimestamp 12:31:47 INFO - PROCESS | 1664 | 12:31:47 INFO - PROCESS | 1664 | ------- Summary: start ------- 12:31:47 INFO - PROCESS | 1664 | Number of tests: 14 12:31:47 INFO - PROCESS | 1664 | 12:31:47 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:134.60 Median:160.00 stddev:29.85 (18.7%) stddev-sans-first:28.78 12:31:47 INFO - PROCESS | 1664 | Values: 170.0 111.0 139.0 172.0 117.0 100.0 117.0 150.0 98.0 172.0 12:31:47 INFO - PROCESS | 1664 | 12:31:47 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:131.30 Median:133.00 stddev:18.32 (13.8%) stddev-sans-first:18.94 12:31:47 INFO - PROCESS | 1664 | Values: 143.0 117.0 131.0 158.0 113.0 133.0 133.0 112.0 161.0 112.0 12:31:47 INFO - PROCESS | 1664 | 12:31:47 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:214.10 Median:209.00 stddev:29.86 (14.3%) stddev-sans-first:31.29 12:31:47 INFO - PROCESS | 1664 | Values: 201.0 208.0 213.0 210.0 231.0 293.0 196.0 196.0 199.0 194.0 12:31:47 INFO - PROCESS | 1664 | 12:31:47 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:90.60 Median:60.00 stddev:41.25 (68.8%) stddev-sans-first:43.08 12:31:47 INFO - PROCESS | 1664 | Values: 70.0 98.0 103.0 178.0 87.0 60.0 57.0 60.0 141.0 52.0 12:31:47 INFO - PROCESS | 1664 | 12:31:47 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:85.70 Median:71.00 stddev:20.97 (29.5%) stddev-sans-first:21.46 12:31:47 INFO - PROCESS | 1664 | Values: 70.0 73.0 124.0 74.0 69.0 84.0 110.0 70.0 72.0 111.0 12:31:47 INFO - PROCESS | 1664 | 12:31:47 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:105.20 Median:75.50 stddev:24.56 (32.5%) stddev-sans-first:25.34 12:31:47 INFO - PROCESS | 1664 | Values: 89.0 78.0 137.0 125.0 89.0 73.0 133.0 132.0 90.0 106.0 12:31:47 INFO - PROCESS | 1664 | 12:31:47 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:118.40 Median:98.00 stddev:29.40 (30.0%) stddev-sans-first:29.17 12:31:47 INFO - PROCESS | 1664 | Values: 148.0 109.0 175.0 156.0 98.0 99.0 103.0 98.0 99.0 99.0 12:31:47 INFO - PROCESS | 1664 | 12:31:47 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:96.50 Median:91.50 stddev:7.35 (8.0%) stddev-sans-first:7.52 12:31:47 INFO - PROCESS | 1664 | Values: 102.0 92.0 91.0 92.0 96.0 91.0 91.0 105.0 93.0 112.0 12:31:47 INFO - PROCESS | 1664 | 12:31:47 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:60.10 Median:57.00 stddev:10.91 (19.1%) stddev-sans-first:11.34 12:31:47 INFO - PROCESS | 1664 | Values: 54.0 91.0 57.0 57.0 58.0 57.0 56.0 57.0 57.0 57.0 12:31:47 INFO - PROCESS | 1664 | 12:31:47 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:79.50 Median:75.00 stddev:12.63 (16.8%) stddev-sans-first:12.56 12:31:47 INFO - PROCESS | 1664 | Values: 67.0 69.0 74.0 70.0 89.0 67.0 76.0 87.0 93.0 103.0 12:31:47 INFO - PROCESS | 1664 | 12:31:47 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:88.70 Median:89.50 stddev:2.36 (2.6%) stddev-sans-first:2.35 12:31:47 INFO - PROCESS | 1664 | Values: 91.0 86.0 89.0 88.0 84.0 90.0 89.0 92.0 90.0 88.0 12:31:47 INFO - PROCESS | 1664 | 12:31:47 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:145.30 Median:141.00 stddev:14.38 (10.2%) stddev-sans-first:15.17 12:31:47 INFO - PROCESS | 1664 | Values: 141.0 141.0 139.0 139.0 141.0 141.0 142.0 144.0 186.0 139.0 12:31:47 INFO - PROCESS | 1664 | 12:31:47 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:206.70 Median:201.00 stddev:30.11 (15.0%) stddev-sans-first:30.47 12:31:47 INFO - PROCESS | 1664 | Values: 181.0 182.0 203.0 184.0 197.0 264.0 199.0 205.0 259.0 193.0 12:31:47 INFO - PROCESS | 1664 | 12:31:47 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.30 Median:86.50 stddev:11.60 (13.4%) stddev-sans-first:11.26 12:31:47 INFO - PROCESS | 1664 | Values: 66.0 85.0 63.0 89.0 94.0 88.0 91.0 79.0 69.0 69.0 12:31:47 INFO - PROCESS | 1664 | -------- Summary: end -------- 12:31:47 INFO - PROCESS | 1664 | 12:31:48 INFO - TEST-INFO | 1664: exit 0 12:31:48 INFO - TEST-OK | kraken | took 61187ms 12:31:48 INFO - SUITE-END | took 1174s 12:31:48 INFO - Completed test suite (00:19:34) 12:31:48 INFO - javascript benchmark 12:31:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7252.13517534334, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3330.3838789823885, 3357.3788849062917, 3387.4749413840855, 3389.593248810143, 3394.3381040697072, 4502.302143167814, 5252.107141785896, 5647.491725744682, 5660.094384898417, 5743.253377060976, 5536.557075433966, 5682.886342273152, 5702.629329093606, 5719.828405147852, 5724.313082430114, 5043.798248070082, 5123.871903202413, 5209.557187639036, 5243.161094224924, 5253.080710875601, 5109.105906466372, 5250.579953603703, 5264.0, 5330.253764472977, 5344.465553444644, 5076.517730815566, 5137.332146820908, 5172.32759741235, 5204.193350030751, 5253.684778913239, 5190.4550022247495, 5194.428612852583, 5197.272381866542, 5204.115300398941, 5208.322918020671, 5144.6141539384325, 5170.715610641413, 5189.818356357547, 5191.091558977201, 5221.608379371524, 5511.53151982082, 5555.083411237181, 5622.409646987089, 5745.339285982123, 5811.854703632401, 4635.976820115911, 4915.606751459874, 5590.468905454016, 5747.798827041075, 6113.388661133896, 5251.52736253739, 6234.844128896769, 6266.937330626726, 6268.247810262752, 6280.309165991737, 5598.356189038272, 5644.5484361251, 5697.658140511582, 5753.424657534297, 5781.277340332458, 6157.353477884758, 6159.784407545714, 6187.876242475125, 6201.937980620226, 6282.811515654537, 4847.684900481458, 6064.848378790477, 6068.75724971005, 6073.301570319381, 6075.5747097703625, 6026.186464827279, 6135.937063216329, 6180.969095154495, 6194.4115309045565, 6203.2556093268195, 5950.672713000827, 6122.142899994004, 6139.921477737008, 6154.292256390483, 6218.626882387071, 6191.380861913773, 6205.47253483459, 6211.689415529206, 6237.532185086136, 6237.688115594293, 4795.017021510582, 4864.027194561102, 4869.659123861366, 4906.484819093944, 4921.163402221631, 4368.825246990084, 4374.628156606724, 4430.335449682509, 4467.441569803775, 4478.596926276651, 4554.407926969473, 5656.632318899258, 5721.942780572224, 5791.710414479259, 5792.855178620568, 6127.295361033449, 6247.250329960434, 6248.319942653996, 6275.748970041239, 6287.779927702509, 5078.796848126033, 5483.533899618497, 5691.459311365414, 5706.029974904193, 5772.855678608068, 5350.5987050971335, 5366.382865970385, 5368.624196306299, 5386.0, 5395.568354531628, 6187.752489900354, 6291.968540157453, 6295.0, 6302.684865756693, 6314.810555683429, 6191.1641928339095, 6192.380761923771, 6203.565750397429, 6259.499240060875, 6270.561060725706, 6209.565330426827, 6250.937490625036, 6279.780207692709, 6289.9371006291185, 6292.559520833498, 6061.696915154313, 6161.84595385119, 6189.907151392823, 6222.346653601307, 6242.906356404748, 5869.442402971711, 6147.954847675816, 6185.474234690102, 6187.257529096357, 6298.653574053471, 4934.777934993006, 6062.939370606237, 6085.2393450818645, 6097.4817140543555, 6156.476699480594, 5916.940830591639, 6064.4541991221, 6082.391760823883, 6092.329843717276, 6101.389861013863, 5701.657900525982, 5722.17028530869, 5730.684812335361, 5769.480746732815, 5807.651540907559, 5902.2622172228475, 5946.643201407929, 5951.732172052355, 5968.552358573038, 5993.460588547052, 4244.7877606118955, 4387.890302742457, 5743.597948143757, 5781.971090144522, 5785.971070144622], "value": 5583.9069885643585, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [446.23693484142115, 466.86460926331364, 467.9812807487701, 530.8009496438834, 537.3337062043067, 36290.8185459073, 39781.60218397815, 39828.0, 39865.80402587923, 40105.19789604206, 34496.31007379857, 39688.20623587527, 40953.38569921454, 41385.58614413855, 41544.58455415445, 9496.47769372686, 9690.515474226295, 9706.708798736014, 9717.805643887117, 9729.708108756713, 9509.0, 9571.37786043905, 9678.854817177747, 9697.563609637547, 9709.17472014876, 9694.757631059245, 9698.36960597559, 9713.854292185622, 9719.708408747749, 9743.853842192373, 9654.806903861918, 9697.563609637584, 9699.757506062335, 9708.95145524275, 9721.611135554569, 9654.22766178711, 9715.514224288758, 9734.269929755226, 9738.074882886185, 9748.805023899553, 39133.0, 39307.80346098251, 39369.80315098435, 39420.60579394227, 39464.60535394638, 3322.5348451216605, 3713.702903767719, 4041.2725709372303, 4054.8175332110127, 4057.797110144481, 29625.555616665766, 29722.0, 30091.097267082023, 30115.09654710362, 30387.848060759778, 3254.8372581371073, 3259.08745551246, 3262.3312220994876, 3295.571575695168, 3350.547676063725, 505573.0, 512216.8778312206, 514010.0, 514133.0, 516708.41645791905, 55565.88868222613, 55857.720711396185, 55867.0, 55873.44126558764, 56049.71975140098, 34038.48942265868, 34868.0, 34960.0, 35743.4638480423, 58455.41544584585, 15966.600834978986, 16151.577263410583, 16152.35390584387, 16156.353745850034, 16235.918820405823, 12417.62747117588, 12484.875151248552, 12515.624531263895, 12522.87477125217, 12524.874751252553, 14926.4775732851, 18223.0, 18333.908330458264, 18366.98981556027, 18590.628187436174, 7801.609919504002, 7857.803554911173, 7881.832496063514, 7961.601919903982, 7967.880481792778, 855.4268640011207, 882.9977974998295, 1032.2825636182931, 1055.3615062886997, 1128.041165009735, 28553.857230713715, 28672.85663571669, 29979.850100749358, 30018.849905750332, 30080.849595751883, 837.367787320569, 1029.4543891737392, 1037.2004572067458, 1046.5081411736471, 1058.4020028683924, 15324.0, 15371.538853834403, 15590.610234744223, 16050.598735031484, 16077.839221607634, 642621.0, 646636.0, 649625.0, 656807.0, 657591.0, 56283.87432251332, 56422.614643121546, 56912.146317805265, 56928.0, 56964.0, 1328.8272524571744, 1333.3799783100906, 1373.6703191234233, 1376.4012654495527, 1380.2201756008023, 26013.479730404906, 28634.85682571574, 29604.963826265976, 29702.554461683525, 30379.848100759355, 47575.0, 48356.27465587949, 48364.79088022828, 48467.0, 48735.512644873095, 508337.0, 509026.0, 509532.0, 511254.0, 619386.0, 24933.625995610444, 25160.119395821057, 25220.0, 25254.242372728848, 25254.49491010206, 42963.785181075145, 43368.56631433645, 43453.56546434622, 43478.91302717331, 43834.780826095666, 36415.0, 36550.26899461943, 36647.63352366442, 37478.0, 37731.24537509289, 34930.0, 35784.0, 36041.279174416886, 36891.44662830006, 37586.0, 34666.65333346634, 34826.651733482344, 34835.65164348324, 35146.0, 35702.0, 26062.0, 30640.387192255585, 32139.67860321367, 32180.0, 32527.674723252465, 22515.66226506571, 22985.425364366023, 24103.63844542298, 24647.876760616084, 24851.502969940862], "value": 19319.14990375951, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.8215608181376, 360.9350316942951, 368.16794045457266, 371.0945293483899, 387.01890707057595, 15618.0, 20160.495987600316, 20555.0, 21237.938103094843, 21480.785192148112, 18134.727979080326, 20471.795282047213, 21210.46973825655, 21216.257430989917, 21301.786982130176, 5812.360640329561, 5940.495057920082, 6187.133801267826, 6201.069839524085, 6206.906896396557, 5163.7676304566385, 6023.668698221596, 6148.692565371736, 6150.538709596775, 6152.077188421727, 5927.911081333784, 6129.908051379233, 6138.14066030756, 6149.354317796612, 6200.937990620104, 6021.759129634809, 6128.662923539204, 6151.046587778901, 6174.289956654975, 6191.164192833955, 5741.655500669973, 5960.850978725523, 6006.489448396892, 6022.6687532185715, 6026.276846778371, 13190.670233244246, 13456.461741530327, 13886.652833679138, 25053.997840086373, 25354.746452535608, 2101.4011006863125, 2191.550732099916, 2232.787885150908, 2285.325828880484, 2600.9089681861046, 9943.425167831996, 9962.551685174185, 9984.201263898944, 10294.279400441972, 10463.0, 1888.1692055495676, 1893.5739458621838, 1922.1542521290587, 1931.169597073258, 1986.7913869043832, 119926.0092488439, 122721.38639306746, 122742.38628806888, 124147.37926310311, 124415.0, 8780.385373023955, 10960.0, 11497.367644779617, 11545.480453379618, 11582.710432239093, 14453.0, 14455.710885782226, 14463.566093017227, 14471.638209044859, 14492.565223043326, 8202.958985205036, 8505.617247223767, 8542.0, 8629.654813807503, 8662.133786621287, 6884.827879303058, 8265.380096492787, 8336.374771892133, 8374.916250837536, 8569.828603427897, 4497.775111244425, 4502.234620114588, 4502.819887204475, 4506.031203291308, 4530.093981203772, 1620.4247492140357, 1632.526567295471, 1878.680624293846, 1918.8081191880979, 1926.5087402712218, 761.2919984414548, 922.3128769066954, 939.0, 952.1811242331589, 994.9502524873727, 19268.0, 21423.0, 21490.0, 21579.892100539397, 21839.454013649785, 833.3083540661237, 869.5565261716444, 901.2654686430539, 941.5433514745343, 997.7705127820642, 9258.166764991183, 10953.3427994319, 11340.0, 11373.147013973985, 11460.885391145981, 553892.0, 575179.0, 583165.0, 599154.0, 600065.0, 125349.0, 126144.0, 130976.0, 132730.0, 138666.0, 975.1321324021626, 1021.7139201023725, 1038.133158812385, 1039.734867608755, 1053.2469284461495, 18740.718889216947, 18751.437456876316, 18881.244750210386, 19069.90465047666, 19178.520536986685, 54081.72959135337, 54329.72835135799, 55156.17265740937, 55275.44724552864, 56686.7165664169, 78059.60970195112, 78739.81890271536, 81253.0, 82827.0, 83568.91077722862, 6908.861822763617, 7011.719531218898, 7947.165547617417, 8036.437449378487, 8120.918790812017, 9592.184664303611, 11277.32336059839, 12295.69260768488, 12475.376231188586, 13200.471981121033, 11042.944785276022, 11077.113830893348, 11357.716057098638, 11389.601363952223, 11394.08847292198, 10663.200259980687, 10694.946525267324, 10909.618163364245, 11081.445927703422, 11099.0, 11047.116230701364, 11285.43572821372, 11410.030147437561, 11484.12896775806, 11506.30962142274, 6118.724657390517, 7062.117235345582, 7175.138983322035, 7422.406207503279, 7424.443166762407, 9626.0, 10754.73113172177, 11086.22396432242, 11117.555297787998, 11186.664400068012], "value": 9431.673244447386, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1463.8902082343825, 1501.9774703379449, 1735.123762499938, 1858.3681548273585, 1899.3637131560936, 7196.208417074117, 8404.45371050882, 8562.272206862424, 8957.955210223949, 8970.596323165457, 8666.869996950036, 8790.912090879105, 8792.516411597375, 8798.472091674488, 8806.691765788199, 2386.081358676909, 2401.423658321999, 2406.254061241012, 2413.3122060212754, 2427.235420842429, 2189.288481243594, 2370.928872133839, 2396.7962723168553, 2431.8905649245826, 2435.0381599268367, 2212.2810086721797, 2378.512404956988, 2391.8325717199805, 2405.494846082316, 2408.7229968553665, 2370.158593699229, 2381.6546600742968, 2383.7020372453444, 2392.605220138675, 2396.113438027923, 2339.368370539945, 2353.329301149164, 2366.3137690069857, 2375.5248950209852, 2382.5354055959097, 8722.476651400937, 8892.288616910631, 8899.243564296978, 8909.777255568597, 8910.064443233432, 5212.139996900507, 5349.251104845286, 5388.973055134738, 5402.945970540283, 5412.242286079952, 3580.445031020196, 3629.5644522657185, 3646.270745850814, 3692.095436618019, 3704.518412606371, 1096.6545538155535, 1103.2773533335599, 1103.7185517693017, 1105.7677887643524, 1119.3731510354228, 11539.76920461586, 11568.190226684135, 11647.767044659144, 11695.883041169565, 11822.881771182265, 3476.287361091021, 3663.908402289964, 3794.203217324393, 3866.8979340888272, 4646.674732768742, 8916.286697064219, 9410.541366088268, 9843.803123937481, 9910.405375677337, 9965.252606054575, 5338.8665283367445, 5379.1662292344745, 5386.165144402624, 5389.946100539023, 5411.080116380225, 4107.445494858216, 4444.422225110779, 4747.098051370229, 4916.975415122973, 4942.5304596063315, 3891.2217556488818, 3894.812082314921, 3931.9213615727526, 4025.6175663311938, 4037.1118353962083, 1124.342259778039, 1305.7584346895856, 1311.2853494845333, 1316.1510825517678, 1323.153181963544, 775.4029397363998, 885.8626912828522, 907.8819753432011, 916.9226159262839, 924.9953750231206, 4542.0, 5037.4458809530925, 5920.703964801829, 5982.282126144891, 6174.197354343996, 890.2699786175274, 923.3905622289257, 956.1983388671122, 971.174501673586, 979.9020097990286, 3268.5587445695, 3679.025058359537, 3797.411401232758, 3806.238752249561, 3967.543732470745, 8869.68956086534, 10577.62978295756, 10691.144708423462, 10706.250562460553, 10815.729606759895, 8848.0, 10669.946650266698, 10740.462976851126, 10781.137508999262, 10783.892161078445, 8144.267015968591, 8167.387445941578, 8190.836183276301, 8213.0, 8259.669613215405, 5632.239647647515, 5707.1153971134545, 5728.226689396878, 5776.971115144397, 5817.069268916953, 7654.923450765421, 9779.266555008546, 9863.0, 9970.700878973643, 9993.950030249802, 10064.043915827984, 10177.236707247133, 10248.692539223835, 10307.072363487323, 10360.326578772356, 6573.441257493167, 6876.415504681958, 6985.406240469617, 7062.3996960258955, 7112.110986126734, 8423.70517031901, 8440.620172092147, 8487.36344774132, 8500.064992850657, 8520.36097292718, 7340.559566426032, 7480.588567628833, 7509.324160825552, 7526.736564220226, 7575.810604734926, 7145.321194486515, 7316.085489313836, 7316.341529262392, 7463.738769143054, 7484.476086673881, 6963.1644202696, 6970.337817907493, 7020.7893763187185, 7034.0, 7049.048378468841, 4768.833090841943, 4866.367372241586, 4893.6085113191575, 4957.429895562056, 4966.726830024239, 4879.5120487952345, 4914.557689807934, 4982.128127577732, 4989.850304490871, 5019.472955339636], "value": 4540.6114431792275, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.63079043566853, 309.6485488970019, 316.65959093973976, 317.85219872759177, 319.85926192475324, 4402.7578483183415, 5521.530669893059, 5589.4070189995855, 5685.152325494216, 5703.0, 5567.638103523268, 5606.131049687305, 5606.21512988182, 5648.0, 5674.773009079642, 3508.2632647144133, 3550.8579656813827, 4297.333913243452, 4301.720388174766, 4306.720063195898, 3187.792793468429, 3857.4599556062176, 4248.830046798124, 4309.978450107745, 4336.457942757155, 3579.9284014319696, 4140.792960351985, 4240.809163587646, 4253.255680255958, 4253.7671369759055, 4033.5159780826343, 4222.408862759201, 4259.3610958356185, 4280.4221430106845, 4287.399764033038, 3042.7870049096573, 3160.925285402974, 3589.730770192246, 3664.78011319319, 3715.3683873741256, 4717.905641887178, 5350.973245133789, 5412.648177868466, 5426.0, 5530.695811730353, 4057.97971010146, 4129.318662420697, 4938.1111399947995, 4957.406649156613, 4989.176785830334, 5815.0, 6006.786369292451, 6014.458698717092, 6029.336772954972, 6051.69741512927, 4047.1298670785627, 4051.736637118578, 4055.3511438169753, 4056.4320995060716, 4077.7145599808027, 5228.581713462914, 6332.873342533124, 6360.809175724782, 6365.809025729235, 6388.488920886364, 3226.419244535959, 3463.8268086595567, 3650.4889315495852, 3945.678197803768, 3986.720929534905, 4003.3594624859884, 4099.3031184698675, 4148.398482220061, 4182.602652747984, 4182.832686692559, 4392.187445322562, 4453.599176074104, 4523.525029871882, 4907.987725032719, 4983.25251212321, 5036.294918711384, 5476.0, 6055.333913269536, 6070.605410648382, 6083.387902205919, 1267.3599832084922, 1420.6022313752167, 1429.7354989327007, 1446.833614134388, 1448.4640682947377, 792.5918152151646, 850.8723691446208, 870.9548541749925, 886.8448021596195, 887.6538150121457, 4334.371516130206, 4457.710248833815, 4759.16714574948, 4765.332853400527, 4799.448063472675, 4316.892077698083, 4483.5204382553675, 4605.032943081923, 5110.751269441434, 5196.142636465017, 3959.742616729903, 4098.815553300049, 4636.884077898012, 4764.737939413296, 4819.084373969004, 2375.2423206826825, 2435.7564243575503, 2494.2641920633096, 2617.371830760642, 2691.3540750219804, 4712.59942904857, 4979.975100124549, 5366.880082367443, 5373.892522149613, 5379.740508986157, 4612.76936153191, 4672.649551283667, 5206.583473322125, 5302.867428314323, 5310.283111779938, 4698.929516057194, 4986.675866068694, 5031.767217031851, 5254.658447200996, 5344.625876188629, 4656.487786343568, 4846.854594362175, 5128.974355128201, 5160.638755287244, 5296.0, 4180.665546756191, 4656.324832899279, 5121.0, 5176.9128483018985, 5507.421720719267, 5252.579793616425, 5742.310922689304, 5838.649681019152, 6113.57204995646, 6390.616563006234, 3074.7700919632325, 3717.665410113103, 4040.6565441937764, 4045.9190816182922, 4062.857799976987, 5694.800681976111, 6017.428344307284, 6043.456088952015, 6088.756449742138, 6209.720562574786, 4970.527799859007, 5650.463205995424, 5978.581499295007, 6021.2473440819895, 6050.788222412194, 4603.6777425579885, 4896.779644916059, 5265.920486300376, 5539.338198540502, 5548.973439913629, 5583.860403489945, 5616.02350754101, 5803.245578074992, 5839.854003649943, 5909.940900591111, 6006.069059295816, 6011.789587364421, 6033.42682445167, 6046.848828779316, 6107.511399087974, 5686.34607020198, 5800.970995144998, 5834.241548598825, 5904.645721256738, 5940.970295148497], "value": 4085.944229693709, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [634.9904751428728, 640.727690731439, 666.257123307512, 756.00585230422, 805.7421625079974, 15147.621309467255, 15925.203739812992, 16024.75962860558, 16071.276792544335, 16080.919595402022, 15220.923895380536, 16074.678506429864, 16155.94986325891, 16185.35258589655, 16263.756043659325, 821.9465734727254, 845.9873101903477, 861.5089399042548, 862.5299211929482, 869.443556124081, 687.2303020616916, 860.7718954477068, 867.7006432780712, 868.0754995929346, 870.0386073388909, 905.1853332001218, 922.6632279218104, 924.7688077980505, 927.777333439973, 929.1776777551847, 902.5306840442998, 913.8263729891332, 915.0209276074603, 919.0166521821653, 919.4988731141544, 679.0459404536634, 679.2290749998738, 686.4645576450376, 687.041577000087, 690.0, 15438.0, 15650.060996340257, 15862.762058569131, 15895.44365947198, 16045.117518536475, 14232.715345693132, 14262.072965257328, 14311.0, 14325.856741432557, 14487.0, 11813.350265735382, 11956.70108247292, 11976.880231197663, 11984.0, 11986.40193916709, 3746.269477451898, 3766.3408903441787, 3788.5074940257864, 3810.6760925321382, 3812.1041555234497, 107013.46493267562, 108923.0, 109146.0, 110316.79366412626, 112075.43962280218, 10849.132069434427, 10935.56257749697, 10946.343219406703, 10961.83557246642, 11105.389203593719, 18103.366382176562, 21799.891000544896, 22267.55464890693, 22311.888440557694, 22331.218407355664, 7811.179826118289, 7972.3622110231045, 8007.639656215484, 8035.55804430762, 8157.632906519221, 3799.8290076946605, 4245.172191422705, 4367.301231802896, 4413.337999300131, 4448.065906159678, 3603.279344131184, 3660.194757153475, 3718.6839118674675, 3728.0307120148955, 3736.065983504124, 654.8409315511518, 667.7562689618352, 673.5689158938284, 684.8014075918028, 694.0214297840022, 521.3431076843204, 526.6050462153385, 577.7573419163962, 652.9738810447624, 665.9633720145439, 8077.798055048671, 8535.231829135408, 9099.226565741987, 9250.953745231365, 9455.763605909908, 493.20593843911274, 537.6962016460766, 551.481607289147, 640.193356370976, 672.9360710732473, 4286.914261714748, 4388.846390376321, 4435.955640443619, 4476.865694029184, 4599.816007359735, 76142.61928690321, 140705.29647351903, 141377.29311353582, 143072.0, 143954.0, 103911.0, 107480.0, 108579.82840343307, 109307.0, 111191.0, 82900.0, 95776.0422395781, 101987.0, 103101.0, 110351.0, 8332.916670833456, 8862.42394244372, 8914.55427228649, 9350.719478415658, 9493.57278922464, 56248.0, 59181.0, 59540.0, 60132.39867601268, 64005.0, 80670.0, 93683.59474608023, 94545.0, 94550.52724736332, 98413.52379714166, 13304.13523120994, 13454.125481843648, 13474.797878032034, 13542.661433464242, 13597.456101755819, 14522.273886305853, 14567.92716036413, 14577.489787857374, 14579.854201457849, 14689.92655036718, 13532.797008045085, 13554.390052447861, 13678.863211368158, 13737.908970481561, 13787.586372408843, 11116.49975751077, 13543.187408755506, 13631.86368136306, 13883.0, 14196.787048194492, 13055.804162937373, 13399.0756851347, 13498.59504214875, 13755.724885502033, 13851.099678520864, 11221.88778112241, 11395.487203076047, 11415.0, 11484.885151148716, 11589.710257243636, 7712.691492340244, 9457.574409151466, 10579.985347109081, 11739.119566032752, 11744.58893938708], "value": 7705.988566128616, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1636.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 111.0, 139.0, 172.0, 117.0, 100.0, 117.0, 150.0, 98.0, 172.0], "value": 134.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 117.0, 131.0, 158.0, 113.0, 133.0, 133.0, 112.0, 161.0, 112.0], "value": 131.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 208.0, 213.0, 210.0, 231.0, 293.0, 196.0, 196.0, 199.0, 194.0], "value": 214.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 98.0, 103.0, 178.0, 87.0, 60.0, 57.0, 60.0, 141.0, 52.0], "value": 90.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 73.0, 124.0, 74.0, 69.0, 84.0, 110.0, 70.0, 72.0, 111.0], "value": 85.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 78.0, 137.0, 125.0, 89.0, 73.0, 133.0, 132.0, 90.0, 106.0], "value": 105.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 109.0, 175.0, 156.0, 98.0, 99.0, 103.0, 98.0, 99.0, 99.0], "value": 118.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 92.0, 91.0, 92.0, 96.0, 91.0, 91.0, 105.0, 93.0, 112.0], "value": 96.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 91.0, 57.0, 57.0, 58.0, 57.0, 56.0, 57.0, 57.0, 57.0], "value": 60.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 74.0, 70.0, 89.0, 67.0, 76.0, 87.0, 93.0, 103.0], "value": 79.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 86.0, 89.0, 88.0, 84.0, 90.0, 89.0, 92.0, 90.0, 88.0], "value": 88.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 141.0, 139.0, 139.0, 141.0, 141.0, 142.0, 144.0, 186.0, 139.0], "value": 145.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 182.0, 203.0, 184.0, 197.0, 264.0, 199.0, 205.0, 259.0, 193.0], "value": 206.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 85.0, 63.0, 89.0, 94.0, 88.0, 91.0, 79.0, 69.0, 69.0], "value": 79.3, "unit": "score"}]}]} 12:31:48 INFO - Return code: 0 12:31:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:31:48 ERROR - # TBPL SUCCESS # 12:31:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:31:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:31:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:31:48 INFO - 2016-09-04 12:31:48.386 screenresolution[1669:10954] starting screenresolution argv=screenresolution get 12:31:48 INFO - 2016-09-04 12:31:48.390 screenresolution[1669:10954] Display 0: 1600x1200x32@60 12:31:48 INFO - 2016-09-04 12:31:48.396 screenresolution[1670:10957] starting screenresolution argv=screenresolution list 12:31:48 INFO - Available Modes on Display 0 12:31:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:31:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:31:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:31:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:31:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:31:48 INFO - Intel Iris: 12:31:48 INFO - Chipset Model: Intel Iris 12:31:48 INFO - Type: GPU 12:31:48 INFO - Bus: Built-In 12:31:48 INFO - VRAM (Dynamic, Max): 1536 MB 12:31:48 INFO - Vendor: Intel (0x8086) 12:31:48 INFO - Device ID: 0x0a2e 12:31:48 INFO - Revision ID: 0x0009 12:31:48 INFO - Displays: 12:31:48 INFO - Display: 12:31:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:31:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:31:48 INFO - Display Serial Number: 1600x1200 60 12:31:48 INFO - Main Display: Yes 12:31:48 INFO - Mirror: Off 12:31:48 INFO - Online: Yes 12:31:48 INFO - Rotation: Supported 12:31:48 INFO - Return code: 0 12:31:48 INFO - Running post-action listener: _resource_record_post_action 12:31:48 INFO - [mozharness: 2016-09-04 19:31:48.636595Z] Finished run-tests step (success) 12:31:48 INFO - Running post-run listener: _resource_record_post_run 12:31:48 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 32119808; Write bytes: 707475456; Read time: 194; Write time: 2072 12:31:48 INFO - TinderboxPrint: CPU usage
54.5% 12:31:48 INFO - TinderboxPrint: I/O read bytes / time
32,119,808 / 194 12:31:48 INFO - TinderboxPrint: I/O write bytes / time
707,475,456 / 2,072 12:31:48 INFO - TinderboxPrint: CPU idle
2,199.7 (45.5%) 12:31:48 INFO - TinderboxPrint: CPU system
978.9 (20.2%) 12:31:48 INFO - TinderboxPrint: CPU user
1,659.0 (34.3%) 12:31:48 INFO - TinderboxPrint: Swap in / out
433,389,568 / 0 12:31:48 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 389898752; Write bytes: 385017856; Read time: 29678; Write time: 850 12:31:48 INFO - run-tests - Wall time: 1175s; CPU: 55.0%; Read bytes: 31198208; Write bytes: 298299392; Read time: 184; Write time: 1162 12:31:49 INFO - Running post-run listener: _upload_blobber_files 12:31:49 INFO - Blob upload gear active. 12:31:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:31:49 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:31:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:31:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:31:49 INFO - (blobuploader) - INFO - Open directory for files ... 12:31:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:31:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:31:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:31:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:31:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:31:50 INFO - (blobuploader) - INFO - Done attempting. 12:31:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:31:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:31:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:31:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:31:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:31:51 INFO - (blobuploader) - INFO - Done attempting. 12:31:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:31:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:31:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:31:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:31:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:31:53 INFO - (blobuploader) - INFO - Done attempting. 12:31:53 INFO - (blobuploader) - INFO - Iteration through files over. 12:31:53 INFO - Return code: 0 12:31:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:31:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:31:53 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b00676788768aba12a166a0f224450a14f72da8e14b2b8f4a961b2598d9b15ce0944b6dc3d3c13c39c160c68e96ffabfc2a8c62a5f39c052e118fd0dffbdb396", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46964ba27349d668bf070f2a615000f882ed695d0e231e281649250cf13f4fe0e420db3d70fc84407e120e8b7e618c3bc73cc137b57ec5aef7e89b32811785e3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dae6675933af90b56f72db16f51551475b39a462b43217f9a99e0912a86fed88d0655cf8b4376e90811e7549f7487d572ee39b221aff7f4978cf758710932c92"} 12:31:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:31:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:31:53 INFO - Contents: 12:31:53 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b00676788768aba12a166a0f224450a14f72da8e14b2b8f4a961b2598d9b15ce0944b6dc3d3c13c39c160c68e96ffabfc2a8c62a5f39c052e118fd0dffbdb396", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46964ba27349d668bf070f2a615000f882ed695d0e231e281649250cf13f4fe0e420db3d70fc84407e120e8b7e618c3bc73cc137b57ec5aef7e89b32811785e3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dae6675933af90b56f72db16f51551475b39a462b43217f9a99e0912a86fed88d0655cf8b4376e90811e7549f7487d572ee39b221aff7f4978cf758710932c92"} 12:31:53 INFO - Running post-run listener: copy_logs_to_upload_dir 12:31:53 INFO - Copying logs to upload dir... 12:31:53 INFO - mkdir: /builds/slave/test/build/upload/logs 12:31:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.080914 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-04 12:31:53.992790) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-04 12:31:53.996472) ========= 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.1smeMuAJBk/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.BQczjBcycD/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b00676788768aba12a166a0f224450a14f72da8e14b2b8f4a961b2598d9b15ce0944b6dc3d3c13c39c160c68e96ffabfc2a8c62a5f39c052e118fd0dffbdb396", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46964ba27349d668bf070f2a615000f882ed695d0e231e281649250cf13f4fe0e420db3d70fc84407e120e8b7e618c3bc73cc137b57ec5aef7e89b32811785e3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dae6675933af90b56f72db16f51551475b39a462b43217f9a99e0912a86fed88d0655cf8b4376e90811e7549f7487d572ee39b221aff7f4978cf758710932c92"} build_url:https://queue.taskcluster.net/v1/task/QUpyhRJlT9SpFzddD_72WA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010985 build_url: 'https://queue.taskcluster.net/v1/task/QUpyhRJlT9SpFzddD_72WA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b00676788768aba12a166a0f224450a14f72da8e14b2b8f4a961b2598d9b15ce0944b6dc3d3c13c39c160c68e96ffabfc2a8c62a5f39c052e118fd0dffbdb396", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46964ba27349d668bf070f2a615000f882ed695d0e231e281649250cf13f4fe0e420db3d70fc84407e120e8b7e618c3bc73cc137b57ec5aef7e89b32811785e3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dae6675933af90b56f72db16f51551475b39a462b43217f9a99e0912a86fed88d0655cf8b4376e90811e7549f7487d572ee39b221aff7f4978cf758710932c92"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-04 12:31:54.029403) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 12:31:54.029736) ========= 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.1smeMuAJBk/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.BQczjBcycD/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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.005299 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 12:31:54.078100) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-04 12:31:54.078448) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-04 12:31:54.078776) ========= ========= Total master_lag: 0.17 =========