builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0367 starttime: 1473016237.29 results: success (0) buildid: 20160904103549 builduid: 7a7f5c814d29484fb23860dc35e0cfe6 revision: ce2a0cbdb4e80f88c9a12f63dcb49176525d3dd2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-04 12:10:37.288668) ========= 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:37.289519) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-04 12:10:37.289812) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-04 12:10:37.694209) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 12:10:37.694480) ========= 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.T8swjSpndo/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.tduF1Ey8Xo/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 49.5M=0s 2016-09-04 12:10:37 (49.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.188652 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 12:10:37.933822) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 12:10:37.934207) ========= 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.T8swjSpndo/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.tduF1Ey8Xo/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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.025768 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 12:10:37.999858) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-04 12:10:38.000263) ========= 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.T8swjSpndo/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.tduF1Ey8Xo/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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,097 truncating revision to first 12 chars 2016-09-04 12:10:38,097 Setting DEBUG logging. 2016-09-04 12:10:38,097 attempt 1/10 2016-09-04 12:10:38,097 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,192 unpacking tar archive at: mozilla-central-ce2a0cbdb4e8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.380994 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-04 12:10:39.411068) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-04 12:10:39.411444) ========= script_repo_revision: ce2a0cbdb4e80f88c9a12f63dcb49176525d3dd2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-04 12:10:39.411893) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-04 12:10:39.412219) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-04 12:10:39.435445) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-09-04 12:10:39.435850) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.T8swjSpndo/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.tduF1Ey8Xo/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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:39 INFO - MultiFileLogger online at 20160904 12:10:39 in /builds/slave/test 12:10:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 12:10:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:10:39 INFO - {'append_to_log': False, 12:10:39 INFO - 'base_work_dir': '/builds/slave/test', 12:10:39 INFO - 'blob_upload_branch': 'Firefox', 12:10:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:10:39 INFO - 'branch': 'Firefox', 12:10:39 INFO - 'buildbot_json_path': 'buildprops.json', 12:10:39 INFO - 'config_files': ('talos/mac_config.py',), 12:10:39 INFO - 'default_actions': ('clobber', 12:10:39 INFO - 'read-buildbot-config', 12:10:39 INFO - 'download-and-extract', 12:10:39 INFO - 'populate-webroot', 12:10:39 INFO - 'create-virtualenv', 12:10:39 INFO - 'install', 12:10:39 INFO - 'run-tests'), 12:10:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:10:39 INFO - 'download_minidump_stackwalk': True, 12:10:39 INFO - 'download_symbols': 'ondemand', 12:10:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:10:39 INFO - 'tooltool.py': '/tools/tooltool.py', 12:10:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:10:39 INFO - '/tools/misc-python/virtualenv.py')}, 12:10:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:10:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:10:39 INFO - 'installer_path': 'installer.exe', 12:10:39 INFO - 'log_level': 'info', 12:10:39 INFO - 'log_name': 'talos', 12:10:39 INFO - 'log_to_console': True, 12:10:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:10:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:10:39 INFO - 'opt_config_files': (), 12:10:39 INFO - 'pip_index': False, 12:10:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:10:39 INFO - 'cmd': ('bash', 12:10:39 INFO - '-c', 12:10:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:10:39 INFO - 'enabled': True, 12:10:39 INFO - 'halt_on_failure': False, 12:10:39 INFO - 'name': 'check_screen_resolution'},), 12:10:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:10:39 INFO - 'cmd': ('bash', 12:10:39 INFO - '-c', 12:10:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:10:39 INFO - 'enabled': True, 12:10:39 INFO - 'halt_on_failure': False, 12:10:39 INFO - 'name': 'check_screen_resolution'},), 12:10:39 INFO - 'run_cmd_checks_enabled': True, 12:10:39 INFO - 'sps_profile': False, 12:10:39 INFO - 'sps_profile_interval': 0, 12:10:39 INFO - 'suite': 'dromaeojs', 12:10:39 INFO - 'system_bits': '32', 12:10:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:10:39 INFO - 'title': 't-yosemite-r7-0367', 12:10:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:10:39 INFO - 'use_talos_json': True, 12:10:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:10:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:10:39 INFO - 'work_dir': 'build'} 12:10:39 INFO - [mozharness: 2016-09-04 19:10:39.653994Z] Running clobber step. 12:10:39 INFO - Running pre-action listener: _resource_record_pre_action 12:10:39 INFO - Running main action method: clobber 12:10:39 INFO - rmtree: /builds/slave/test/build 12:10:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:10:41 INFO - Running post-action listener: _resource_record_post_action 12:10:41 INFO - [mozharness: 2016-09-04 19:10:41.728606Z] Finished clobber step (success) 12:10:41 INFO - [mozharness: 2016-09-04 19:10:41.728728Z] Running read-buildbot-config step. 12:10:41 INFO - Running pre-action listener: _resource_record_pre_action 12:10:41 INFO - Running main action method: read_buildbot_config 12:10:41 INFO - Using buildbot properties: 12:10:41 INFO - { 12:10:41 INFO - "project": "", 12:10:41 INFO - "product": "firefox", 12:10:41 INFO - "script_repo_revision": "production", 12:10:41 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 12:10:41 INFO - "repository": "", 12:10:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 12:10:41 INFO - "buildid": "20160904103549", 12:10:41 INFO - "pgo_build": "False", 12:10:41 INFO - "basedir": "/builds/slave/test", 12:10:41 INFO - "buildnumber": 97, 12:10:41 INFO - "slavename": "t-yosemite-r7-0367", 12:10:41 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 12:10:41 INFO - "platform": "yosemite_r7", 12:10:41 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 12:10:41 INFO - "branch": "mozilla-central", 12:10:41 INFO - "repo_path": "mozilla-central", 12:10:41 INFO - "revision": "ce2a0cbdb4e80f88c9a12f63dcb49176525d3dd2", 12:10:41 INFO - "stage_platform": "macosx64", 12:10:41 INFO - "builduid": "7a7f5c814d29484fb23860dc35e0cfe6", 12:10:41 INFO - "slavebuilddir": "test" 12:10:41 INFO - } 12:10:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/QUpyhRJlT9SpFzddD_72WA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 12:10:41 INFO - Running post-action listener: _resource_record_post_action 12:10:41 INFO - [mozharness: 2016-09-04 19:10:41.732047Z] Finished read-buildbot-config step (success) 12:10:41 INFO - [mozharness: 2016-09-04 19:10:41.732157Z] Running download-and-extract step. 12:10:41 INFO - Running pre-action listener: _resource_record_pre_action 12:10:41 INFO - Running main action method: download_and_extract 12:10:41 INFO - mkdir: /builds/slave/test/build/tests 12:10:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:41 INFO - trying https://queue.taskcluster.net/v1/task/QUpyhRJlT9SpFzddD_72WA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 12:10:41 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:41 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:43 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:43 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:59 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:59 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:04 INFO - Downloaded 111490047 bytes. 12:11:04 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:04 INFO - mkdir: /builds/slave/test/properties 12:11:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:11:04 INFO - Writing to file /builds/slave/test/properties/build_url 12:11:04 INFO - Contents: 12:11:04 INFO - build_url:https://queue.taskcluster.net/v1/task/QUpyhRJlT9SpFzddD_72WA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:11:05 INFO - Running post-action listener: _resource_record_post_action 12:11:05 INFO - Running post-action listener: set_extra_try_arguments 12:11:05 INFO - [mozharness: 2016-09-04 19:11:05.259446Z] Finished download-and-extract step (success) 12:11:05 INFO - [mozharness: 2016-09-04 19:11:05.259568Z] Running populate-webroot step. 12:11:05 INFO - Running pre-action listener: _resource_record_pre_action 12:11:05 INFO - Running main action method: populate_webroot 12:11:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:11:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:11:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:11:05 INFO - '--tppagecycles', 12:11:05 INFO - '7'], 12:11:05 INFO - 'tests': ['tsvgm']}}, 12:11:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:11:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:11:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:11:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:11:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:11:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:11:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:05 INFO - 'talos_options': ['--disable-e10s'], 12:11:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:11:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:11:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:05 INFO - 'talos_options': ['--disable-e10s'], 12:11:05 INFO - 'tests': ['damp', 'tps']}, 12:11:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:05 INFO - 'tests': ['damp', 'tps']}, 12:11:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:11:05 INFO - 'tests': ['dromaeo_dom']}, 12:11:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:11:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:11:05 INFO - 'tests': ['basic_compositor_video']}, 12:11:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:11:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:11:05 INFO - 'tests': ['a11yr', 12:11:05 INFO - 'ts_paint', 12:11:05 INFO - 'tpaint', 12:11:05 INFO - 'sessionrestore', 12:11:05 INFO - 'sessionrestore_no_auto_restore', 12:11:05 INFO - 'tabpaint']}, 12:11:05 INFO - 'other-e10s': {'tests': ['a11yr', 12:11:05 INFO - 'ts_paint', 12:11:05 INFO - 'tpaint', 12:11:05 INFO - 'sessionrestore', 12:11:05 INFO - 'sessionrestore_no_auto_restore', 12:11:05 INFO - 'tabpaint']}, 12:11:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:11:05 INFO - 'ts_paint', 12:11:05 INFO - 'tpaint', 12:11:05 INFO - 'sessionrestore', 12:11:05 INFO - 'sessionrestore_no_auto_restore', 12:11:05 INFO - 'tabpaint']}, 12:11:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:11:05 INFO - 'ts_paint', 12:11:05 INFO - 'tpaint', 12:11:05 INFO - 'sessionrestore', 12:11:05 INFO - 'sessionrestore_no_auto_restore', 12:11:05 INFO - 'tabpaint']}, 12:11:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:11:05 INFO - 'tests': ['a11yr', 12:11:05 INFO - 'ts_paint', 12:11:05 INFO - 'tpaint', 12:11:05 INFO - 'sessionrestore', 12:11:05 INFO - 'sessionrestore_no_auto_restore', 12:11:05 INFO - 'tabpaint']}, 12:11:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:11:05 INFO - 'tests': ['a11yr', 12:11:05 INFO - 'ts_paint', 12:11:05 INFO - 'tpaint', 12:11:05 INFO - 'sessionrestore', 12:11:05 INFO - 'sessionrestore_no_auto_restore', 12:11:05 INFO - 'tabpaint']}, 12:11:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:11:05 INFO - 'tests': ['tsvgx', 12:11:05 INFO - 'tsvgr_opacity', 12:11:05 INFO - 'tart', 12:11:05 INFO - 'tscrollx', 12:11:05 INFO - 'cart']}, 12:11:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:11:05 INFO - 'tsvgr_opacity', 12:11:05 INFO - 'tart', 12:11:05 INFO - 'tscrollx', 12:11:05 INFO - 'cart']}, 12:11:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:05 INFO - 'talos_options': ['--disable-e10s'], 12:11:05 INFO - 'tests': ['tp5o']}, 12:11:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:05 INFO - 'tests': ['tp5o']}, 12:11:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:05 INFO - 'talos_options': ['--disable-e10s', 12:11:05 INFO - '--xperf_path', 12:11:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:11:05 INFO - 'tests': ['tp5n']}, 12:11:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:05 INFO - 'talos_options': ['--xperf_path', 12:11:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:11:05 INFO - 'tests': ['tp5n']}}, 12:11:05 INFO - 'talos.zip': {'path': '', 12:11:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:11:05 INFO - Running post-action listener: _resource_record_post_action 12:11:05 INFO - [mozharness: 2016-09-04 19:11:05.278109Z] Finished populate-webroot step (success) 12:11:05 INFO - [mozharness: 2016-09-04 19:11:05.278194Z] Running create-virtualenv step. 12:11:05 INFO - Running pre-action listener: _resource_record_pre_action 12:11:05 INFO - Running main action method: create_virtualenv 12:11:05 INFO - Creating virtualenv /builds/slave/test/build/venv 12:11:05 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:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:11:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:11:05 INFO - Using real prefix '/tools/python27' 12:11:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:11:06 INFO - Installing distribute.............................................................................................................................................................................................done. 12:11:08 INFO - Installing pip.................done. 12:11:08 INFO - Return code: 0 12:11:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:11:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:09 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:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:09 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 0x102dc4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e59618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91fbd990c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d28d30>, '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 0x102a8ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d20b20>, '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.T8swjSpndo/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.tduF1Ey8Xo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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:09 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:09 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:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T8swjSpndo/Render', 12:11:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:09 INFO - 'HOME': '/Users/cltbld', 12:11:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:09 INFO - 'LOGNAME': 'cltbld', 12:11:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:09 INFO - 'MOZ_NO_REMOTE': '1', 12:11:09 INFO - 'NO_EM_RESTART': '1', 12:11:09 INFO - 'PAGER': '/bin/cat', 12:11:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:09 INFO - 'PWD': '/builds/slave/test', 12:11:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:09 INFO - 'SHELL': '/bin/bash', 12:11:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tduF1Ey8Xo/Listeners', 12:11:09 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 12:11:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:09 INFO - 'USER': 'cltbld', 12:11:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:09 INFO - 'XPC_FLAGS': '0x0', 12:11:09 INFO - 'XPC_SERVICE_NAME': '0', 12:11:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:09 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:09 INFO - Cleaning up... 12:11:09 INFO - Return code: 0 12:11:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:11:09 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:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:09 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:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:09 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 0x102dc4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e59618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91fbd990c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d28d30>, '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 0x102a8ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d20b20>, '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.T8swjSpndo/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.tduF1Ey8Xo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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:09 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:09 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:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T8swjSpndo/Render', 12:11:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:09 INFO - 'HOME': '/Users/cltbld', 12:11:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:09 INFO - 'LOGNAME': 'cltbld', 12:11:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:09 INFO - 'MOZ_NO_REMOTE': '1', 12:11:09 INFO - 'NO_EM_RESTART': '1', 12:11:09 INFO - 'PAGER': '/bin/cat', 12:11:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:09 INFO - 'PWD': '/builds/slave/test', 12:11:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:09 INFO - 'SHELL': '/bin/bash', 12:11:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tduF1Ey8Xo/Listeners', 12:11:09 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 12:11:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:09 INFO - 'USER': 'cltbld', 12:11:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:09 INFO - 'XPC_FLAGS': '0x0', 12:11:09 INFO - 'XPC_SERVICE_NAME': '0', 12:11:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:09 INFO - Downloading/unpacking psutil>=3.1.1 12:11:09 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:09 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:09 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:09 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:09 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:09 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:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:11:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:11:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:11:13 INFO - Installing collected packages: psutil 12:11:13 INFO - Running setup.py install for psutil 12:11:13 INFO - building 'psutil._psutil_osx' extension 12:11:13 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:14 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:14 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:14 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:14 INFO - building 'psutil._psutil_posix' extension 12:11:14 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:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:11:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:11:14 INFO - ^ 12:11:14 INFO - 1 warning generated. 12:11:14 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: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 - Successfully installed psutil 12:11:14 INFO - Cleaning up... 12:11:14 INFO - Return code: 0 12:11:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:11:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102dc4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e59618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91fbd990c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d28d30>, '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 0x102a8ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d20b20>, '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.T8swjSpndo/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.tduF1Ey8Xo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:11:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:11:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T8swjSpndo/Render', 12:11:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:14 INFO - 'HOME': '/Users/cltbld', 12:11:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:14 INFO - 'LOGNAME': 'cltbld', 12:11:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:14 INFO - 'MOZ_NO_REMOTE': '1', 12:11:14 INFO - 'NO_EM_RESTART': '1', 12:11:14 INFO - 'PAGER': '/bin/cat', 12:11:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:14 INFO - 'PWD': '/builds/slave/test', 12:11:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:14 INFO - 'SHELL': '/bin/bash', 12:11:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tduF1Ey8Xo/Listeners', 12:11:14 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 12:11:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:14 INFO - 'USER': 'cltbld', 12:11:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:14 INFO - 'XPC_FLAGS': '0x0', 12:11:14 INFO - 'XPC_SERVICE_NAME': '0', 12:11:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:11:15 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:15 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:15 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:15 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:15 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:15 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:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:11:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:11:17 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:17 INFO - Installing collected packages: mozsystemmonitor 12:11:17 INFO - Running setup.py install for mozsystemmonitor 12:11:17 INFO - Successfully installed mozsystemmonitor 12:11:17 INFO - Cleaning up... 12:11:17 INFO - Return code: 0 12:11:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:11:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102dc4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e59618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91fbd990c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d28d30>, '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 0x102a8ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d20b20>, '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.T8swjSpndo/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.tduF1Ey8Xo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:11:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:11:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T8swjSpndo/Render', 12:11:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:17 INFO - 'HOME': '/Users/cltbld', 12:11:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:17 INFO - 'LOGNAME': 'cltbld', 12:11:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:17 INFO - 'MOZ_NO_REMOTE': '1', 12:11:17 INFO - 'NO_EM_RESTART': '1', 12:11:17 INFO - 'PAGER': '/bin/cat', 12:11:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:17 INFO - 'PWD': '/builds/slave/test', 12:11:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:17 INFO - 'SHELL': '/bin/bash', 12:11:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tduF1Ey8Xo/Listeners', 12:11:17 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 12:11:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:17 INFO - 'USER': 'cltbld', 12:11:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:17 INFO - 'XPC_FLAGS': '0x0', 12:11:17 INFO - 'XPC_SERVICE_NAME': '0', 12:11:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:18 INFO - Downloading/unpacking jsonschema==2.5.1 12:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:11:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:11:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:21 INFO - Downloading functools32-3.2.3-2.tar.gz 12:11:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:11:21 INFO - warning: no files found matching '*.txt' 12:11:21 INFO - no previously-included directories found matching 'build' 12:11:21 INFO - no previously-included directories found matching 'dist' 12:11:21 INFO - no previously-included directories found matching '.git*' 12:11:21 INFO - Installing collected packages: jsonschema, functools32 12:11:21 INFO - Running setup.py install for functools32 12:11:21 INFO - warning: no files found matching '*.txt' 12:11:21 INFO - no previously-included directories found matching 'build' 12:11:21 INFO - no previously-included directories found matching 'dist' 12:11:21 INFO - no previously-included directories found matching '.git*' 12:11:21 INFO - Successfully installed jsonschema functools32 12:11:21 INFO - Cleaning up... 12:11:21 INFO - Return code: 0 12:11:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:11:21 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:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:21 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:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:21 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 0x102dc4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e59618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91fbd990c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d28d30>, '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 0x102a8ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d20b20>, '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.T8swjSpndo/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.tduF1Ey8Xo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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:21 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:21 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:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T8swjSpndo/Render', 12:11:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:21 INFO - 'HOME': '/Users/cltbld', 12:11:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:21 INFO - 'LOGNAME': 'cltbld', 12:11:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:21 INFO - 'MOZ_NO_REMOTE': '1', 12:11:21 INFO - 'NO_EM_RESTART': '1', 12:11:21 INFO - 'PAGER': '/bin/cat', 12:11:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:21 INFO - 'PWD': '/builds/slave/test', 12:11:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:21 INFO - 'SHELL': '/bin/bash', 12:11:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tduF1Ey8Xo/Listeners', 12:11:21 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 12:11:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:21 INFO - 'USER': 'cltbld', 12:11:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:21 INFO - 'XPC_FLAGS': '0x0', 12:11:21 INFO - 'XPC_SERVICE_NAME': '0', 12:11:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:11:21 INFO - Cleaning up... 12:11:21 INFO - Return code: 0 12:11:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:11:21 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:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:21 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:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:21 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 0x102dc4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e59618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91fbd990c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d28d30>, '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 0x102a8ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d20b20>, '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.T8swjSpndo/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.tduF1Ey8Xo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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:21 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:21 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:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T8swjSpndo/Render', 12:11:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:21 INFO - 'HOME': '/Users/cltbld', 12:11:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:21 INFO - 'LOGNAME': 'cltbld', 12:11:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:21 INFO - 'MOZ_NO_REMOTE': '1', 12:11:21 INFO - 'NO_EM_RESTART': '1', 12:11:21 INFO - 'PAGER': '/bin/cat', 12:11:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:21 INFO - 'PWD': '/builds/slave/test', 12:11:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:21 INFO - 'SHELL': '/bin/bash', 12:11:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tduF1Ey8Xo/Listeners', 12:11:21 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 12:11:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:21 INFO - 'USER': 'cltbld', 12:11:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:21 INFO - 'XPC_FLAGS': '0x0', 12:11:21 INFO - 'XPC_SERVICE_NAME': '0', 12:11:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:21 INFO - Downloading/unpacking blobuploader==1.2.4 12:11:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:24 INFO - Downloading blobuploader-1.2.4.tar.gz 12:11:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:11:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:11:24 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:24 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:24 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:24 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:24 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:24 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:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:11:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:11:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:25 INFO - Downloading docopt-0.6.1.tar.gz 12:11:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:11:25 INFO - Installing collected packages: blobuploader, requests, docopt 12:11:25 INFO - Running setup.py install for blobuploader 12:11:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:11:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:11:25 INFO - Running setup.py install for requests 12:11:26 INFO - Running setup.py install for docopt 12:11:26 INFO - Successfully installed blobuploader requests docopt 12:11:26 INFO - Cleaning up... 12:11:26 INFO - Return code: 0 12:11:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:11:26 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:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:26 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:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:26 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 0x102dc4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e59618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91fbd990c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d28d30>, '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 0x102a8ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d20b20>, '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.T8swjSpndo/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.tduF1Ey8Xo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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:26 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:26 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:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T8swjSpndo/Render', 12:11:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:26 INFO - 'HOME': '/Users/cltbld', 12:11:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:26 INFO - 'LOGNAME': 'cltbld', 12:11:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:26 INFO - 'MOZ_NO_REMOTE': '1', 12:11:26 INFO - 'NO_EM_RESTART': '1', 12:11:26 INFO - 'PAGER': '/bin/cat', 12:11:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:26 INFO - 'PWD': '/builds/slave/test', 12:11:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:26 INFO - 'SHELL': '/bin/bash', 12:11:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tduF1Ey8Xo/Listeners', 12:11:26 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 12:11:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:26 INFO - 'USER': 'cltbld', 12:11:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:26 INFO - 'XPC_FLAGS': '0x0', 12:11:26 INFO - 'XPC_SERVICE_NAME': '0', 12:11:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:11:26 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-FiAO_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:11:26 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-qHW8W_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:11:27 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-Joa70i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:11:27 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-2wDEsR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:11:27 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-6oJCN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:11:27 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-ynrh2r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:11:27 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-JG7jCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:11:27 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-RkM4Qi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:11:27 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-GkaGDe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:11:27 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-a6ictc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:11:27 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-AlIydc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:11:27 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-mIhaoR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:11:28 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-jtwlV8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:11:28 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-pIMtHc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:28 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-kVcqVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:11:28 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-ZkYykN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:11:28 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-scjcI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:11:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:11:28 INFO - Running setup.py install for manifestparser 12:11:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:11:28 INFO - Running setup.py install for mozcrash 12:11:28 INFO - Running setup.py install for mozdebug 12:11:29 INFO - Running setup.py install for mozdevice 12:11:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:11:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:11:29 INFO - Running setup.py install for mozfile 12:11:29 INFO - Running setup.py install for mozhttpd 12:11:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:11:29 INFO - Running setup.py install for mozinfo 12:11:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:11:29 INFO - Running setup.py install for mozInstall 12:11:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:11:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:11:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:11:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:11:29 INFO - Running setup.py install for mozleak 12:11:29 INFO - Running setup.py install for mozlog 12:11:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:11:30 INFO - Running setup.py install for moznetwork 12:11:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:11:30 INFO - Running setup.py install for mozprocess 12:11:30 INFO - Running setup.py install for mozprofile 12:11:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:11:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:11:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:11:30 INFO - Running setup.py install for mozrunner 12:11:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:11:30 INFO - Running setup.py install for mozscreenshot 12:11:30 INFO - Running setup.py install for moztest 12:11:31 INFO - Running setup.py install for mozversion 12:11:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:11:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:11:31 INFO - Cleaning up... 12:11:31 INFO - Return code: 0 12:11:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:11:31 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:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:31 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:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:31 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 0x102dc4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e59618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91fbd990c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d28d30>, '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 0x102a8ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d20b20>, '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.T8swjSpndo/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.tduF1Ey8Xo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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:31 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:31 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:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T8swjSpndo/Render', 12:11:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:31 INFO - 'HOME': '/Users/cltbld', 12:11:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:31 INFO - 'LOGNAME': 'cltbld', 12:11:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:31 INFO - 'MOZ_NO_REMOTE': '1', 12:11:31 INFO - 'NO_EM_RESTART': '1', 12:11:31 INFO - 'PAGER': '/bin/cat', 12:11:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:31 INFO - 'PWD': '/builds/slave/test', 12:11:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:31 INFO - 'SHELL': '/bin/bash', 12:11:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tduF1Ey8Xo/Listeners', 12:11:31 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 12:11:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:31 INFO - 'USER': 'cltbld', 12:11:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:31 INFO - 'XPC_FLAGS': '0x0', 12:11:31 INFO - 'XPC_SERVICE_NAME': '0', 12:11:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:11:31 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-JiDC3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:11:31 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:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:11:31 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-Eo0AIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:11:31 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:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:11:31 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-886QWN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:11:31 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:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:11:31 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-1jKO7A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:11:31 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:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:11:31 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-TwEPQT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:11:31 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:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:11:31 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-YqqI4H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:11:32 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:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:11:32 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-pVkzEH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:11:32 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:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:11:32 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-Ep_Wrr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:11:32 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:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:11:32 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-CyEpo9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:11:32 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:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:11:32 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-KNi2h4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:11:32 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:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:11:32 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-GjANrE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:11:32 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:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:11:32 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-RyQKo1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:11:32 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:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:11:32 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-qQ_IHv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:11:32 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:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:11:32 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-OJkhZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:11:32 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:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:32 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-OxrJZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:33 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:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:11:33 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-IbbuyD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:11:33 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:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:11:33 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-p7a3KL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:11:33 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:33 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:33 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:33 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:33 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:33 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:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:11:33 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:33 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:33 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:33 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:33 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:33 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:35 INFO - Downloading blessings-1.6.tar.gz 12:11:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:11:35 INFO - Installing collected packages: blessings 12:11:35 INFO - Running setup.py install for blessings 12:11:36 INFO - Successfully installed blessings 12:11:36 INFO - Cleaning up... 12:11:36 INFO - Return code: 0 12:11:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:11:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:11:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:11:36 INFO - Reading from file tmpfile_stdout 12:11:36 INFO - Current package versions: 12:11:36 INFO - blessings == 1.6 12:11:36 INFO - blobuploader == 1.2.4 12:11:36 INFO - docopt == 0.6.1 12:11:36 INFO - functools32 == 3.2.3-2 12:11:36 INFO - jsonschema == 2.5.1 12:11:36 INFO - manifestparser == 1.1 12:11:36 INFO - mozInstall == 1.12 12:11:36 INFO - mozcrash == 0.17 12:11:36 INFO - mozdebug == 0.1 12:11:36 INFO - mozdevice == 0.48 12:11:36 INFO - mozfile == 1.2 12:11:36 INFO - mozhttpd == 0.7 12:11:36 INFO - mozinfo == 0.9 12:11:36 INFO - mozleak == 0.1 12:11:36 INFO - mozlog == 3.3 12:11:36 INFO - moznetwork == 0.27 12:11:36 INFO - mozprocess == 0.23 12:11:36 INFO - mozprofile == 0.28 12:11:36 INFO - mozrunner == 6.12 12:11:36 INFO - mozscreenshot == 0.1 12:11:36 INFO - mozsystemmonitor == 0.3 12:11:36 INFO - moztest == 0.7 12:11:36 INFO - mozversion == 1.4 12:11:36 INFO - psutil == 3.1.1 12:11:36 INFO - requests == 1.2.3 12:11:36 INFO - wsgiref == 0.1.2 12:11:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:11:36 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:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:36 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:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:36 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 0x102dc4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e59618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91fbd990c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d28d30>, '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 0x102a8ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d20b20>, '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.T8swjSpndo/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.tduF1Ey8Xo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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:36 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:36 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:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T8swjSpndo/Render', 12:11:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:36 INFO - 'HOME': '/Users/cltbld', 12:11:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:36 INFO - 'LOGNAME': 'cltbld', 12:11:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:36 INFO - 'MOZ_NO_REMOTE': '1', 12:11:36 INFO - 'NO_EM_RESTART': '1', 12:11:36 INFO - 'PAGER': '/bin/cat', 12:11:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:36 INFO - 'PWD': '/builds/slave/test', 12:11:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:36 INFO - 'SHELL': '/bin/bash', 12:11:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tduF1Ey8Xo/Listeners', 12:11:36 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 12:11:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:36 INFO - 'USER': 'cltbld', 12:11:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:36 INFO - 'XPC_FLAGS': '0x0', 12:11:36 INFO - 'XPC_SERVICE_NAME': '0', 12:11:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:36 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:36 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:36 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:36 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:36 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:36 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:36 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:36 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:36 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:36 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:36 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:36 INFO - Cleaning up... 12:11:36 INFO - Return code: 0 12:11:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:11:36 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:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:36 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:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:36 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 0x102dc4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e59618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91fbd990c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d28d30>, '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 0x102a8ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d20b20>, '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.T8swjSpndo/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.tduF1Ey8Xo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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:36 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:36 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:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T8swjSpndo/Render', 12:11:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:36 INFO - 'HOME': '/Users/cltbld', 12:11:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:36 INFO - 'LOGNAME': 'cltbld', 12:11:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:36 INFO - 'MOZ_NO_REMOTE': '1', 12:11:36 INFO - 'NO_EM_RESTART': '1', 12:11:36 INFO - 'PAGER': '/bin/cat', 12:11:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:36 INFO - 'PWD': '/builds/slave/test', 12:11:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:36 INFO - 'SHELL': '/bin/bash', 12:11:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tduF1Ey8Xo/Listeners', 12:11:36 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 12:11:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:36 INFO - 'USER': 'cltbld', 12:11:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:36 INFO - 'XPC_FLAGS': '0x0', 12:11:36 INFO - 'XPC_SERVICE_NAME': '0', 12:11:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:11:36 INFO - Cleaning up... 12:11:36 INFO - Return code: 0 12:11:36 INFO - Running post-action listener: _resource_record_post_action 12:11:36 INFO - Running post-action listener: _start_resource_monitoring 12:11:36 INFO - Starting resource monitoring. 12:11:36 INFO - [mozharness: 2016-09-04 19:11:36.998034Z] Finished create-virtualenv step (success) 12:11:36 INFO - [mozharness: 2016-09-04 19:11:36.999012Z] Running install step. 12:11:36 INFO - Running pre-action listener: _resource_record_pre_action 12:11:36 INFO - Running main action method: install 12:11:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:11:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:11:37 INFO - Reading from file tmpfile_stdout 12:11:37 INFO - Detecting whether we're running mozinstall >=1.0... 12:11:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:11:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:11:37 INFO - Reading from file tmpfile_stdout 12:11:37 INFO - Output received: 12:11:37 INFO - Usage: mozinstall [options] installer 12:11:37 INFO - Options: 12:11:37 INFO - -h, --help show this help message and exit 12:11:37 INFO - -d DEST, --destination=DEST 12:11:37 INFO - Directory to install application into. [default: 12:11:37 INFO - "/builds/slave/test"] 12:11:37 INFO - --app=APP Application being installed. [default: firefox] 12:11:37 INFO - mkdir: /builds/slave/test/build/application 12:11:37 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:37 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:11 INFO - Reading from file tmpfile_stdout 12:12:11 INFO - Output received: 12:12:11 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:12:11 INFO - Running post-action listener: _resource_record_post_action 12:12:11 INFO - [mozharness: 2016-09-04 19:12:11.947237Z] Finished install step (success) 12:12:11 INFO - [mozharness: 2016-09-04 19:12:11.947367Z] Running run-tests step. 12:12:11 INFO - Running pre-action listener: _resource_record_pre_action 12:12:11 INFO - Running main action method: run_tests 12:12:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:12:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:12:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:12:11 INFO - 2016-09-04 12:12:11.961 screenresolution[1643:7502] starting screenresolution argv=screenresolution get 12:12:11 INFO - 2016-09-04 12:12:11.964 screenresolution[1643:7502] Display 0: 1600x1200x32@60 12:12:11 INFO - 2016-09-04 12:12:11.970 screenresolution[1644:7504] starting screenresolution argv=screenresolution list 12:12:11 INFO - Available Modes on Display 0 12:12:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:12:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:12:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:12:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:12:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:12:12 INFO - Intel Iris: 12:12:12 INFO - Chipset Model: Intel Iris 12:12:12 INFO - Type: GPU 12:12:12 INFO - Bus: Built-In 12:12:12 INFO - VRAM (Dynamic, Max): 1536 MB 12:12:12 INFO - Vendor: Intel (0x8086) 12:12:12 INFO - Device ID: 0x0a2e 12:12:12 INFO - Revision ID: 0x0009 12:12:12 INFO - Displays: 12:12:12 INFO - Display: 12:12:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:12:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:12:12 INFO - Display Serial Number: 1600x1200 60 12:12:12 INFO - Main Display: Yes 12:12:12 INFO - Mirror: Off 12:12:12 INFO - Online: Yes 12:12:12 INFO - Rotation: Supported 12:12:12 INFO - Return code: 0 12:12:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:12:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:12:12 INFO - Python 2.7.3 12:12:12 INFO - Return code: 0 12:12:12 INFO - grabbing minidump binary from tooltool 12:12:12 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:12 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 0x102d28d30>, '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 0x102a8ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d20b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:12:12 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:12 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:12 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:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:12:12 INFO - Return code: 0 12:12:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:12:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:12:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:12:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:12:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:12:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QUpyhRJlT9SpFzddD_72WA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0367', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 12:12:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QUpyhRJlT9SpFzddD_72WA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0367 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 12:12:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T8swjSpndo/Render', 12:12:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:12:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:12:12 INFO - 'HOME': '/Users/cltbld', 12:12:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:12:12 INFO - 'LOGNAME': 'cltbld', 12:12:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:12:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:12:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:12 INFO - 'MOZ_NO_REMOTE': '1', 12:12:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:12:12 INFO - 'NO_EM_RESTART': '1', 12:12:12 INFO - 'PAGER': '/bin/cat', 12:12:12 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:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:12 INFO - 'PWD': '/builds/slave/test', 12:12:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:12:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:12:12 INFO - 'SHELL': '/bin/bash', 12:12:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tduF1Ey8Xo/Listeners', 12:12:12 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 12:12:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:12:12 INFO - 'USER': 'cltbld', 12:12:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:12:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:12:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:12 INFO - 'XPC_FLAGS': '0x0', 12:12:12 INFO - 'XPC_SERVICE_NAME': '0', 12:12:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:12:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QUpyhRJlT9SpFzddD_72WA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0367', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 12:12:12 INFO - mozversion application_buildid: 20160904103549 12:12:12 INFO - mozversion application_changeset: ce2a0cbdb4e80f88c9a12f63dcb49176525d3dd2 12:12:12 INFO - mozversion application_display_name: Nightly 12:12:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:12:12 INFO - mozversion application_name: Firefox 12:12:12 INFO - mozversion application_remotingname: firefox 12:12:12 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:12:12 INFO - mozversion application_vendor: Mozilla 12:12:12 INFO - mozversion application_version: 51.0a1 12:12:12 INFO - mozversion platform_buildid: 20160904103549 12:12:12 INFO - mozversion platform_changeset: ce2a0cbdb4e80f88c9a12f63dcb49176525d3dd2 12:12:12 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:12:12 INFO - mozversion platform_version: 51.0a1 12:12:12 INFO - using testdate: 1473016332 12:12:12 INFO - actual date: 1473016332 12:12:12 INFO - starting webserver on 'localhost:49274' 12:12:12 INFO - SUITE-START | Running 2 tests 12:12:12 INFO - TEST-START | dromaeo_css 12:12:12 INFO - operating with platform_type : mac_ 12:12:12 INFO - Initialising browser for dromaeo_css test... 12:12:12 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/tmpHuG7zE/profile http://localhost:49274/getInfo.html) 12:12:15 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 12:12:15 INFO - PROCESS | 1654 | colorDepth:24 12:12:15 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 12:12:15 INFO - PROCESS | 1654 | __metrics 12:12:15 INFO - PROCESS | 1654 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:12:15 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:12:15 INFO - TEST-INFO | 1654: exit 0 12:12:15 INFO - Browser initialized. 12:12:15 INFO - Running cycle 1/1 for dromaeo_css test... 12:12:15 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/tmpHuG7zE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:12:27 INFO - PROCESS | 1657 | RSS: Main: 233955328 12:12:27 INFO - PROCESS | 1657 | 12:13:19 INFO - PROCESS | 1657 | 1473016399188 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 | 1657 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 12:15:32 INFO - PROCESS | 1657 | RSS: Main: 367366144 12:15:32 INFO - PROCESS | 1657 | 12:18:35 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 12:18:35 INFO - PROCESS | 1657 | RSS: Main: 397443072 12:18:35 INFO - PROCESS | 1657 | 12:21:38 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 12:21:38 INFO - PROCESS | 1657 | RSS: Main: 339513344 12:21:38 INFO - PROCESS | 1657 | 12:24:41 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 12:24:41 INFO - PROCESS | 1657 | RSS: Main: 362213376 12:24:41 INFO - PROCESS | 1657 | 12:27:45 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 12:27:45 INFO - PROCESS | 1657 | RSS: Main: 331624448 12:27:45 INFO - PROCESS | 1657 | 12:30:49 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 12:30:49 INFO - PROCESS | 1657 | RSS: Main: 400392192 12:30:49 INFO - PROCESS | 1657 | 12:30:49 INFO - PROCESS | 1657 | __start_tp_report 12:30:49 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 12:30:49 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 12:30:49 INFO - PROCESS | 1657 | |i|pagename|runs| 12:30:49 INFO - PROCESS | 1657 | |0;dojo.html;3083.660797312297;3259.1363288728476;3327.3345330933803;3329.5172200030997;3371.6965473107416;5659.462351076651;5724.885502289957;5731.254936858213;5770.884582308351;5799.13013048043;5730.68481233532;5769.798057067993;5780.595358324919;5786.56600754944;5787.13193021047;5168.819091331795;5202.427732949392;5217.060929032791;5241.213817927322;5267.341582302212;5267.841964741064;5273.789048438058;5278.392984806739;5287.894242115155;5294.841154765363;5187.273781670569;5197.480251974791;5227.163653815391;5251.789928402878;5261.368635763713;5277.47225277473;5285.233641122054;5293.994141113176;5305.283786688786;5341.439148889388;5100.413452452978;5107.846764597068;5123.129068058439;5223.138182199932;5226.92159617606;4725.1730947084125;5723.370429252779;5748.223989761371;5794.478496935296;5848.853778655525;6245.594036387621;6273.309935907047;6277.937220627781;6297.464715499188;6298.118263443122;6066.013378126436;6232.439080482733;6259.71831267594;6322.114903913456;6322.367763223687;5809.099589563624;5851.063829787214;5851.882962340772;5878.353381128072;5884.705764711747;6306.306306306303;6311.558190926591;6314.273858506239;6315.5579109462815;6321.778737744157;6010.398960103955;6065.723595327032;6097.373446762125;6156.569040167145;6197.380261973766;6248.687565621701;6282.4974002079725;6311.905321420182;6317.652529110851;6320.367963203735;6158.538109641795;6212.161358216673;6244.656543890039;6261;6261.749530018839;6097.97841994295;6144.648177400964;6166.630002199882;6259.967105427553;6310.716017779211;4903.632227582874;4919.90160196794;4947.82682606107;4956.727379994063;4985;4426.513083560806;4433.334999750064;4457.598816106566;4492.3710680504755;4523.2988886722615;5766.192733017381;5791.449812267828;5795.710214489259;5796.333421656479;5807.651540907559;6151.138840562325;6224.37756224374;6263.091851681481;6312;6318.431341179224;5803;5808.448197421239;5818.44724751148;5824.922389357897;5858.707064646751;5422.810201643002;5432.212329212242;5436.782528698887;5442.945570544323;5444.183372494158;5325.893482130413;6160.969195153995;6249.187605611241;6319.46284565817;6325.683715814191;6284.465820405317;6301;6320;6342.429181373698;6351.460125889351;6315.905261421174;6319.462845657987;6320.905186422299;6326.43062124411;6338.239411270492;6093.451589356979;6101.822802906834;6123.526657168115;6211.472024877936;6255.718492667932;6223.844403889758;6297.086922396135;6307.905381419374;6315.242170939517;6331.271903730945;6045.06301523247;6127.479164270909;6142;6152.692365381624;6160.260768707783;5967.254093238345;6055.212822333068;6122.908156377747;6124.693765311806;6144.324124346229;5916.994111001226;6047.334793172836;6050.062240352752;6060.2424696912885;6074.179985701874;6149.508039356751;6158.969205154125;6164.106204600397;6172.845678858064;6174.845628859314;5786.797462088806;5883.94116058834;5922.170896074467;5970.701464926649;6041.335453100243 12:30:49 INFO - PROCESS | 1657 | |1;ext.html;490.4996903158778;528.6405244433786;529.9178627312766;539.1508374310462;563.2030676592622;30059.248518787015;32403.51394729078;38150.80924595373;40314;40419.191616167656;41578.58421415793;41895.58104418955;41955.16089678204;41970.37044444336;42013.36979945296;8668.436551624125;9638.228941684665;9852.162566181883;9858.359206651545;9869.851952220686;11906.404679765978;12144.939275303655;12189.268643881396;12404.379781010914;12415.56545520907;11952.581659641914;12058;12063.698407539838;12076.577319793809;12088.939555302211;12084.335361555111;12150.39248037599;12157.331346775924;12157.817632735516;12161.20952138117;11844.585439509576;11933.224340417932;11938.462769175409;11940.880591194064;11952.70118247042;39205.41191882124;39572.60427395718;39597.60402395968;39599.8020009901;39646.80176599127;3326.6340702522702;3353.111425472252;3406.029281654741;3591.982040089783;3940.9649802355816;30053.248668783235;31048.223794405094;31353.216169595715;31375.529367059513;31556.526652100238;3276.7214786743157;3284.310294838087;3300.950485742716;3335.0828522156603;3394.592648882125;358494;361601;362779;364344.1782791069;370529.1473542642;56400.71799640975;56793;56824.715876420356;56838.863222735316;56844.14733778997;33585.16037099043;34152;34205.97382078542;35502;35568.64431355705;14693;16164;16342.346306147621;16348.509544713677;16348.591285217726;11292.322460652387;11437.485313160927;11460;12442.875571244353;12540.122191446693;15306.923465382602;16354;16560.420385286456;18015.459536213933;18478.81521184771;6974.302569742985;7721.806954826062;7949.205079492121;8014.078380986145;8018.5990700464745;856.674463703801;915.8809354783969;965.7778710896392;1036.212478516318;1080.3949788118678;24086.277411677678;27970.160895173176;28081.43837123287;28511.859525618744;28981.13056608263;1012.5241136665755;1084.1814430105378;1099.8405231241425;1113.6937342230951;1209.8608660004213;12307.984354003118;13475.393607287695;14697.824174066049;15313.923430382776;15402.460913868184;654121;659818;660872;662932.4827006866;664085;47192.5280747195;56244.718776405854;56713.14930276049;56737;56772;1078.5092782783659;1235.6540168752763;1273.5797186928291;1381.951631692886;1406.0509156319442;28696.426071478872;30293;30828.16744445247;30906;31059.068227953197;46684;47262.52737472581;47329.52670473251;47413.525864740914;47542.52457475381;517980;518306;520270;520625;522799;25367.36581585475;26054.86972565125;26475;26667.599986000612;27670.308242293297;45789.77105114453;46525.3021204689;46636.300455492514;47186;47453;37701.229877012076;37729.81135094307;38610;39333.01667458336;39810;38284.617153828105;38543.80728096342;38653.568044681815;38808.80595597004;39711.602883971944;36977.815110924275;37376.813115934245;37813.43279850859;37926.62073379231;37931.81034094812;30979;33685.49471757975;33737.15657108592;34217;34697.479537806445;21880.343589691696;25225.495490090463;25232;25407.237782866545;25607.23178304654 12:30:49 INFO - PROCESS | 1657 | |2;jquery.html;344.02297475170514;363.6944966228368;364.0425680460387;376.41843352021135;398.491922798432;15163.62090947727;16379.935304205233;19644.017799110057;20194.59610807783;20310.796892031092;20344.491387715316;20770.961451927382;20831.0626021829;21019.789802101976;21094;5700.059490184115;5776.682282474462;5904.1734157218025;6024.2168518092585;6049.213602231704;4113.095119073799;5812.157237200625;5919.1713160157615;5930.110483427498;5934.198883150784;6201.658908760016;6486.870262594745;6524.282328943837;6574.769883054094;6620.801375958729;5756.7121643917635;6039.516838652898;6083.239595050619;6550.967245163767;6559.606423614575;5867.530597552228;5870.471657550841;5879.970600147015;5884.588078834483;5893.3222679391565;12997.740045199138;13232.669183270398;24743.77306042465;25209.36976575582;25447.36381590475;2164.7943445372666;2428.514297140561;2542.313575334668;2828.4160869912916;2890.624218851557;9451.432914025181;9892.703218903445;10128.54421551032;10370.014848589371;10405.427701476416;1607.790987171672;1817.4184261036473;1837.6049149432936;1881.604862978776;1918.539550507882;113834.43082784615;123163.38418307941;126204;127142;128870.40166384977;9037.593308301142;10062.345947513535;11255.774884502427;11441.885581144248;11447.484863181176;14225.288735563181;14235;14245.717885390364;14245.78631320531;14258.358373873201;8182.4681395709085;8337.666493340319;8381.706640267561;8459.365547583955;8473.618687159093;7996.880046799302;8022.679092836338;8069.556174410346;8140.674373025131;8141.185881411811;4374.168907907488;4454.532274111171;4471.374007638933;4516.932246016312;4547.931781023287;1838.9172487238106;1886.2737845929416;1888.1880791259616;1891.7351570780102;1900.8004159563047;769.5382770337752;825.7357645553366;881.722257488889;886.8916230314633;970.4662435660358;19964.401067967694;21329.68005479919;21434.03546840396;21505.67741483879;21515.8924205378;863.8142799298181;882.6778225947482;899.4827973914894;982.2093214962024;1000.659775676259;10561.471926403648;10945.288556243819;11041.668749937515;11129.387883666313;11419.429028548539;549376;559741;563795;569053;573626;70812.2918770816;71392.28607713961;87634.60972531905;124194;126402.36798815947;961.0725649748026;961.1877963121151;964.2430691906819;965.0494263150791;984.8965858584745;18738.81261187425;18990.43028709141;19238.03809809532;19255.32606358771;19271;51173;51606.22590661068;53070.73464632652;53172.73413632907;53848.46151538591;56815.57961051007;73635.74096201669;75474.62262688651;75912;76075;7891.802704932193;7986.281234688906;8133.34933205354;8143.959280203798;8327.375446841632;9514.714558563253;12921.870781292067;13003.08978371505;13426.395812188279;13482.865171348161;10258.666373371414;11164;11354.659360219206;11687.824682629938;11710.765784684429;10609.363438193734;11167;11243.437828108725;11615.245009074382;11716.06271498295;9506.239500839778;11673.416329183337;11849.881501184878;11911.642650720492;12016.519339226334;6999.335063168991;8085.151059138941;8106.635201415833;8216.137305582828;8255.091939886488;10830.079443247098;11397.829032564352;11457.197996140523;11720.003799677123;11753.47109380097 12:30:49 INFO - PROCESS | 1657 | |3;mootools.html;1557.844215578442;1565.4599163288665;1694.2799310293137;1803.3147403986484;1954.8924809135501;8399.748007559776;8907.421017633866;8913.50975696338;8913.598888050037;8916.955415222923;7820.687172513092;8063.717769878055;8466.026406963185;8670.739877803677;8691.826163476726;2092.215419217793;2362.303120579429;2365.1721897335965;2396.520695860826;2405.194259922928;2027.1789925080293;2362.208660098869;2376.5009348036933;2397.9209355789876;2409.4458274596764;2049.75402951646;2381.7499162587937;2410.6866107406013;2415.263344679872;2419.6733440985417;2048.5800410915726;2359.55168517981;2372.95254094918;2386.2244770449674;2386.6300723387903;2374.798142157919;2377.132346693462;2378.191414918936;2379.9048038078454;2383.2254517281954;8847.867281990775;8851.46891186531;8944.060873608243;8948.507832069234;8988.146126118008;5151;5296.947030529684;5297.231901374318;5299.12564426873;5300.284461597674;2802.285417218596;3467.5318831957616;3566.785992840412;3627.818609069536;3640.7451478396524;1084.701707030565;1098.1489345757022;1759.9912000439915;1817.6728188925988;1868.2993877296014;11148.721281967935;11433.028192603639;11451.770964580744;11573.36346500942;11703.23928944616;4436.0906014267;4470.754108524063;4673.976630116896;4674.485806561275;4674.626029917598;9066.682666106655;9082.046385129466;9085.273178145733;9086.727398178065;9091.227245684191;5235.869103272373;5240.947590524046;5241.475852414728;5248.291480650139;5251.842444726664;4393.912121757547;4479.216137175981;4500.977495112504;4550.203714350042;4929.580985616191;3651.1419816343137;3691.796951167658;3794.848206071726;3828.2343531293855;3832.9808350958065;1140.8060629692973;1166.3002198680724;1171.0338970349496;1172.8944395004492;1180.7166280092715;730.5689643110591;888.2893685051932;905.542700936023;906.5693795447241;932.4964519159578;5515.310586176728;5745;5852.209951656472;5989.490893274034;6065.75736970525;917.2111983694018;946.0870260198941;947.772534591693;953.2850362228329;953.7281874665687;3559.9288014240087;3668.6147954464395;3789.5073640427117;3812.7902965336616;3814.446905198786;10540.578376864993;10742.033217010507;10840.837387439195;10858.67423977282;10944;9926.404415735078;10798.406087665096;10801.243912926177;10808.351498910091;10822.729431764268;7855.921440785633;8069.9596502018285;8182.831549639351;8194.918050819535;8198.057223419382;5772.2207501986695;5785.247917770831;5822.446867547576;5906.911396329145;6171.352008039271;9682.080202380763;9886.03378657603;10010.949945250228;10043.397396156253;10047.447390393307;9984.60061597528;10090.646827361006;10178.13485853681;10203.234757393077;10251.436171010666;7514.39884809203;7716.5370077795515;8114.675412983651;8228.50628962264;8267;8056.879146812686;8275.62759675828;8284.420090593601;8332.333413326798;8421.284190843848;7340.816479588053;7451.4038876888635;7484.663190156347;7519.887201691869;7548.3961283098315;7228.927710722825;7263.237360077327;7318.817029574303;7353.264673532818;7430.517016394133;6937.479689023243;6944.4444444445335;6985.3713165815325;7003.124609423822;7006.719731210898;4786.569208771228;4928.285398617252;4956.727379994135;5002.624803139852;5085.745712714275;4911.336969509213;4928.2853986171085;5026.974865125651;5091.567216786615;5161.561267292323 12:30:49 INFO - PROCESS | 1657 | |4;prototype.html;286.58302170342154;295.5743729030197;304.8628615265062;308.3755395324466;308.41709169669315;4485.5962963333295;4990.251462280659;5450.364890532844;5473;5484.396716361197;5187.844364669057;5209.609279304048;5377.139657654776;5380.381256155543;5385.81149659762;3455.8768400269887;3739.663430291273;3796.4874741909766;4116.485439320085;4117.773522456271;3439.9164263256994;4112.239235741383;4115.650169735576;4129.277376459123;4144.689148313873;3394.779339342947;3738.775673459601;3939.8227079781477;4002.4796776419025;4085.9182816343655;3352.4300868852233;3621;3796.1268908151005;3973.8938362667545;4019.5176578810583;3051.611516759869;3190.6968837960358;3283.635782236786;3468.6878180963836;3479.895603131885;5107.284980102789;5263;5291.28567643367;5295.841124766263;5302.787888484456;4051.6353528182603;4462.799174037176;4572.702774319659;4577.5193604671695;4637.281221410687;4478.843240486567;5661;5674.943250567483;5682.801101961453;5696.401877802812;2929.4434057529;3797.8670746524017;3800.6769424598942;3806.2387522495333;3818.369968955119;5478.369987451454;5565.526930210937;5668.97165514174;5855.121731740273;5894.705264736789;3337.816420096918;3516.701080408143;3727.1986522897705;3778.41178792528;3859.7877116758286;3516.7362447816004;3929.54810196831;3940.408938659167;3948.348522493814;3958.2281455115976;3655.7440979131206;4265.76292875069;4410.500429853865;4625.791839367236;4631.560001799824;5651.039323315046;5676.2036079512745;5695.686737229492;5700.914486282709;5702.7433765480655;1192.791261529229;1271.4977583854325;1324.7017922435873;1340.2561578323928;1352.7226918481688;761.3490465651788;778.1557010643402;820.2371794231422;821.8479581277483;832.8667413213857;3661.029827095822;3851.9614803852164;4392.912141757147;4459.722104253371;4463.018136010072;3842.634949679776;4308.0274610518645;4494.5730155635165;4840.661153719277;4886.926696099561;3801.50580424543;4429.822807087745;4437.400950871655;4439.755813430293;4496.100779844044;2258.802834497719;2276.886155692209;2308.746037935826;2553.6848523010663;2579.806514511419;4479.664025198058;4578.427696537933;4985.925211121836;5029.315179414937;5039.924401133986;4062.6749860011128;4238.2703221165175;4928.796282416386;4929;4972.751362431936;4638.234691275901;4806.596701649175;5546.972265138648;6114.2357205349335;6131.601445906002;5229.320188375486;5847.105682687097;5890.941090589211;5916.792912248051;5977.671228082497;5526.723663816712;5845.357010728731;5886.705664716661;5896.882062358814;5904.527637789052;5230.555402790806;5257.868553286198;5275.254569255718;6024.578279520567;6039.728212230548;3437.914052148716;3623.6376362363553;3915.4322623218904;3919.7452165609143;3920.843166273317;4882.707037577757;5596.832095037155;5867.802047883999;5868.501177399969;5874.794382196603;5450.754716037709;5691.402402747652;5707.315122185199;5732.656040637408;5764.011416061452;5590.552755779447;5605.467480589337;5662.546996240373;5664.0459041630575;5686.800961966312;5326.467353264643;5520.696361700145;5543.584231182758;5545.251391062155;5582.525485333792;5623.156526521055;5659.54723622101;5679;5711.371749107512;5713.657180569179;5538.944610553843;5542.201188399832;5546.473085056913;5562.249096371938;5589 12:30:49 INFO - PROCESS | 1657 | |5;yui.html;710.3251910684849;733.189825243106;740.5371642723298;783.6473586885901;806.2461598405488;15815.762763558541;16555;16558.420455284067;16585.419510317126;16662.33350665975;14392.424303027867;14462.132272063684;14462.349194286257;14502.637434064154;14900.850991490082;678.915135608049;699.615211633602;702.2696395748416;705.0904333409895;834.253343257784;691.3915754136378;822.2805045585113;863.5034854958393;864.8918885139358;866.0127454701645;902.1429641840245;911.129870973219;913.6893456224882;913.8857642794651;913.9268858491305;910.7723069232692;910.9362344635878;912.5346073502495;912.6167009855876;913.1827014821697;525.4351572060012;652.6704014472708;678.5114717403461;680.1056610557101;683;15100.320485578066;15120.39518419262;15189;15190.240487975558;15211.543653690407;13639;13957.79063314051;13974.58076257714;14033.087849289763;14150.646233844132;12208.735802197678;12601.36993150348;12642.304673242968;12650.620481385573;12976.740465190738;4481.820727170909;4524.977375113103;4595.793189306489;4627.051454451829;4741.525847415265;121118;123175.76824231732;123533;124004;124095;10903.454827258605;10999;11117.332960022262;11307.378094204732;11456.083513318914;22110.889445552668;22139.889300553717;22140.66788998166;22166.88916555407;22181.445463863532;7644.044494438195;7797.220277972157;7860.0567931848545;7936.761897143095;7942.960285198653;4187.602177793105;4256.1487702459635;4312.094460163401;4405.647548196201;4452.265376212888;3429.5027221052806;3480.773749706261;3532.7527073104634;3536.504889315498;3540.6459354064386;572.7737543670227;643.707113263464;653.5849735417949;665.5241502325861;668.5153263883645;567.2597260574906;568.2158621102934;598.3448124303881;609.6738245038972;635.8855406026867;8470;8830;9832.360896541702;10113.797724045626;10281.177505799518;528.1417696243604;580.8809194115196;596.7195418153461;598.3687209993499;615.4245780195442;4576.748278844629;4652.278896771006;4661.021185551072;4678.69588476748;4716.174669432904;151641;151672;153233;153642.46357536505;159099;127530;128044;130362;131457.34271328582;134518;83011.09461169071;91836;95290;97332;116878.24682629768;6818.284080171709;8381.874271885805;9303.162715355651;9637.42175469474;9824.263180261652;61589.384106158366;61764.69117654383;62663;64209;64709.35290647222;59614.80770384481;59919.801603968546;62043;79331.01672458235;82843;11314.943425282821;13392.661827582624;13488.12327198729;13518.59444216675;13712.245826479639;11891.76216475683;14865;14938.253087345807;15207.771883421536;15410.614734631725;13739.74255431595;13766.793498097337;14017.859821401655;14405.351759170653;14429.639259018608;12583.87416125827;13688.794668079787;13883.444662213398;13900.72198556003;13986;13407.68265276241;13617.659558511117;13655.522056727967;13850.238236897067;14229.715405692035;11227.775444491228;11455.198136130392;11601.419929003347;11630.360330181922;11642.825357619811;10475.476226188508;10517.842232366367;10645.787084258427;11304.830427543759;11568.190226684052 12:30:49 INFO - PROCESS | 1657 | __end_tp_report 12:30:49 INFO - PROCESS | 1657 | __start_cc_report 12:30:49 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,1205 12:30:49 INFO - PROCESS | 1657 | __end_cc_report 12:30:49 INFO - PROCESS | 1657 | __startTimestamp1473017449722__endTimestamp 12:30:49 INFO - PROCESS | 1657 | 12:30:49 INFO - PROCESS | 1657 | ------- Summary: start ------- 12:30:49 INFO - PROCESS | 1657 | Number of tests: 6 12:30:49 INFO - PROCESS | 1657 | 12:30:49 INFO - PROCESS | 1657 | [#0] dojo.html Cycles:165 Average:5765.28 Median:6010.40 stddev:653.22 (10.9%) stddev-sans-first:620.42 12:30:49 INFO - PROCESS | 1657 | Values: 3083.7 3259.1 3327.3 3329.5 3371.7 5659.5 5724.9 5731.3 5770.9 5799.1 5730.7 5769.8 5780.6 5786.6 5787.1 5168.8 5202.4 5217.1 5241.2 5267.3 5267.8 5273.8 5278.4 5287.9 5294.8 5187.3 5197.5 5227.2 5251.8 5261.4 5277.5 5285.2 5294.0 5305.3 5341.4 5100.4 5107.8 5123.1 5223.1 5226.9 4725.2 5723.4 5748.2 5794.5 5848.9 6245.6 6273.3 6277.9 6297.5 6298.1 6066.0 6232.4 6259.7 6322.1 6322.4 5809.1 5851.1 5851.9 5878.4 5884.7 6306.3 6311.6 6314.3 6315.6 6321.8 6010.4 6065.7 6097.4 6156.6 6197.4 6248.7 6282.5 6311.9 6317.7 6320.4 6158.5 6212.2 6244.7 6261.0 6261.7 6098.0 6144.6 6166.6 6260.0 6310.7 4903.6 4919.9 4947.8 4956.7 4985.0 4426.5 4433.3 4457.6 4492.4 4523.3 5766.2 5791.4 5795.7 5796.3 5807.7 6151.1 6224.4 6263.1 6312.0 6318.4 5803.0 5808.4 5818.4 5824.9 5858.7 5422.8 5432.2 5436.8 5442.9 5444.2 5325.9 6161.0 6249.2 6319.5 6325.7 6284.5 6301.0 6320.0 6342.4 6351.5 6315.9 6319.5 6320.9 6326.4 6338.2 6093.5 6101.8 6123.5 6211.5 6255.7 6223.8 6297.1 6307.9 6315.2 6331.3 6045.1 6127.5 6142.0 6152.7 6160.3 5967.3 6055.2 6122.9 6124.7 6144.3 5917.0 6047.3 6050.1 6060.2 6074.2 6149.5 6159.0 6164.1 6172.8 6174.8 5786.8 5883.9 5922.2 5970.7 6041.3 12:30:49 INFO - PROCESS | 1657 | 12:30:49 INFO - PROCESS | 1657 | [#1] ext.html Cycles:180 Average:64599.07 Median:18779.01 stddev:141430.02 (753.1%) stddev-sans-first:141744.86 12:30:49 INFO - PROCESS | 1657 | Values: 490.5 528.6 529.9 539.2 563.2 30059.2 32403.5 38150.8 40314.0 40419.2 41578.6 41895.6 41955.2 41970.4 42013.4 8668.4 9638.2 9852.2 9858.4 9869.9 11906.4 12144.9 12189.3 12404.4 12415.6 11952.6 12058.0 12063.7 12076.6 12088.9 12084.3 12150.4 12157.3 12157.8 12161.2 11844.6 11933.2 11938.5 11940.9 11952.7 39205.4 39572.6 39597.6 39599.8 39646.8 3326.6 3353.1 3406.0 3592.0 3941.0 30053.2 31048.2 31353.2 31375.5 31556.5 3276.7 3284.3 3301.0 3335.1 3394.6 358494.0 361601.0 362779.0 364344.2 370529.1 56400.7 56793.0 56824.7 56838.9 56844.1 33585.2 34152.0 34206.0 35502.0 35568.6 14693.0 16164.0 16342.3 16348.5 16348.6 11292.3 11437.5 11460.0 12442.9 12540.1 15306.9 16354.0 16560.4 18015.5 18478.8 6974.3 7721.8 7949.2 8014.1 8018.6 856.7 915.9 965.8 1036.2 1080.4 24086.3 27970.2 28081.4 28511.9 28981.1 1012.5 1084.2 1099.8 1113.7 1209.9 12308.0 13475.4 14697.8 15313.9 15402.5 654121.0 659818.0 660872.0 662932.5 664085.0 47192.5 56244.7 56713.1 56737.0 56772.0 1078.5 1235.7 1273.6 1382.0 1406.1 28696.4 30293.0 30828.2 30906.0 31059.1 46684.0 47262.5 47329.5 47413.5 47542.5 517980.0 518306.0 520270.0 520625.0 522799.0 25367.4 26054.9 26475.0 26667.6 27670.3 45789.8 46525.3 46636.3 47186.0 47453.0 37701.2 37729.8 38610.0 39333.0 39810.0 38284.6 38543.8 38653.6 38808.8 39711.6 36977.8 37376.8 37813.4 37926.6 37931.8 30979.0 33685.5 33737.2 34217.0 34697.5 21880.3 25225.5 25232.0 25407.2 25607.2 12:30:49 INFO - PROCESS | 1657 | 12:30:49 INFO - PROCESS | 1657 | [#2] jquery.html Cycles:180 Average:32972.71 Median:370.23 stddev:93788.81 (25332.5%) stddev-sans-first:94019.91 12:30:49 INFO - PROCESS | 1657 | Values: 344.0 363.7 364.0 376.4 398.5 15163.6 16379.9 19644.0 20194.6 20310.8 20344.5 20771.0 20831.1 21019.8 21094.0 5700.1 5776.7 5904.2 6024.2 6049.2 4113.1 5812.2 5919.2 5930.1 5934.2 6201.7 6486.9 6524.3 6574.8 6620.8 5756.7 6039.5 6083.2 6551.0 6559.6 5867.5 5870.5 5880.0 5884.6 5893.3 12997.7 13232.7 24743.8 25209.4 25447.4 2164.8 2428.5 2542.3 2828.4 2890.6 9451.4 9892.7 10128.5 10370.0 10405.4 1607.8 1817.4 1837.6 1881.6 1918.5 113834.4 123163.4 126204.0 127142.0 128870.4 9037.6 10062.3 11255.8 11441.9 11447.5 14225.3 14235.0 14245.7 14245.8 14258.4 8182.5 8337.7 8381.7 8459.4 8473.6 7996.9 8022.7 8069.6 8140.7 8141.2 4374.2 4454.5 4471.4 4516.9 4547.9 1838.9 1886.3 1888.2 1891.7 1900.8 769.5 825.7 881.7 886.9 970.5 19964.4 21329.7 21434.0 21505.7 21515.9 863.8 882.7 899.5 982.2 1000.7 10561.5 10945.3 11041.7 11129.4 11419.4 549376.0 559741.0 563795.0 569053.0 573626.0 70812.3 71392.3 87634.6 124194.0 126402.4 961.1 961.2 964.2 965.0 984.9 18738.8 18990.4 19238.0 19255.3 19271.0 51173.0 51606.2 53070.7 53172.7 53848.5 56815.6 73635.7 75474.6 75912.0 76075.0 7891.8 7986.3 8133.3 8144.0 8327.4 9514.7 12921.9 13003.1 13426.4 13482.9 10258.7 11164.0 11354.7 11687.8 11710.8 10609.4 11167.0 11243.4 11615.2 11716.1 9506.2 11673.4 11849.9 11911.6 12016.5 6999.3 8085.2 8106.6 8216.1 8255.1 10830.1 11397.8 11457.2 11720.0 11753.5 12:30:49 INFO - PROCESS | 1657 | 12:30:49 INFO - PROCESS | 1657 | [#3] mootools.html Cycles:180 Average:5677.20 Median:4979.68 stddev:3165.10 (63.6%) stddev-sans-first:3158.84 12:30:49 INFO - PROCESS | 1657 | Values: 1557.8 1565.5 1694.3 1803.3 1954.9 8399.7 8907.4 8913.5 8913.6 8917.0 7820.7 8063.7 8466.0 8670.7 8691.8 2092.2 2362.3 2365.2 2396.5 2405.2 2027.2 2362.2 2376.5 2397.9 2409.4 2049.8 2381.7 2410.7 2415.3 2419.7 2048.6 2359.6 2373.0 2386.2 2386.6 2374.8 2377.1 2378.2 2379.9 2383.2 8847.9 8851.5 8944.1 8948.5 8988.1 5151.0 5296.9 5297.2 5299.1 5300.3 2802.3 3467.5 3566.8 3627.8 3640.7 1084.7 1098.1 1760.0 1817.7 1868.3 11148.7 11433.0 11451.8 11573.4 11703.2 4436.1 4470.8 4674.0 4674.5 4674.6 9066.7 9082.0 9085.3 9086.7 9091.2 5235.9 5240.9 5241.5 5248.3 5251.8 4393.9 4479.2 4501.0 4550.2 4929.6 3651.1 3691.8 3794.8 3828.2 3833.0 1140.8 1166.3 1171.0 1172.9 1180.7 730.6 888.3 905.5 906.6 932.5 5515.3 5745.0 5852.2 5989.5 6065.8 917.2 946.1 947.8 953.3 953.7 3559.9 3668.6 3789.5 3812.8 3814.4 10540.6 10742.0 10840.8 10858.7 10944.0 9926.4 10798.4 10801.2 10808.4 10822.7 7855.9 8070.0 8182.8 8194.9 8198.1 5772.2 5785.2 5822.4 5906.9 6171.4 9682.1 9886.0 10010.9 10043.4 10047.4 9984.6 10090.6 10178.1 10203.2 10251.4 7514.4 7716.5 8114.7 8228.5 8267.0 8056.9 8275.6 8284.4 8332.3 8421.3 7340.8 7451.4 7484.7 7519.9 7548.4 7228.9 7263.2 7318.8 7353.3 7430.5 6937.5 6944.4 6985.4 7003.1 7006.7 4786.6 4928.3 4956.7 5002.6 5085.7 4911.3 4928.3 5027.0 5091.6 5161.6 12:30:49 INFO - PROCESS | 1657 | 12:30:49 INFO - PROCESS | 1657 | [#4] prototype.html Cycles:180 Average:4400.32 Median:4823.63 stddev:1420.14 (29.4%) stddev-sans-first:1390.16 12:30:49 INFO - PROCESS | 1657 | Values: 286.6 295.6 304.9 308.4 308.4 4485.6 4990.3 5450.4 5473.0 5484.4 5187.8 5209.6 5377.1 5380.4 5385.8 3455.9 3739.7 3796.5 4116.5 4117.8 3439.9 4112.2 4115.7 4129.3 4144.7 3394.8 3738.8 3939.8 4002.5 4085.9 3352.4 3621.0 3796.1 3973.9 4019.5 3051.6 3190.7 3283.6 3468.7 3479.9 5107.3 5263.0 5291.3 5295.8 5302.8 4051.6 4462.8 4572.7 4577.5 4637.3 4478.8 5661.0 5674.9 5682.8 5696.4 2929.4 3797.9 3800.7 3806.2 3818.4 5478.4 5565.5 5669.0 5855.1 5894.7 3337.8 3516.7 3727.2 3778.4 3859.8 3516.7 3929.5 3940.4 3948.3 3958.2 3655.7 4265.8 4410.5 4625.8 4631.6 5651.0 5676.2 5695.7 5700.9 5702.7 1192.8 1271.5 1324.7 1340.3 1352.7 761.3 778.2 820.2 821.8 832.9 3661.0 3852.0 4392.9 4459.7 4463.0 3842.6 4308.0 4494.6 4840.7 4886.9 3801.5 4429.8 4437.4 4439.8 4496.1 2258.8 2276.9 2308.7 2553.7 2579.8 4479.7 4578.4 4985.9 5029.3 5039.9 4062.7 4238.3 4928.8 4929.0 4972.8 4638.2 4806.6 5547.0 6114.2 6131.6 5229.3 5847.1 5890.9 5916.8 5977.7 5526.7 5845.4 5886.7 5896.9 5904.5 5230.6 5257.9 5275.3 6024.6 6039.7 3437.9 3623.6 3915.4 3919.7 3920.8 4882.7 5596.8 5867.8 5868.5 5874.8 5450.8 5691.4 5707.3 5732.7 5764.0 5590.6 5605.5 5662.5 5664.0 5686.8 5326.5 5520.7 5543.6 5545.3 5582.5 5623.2 5659.5 5679.0 5711.4 5713.7 5538.9 5542.2 5546.5 5562.2 5589.0 12:30:49 INFO - PROCESS | 1657 | 12:30:49 INFO - PROCESS | 1657 | [#5] yui.html Cycles:180 Average:24534.45 Median:3534.63 stddev:39442.94 (1115.9%) stddev-sans-first:39513.03 12:30:49 INFO - PROCESS | 1657 | Values: 710.3 733.2 740.5 783.6 806.2 15815.8 16555.0 16558.4 16585.4 16662.3 14392.4 14462.1 14462.3 14502.6 14900.9 678.9 699.6 702.3 705.1 834.3 691.4 822.3 863.5 864.9 866.0 902.1 911.1 913.7 913.9 913.9 910.8 910.9 912.5 912.6 913.2 525.4 652.7 678.5 680.1 683.0 15100.3 15120.4 15189.0 15190.2 15211.5 13639.0 13957.8 13974.6 14033.1 14150.6 12208.7 12601.4 12642.3 12650.6 12976.7 4481.8 4525.0 4595.8 4627.1 4741.5 121118.0 123175.8 123533.0 124004.0 124095.0 10903.5 10999.0 11117.3 11307.4 11456.1 22110.9 22139.9 22140.7 22166.9 22181.4 7644.0 7797.2 7860.1 7936.8 7943.0 4187.6 4256.1 4312.1 4405.6 4452.3 3429.5 3480.8 3532.8 3536.5 3540.6 572.8 643.7 653.6 665.5 668.5 567.3 568.2 598.3 609.7 635.9 8470.0 8830.0 9832.4 10113.8 10281.2 528.1 580.9 596.7 598.4 615.4 4576.7 4652.3 4661.0 4678.7 4716.2 151641.0 151672.0 153233.0 153642.5 159099.0 127530.0 128044.0 130362.0 131457.3 134518.0 83011.1 91836.0 95290.0 97332.0 116878.2 6818.3 8381.9 9303.2 9637.4 9824.3 61589.4 61764.7 62663.0 64209.0 64709.4 59614.8 59919.8 62043.0 79331.0 82843.0 11314.9 13392.7 13488.1 13518.6 13712.2 11891.8 14865.0 14938.3 15207.8 15410.6 13739.7 13766.8 14017.9 14405.4 14429.6 12583.9 13688.8 13883.4 13900.7 13986.0 13407.7 13617.7 13655.5 13850.2 14229.7 11227.8 11455.2 11601.4 11630.4 11642.8 10475.5 10517.8 10645.8 11304.8 11568.2 12:30:49 INFO - PROCESS | 1657 | -------- Summary: end -------- 12:30:49 INFO - PROCESS | 1657 | 12:30:50 INFO - TEST-INFO | 1657: exit 0 12:30:50 INFO - TEST-OK | dromaeo_css | took 1117768ms 12:30:50 INFO - TEST-START | kraken 12:30:50 INFO - operating with platform_type : mac_ 12:30:50 INFO - Initialising browser 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/46/dyhgxyws7270nkhp4l7t8z0800000w/T/tmp3xZ2EF/profile http://localhost:49274/getInfo.html) 12:30:52 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 12:30:52 INFO - PROCESS | 1664 | colorDepth:24 12:30:52 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 12:30:52 INFO - PROCESS | 1664 | __metrics 12:30:53 INFO - PROCESS | 1664 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:30:53 INFO - PROCESS | 1664 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:30:53 INFO - TEST-INFO | 1664: exit 0 12:30:53 INFO - Browser initialized. 12:30:53 INFO - Running cycle 1/1 for kraken test... 12:30:53 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/tmp3xZ2EF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:31:05 INFO - PROCESS | 1665 | RSS: Main: 234000384 12:31:05 INFO - PROCESS | 1665 | 12:31:08 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 12:31:09 INFO - PROCESS | 1665 | RSS: Main: 292524032 12:31:09 INFO - PROCESS | 1665 | 12:31:11 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 12:31:12 INFO - PROCESS | 1665 | RSS: Main: 289226752 12:31:12 INFO - PROCESS | 1665 | 12:31:15 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 12:31:16 INFO - PROCESS | 1665 | RSS: Main: 380948480 12:31:16 INFO - PROCESS | 1665 | 12:31:18 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 12:31:19 INFO - PROCESS | 1665 | RSS: Main: 281366528 12:31:19 INFO - PROCESS | 1665 | 12:31:21 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:31:21 INFO - PROCESS | 1665 | RSS: Main: 286294016 12:31:21 INFO - PROCESS | 1665 | 12:31:24 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 12:31:25 INFO - PROCESS | 1665 | RSS: Main: 325152768 12:31:25 INFO - PROCESS | 1665 | 12:31:28 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 12:31:28 INFO - PROCESS | 1665 | RSS: Main: 326877184 12:31:28 INFO - PROCESS | 1665 | 12:31:31 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 12:31:32 INFO - PROCESS | 1665 | RSS: Main: 324734976 12:31:32 INFO - PROCESS | 1665 | 12:31:33 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:31:34 INFO - PROCESS | 1665 | RSS: Main: 299356160 12:31:34 INFO - PROCESS | 1665 | 12:31:36 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:31:36 INFO - PROCESS | 1665 | RSS: Main: 306016256 12:31:36 INFO - PROCESS | 1665 | 12:31:38 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:31:39 INFO - PROCESS | 1665 | RSS: Main: 305676288 12:31:39 INFO - PROCESS | 1665 | 12:31:42 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:31:43 INFO - PROCESS | 1665 | RSS: Main: 321486848 12:31:43 INFO - PROCESS | 1665 | 12:31:46 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:31:46 INFO - PROCESS | 1665 | RSS: Main: 317698048 12:31:46 INFO - PROCESS | 1665 | 12:31:48 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 12:31:48 INFO - PROCESS | 1665 | RSS: Main: 361455616 12:31:48 INFO - PROCESS | 1665 | 12:31:48 INFO - PROCESS | 1665 | __start_tp_report 12:31:48 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 12:31:48 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 12:31:48 INFO - PROCESS | 1665 | |i|pagename|runs| 12:31:48 INFO - PROCESS | 1665 | |0;ai-astar;115;114;116;113;113;115;114;114;113;124 12:31:48 INFO - PROCESS | 1665 | |1;audio-beat-detection;147;114;130;160;109;129;128;108;161;107 12:31:48 INFO - PROCESS | 1665 | |2;audio-dft;185;185;185;184;183;183;179;185;186;185 12:31:48 INFO - PROCESS | 1665 | |3;audio-fft;75;75;77;157;58;57;57;64;141;58 12:31:48 INFO - PROCESS | 1665 | |4;audio-oscillator;81;82;139;71;71;75;117;69;69;118 12:31:48 INFO - PROCESS | 1665 | |5;imaging-gaussian-blur;89;84;135;89;88;85;90;88;89;86 12:31:48 INFO - PROCESS | 1665 | |6;imaging-darkroom;97;96;96;97;97;97;98;97;96;96 12:31:48 INFO - PROCESS | 1665 | |7;imaging-desaturate;86;87;86;85;85;86;84;85;86;86 12:31:48 INFO - PROCESS | 1665 | |8;json-parse-financial;56;55;57;53;53;55;54;54;54;53 12:31:48 INFO - PROCESS | 1665 | |9;json-stringify-tinderbox;66;68;76;75;72;69;69;70;69;70 12:31:48 INFO - PROCESS | 1665 | |10;stanford-crypto-aes;86;85;85;84;86;87;88;83;85;89 12:31:48 INFO - PROCESS | 1665 | |11;stanford-crypto-ccm;139;139;138;143;140;137;137;140;181;139 12:31:48 INFO - PROCESS | 1665 | |12;stanford-crypto-pbkdf2;170;169;172;172;170;171;168;171;171;169 12:31:48 INFO - PROCESS | 1665 | |13;stanford-crypto-sha256-iterative;68;68;69;68;68;67;68;67;67;69 12:31:48 INFO - PROCESS | 1665 | __end_tp_report 12:31:48 INFO - PROCESS | 1665 | __start_cc_report 12:31:48 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,2013 12:31:48 INFO - PROCESS | 1665 | __end_cc_report 12:31:48 INFO - PROCESS | 1665 | __startTimestamp1473017508622__endTimestamp 12:31:48 INFO - PROCESS | 1665 | 12:31:48 INFO - PROCESS | 1665 | ------- Summary: start ------- 12:31:48 INFO - PROCESS | 1665 | Number of tests: 14 12:31:48 INFO - PROCESS | 1665 | 12:31:48 INFO - PROCESS | 1665 | [#0] ai-astar Cycles:10 Average:115.10 Median:114.50 stddev:3.28 (2.9%) stddev-sans-first:3.48 12:31:48 INFO - PROCESS | 1665 | Values: 115.0 114.0 116.0 113.0 113.0 115.0 114.0 114.0 113.0 124.0 12:31:48 INFO - PROCESS | 1665 | 12:31:48 INFO - PROCESS | 1665 | [#1] audio-beat-detection Cycles:10 Average:129.30 Median:129.50 stddev:20.71 (16.0%) stddev-sans-first:20.95 12:31:48 INFO - PROCESS | 1665 | Values: 147.0 114.0 130.0 160.0 109.0 129.0 128.0 108.0 161.0 107.0 12:31:48 INFO - PROCESS | 1665 | 12:31:48 INFO - PROCESS | 1665 | [#2] audio-dft Cycles:10 Average:184.00 Median:185.00 stddev:2.00 (1.1%) stddev-sans-first:2.09 12:31:48 INFO - PROCESS | 1665 | Values: 185.0 185.0 185.0 184.0 183.0 183.0 179.0 185.0 186.0 185.0 12:31:48 INFO - PROCESS | 1665 | 12:31:48 INFO - PROCESS | 1665 | [#3] audio-fft Cycles:10 Average:81.90 Median:61.00 stddev:36.45 (59.7%) stddev-sans-first:38.57 12:31:48 INFO - PROCESS | 1665 | Values: 75.0 75.0 77.0 157.0 58.0 57.0 57.0 64.0 141.0 58.0 12:31:48 INFO - PROCESS | 1665 | 12:31:48 INFO - PROCESS | 1665 | [#4] audio-oscillator Cycles:10 Average:89.20 Median:71.00 stddev:25.56 (36.0%) stddev-sans-first:26.94 12:31:48 INFO - PROCESS | 1665 | Values: 81.0 82.0 139.0 71.0 71.0 75.0 117.0 69.0 69.0 118.0 12:31:48 INFO - PROCESS | 1665 | 12:31:48 INFO - PROCESS | 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:92.30 Median:88.50 stddev:15.13 (17.1%) stddev-sans-first:16.00 12:31:48 INFO - PROCESS | 1665 | Values: 89.0 84.0 135.0 89.0 88.0 85.0 90.0 88.0 89.0 86.0 12:31:48 INFO - PROCESS | 1665 | 12:31:48 INFO - PROCESS | 1665 | [#6] imaging-darkroom Cycles:10 Average:96.70 Median:97.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 12:31:48 INFO - PROCESS | 1665 | Values: 97.0 96.0 96.0 97.0 97.0 97.0 98.0 97.0 96.0 96.0 12:31:48 INFO - PROCESS | 1665 | 12:31:48 INFO - PROCESS | 1665 | [#7] imaging-desaturate Cycles:10 Average:85.60 Median:86.00 stddev:0.84 (1.0%) stddev-sans-first:0.88 12:31:48 INFO - PROCESS | 1665 | Values: 86.0 87.0 86.0 85.0 85.0 86.0 84.0 85.0 86.0 86.0 12:31:48 INFO - PROCESS | 1665 | 12:31:48 INFO - PROCESS | 1665 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.50 stddev:1.35 (2.5%) stddev-sans-first:1.30 12:31:48 INFO - PROCESS | 1665 | Values: 56.0 55.0 57.0 53.0 53.0 55.0 54.0 54.0 54.0 53.0 12:31:48 INFO - PROCESS | 1665 | 12:31:48 INFO - PROCESS | 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:70.00 stddev:3.10 (4.4%) stddev-sans-first:2.85 12:31:48 INFO - PROCESS | 1665 | Values: 66.0 68.0 76.0 75.0 72.0 69.0 69.0 70.0 69.0 70.0 12:31:48 INFO - PROCESS | 1665 | 12:31:48 INFO - PROCESS | 1665 | [#10] stanford-crypto-aes Cycles:10 Average:85.80 Median:86.00 stddev:1.81 (2.1%) stddev-sans-first:1.92 12:31:48 INFO - PROCESS | 1665 | Values: 86.0 85.0 85.0 84.0 86.0 87.0 88.0 83.0 85.0 89.0 12:31:48 INFO - PROCESS | 1665 | 12:31:48 INFO - PROCESS | 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:143.30 Median:139.50 stddev:13.36 (9.6%) stddev-sans-first:14.08 12:31:48 INFO - PROCESS | 1665 | Values: 139.0 139.0 138.0 143.0 140.0 137.0 137.0 140.0 181.0 139.0 12:31:48 INFO - PROCESS | 1665 | 12:31:48 INFO - PROCESS | 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.30 Median:171.00 stddev:1.34 (0.8%) stddev-sans-first:1.41 12:31:48 INFO - PROCESS | 1665 | Values: 170.0 169.0 172.0 172.0 170.0 171.0 168.0 171.0 171.0 169.0 12:31:48 INFO - PROCESS | 1665 | 12:31:48 INFO - PROCESS | 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.00 stddev:0.74 (1.1%) stddev-sans-first:0.78 12:31:48 INFO - PROCESS | 1665 | Values: 68.0 68.0 69.0 68.0 68.0 67.0 68.0 67.0 67.0 69.0 12:31:48 INFO - PROCESS | 1665 | -------- Summary: end -------- 12:31:48 INFO - PROCESS | 1665 | 12:31:49 INFO - TEST-INFO | 1665: exit 0 12:31:49 INFO - TEST-OK | kraken | took 59239ms 12:31:49 INFO - SUITE-END | took 1177s 12:31:49 INFO - Completed test suite (00:19:37) 12:31:49 INFO - javascript benchmark 12:31:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3083.660797312297, 3259.1363288728476, 3327.3345330933803, 3329.5172200030997, 3371.6965473107416, 5659.462351076651, 5724.885502289957, 5731.254936858213, 5770.884582308351, 5799.13013048043, 5730.68481233532, 5769.798057067993, 5780.595358324919, 5786.56600754944, 5787.13193021047, 5168.819091331795, 5202.427732949392, 5217.060929032791, 5241.213817927322, 5267.341582302212, 5267.841964741064, 5273.789048438058, 5278.392984806739, 5287.894242115155, 5294.841154765363, 5187.273781670569, 5197.480251974791, 5227.163653815391, 5251.789928402878, 5261.368635763713, 5277.47225277473, 5285.233641122054, 5293.994141113176, 5305.283786688786, 5341.439148889388, 5100.413452452978, 5107.846764597068, 5123.129068058439, 5223.138182199932, 5226.92159617606, 4725.1730947084125, 5723.370429252779, 5748.223989761371, 5794.478496935296, 5848.853778655525, 6245.594036387621, 6273.309935907047, 6277.937220627781, 6297.464715499188, 6298.118263443122, 6066.013378126436, 6232.439080482733, 6259.71831267594, 6322.114903913456, 6322.367763223687, 5809.099589563624, 5851.063829787214, 5851.882962340772, 5878.353381128072, 5884.705764711747, 6306.306306306303, 6311.558190926591, 6314.273858506239, 6315.5579109462815, 6321.778737744157, 6010.398960103955, 6065.723595327032, 6097.373446762125, 6156.569040167145, 6197.380261973766, 6248.687565621701, 6282.4974002079725, 6311.905321420182, 6317.652529110851, 6320.367963203735, 6158.538109641795, 6212.161358216673, 6244.656543890039, 6261.0, 6261.749530018839, 6097.97841994295, 6144.648177400964, 6166.630002199882, 6259.967105427553, 6310.716017779211, 4903.632227582874, 4919.90160196794, 4947.82682606107, 4956.727379994063, 4985.0, 4426.513083560806, 4433.334999750064, 4457.598816106566, 4492.3710680504755, 4523.2988886722615, 5766.192733017381, 5791.449812267828, 5795.710214489259, 5796.333421656479, 5807.651540907559, 6151.138840562325, 6224.37756224374, 6263.091851681481, 6312.0, 6318.431341179224, 5803.0, 5808.448197421239, 5818.44724751148, 5824.922389357897, 5858.707064646751, 5422.810201643002, 5432.212329212242, 5436.782528698887, 5442.945570544323, 5444.183372494158, 5325.893482130413, 6160.969195153995, 6249.187605611241, 6319.46284565817, 6325.683715814191, 6284.465820405317, 6301.0, 6320.0, 6342.429181373698, 6351.460125889351, 6315.905261421174, 6319.462845657987, 6320.905186422299, 6326.43062124411, 6338.239411270492, 6093.451589356979, 6101.822802906834, 6123.526657168115, 6211.472024877936, 6255.718492667932, 6223.844403889758, 6297.086922396135, 6307.905381419374, 6315.242170939517, 6331.271903730945, 6045.06301523247, 6127.479164270909, 6142.0, 6152.692365381624, 6160.260768707783, 5967.254093238345, 6055.212822333068, 6122.908156377747, 6124.693765311806, 6144.324124346229, 5916.994111001226, 6047.334793172836, 6050.062240352752, 6060.2424696912885, 6074.179985701874, 6149.508039356751, 6158.969205154125, 6164.106204600397, 6172.845678858064, 6174.845628859314, 5786.797462088806, 5883.94116058834, 5922.170896074467, 5970.701464926649, 6041.335453100243], "value": 5721.876843200818, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [490.4996903158778, 528.6405244433786, 529.9178627312766, 539.1508374310462, 563.2030676592622, 30059.248518787015, 32403.51394729078, 38150.80924595373, 40314.0, 40419.191616167656, 41578.58421415793, 41895.58104418955, 41955.16089678204, 41970.37044444336, 42013.36979945296, 8668.436551624125, 9638.228941684665, 9852.162566181883, 9858.359206651545, 9869.851952220686, 11906.404679765978, 12144.939275303655, 12189.268643881396, 12404.379781010914, 12415.56545520907, 11952.581659641914, 12058.0, 12063.698407539838, 12076.577319793809, 12088.939555302211, 12084.335361555111, 12150.39248037599, 12157.331346775924, 12157.817632735516, 12161.20952138117, 11844.585439509576, 11933.224340417932, 11938.462769175409, 11940.880591194064, 11952.70118247042, 39205.41191882124, 39572.60427395718, 39597.60402395968, 39599.8020009901, 39646.80176599127, 3326.6340702522702, 3353.111425472252, 3406.029281654741, 3591.982040089783, 3940.9649802355816, 30053.248668783235, 31048.223794405094, 31353.216169595715, 31375.529367059513, 31556.526652100238, 3276.7214786743157, 3284.310294838087, 3300.950485742716, 3335.0828522156603, 3394.592648882125, 358494.0, 361601.0, 362779.0, 364344.1782791069, 370529.1473542642, 56400.71799640975, 56793.0, 56824.715876420356, 56838.863222735316, 56844.14733778997, 33585.16037099043, 34152.0, 34205.97382078542, 35502.0, 35568.64431355705, 14693.0, 16164.0, 16342.346306147621, 16348.509544713677, 16348.591285217726, 11292.322460652387, 11437.485313160927, 11460.0, 12442.875571244353, 12540.122191446693, 15306.923465382602, 16354.0, 16560.420385286456, 18015.459536213933, 18478.81521184771, 6974.302569742985, 7721.806954826062, 7949.205079492121, 8014.078380986145, 8018.5990700464745, 856.674463703801, 915.8809354783969, 965.7778710896392, 1036.212478516318, 1080.3949788118678, 24086.277411677678, 27970.160895173176, 28081.43837123287, 28511.859525618744, 28981.13056608263, 1012.5241136665755, 1084.1814430105378, 1099.8405231241425, 1113.6937342230951, 1209.8608660004213, 12307.984354003118, 13475.393607287695, 14697.824174066049, 15313.923430382776, 15402.460913868184, 654121.0, 659818.0, 660872.0, 662932.4827006866, 664085.0, 47192.5280747195, 56244.718776405854, 56713.14930276049, 56737.0, 56772.0, 1078.5092782783659, 1235.6540168752763, 1273.5797186928291, 1381.951631692886, 1406.0509156319442, 28696.426071478872, 30293.0, 30828.16744445247, 30906.0, 31059.068227953197, 46684.0, 47262.52737472581, 47329.52670473251, 47413.525864740914, 47542.52457475381, 517980.0, 518306.0, 520270.0, 520625.0, 522799.0, 25367.36581585475, 26054.86972565125, 26475.0, 26667.599986000612, 27670.308242293297, 45789.77105114453, 46525.3021204689, 46636.300455492514, 47186.0, 47453.0, 37701.229877012076, 37729.81135094307, 38610.0, 39333.01667458336, 39810.0, 38284.617153828105, 38543.80728096342, 38653.568044681815, 38808.80595597004, 39711.602883971944, 36977.815110924275, 37376.813115934245, 37813.43279850859, 37926.62073379231, 37931.81034094812, 30979.0, 33685.49471757975, 33737.15657108592, 34217.0, 34697.479537806445, 21880.343589691696, 25225.495490090463, 25232.0, 25407.237782866545, 25607.23178304654], "value": 19626.11773290734, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.02297475170514, 363.6944966228368, 364.0425680460387, 376.41843352021135, 398.491922798432, 15163.62090947727, 16379.935304205233, 19644.017799110057, 20194.59610807783, 20310.796892031092, 20344.491387715316, 20770.961451927382, 20831.0626021829, 21019.789802101976, 21094.0, 5700.059490184115, 5776.682282474462, 5904.1734157218025, 6024.2168518092585, 6049.213602231704, 4113.095119073799, 5812.157237200625, 5919.1713160157615, 5930.110483427498, 5934.198883150784, 6201.658908760016, 6486.870262594745, 6524.282328943837, 6574.769883054094, 6620.801375958729, 5756.7121643917635, 6039.516838652898, 6083.239595050619, 6550.967245163767, 6559.606423614575, 5867.530597552228, 5870.471657550841, 5879.970600147015, 5884.588078834483, 5893.3222679391565, 12997.740045199138, 13232.669183270398, 24743.77306042465, 25209.36976575582, 25447.36381590475, 2164.7943445372666, 2428.514297140561, 2542.313575334668, 2828.4160869912916, 2890.624218851557, 9451.432914025181, 9892.703218903445, 10128.54421551032, 10370.014848589371, 10405.427701476416, 1607.790987171672, 1817.4184261036473, 1837.6049149432936, 1881.604862978776, 1918.539550507882, 113834.43082784615, 123163.38418307941, 126204.0, 127142.0, 128870.40166384977, 9037.593308301142, 10062.345947513535, 11255.774884502427, 11441.885581144248, 11447.484863181176, 14225.288735563181, 14235.0, 14245.717885390364, 14245.78631320531, 14258.358373873201, 8182.4681395709085, 8337.666493340319, 8381.706640267561, 8459.365547583955, 8473.618687159093, 7996.880046799302, 8022.679092836338, 8069.556174410346, 8140.674373025131, 8141.185881411811, 4374.168907907488, 4454.532274111171, 4471.374007638933, 4516.932246016312, 4547.931781023287, 1838.9172487238106, 1886.2737845929416, 1888.1880791259616, 1891.7351570780102, 1900.8004159563047, 769.5382770337752, 825.7357645553366, 881.722257488889, 886.8916230314633, 970.4662435660358, 19964.401067967694, 21329.68005479919, 21434.03546840396, 21505.67741483879, 21515.8924205378, 863.8142799298181, 882.6778225947482, 899.4827973914894, 982.2093214962024, 1000.659775676259, 10561.471926403648, 10945.288556243819, 11041.668749937515, 11129.387883666313, 11419.429028548539, 549376.0, 559741.0, 563795.0, 569053.0, 573626.0, 70812.2918770816, 71392.28607713961, 87634.60972531905, 124194.0, 126402.36798815947, 961.0725649748026, 961.1877963121151, 964.2430691906819, 965.0494263150791, 984.8965858584745, 18738.81261187425, 18990.43028709141, 19238.03809809532, 19255.32606358771, 19271.0, 51173.0, 51606.22590661068, 53070.73464632652, 53172.73413632907, 53848.46151538591, 56815.57961051007, 73635.74096201669, 75474.62262688651, 75912.0, 76075.0, 7891.802704932193, 7986.281234688906, 8133.34933205354, 8143.959280203798, 8327.375446841632, 9514.714558563253, 12921.870781292067, 13003.08978371505, 13426.395812188279, 13482.865171348161, 10258.666373371414, 11164.0, 11354.659360219206, 11687.824682629938, 11710.765784684429, 10609.363438193734, 11167.0, 11243.437828108725, 11615.245009074382, 11716.06271498295, 9506.239500839778, 11673.416329183337, 11849.881501184878, 11911.642650720492, 12016.519339226334, 6999.335063168991, 8085.151059138941, 8106.635201415833, 8216.137305582828, 8255.091939886488, 10830.079443247098, 11397.829032564352, 11457.197996140523, 11720.003799677123, 11753.47109380097], "value": 9388.851202754502, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1557.844215578442, 1565.4599163288665, 1694.2799310293137, 1803.3147403986484, 1954.8924809135501, 8399.748007559776, 8907.421017633866, 8913.50975696338, 8913.598888050037, 8916.955415222923, 7820.687172513092, 8063.717769878055, 8466.026406963185, 8670.739877803677, 8691.826163476726, 2092.215419217793, 2362.303120579429, 2365.1721897335965, 2396.520695860826, 2405.194259922928, 2027.1789925080293, 2362.208660098869, 2376.5009348036933, 2397.9209355789876, 2409.4458274596764, 2049.75402951646, 2381.7499162587937, 2410.6866107406013, 2415.263344679872, 2419.6733440985417, 2048.5800410915726, 2359.55168517981, 2372.95254094918, 2386.2244770449674, 2386.6300723387903, 2374.798142157919, 2377.132346693462, 2378.191414918936, 2379.9048038078454, 2383.2254517281954, 8847.867281990775, 8851.46891186531, 8944.060873608243, 8948.507832069234, 8988.146126118008, 5151.0, 5296.947030529684, 5297.231901374318, 5299.12564426873, 5300.284461597674, 2802.285417218596, 3467.5318831957616, 3566.785992840412, 3627.818609069536, 3640.7451478396524, 1084.701707030565, 1098.1489345757022, 1759.9912000439915, 1817.6728188925988, 1868.2993877296014, 11148.721281967935, 11433.028192603639, 11451.770964580744, 11573.36346500942, 11703.23928944616, 4436.0906014267, 4470.754108524063, 4673.976630116896, 4674.485806561275, 4674.626029917598, 9066.682666106655, 9082.046385129466, 9085.273178145733, 9086.727398178065, 9091.227245684191, 5235.869103272373, 5240.947590524046, 5241.475852414728, 5248.291480650139, 5251.842444726664, 4393.912121757547, 4479.216137175981, 4500.977495112504, 4550.203714350042, 4929.580985616191, 3651.1419816343137, 3691.796951167658, 3794.848206071726, 3828.2343531293855, 3832.9808350958065, 1140.8060629692973, 1166.3002198680724, 1171.0338970349496, 1172.8944395004492, 1180.7166280092715, 730.5689643110591, 888.2893685051932, 905.542700936023, 906.5693795447241, 932.4964519159578, 5515.310586176728, 5745.0, 5852.209951656472, 5989.490893274034, 6065.75736970525, 917.2111983694018, 946.0870260198941, 947.772534591693, 953.2850362228329, 953.7281874665687, 3559.9288014240087, 3668.6147954464395, 3789.5073640427117, 3812.7902965336616, 3814.446905198786, 10540.578376864993, 10742.033217010507, 10840.837387439195, 10858.67423977282, 10944.0, 9926.404415735078, 10798.406087665096, 10801.243912926177, 10808.351498910091, 10822.729431764268, 7855.921440785633, 8069.9596502018285, 8182.831549639351, 8194.918050819535, 8198.057223419382, 5772.2207501986695, 5785.247917770831, 5822.446867547576, 5906.911396329145, 6171.352008039271, 9682.080202380763, 9886.03378657603, 10010.949945250228, 10043.397396156253, 10047.447390393307, 9984.60061597528, 10090.646827361006, 10178.13485853681, 10203.234757393077, 10251.436171010666, 7514.39884809203, 7716.5370077795515, 8114.675412983651, 8228.50628962264, 8267.0, 8056.879146812686, 8275.62759675828, 8284.420090593601, 8332.333413326798, 8421.284190843848, 7340.816479588053, 7451.4038876888635, 7484.663190156347, 7519.887201691869, 7548.3961283098315, 7228.927710722825, 7263.237360077327, 7318.817029574303, 7353.264673532818, 7430.517016394133, 6937.479689023243, 6944.4444444445335, 6985.3713165815325, 7003.124609423822, 7006.719731210898, 4786.569208771228, 4928.285398617252, 4956.727379994135, 5002.624803139852, 5085.745712714275, 4911.336969509213, 4928.2853986171085, 5026.974865125651, 5091.567216786615, 5161.561267292323], "value": 4588.9906646252, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [286.58302170342154, 295.5743729030197, 304.8628615265062, 308.3755395324466, 308.41709169669315, 4485.5962963333295, 4990.251462280659, 5450.364890532844, 5473.0, 5484.396716361197, 5187.844364669057, 5209.609279304048, 5377.139657654776, 5380.381256155543, 5385.81149659762, 3455.8768400269887, 3739.663430291273, 3796.4874741909766, 4116.485439320085, 4117.773522456271, 3439.9164263256994, 4112.239235741383, 4115.650169735576, 4129.277376459123, 4144.689148313873, 3394.779339342947, 3738.775673459601, 3939.8227079781477, 4002.4796776419025, 4085.9182816343655, 3352.4300868852233, 3621.0, 3796.1268908151005, 3973.8938362667545, 4019.5176578810583, 3051.611516759869, 3190.6968837960358, 3283.635782236786, 3468.6878180963836, 3479.895603131885, 5107.284980102789, 5263.0, 5291.28567643367, 5295.841124766263, 5302.787888484456, 4051.6353528182603, 4462.799174037176, 4572.702774319659, 4577.5193604671695, 4637.281221410687, 4478.843240486567, 5661.0, 5674.943250567483, 5682.801101961453, 5696.401877802812, 2929.4434057529, 3797.8670746524017, 3800.6769424598942, 3806.2387522495333, 3818.369968955119, 5478.369987451454, 5565.526930210937, 5668.97165514174, 5855.121731740273, 5894.705264736789, 3337.816420096918, 3516.701080408143, 3727.1986522897705, 3778.41178792528, 3859.7877116758286, 3516.7362447816004, 3929.54810196831, 3940.408938659167, 3948.348522493814, 3958.2281455115976, 3655.7440979131206, 4265.76292875069, 4410.500429853865, 4625.791839367236, 4631.560001799824, 5651.039323315046, 5676.2036079512745, 5695.686737229492, 5700.914486282709, 5702.7433765480655, 1192.791261529229, 1271.4977583854325, 1324.7017922435873, 1340.2561578323928, 1352.7226918481688, 761.3490465651788, 778.1557010643402, 820.2371794231422, 821.8479581277483, 832.8667413213857, 3661.029827095822, 3851.9614803852164, 4392.912141757147, 4459.722104253371, 4463.018136010072, 3842.634949679776, 4308.0274610518645, 4494.5730155635165, 4840.661153719277, 4886.926696099561, 3801.50580424543, 4429.822807087745, 4437.400950871655, 4439.755813430293, 4496.100779844044, 2258.802834497719, 2276.886155692209, 2308.746037935826, 2553.6848523010663, 2579.806514511419, 4479.664025198058, 4578.427696537933, 4985.925211121836, 5029.315179414937, 5039.924401133986, 4062.6749860011128, 4238.2703221165175, 4928.796282416386, 4929.0, 4972.751362431936, 4638.234691275901, 4806.596701649175, 5546.972265138648, 6114.2357205349335, 6131.601445906002, 5229.320188375486, 5847.105682687097, 5890.941090589211, 5916.792912248051, 5977.671228082497, 5526.723663816712, 5845.357010728731, 5886.705664716661, 5896.882062358814, 5904.527637789052, 5230.555402790806, 5257.868553286198, 5275.254569255718, 6024.578279520567, 6039.728212230548, 3437.914052148716, 3623.6376362363553, 3915.4322623218904, 3919.7452165609143, 3920.843166273317, 4882.707037577757, 5596.832095037155, 5867.802047883999, 5868.501177399969, 5874.794382196603, 5450.754716037709, 5691.402402747652, 5707.315122185199, 5732.656040637408, 5764.011416061452, 5590.552755779447, 5605.467480589337, 5662.546996240373, 5664.0459041630575, 5686.800961966312, 5326.467353264643, 5520.696361700145, 5543.584231182758, 5545.251391062155, 5582.525485333792, 5623.156526521055, 5659.54723622101, 5679.0, 5711.371749107512, 5713.657180569179, 5538.944610553843, 5542.201188399832, 5546.473085056913, 5562.249096371938, 5589.0], "value": 3947.358171499921, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [710.3251910684849, 733.189825243106, 740.5371642723298, 783.6473586885901, 806.2461598405488, 15815.762763558541, 16555.0, 16558.420455284067, 16585.419510317126, 16662.33350665975, 14392.424303027867, 14462.132272063684, 14462.349194286257, 14502.637434064154, 14900.850991490082, 678.915135608049, 699.615211633602, 702.2696395748416, 705.0904333409895, 834.253343257784, 691.3915754136378, 822.2805045585113, 863.5034854958393, 864.8918885139358, 866.0127454701645, 902.1429641840245, 911.129870973219, 913.6893456224882, 913.8857642794651, 913.9268858491305, 910.7723069232692, 910.9362344635878, 912.5346073502495, 912.6167009855876, 913.1827014821697, 525.4351572060012, 652.6704014472708, 678.5114717403461, 680.1056610557101, 683.0, 15100.320485578066, 15120.39518419262, 15189.0, 15190.240487975558, 15211.543653690407, 13639.0, 13957.79063314051, 13974.58076257714, 14033.087849289763, 14150.646233844132, 12208.735802197678, 12601.36993150348, 12642.304673242968, 12650.620481385573, 12976.740465190738, 4481.820727170909, 4524.977375113103, 4595.793189306489, 4627.051454451829, 4741.525847415265, 121118.0, 123175.76824231732, 123533.0, 124004.0, 124095.0, 10903.454827258605, 10999.0, 11117.332960022262, 11307.378094204732, 11456.083513318914, 22110.889445552668, 22139.889300553717, 22140.66788998166, 22166.88916555407, 22181.445463863532, 7644.044494438195, 7797.220277972157, 7860.0567931848545, 7936.761897143095, 7942.960285198653, 4187.602177793105, 4256.1487702459635, 4312.094460163401, 4405.647548196201, 4452.265376212888, 3429.5027221052806, 3480.773749706261, 3532.7527073104634, 3536.504889315498, 3540.6459354064386, 572.7737543670227, 643.707113263464, 653.5849735417949, 665.5241502325861, 668.5153263883645, 567.2597260574906, 568.2158621102934, 598.3448124303881, 609.6738245038972, 635.8855406026867, 8470.0, 8830.0, 9832.360896541702, 10113.797724045626, 10281.177505799518, 528.1417696243604, 580.8809194115196, 596.7195418153461, 598.3687209993499, 615.4245780195442, 4576.748278844629, 4652.278896771006, 4661.021185551072, 4678.69588476748, 4716.174669432904, 151641.0, 151672.0, 153233.0, 153642.46357536505, 159099.0, 127530.0, 128044.0, 130362.0, 131457.34271328582, 134518.0, 83011.09461169071, 91836.0, 95290.0, 97332.0, 116878.24682629768, 6818.284080171709, 8381.874271885805, 9303.162715355651, 9637.42175469474, 9824.263180261652, 61589.384106158366, 61764.69117654383, 62663.0, 64209.0, 64709.35290647222, 59614.80770384481, 59919.801603968546, 62043.0, 79331.01672458235, 82843.0, 11314.943425282821, 13392.661827582624, 13488.12327198729, 13518.59444216675, 13712.245826479639, 11891.76216475683, 14865.0, 14938.253087345807, 15207.771883421536, 15410.614734631725, 13739.74255431595, 13766.793498097337, 14017.859821401655, 14405.351759170653, 14429.639259018608, 12583.87416125827, 13688.794668079787, 13883.444662213398, 13900.72198556003, 13986.0, 13407.68265276241, 13617.659558511117, 13655.522056727967, 13850.238236897067, 14229.715405692035, 11227.775444491228, 11455.198136130392, 11601.419929003347, 11630.360330181922, 11642.825357619811, 10475.476226188508, 10517.842232366367, 10645.787084258427, 11304.830427543759, 11568.190226684052], "value": 7777.207838699003, "unit": "score"}], "name": "dromaeo_css", "value": 7277.461475796762, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 116.0, 113.0, 113.0, 115.0, 114.0, 114.0, 113.0, 124.0], "value": 115.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 114.0, 130.0, 160.0, 109.0, 129.0, 128.0, 108.0, 161.0, 107.0], "value": 129.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 185.0, 184.0, 183.0, 183.0, 179.0, 185.0, 186.0, 185.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 77.0, 157.0, 58.0, 57.0, 57.0, 64.0, 141.0, 58.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 139.0, 71.0, 71.0, 75.0, 117.0, 69.0, 69.0, 118.0], "value": 89.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 84.0, 135.0, 89.0, 88.0, 85.0, 90.0, 88.0, 89.0, 86.0], "value": 92.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 96.0, 97.0, 97.0, 97.0, 98.0, 97.0, 96.0, 96.0], "value": 96.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 86.0, 85.0, 85.0, 86.0, 84.0, 85.0, 86.0, 86.0], "value": 85.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 57.0, 53.0, 53.0, 55.0, 54.0, 54.0, 54.0, 53.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 76.0, 75.0, 72.0, 69.0, 69.0, 70.0, 69.0, 70.0], "value": 70.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 85.0, 84.0, 86.0, 87.0, 88.0, 83.0, 85.0, 89.0], "value": 85.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 138.0, 143.0, 140.0, 137.0, 137.0, 140.0, 181.0, 139.0], "value": 143.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 169.0, 172.0, 172.0, 170.0, 171.0, 168.0, 171.0, 171.0, 169.0], "value": 170.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 68.0, 68.0, 67.0, 68.0, 67.0, 67.0, 69.0], "value": 67.9, "unit": "score"}], "name": "kraken", "value": 1466.2, "alertThreshold": 2.0}]} 12:31:49 INFO - Return code: 0 12:31:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:31:49 INFO - # TBPL SUCCESS # 12:31:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:31:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:31:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:31:49 INFO - 2016-09-04 12:31:49.918 screenresolution[1669:9757] starting screenresolution argv=screenresolution get 12:31:49 INFO - 2016-09-04 12:31:49.923 screenresolution[1669:9757] Display 0: 1600x1200x32@60 12:31:49 INFO - 2016-09-04 12:31:49.929 screenresolution[1670:9760] starting screenresolution argv=screenresolution list 12:31:49 INFO - Available Modes on Display 0 12:31:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:31:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:31:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:31:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:31:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:31:50 INFO - Intel Iris: 12:31:50 INFO - Chipset Model: Intel Iris 12:31:50 INFO - Type: GPU 12:31:50 INFO - Bus: Built-In 12:31:50 INFO - VRAM (Dynamic, Max): 1536 MB 12:31:50 INFO - Vendor: Intel (0x8086) 12:31:50 INFO - Device ID: 0x0a2e 12:31:50 INFO - Revision ID: 0x0009 12:31:50 INFO - Displays: 12:31:50 INFO - Display: 12:31:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:31:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:31:50 INFO - Display Serial Number: 1600x1200 60 12:31:50 INFO - Main Display: Yes 12:31:50 INFO - Mirror: Off 12:31:50 INFO - Online: Yes 12:31:50 INFO - Rotation: Supported 12:31:50 INFO - Return code: 0 12:31:50 INFO - Running post-action listener: _resource_record_post_action 12:31:50 INFO - [mozharness: 2016-09-04 19:31:50.168836Z] Finished run-tests step (success) 12:31:50 INFO - Running post-run listener: _resource_record_post_run 12:31:50 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 37430272; Write bytes: 705499648; Read time: 241; Write time: 1945 12:31:50 INFO - TinderboxPrint: CPU usage
53.7% 12:31:50 INFO - TinderboxPrint: I/O read bytes / time
37,430,272 / 241 12:31:50 INFO - TinderboxPrint: I/O write bytes / time
705,499,648 / 1,945 12:31:50 INFO - TinderboxPrint: CPU idle
2,247.5 (46.3%) 12:31:50 INFO - TinderboxPrint: CPU system
970.2 (20.0%) 12:31:50 INFO - TinderboxPrint: CPU user
1,632.0 (33.7%) 12:31:50 INFO - TinderboxPrint: Swap in / out
456,810,496 / 0 12:31:50 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 391043584; Write bytes: 381138944; Read time: 28604; Write time: 762 12:31:50 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 31169536; Write bytes: 296008192; Read time: 186; Write time: 1116 12:31:50 INFO - Running post-run listener: _upload_blobber_files 12:31:50 INFO - Blob upload gear active. 12:31:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:31:50 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:50 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:50 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:51 INFO - (blobuploader) - INFO - Open directory for files ... 12:31:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:31:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:31:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:31:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.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/dromaeojs_raw.log ... 12:31:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:31:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:31:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:31:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:31:52 INFO - (blobuploader) - INFO - Done attempting. 12:31:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:31:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:31:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:31:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:31:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:31:55 INFO - (blobuploader) - INFO - Done attempting. 12:31:55 INFO - (blobuploader) - INFO - Iteration through files over. 12:31:55 INFO - Return code: 0 12:31:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:31:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:31:55 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef0e4ba58d4b142da2792fa5b0058fc30532b721bc8fdf83b310b12491ce1d3e6a0d6616ffc00318b98c7d7dd7fe13ec07a536561558c7a5d3a6adb13f923495", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f26a1c61b985f04034861d155fe60b68e1fc834a9fba92a8710a6c56557464baa01ed48af1c8b8a74cedca01c68ae74c29f28859473a8840312a695428aeb6b6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/097076ab1f3f3d51d474b61aac452ec1ce31ee3ba694ff1906389df1627af8803a1e0d24c531520c4e7f5b72b225f7e8b8dd53d082f4f600018e38b1bc160c21"} 12:31:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:31:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:31:55 INFO - Contents: 12:31:55 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef0e4ba58d4b142da2792fa5b0058fc30532b721bc8fdf83b310b12491ce1d3e6a0d6616ffc00318b98c7d7dd7fe13ec07a536561558c7a5d3a6adb13f923495", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f26a1c61b985f04034861d155fe60b68e1fc834a9fba92a8710a6c56557464baa01ed48af1c8b8a74cedca01c68ae74c29f28859473a8840312a695428aeb6b6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/097076ab1f3f3d51d474b61aac452ec1ce31ee3ba694ff1906389df1627af8803a1e0d24c531520c4e7f5b72b225f7e8b8dd53d082f4f600018e38b1bc160c21"} 12:31:55 INFO - Running post-run listener: copy_logs_to_upload_dir 12:31:55 INFO - Copying logs to upload dir... 12:31:55 INFO - mkdir: /builds/slave/test/build/upload/logs 12:31:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.868912 ========= master_lag: 2.32 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-09-04 12:31:57.622955) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-04 12:31:57.626392) ========= 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.T8swjSpndo/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.tduF1Ey8Xo/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef0e4ba58d4b142da2792fa5b0058fc30532b721bc8fdf83b310b12491ce1d3e6a0d6616ffc00318b98c7d7dd7fe13ec07a536561558c7a5d3a6adb13f923495", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f26a1c61b985f04034861d155fe60b68e1fc834a9fba92a8710a6c56557464baa01ed48af1c8b8a74cedca01c68ae74c29f28859473a8840312a695428aeb6b6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/097076ab1f3f3d51d474b61aac452ec1ce31ee3ba694ff1906389df1627af8803a1e0d24c531520c4e7f5b72b225f7e8b8dd53d082f4f600018e38b1bc160c21"} 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.011203 build_url: 'https://queue.taskcluster.net/v1/task/QUpyhRJlT9SpFzddD_72WA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef0e4ba58d4b142da2792fa5b0058fc30532b721bc8fdf83b310b12491ce1d3e6a0d6616ffc00318b98c7d7dd7fe13ec07a536561558c7a5d3a6adb13f923495", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f26a1c61b985f04034861d155fe60b68e1fc834a9fba92a8710a6c56557464baa01ed48af1c8b8a74cedca01c68ae74c29f28859473a8840312a695428aeb6b6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/097076ab1f3f3d51d474b61aac452ec1ce31ee3ba694ff1906389df1627af8803a1e0d24c531520c4e7f5b72b225f7e8b8dd53d082f4f600018e38b1bc160c21"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-04 12:31:57.664128) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 12:31:57.664461) ========= 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.T8swjSpndo/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.tduF1Ey8Xo/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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.005162 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 12:31:57.712829) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-04 12:31:57.713152) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-04 12:31:57.713470) ========= ========= Total master_lag: 2.51 =========